-- Logs begin at Tue 2024-07-23 01:22:29 CST, end at Sun 2024-08-18 12:31:14 CST. -- Aug 18 12:30:00 volumio wpa_supplicant[902]: wlan0: CTRL-EVENT-DISCONNECTED bssid=c2:ea:8f:e0:78:0a reason=9 Aug 18 12:30:00 volumio dhcpcd[914]: wlan0: carrier lost Aug 18 12:30:00 volumio wpa_supplicant[902]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Aug 18 12:30:00 volumio wpa_supplicant[902]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=CN Aug 18 12:30:00 volumio avahi-daemon[614]: Withdrawing address record for 192.168.3.21 on wlan0. Aug 18 12:30:00 volumio avahi-daemon[614]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.21. Aug 18 12:30:00 volumio avahi-daemon[614]: Interface wlan0.IPv4 no longer relevant for mDNS. Aug 18 12:30:00 volumio dhcpcd[914]: wlan0: deleting route to 192.168.3.0/24 Aug 18 12:30:00 volumio dhcpcd[914]: wlan0: deleting default route via 192.168.3.1 Aug 18 12:30:00 volumio dhcpcd[914]: wlan0: dhcp_envoption 213: Operation not supported Aug 18 12:30:00 volumio volumio[1010]: info: Discovery: A device disappeared from network Aug 18 12:30:00 volumio volumio[1010]: info: Discovery: Device volumio disappeared from network Aug 18 12:30:00 volumio wpa_supplicant[902]: wlan0: Trying to associate with SSID 'yzz' Aug 18 12:30:01 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 18 12:30:01 volumio dhcpcd[914]: wlan0: carrier acquired Aug 18 12:30:01 volumio wpa_supplicant[902]: wlan0: Associated with c2:ea:8f:e0:78:0a Aug 18 12:30:01 volumio wpa_supplicant[902]: wlan0: CTRL-EVENT-CONNECTED - Connection to c2:ea:8f:e0:78:0a completed [id=0 id_str=] Aug 18 12:30:01 volumio wpa_supplicant[902]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 18 12:30:01 volumio dhcpcd[914]: wlan0: IAID eb:d3:96:6e Aug 18 12:30:01 volumio dhcpcd[914]: wlan0: carrier lost Aug 18 12:30:01 volumio dhcpcd[914]: wlan0: carrier acquired Aug 18 12:30:01 volumio dhcpcd[914]: wlan0: IAID eb:d3:96:6e Aug 18 12:30:01 volumio dhcpcd[914]: wlan0: soliciting an IPv6 router Aug 18 12:30:01 volumio dhcpcd[914]: wlan0: rebinding lease of 192.168.3.21 Aug 18 12:30:02 volumio dhcpcd[914]: wlan0: NAK: Invalid Request Message from 192.168.3.1 Aug 18 12:30:02 volumio dhcpcd[914]: wlan0: message: Invalid Request Message Aug 18 12:30:02 volumio dhcpcd[914]: wlan0: soliciting a DHCP lease Aug 18 12:30:02 volumio dhcpcd[914]: wlan0: offered 192.168.3.21 from 192.168.3.1 Aug 18 12:30:02 volumio dhcpcd[914]: wlan0: probing address 192.168.3.21/24 Aug 18 12:30:02 volumio ntpd[798]: Deleting interface #8 wlan0, 192.168.3.21#123, interface stats: received=0, sent=0, dropped=0, active_time=208 secs Aug 18 12:30:07 volumio kernel: hwmon hwmon1: Voltage normalised Aug 18 12:30:08 volumio dhcpcd[914]: wlan0: leased 192.168.3.21 for 86400 seconds Aug 18 12:30:08 volumio avahi-daemon[614]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.21. Aug 18 12:30:08 volumio avahi-daemon[614]: New relevant interface wlan0.IPv4 for mDNS. Aug 18 12:30:08 volumio avahi-daemon[614]: Registering new address record for 192.168.3.21 on wlan0.IPv4. Aug 18 12:30:08 volumio dhcpcd[914]: wlan0: adding route to 192.168.3.0/24 Aug 18 12:30:08 volumio dhcpcd[914]: wlan0: adding default route via 192.168.3.1 Aug 18 12:30:08 volumio dhcpcd[914]: wlan0: dhcp_envoption 213: Operation not supported Aug 18 12:30:09 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 18 12:30:09 volumio volumio[1010]: error: Upnp client error: Error: This socket has been ended by the other party Aug 18 12:30:09 volumio volumio[1010]: info: Discovery: adding bf9e65f3-e8bb-433a-a262-ab37b6b92091 Aug 18 12:30:09 volumio volumio[1010]: info: Discovery: Found device Volumio Aug 18 12:30:09 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Aug 18 12:30:09 volumio ntpd[798]: Listen normally on 9 wlan0 192.168.3.21:123 Aug 18 12:30:09 volumio ntpd[798]: new interface(s) found: waking up resolver Aug 18 12:30:17 volumio kernel: hwmon hwmon1: Voltage normalised Aug 18 12:30:26 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Aug 18 12:30:28 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 18 12:30:29 volumio volumio[1010]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 18 12:30:29 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 18 12:30:29 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 12:30:29 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 18 12:30:29 volumio volumio[1010]: info: Getting Alsa Cards List without I2S DAC Aug 18 12:30:30 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 18 12:30:30 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 12:30:30 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 12:30:30 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 12:30:30 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 12:30:30 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 12:30:30 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 12:30:30 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 12:30:30 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 12:30:30 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 18 12:30:30 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 18 12:30:36 volumio kernel: hwmon hwmon1: Voltage normalised Aug 18 12:30:46 volumio nmbd[755]: [2024/08/18 12:30:46.958372, 0] ../source3/nmbd/nmbd_packets.c:1398(validate_nmb_packet) Aug 18 12:30:46 volumio nmbd[755]: validate_nmb_packet: Bad QUERY Packet. validate_nmb_packet: Ignoring request packet with opcode 0. Aug 18 12:30:47 volumio nmbd[755]: [2024/08/18 12:30:47.171781, 0] ../source3/nmbd/nmbd_packets.c:1398(validate_nmb_packet) Aug 18 12:30:47 volumio nmbd[755]: validate_nmb_packet: Bad QUERY Packet. validate_nmb_packet: Ignoring request packet with opcode 0. Aug 18 12:30:47 volumio nmbd[755]: [2024/08/18 12:30:47.172195, 0] ../source3/nmbd/nmbd_packets.c:1398(validate_nmb_packet) Aug 18 12:30:47 volumio nmbd[755]: validate_nmb_packet: Bad QUERY Packet. validate_nmb_packet: Ignoring request packet with opcode 0. Aug 18 12:30:47 volumio nmbd[755]: [2024/08/18 12:30:47.172391, 0] ../source3/nmbd/nmbd_packets.c:1398(validate_nmb_packet) Aug 18 12:30:47 volumio nmbd[755]: validate_nmb_packet: Bad QUERY Packet. validate_nmb_packet: Ignoring request packet with opcode 0. Aug 18 12:30:47 volumio nmbd[755]: [2024/08/18 12:30:47.172571, 0] ../source3/nmbd/nmbd_packets.c:1398(validate_nmb_packet) Aug 18 12:30:47 volumio nmbd[755]: validate_nmb_packet: Bad QUERY Packet. validate_nmb_packet: Ignoring request packet with opcode 0. Aug 18 12:30:47 volumio nmbd[755]: [2024/08/18 12:30:47.172749, 0] ../source3/nmbd/nmbd_packets.c:1398(validate_nmb_packet) Aug 18 12:30:47 volumio nmbd[755]: validate_nmb_packet: Bad QUERY Packet. validate_nmb_packet: Ignoring request packet with opcode 0. Aug 18 12:30:47 volumio nmbd[755]: [2024/08/18 12:30:47.172926, 0] ../source3/nmbd/nmbd_packets.c:1398(validate_nmb_packet) Aug 18 12:30:47 volumio nmbd[755]: validate_nmb_packet: Bad QUERY Packet. validate_nmb_packet: Ignoring request packet with opcode 0. Aug 18 12:30:47 volumio nmbd[755]: [2024/08/18 12:30:47.634783, 0] ../source3/nmbd/nmbd_packets.c:1398(validate_nmb_packet) Aug 18 12:30:47 volumio nmbd[755]: validate_nmb_packet: Bad QUERY Packet. validate_nmb_packet: Ignoring request packet with opcode 0. Aug 18 12:30:50 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 18 12:30:51 volumio volumio[1010]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Aug 18 12:30:51 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Aug 18 12:30:51 volumio volumio[1010]: info: CoreCommandRouter::volumioGetState Aug 18 12:30:51 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Aug 18 12:30:51 volumio volumio[1010]: info: Restoring Previous Volume level: 30 false true Aug 18 12:30:51 volumio volumio[1010]: info: VolumeController::SetAlsaVolume100 Aug 18 12:30:51 volumio sudo[2790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 18 12:30:51 volumio sudo[2790]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 12:30:51 volumio sudo[2790]: pam_unix(sudo:session): session closed for user root Aug 18 12:30:51 volumio volumio[1010]: info: Volume configurations have been set Aug 18 12:30:51 volumio sudo[2792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 18 12:30:51 volumio sudo[2792]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 12:30:51 volumio systemd[1]: Stopping Music Player Daemon... Aug 18 12:30:51 volumio systemd[1]: mpd.service: Succeeded. Aug 18 12:30:51 volumio systemd[1]: Stopped Music Player Daemon. Aug 18 12:30:51 volumio volumio[1010]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 18 12:30:51 volumio volumio[1010]: info: Updating Volume Controller Parameters: Device: 2 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 5 Aug 18 12:30:51 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 18 12:30:51 volumio volumio[1010]: info: Disabling external Volume Control Aug 18 12:30:51 volumio volumio[1010]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 18 12:30:51 volumio systemd[1]: Starting Music Player Daemon... Aug 18 12:30:51 volumio volumio[1010]: info: CoreStateMachine::pushState Aug 18 12:30:51 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 12:30:51 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Aug 18 12:30:51 volumio volumio[1010]: info: CoreStateMachine::pushState Aug 18 12:30:51 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 12:30:51 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Aug 18 12:30:51 volumio volumio[1010]: info: MPD Permissions set Aug 18 12:30:51 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 12:30:51 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 12:30:51 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 12:30:51 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 12:30:51 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 12:30:51 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 12:30:51 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 12:30:51 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 12:30:51 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 12:30:51 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 12:30:51 volumio sudo[2798]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 18 12:30:51 volumio sudo[2798]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 12:30:51 volumio sudo[2798]: pam_unix(sudo:session): session closed for user root Aug 18 12:30:51 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 18 12:30:51 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 12:30:51 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 18 12:30:51 volumio volumio[1010]: info: Getting Alsa Cards List without I2S DAC Aug 18 12:30:51 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 18 12:30:51 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 12:30:52 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 12:30:52 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 12:30:52 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 12:30:52 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 12:30:52 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 12:30:52 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 12:30:52 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 12:30:52 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 18 12:30:52 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 18 12:30:54 volumio mpd[2814]: Aug 18 12:30 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 18 12:30:54 volumio systemd[1]: Started Music Player Daemon. Aug 18 12:30:54 volumio sudo[2792]: pam_unix(sudo:session): session closed for user root Aug 18 12:30:54 volumio volumio[1010]: error: updateQueue error: null Aug 18 12:30:55 volumio volumio[1010]: info: VolumeController::SetAlsaVolume30 Aug 18 12:30:55 volumio volumio[1010]: info: CoreStateMachine::pushState Aug 18 12:30:55 volumio volumio[1010]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 12:30:55 volumio volumio[1010]: info: CoreCommandRouter::volumioPushState Aug 18 12:31:01 volumio kernel: hwmon hwmon1: Voltage normalised Aug 18 12:31:09 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 18 12:31:13 volumio volumio[1010]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 12:31:13 volumio volumio[1010]: Error: connect ETIMEDOUT 157.240.16.50:80 Aug 18 12:31:13 volumio volumio[1010]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Aug 18 12:31:13 volumio volumio[1010]: errno: -110, Aug 18 12:31:13 volumio volumio[1010]: code: 'ETIMEDOUT', Aug 18 12:31:13 volumio volumio[1010]: syscall: 'connect', Aug 18 12:31:13 volumio volumio[1010]: address: '157.240.16.50', Aug 18 12:31:13 volumio volumio[1010]: port: 80 Aug 18 12:31:13 volumio volumio[1010]: } Aug 18 12:31:13 volumio volumio[1010]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 12:31:14 volumio sudo[2874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-18 12:30 Aug 18 12:31:14 volumio sudo[2874]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"