-- Logs begin at Mon 2024-09-02 16:10:19 GMT, end at Mon 2024-09-02 16:20:19 GMT. -- Sep 02 16:19:04 volumio kernel: usb 1-1.6: new high-speed USB device number 4 using ehci-pci Sep 02 16:19:05 volumio kernel: usb 1-1.6: New USB device found, idVendor=16d0, idProduct=071a, bcdDevice= 1.96 Sep 02 16:19:05 volumio kernel: usb 1-1.6: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Sep 02 16:19:05 volumio kernel: usb 1-1.6: Product: Combo384 Amanero Sep 02 16:19:05 volumio kernel: usb 1-1.6: Manufacturer: Amanero Technologies Sep 02 16:19:05 volumio kernel: usb 1-1.6: SerialNumber: 413-001 Sep 02 16:19:05 volumio kernel: usb 1-1.6: Warning! Unlikely big volume range (=32767), cval->res is probably wrong. Sep 02 16:19:05 volumio kernel: usb 1-1.6: [10] FU [PCM Playback Volume] ch = 2, val = -32767/0/1 Sep 02 16:19:05 volumio kernel: usb 1-1.6: Warning! Unlikely big volume range (=32767), cval->res is probably wrong. Sep 02 16:19:05 volumio kernel: usb 1-1.6: [10] FU [PCM Playback Volume] ch = 1, val = -32767/0/1 Sep 02 16:19:05 volumio kernel: usbcore: registered new interface driver snd-usb-audio Sep 02 16:19:05 volumio systemd-udevd[3520]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99. Sep 02 16:19:05 volumio volumio[644]: info: Sep 02 16:19:05 volumio volumio[644]: ---------------------------- USB Audio Device Attached Sep 02 16:19:05 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Sep 02 16:19:10 volumio volumio[644]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 19 Sep 02 16:19:11 volumio volumio[644]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 17 Sep 02 16:19:11 volumio volumio[644]: info: CoreCommandRouter::volumioGetState Sep 02 16:19:17 volumio volumio[644]: verbose: New Socket.io Connection to 192.168.1.4 from 192.168.1.3 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_6_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.6 Mobile/15E148 Safari/604.1 Total Clients: 18 Sep 02 16:19:17 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 02 16:19:17 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 02 16:19:17 volumio volumio[644]: info: CoreCommandRouter::volumioGetVisibleSources Sep 02 16:19:17 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 16:19:17 volumio volumio[644]: info: CoreCommandRouter::volumioGetState Sep 02 16:19:17 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 02 16:19:17 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 02 16:19:17 volumio volumio[644]: info: Received Get System Info Sep 02 16:19:17 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 02 16:19:17 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 02 16:19:17 volumio volumio[644]: info: Discovery: Getting this device information Sep 02 16:19:17 volumio volumio[644]: info: CoreCommandRouter::volumioGetState Sep 02 16:19:17 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 16:19:17 volumio volumio[644]: info: CoreCommandRouter::volumioGetState Sep 02 16:19:17 volumio volumio[644]: info: Listing playlists Sep 02 16:19:17 volumio volumio[644]: info: CoreCommandRouter::volumioGetQueue Sep 02 16:19:17 volumio volumio[644]: info: CoreStateMachine::getQueue Sep 02 16:19:17 volumio volumio[644]: info: CorePlayQueue::getQueue Sep 02 16:19:17 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 02 16:19:26 volumio volumio[644]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 02 16:19:26 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 02 16:19:26 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 16:19:26 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 02 16:19:27 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 16:19:27 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 16:19:27 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 16:19:27 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 16:19:27 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 16:19:27 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 16:19:27 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 16:19:27 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 02 16:19:27 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 02 16:19:27 volumio volumio[644]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 18 Sep 02 16:19:28 volumio volumio[644]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 19 Sep 02 16:19:28 volumio volumio[644]: info: CoreCommandRouter::volumioGetState Sep 02 16:19:34 volumio volumio[644]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Sep 02 16:19:34 volumio volumio[644]: info: Preparing to save Alsa Options, stopping services first Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::volumioGetState Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::volumioPause Sep 02 16:19:34 volumio volumio[644]: info: CoreStateMachine::pause Sep 02 16:19:34 volumio volumio[644]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Combo384 Amanero"}} Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 02 16:19:34 volumio volumio[644]: info: Setting mixer PCM for card Combo384 Amanero Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::volumioUpdateVolumeSettings Sep 02 16:19:34 volumio volumio[644]: info: Updating Volume Controller Parameters: Device: 5 Name: Combo384 Amanero Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Sep 02 16:19:34 volumio volumio[644]: info: Disabling external Volume Control Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 02 16:19:34 volumio volumio[644]: info: Preparing to generate the ALSA configuration file Sep 02 16:19:34 volumio volumio[644]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Sep 02 16:19:34 volumio volumio[644]: info: Reading ALSA contributions from plugins. Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 02 16:19:34 volumio volumio[644]: info: VolumeController:: Volume=46 Mute =false Sep 02 16:19:34 volumio volumio[644]: info: CoreStateMachine::pushState Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::volumioPushState Sep 02 16:19:34 volumio volumio[644]: info: Asound.conf file written Sep 02 16:19:34 volumio sudo[3617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Sep 02 16:19:34 volumio sudo[3617]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 16:19:34 volumio sudo[3617]: pam_unix(sudo:session): session closed for user root Sep 02 16:19:34 volumio volumio[644]: No state is present for card Amanero Sep 02 16:19:34 volumio volumio[644]: Found hardware: "USB-Audio" "USB Mixer" "USB16d0:071a" "" "" Sep 02 16:19:34 volumio volumio[644]: Hardware is initialized using a generic method Sep 02 16:19:34 volumio volumio[644]: No state is present for card Amanero Sep 02 16:19:34 volumio volumio[644]: info: Output device has changed, restarting MPD Sep 02 16:19:34 volumio sudo[3623]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 16:19:34 volumio sudo[3623]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 16:19:34 volumio volumio[644]: info: Output device has changed, restarting Shairport Sync Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 16:19:34 volumio sudo[3623]: pam_unix(sudo:session): session closed for user root Sep 02 16:19:34 volumio sudo[3626]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 16:19:34 volumio sudo[3626]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 16:19:34 volumio systemd[1]: Stopping Music Player Daemon... Sep 02 16:19:34 volumio volumio[644]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 02 16:19:34 volumio volumio[644]: info: Output device has changed, restarting MPD Sep 02 16:19:34 volumio systemd[1]: mpd.service: Succeeded. Sep 02 16:19:34 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 16:19:34 volumio sudo[3633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 16:19:34 volumio sudo[3633]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 16:19:34 volumio volumio[644]: info: Output device has changed, restarting Shairport Sync Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 16:19:34 volumio volumio[644]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 02 16:19:34 volumio volumio[644]: info: MPD Permissions set Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 02 16:19:34 volumio sudo[3633]: pam_unix(sudo:session): session closed for user root Sep 02 16:19:34 volumio systemd[1]: Starting Music Player Daemon... Sep 02 16:19:34 volumio sudo[3635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 16:19:34 volumio sudo[3635]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 16:19:34 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 02 16:19:34 volumio systemd[1]: mpd.service: Succeeded. Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 16:19:34 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 16:19:34 volumio systemd[1]: Starting Music Player Daemon... Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 16:19:34 volumio volumio[644]: info: Starting Shairport Sync Sep 02 16:19:34 volumio volumio[644]: info: Starting Shairport Sync Sep 02 16:19:34 volumio sudo[3656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 02 16:19:34 volumio sudo[3656]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 16:19:34 volumio sudo[3659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 02 16:19:34 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 02 16:19:34 volumio sudo[3659]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 16:19:34 volumio shairport-sync[2720]: {"time":1725293914738,"response":"stopAirplayPlayback Success"} Sep 02 16:19:34 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 02 16:19:34 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 02 16:19:34 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 02 16:19:34 volumio sudo[3656]: pam_unix(sudo:session): session closed for user root Sep 02 16:19:34 volumio volumio[644]: info: Shairport-Sync Started Sep 02 16:19:34 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 02 16:19:34 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Sep 02 16:19:34 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 02 16:19:34 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 02 16:19:34 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 02 16:19:34 volumio sudo[3659]: pam_unix(sudo:session): session closed for user root Sep 02 16:19:34 volumio volumio[644]: info: Shairport-Sync Started Sep 02 16:19:34 volumio mpd[3647]: Sep 02 16:19 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 02 16:19:34 volumio systemd[1]: Started Music Player Daemon. Sep 02 16:19:34 volumio sudo[3626]: pam_unix(sudo:session): session closed for user root Sep 02 16:19:34 volumio sudo[3635]: pam_unix(sudo:session): session closed for user root Sep 02 16:19:34 volumio volumio[644]: error: MPD error: The expression evaluated to a falsy value: Sep 02 16:19:34 volumio volumio[644]: assert.ok(self.idling) Sep 02 16:19:34 volumio volumio[644]: error: The expression evaluated to a falsy value: Sep 02 16:19:34 volumio volumio[644]: assert.ok(self.idling) Sep 02 16:19:34 volumio volumio[644]: error: updateQueue error: null Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 16:19:34 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Sep 02 16:19:38 volumio volumio[644]: info: CoreStateMachine::getcurrentVolume Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 16:19:38 volumio volumio[644]: info: VolumeController:: Volume=46 Mute =false Sep 02 16:19:38 volumio volumio[644]: info: CoreStateMachine::pushState Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::volumioPushState Sep 02 16:19:38 volumio volumio[644]: info: CoreStateMachine::updateTrackBlock Sep 02 16:19:38 volumio volumio[644]: info: CorePlayQueue::getTrackBlock Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 16:19:38 volumio volumio[644]: info: VolumeController:: Volume=46 Mute =false Sep 02 16:19:38 volumio volumio[644]: info: CoreStateMachine::pushState Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::volumioPushState Sep 02 16:19:38 volumio volumio[644]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Sep 02 16:19:38 volumio volumio[644]: info: Preparing to save Alsa Options, stopping services first Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::volumioGetState Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::volumioPause Sep 02 16:19:38 volumio volumio[644]: info: CoreStateMachine::pause Sep 02 16:19:38 volumio volumio[644]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Combo384 Amanero"}} Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 02 16:19:38 volumio volumio[644]: info: Setting mixer PCM for card Combo384 Amanero Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::volumioUpdateVolumeSettings Sep 02 16:19:38 volumio volumio[644]: info: Updating Volume Controller Parameters: Device: 5 Name: Combo384 Amanero Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Sep 02 16:19:38 volumio volumio[644]: info: Disabling external Volume Control Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 02 16:19:38 volumio volumio[644]: info: Preparing to generate the ALSA configuration file Sep 02 16:19:38 volumio volumio[644]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Sep 02 16:19:38 volumio volumio[644]: info: Reading ALSA contributions from plugins. Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 02 16:19:38 volumio volumio[644]: info: VolumeController:: Volume=46 Mute =false Sep 02 16:19:38 volumio volumio[644]: info: CoreStateMachine::pushState Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::volumioPushState Sep 02 16:19:38 volumio volumio[644]: info: Asound.conf file unchanged, so no further update is needed Sep 02 16:19:38 volumio volumio[644]: info: Output device has changed, restarting MPD Sep 02 16:19:38 volumio sudo[3693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 16:19:38 volumio sudo[3693]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 16:19:38 volumio volumio[644]: info: Output device has changed, restarting Shairport Sync Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 16:19:38 volumio sudo[3693]: pam_unix(sudo:session): session closed for user root Sep 02 16:19:38 volumio sudo[3695]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 16:19:38 volumio sudo[3695]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 16:19:38 volumio systemd[1]: Stopping Music Player Daemon... Sep 02 16:19:38 volumio volumio[644]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 02 16:19:38 volumio volumio[644]: info: MPD Permissions set Sep 02 16:19:38 volumio systemd[1]: mpd.service: Succeeded. Sep 02 16:19:38 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 02 16:19:38 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 16:19:38 volumio volumio[644]: info: Starting Shairport Sync Sep 02 16:19:38 volumio sudo[3709]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 02 16:19:38 volumio sudo[3709]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 16:19:38 volumio systemd[1]: Starting Music Player Daemon... Sep 02 16:19:38 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 02 16:19:38 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 02 16:19:38 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 02 16:19:38 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 02 16:19:38 volumio sudo[3709]: pam_unix(sudo:session): session closed for user root Sep 02 16:19:38 volumio volumio[644]: info: Shairport-Sync Started Sep 02 16:19:39 volumio mpd[3701]: Sep 02 16:19 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 02 16:19:39 volumio systemd[1]: Started Music Player Daemon. Sep 02 16:19:39 volumio sudo[3695]: pam_unix(sudo:session): session closed for user root Sep 02 16:19:39 volumio volumio[644]: error: updateQueue error: null Sep 02 16:19:39 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 16:19:39 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 16:19:42 volumio volumio[644]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Sep 02 16:19:42 volumio volumio[644]: info: CoreStateMachine::getcurrentVolume Sep 02 16:19:42 volumio volumio[644]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 16:19:42 volumio volumio[644]: info: VolumeController:: Volume=46 Mute =false Sep 02 16:19:42 volumio volumio[644]: info: CoreStateMachine::pushState Sep 02 16:19:42 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 16:19:42 volumio volumio[644]: info: CoreCommandRouter::volumioPushState Sep 02 16:19:42 volumio volumio[644]: info: CoreStateMachine::updateTrackBlock Sep 02 16:19:42 volumio volumio[644]: info: CorePlayQueue::getTrackBlock Sep 02 16:19:42 volumio volumio[644]: info: CoreCommandRouter::volumioRetrievevolume Sep 02 16:19:42 volumio volumio[644]: info: VolumeController:: Volume=46 Mute =false Sep 02 16:19:42 volumio volumio[644]: info: CoreStateMachine::pushState Sep 02 16:19:42 volumio volumio[644]: info: CoreCommandRouter::volumioPushState Sep 02 16:19:46 volumio volumio[644]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 19 Sep 02 16:19:46 volumio volumio[644]: info: CoreCommandRouter::volumioGetState Sep 02 16:19:46 volumio volumio[644]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 20 Sep 02 16:20:01 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 02 16:20:01 volumio volumio[644]: info: CURURI: music-library Sep 02 16:20:01 volumio volumio[644]: info: Preload queue cleared Sep 02 16:20:01 volumio volumio[644]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 21 Sep 02 16:20:02 volumio volumio[644]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 19 Sep 02 16:20:02 volumio volumio[644]: info: CoreCommandRouter::volumioGetState Sep 02 16:20:03 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 02 16:20:03 volumio volumio[644]: info: CURURI: music-library/USB Sep 02 16:20:03 volumio volumio[644]: info: Preload queue cleared Sep 02 16:20:03 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 02 16:20:03 volumio volumio[644]: info: CURURI: music-library/USB/系统 Sep 02 16:20:03 volumio volumio[644]: info: Preload queue cleared Sep 02 16:20:04 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 02 16:20:04 volumio volumio[644]: info: CURURI: music-library/USB/系统/惠威试音 Sep 02 16:20:04 volumio volumio[644]: info: Preload queue cleared Sep 02 16:20:07 volumio volumio[644]: info: Preload queue cleared Sep 02 16:20:07 volumio volumio[644]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 02 16:20:07 volumio volumio[644]: info: CoreStateMachine::ClearQueue Sep 02 16:20:07 volumio volumio[644]: info: CoreStateMachine::stop Sep 02 16:20:07 volumio volumio[644]: info: CoreStateMachine::serviceStop Sep 02 16:20:07 volumio volumio[644]: info: CoreCommandRouter::serviceStop Sep 02 16:20:07 volumio volumio[644]: info: Airplay Stop Sep 02 16:20:07 volumio volumio[644]: info: Stopping Airplay Playback and sending pause command to client via USR2 Sep 02 16:20:07 volumio volumio[644]: info: CorePlayQueue::clearPlayQueue Sep 02 16:20:07 volumio volumio[644]: info: CorePlayQueue::saveQueue Sep 02 16:20:07 volumio volumio[644]: info: CoreCommandRouter::volumioPushQueue Sep 02 16:20:07 volumio volumio[644]: info: CoreStateMachine::addQueueItems Sep 02 16:20:07 volumio volumio[644]: info: CorePlayQueue::addQueueItems Sep 02 16:20:07 volumio volumio[644]: info: Preload queue cleared Sep 02 16:20:07 volumio volumio[644]: info: Adding Item to queue: music-library/USB/系统/惠威试音/youve got away.wav Sep 02 16:20:07 volumio volumio[644]: info: Using cached record of: music-library/USB/系统/惠威试音/youve got away.wav Sep 02 16:20:07 volumio volumio[644]: info: Adding Item to queue: music-library/USB/系统/惠威试音/偏偏喜欢你.wav Sep 02 16:20:07 volumio volumio[644]: info: Using cached record of: music-library/USB/系统/惠威试音/偏偏喜欢你.wav Sep 02 16:20:07 volumio volumio[644]: info: Adding Item to queue: music-library/USB/系统/惠威试音/加州旅馆.wav Sep 02 16:20:07 volumio volumio[644]: info: Using cached record of: music-library/USB/系统/惠威试音/加州旅馆.wav Sep 02 16:20:07 volumio volumio[644]: info: Adding Item to queue: music-library/USB/系统/惠威试音/张三的歌.wav Sep 02 16:20:07 volumio volumio[644]: info: Using cached record of: music-library/USB/系统/惠威试音/张三的歌.wav Sep 02 16:20:07 volumio volumio[644]: info: Adding Item to queue: music-library/USB/系统/惠威试音/惠威试音碟 - 鼓动心弦.mp3 Sep 02 16:20:07 volumio volumio[644]: info: Using cached record of: music-library/USB/系统/惠威试音/惠威试音碟 - 鼓动心弦.mp3 Sep 02 16:20:07 volumio volumio[644]: info: Adding Item to queue: music-library/USB/系统/惠威试音/渡口.wav Sep 02 16:20:07 volumio volumio[644]: info: Using cached record of: music-library/USB/系统/惠威试音/渡口.wav Sep 02 16:20:07 volumio volumio[644]: info: Adding Item to queue: music-library/USB/系统/惠威试音/莫扎特交响乐.wav Sep 02 16:20:07 volumio volumio[644]: info: Using cached record of: music-library/USB/系统/惠威试音/莫扎特交响乐.wav Sep 02 16:20:07 volumio volumio[644]: info: Adding Item to queue: music-library/USB/系统/惠威试音/蔡琴 - 被遗忘的时光.ape Sep 02 16:20:07 volumio volumio[644]: info: Using cached record of: music-library/USB/系统/惠威试音/蔡琴 - 被遗忘的时光.ape Sep 02 16:20:07 volumio volumio[644]: info: Adding Item to queue: music-library/USB/系统/惠威试音/超好听的天籁旋律.wav Sep 02 16:20:07 volumio volumio[644]: info: Using cached record of: music-library/USB/系统/惠威试音/超好听的天籁旋律.wav Sep 02 16:20:07 volumio volumio[644]: info: Adding Item to queue: music-library/USB/系统/惠威试音/阿姐鼓.ape Sep 02 16:20:07 volumio volumio[644]: info: Using cached record of: music-library/USB/系统/惠威试音/阿姐鼓.ape Sep 02 16:20:07 volumio volumio[644]: info: Adding Item to queue: music-library/USB/系统/惠威试音/鼓诗.wav Sep 02 16:20:07 volumio volumio[644]: info: Using cached record of: music-library/USB/系统/惠威试音/鼓诗.wav Sep 02 16:20:07 volumio volumio[644]: info: Adding Item to queue: music-library/USB/系统/惠威试音/惠威试音碟 - 动感舞曲.ape Sep 02 16:20:07 volumio volumio[644]: info: Using cached record of: music-library/USB/系统/惠威试音/惠威试音碟 - 动感舞曲.ape Sep 02 16:20:07 volumio volumio[644]: info: Adding Item to queue: music-library/USB/系统/惠威试音/试音碟、黄莺莺 - 惠威.ape Sep 02 16:20:07 volumio volumio[644]: info: Using cached record of: music-library/USB/系统/惠威试音/试音碟、黄莺莺 - 惠威.ape Sep 02 16:20:07 volumio volumio[644]: info: Adding Item to queue: music-library/USB/系统/惠威试音/惠威试音碟 - 惠威高低音试音碟.ape Sep 02 16:20:07 volumio volumio[644]: info: Using cached record of: music-library/USB/系统/惠威试音/惠威试音碟 - 惠威高低音试音碟.ape Sep 02 16:20:07 volumio volumio[644]: info: Adding Item to queue: music-library/USB/系统/惠威试音/蔡琴 - 你的眼神 - 惠威试音碟.ape Sep 02 16:20:07 volumio volumio[644]: info: Using cached record of: music-library/USB/系统/惠威试音/蔡琴 - 你的眼神 - 惠威试音碟.ape Sep 02 16:20:07 volumio volumio[644]: info: Adding Item to queue: music-library/USB/系统/惠威试音/木村好夫 - 吉他 - 惠威试音天碟 柳濑小镇.ape Sep 02 16:20:07 volumio volumio[644]: info: Using cached record of: music-library/USB/系统/惠威试音/木村好夫 - 吉他 - 惠威试音天碟 柳濑小镇.ape Sep 02 16:20:07 volumio volumio[644]: info: CoreCommandRouter::volumioPushQueue Sep 02 16:20:07 volumio sudo[3745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 3711 Sep 02 16:20:07 volumio sudo[3745]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 16:20:07 volumio volumio[644]: info: CorePlayQueue::saveQueue Sep 02 16:20:07 volumio volumio[644]: info: CoreStateMachine::updateTrackBlock Sep 02 16:20:07 volumio volumio[644]: info: CorePlayQueue::getTrackBlock Sep 02 16:20:07 volumio volumio[644]: info: CoreCommandRouter::volumioPlay Sep 02 16:20:07 volumio volumio[644]: verbose: UNSET VOLATILE: Service: airplay_emulation Sep 02 16:20:07 volumio volumio[644]: info: Stopping Airplay Playback and sending pause command to client via USR2 Sep 02 16:20:07 volumio sudo[3745]: pam_unix(sudo:session): session closed for user root Sep 02 16:20:07 volumio volumio[644]: info: CoreStateMachine::play index 9 Sep 02 16:20:07 volumio volumio[644]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 16:20:07 volumio volumio[644]: info: CoreStateMachine::stop Sep 02 16:20:07 volumio volumio[644]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 16:20:07 volumio volumio[644]: info: CoreStateMachine::play index undefined Sep 02 16:20:07 volumio volumio[644]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 16:20:07 volumio volumio[644]: info: CorePlayQueue::getTrack 9 Sep 02 16:20:07 volumio volumio[644]: info: CoreStateMachine::startPlaybackTimer Sep 02 16:20:07 volumio volumio[644]: info: CorePlayQueue::getTrack 9 Sep 02 16:20:07 volumio volumio[644]: verbose: ControllerMpd::clearAddPlayTracks USB/系统/惠威试音/阿姐鼓.ape Sep 02 16:20:07 volumio volumio[644]: verbose: ControllerMpd::sendMpdCommand stop Sep 02 16:20:07 volumio volumio[644]: info: sendMpdCommand stop took 1 milliseconds Sep 02 16:20:07 volumio volumio[644]: verbose: ControllerMpd::sendMpdCommand clear Sep 02 16:20:07 volumio volumio[644]: info: Shairport-Sync paused with USR2 Sep 02 16:20:07 volumio volumio[644]: info: Sep 02 16:20:07 volumio volumio[644]: ---------------------------- MPD announces system playlist update Sep 02 16:20:07 volumio volumio[644]: info: Ignoring MPD Status Update Sep 02 16:20:07 volumio volumio[644]: info: sendMpdCommand clear took 1 milliseconds Sep 02 16:20:07 volumio volumio[644]: verbose: ControllerMpd::sendMpdCommand add "USB/系统/惠威试音/阿姐鼓.ape" Sep 02 16:20:07 volumio volumio[644]: error: updateQueue error: null Sep 02 16:20:07 volumio volumio[644]: info: Sep 02 16:20:07 volumio volumio[644]: ---------------------------- MPD announces system playlist update Sep 02 16:20:07 volumio volumio[644]: info: Ignoring MPD Status Update Sep 02 16:20:07 volumio volumio[644]: info: ------------------------------ 1ms Sep 02 16:20:07 volumio volumio[644]: info: sendMpdCommand add "USB/系统/惠威试音/阿姐鼓.ape" took 1 milliseconds Sep 02 16:20:07 volumio volumio[644]: verbose: ControllerMpd::sendMpdCommand play Sep 02 16:20:07 volumio volumio[644]: info: ------------------------------ 1ms Sep 02 16:20:07 volumio volumio[644]: info: sendMpdCommand play took 1 milliseconds Sep 02 16:20:07 volumio volumio[644]: info: Sep 02 16:20:07 volumio volumio[644]: ---------------------------- MPD announces state update: player Sep 02 16:20:07 volumio volumio[644]: info: ControllerMpd::getState Sep 02 16:20:07 volumio volumio[644]: verbose: ControllerMpd::sendMpdCommand status Sep 02 16:20:07 volumio sudo[3749]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 3711 Sep 02 16:20:07 volumio sudo[3749]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 16:20:07 volumio sudo[3749]: pam_unix(sudo:session): session closed for user root Sep 02 16:20:07 volumio volumio[644]: info: Shairport-Sync paused with USR2 Sep 02 16:20:07 volumio volumio[644]: info: Sep 02 16:20:07 volumio volumio[644]: ---------------------------- MPD announces state update: player Sep 02 16:20:07 volumio volumio[644]: info: sendMpdCommand status took 9 milliseconds Sep 02 16:20:07 volumio volumio[644]: info: ControllerMpd::getState Sep 02 16:20:07 volumio volumio[644]: verbose: ControllerMpd::sendMpdCommand status Sep 02 16:20:07 volumio volumio[644]: verbose: ControllerMpd::parseState Sep 02 16:20:07 volumio volumio[644]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 16:20:07 volumio volumio[644]: info: sendMpdCommand status took 0 milliseconds Sep 02 16:20:07 volumio volumio[644]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 02 16:20:07 volumio volumio[644]: verbose: ControllerMpd::parseState Sep 02 16:20:07 volumio volumio[644]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 16:20:07 volumio volumio[644]: verbose: ControllerMpd::parseTrackInfo Sep 02 16:20:07 volumio volumio[644]: info: ControllerMpd::pushState Sep 02 16:20:07 volumio volumio[644]: info: CoreCommandRouter::servicePushState Sep 02 16:20:07 volumio volumio[644]: info: CorePlayQueue::getTrack 9 Sep 02 16:20:07 volumio volumio[644]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":348,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"阿姐鼓.ape","artist":null,"album":null,"uri":"USB/系统/惠威试音/阿姐鼓.ape","trackType":"ape"} Sep 02 16:20:07 volumio volumio[644]: verbose: CURRENT POSITION 9 Sep 02 16:20:07 volumio volumio[644]: info: CoreStateMachine::syncState stateService play Sep 02 16:20:07 volumio volumio[644]: info: CoreStateMachine::syncState currentStatus stop Sep 02 16:20:07 volumio volumio[644]: info: ------------------------------ 13ms Sep 02 16:20:07 volumio volumio[644]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 02 16:20:07 volumio volumio[644]: verbose: ControllerMpd::parseTrackInfo Sep 02 16:20:07 volumio volumio[644]: info: ControllerMpd::pushState Sep 02 16:20:07 volumio volumio[644]: info: CoreCommandRouter::servicePushState Sep 02 16:20:07 volumio volumio[644]: info: CorePlayQueue::getTrack 9 Sep 02 16:20:07 volumio volumio[644]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":348,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"阿姐鼓.ape","artist":null,"album":null,"uri":"USB/系统/惠威试音/阿姐鼓.ape","trackType":"ape"} Sep 02 16:20:07 volumio volumio[644]: verbose: CURRENT POSITION 9 Sep 02 16:20:07 volumio volumio[644]: info: CoreStateMachine::syncState stateService play Sep 02 16:20:07 volumio volumio[644]: info: CoreStateMachine::syncState currentStatus play Sep 02 16:20:07 volumio volumio[644]: info: Received an update from plugin. extracting info from payload Sep 02 16:20:07 volumio volumio[644]: info: CoreStateMachine::pushState Sep 02 16:20:07 volumio volumio[644]: info: CorePlayQueue::getTrack 9 Sep 02 16:20:07 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 16:20:07 volumio volumio[644]: info: CoreCommandRouter::volumioPushState Sep 02 16:20:07 volumio volumio[644]: info: CoreStateMachine::pushState Sep 02 16:20:07 volumio volumio[644]: info: CorePlayQueue::getTrack 9 Sep 02 16:20:07 volumio volumio[644]: info: CoreCommandRouter::volumioPushState Sep 02 16:20:07 volumio volumio[644]: info: ------------------------------ 10ms Sep 02 16:20:08 volumio volumio[644]: info: Restarting Shairport-Sync after stop Sep 02 16:20:08 volumio volumio[644]: info: Restarting Shairport-Sync after stop Sep 02 16:20:08 volumio sudo[3763]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 02 16:20:08 volumio sudo[3763]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 16:20:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 02 16:20:08 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 02 16:20:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 02 16:20:08 volumio sudo[3766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 02 16:20:08 volumio sudo[3766]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 16:20:09 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 02 16:20:09 volumio sudo[3763]: pam_unix(sudo:session): session closed for user root Sep 02 16:20:09 volumio volumio[644]: info: Shairport-Sync retarted Sep 02 16:20:09 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 02 16:20:09 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Sep 02 16:20:09 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 02 16:20:09 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 02 16:20:09 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 02 16:20:09 volumio sudo[3766]: pam_unix(sudo:session): session closed for user root Sep 02 16:20:09 volumio volumio[644]: info: Shairport-Sync retarted Sep 02 16:20:18 volumio volumio[644]: info: Preload queue cleared Sep 02 16:20:18 volumio volumio[644]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 02 16:20:18 volumio volumio[644]: info: CoreStateMachine::ClearQueue Sep 02 16:20:18 volumio volumio[644]: info: CoreStateMachine::stop Sep 02 16:20:18 volumio volumio[644]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 16:20:18 volumio volumio[644]: info: CoreStateMachine::stPlaybackTimer Sep 02 16:20:18 volumio volumio[644]: info: CoreStateMachine::updateTrackBlock Sep 02 16:20:18 volumio volumio[644]: info: CorePlayQueue::getTrackBlock Sep 02 16:20:18 volumio volumio[644]: info: CoreStateMachine::pushState Sep 02 16:20:18 volumio volumio[644]: info: CorePlayQueue::getTrack 9 Sep 02 16:20:18 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 16:20:18 volumio volumio[644]: info: CoreCommandRouter::volumioPushState Sep 02 16:20:18 volumio volumio[644]: info: CoreStateMachine::serviceStop Sep 02 16:20:18 volumio volumio[644]: info: CorePlayQueue::getTrack 9 Sep 02 16:20:18 volumio volumio[644]: info: CoreCommandRouter::serviceStop Sep 02 16:20:18 volumio volumio[644]: info: ControllerMpd::stop Sep 02 16:20:18 volumio volumio[644]: verbose: ControllerMpd::sendMpdCommand stop Sep 02 16:20:18 volumio volumio[644]: info: CorePlayQueue::clearPlayQueue Sep 02 16:20:18 volumio volumio[644]: info: CorePlayQueue::saveQueue Sep 02 16:20:18 volumio volumio[644]: info: CoreCommandRouter::volumioPushQueue Sep 02 16:20:18 volumio volumio[644]: info: CoreStateMachine::addQueueItems Sep 02 16:20:18 volumio volumio[644]: info: CorePlayQueue::addQueueItems Sep 02 16:20:18 volumio volumio[644]: info: Preload queue cleared Sep 02 16:20:18 volumio volumio[644]: info: Adding Item to queue: music-library/USB/系统/惠威试音/youve got away.wav Sep 02 16:20:18 volumio volumio[644]: info: Using cached record of: music-library/USB/系统/惠威试音/youve got away.wav Sep 02 16:20:18 volumio volumio[644]: info: Adding Item to queue: music-library/USB/系统/惠威试音/偏偏喜欢你.wav Sep 02 16:20:18 volumio volumio[644]: info: Using cached record of: music-library/USB/系统/惠威试音/偏偏喜欢你.wav Sep 02 16:20:18 volumio volumio[644]: info: Adding Item to queue: music-library/USB/系统/惠威试音/加州旅馆.wav Sep 02 16:20:18 volumio volumio[644]: info: Using cached record of: music-library/USB/系统/惠威试音/加州旅馆.wav Sep 02 16:20:18 volumio volumio[644]: info: Adding Item to queue: music-library/USB/系统/惠威试音/张三的歌.wav Sep 02 16:20:18 volumio volumio[644]: info: Using cached record of: music-library/USB/系统/惠威试音/张三的歌.wav Sep 02 16:20:18 volumio volumio[644]: info: Adding Item to queue: music-library/USB/系统/惠威试音/惠威试音碟 - 鼓动心弦.mp3 Sep 02 16:20:18 volumio volumio[644]: info: Using cached record of: music-library/USB/系统/惠威试音/惠威试音碟 - 鼓动心弦.mp3 Sep 02 16:20:18 volumio volumio[644]: info: Adding Item to queue: music-library/USB/系统/惠威试音/渡口.wav Sep 02 16:20:18 volumio volumio[644]: info: Using cached record of: music-library/USB/系统/惠威试音/渡口.wav Sep 02 16:20:18 volumio volumio[644]: info: Adding Item to queue: music-library/USB/系统/惠威试音/莫扎特交响乐.wav Sep 02 16:20:18 volumio volumio[644]: info: Using cached record of: music-library/USB/系统/惠威试音/莫扎特交响乐.wav Sep 02 16:20:18 volumio volumio[644]: info: Adding Item to queue: music-library/USB/系统/惠威试音/蔡琴 - 被遗忘的时光.ape Sep 02 16:20:18 volumio volumio[644]: info: Using cached record of: music-library/USB/系统/惠威试音/蔡琴 - 被遗忘的时光.ape Sep 02 16:20:18 volumio volumio[644]: info: Adding Item to queue: music-library/USB/系统/惠威试音/超好听的天籁旋律.wav Sep 02 16:20:18 volumio volumio[644]: info: Using cached record of: music-library/USB/系统/惠威试音/超好听的天籁旋律.wav Sep 02 16:20:18 volumio volumio[644]: info: Adding Item to queue: music-library/USB/系统/惠威试音/阿姐鼓.ape Sep 02 16:20:18 volumio volumio[644]: info: Using cached record of: music-library/USB/系统/惠威试音/阿姐鼓.ape Sep 02 16:20:18 volumio volumio[644]: info: Adding Item to queue: music-library/USB/系统/惠威试音/鼓诗.wav Sep 02 16:20:18 volumio volumio[644]: info: Using cached record of: music-library/USB/系统/惠威试音/鼓诗.wav Sep 02 16:20:18 volumio volumio[644]: info: Adding Item to queue: music-library/USB/系统/惠威试音/惠威试音碟 - 动感舞曲.ape Sep 02 16:20:18 volumio volumio[644]: info: Using cached record of: music-library/USB/系统/惠威试音/惠威试音碟 - 动感舞曲.ape Sep 02 16:20:18 volumio volumio[644]: info: Adding Item to queue: music-library/USB/系统/惠威试音/试音碟、黄莺莺 - 惠威.ape Sep 02 16:20:18 volumio volumio[644]: info: Using cached record of: music-library/USB/系统/惠威试音/试音碟、黄莺莺 - 惠威.ape Sep 02 16:20:18 volumio volumio[644]: info: Adding Item to queue: music-library/USB/系统/惠威试音/惠威试音碟 - 惠威高低音试音碟.ape Sep 02 16:20:18 volumio volumio[644]: info: Using cached record of: music-library/USB/系统/惠威试音/惠威试音碟 - 惠威高低音试音碟.ape Sep 02 16:20:18 volumio volumio[644]: info: Adding Item to queue: music-library/USB/系统/惠威试音/蔡琴 - 你的眼神 - 惠威试音碟.ape Sep 02 16:20:18 volumio volumio[644]: info: Using cached record of: music-library/USB/系统/惠威试音/蔡琴 - 你的眼神 - 惠威试音碟.ape Sep 02 16:20:18 volumio volumio[644]: info: Adding Item to queue: music-library/USB/系统/惠威试音/木村好夫 - 吉他 - 惠威试音天碟 柳濑小镇.ape Sep 02 16:20:18 volumio volumio[644]: info: Using cached record of: music-library/USB/系统/惠威试音/木村好夫 - 吉他 - 惠威试音天碟 柳濑小镇.ape Sep 02 16:20:18 volumio volumio[644]: info: CoreCommandRouter::volumioPushQueue Sep 02 16:20:18 volumio volumio[644]: info: CorePlayQueue::saveQueue Sep 02 16:20:18 volumio volumio[644]: info: CoreStateMachine::updateTrackBlock Sep 02 16:20:18 volumio volumio[644]: info: CorePlayQueue::getTrackBlock Sep 02 16:20:18 volumio volumio[644]: info: CoreCommandRouter::volumioPlay Sep 02 16:20:18 volumio volumio[644]: info: CoreStateMachine::play index 6 Sep 02 16:20:18 volumio volumio[644]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 16:20:18 volumio volumio[644]: info: CoreStateMachine::stop Sep 02 16:20:18 volumio volumio[644]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 16:20:18 volumio volumio[644]: info: CoreStateMachine::play index undefined Sep 02 16:20:18 volumio volumio[644]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 16:20:18 volumio volumio[644]: info: CorePlayQueue::getTrack 6 Sep 02 16:20:18 volumio volumio[644]: info: CoreStateMachine::startPlaybackTimer Sep 02 16:20:18 volumio volumio[644]: info: CorePlayQueue::getTrack 6 Sep 02 16:20:18 volumio volumio[644]: verbose: ControllerMpd::clearAddPlayTracks USB/系统/惠威试音/莫扎特交响乐.wav Sep 02 16:20:18 volumio volumio[644]: verbose: ControllerMpd::sendMpdCommand stop Sep 02 16:20:18 volumio volumio[644]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 20 Sep 02 16:20:18 volumio volumio[644]: info: Sep 02 16:20:18 volumio volumio[644]: ---------------------------- MPD announces state update: player Sep 02 16:20:18 volumio volumio[644]: info: sendMpdCommand stop took 41 milliseconds Sep 02 16:20:18 volumio volumio[644]: info: ControllerMpd::getState Sep 02 16:20:18 volumio volumio[644]: verbose: ControllerMpd::sendMpdCommand status Sep 02 16:20:18 volumio volumio[644]: info: sendMpdCommand stop took 29 milliseconds Sep 02 16:20:18 volumio volumio[644]: verbose: ControllerMpd::sendMpdCommand clear Sep 02 16:20:18 volumio volumio[644]: info: Sep 02 16:20:18 volumio volumio[644]: ---------------------------- MPD announces system playlist update Sep 02 16:20:18 volumio volumio[644]: info: Ignoring MPD Status Update Sep 02 16:20:18 volumio volumio[644]: info: sendMpdCommand status took 1 milliseconds Sep 02 16:20:18 volumio volumio[644]: info: sendMpdCommand clear took 1 milliseconds Sep 02 16:20:18 volumio volumio[644]: verbose: ControllerMpd::parseState Sep 02 16:20:18 volumio volumio[644]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 16:20:18 volumio volumio[644]: verbose: ControllerMpd::sendMpdCommand add "USB/系统/惠威试音/莫扎特交响乐.wav" Sep 02 16:20:18 volumio volumio[644]: error: updateQueue error: null Sep 02 16:20:18 volumio volumio[644]: info: Sep 02 16:20:18 volumio volumio[644]: ---------------------------- MPD announces system playlist update Sep 02 16:20:18 volumio volumio[644]: info: Ignoring MPD Status Update Sep 02 16:20:18 volumio volumio[644]: info: ------------------------------ 0ms Sep 02 16:20:18 volumio volumio[644]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 02 16:20:18 volumio volumio[644]: info: sendMpdCommand add "USB/系统/惠威试音/莫扎特交响乐.wav" took 0 milliseconds Sep 02 16:20:18 volumio volumio[644]: verbose: ControllerMpd::parseTrackInfo Sep 02 16:20:18 volumio volumio[644]: verbose: ControllerMpd::sendMpdCommand play Sep 02 16:20:18 volumio volumio[644]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 02 16:20:18 volumio volumio[644]: info: ------------------------------ 2ms Sep 02 16:20:18 volumio volumio[644]: info: ------------------------------ 1ms Sep 02 16:20:18 volumio volumio[644]: info: sendMpdCommand play took 0 milliseconds Sep 02 16:20:18 volumio volumio[644]: info: Sep 02 16:20:18 volumio volumio[644]: ---------------------------- MPD announces state update: player Sep 02 16:20:18 volumio volumio[644]: info: ControllerMpd::getState Sep 02 16:20:18 volumio volumio[644]: verbose: ControllerMpd::sendMpdCommand status Sep 02 16:20:18 volumio volumio[644]: info: Sep 02 16:20:18 volumio volumio[644]: ---------------------------- MPD announces state update: player Sep 02 16:20:18 volumio volumio[644]: info: sendMpdCommand status took 3 milliseconds Sep 02 16:20:18 volumio volumio[644]: info: ControllerMpd::getState Sep 02 16:20:18 volumio volumio[644]: verbose: ControllerMpd::sendMpdCommand status Sep 02 16:20:18 volumio volumio[644]: verbose: ControllerMpd::parseState Sep 02 16:20:18 volumio volumio[644]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 16:20:18 volumio volumio[644]: info: sendMpdCommand status took 0 milliseconds Sep 02 16:20:18 volumio volumio[644]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 02 16:20:18 volumio volumio[644]: verbose: ControllerMpd::parseState Sep 02 16:20:18 volumio volumio[644]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 16:20:18 volumio volumio[644]: verbose: ControllerMpd::parseTrackInfo Sep 02 16:20:18 volumio volumio[644]: info: ControllerMpd::pushState Sep 02 16:20:18 volumio volumio[644]: info: CoreCommandRouter::servicePushState Sep 02 16:20:18 volumio volumio[644]: info: CorePlayQueue::getTrack 6 Sep 02 16:20:18 volumio volumio[644]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":201,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"莫扎特交响乐.wav","artist":null,"album":null,"uri":"USB/系统/惠威试音/莫扎特交响乐.wav","trackType":"wav"} Sep 02 16:20:18 volumio volumio[644]: verbose: CURRENT POSITION 6 Sep 02 16:20:18 volumio volumio[644]: info: CoreStateMachine::syncState stateService play Sep 02 16:20:18 volumio volumio[644]: info: CoreStateMachine::syncState currentStatus stop Sep 02 16:20:18 volumio volumio[644]: info: ------------------------------ 4ms Sep 02 16:20:18 volumio volumio[644]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 02 16:20:18 volumio volumio[644]: verbose: ControllerMpd::parseTrackInfo Sep 02 16:20:18 volumio volumio[644]: info: ControllerMpd::pushState Sep 02 16:20:18 volumio volumio[644]: info: CoreCommandRouter::servicePushState Sep 02 16:20:18 volumio volumio[644]: info: CorePlayQueue::getTrack 6 Sep 02 16:20:18 volumio volumio[644]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":201,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"莫扎特交响乐.wav","artist":null,"album":null,"uri":"USB/系统/惠威试音/莫扎特交响乐.wav","trackType":"wav"} Sep 02 16:20:18 volumio volumio[644]: verbose: CURRENT POSITION 6 Sep 02 16:20:18 volumio volumio[644]: info: CoreStateMachine::syncState stateService play Sep 02 16:20:18 volumio volumio[644]: info: CoreStateMachine::syncState currentStatus play Sep 02 16:20:18 volumio volumio[644]: info: Received an update from plugin. extracting info from payload Sep 02 16:20:18 volumio volumio[644]: info: CoreStateMachine::pushState Sep 02 16:20:18 volumio volumio[644]: info: CorePlayQueue::getTrack 6 Sep 02 16:20:18 volumio volumio[644]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 16:20:18 volumio volumio[644]: info: CoreCommandRouter::volumioPushState Sep 02 16:20:18 volumio volumio[644]: info: CoreStateMachine::pushState Sep 02 16:20:18 volumio volumio[644]: info: CorePlayQueue::getTrack 6 Sep 02 16:20:18 volumio volumio[644]: info: CoreCommandRouter::volumioPushState Sep 02 16:20:18 volumio volumio[644]: info: ------------------------------ 7ms Sep 02 16:20:19 volumio volumio[644]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 21 Sep 02 16:20:19 volumio volumio[644]: info: CoreCommandRouter::volumioGetState Sep 02 16:20:19 volumio volumio[644]: info: CorePlayQueue::getTrack 6 Sep 02 16:20:19 volumio volumio[644]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 16:20:19 volumio volumio[644]: Error: connect ETIMEDOUT 108.160.165.8:443 Sep 02 16:20:19 volumio volumio[644]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Sep 02 16:20:19 volumio volumio[644]: errno: -110, Sep 02 16:20:19 volumio volumio[644]: code: 'ETIMEDOUT', Sep 02 16:20:19 volumio volumio[644]: syscall: 'connect', Sep 02 16:20:19 volumio volumio[644]: address: '108.160.165.8', Sep 02 16:20:19 volumio volumio[644]: port: 443 Sep 02 16:20:19 volumio volumio[644]: } Sep 02 16:20:19 volumio volumio[644]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 16:20:19 volumio sudo[3791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-02 16:19 Sep 02 16:20:19 volumio sudo[3791]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:42:03 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="c434411fe8754b648dc7feb1d8f43761"