-- Logs begin at Fri 2024-03-29 16:53:48 CST, end at Fri 2024-03-29 17:11:42 CST. -- Mar 29 17:10:03 volumio volumio[734]: info: VolumeController::SetAlsaVolume82 Mar 29 17:10:03 volumio volumio[734]: info: CoreStateMachine::pushState Mar 29 17:10:03 volumio volumio[734]: info: CorePlayQueue::getTrack 0 Mar 29 17:10:03 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 17:10:03 volumio volumio[734]: info: CoreCommandRouter::volumioPushState Mar 29 17:10:06 volumio volumio[734]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%99%B3%E6%85%A7%E5%AB%BB/%E6%B0%B8%E9%81%A0%E6%98%AF%E4%BD%A0%E7%9A%84%E9%99%B3%E6%85%A7%E5%AB%BB%20%5B%E7%89%B9%E5%88%A5%E7%89%88%5D%20%5BMQS%5D/63a37b4d-5f46-4cdc-a611-89dcd2475eef.jpg' Mar 29 17:10:08 volumio volumio[734]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 17:10:08 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 29 17:10:08 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 17:10:08 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 29 17:10:08 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 17:10:08 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 17:10:08 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 17:10:08 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 17:10:08 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 17:10:08 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 17:10:08 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 17:10:08 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 29 17:10:08 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 29 17:10:47 volumio volumio[734]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Mar 29 17:10:47 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Mar 29 17:10:47 volumio volumio[734]: info: CoreCommandRouter::volumioGetState Mar 29 17:10:47 volumio volumio[734]: info: CorePlayQueue::getTrack 0 Mar 29 17:10:47 volumio volumio[734]: info: Disable softmixer device for audio device Mar 29 17:10:47 volumio volumio[734]: info: Output device has changed, restarting MPD Mar 29 17:10:48 volumio volumio[734]: info: Output device has changed, restarting Shairport Sync Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 17:10:48 volumio sudo[3377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 29 17:10:48 volumio sudo[3375]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 29 17:10:48 volumio sudo[3375]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 17:10:48 volumio sudo[3377]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 17:10:48 volumio sudo[3375]: pam_unix(sudo:session): session closed for user root Mar 29 17:10:48 volumio systemd[1]: Stopping Music Player Daemon... Mar 29 17:10:48 volumio volumio[734]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 29 17:10:48 volumio volumio[734]: info: Restoring Previous Volume level: 82 false false Mar 29 17:10:48 volumio volumio[734]: info: Volume configurations have been set Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 29 17:10:48 volumio volumio[734]: info: Updating Volume Controller Parameters: Device: 5 Name: Combo384 Amanero Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 2 Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 29 17:10:48 volumio volumio[734]: info: Disabling external Volume Control Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 17:10:48 volumio volumio[734]: info: Soft Volume ALSA configuration file deleted Mar 29 17:10:48 volumio volumio[734]: info: Preparing to generate the ALSA configuration file Mar 29 17:10:48 volumio volumio[734]: info: MPD Permissions set Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 17:10:48 volumio systemd[1]: mpd.service: Succeeded. Mar 29 17:10:48 volumio systemd[1]: Stopped Music Player Daemon. Mar 29 17:10:48 volumio systemd[1]: Starting Music Player Daemon... Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 29 17:10:48 volumio volumio[734]: info: VolumeController:: Volume=100 Mute =false Mar 29 17:10:48 volumio volumio[734]: info: CoreStateMachine::pushState Mar 29 17:10:48 volumio volumio[734]: info: CorePlayQueue::getTrack 0 Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::volumioPushState Mar 29 17:10:48 volumio volumio[734]: info: Starting Shairport Sync Mar 29 17:10:48 volumio sudo[3402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 29 17:10:48 volumio sudo[3402]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 17:10:48 volumio volumio[734]: info: Asound.conf file written Mar 29 17:10:48 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 29 17:10:48 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 29 17:10:48 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 29 17:10:48 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 29 17:10:48 volumio sudo[3402]: pam_unix(sudo:session): session closed for user root Mar 29 17:10:48 volumio sudo[3406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 29 17:10:48 volumio sudo[3406]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 17:10:48 volumio sudo[3406]: pam_unix(sudo:session): session closed for user root Mar 29 17:10:48 volumio volumio[734]: info: Output device has changed, restarting MPD Mar 29 17:10:48 volumio sudo[3414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 29 17:10:48 volumio sudo[3414]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 17:10:48 volumio sudo[3414]: pam_unix(sudo:session): session closed for user root Mar 29 17:10:48 volumio volumio[734]: info: Output device has changed, restarting Shairport Sync Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 17:10:48 volumio sudo[3417]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 29 17:10:48 volumio sudo[3417]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 17:10:48 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 29 17:10:48 volumio systemd[1]: mpd.service: Succeeded. Mar 29 17:10:48 volumio systemd[1]: Stopped Music Player Daemon. Mar 29 17:10:48 volumio systemd[1]: Starting Music Player Daemon... Mar 29 17:10:48 volumio volumio[734]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 29 17:10:48 volumio volumio[734]: info: MPD Permissions set Mar 29 17:10:48 volumio volumio[734]: info: Shairport-Sync Started Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 29 17:10:48 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 17:10:48 volumio volumio[734]: info: Starting Shairport Sync Mar 29 17:10:48 volumio sudo[3431]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 29 17:10:48 volumio sudo[3431]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 17:10:48 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 29 17:10:48 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 29 17:10:48 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 29 17:10:48 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 29 17:10:48 volumio sudo[3431]: pam_unix(sudo:session): session closed for user root Mar 29 17:10:48 volumio volumio[734]: info: Shairport-Sync Started Mar 29 17:10:48 volumio volumio[734]: error: Upnp client error: Error: This socket has been ended by the other party Mar 29 17:10:49 volumio mpd[3435]: Mar 29 17:10 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 29 17:10:49 volumio mpd[3435]: Mar 29 17:10 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Mar 29 17:10:49 volumio mpd[3435]: Mar 29 17:10 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Mar 29 17:10:49 volumio systemd[1]: Started Music Player Daemon. Mar 29 17:10:49 volumio sudo[3377]: pam_unix(sudo:session): session closed for user root Mar 29 17:10:49 volumio sudo[3417]: pam_unix(sudo:session): session closed for user root Mar 29 17:10:49 volumio volumio[734]: error: MPD error: The expression evaluated to a falsy value: Mar 29 17:10:49 volumio volumio[734]: assert.ok(self.idling) Mar 29 17:10:49 volumio volumio[734]: error: updateQueue error: null Mar 29 17:10:52 volumio volumio[734]: info: VolumeController::SetAlsaVolume82 Mar 29 17:10:52 volumio volumio[734]: info: CoreStateMachine::pushState Mar 29 17:10:52 volumio volumio[734]: info: CorePlayQueue::getTrack 0 Mar 29 17:10:52 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 17:10:52 volumio volumio[734]: info: CoreCommandRouter::volumioPushState Mar 29 17:10:54 volumio volumio[734]: error: updateQueue error: null Mar 29 17:11:02 volumio volumio[734]: info: CoreCommandRouter::volumioPause Mar 29 17:11:02 volumio volumio[734]: info: CoreStateMachine::pause Mar 29 17:11:02 volumio volumio[734]: info: CoreStateMachine::stPlaybackTimer Mar 29 17:11:02 volumio volumio[734]: info: CoreStateMachine::servicePause Mar 29 17:11:02 volumio volumio[734]: info: CorePlayQueue::getTrack 0 Mar 29 17:11:02 volumio volumio[734]: info: CoreCommandRouter::servicePause Mar 29 17:11:02 volumio volumio[734]: info: ControllerMpd::pause Mar 29 17:11:02 volumio volumio[734]: verbose: ControllerMpd::sendMpdCommand pause Mar 29 17:11:02 volumio volumio[734]: info: sendMpdCommand pause took 0 milliseconds Mar 29 17:11:05 volumio volumio[734]: info: CoreCommandRouter::volumioPause Mar 29 17:11:05 volumio volumio[734]: info: CoreStateMachine::pause Mar 29 17:11:12 volumio volumio[734]: info: VolumeController::SetAlsaVolume+ Mar 29 17:11:12 volumio volumio[734]: info: CoreStateMachine::pushState Mar 29 17:11:12 volumio volumio[734]: info: CorePlayQueue::getTrack 0 Mar 29 17:11:12 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 17:11:12 volumio volumio[734]: info: CoreCommandRouter::volumioPushState Mar 29 17:11:13 volumio volumio[734]: info: VolumeController::SetAlsaVolume- Mar 29 17:11:13 volumio volumio[734]: info: CoreStateMachine::pushState Mar 29 17:11:13 volumio volumio[734]: info: CorePlayQueue::getTrack 0 Mar 29 17:11:13 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 17:11:13 volumio volumio[734]: info: CoreCommandRouter::volumioPushState Mar 29 17:11:18 volumio volumio[734]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 17:11:18 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 29 17:11:18 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 17:11:18 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 29 17:11:18 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 17:11:18 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 17:11:18 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 17:11:18 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 17:11:18 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 17:11:18 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 17:11:18 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 17:11:18 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 29 17:11:18 volumio volumio[734]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 29 17:11:42 volumio volumio[734]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 17:11:42 volumio volumio[734]: Error: connect ETIMEDOUT 23.234.30.58:80 Mar 29 17:11:42 volumio volumio[734]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Mar 29 17:11:42 volumio volumio[734]: errno: -110, Mar 29 17:11:42 volumio volumio[734]: code: 'ETIMEDOUT', Mar 29 17:11:42 volumio volumio[734]: syscall: 'connect', Mar 29 17:11:42 volumio volumio[734]: address: '23.234.30.58', Mar 29 17:11:42 volumio volumio[734]: port: 80 Mar 29 17:11:42 volumio volumio[734]: } Mar 29 17:11:42 volumio volumio[734]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 17:11:42 volumio sudo[3564]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-03-29 17:10 Mar 29 17:11:42 volumio sudo[3564]: 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="1d63a568b7d5e4a317f16be7d0961cbb2d82e282" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="4f8978e0148ab5e1610548b80abf1b4b5e03ea0a" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 07 Feb 2023 05:04:19 PM CET" VOLUMIO_VERSION="3.429" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4c8dcdad83349056095d42fbad08221f"