-- Logs begin at Tue 2025-12-23 18:45:18 CST, end at Tue 2025-12-23 18:55:55 CST. -- Dec 23 18:54:02 volumio-x86 volumio[908]: info: MyVolumio login type: Token Dec 23 18:54:03 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioGetState Dec 23 18:54:03 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:54:16 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioGetState Dec 23 18:54:16 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:54:22 volumio-x86 volumio[908]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 23 18:54:22 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 23 18:54:22 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 18:54:22 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 23 18:54:22 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:22 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:22 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:22 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:22 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:22 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:22 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:22 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 23 18:54:22 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 23 18:54:26 volumio-x86 volumio[908]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 23 18:54:26 volumio-x86 volumio[908]: info: Preparing to save Alsa Options, stopping services first Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioGetState Dec 23 18:54:26 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPause Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreStateMachine::pause Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreStateMachine::stPlaybackTimer Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreStateMachine::servicePause Dec 23 18:54:26 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreCommandRouter::servicePause Dec 23 18:54:26 volumio-x86 volumio[908]: info: ControllerMpd::pause Dec 23 18:54:26 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand pause Dec 23 18:54:26 volumio-x86 volumio[908]: info: Saving Audio Output to: {"output_device":{"value":"0,3","label":"HDMI"}} Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 23 18:54:26 volumio-x86 volumio[908]: info: Found match in Cards Database: setting mixer for card HDMI Dec 23 18:54:26 volumio-x86 volumio[908]: info: Setting mixer IEC958 for card HDMI Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 23 18:54:26 volumio-x86 volumio[908]: info: Updating Volume Controller Parameters: Device: 0,3 Name: HDMI Mixer: IEC958 Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 23 18:54:26 volumio-x86 volumio[908]: info: Disabling external Volume Control Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 23 18:54:26 volumio-x86 volumio[908]: info: Preparing to generate the ALSA configuration file Dec 23 18:54:26 volumio-x86 volumio[908]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Dec 23 18:54:26 volumio-x86 volumio[908]: info: Reading ALSA contributions from plugins. Dec 23 18:54:26 volumio-x86 volumio[908]: info: Dec 23 18:54:26 volumio-x86 volumio[908]: ---------------------------- MPD announces state update: player Dec 23 18:54:26 volumio-x86 volumio[908]: info: sendMpdCommand pause took 256 milliseconds Dec 23 18:54:26 volumio-x86 volumio[908]: info: ControllerMpd::getState Dec 23 18:54:26 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand status Dec 23 18:54:26 volumio-x86 volumio[908]: info: Dec 23 18:54:26 volumio-x86 volumio[908]: ---------------------------- MPD announces state update: player Dec 23 18:54:26 volumio-x86 volumio[908]: info: ControllerMpd::getState Dec 23 18:54:26 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand status Dec 23 18:54:26 volumio-x86 volumio[908]: info: Dec 23 18:54:26 volumio-x86 volumio[908]: ---------------------------- MPD announces state update: player Dec 23 18:54:26 volumio-x86 volumio[908]: info: ControllerMpd::getState Dec 23 18:54:26 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand status Dec 23 18:54:26 volumio-x86 volumio[908]: info: Dec 23 18:54:26 volumio-x86 volumio[908]: ---------------------------- MPD announces state update: player Dec 23 18:54:26 volumio-x86 volumio[908]: info: ControllerMpd::getState Dec 23 18:54:26 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand status Dec 23 18:54:26 volumio-x86 volumio[908]: Unable to parse: Dec 23 18:54:26 volumio-x86 volumio[908]: Simple mixer control 'IEC958',0 Dec 23 18:54:26 volumio-x86 volumio[908]: Capabilities: pswitch pswitch-joined Dec 23 18:54:26 volumio-x86 volumio[908]: Playback channels: Mono Dec 23 18:54:26 volumio-x86 volumio[908]: Mono: Playback [on] Dec 23 18:54:26 volumio-x86 volumio[908]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Dec 23 18:54:26 volumio-x86 volumio[908]: info: sendMpdCommand status took 31 milliseconds Dec 23 18:54:26 volumio-x86 volumio[908]: info: sendMpdCommand status took 30 milliseconds Dec 23 18:54:26 volumio-x86 volumio[908]: info: sendMpdCommand status took 29 milliseconds Dec 23 18:54:26 volumio-x86 volumio[908]: info: sendMpdCommand status took 28 milliseconds Dec 23 18:54:26 volumio-x86 volumio[908]: verbose: ControllerMpd::parseState Dec 23 18:54:26 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 18:54:26 volumio-x86 volumio[908]: verbose: ControllerMpd::parseState Dec 23 18:54:26 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 18:54:26 volumio-x86 volumio[908]: verbose: ControllerMpd::parseState Dec 23 18:54:26 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 18:54:26 volumio-x86 volumio[908]: verbose: ControllerMpd::parseState Dec 23 18:54:26 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 18:54:26 volumio-x86 volumio[908]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 23 18:54:26 volumio-x86 volumio[908]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 23 18:54:26 volumio-x86 volumio[908]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 23 18:54:26 volumio-x86 volumio[908]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 23 18:54:26 volumio-x86 volumio[908]: verbose: ControllerMpd::parseTrackInfo Dec 23 18:54:26 volumio-x86 volumio[908]: info: ControllerMpd::pushState Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreCommandRouter::servicePushState Dec 23 18:54:26 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:54:26 volumio-x86 volumio[908]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":41434,"duration":261,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"504 Kbps","isStreaming":false,"title":"不必在乎我是谁","artist":"林忆莲","album":"不必在乎我是谁","uri":"INTERNAL/musics/不必在乎我是谁/林忆莲-不必在乎我是谁.flac","trackType":"flac"} Dec 23 18:54:26 volumio-x86 volumio[908]: verbose: CURRENT POSITION 0 Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreStateMachine::syncState stateService pause Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreStateMachine::syncState currentStatus pause Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreStateMachine::pushState Dec 23 18:54:26 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPushState Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreStateMachine::stPlaybackTimer Dec 23 18:54:26 volumio-x86 volumio[908]: info: ControllerMpd::pushState Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreCommandRouter::servicePushState Dec 23 18:54:26 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:54:26 volumio-x86 volumio[908]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":41434,"duration":261,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"504 Kbps","isStreaming":false,"title":"不必在乎我是谁","artist":"林忆莲","album":"不必在乎我是谁","uri":"INTERNAL/musics/不必在乎我是谁/林忆莲-不必在乎我是谁.flac","trackType":"flac"} Dec 23 18:54:26 volumio-x86 volumio[908]: verbose: CURRENT POSITION 0 Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreStateMachine::syncState stateService pause Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreStateMachine::syncState currentStatus pause Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreStateMachine::pushState Dec 23 18:54:26 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPushState Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreStateMachine::stPlaybackTimer Dec 23 18:54:26 volumio-x86 volumio[908]: info: ControllerMpd::pushState Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreCommandRouter::servicePushState Dec 23 18:54:26 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:54:26 volumio-x86 volumio[908]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":41434,"duration":261,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"504 Kbps","isStreaming":false,"title":"不必在乎我是谁","artist":"林忆莲","album":"不必在乎我是谁","uri":"INTERNAL/musics/不必在乎我是谁/林忆莲-不必在乎我是谁.flac","trackType":"flac"} Dec 23 18:54:26 volumio-x86 volumio[908]: verbose: CURRENT POSITION 0 Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreStateMachine::syncState stateService pause Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreStateMachine::syncState currentStatus pause Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreStateMachine::pushState Dec 23 18:54:26 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPushState Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreStateMachine::stPlaybackTimer Dec 23 18:54:26 volumio-x86 volumio[908]: info: ControllerMpd::pushState Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreCommandRouter::servicePushState Dec 23 18:54:26 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:54:26 volumio-x86 volumio[908]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":41434,"duration":261,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"504 Kbps","isStreaming":false,"title":"不必在乎我是谁","artist":"林忆莲","album":"不必在乎我是谁","uri":"INTERNAL/musics/不必在乎我是谁/林忆莲-不必在乎我是谁.flac","trackType":"flac"} Dec 23 18:54:26 volumio-x86 volumio[908]: verbose: CURRENT POSITION 0 Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreStateMachine::syncState stateService pause Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreStateMachine::syncState currentStatus pause Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreStateMachine::pushState Dec 23 18:54:26 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPushState Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreStateMachine::stPlaybackTimer Dec 23 18:54:26 volumio-x86 volumio[908]: info: ------------------------------ 122ms Dec 23 18:54:26 volumio-x86 volumio[908]: info: ------------------------------ 121ms Dec 23 18:54:26 volumio-x86 volumio[908]: info: ------------------------------ 120ms Dec 23 18:54:26 volumio-x86 volumio[908]: info: ------------------------------ 120ms Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 23 18:54:26 volumio-x86 volumio[908]: Unable to parse: Dec 23 18:54:26 volumio-x86 volumio[908]: Simple mixer control 'IEC958',0 Dec 23 18:54:26 volumio-x86 volumio[908]: Capabilities: pswitch pswitch-joined Dec 23 18:54:26 volumio-x86 volumio[908]: Playback channels: Mono Dec 23 18:54:26 volumio-x86 volumio[908]: Mono: Playback [on] Dec 23 18:54:26 volumio-x86 volumio[908]: info: VolumeController:: Volume=undefined Mute =false Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreStateMachine::pushState Dec 23 18:54:26 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPushState Dec 23 18:54:26 volumio-x86 volumio[908]: info: Asound.conf file written Dec 23 18:54:26 volumio-x86 sudo[4179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 23 18:54:26 volumio-x86 sudo[4179]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 18:54:26 volumio-x86 sudo[4179]: pam_unix(sudo:session): session closed for user root Dec 23 18:54:26 volumio-x86 volumio[908]: info: Output device has changed, restarting MPD Dec 23 18:54:26 volumio-x86 sudo[4184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 23 18:54:26 volumio-x86 sudo[4184]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 18:54:26 volumio-x86 volumio[908]: info: Output device has changed, restarting Shairport Sync Dec 23 18:54:26 volumio-x86 sudo[4184]: pam_unix(sudo:session): session closed for user root Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 18:54:26 volumio-x86 sudo[4187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 23 18:54:26 volumio-x86 sudo[4187]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 18:54:26 volumio-x86 systemd[1]: Stopping Music Player Daemon... Dec 23 18:54:26 volumio-x86 volumio[908]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 23 18:54:26 volumio-x86 volumio[908]: info: Output device has changed, restarting MPD Dec 23 18:54:26 volumio-x86 systemd[1]: mpd.service: Succeeded. Dec 23 18:54:26 volumio-x86 systemd[1]: Stopped Music Player Daemon. Dec 23 18:54:26 volumio-x86 systemd[1]: Starting Music Player Daemon... Dec 23 18:54:26 volumio-x86 volumio[908]: info: Output device has changed, restarting Shairport Sync Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:26 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 18:54:26 volumio-x86 sudo[4193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 23 18:54:26 volumio-x86 sudo[4193]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 18:54:27 volumio-x86 sudo[4197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 23 18:54:27 volumio-x86 sudo[4197]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 18:54:27 volumio-x86 sudo[4193]: pam_unix(sudo:session): session closed for user root Dec 23 18:54:27 volumio-x86 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 23 18:54:27 volumio-x86 systemd[1]: mpd.service: Succeeded. Dec 23 18:54:27 volumio-x86 systemd[1]: Stopped Music Player Daemon. Dec 23 18:54:27 volumio-x86 systemd[1]: Starting Music Player Daemon... Dec 23 18:54:27 volumio-x86 volumio[908]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 23 18:54:27 volumio-x86 volumio[908]: info: MPD Permissions set Dec 23 18:54:27 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:27 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:27 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 18:54:27 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 23 18:54:27 volumio-x86 sudo[4203]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 23 18:54:27 volumio-x86 sudo[4203]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 18:54:27 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:27 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:27 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:27 volumio-x86 sudo[4203]: pam_unix(sudo:session): session closed for user root Dec 23 18:54:27 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:27 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:27 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 18:54:27 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 23 18:54:27 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:27 volumio-x86 volumio[908]: info: Starting Shairport Sync Dec 23 18:54:27 volumio-x86 volumio[908]: info: Starting Shairport Sync Dec 23 18:54:27 volumio-x86 sudo[4220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 23 18:54:27 volumio-x86 sudo[4220]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 18:54:27 volumio-x86 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 23 18:54:27 volumio-x86 systemd[1]: shairport-sync.service: Succeeded. Dec 23 18:54:27 volumio-x86 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 23 18:54:27 volumio-x86 sudo[4223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 23 18:54:27 volumio-x86 sudo[4223]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 18:54:27 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 23 18:54:27 volumio-x86 sudo[4220]: pam_unix(sudo:session): session closed for user root Dec 23 18:54:27 volumio-x86 volumio[908]: info: Shairport-Sync Started Dec 23 18:54:27 volumio-x86 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 23 18:54:27 volumio-x86 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 23 18:54:27 volumio-x86 systemd[1]: shairport-sync.service: Succeeded. Dec 23 18:54:27 volumio-x86 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 23 18:54:27 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 23 18:54:27 volumio-x86 sudo[4223]: pam_unix(sudo:session): session closed for user root Dec 23 18:54:27 volumio-x86 volumio[908]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 23 18:54:27 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 23 18:54:27 volumio-x86 volumio[908]: info: Preparing to save Alsa Options, stopping services first Dec 23 18:54:27 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioGetState Dec 23 18:54:27 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:54:27 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPause Dec 23 18:54:27 volumio-x86 volumio[908]: info: CoreStateMachine::pause Dec 23 18:54:27 volumio-x86 volumio[908]: info: Saving Audio Output to: {"output_device":{"value":"0,3","label":"HDMI"}} Dec 23 18:54:27 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 23 18:54:27 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 23 18:54:27 volumio-x86 volumio[908]: info: Found match in Cards Database: setting mixer for card HDMI Dec 23 18:54:27 volumio-x86 volumio[908]: info: Setting mixer IEC958 for card HDMI Dec 23 18:54:27 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 23 18:54:27 volumio-x86 volumio[908]: info: Updating Volume Controller Parameters: Device: 0,3 Name: HDMI Mixer: IEC958 Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 23 18:54:27 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 23 18:54:27 volumio-x86 volumio[908]: info: Disabling external Volume Control Dec 23 18:54:27 volumio-x86 volumio[908]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 23 18:54:27 volumio-x86 volumio[908]: info: Preparing to generate the ALSA configuration file Dec 23 18:54:27 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:27 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:27 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:27 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:27 volumio-x86 volumio[908]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Dec 23 18:54:27 volumio-x86 volumio[908]: info: Reading ALSA contributions from plugins. Dec 23 18:54:27 volumio-x86 volumio[908]: Unable to parse: Dec 23 18:54:27 volumio-x86 volumio[908]: Simple mixer control 'IEC958',0 Dec 23 18:54:27 volumio-x86 volumio[908]: Capabilities: pswitch pswitch-joined Dec 23 18:54:27 volumio-x86 volumio[908]: Playback channels: Mono Dec 23 18:54:27 volumio-x86 volumio[908]: Mono: Playback [on] Dec 23 18:54:27 volumio-x86 volumio[908]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Dec 23 18:54:27 volumio-x86 volumio[908]: info: Shairport-Sync Started Dec 23 18:54:27 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 23 18:54:27 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 18:54:27 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 23 18:54:27 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:27 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:27 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:27 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:27 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:27 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 23 18:54:27 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 23 18:54:27 volumio-x86 volumio[908]: Unable to parse: Dec 23 18:54:27 volumio-x86 volumio[908]: Simple mixer control 'IEC958',0 Dec 23 18:54:27 volumio-x86 volumio[908]: Capabilities: pswitch pswitch-joined Dec 23 18:54:27 volumio-x86 volumio[908]: Playback channels: Mono Dec 23 18:54:27 volumio-x86 volumio[908]: Mono: Playback [on] Dec 23 18:54:27 volumio-x86 volumio[908]: info: VolumeController:: Volume=undefined Mute =false Dec 23 18:54:27 volumio-x86 volumio[908]: info: CoreStateMachine::pushState Dec 23 18:54:27 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:54:27 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPushState Dec 23 18:54:28 volumio-x86 volumio[908]: info: Asound.conf file unchanged, so no further update is needed Dec 23 18:54:28 volumio-x86 volumio[908]: info: Output device has changed, restarting MPD Dec 23 18:54:28 volumio-x86 sudo[4263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 23 18:54:28 volumio-x86 sudo[4263]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 18:54:28 volumio-x86 volumio[908]: info: Output device has changed, restarting Shairport Sync Dec 23 18:54:28 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:28 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 18:54:28 volumio-x86 sudo[4263]: pam_unix(sudo:session): session closed for user root Dec 23 18:54:28 volumio-x86 sudo[4266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 23 18:54:28 volumio-x86 sudo[4266]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 18:54:28 volumio-x86 volumio[908]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 23 18:54:28 volumio-x86 volumio[908]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 23 18:54:28 volumio-x86 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Dec 23 18:54:28 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 23 18:54:28 volumio-x86 volumio[908]: info: Preparing to save Alsa Options, stopping services first Dec 23 18:54:28 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioGetState Dec 23 18:54:28 volumio-x86 systemd[1]: mpd.service: Succeeded. Dec 23 18:54:28 volumio-x86 systemd[1]: Stopped Music Player Daemon. Dec 23 18:54:28 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:54:28 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPause Dec 23 18:54:28 volumio-x86 volumio[908]: info: CoreStateMachine::pause Dec 23 18:54:28 volumio-x86 volumio[908]: info: Saving Audio Output to: {"output_device":{"value":"0,3","label":"HDMI"}} Dec 23 18:54:28 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 23 18:54:28 volumio-x86 systemd[1]: Starting Music Player Daemon... Dec 23 18:54:28 volumio-x86 sudo[4271]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 23 18:54:28 volumio-x86 sudo[4271]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 18:54:28 volumio-x86 sudo[4271]: pam_unix(sudo:session): session closed for user root Dec 23 18:54:28 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 23 18:54:28 volumio-x86 volumio[908]: info: Found match in Cards Database: setting mixer for card HDMI Dec 23 18:54:28 volumio-x86 volumio[908]: info: Setting mixer IEC958 for card HDMI Dec 23 18:54:28 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 23 18:54:28 volumio-x86 volumio[908]: info: Updating Volume Controller Parameters: Device: 0,3 Name: HDMI Mixer: IEC958 Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 23 18:54:28 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 23 18:54:28 volumio-x86 volumio[908]: info: Disabling external Volume Control Dec 23 18:54:28 volumio-x86 volumio[908]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 23 18:54:28 volumio-x86 volumio[908]: info: Preparing to generate the ALSA configuration file Dec 23 18:54:28 volumio-x86 volumio[908]: info: MPD Permissions set Dec 23 18:54:28 volumio-x86 volumio[908]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Dec 23 18:54:28 volumio-x86 volumio[908]: info: Reading ALSA contributions from plugins. Dec 23 18:54:28 volumio-x86 volumio[908]: Unable to parse: Dec 23 18:54:28 volumio-x86 volumio[908]: Simple mixer control 'IEC958',0 Dec 23 18:54:28 volumio-x86 volumio[908]: Capabilities: pswitch pswitch-joined Dec 23 18:54:28 volumio-x86 volumio[908]: Playback channels: Mono Dec 23 18:54:28 volumio-x86 volumio[908]: Mono: Playback [on] Dec 23 18:54:28 volumio-x86 volumio[908]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Dec 23 18:54:28 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:28 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:28 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:28 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:28 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:28 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:28 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:28 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:28 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 18:54:28 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 23 18:54:28 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:28 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 23 18:54:28 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 18:54:28 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 23 18:54:28 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:28 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:28 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:28 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:28 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:28 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:28 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:28 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 23 18:54:28 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 23 18:54:28 volumio-x86 volumio[908]: Unable to parse: Dec 23 18:54:28 volumio-x86 volumio[908]: Simple mixer control 'IEC958',0 Dec 23 18:54:28 volumio-x86 volumio[908]: Capabilities: pswitch pswitch-joined Dec 23 18:54:28 volumio-x86 volumio[908]: Playback channels: Mono Dec 23 18:54:28 volumio-x86 volumio[908]: Mono: Playback [on] Dec 23 18:54:28 volumio-x86 volumio[908]: info: VolumeController:: Volume=undefined Mute =false Dec 23 18:54:28 volumio-x86 volumio[908]: info: CoreStateMachine::pushState Dec 23 18:54:28 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:54:28 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPushState Dec 23 18:54:28 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:28 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:28 volumio-x86 volumio[908]: info: Starting Shairport Sync Dec 23 18:54:28 volumio-x86 volumio[908]: info: Asound.conf file unchanged, so no further update is needed Dec 23 18:54:28 volumio-x86 volumio[908]: info: Output device has changed, restarting MPD Dec 23 18:54:28 volumio-x86 sudo[4299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 23 18:54:28 volumio-x86 sudo[4299]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 18:54:29 volumio-x86 sudo[4302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 23 18:54:29 volumio-x86 sudo[4302]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 18:54:29 volumio-x86 volumio[908]: info: Output device has changed, restarting Shairport Sync Dec 23 18:54:29 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:29 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 18:54:29 volumio-x86 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 23 18:54:29 volumio-x86 sudo[4302]: pam_unix(sudo:session): session closed for user root Dec 23 18:54:29 volumio-x86 systemd[1]: shairport-sync.service: Succeeded. Dec 23 18:54:29 volumio-x86 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 23 18:54:29 volumio-x86 sudo[4305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 23 18:54:29 volumio-x86 sudo[4305]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 18:54:29 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 23 18:54:29 volumio-x86 sudo[4299]: pam_unix(sudo:session): session closed for user root Dec 23 18:54:29 volumio-x86 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Dec 23 18:54:29 volumio-x86 systemd[1]: mpd.service: Succeeded. Dec 23 18:54:29 volumio-x86 systemd[1]: Stopped Music Player Daemon. Dec 23 18:54:29 volumio-x86 systemd[1]: Starting Music Player Daemon... Dec 23 18:54:29 volumio-x86 volumio[908]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 23 18:54:29 volumio-x86 volumio[908]: info: MPD Permissions set Dec 23 18:54:29 volumio-x86 volumio[908]: info: Shairport-Sync Started Dec 23 18:54:29 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:29 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:29 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:29 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:29 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:29 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:29 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:29 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:29 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 18:54:29 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 23 18:54:29 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:29 volumio-x86 sudo[4312]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 23 18:54:29 volumio-x86 sudo[4312]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 18:54:29 volumio-x86 volumio[908]: info: Starting Shairport Sync Dec 23 18:54:29 volumio-x86 sudo[4312]: pam_unix(sudo:session): session closed for user root Dec 23 18:54:29 volumio-x86 sudo[4324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 23 18:54:29 volumio-x86 sudo[4324]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 18:54:29 volumio-x86 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 23 18:54:29 volumio-x86 systemd[1]: shairport-sync.service: Succeeded. Dec 23 18:54:29 volumio-x86 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 23 18:54:29 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 23 18:54:29 volumio-x86 sudo[4324]: pam_unix(sudo:session): session closed for user root Dec 23 18:54:29 volumio-x86 volumio[908]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 23 18:54:29 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 23 18:54:29 volumio-x86 volumio[908]: info: Preparing to save Alsa Options, stopping services first Dec 23 18:54:29 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioGetState Dec 23 18:54:29 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:54:29 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPause Dec 23 18:54:29 volumio-x86 volumio[908]: info: CoreStateMachine::pause Dec 23 18:54:29 volumio-x86 volumio[908]: info: Saving Audio Output to: {"output_device":{"value":"0,3","label":"HDMI"}} Dec 23 18:54:29 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 23 18:54:29 volumio-x86 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Dec 23 18:54:29 volumio-x86 systemd[1]: mpd.service: Failed with result 'protocol'. Dec 23 18:54:29 volumio-x86 systemd[1]: Failed to start Music Player Daemon. Dec 23 18:54:29 volumio-x86 sudo[4266]: pam_unix(sudo:session): session closed for user root Dec 23 18:54:29 volumio-x86 sudo[4197]: pam_unix(sudo:session): session closed for user root Dec 23 18:54:29 volumio-x86 sudo[4187]: pam_unix(sudo:session): session closed for user root Dec 23 18:54:29 volumio-x86 sudo[4305]: pam_unix(sudo:session): session closed for user root Dec 23 18:54:29 volumio-x86 systemd[1]: Starting Music Player Daemon... Dec 23 18:54:29 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 23 18:54:29 volumio-x86 volumio[908]: info: Found match in Cards Database: setting mixer for card HDMI Dec 23 18:54:29 volumio-x86 sudo[4333]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 23 18:54:29 volumio-x86 sudo[4333]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 18:54:29 volumio-x86 sudo[4333]: pam_unix(sudo:session): session closed for user root Dec 23 18:54:29 volumio-x86 volumio[908]: info: Setting mixer IEC958 for card HDMI Dec 23 18:54:29 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 23 18:54:29 volumio-x86 volumio[908]: info: Updating Volume Controller Parameters: Device: 0,3 Name: HDMI Mixer: IEC958 Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 23 18:54:29 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 23 18:54:29 volumio-x86 volumio[908]: info: Disabling external Volume Control Dec 23 18:54:29 volumio-x86 volumio[908]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 23 18:54:29 volumio-x86 volumio[908]: info: Preparing to generate the ALSA configuration file Dec 23 18:54:29 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:29 volumio-x86 volumio[908]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Dec 23 18:54:29 volumio-x86 volumio[908]: info: Reading ALSA contributions from plugins. Dec 23 18:54:29 volumio-x86 volumio[908]: info: Shairport-Sync Started Dec 23 18:54:29 volumio-x86 volumio[908]: Unable to parse: Dec 23 18:54:29 volumio-x86 volumio[908]: Simple mixer control 'IEC958',0 Dec 23 18:54:29 volumio-x86 volumio[908]: Capabilities: pswitch pswitch-joined Dec 23 18:54:29 volumio-x86 volumio[908]: Playback channels: Mono Dec 23 18:54:29 volumio-x86 volumio[908]: Mono: Playback [on] Dec 23 18:54:29 volumio-x86 volumio[908]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Dec 23 18:54:29 volumio-x86 volumio[908]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Dec 23 18:54:29 volumio-x86 volumio[908]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. Dec 23 18:54:29 volumio-x86 volumio[908]: See "systemctl status mpd.service" and "journalctl -xe" for details. Dec 23 18:54:29 volumio-x86 volumio[908]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Dec 23 18:54:29 volumio-x86 volumio[908]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. Dec 23 18:54:29 volumio-x86 volumio[908]: See "systemctl status mpd.service" and "journalctl -xe" for details. Dec 23 18:54:29 volumio-x86 volumio[908]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Dec 23 18:54:29 volumio-x86 volumio[908]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. Dec 23 18:54:29 volumio-x86 volumio[908]: See "systemctl status mpd.service" and "journalctl -xe" for details. Dec 23 18:54:29 volumio-x86 volumio[908]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Dec 23 18:54:29 volumio-x86 volumio[908]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. Dec 23 18:54:29 volumio-x86 volumio[908]: See "systemctl status mpd.service" and "journalctl -xe" for details. Dec 23 18:54:29 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 23 18:54:29 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 18:54:29 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 23 18:54:29 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:29 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:29 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:29 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:29 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:29 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:29 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:29 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 23 18:54:29 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 23 18:54:29 volumio-x86 volumio[908]: Unable to parse: Dec 23 18:54:29 volumio-x86 volumio[908]: Simple mixer control 'IEC958',0 Dec 23 18:54:29 volumio-x86 volumio[908]: Capabilities: pswitch pswitch-joined Dec 23 18:54:29 volumio-x86 volumio[908]: Playback channels: Mono Dec 23 18:54:29 volumio-x86 volumio[908]: Mono: Playback [on] Dec 23 18:54:29 volumio-x86 volumio[908]: info: VolumeController:: Volume=undefined Mute =false Dec 23 18:54:29 volumio-x86 volumio[908]: info: CoreStateMachine::pushState Dec 23 18:54:29 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:54:29 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPushState Dec 23 18:54:29 volumio-x86 volumio[908]: info: Asound.conf file unchanged, so no further update is needed Dec 23 18:54:29 volumio-x86 volumio[908]: info: Output device has changed, restarting MPD Dec 23 18:54:30 volumio-x86 volumio[908]: info: Output device has changed, restarting Shairport Sync Dec 23 18:54:30 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:30 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 18:54:30 volumio-x86 sudo[4352]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 23 18:54:30 volumio-x86 sudo[4354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 23 18:54:30 volumio-x86 sudo[4354]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 18:54:30 volumio-x86 sudo[4352]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 18:54:30 volumio-x86 sudo[4352]: pam_unix(sudo:session): session closed for user root Dec 23 18:54:30 volumio-x86 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Dec 23 18:54:30 volumio-x86 systemd[1]: mpd.service: Succeeded. Dec 23 18:54:30 volumio-x86 volumio[908]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 23 18:54:30 volumio-x86 systemd[1]: Stopped Music Player Daemon. Dec 23 18:54:30 volumio-x86 volumio[908]: info: MPD Permissions set Dec 23 18:54:30 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:30 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:30 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:30 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:30 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:30 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:30 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:30 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:30 volumio-x86 systemd[1]: Starting Music Player Daemon... Dec 23 18:54:30 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 18:54:30 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 23 18:54:30 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:30 volumio-x86 volumio[908]: info: Starting Shairport Sync Dec 23 18:54:30 volumio-x86 sudo[4360]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 23 18:54:30 volumio-x86 sudo[4360]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 18:54:30 volumio-x86 sudo[4368]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 23 18:54:30 volumio-x86 sudo[4368]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 18:54:30 volumio-x86 sudo[4360]: pam_unix(sudo:session): session closed for user root Dec 23 18:54:30 volumio-x86 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 23 18:54:30 volumio-x86 systemd[1]: shairport-sync.service: Succeeded. Dec 23 18:54:30 volumio-x86 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 23 18:54:30 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 23 18:54:30 volumio-x86 sudo[4368]: pam_unix(sudo:session): session closed for user root Dec 23 18:54:30 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 23 18:54:30 volumio-x86 volumio[908]: info: CoreStateMachine::getcurrentVolume Dec 23 18:54:30 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioRetrievevolume Dec 23 18:54:30 volumio-x86 volumio[908]: info: Shairport-Sync Started Dec 23 18:54:30 volumio-x86 volumio[908]: Unable to parse: Dec 23 18:54:30 volumio-x86 volumio[908]: Simple mixer control 'IEC958',0 Dec 23 18:54:30 volumio-x86 volumio[908]: Capabilities: pswitch pswitch-joined Dec 23 18:54:30 volumio-x86 volumio[908]: Playback channels: Mono Dec 23 18:54:30 volumio-x86 volumio[908]: Mono: Playback [on] Dec 23 18:54:30 volumio-x86 volumio[908]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Dec 23 18:54:30 volumio-x86 volumio[908]: Unable to parse: Dec 23 18:54:30 volumio-x86 volumio[908]: Simple mixer control 'IEC958',0 Dec 23 18:54:30 volumio-x86 volumio[908]: Capabilities: pswitch pswitch-joined Dec 23 18:54:30 volumio-x86 volumio[908]: Playback channels: Mono Dec 23 18:54:30 volumio-x86 volumio[908]: Mono: Playback [on] Dec 23 18:54:30 volumio-x86 volumio[908]: info: VolumeController:: Volume=undefined Mute =false Dec 23 18:54:30 volumio-x86 volumio[908]: info: CoreStateMachine::pushState Dec 23 18:54:30 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:54:30 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPushState Dec 23 18:54:30 volumio-x86 volumio[908]: info: CoreStateMachine::updateTrackBlock Dec 23 18:54:30 volumio-x86 volumio[908]: info: CorePlayQueue::getTrackBlock Dec 23 18:54:30 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioRetrievevolume Dec 23 18:54:30 volumio-x86 volumio[908]: Unable to parse: Dec 23 18:54:30 volumio-x86 volumio[908]: Simple mixer control 'IEC958',0 Dec 23 18:54:30 volumio-x86 volumio[908]: Capabilities: pswitch pswitch-joined Dec 23 18:54:30 volumio-x86 volumio[908]: Playback channels: Mono Dec 23 18:54:30 volumio-x86 volumio[908]: Mono: Playback [on] Dec 23 18:54:30 volumio-x86 volumio[908]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Dec 23 18:54:30 volumio-x86 volumio[908]: Unable to parse: Dec 23 18:54:30 volumio-x86 volumio[908]: Simple mixer control 'IEC958',0 Dec 23 18:54:30 volumio-x86 volumio[908]: Capabilities: pswitch pswitch-joined Dec 23 18:54:30 volumio-x86 volumio[908]: Playback channels: Mono Dec 23 18:54:30 volumio-x86 volumio[908]: Mono: Playback [on] Dec 23 18:54:30 volumio-x86 volumio[908]: info: VolumeController:: Volume=undefined Mute =false Dec 23 18:54:30 volumio-x86 volumio[908]: info: CoreStateMachine::pushState Dec 23 18:54:30 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:54:30 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPushState Dec 23 18:54:30 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:30 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:31 volumio-x86 mpd[4371]: Dec 23 18:54 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 23 18:54:31 volumio-x86 systemd[1]: Started Music Player Daemon. Dec 23 18:54:31 volumio-x86 volumio[908]: Upnp client error: Error: This socket has been ended by the other party Dec 23 18:54:31 volumio-x86 mpd_monitor.sh[629]: MPD restarted due to no mpc output. Dec 23 18:54:31 volumio-x86 sudo[4354]: pam_unix(sudo:session): session closed for user root Dec 23 18:54:31 volumio-x86 volumio[908]: error: MPD error: The expression evaluated to a falsy value: Dec 23 18:54:31 volumio-x86 volumio[908]: assert.ok(self.idling) Dec 23 18:54:31 volumio-x86 volumio[908]: error: The expression evaluated to a falsy value: Dec 23 18:54:31 volumio-x86 volumio[908]: assert.ok(self.idling) Dec 23 18:54:31 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 23 18:54:31 volumio-x86 volumio[908]: info: CoreStateMachine::getcurrentVolume Dec 23 18:54:31 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioRetrievevolume Dec 23 18:54:31 volumio-x86 volumio[908]: error: MPD error: The expression evaluated to a falsy value: Dec 23 18:54:31 volumio-x86 volumio[908]: assert.ok(self.idling) Dec 23 18:54:31 volumio-x86 volumio[908]: error: The expression evaluated to a falsy value: Dec 23 18:54:31 volumio-x86 volumio[908]: assert.ok(self.idling) Dec 23 18:54:31 volumio-x86 volumio[908]: error: MPD error: The expression evaluated to a falsy value: Dec 23 18:54:31 volumio-x86 volumio[908]: assert.ok(self.idling) Dec 23 18:54:31 volumio-x86 volumio[908]: error: The expression evaluated to a falsy value: Dec 23 18:54:31 volumio-x86 volumio[908]: assert.ok(self.idling) Dec 23 18:54:31 volumio-x86 volumio[908]: error: updateQueue error: null Dec 23 18:54:31 volumio-x86 volumio[908]: error: updateQueue error: null Dec 23 18:54:31 volumio-x86 volumio[908]: Unable to parse: Dec 23 18:54:31 volumio-x86 volumio[908]: Simple mixer control 'IEC958',0 Dec 23 18:54:31 volumio-x86 volumio[908]: Capabilities: pswitch pswitch-joined Dec 23 18:54:31 volumio-x86 volumio[908]: Playback channels: Mono Dec 23 18:54:31 volumio-x86 volumio[908]: Mono: Playback [on] Dec 23 18:54:31 volumio-x86 volumio[908]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Dec 23 18:54:31 volumio-x86 volumio[908]: Unable to parse: Dec 23 18:54:31 volumio-x86 volumio[908]: Simple mixer control 'IEC958',0 Dec 23 18:54:31 volumio-x86 volumio[908]: Capabilities: pswitch pswitch-joined Dec 23 18:54:31 volumio-x86 volumio[908]: Playback channels: Mono Dec 23 18:54:31 volumio-x86 volumio[908]: Mono: Playback [on] Dec 23 18:54:31 volumio-x86 volumio[908]: info: VolumeController:: Volume=undefined Mute =false Dec 23 18:54:31 volumio-x86 volumio[908]: info: CoreStateMachine::pushState Dec 23 18:54:31 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:54:31 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPushState Dec 23 18:54:31 volumio-x86 volumio[908]: info: CoreStateMachine::updateTrackBlock Dec 23 18:54:31 volumio-x86 volumio[908]: info: CorePlayQueue::getTrackBlock Dec 23 18:54:31 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioRetrievevolume Dec 23 18:54:31 volumio-x86 volumio[908]: Unable to parse: Dec 23 18:54:31 volumio-x86 volumio[908]: Simple mixer control 'IEC958',0 Dec 23 18:54:31 volumio-x86 volumio[908]: Capabilities: pswitch pswitch-joined Dec 23 18:54:31 volumio-x86 volumio[908]: Playback channels: Mono Dec 23 18:54:31 volumio-x86 volumio[908]: Mono: Playback [on] Dec 23 18:54:31 volumio-x86 volumio[908]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Dec 23 18:54:31 volumio-x86 volumio[908]: Unable to parse: Dec 23 18:54:31 volumio-x86 volumio[908]: Simple mixer control 'IEC958',0 Dec 23 18:54:31 volumio-x86 volumio[908]: Capabilities: pswitch pswitch-joined Dec 23 18:54:31 volumio-x86 volumio[908]: Playback channels: Mono Dec 23 18:54:31 volumio-x86 volumio[908]: Mono: Playback [on] Dec 23 18:54:31 volumio-x86 volumio[908]: info: VolumeController:: Volume=undefined Mute =false Dec 23 18:54:31 volumio-x86 volumio[908]: info: CoreStateMachine::pushState Dec 23 18:54:31 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:54:31 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPushState Dec 23 18:54:32 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 23 18:54:32 volumio-x86 volumio[908]: info: CoreStateMachine::getcurrentVolume Dec 23 18:54:32 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioRetrievevolume Dec 23 18:54:32 volumio-x86 volumio[908]: Unable to parse: Dec 23 18:54:32 volumio-x86 volumio[908]: Simple mixer control 'IEC958',0 Dec 23 18:54:32 volumio-x86 volumio[908]: Capabilities: pswitch pswitch-joined Dec 23 18:54:32 volumio-x86 volumio[908]: Playback channels: Mono Dec 23 18:54:32 volumio-x86 volumio[908]: Mono: Playback [on] Dec 23 18:54:32 volumio-x86 volumio[908]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Dec 23 18:54:32 volumio-x86 volumio[908]: Unable to parse: Dec 23 18:54:32 volumio-x86 volumio[908]: Simple mixer control 'IEC958',0 Dec 23 18:54:32 volumio-x86 volumio[908]: Capabilities: pswitch pswitch-joined Dec 23 18:54:32 volumio-x86 volumio[908]: Playback channels: Mono Dec 23 18:54:32 volumio-x86 volumio[908]: Mono: Playback [on] Dec 23 18:54:32 volumio-x86 volumio[908]: info: VolumeController:: Volume=undefined Mute =false Dec 23 18:54:32 volumio-x86 volumio[908]: info: CoreStateMachine::pushState Dec 23 18:54:32 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:54:32 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPushState Dec 23 18:54:32 volumio-x86 volumio[908]: info: CoreStateMachine::updateTrackBlock Dec 23 18:54:32 volumio-x86 volumio[908]: info: CorePlayQueue::getTrackBlock Dec 23 18:54:32 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioRetrievevolume Dec 23 18:54:32 volumio-x86 volumio[908]: Unable to parse: Dec 23 18:54:32 volumio-x86 volumio[908]: Simple mixer control 'IEC958',0 Dec 23 18:54:32 volumio-x86 volumio[908]: Capabilities: pswitch pswitch-joined Dec 23 18:54:32 volumio-x86 volumio[908]: Playback channels: Mono Dec 23 18:54:32 volumio-x86 volumio[908]: Mono: Playback [on] Dec 23 18:54:32 volumio-x86 volumio[908]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Dec 23 18:54:32 volumio-x86 volumio[908]: error: Upnp client error: Error: This socket has been ended by the other party Dec 23 18:54:32 volumio-x86 volumio[908]: Unable to parse: Dec 23 18:54:32 volumio-x86 volumio[908]: Simple mixer control 'IEC958',0 Dec 23 18:54:32 volumio-x86 volumio[908]: Capabilities: pswitch pswitch-joined Dec 23 18:54:32 volumio-x86 volumio[908]: Playback channels: Mono Dec 23 18:54:32 volumio-x86 volumio[908]: Mono: Playback [on] Dec 23 18:54:32 volumio-x86 volumio[908]: info: VolumeController:: Volume=undefined Mute =false Dec 23 18:54:32 volumio-x86 volumio[908]: info: CoreStateMachine::pushState Dec 23 18:54:32 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:54:32 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPushState Dec 23 18:54:32 volumio-x86 volumio[908]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Dec 23 18:54:32 volumio-x86 volumio[908]: info: Retrying Login Due to Network Error Dec 23 18:54:33 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 23 18:54:33 volumio-x86 volumio[908]: info: CoreStateMachine::getcurrentVolume Dec 23 18:54:33 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioRetrievevolume Dec 23 18:54:33 volumio-x86 volumio[908]: Unable to parse: Dec 23 18:54:33 volumio-x86 volumio[908]: Simple mixer control 'IEC958',0 Dec 23 18:54:33 volumio-x86 volumio[908]: Capabilities: pswitch pswitch-joined Dec 23 18:54:33 volumio-x86 volumio[908]: Playback channels: Mono Dec 23 18:54:33 volumio-x86 volumio[908]: Mono: Playback [on] Dec 23 18:54:33 volumio-x86 volumio[908]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Dec 23 18:54:33 volumio-x86 volumio[908]: Unable to parse: Dec 23 18:54:33 volumio-x86 volumio[908]: Simple mixer control 'IEC958',0 Dec 23 18:54:33 volumio-x86 volumio[908]: Capabilities: pswitch pswitch-joined Dec 23 18:54:33 volumio-x86 volumio[908]: Playback channels: Mono Dec 23 18:54:33 volumio-x86 volumio[908]: Mono: Playback [on] Dec 23 18:54:33 volumio-x86 volumio[908]: info: VolumeController:: Volume=undefined Mute =false Dec 23 18:54:33 volumio-x86 volumio[908]: info: CoreStateMachine::pushState Dec 23 18:54:33 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:54:33 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPushState Dec 23 18:54:33 volumio-x86 volumio[908]: info: CoreStateMachine::updateTrackBlock Dec 23 18:54:33 volumio-x86 volumio[908]: info: CorePlayQueue::getTrackBlock Dec 23 18:54:33 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioRetrievevolume Dec 23 18:54:33 volumio-x86 volumio[908]: Unable to parse: Dec 23 18:54:33 volumio-x86 volumio[908]: Simple mixer control 'IEC958',0 Dec 23 18:54:33 volumio-x86 volumio[908]: Capabilities: pswitch pswitch-joined Dec 23 18:54:33 volumio-x86 volumio[908]: Playback channels: Mono Dec 23 18:54:33 volumio-x86 volumio[908]: Mono: Playback [on] Dec 23 18:54:33 volumio-x86 volumio[908]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Dec 23 18:54:33 volumio-x86 volumio[908]: Unable to parse: Dec 23 18:54:33 volumio-x86 volumio[908]: Simple mixer control 'IEC958',0 Dec 23 18:54:33 volumio-x86 volumio[908]: Capabilities: pswitch pswitch-joined Dec 23 18:54:33 volumio-x86 volumio[908]: Playback channels: Mono Dec 23 18:54:33 volumio-x86 volumio[908]: Mono: Playback [on] Dec 23 18:54:33 volumio-x86 volumio[908]: info: VolumeController:: Volume=undefined Mute =false Dec 23 18:54:33 volumio-x86 volumio[908]: info: CoreStateMachine::pushState Dec 23 18:54:33 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:54:33 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPushState Dec 23 18:54:35 volumio-x86 volumio[908]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 23 18:54:35 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 23 18:54:35 volumio-x86 volumio[908]: info: Preparing to save Alsa Options, stopping services first Dec 23 18:54:35 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioGetState Dec 23 18:54:35 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:54:35 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPause Dec 23 18:54:35 volumio-x86 volumio[908]: info: CoreStateMachine::pause Dec 23 18:54:35 volumio-x86 volumio[908]: info: Saving Audio Output to: {"output_device":{"value":"0,3","label":"HDMI"}} Dec 23 18:54:35 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 23 18:54:35 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 23 18:54:35 volumio-x86 volumio[908]: info: Found match in Cards Database: setting mixer for card HDMI Dec 23 18:54:35 volumio-x86 volumio[908]: info: Setting mixer IEC958 for card HDMI Dec 23 18:54:35 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 23 18:54:35 volumio-x86 volumio[908]: info: Updating Volume Controller Parameters: Device: 0,3 Name: HDMI Mixer: IEC958 Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 23 18:54:35 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 23 18:54:35 volumio-x86 volumio[908]: info: Disabling external Volume Control Dec 23 18:54:35 volumio-x86 volumio[908]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 23 18:54:35 volumio-x86 volumio[908]: info: Preparing to generate the ALSA configuration file Dec 23 18:54:35 volumio-x86 volumio[908]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Dec 23 18:54:35 volumio-x86 volumio[908]: info: Reading ALSA contributions from plugins. Dec 23 18:54:35 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 23 18:54:35 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 18:54:35 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 23 18:54:35 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:35 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:35 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:35 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:35 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:35 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:35 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 18:54:35 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 23 18:54:35 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 23 18:54:35 volumio-x86 volumio[908]: Unable to parse: Dec 23 18:54:35 volumio-x86 volumio[908]: Simple mixer control 'IEC958',0 Dec 23 18:54:35 volumio-x86 volumio[908]: Capabilities: pswitch pswitch-joined Dec 23 18:54:35 volumio-x86 volumio[908]: Playback channels: Mono Dec 23 18:54:35 volumio-x86 volumio[908]: Mono: Playback [on] Dec 23 18:54:35 volumio-x86 volumio[908]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Dec 23 18:54:35 volumio-x86 volumio[908]: Unable to parse: Dec 23 18:54:35 volumio-x86 volumio[908]: Simple mixer control 'IEC958',0 Dec 23 18:54:35 volumio-x86 volumio[908]: Capabilities: pswitch pswitch-joined Dec 23 18:54:35 volumio-x86 volumio[908]: Playback channels: Mono Dec 23 18:54:35 volumio-x86 volumio[908]: Mono: Playback [on] Dec 23 18:54:35 volumio-x86 volumio[908]: info: VolumeController:: Volume=undefined Mute =false Dec 23 18:54:35 volumio-x86 volumio[908]: info: CoreStateMachine::pushState Dec 23 18:54:35 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:54:35 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPushState Dec 23 18:54:35 volumio-x86 volumio[908]: info: Asound.conf file unchanged, so no further update is needed Dec 23 18:54:35 volumio-x86 volumio[908]: info: Output device has changed, restarting MPD Dec 23 18:54:35 volumio-x86 sudo[4429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 23 18:54:35 volumio-x86 sudo[4429]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 18:54:35 volumio-x86 sudo[4429]: pam_unix(sudo:session): session closed for user root Dec 23 18:54:35 volumio-x86 volumio[908]: info: Output device has changed, restarting Shairport Sync Dec 23 18:54:35 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:35 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 18:54:35 volumio-x86 sudo[4432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 23 18:54:35 volumio-x86 sudo[4432]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 18:54:35 volumio-x86 systemd[1]: Stopping Music Player Daemon... Dec 23 18:54:35 volumio-x86 volumio[908]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 23 18:54:35 volumio-x86 systemd[1]: mpd.service: Succeeded. Dec 23 18:54:35 volumio-x86 systemd[1]: Stopped Music Player Daemon. Dec 23 18:54:35 volumio-x86 volumio[908]: info: MPD Permissions set Dec 23 18:54:35 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:35 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:35 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:35 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:35 volumio-x86 systemd[1]: Starting Music Player Daemon... Dec 23 18:54:35 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 18:54:35 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 23 18:54:35 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:35 volumio-x86 volumio[908]: info: Starting Shairport Sync Dec 23 18:54:35 volumio-x86 sudo[4437]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 23 18:54:35 volumio-x86 sudo[4437]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 18:54:35 volumio-x86 sudo[4445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 23 18:54:35 volumio-x86 sudo[4445]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 18:54:35 volumio-x86 sudo[4437]: pam_unix(sudo:session): session closed for user root Dec 23 18:54:35 volumio-x86 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 23 18:54:35 volumio-x86 systemd[1]: shairport-sync.service: Succeeded. Dec 23 18:54:35 volumio-x86 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 23 18:54:35 volumio-x86 systemd[1]: shairport-sync.service: Start request repeated too quickly. Dec 23 18:54:35 volumio-x86 systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Dec 23 18:54:35 volumio-x86 systemd[1]: Failed to start Shairport Sync - AirPlay Audio Receiver. Dec 23 18:54:35 volumio-x86 sudo[4445]: pam_unix(sudo:session): session closed for user root Dec 23 18:54:35 volumio-x86 volumio[908]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Dec 23 18:54:35 volumio-x86 volumio[908]: Job for shairport-sync.service failed. Dec 23 18:54:35 volumio-x86 volumio[908]: See "systemctl status shairport-sync.service" and "journalctl -xe" for details. Dec 23 18:54:36 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 18:54:37 volumio-x86 mpd[4448]: Dec 23 18:54 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 23 18:54:37 volumio-x86 systemd[1]: Started Music Player Daemon. Dec 23 18:54:37 volumio-x86 sudo[4432]: pam_unix(sudo:session): session closed for user root Dec 23 18:54:37 volumio-x86 volumio[908]: error: updateQueue error: null Dec 23 18:54:39 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 23 18:54:39 volumio-x86 volumio[908]: info: CoreStateMachine::getcurrentVolume Dec 23 18:54:39 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioRetrievevolume Dec 23 18:54:39 volumio-x86 volumio[908]: Unable to parse: Dec 23 18:54:39 volumio-x86 volumio[908]: Simple mixer control 'IEC958',0 Dec 23 18:54:39 volumio-x86 volumio[908]: Capabilities: pswitch pswitch-joined Dec 23 18:54:39 volumio-x86 volumio[908]: Playback channels: Mono Dec 23 18:54:39 volumio-x86 volumio[908]: Mono: Playback [on] Dec 23 18:54:39 volumio-x86 volumio[908]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Dec 23 18:54:39 volumio-x86 volumio[908]: Unable to parse: Dec 23 18:54:39 volumio-x86 volumio[908]: Simple mixer control 'IEC958',0 Dec 23 18:54:39 volumio-x86 volumio[908]: Capabilities: pswitch pswitch-joined Dec 23 18:54:39 volumio-x86 volumio[908]: Playback channels: Mono Dec 23 18:54:39 volumio-x86 volumio[908]: Mono: Playback [on] Dec 23 18:54:39 volumio-x86 volumio[908]: info: VolumeController:: Volume=undefined Mute =false Dec 23 18:54:39 volumio-x86 volumio[908]: info: CoreStateMachine::pushState Dec 23 18:54:39 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:54:39 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPushState Dec 23 18:54:39 volumio-x86 volumio[908]: info: CoreStateMachine::updateTrackBlock Dec 23 18:54:39 volumio-x86 volumio[908]: info: CorePlayQueue::getTrackBlock Dec 23 18:54:39 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioRetrievevolume Dec 23 18:54:39 volumio-x86 volumio[908]: Unable to parse: Dec 23 18:54:39 volumio-x86 volumio[908]: Simple mixer control 'IEC958',0 Dec 23 18:54:39 volumio-x86 volumio[908]: Capabilities: pswitch pswitch-joined Dec 23 18:54:39 volumio-x86 volumio[908]: Playback channels: Mono Dec 23 18:54:39 volumio-x86 volumio[908]: Mono: Playback [on] Dec 23 18:54:39 volumio-x86 volumio[908]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Dec 23 18:54:39 volumio-x86 volumio[908]: Unable to parse: Dec 23 18:54:39 volumio-x86 volumio[908]: Simple mixer control 'IEC958',0 Dec 23 18:54:39 volumio-x86 volumio[908]: Capabilities: pswitch pswitch-joined Dec 23 18:54:39 volumio-x86 volumio[908]: Playback channels: Mono Dec 23 18:54:39 volumio-x86 volumio[908]: Mono: Playback [on] Dec 23 18:54:39 volumio-x86 volumio[908]: info: VolumeController:: Volume=undefined Mute =false Dec 23 18:54:39 volumio-x86 volumio[908]: info: CoreStateMachine::pushState Dec 23 18:54:39 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:54:39 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPushState Dec 23 18:54:42 volumio-x86 volumio[908]: info: MyVolumio login type: Token Dec 23 18:54:44 volumio-x86 ntpd[1264]: 5.79.108.34 local addr 192.168.124.109 -> Dec 23 18:54:45 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPlay Dec 23 18:54:45 volumio-x86 volumio[908]: info: CoreStateMachine::play index undefined Dec 23 18:54:45 volumio-x86 volumio[908]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 18:54:45 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:54:45 volumio-x86 volumio[908]: info: CoreStateMachine::startPlaybackTimer Dec 23 18:54:45 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:54:45 volumio-x86 volumio[908]: info: ControllerMpd::resume Dec 23 18:54:45 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand play Dec 23 18:54:45 volumio-x86 volumio[908]: info: sendMpdCommand play took 1 milliseconds Dec 23 18:54:54 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioGetState Dec 23 18:54:54 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:54:55 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPlay Dec 23 18:54:55 volumio-x86 volumio[908]: info: CoreStateMachine::play index undefined Dec 23 18:54:55 volumio-x86 volumio[908]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 18:54:55 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:54:55 volumio-x86 volumio[908]: info: CoreStateMachine::startPlaybackTimer Dec 23 18:54:55 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:54:55 volumio-x86 volumio[908]: info: ControllerMpd::resume Dec 23 18:54:55 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand play Dec 23 18:54:55 volumio-x86 volumio[908]: info: sendMpdCommand play took 1 milliseconds Dec 23 18:55:04 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPlay Dec 23 18:55:04 volumio-x86 volumio[908]: info: CoreStateMachine::play index 0 Dec 23 18:55:04 volumio-x86 volumio[908]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 18:55:04 volumio-x86 volumio[908]: info: CoreStateMachine::stop Dec 23 18:55:04 volumio-x86 volumio[908]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 18:55:04 volumio-x86 volumio[908]: info: CoreStateMachine::updateTrackBlock Dec 23 18:55:04 volumio-x86 volumio[908]: info: CorePlayQueue::getTrackBlock Dec 23 18:55:04 volumio-x86 volumio[908]: info: CoreStateMachine::stPlaybackTimer Dec 23 18:55:04 volumio-x86 volumio[908]: info: CoreStateMachine::pushState Dec 23 18:55:04 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:55:04 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 18:55:04 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPushState Dec 23 18:55:04 volumio-x86 volumio[908]: info: CoreStateMachine::serviceStop Dec 23 18:55:04 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:55:04 volumio-x86 volumio[908]: info: CoreCommandRouter::serviceStop Dec 23 18:55:04 volumio-x86 volumio[908]: info: ControllerMpd::stop Dec 23 18:55:04 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand stop Dec 23 18:55:04 volumio-x86 volumio[908]: info: sendMpdCommand stop took 19 milliseconds Dec 23 18:55:04 volumio-x86 volumio[908]: info: CoreStateMachine::play index undefined Dec 23 18:55:04 volumio-x86 volumio[908]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 18:55:04 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:55:04 volumio-x86 volumio[908]: info: CoreStateMachine::startPlaybackTimer Dec 23 18:55:04 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:55:04 volumio-x86 volumio[908]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/musics/不必在乎我是谁/林忆莲-不必在乎我是谁.flac Dec 23 18:55:04 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand stop Dec 23 18:55:04 volumio-x86 volumio[908]: info: sendMpdCommand stop took 3 milliseconds Dec 23 18:55:04 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand clear Dec 23 18:55:04 volumio-x86 volumio[908]: info: Dec 23 18:55:04 volumio-x86 volumio[908]: ---------------------------- MPD announces system playlist update Dec 23 18:55:04 volumio-x86 volumio[908]: info: Ignoring MPD Status Update Dec 23 18:55:04 volumio-x86 volumio[908]: info: sendMpdCommand clear took 4 milliseconds Dec 23 18:55:04 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/musics/不必在乎我是谁/林忆莲-不必在乎我是谁.flac" Dec 23 18:55:04 volumio-x86 volumio[908]: error: updateQueue error: null Dec 23 18:55:04 volumio-x86 volumio[908]: info: Dec 23 18:55:04 volumio-x86 volumio[908]: ---------------------------- MPD announces system playlist update Dec 23 18:55:04 volumio-x86 volumio[908]: info: Ignoring MPD Status Update Dec 23 18:55:04 volumio-x86 volumio[908]: info: ------------------------------ 5ms Dec 23 18:55:04 volumio-x86 volumio[908]: info: sendMpdCommand add "INTERNAL/musics/不必在乎我是谁/林忆莲-不必在乎我是谁.flac" took 3 milliseconds Dec 23 18:55:04 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand play Dec 23 18:55:04 volumio-x86 volumio[908]: info: ------------------------------ 4ms Dec 23 18:55:04 volumio-x86 volumio[908]: info: sendMpdCommand play took 5 milliseconds Dec 23 18:55:04 volumio-x86 volumio[908]: info: Dec 23 18:55:04 volumio-x86 volumio[908]: ---------------------------- MPD announces state update: player Dec 23 18:55:04 volumio-x86 volumio[908]: info: ControllerMpd::getState Dec 23 18:55:04 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand status Dec 23 18:55:04 volumio-x86 volumio[908]: info: Dec 23 18:55:04 volumio-x86 volumio[908]: ---------------------------- MPD announces state update: player Dec 23 18:55:04 volumio-x86 volumio[908]: info: sendMpdCommand status took 28 milliseconds Dec 23 18:55:04 volumio-x86 volumio[908]: info: ControllerMpd::getState Dec 23 18:55:04 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand status Dec 23 18:55:04 volumio-x86 volumio[908]: verbose: ControllerMpd::parseState Dec 23 18:55:04 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 18:55:04 volumio-x86 volumio[908]: info: sendMpdCommand status took 2 milliseconds Dec 23 18:55:04 volumio-x86 volumio[908]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 23 18:55:04 volumio-x86 volumio[908]: verbose: ControllerMpd::parseState Dec 23 18:55:04 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 18:55:04 volumio-x86 volumio[908]: verbose: ControllerMpd::parseTrackInfo Dec 23 18:55:04 volumio-x86 volumio[908]: info: ControllerMpd::pushState Dec 23 18:55:04 volumio-x86 volumio[908]: info: CoreCommandRouter::servicePushState Dec 23 18:55:04 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:55:04 volumio-x86 volumio[908]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":261,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"不必在乎我是谁","artist":"林忆莲","album":"不必在乎我是谁","uri":"INTERNAL/musics/不必在乎我是谁/林忆莲-不必在乎我是谁.flac","trackType":"flac"} Dec 23 18:55:04 volumio-x86 volumio[908]: verbose: CURRENT POSITION 0 Dec 23 18:55:04 volumio-x86 volumio[908]: info: CoreStateMachine::syncState stateService play Dec 23 18:55:04 volumio-x86 volumio[908]: info: CoreStateMachine::syncState currentStatus stop Dec 23 18:55:04 volumio-x86 volumio[908]: info: ------------------------------ 47ms Dec 23 18:55:04 volumio-x86 volumio[908]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 23 18:55:04 volumio-x86 volumio[908]: verbose: ControllerMpd::parseTrackInfo Dec 23 18:55:04 volumio-x86 volumio[908]: info: ControllerMpd::pushState Dec 23 18:55:04 volumio-x86 volumio[908]: info: CoreCommandRouter::servicePushState Dec 23 18:55:04 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:55:04 volumio-x86 volumio[908]: verbose: STATE SERVICE {"status":"play","position":0,"seek":614,"duration":261,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"690 Kbps","isStreaming":false,"title":"不必在乎我是谁","artist":"林忆莲","album":"不必在乎我是谁","uri":"INTERNAL/musics/不必在乎我是谁/林忆莲-不必在乎我是谁.flac","trackType":"flac"} Dec 23 18:55:04 volumio-x86 volumio[908]: verbose: CURRENT POSITION 0 Dec 23 18:55:04 volumio-x86 volumio[908]: info: CoreStateMachine::syncState stateService play Dec 23 18:55:04 volumio-x86 volumio[908]: info: CoreStateMachine::syncState currentStatus play Dec 23 18:55:04 volumio-x86 volumio[908]: info: Received an update from plugin. extracting info from payload Dec 23 18:55:04 volumio-x86 volumio[908]: info: CoreStateMachine::pushState Dec 23 18:55:04 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:55:04 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 18:55:04 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPushState Dec 23 18:55:04 volumio-x86 kernel: snd_hda_intel 0000:00:01.1: IRQ timing workaround is activated for card #0. Suggest a bigger bdl_pos_adj. Dec 23 18:55:04 volumio-x86 volumio[908]: info: CoreStateMachine::pushState Dec 23 18:55:04 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:55:04 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPushState Dec 23 18:55:04 volumio-x86 volumio[908]: info: ------------------------------ 86ms Dec 23 18:55:06 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPlay Dec 23 18:55:06 volumio-x86 volumio[908]: info: CoreStateMachine::play index 1 Dec 23 18:55:06 volumio-x86 volumio[908]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 18:55:06 volumio-x86 volumio[908]: info: CoreStateMachine::stop Dec 23 18:55:06 volumio-x86 volumio[908]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 18:55:06 volumio-x86 volumio[908]: info: CoreStateMachine::stPlaybackTimer Dec 23 18:55:06 volumio-x86 volumio[908]: info: CoreStateMachine::updateTrackBlock Dec 23 18:55:06 volumio-x86 volumio[908]: info: CorePlayQueue::getTrackBlock Dec 23 18:55:06 volumio-x86 volumio[908]: info: CoreStateMachine::pushState Dec 23 18:55:06 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:55:06 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 18:55:06 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPushState Dec 23 18:55:06 volumio-x86 volumio[908]: info: CoreStateMachine::serviceStop Dec 23 18:55:06 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 0 Dec 23 18:55:06 volumio-x86 volumio[908]: info: CoreCommandRouter::serviceStop Dec 23 18:55:06 volumio-x86 volumio[908]: info: ControllerMpd::stop Dec 23 18:55:06 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand stop Dec 23 18:55:06 volumio-x86 volumio[908]: info: Dec 23 18:55:06 volumio-x86 volumio[908]: ---------------------------- MPD announces state update: player Dec 23 18:55:06 volumio-x86 volumio[908]: info: sendMpdCommand stop took 37 milliseconds Dec 23 18:55:06 volumio-x86 volumio[908]: info: ControllerMpd::getState Dec 23 18:55:06 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand status Dec 23 18:55:06 volumio-x86 volumio[908]: info: CoreStateMachine::play index undefined Dec 23 18:55:06 volumio-x86 volumio[908]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 18:55:06 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 1 Dec 23 18:55:06 volumio-x86 volumio[908]: info: CoreStateMachine::startPlaybackTimer Dec 23 18:55:06 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 1 Dec 23 18:55:06 volumio-x86 volumio[908]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/musics/不必在乎我是谁/林忆莲-快乐的坏东西.flac Dec 23 18:55:06 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand stop Dec 23 18:55:06 volumio-x86 volumio[908]: info: sendMpdCommand status took 8 milliseconds Dec 23 18:55:06 volumio-x86 volumio[908]: info: sendMpdCommand stop took 2 milliseconds Dec 23 18:55:06 volumio-x86 volumio[908]: verbose: ControllerMpd::parseState Dec 23 18:55:06 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 18:55:06 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand clear Dec 23 18:55:06 volumio-x86 volumio[908]: info: Dec 23 18:55:06 volumio-x86 volumio[908]: ---------------------------- MPD announces system playlist update Dec 23 18:55:06 volumio-x86 volumio[908]: info: Ignoring MPD Status Update Dec 23 18:55:06 volumio-x86 volumio[908]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 23 18:55:06 volumio-x86 volumio[908]: info: sendMpdCommand clear took 4 milliseconds Dec 23 18:55:06 volumio-x86 volumio[908]: verbose: ControllerMpd::parseTrackInfo Dec 23 18:55:06 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/musics/不必在乎我是谁/林忆莲-快乐的坏东西.flac" Dec 23 18:55:06 volumio-x86 volumio[908]: info: ControllerMpd::pushState Dec 23 18:55:06 volumio-x86 volumio[908]: info: CoreCommandRouter::servicePushState Dec 23 18:55:06 volumio-x86 volumio[908]: info: CoreStateMachine::pushState Dec 23 18:55:06 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 1 Dec 23 18:55:06 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 18:55:06 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPushState Dec 23 18:55:06 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 1 Dec 23 18:55:06 volumio-x86 volumio[908]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"不必在乎我是谁","artist":"林忆莲","album":"不必在乎我是谁","uri":"INTERNAL/musics/不必在乎我是谁/林忆莲-不必在乎我是谁.flac","trackType":"flac"} Dec 23 18:55:06 volumio-x86 volumio[908]: verbose: CURRENT POSITION 1 Dec 23 18:55:06 volumio-x86 volumio[908]: info: CoreStateMachine::syncState stateService stop Dec 23 18:55:06 volumio-x86 volumio[908]: info: CoreStateMachine::syncState currentStatus stop Dec 23 18:55:06 volumio-x86 volumio[908]: info: CoreStateMachine::pushState Dec 23 18:55:06 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 1 Dec 23 18:55:06 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPushState Dec 23 18:55:06 volumio-x86 volumio[908]: info: No code Dec 23 18:55:06 volumio-x86 volumio[908]: info: CoreStateMachine::pushState Dec 23 18:55:06 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 1 Dec 23 18:55:06 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPushState Dec 23 18:55:06 volumio-x86 volumio[908]: info: ------------------------------ 92ms Dec 23 18:55:06 volumio-x86 volumio[908]: error: updateQueue error: null Dec 23 18:55:06 volumio-x86 volumio[908]: info: Dec 23 18:55:06 volumio-x86 volumio[908]: ---------------------------- MPD announces system playlist update Dec 23 18:55:06 volumio-x86 volumio[908]: info: Ignoring MPD Status Update Dec 23 18:55:06 volumio-x86 volumio[908]: info: ------------------------------ 83ms Dec 23 18:55:06 volumio-x86 volumio[908]: info: sendMpdCommand add "INTERNAL/musics/不必在乎我是谁/林忆莲-快乐的坏东西.flac" took 71 milliseconds Dec 23 18:55:06 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand play Dec 23 18:55:06 volumio-x86 volumio[908]: info: Dec 23 18:55:06 volumio-x86 volumio[908]: ---------------------------- MPD announces state update: player Dec 23 18:55:06 volumio-x86 volumio[908]: info: ------------------------------ 22ms Dec 23 18:55:06 volumio-x86 volumio[908]: info: sendMpdCommand play took 20 milliseconds Dec 23 18:55:06 volumio-x86 volumio[908]: info: ControllerMpd::getState Dec 23 18:55:06 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand status Dec 23 18:55:06 volumio-x86 volumio[908]: info: Dec 23 18:55:06 volumio-x86 volumio[908]: ---------------------------- MPD announces state update: player Dec 23 18:55:06 volumio-x86 volumio[908]: info: ControllerMpd::getState Dec 23 18:55:06 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand status Dec 23 18:55:06 volumio-x86 volumio[908]: info: sendMpdCommand status took 5 milliseconds Dec 23 18:55:06 volumio-x86 volumio[908]: verbose: ControllerMpd::parseState Dec 23 18:55:06 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 18:55:06 volumio-x86 volumio[908]: info: sendMpdCommand status took 12 milliseconds Dec 23 18:55:06 volumio-x86 volumio[908]: info: sendMpdCommand playlistinfo took 12 milliseconds Dec 23 18:55:06 volumio-x86 volumio[908]: verbose: ControllerMpd::parseState Dec 23 18:55:06 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 18:55:06 volumio-x86 volumio[908]: verbose: ControllerMpd::parseTrackInfo Dec 23 18:55:06 volumio-x86 volumio[908]: info: ControllerMpd::pushState Dec 23 18:55:06 volumio-x86 volumio[908]: info: CoreCommandRouter::servicePushState Dec 23 18:55:06 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 1 Dec 23 18:55:06 volumio-x86 volumio[908]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":296,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"快乐的坏东西","artist":"林忆莲","album":"不必在乎我是谁","uri":"INTERNAL/musics/不必在乎我是谁/林忆莲-快乐的坏东西.flac","trackType":"flac"} Dec 23 18:55:06 volumio-x86 volumio[908]: verbose: CURRENT POSITION 1 Dec 23 18:55:06 volumio-x86 volumio[908]: info: CoreStateMachine::syncState stateService play Dec 23 18:55:06 volumio-x86 volumio[908]: info: CoreStateMachine::syncState currentStatus stop Dec 23 18:55:06 volumio-x86 volumio[908]: info: ------------------------------ 23ms Dec 23 18:55:06 volumio-x86 volumio[908]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 23 18:55:06 volumio-x86 volumio[908]: verbose: ControllerMpd::parseTrackInfo Dec 23 18:55:06 volumio-x86 volumio[908]: info: ControllerMpd::pushState Dec 23 18:55:06 volumio-x86 volumio[908]: info: CoreCommandRouter::servicePushState Dec 23 18:55:06 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 1 Dec 23 18:55:06 volumio-x86 volumio[908]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":296,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"快乐的坏东西","artist":"林忆莲","album":"不必在乎我是谁","uri":"INTERNAL/musics/不必在乎我是谁/林忆莲-快乐的坏东西.flac","trackType":"flac"} Dec 23 18:55:06 volumio-x86 volumio[908]: verbose: CURRENT POSITION 1 Dec 23 18:55:06 volumio-x86 volumio[908]: info: CoreStateMachine::syncState stateService play Dec 23 18:55:06 volumio-x86 volumio[908]: info: CoreStateMachine::syncState currentStatus play Dec 23 18:55:06 volumio-x86 volumio[908]: info: Received an update from plugin. extracting info from payload Dec 23 18:55:06 volumio-x86 volumio[908]: info: CoreStateMachine::pushState Dec 23 18:55:06 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 1 Dec 23 18:55:06 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 18:55:06 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPushState Dec 23 18:55:06 volumio-x86 volumio[908]: info: CoreStateMachine::pushState Dec 23 18:55:06 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 1 Dec 23 18:55:06 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPushState Dec 23 18:55:06 volumio-x86 volumio[908]: info: ------------------------------ 113ms Dec 23 18:55:12 volumio-x86 volumio[908]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Dec 23 18:55:12 volumio-x86 volumio[908]: info: Retrying Login Due to Network Error Dec 23 18:55:22 volumio-x86 volumio[908]: info: MyVolumio login type: Token Dec 23 18:55:30 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 23 18:55:30 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 23 18:55:32 volumio-x86 sudo[4629]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 23 18:55:32 volumio-x86 sudo[4629]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 18:55:32 volumio-x86 sudo[4629]: pam_unix(sudo:session): session closed for user root Dec 23 18:55:32 volumio-x86 sudo[4632]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 23 18:55:32 volumio-x86 sudo[4632]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 18:55:32 volumio-x86 sudo[4632]: pam_unix(sudo:session): session closed for user root Dec 23 18:55:32 volumio-x86 volumio[908]: verbose: New Socket.io Connection to 192.168.124.109 from 192.168.124.112 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_6) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 9 Dec 23 18:55:33 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 23 18:55:33 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioGetVisibleSources Dec 23 18:55:33 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 18:55:33 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioGetState Dec 23 18:55:33 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 1 Dec 23 18:55:33 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 23 18:55:33 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 23 18:55:33 volumio-x86 volumio[908]: info: Received Get System Info Dec 23 18:55:33 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 23 18:55:33 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 23 18:55:33 volumio-x86 volumio[908]: info: Discovery: Getting this device information Dec 23 18:55:33 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioGetState Dec 23 18:55:33 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 1 Dec 23 18:55:33 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 23 18:55:33 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioGetState Dec 23 18:55:33 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 1 Dec 23 18:55:33 volumio-x86 volumio[908]: info: Listing playlists Dec 23 18:55:33 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 23 18:55:33 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 23 18:55:33 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 23 18:55:33 volumio-x86 sudo[4636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 23 18:55:33 volumio-x86 sudo[4636]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 18:55:33 volumio-x86 sudo[4636]: pam_unix(sudo:session): session closed for user root Dec 23 18:55:34 volumio-x86 sudo[4639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 23 18:55:34 volumio-x86 sudo[4639]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 18:55:34 volumio-x86 sudo[4639]: pam_unix(sudo:session): session closed for user root Dec 23 18:55:34 volumio-x86 volumio[908]: verbose: New Socket.io Connection to 192.168.124.109 from 192.168.124.112 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_6) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 10 Dec 23 18:55:34 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 23 18:55:34 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioGetVisibleSources Dec 23 18:55:34 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 18:55:34 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioGetState Dec 23 18:55:34 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 1 Dec 23 18:55:34 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 23 18:55:34 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 23 18:55:34 volumio-x86 volumio[908]: info: Received Get System Info Dec 23 18:55:34 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 23 18:55:34 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 23 18:55:34 volumio-x86 volumio[908]: info: Discovery: Getting this device information Dec 23 18:55:34 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioGetState Dec 23 18:55:34 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 1 Dec 23 18:55:34 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 23 18:55:34 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioGetState Dec 23 18:55:34 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 1 Dec 23 18:55:34 volumio-x86 volumio[908]: info: Listing playlists Dec 23 18:55:34 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 23 18:55:34 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 23 18:55:35 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 23 18:55:35 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioGetState Dec 23 18:55:35 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 1 Dec 23 18:55:35 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 23 18:55:35 volumio-x86 volumio[908]: info: Received Get System Info Dec 23 18:55:35 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 23 18:55:35 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 23 18:55:35 volumio-x86 volumio[908]: info: Discovery: Getting this device information Dec 23 18:55:35 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioGetState Dec 23 18:55:35 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 1 Dec 23 18:55:35 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 23 18:55:36 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 23 18:55:36 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioGetQueue Dec 23 18:55:36 volumio-x86 volumio[908]: info: CoreStateMachine::getQueue Dec 23 18:55:36 volumio-x86 volumio[908]: info: CorePlayQueue::getQueue Dec 23 18:55:38 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPlay Dec 23 18:55:38 volumio-x86 volumio[908]: info: CoreStateMachine::play index 1 Dec 23 18:55:38 volumio-x86 volumio[908]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 18:55:38 volumio-x86 volumio[908]: info: CoreStateMachine::stop Dec 23 18:55:38 volumio-x86 volumio[908]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 18:55:38 volumio-x86 volumio[908]: info: CoreStateMachine::stPlaybackTimer Dec 23 18:55:38 volumio-x86 volumio[908]: info: CoreStateMachine::updateTrackBlock Dec 23 18:55:38 volumio-x86 volumio[908]: info: CorePlayQueue::getTrackBlock Dec 23 18:55:38 volumio-x86 volumio[908]: info: CoreStateMachine::pushState Dec 23 18:55:38 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 1 Dec 23 18:55:38 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 18:55:38 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPushState Dec 23 18:55:38 volumio-x86 volumio[908]: info: CoreStateMachine::serviceStop Dec 23 18:55:38 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 1 Dec 23 18:55:38 volumio-x86 volumio[908]: info: CoreCommandRouter::serviceStop Dec 23 18:55:38 volumio-x86 volumio[908]: info: ControllerMpd::stop Dec 23 18:55:38 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand stop Dec 23 18:55:38 volumio-x86 volumio[908]: info: Dec 23 18:55:38 volumio-x86 volumio[908]: ---------------------------- MPD announces state update: player Dec 23 18:55:38 volumio-x86 volumio[908]: info: sendMpdCommand stop took 27 milliseconds Dec 23 18:55:38 volumio-x86 volumio[908]: info: ControllerMpd::getState Dec 23 18:55:38 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand status Dec 23 18:55:38 volumio-x86 volumio[908]: info: CoreStateMachine::play index undefined Dec 23 18:55:38 volumio-x86 volumio[908]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 18:55:38 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 1 Dec 23 18:55:38 volumio-x86 volumio[908]: info: CoreStateMachine::startPlaybackTimer Dec 23 18:55:38 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 1 Dec 23 18:55:38 volumio-x86 volumio[908]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/musics/不必在乎我是谁/林忆莲-快乐的坏东西.flac Dec 23 18:55:38 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand stop Dec 23 18:55:38 volumio-x86 volumio[908]: info: sendMpdCommand status took 4 milliseconds Dec 23 18:55:38 volumio-x86 volumio[908]: info: sendMpdCommand stop took 2 milliseconds Dec 23 18:55:38 volumio-x86 volumio[908]: verbose: ControllerMpd::parseState Dec 23 18:55:38 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 18:55:38 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand clear Dec 23 18:55:38 volumio-x86 volumio[908]: info: Dec 23 18:55:38 volumio-x86 volumio[908]: ---------------------------- MPD announces system playlist update Dec 23 18:55:38 volumio-x86 volumio[908]: info: Ignoring MPD Status Update Dec 23 18:55:38 volumio-x86 volumio[908]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 23 18:55:38 volumio-x86 volumio[908]: info: sendMpdCommand clear took 4 milliseconds Dec 23 18:55:38 volumio-x86 volumio[908]: verbose: ControllerMpd::parseTrackInfo Dec 23 18:55:38 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/musics/不必在乎我是谁/林忆莲-快乐的坏东西.flac" Dec 23 18:55:38 volumio-x86 volumio[908]: info: ControllerMpd::pushState Dec 23 18:55:38 volumio-x86 volumio[908]: info: CoreCommandRouter::servicePushState Dec 23 18:55:38 volumio-x86 volumio[908]: info: CoreStateMachine::pushState Dec 23 18:55:38 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 1 Dec 23 18:55:38 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPushState Dec 23 18:55:38 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 1 Dec 23 18:55:38 volumio-x86 volumio[908]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"快乐的坏东西","artist":"林忆莲","album":"不必在乎我是谁","uri":"INTERNAL/musics/不必在乎我是谁/林忆莲-快乐的坏东西.flac","trackType":"flac"} Dec 23 18:55:38 volumio-x86 volumio[908]: verbose: CURRENT POSITION 1 Dec 23 18:55:38 volumio-x86 volumio[908]: info: CoreStateMachine::syncState stateService stop Dec 23 18:55:38 volumio-x86 volumio[908]: info: CoreStateMachine::syncState currentStatus stop Dec 23 18:55:38 volumio-x86 volumio[908]: info: CoreStateMachine::pushState Dec 23 18:55:38 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 1 Dec 23 18:55:38 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPushState Dec 23 18:55:38 volumio-x86 volumio[908]: info: No code Dec 23 18:55:38 volumio-x86 volumio[908]: info: CoreStateMachine::pushState Dec 23 18:55:38 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 1 Dec 23 18:55:38 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPushState Dec 23 18:55:38 volumio-x86 volumio[908]: info: ------------------------------ 70ms Dec 23 18:55:38 volumio-x86 volumio[908]: error: updateQueue error: null Dec 23 18:55:38 volumio-x86 volumio[908]: info: Dec 23 18:55:38 volumio-x86 volumio[908]: ---------------------------- MPD announces system playlist update Dec 23 18:55:38 volumio-x86 volumio[908]: info: Ignoring MPD Status Update Dec 23 18:55:38 volumio-x86 volumio[908]: info: ------------------------------ 65ms Dec 23 18:55:38 volumio-x86 volumio[908]: info: sendMpdCommand add "INTERNAL/musics/不必在乎我是谁/林忆莲-快乐的坏东西.flac" took 63 milliseconds Dec 23 18:55:38 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand play Dec 23 18:55:38 volumio-x86 volumio[908]: info: ------------------------------ 21ms Dec 23 18:55:38 volumio-x86 volumio[908]: info: sendMpdCommand play took 18 milliseconds Dec 23 18:55:38 volumio-x86 volumio[908]: info: Dec 23 18:55:38 volumio-x86 volumio[908]: ---------------------------- MPD announces state update: player Dec 23 18:55:38 volumio-x86 volumio[908]: info: ControllerMpd::getState Dec 23 18:55:38 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand status Dec 23 18:55:38 volumio-x86 volumio[908]: info: sendMpdCommand status took 2 milliseconds Dec 23 18:55:38 volumio-x86 volumio[908]: verbose: ControllerMpd::parseState Dec 23 18:55:38 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 18:55:38 volumio-x86 volumio[908]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 23 18:55:38 volumio-x86 volumio[908]: verbose: ControllerMpd::parseTrackInfo Dec 23 18:55:38 volumio-x86 volumio[908]: info: ControllerMpd::pushState Dec 23 18:55:38 volumio-x86 volumio[908]: info: CoreCommandRouter::servicePushState Dec 23 18:55:38 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 1 Dec 23 18:55:38 volumio-x86 volumio[908]: verbose: STATE SERVICE {"status":"play","position":0,"seek":614,"duration":296,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"853 Kbps","isStreaming":false,"title":"快乐的坏东西","artist":"林忆莲","album":"不必在乎我是谁","uri":"INTERNAL/musics/不必在乎我是谁/林忆莲-快乐的坏东西.flac","trackType":"flac"} Dec 23 18:55:38 volumio-x86 volumio[908]: verbose: CURRENT POSITION 1 Dec 23 18:55:38 volumio-x86 volumio[908]: info: CoreStateMachine::syncState stateService play Dec 23 18:55:38 volumio-x86 volumio[908]: info: CoreStateMachine::syncState currentStatus stop Dec 23 18:55:38 volumio-x86 volumio[908]: info: ------------------------------ 16ms Dec 23 18:55:39 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPlay Dec 23 18:55:39 volumio-x86 volumio[908]: info: CoreStateMachine::play index 2 Dec 23 18:55:39 volumio-x86 volumio[908]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 18:55:39 volumio-x86 volumio[908]: info: CoreStateMachine::stop Dec 23 18:55:39 volumio-x86 volumio[908]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 18:55:39 volumio-x86 volumio[908]: info: CoreStateMachine::stPlaybackTimer Dec 23 18:55:39 volumio-x86 volumio[908]: info: CoreStateMachine::updateTrackBlock Dec 23 18:55:39 volumio-x86 volumio[908]: info: CorePlayQueue::getTrackBlock Dec 23 18:55:39 volumio-x86 volumio[908]: info: CoreStateMachine::pushState Dec 23 18:55:39 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 1 Dec 23 18:55:39 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPushState Dec 23 18:55:39 volumio-x86 volumio[908]: info: CoreStateMachine::serviceStop Dec 23 18:55:39 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 1 Dec 23 18:55:39 volumio-x86 volumio[908]: info: CoreCommandRouter::serviceStop Dec 23 18:55:39 volumio-x86 volumio[908]: info: ControllerMpd::stop Dec 23 18:55:39 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand stop Dec 23 18:55:39 volumio-x86 volumio[908]: info: Dec 23 18:55:39 volumio-x86 volumio[908]: ---------------------------- MPD announces state update: player Dec 23 18:55:39 volumio-x86 volumio[908]: info: sendMpdCommand stop took 35 milliseconds Dec 23 18:55:39 volumio-x86 volumio[908]: info: ControllerMpd::getState Dec 23 18:55:39 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand status Dec 23 18:55:39 volumio-x86 volumio[908]: info: CoreStateMachine::play index undefined Dec 23 18:55:39 volumio-x86 volumio[908]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 18:55:39 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 2 Dec 23 18:55:39 volumio-x86 volumio[908]: info: CoreStateMachine::startPlaybackTimer Dec 23 18:55:39 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 2 Dec 23 18:55:39 volumio-x86 volumio[908]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/musics/不必在乎我是谁/林忆莲-给等最久的人.flac Dec 23 18:55:39 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand stop Dec 23 18:55:39 volumio-x86 volumio[908]: info: sendMpdCommand status took 2 milliseconds Dec 23 18:55:39 volumio-x86 volumio[908]: info: sendMpdCommand stop took 2 milliseconds Dec 23 18:55:39 volumio-x86 volumio[908]: verbose: ControllerMpd::parseState Dec 23 18:55:39 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 18:55:39 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand clear Dec 23 18:55:39 volumio-x86 volumio[908]: info: Dec 23 18:55:39 volumio-x86 volumio[908]: ---------------------------- MPD announces system playlist update Dec 23 18:55:39 volumio-x86 volumio[908]: info: Ignoring MPD Status Update Dec 23 18:55:39 volumio-x86 volumio[908]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 23 18:55:39 volumio-x86 volumio[908]: info: sendMpdCommand clear took 2 milliseconds Dec 23 18:55:39 volumio-x86 volumio[908]: verbose: ControllerMpd::parseTrackInfo Dec 23 18:55:39 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/musics/不必在乎我是谁/林忆莲-给等最久的人.flac" Dec 23 18:55:39 volumio-x86 volumio[908]: info: ControllerMpd::pushState Dec 23 18:55:39 volumio-x86 volumio[908]: info: CoreCommandRouter::servicePushState Dec 23 18:55:39 volumio-x86 volumio[908]: info: CoreStateMachine::pushState Dec 23 18:55:39 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 2 Dec 23 18:55:39 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 18:55:39 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPushState Dec 23 18:55:39 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 2 Dec 23 18:55:39 volumio-x86 volumio[908]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"快乐的坏东西","artist":"林忆莲","album":"不必在乎我是谁","uri":"INTERNAL/musics/不必在乎我是谁/林忆莲-快乐的坏东西.flac","trackType":"flac"} Dec 23 18:55:39 volumio-x86 volumio[908]: verbose: CURRENT POSITION 2 Dec 23 18:55:39 volumio-x86 volumio[908]: info: CoreStateMachine::syncState stateService stop Dec 23 18:55:39 volumio-x86 volumio[908]: info: CoreStateMachine::syncState currentStatus stop Dec 23 18:55:39 volumio-x86 volumio[908]: info: CoreStateMachine::pushState Dec 23 18:55:39 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 2 Dec 23 18:55:39 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPushState Dec 23 18:55:39 volumio-x86 volumio[908]: info: No code Dec 23 18:55:39 volumio-x86 volumio[908]: info: CoreStateMachine::pushState Dec 23 18:55:39 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 2 Dec 23 18:55:39 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioPushState Dec 23 18:55:39 volumio-x86 volumio[908]: info: ------------------------------ 59ms Dec 23 18:55:39 volumio-x86 volumio[908]: error: updateQueue error: null Dec 23 18:55:39 volumio-x86 volumio[908]: info: Dec 23 18:55:39 volumio-x86 volumio[908]: ---------------------------- MPD announces system playlist update Dec 23 18:55:39 volumio-x86 volumio[908]: info: Ignoring MPD Status Update Dec 23 18:55:39 volumio-x86 volumio[908]: info: ------------------------------ 57ms Dec 23 18:55:39 volumio-x86 volumio[908]: info: sendMpdCommand add "INTERNAL/musics/不必在乎我是谁/林忆莲-给等最久的人.flac" took 55 milliseconds Dec 23 18:55:39 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand play Dec 23 18:55:39 volumio-x86 volumio[908]: info: ------------------------------ 12ms Dec 23 18:55:39 volumio-x86 volumio[908]: info: sendMpdCommand play took 11 milliseconds Dec 23 18:55:39 volumio-x86 volumio[908]: info: Dec 23 18:55:39 volumio-x86 volumio[908]: ---------------------------- MPD announces state update: player Dec 23 18:55:39 volumio-x86 volumio[908]: info: ControllerMpd::getState Dec 23 18:55:39 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand status Dec 23 18:55:39 volumio-x86 volumio[908]: info: sendMpdCommand status took 12 milliseconds Dec 23 18:55:39 volumio-x86 volumio[908]: verbose: ControllerMpd::parseState Dec 23 18:55:39 volumio-x86 volumio[908]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 18:55:39 volumio-x86 volumio[908]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 23 18:55:39 volumio-x86 volumio[908]: verbose: ControllerMpd::parseTrackInfo Dec 23 18:55:39 volumio-x86 volumio[908]: info: ControllerMpd::pushState Dec 23 18:55:39 volumio-x86 volumio[908]: info: CoreCommandRouter::servicePushState Dec 23 18:55:39 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 2 Dec 23 18:55:39 volumio-x86 volumio[908]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":278,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"给等最久的人","artist":"林忆莲","album":"不必在乎我是谁","uri":"INTERNAL/musics/不必在乎我是谁/林忆莲-给等最久的人.flac","trackType":"flac"} Dec 23 18:55:39 volumio-x86 volumio[908]: verbose: CURRENT POSITION 2 Dec 23 18:55:39 volumio-x86 volumio[908]: info: CoreStateMachine::syncState stateService play Dec 23 18:55:39 volumio-x86 volumio[908]: info: CoreStateMachine::syncState currentStatus stop Dec 23 18:55:39 volumio-x86 volumio[908]: info: ------------------------------ 18ms Dec 23 18:55:44 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 23 18:55:46 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 23 18:55:46 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 23 18:55:47 volumio-x86 sudo[4679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 23 18:55:47 volumio-x86 sudo[4679]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 18:55:47 volumio-x86 sudo[4679]: pam_unix(sudo:session): session closed for user root Dec 23 18:55:47 volumio-x86 sudo[4682]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 23 18:55:47 volumio-x86 sudo[4682]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 18:55:47 volumio-x86 sudo[4682]: pam_unix(sudo:session): session closed for user root Dec 23 18:55:47 volumio-x86 volumio[908]: verbose: New Socket.io Connection to 192.168.124.109 from 192.168.124.112 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_6) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 11 Dec 23 18:55:48 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 23 18:55:48 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioGetVisibleSources Dec 23 18:55:48 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 18:55:48 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioGetState Dec 23 18:55:48 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 2 Dec 23 18:55:48 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 23 18:55:48 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 23 18:55:48 volumio-x86 volumio[908]: info: Received Get System Info Dec 23 18:55:48 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 23 18:55:48 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 23 18:55:48 volumio-x86 volumio[908]: info: Discovery: Getting this device information Dec 23 18:55:48 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioGetState Dec 23 18:55:48 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 2 Dec 23 18:55:48 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 23 18:55:48 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioGetState Dec 23 18:55:48 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 2 Dec 23 18:55:48 volumio-x86 volumio[908]: info: Listing playlists Dec 23 18:55:48 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 23 18:55:48 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 23 18:55:49 volumio-x86 sudo[4699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 23 18:55:49 volumio-x86 sudo[4699]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 18:55:49 volumio-x86 sudo[4699]: pam_unix(sudo:session): session closed for user root Dec 23 18:55:49 volumio-x86 sudo[4702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 23 18:55:49 volumio-x86 sudo[4702]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 18:55:49 volumio-x86 sudo[4702]: pam_unix(sudo:session): session closed for user root Dec 23 18:55:49 volumio-x86 volumio[908]: verbose: New Socket.io Connection to 192.168.124.109 from 192.168.124.112 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_6) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 12 Dec 23 18:55:49 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 23 18:55:49 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 23 18:55:49 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioGetVisibleSources Dec 23 18:55:49 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 18:55:49 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioGetState Dec 23 18:55:49 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 2 Dec 23 18:55:49 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 23 18:55:49 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 23 18:55:49 volumio-x86 volumio[908]: info: Received Get System Info Dec 23 18:55:49 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 23 18:55:49 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 23 18:55:49 volumio-x86 volumio[908]: info: Discovery: Getting this device information Dec 23 18:55:49 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioGetState Dec 23 18:55:49 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 2 Dec 23 18:55:49 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 23 18:55:49 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioGetState Dec 23 18:55:49 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 2 Dec 23 18:55:49 volumio-x86 volumio[908]: info: Listing playlists Dec 23 18:55:49 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 23 18:55:49 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 23 18:55:50 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioGetState Dec 23 18:55:50 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 2 Dec 23 18:55:50 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 23 18:55:51 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 23 18:55:51 volumio-x86 volumio[908]: info: Received Get System Info Dec 23 18:55:51 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 23 18:55:51 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 23 18:55:51 volumio-x86 volumio[908]: info: Discovery: Getting this device information Dec 23 18:55:51 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioGetState Dec 23 18:55:51 volumio-x86 volumio[908]: info: CorePlayQueue::getTrack 2 Dec 23 18:55:51 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 23 18:55:51 volumio-x86 volumio[908]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 23 18:55:51 volumio-x86 volumio[908]: info: CoreCommandRouter::volumioGetQueue Dec 23 18:55:51 volumio-x86 volumio[908]: info: CoreStateMachine::getQueue Dec 23 18:55:51 volumio-x86 volumio[908]: info: CorePlayQueue::getQueue Dec 23 18:55:52 volumio-x86 volumio[908]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Dec 23 18:55:52 volumio-x86 volumio[908]: info: Retrying Login Due to Network Error Dec 23 18:55:54 volumio-x86 volumio[908]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 18:55:54 volumio-x86 volumio[908]: Error: connect ETIMEDOUT 69.63.184.30:443 Dec 23 18:55:54 volumio-x86 volumio[908]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Dec 23 18:55:54 volumio-x86 volumio[908]: errno: -110, Dec 23 18:55:54 volumio-x86 volumio[908]: code: 'ETIMEDOUT', Dec 23 18:55:54 volumio-x86 volumio[908]: syscall: 'connect', Dec 23 18:55:54 volumio-x86 volumio[908]: address: '69.63.184.30', Dec 23 18:55:54 volumio-x86 volumio[908]: port: 443 Dec 23 18:55:54 volumio-x86 volumio[908]: } Dec 23 18:55:54 volumio-x86 volumio[908]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 18:55:55 volumio-x86 sudo[4716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-23 18:54 Dec 23 18:55:55 volumio-x86 sudo[4716]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"