-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Wed 2024-11-27 10:02:11 CST. -- Nov 27 10:01:10 volumio volumio[2402]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Nov 27 10:01:10 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Nov 27 10:01:10 volumio volumio[2402]: info: Preparing to save Alsa Options, stopping services first Nov 27 10:01:10 volumio volumio[2402]: info: CoreCommandRouter::volumioGetState Nov 27 10:01:10 volumio volumio[2402]: info: CorePlayQueue::getTrack 0 Nov 27 10:01:10 volumio volumio[2402]: info: CoreCommandRouter::volumioPause Nov 27 10:01:10 volumio volumio[2402]: info: CoreStateMachine::pause Nov 27 10:01:10 volumio volumio[2402]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI Out"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Nov 27 10:01:10 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 27 10:01:10 volumio volumio[2402]: info: Enabling PI HDMI Output Nov 27 10:01:10 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 27 10:01:10 volumio volumio[2402]: info: Found match in Cards Database: setting mixer PCM for card HDMI Out Nov 27 10:01:10 volumio volumio[2402]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 27 10:01:10 volumio volumio[2402]: info: Updating Volume Controller Parameters: Device: 0 Name: HDMI Out Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Nov 27 10:01:10 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 27 10:01:10 volumio volumio[2402]: info: Disabling external Volume Control Nov 27 10:01:10 volumio volumio[2402]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 27 10:01:10 volumio volumio[2402]: info: Preparing to generate the ALSA configuration file Nov 27 10:01:10 volumio volumio[2402]: info: Raspberry PI HDMI Output Enabled Nov 27 10:01:10 volumio sudo[2861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl store Nov 27 10:01:10 volumio sudo[2861]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 10:01:10 volumio sudo[2861]: pam_unix(sudo:session): session closed for user root Nov 27 10:01:10 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 27 10:01:10 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 10:01:10 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 27 10:01:10 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 10:01:10 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 10:01:10 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 10:01:10 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 10:01:10 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 10:01:10 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 10:01:10 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 27 10:01:10 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 27 10:01:10 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 27 10:01:10 volumio volumio[2402]: info: Alsa Settings successfully stored Nov 27 10:01:10 volumio volumio[2402]: info: VolumeController:: Volume=86 Mute =false Nov 27 10:01:10 volumio volumio[2402]: info: CoreStateMachine::pushState Nov 27 10:01:10 volumio volumio[2402]: info: CorePlayQueue::getTrack 0 Nov 27 10:01:10 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 27 10:01:10 volumio volumio[2402]: info: CoreCommandRouter::volumioPushState Nov 27 10:01:10 volumio volumio[2402]: info: Asound.conf file written Nov 27 10:01:10 volumio sudo[2871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 27 10:01:10 volumio sudo[2871]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 10:01:10 volumio sudo[2871]: pam_unix(sudo:session): session closed for user root Nov 27 10:01:10 volumio volumio[2402]: info: Output device has changed, restarting MPD Nov 27 10:01:10 volumio sudo[2876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 27 10:01:10 volumio sudo[2876]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 10:01:10 volumio volumio[2402]: info: Output device has changed, restarting Shairport Sync Nov 27 10:01:10 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 10:01:10 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 10:01:10 volumio sudo[2876]: pam_unix(sudo:session): session closed for user root Nov 27 10:01:10 volumio sudo[2878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 27 10:01:10 volumio sudo[2878]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 10:01:10 volumio systemd[1]: Stopping Music Player Daemon... Nov 27 10:01:10 volumio volumio[2402]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 27 10:01:10 volumio volumio[2402]: info: Output device has changed, restarting MPD Nov 27 10:01:10 volumio systemd[1]: mpd.service: Succeeded. Nov 27 10:01:10 volumio systemd[1]: Stopped Music Player Daemon. Nov 27 10:01:10 volumio sudo[2885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 27 10:01:10 volumio sudo[2885]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 10:01:11 volumio systemd[1]: Starting Music Player Daemon... Nov 27 10:01:11 volumio volumio[2402]: info: Output device has changed, restarting Shairport Sync Nov 27 10:01:11 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 10:01:11 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 10:01:10 volumio sudo[2885]: pam_unix(sudo:session): session closed for user root Nov 27 10:01:11 volumio sudo[2888]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 27 10:01:11 volumio sudo[2888]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 10:01:11 volumio volumio[2402]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 27 10:01:11 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 27 10:01:11 volumio systemd[1]: mpd.service: Succeeded. Nov 27 10:01:11 volumio systemd[1]: Stopped Music Player Daemon. Nov 27 10:01:11 volumio volumio[2402]: info: MPD Permissions set Nov 27 10:01:11 volumio volumio[2402]: info: MPD Permissions set Nov 27 10:01:11 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 10:01:11 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 10:01:11 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 10:01:11 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 10:01:11 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 10:01:11 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 10:01:11 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 10:01:11 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 10:01:11 volumio systemd[1]: Starting Music Player Daemon... Nov 27 10:01:11 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 10:01:11 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 10:01:11 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 10:01:11 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 10:01:11 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 10:01:11 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 10:01:11 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 10:01:11 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 10:01:11 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 10:01:11 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 10:01:11 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 27 10:01:11 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 27 10:01:11 volumio volumio[2402]: info: Starting Shairport Sync Nov 27 10:01:11 volumio volumio[2402]: info: Starting Shairport Sync Nov 27 10:01:11 volumio sudo[2897]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 27 10:01:11 volumio sudo[2897]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 10:01:11 volumio sudo[2897]: pam_unix(sudo:session): session closed for user root Nov 27 10:01:11 volumio sudo[2907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 27 10:01:11 volumio sudo[2907]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 10:01:11 volumio sudo[2911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 27 10:01:11 volumio sudo[2911]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 10:01:11 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 27 10:01:11 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 27 10:01:11 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 27 10:01:11 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 27 10:01:11 volumio sudo[2907]: pam_unix(sudo:session): session closed for user root Nov 27 10:01:11 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 27 10:01:11 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Nov 27 10:01:11 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 27 10:01:11 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 27 10:01:11 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 27 10:01:11 volumio sudo[2911]: pam_unix(sudo:session): session closed for user root Nov 27 10:01:11 volumio mpd[2910]: Nov 27 10:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 27 10:01:11 volumio systemd[1]: Started Music Player Daemon. Nov 27 10:01:11 volumio sudo[2888]: pam_unix(sudo:session): session closed for user root Nov 27 10:01:11 volumio sudo[2878]: pam_unix(sudo:session): session closed for user root Nov 27 10:01:11 volumio volumio[2402]: info: Shairport-Sync Started Nov 27 10:01:11 volumio volumio[2402]: info: Shairport-Sync Started Nov 27 10:01:11 volumio volumio[2402]: error: MPD error: The expression evaluated to a falsy value: Nov 27 10:01:11 volumio volumio[2402]: assert.ok(self.idling) Nov 27 10:01:11 volumio volumio[2402]: error: The expression evaluated to a falsy value: Nov 27 10:01:11 volumio volumio[2402]: assert.ok(self.idling) Nov 27 10:01:11 volumio volumio[2402]: error: updateQueue error: null Nov 27 10:01:14 volumio volumio[2402]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Nov 27 10:01:14 volumio volumio[2402]: info: CoreStateMachine::getcurrentVolume Nov 27 10:01:14 volumio volumio[2402]: info: CoreCommandRouter::volumioRetrievevolume Nov 27 10:01:14 volumio volumio[2402]: info: VolumeController:: Volume=86 Mute =false Nov 27 10:01:14 volumio volumio[2402]: info: CoreStateMachine::pushState Nov 27 10:01:14 volumio volumio[2402]: info: CorePlayQueue::getTrack 0 Nov 27 10:01:14 volumio volumio[2402]: info: CoreCommandRouter::volumioPushState Nov 27 10:01:14 volumio volumio[2402]: info: CoreStateMachine::updateTrackBlock Nov 27 10:01:14 volumio volumio[2402]: info: CorePlayQueue::getTrackBlock Nov 27 10:01:14 volumio volumio[2402]: info: CoreCommandRouter::volumioRetrievevolume Nov 27 10:01:14 volumio volumio[2402]: info: VolumeController:: Volume=86 Mute =false Nov 27 10:01:14 volumio volumio[2402]: info: CoreStateMachine::pushState Nov 27 10:01:14 volumio volumio[2402]: info: CorePlayQueue::getTrack 0 Nov 27 10:01:14 volumio volumio[2402]: info: CoreCommandRouter::volumioPushState Nov 27 10:01:19 volumio kernel: usb 1-1.4: USB disconnect, device number 6 Nov 27 10:02:01 volumio kernel: usb 1-1.2: new high-speed USB device number 7 using xhci_hcd Nov 27 10:02:01 volumio kernel: usb 1-1.2: New USB device found, idVendor=06cb, idProduct=1595, bcdDevice= 0.02 Nov 27 10:02:01 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Nov 27 10:02:01 volumio kernel: usb 1-1.2: Product: CX31993 384Khz HIFI AUDIO Nov 27 10:02:01 volumio kernel: usb 1-1.2: Manufacturer: Synaptics Nov 27 10:02:01 volumio kernel: input: Synaptics CX31993 384Khz HIFI AUDIO Consumer Control as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.2/1-1.2:1.2/0003:06CB:1595.0003/input/input2 Nov 27 10:02:01 volumio kernel: hid-generic 0003:06CB:1595.0003: input,hidraw0: USB HID v1.11 Device [Synaptics CX31993 384Khz HIFI AUDIO] on usb-0000:01:00.0-1.2/input2 Nov 27 10:02:01 volumio systemd-udevd[3053]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99. Nov 27 10:02:01 volumio volumio[2402]: info: Nov 27 10:02:01 volumio volumio[2402]: ---------------------------- USB Audio Device Attached Nov 27 10:02:01 volumio volumio[2402]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Nov 27 10:02:02 volumio systemd-udevd[3052]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Nov 27 10:02:11 volumio volumio[2402]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 27 10:02:11 volumio volumio[2402]: Error: connect ETIMEDOUT 31.13.95.17:443 Nov 27 10:02:11 volumio volumio[2402]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Nov 27 10:02:11 volumio volumio[2402]: errno: -110, Nov 27 10:02:11 volumio volumio[2402]: code: 'ETIMEDOUT', Nov 27 10:02:11 volumio volumio[2402]: syscall: 'connect', Nov 27 10:02:11 volumio volumio[2402]: address: '31.13.95.17', Nov 27 10:02:11 volumio volumio[2402]: port: 443 Nov 27 10:02:11 volumio volumio[2402]: } Nov 27 10:02:11 volumio volumio[2402]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 27 10:02:11 volumio sudo[3086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-27 10:01 Nov 27 10:02:11 volumio sudo[3086]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"