-- Logs begin at Thu 2019-02-14 17:11:59 +07, end at Fri 2023-10-20 22:30:12 +07. -- Oct 20 22:29:08 volumio volumio[731]: error: error Oct 20 22:29:11 volumio volumio[731]: error: error Oct 20 22:29:30 volumio ntpd[641]: Soliciting pool server 103.130.217.41 Oct 20 22:29:36 volumio ntpd[641]: Soliciting pool server 2a0c:b847:ffff:79::e53c Oct 20 22:29:36 volumio ntpd[641]: Soliciting pool server 115.165.161.155 Oct 20 22:29:39 volumio ntpd[641]: Soliciting pool server 115.165.161.155 Oct 20 22:29:45 volumio kernel: usb 1-1.1: new high-speed USB device number 6 using xhci_hcd Oct 20 22:29:45 volumio kernel: usb 1-1.1: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 2.06 Oct 20 22:29:45 volumio kernel: usb 1-1.1: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Oct 20 22:29:45 volumio kernel: usb 1-1.1: Product: SMSL USB AUDIO Oct 20 22:29:45 volumio kernel: usb 1-1.1: Manufacturer: SMSL Oct 20 22:29:45 volumio kernel: usb 1-1.1: 1:3 : unsupported format bits 0x100000000 Oct 20 22:29:45 volumio kernel: hid-generic 0003:152A:85DD.0003: hiddev96,hidraw0: USB HID v1.10 Device [SMSL SMSL USB AUDIO] on usb-0000:01:00.0-1.1/input3 Oct 20 22:29:45 volumio volumio[731]: info: Oct 20 22:29:45 volumio volumio[731]: ---------------------------- USB Audio Device Attached Oct 20 22:29:45 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Oct 20 22:29:45 volumio volumio[731]: info: CoreCommandRouter::Close All Modals sent Oct 20 22:29:45 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 20 22:29:45 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 20 22:29:45 volumio volumio[731]: info: Device 5 does not have any Mixer Control Available Oct 20 22:29:45 volumio volumio[731]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 20 22:29:45 volumio volumio[731]: info: Updating Volume Controller Parameters: Device: 5 Name: SMSL USB AUDIO Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 20 22:29:45 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 20 22:29:45 volumio volumio[731]: info: Disabling external Volume Control Oct 20 22:29:45 volumio volumio[731]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 20 22:29:45 volumio volumio[731]: info: CoreStateMachine::pushState Oct 20 22:29:45 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 20 22:29:45 volumio volumio[731]: info: CoreCommandRouter::volumioPushState Oct 20 22:29:45 volumio volumio[731]: info: Preparing to generate the ALSA configuration file Oct 20 22:29:45 volumio volumio[731]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'PCM',0 Oct 20 22:29:45 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 20 22:29:45 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 20 22:29:45 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 20 22:29:45 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 20 22:29:45 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 20 22:29:45 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 20 22:29:45 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 20 22:29:45 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 20 22:29:45 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 20 22:29:45 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 20 22:29:45 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 20 22:29:45 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 20 22:29:45 volumio volumio[731]: info: Asound.conf file written Oct 20 22:29:46 volumio sudo[2168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 20 22:29:46 volumio sudo[2168]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 22:29:46 volumio sudo[2168]: pam_unix(sudo:session): session closed for user root Oct 20 22:29:46 volumio volumio[731]: info: Output device has changed, restarting MPD Oct 20 22:29:46 volumio volumio[731]: info: Output device has changed, restarting Shairport Sync Oct 20 22:29:46 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 22:29:46 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 20 22:29:46 volumio sudo[2173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 20 22:29:46 volumio sudo[2173]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 22:29:46 volumio sudo[2173]: pam_unix(sudo:session): session closed for user root Oct 20 22:29:46 volumio sudo[2175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 20 22:29:46 volumio sudo[2175]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 22:29:46 volumio systemd[1]: Stopping Music Player Daemon... Oct 20 22:29:46 volumio volumio[731]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 20 22:29:46 volumio volumio[731]: info: Output device has changed, restarting MPD Oct 20 22:29:46 volumio systemd[1]: mpd.service: Succeeded. Oct 20 22:29:46 volumio systemd[1]: Stopped Music Player Daemon. Oct 20 22:29:46 volumio volumio[731]: info: Output device has changed, restarting Shairport Sync Oct 20 22:29:46 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 22:29:46 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 20 22:29:46 volumio sudo[2183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 20 22:29:46 volumio sudo[2183]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 22:29:46 volumio volumio[731]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 20 22:29:46 volumio volumio[731]: info: MPD Permissions set Oct 20 22:29:46 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 22:29:46 volumio sudo[2183]: pam_unix(sudo:session): session closed for user root Oct 20 22:29:46 volumio systemd[1]: Starting Music Player Daemon... Oct 20 22:29:46 volumio sudo[2185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 20 22:29:46 volumio sudo[2185]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 22:29:46 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 20 22:29:46 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 20 22:29:46 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 22:29:46 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 22:29:46 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 22:29:46 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 22:29:46 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 22:29:46 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 22:29:46 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 22:29:46 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 22:29:46 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 22:29:46 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 20 22:29:46 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 20 22:29:46 volumio volumio[731]: info: Starting Shairport Sync Oct 20 22:29:46 volumio volumio[731]: info: Starting Shairport Sync Oct 20 22:29:46 volumio sudo[2202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 20 22:29:46 volumio systemd[1]: mpd.service: Succeeded. Oct 20 22:29:46 volumio systemd[1]: Stopped Music Player Daemon. Oct 20 22:29:46 volumio sudo[2202]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 22:29:46 volumio systemd[1]: Starting Music Player Daemon... Oct 20 22:29:46 volumio sudo[2204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 20 22:29:46 volumio sudo[2204]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 22:29:46 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 20 22:29:46 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 20 22:29:46 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 20 22:29:46 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 20 22:29:46 volumio sudo[2202]: pam_unix(sudo:session): session closed for user root Oct 20 22:29:46 volumio volumio[731]: info: Shairport-Sync Started Oct 20 22:29:46 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 20 22:29:46 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 20 22:29:46 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 20 22:29:46 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 20 22:29:46 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 20 22:29:46 volumio sudo[2204]: pam_unix(sudo:session): session closed for user root Oct 20 22:29:46 volumio volumio[731]: info: Shairport-Sync Started Oct 20 22:29:46 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Oct 20 22:29:46 volumio mpd[2214]: Oct 20 22:29 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 20 22:29:46 volumio mpd[2214]: Oct 20 22:29 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Oct 20 22:29:46 volumio mpd[2214]: Oct 20 22:29 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Oct 20 22:29:46 volumio systemd[1]: Started Music Player Daemon. Oct 20 22:29:46 volumio sudo[2185]: pam_unix(sudo:session): session closed for user root Oct 20 22:29:46 volumio sudo[2175]: pam_unix(sudo:session): session closed for user root Oct 20 22:29:46 volumio volumio[731]: error: MPD error: The expression evaluated to a falsy value: Oct 20 22:29:46 volumio volumio[731]: assert.ok(self.idling) Oct 20 22:29:46 volumio volumio[731]: error: The expression evaluated to a falsy value: Oct 20 22:29:46 volumio volumio[731]: assert.ok(self.idling) Oct 20 22:29:46 volumio volumio[731]: error: updateQueue error: null Oct 20 22:29:49 volumio volumio[731]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 20 22:29:49 volumio volumio[731]: info: CoreStateMachine::getcurrentVolume Oct 20 22:29:49 volumio volumio[731]: info: CoreCommandRouter::volumioRetrievevolume Oct 20 22:29:49 volumio volumio[731]: info: CoreStateMachine::pushState Oct 20 22:29:49 volumio volumio[731]: info: CoreCommandRouter::volumioPushState Oct 20 22:29:58 volumio volumio[731]: error: error Oct 20 22:30:01 volumio volumio[731]: error: error Oct 20 22:30:09 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 20 22:30:09 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 20 22:30:09 volumio volumio[731]: info: Discovery: Getting this device information Oct 20 22:30:09 volumio volumio[731]: info: CoreCommandRouter::volumioGetState Oct 20 22:30:09 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 20 22:30:09 volumio volumio[731]: verbose: New Socket.io Connection to 192.168.1.3:3000 from 192.168.1.4 UA: Dart/3.4 (dart:io) Total Clients: 3 Oct 20 22:30:09 volumio volumio[731]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Frances%20Black/Don't%20Get%20Me%20Wrong/181cb4fd-bf4b-461a-b122-996cf56bda43.jpg' Oct 20 22:30:09 volumio volumio[731]: info: CoreCommandRouter::volumioGetState Oct 20 22:30:09 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 20 22:30:09 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 20 22:30:09 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 20 22:30:09 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 20 22:30:09 volumio volumio[731]: info: Discovery: Getting this device information Oct 20 22:30:09 volumio volumio[731]: info: CoreCommandRouter::volumioGetState Oct 20 22:30:09 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 20 22:30:10 volumio sudo[2220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 20 22:30:10 volumio sudo[2220]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 22:30:10 volumio sudo[2220]: pam_unix(sudo:session): session closed for user root Oct 20 22:30:10 volumio sudo[2222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 20 22:30:10 volumio sudo[2222]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 22:30:10 volumio sudo[2222]: pam_unix(sudo:session): session closed for user root Oct 20 22:30:11 volumio volumio[731]: verbose: New Socket.io Connection to 192.168.1.3 from 192.168.1.4 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_6_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 4 Oct 20 22:30:11 volumio sudo[2226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 20 22:30:11 volumio sudo[2226]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 22:30:11 volumio sudo[2226]: pam_unix(sudo:session): session closed for user root Oct 20 22:30:11 volumio sudo[2228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 20 22:30:11 volumio sudo[2228]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 20 22:30:11 volumio sudo[2228]: pam_unix(sudo:session): session closed for user root Oct 20 22:30:11 volumio volumio[731]: verbose: New Socket.io Connection to 192.168.1.3 from 192.168.1.4 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_6_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 5 Oct 20 22:30:11 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 20 22:30:11 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 20 22:30:11 volumio volumio[731]: info: CoreCommandRouter::volumioGetVisibleSources Oct 20 22:30:11 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 20 22:30:11 volumio volumio[731]: info: CoreCommandRouter::volumioGetState Oct 20 22:30:11 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 20 22:30:11 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 20 22:30:11 volumio volumio[731]: info: Received Get System Info Oct 20 22:30:11 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 20 22:30:11 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 20 22:30:11 volumio volumio[731]: info: Discovery: Getting this device information Oct 20 22:30:11 volumio volumio[731]: info: CoreCommandRouter::volumioGetState Oct 20 22:30:11 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 20 22:30:11 volumio volumio[731]: info: CoreCommandRouter::volumioGetState Oct 20 22:30:11 volumio volumio[731]: info: Listing playlists Oct 20 22:30:11 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 20 22:30:11 volumio volumio[731]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 20 22:30:11 volumio volumio[731]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 20 22:30:11 volumio volumio[731]: Error: certificate is not yet valid Oct 20 22:30:11 volumio volumio[731]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Oct 20 22:30:11 volumio volumio[731]: at TLSSocket.emit (events.js:315:20) Oct 20 22:30:11 volumio volumio[731]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Oct 20 22:30:11 volumio volumio[731]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Oct 20 22:30:11 volumio volumio[731]: code: 'CERT_NOT_YET_VALID' Oct 20 22:30:11 volumio volumio[731]: } Oct 20 22:30:11 volumio volumio[731]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 20 22:30:12 volumio sudo[2240]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-10-20 22:29 Oct 20 22:30:12 volumio sudo[2240]: 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="893d2e91c55a7857b58762e70c2f65b9d163562b" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="30b77d58bf3c2745acc494ddafed946392a79905" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 20 Oct 2023 03:38:28 PM CEST" VOLUMIO_VERSION="3.569" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4163d3756b55b3bf7c480d7285f68954"