-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Sun 2024-08-18 00:54:47 CST. -- Aug 18 00:53:00 volumio sudo[1057]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:00 volumio wireless.js[808]: DHCP IP Aug 18 00:53:00 volumio wireless.js[808]: Start ap Aug 18 00:53:00 volumio wpa_supplicant[1059]: Successfully initialized wpa_supplicant Aug 18 00:53:00 volumio sudo[1060]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Aug 18 00:53:00 volumio sudo[1060]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:00 volumio dhcpcd[1061]: dev: loaded udev Aug 18 00:53:00 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 18 00:53:00 volumio dhcpcd-run-hooks[1079]: wlan0: starting wpa_supplicant Aug 18 00:53:00 volumio dhcpcd-run-hooks[1084]: wlan0: failed to start wpa_supplicant Aug 18 00:53:00 volumio dhcpcd-run-hooks[1085]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma Aug 18 00:53:00 volumio dhcpcd[1061]: wlan0: connected to Access Point `' Aug 18 00:53:00 volumio dhcpcd[1061]: no interfaces have a carrier Aug 18 00:53:00 volumio dhcpcd[1061]: forked to background, child pid 1095 Aug 18 00:53:00 volumio dhcpcd[1095]: eth0: waiting for carrier Aug 18 00:53:00 volumio dhcpcd[1095]: wlan0: waiting for carrier Aug 18 00:53:00 volumio sudo[1060]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:00 volumio ntpd[976]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Aug 18 00:53:01 volumio wireless.js[808]: trying... Aug 18 00:53:01 volumio sudo[1097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 18 00:53:01 volumio sudo[1097]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:01 volumio sudo[1097]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:01 volumio wpa_supplicant[1083]: wlan0: Trying to associate with SSID 'ME Home (5GHz)' Aug 18 00:53:01 volumio ntpd[976]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Aug 18 00:53:02 volumio systemd[1]: systemd-rfkill.service: Succeeded. Aug 18 00:53:02 volumio wireless.js[808]: trying... Aug 18 00:53:02 volumio sudo[1100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 18 00:53:02 volumio sudo[1100]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:02 volumio sudo[1100]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:02 volumio wpa_supplicant[1083]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Aug 18 00:53:02 volumio volumio-remote-updater[821]: [2024-08-18 00:53:02] [info] asio async_connect error: system:111 (Connection refused) Aug 18 00:53:02 volumio volumio-remote-updater[821]: [2024-08-18 00:53:02] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 18 00:53:02 volumio volumio-remote-updater[821]: [2024-08-18 00:53:02] [error] handle_connect error: Underlying Transport Error Aug 18 00:53:02 volumio bthelper[797]: Changing power off succeeded Aug 18 00:53:02 volumio bthelper[797]: [CHG] Controller 2C:CF:67:38:FC:78 Class: 0x0000041c Aug 18 00:53:02 volumio bthelper[797]: Changing power on succeeded Aug 18 00:53:03 volumio wireless.js[808]: trying... Aug 18 00:53:03 volumio sudo[1105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 18 00:53:03 volumio sudo[1105]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:03 volumio sudo[1105]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:03 volumio wpa_supplicant[1083]: wlan0: Trying to associate with SSID 'ME Home (5GHz)' Aug 18 00:53:04 volumio wireless.js[808]: trying... Aug 18 00:53:04 volumio sudo[1108]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 18 00:53:04 volumio sudo[1108]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:04 volumio sudo[1108]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:05 volumio wireless.js[808]: trying... Aug 18 00:53:05 volumio sudo[1111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 18 00:53:05 volumio sudo[1111]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:05 volumio sudo[1111]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:05 volumio wpa_supplicant[1083]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Aug 18 00:53:06 volumio wireless.js[808]: trying... Aug 18 00:53:06 volumio sudo[1114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 18 00:53:06 volumio sudo[1114]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:06 volumio sudo[1114]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:06 volumio wpa_supplicant[1083]: wlan0: Trying to associate with SSID 'ME Home (5GHz)' Aug 18 00:53:07 volumio wireless.js[808]: trying... Aug 18 00:53:07 volumio sudo[1117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 18 00:53:07 volumio sudo[1117]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:07 volumio sudo[1117]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:08 volumio wireless.js[808]: trying... Aug 18 00:53:08 volumio sudo[1120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 18 00:53:08 volumio sudo[1120]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:08 volumio sudo[1120]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:08 volumio wpa_supplicant[1083]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Aug 18 00:53:09 volumio wireless.js[808]: trying... Aug 18 00:53:09 volumio sudo[1123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 18 00:53:09 volumio sudo[1123]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:09 volumio sudo[1123]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:09 volumio wpa_supplicant[1083]: wlan0: Trying to associate with SSID 'ME Home (5GHz)' Aug 18 00:53:10 volumio volumio-remote-updater[821]: [2024-08-18 00:53:10] [info] asio async_connect error: system:111 (Connection refused) Aug 18 00:53:10 volumio volumio-remote-updater[821]: [2024-08-18 00:53:10] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 18 00:53:10 volumio volumio-remote-updater[821]: [2024-08-18 00:53:10] [error] handle_connect error: Underlying Transport Error Aug 18 00:53:10 volumio wireless.js[808]: trying... Aug 18 00:53:10 volumio sudo[1126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 18 00:53:10 volumio sudo[1126]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:10 volumio sudo[1126]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:11 volumio wireless.js[808]: trying... Aug 18 00:53:11 volumio wpa_supplicant[1083]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Aug 18 00:53:11 volumio sudo[1129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 18 00:53:11 volumio sudo[1129]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:11 volumio sudo[1129]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:12 volumio wireless.js[808]: trying... Aug 18 00:53:12 volumio sudo[1132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 18 00:53:12 volumio sudo[1132]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:12 volumio sudo[1132]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:12 volumio wpa_supplicant[1083]: wlan0: Trying to associate with SSID 'ME Home (5GHz)' Aug 18 00:53:13 volumio wireless.js[808]: trying... Aug 18 00:53:13 volumio sudo[1135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 18 00:53:13 volumio sudo[1135]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:13 volumio sudo[1135]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:13 volumio wpa_supplicant[1083]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Aug 18 00:53:14 volumio wireless.js[808]: trying... Aug 18 00:53:14 volumio sudo[1138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 18 00:53:14 volumio sudo[1138]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:14 volumio sudo[1138]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:14 volumio wpa_supplicant[1083]: wlan0: Trying to associate with SSID 'ME Home (5GHz)' Aug 18 00:53:15 volumio wireless.js[808]: trying... Aug 18 00:53:15 volumio sudo[1141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 18 00:53:15 volumio sudo[1141]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:15 volumio sudo[1141]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:16 volumio wireless.js[808]: trying... Aug 18 00:53:16 volumio sudo[1144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 18 00:53:16 volumio sudo[1144]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:16 volumio sudo[1144]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:16 volumio wpa_supplicant[1083]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Aug 18 00:53:17 volumio wireless.js[808]: trying... Aug 18 00:53:17 volumio sudo[1147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 18 00:53:17 volumio sudo[1147]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:17 volumio sudo[1147]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:18 volumio wpa_supplicant[1083]: wlan0: Trying to associate with SSID 'ME Home (5GHz)' Aug 18 00:53:18 volumio wireless.js[808]: trying... Aug 18 00:53:18 volumio sudo[1150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 18 00:53:18 volumio sudo[1150]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:18 volumio sudo[1150]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:19 volumio wireless.js[808]: trying... Aug 18 00:53:19 volumio sudo[1153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 18 00:53:19 volumio sudo[1153]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:19 volumio sudo[1153]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:19 volumio wpa_supplicant[1083]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Aug 18 00:53:20 volumio volumio-remote-updater[821]: [2024-08-18 00:53:20] [info] asio async_connect error: system:111 (Connection refused) Aug 18 00:53:20 volumio volumio-remote-updater[821]: [2024-08-18 00:53:20] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 18 00:53:20 volumio volumio-remote-updater[821]: [2024-08-18 00:53:20] [error] handle_connect error: Underlying Transport Error Aug 18 00:53:20 volumio wireless.js[808]: trying... Aug 18 00:53:20 volumio sudo[1156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 18 00:53:20 volumio sudo[1156]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:20 volumio sudo[1156]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:21 volumio wireless.js[808]: Overtime, starting plan B Aug 18 00:53:21 volumio wireless.js[808]: STARTING HOTSPOT Aug 18 00:53:21 volumio wpa_supplicant[1083]: nl80211: Failed to open /proc/sys/net/ipv4/conf/p2p-dev-wlan0/drop_unicast_in_l2_multicast: No such file or directory Aug 18 00:53:21 volumio wpa_supplicant[1083]: nl80211: Failed to set IPv4 unicast in multicast filter Aug 18 00:53:21 volumio wpa_supplicant[1083]: nl80211: Failed to open /proc/sys/net/ipv4/conf/p2p-dev-wlan0/drop_unicast_in_l2_multicast: No such file or directory Aug 18 00:53:21 volumio wpa_supplicant[1083]: nl80211: Failed to set IPv4 unicast in multicast filter Aug 18 00:53:21 volumio wpa_supplicant[1083]: nl80211: deinit ifname=p2p-dev-wlan0 disabled_11b_rates=0 Aug 18 00:53:21 volumio wpa_supplicant[1083]: p2p-dev-wlan0: CTRL-EVENT-TERMINATING Aug 18 00:53:21 volumio wpa_supplicant[1083]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Aug 18 00:53:21 volumio wpa_supplicant[1083]: wlan0: CTRL-EVENT-TERMINATING Aug 18 00:53:24 volumio sudo[1165]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 18 00:53:24 volumio sudo[1165]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:24 volumio sudo[1165]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:24 volumio sudo[1167]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Aug 18 00:53:24 volumio sudo[1167]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:24 volumio sudo[1167]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:24 volumio avahi-daemon[796]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Aug 18 00:53:24 volumio avahi-daemon[796]: New relevant interface wlan0.IPv4 for mDNS. Aug 18 00:53:24 volumio avahi-daemon[796]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Aug 18 00:53:24 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 18 00:53:24 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Aug 18 00:53:24 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Aug 18 00:53:24 volumio dnsmasq[1174]: dnsmasq: syntax check OK. Aug 18 00:53:24 volumio hostapd[1173]: Configuration file: /etc/hostapd/hostapd.conf Aug 18 00:53:24 volumio hostapd[1173]: wlan0: Could not connect to kernel driver Aug 18 00:53:24 volumio hostapd[1173]: Using interface wlan0 with hwaddr 2c:cf:67:38:fc:77 and ssid "Volumio-AC5E5" Aug 18 00:53:24 volumio dnsmasq[1180]: started, version 2.80 cachesize 150 Aug 18 00:53:24 volumio dnsmasq[1180]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Aug 18 00:53:24 volumio dnsmasq-dhcp[1180]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Aug 18 00:53:24 volumio dnsmasq[1180]: reading /etc/resolv.conf Aug 18 00:53:24 volumio dnsmasq[1180]: using nameserver 208.67.222.222#53 Aug 18 00:53:24 volumio dnsmasq[1180]: using nameserver 208.67.220.220#53 Aug 18 00:53:24 volumio dnsmasq[1180]: read /etc/hosts - 5 addresses Aug 18 00:53:24 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Aug 18 00:53:24 volumio systemd[1]: Reached target Host and Network Name Lookups. Aug 18 00:53:24 volumio dhcpcd[1095]: wlan0: carrier acquired Aug 18 00:53:24 volumio hostapd[1173]: wlan0: interface state UNINITIALIZED->ENABLED Aug 18 00:53:24 volumio hostapd[1173]: wlan0: AP-ENABLED Aug 18 00:53:24 volumio systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Aug 18 00:53:24 volumio dhcpcd[1095]: DUID 00:01:00:01:2d:f2:1d:98:2c:cf:67:11:b0:20 Aug 18 00:53:24 volumio dhcpcd[1095]: wlan0: IAID 67:38:fc:77 Aug 18 00:53:24 volumio dhcpcd[1095]: wlan0: adding address fe80::2ecf:67ff:fe38:fc77 Aug 18 00:53:24 volumio dhcpcd[1095]: ipv6_addaddr1: Permission denied Aug 18 00:53:24 volumio dhcpcd[1095]: wlan0: probing address 192.168.1.65/24 Aug 18 00:53:24 volumio iwconfig[1189]: Error for wireless request "Set Power Management" (8B2C) : Aug 18 00:53:24 volumio iwconfig[1189]: SET failed on device wlan0 ; Invalid argument. Aug 18 00:53:24 volumio systemd[1]: Started Wireless Services. Aug 18 00:53:24 volumio systemd[1]: Started Volumio Backend Module. Aug 18 00:53:24 volumio systemd[1]: Started Volumio Cpu Tweaker. Aug 18 00:53:24 volumio volumio-cpu-tweak[1191]: Setting RT Priority for mpd Aug 18 00:53:24 volumio volumio-cpu-tweak[1191]: pid 35's current scheduling policy: SCHED_OTHER Aug 18 00:53:24 volumio volumio-cpu-tweak[1191]: pid 35's current scheduling priority: 0 Aug 18 00:53:24 volumio volumio-cpu-tweak[1191]: Setting MPD Affinity Aug 18 00:53:24 volumio volumio-cpu-tweak[1191]: pid 3's current affinity mask: f Aug 18 00:53:24 volumio volumio-cpu-tweak[1191]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Aug 18 00:53:24 volumio volumio-cpu-tweak[1191]: VOLUMIO CPU TWEAK: Setting CPU Governor: conservative Aug 18 00:53:24 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Aug 18 00:53:24 volumio dhcpcd[1095]: wlan0: soliciting an IPv6 router Aug 18 00:53:25 volumio volumio[1190]: info: ------------------------------------------- Aug 18 00:53:25 volumio volumio[1190]: info: ----- Volumio3 ---- Aug 18 00:53:25 volumio volumio[1190]: info: ------------------------------------------- Aug 18 00:53:25 volumio volumio[1190]: info: ----- System startup ---- Aug 18 00:53:25 volumio volumio[1190]: info: ------------------------------------------- Aug 18 00:53:25 volumio volumio[1190]: info: MYVOLUMIO Environment detected Aug 18 00:53:25 volumio volumio[1190]: info: Plugin folders cleanup Aug 18 00:53:25 volumio volumio[1190]: info: Scanning into folder /volumio/app/plugins/ Aug 18 00:53:25 volumio volumio[1190]: info: Scanning category audio_interface Aug 18 00:53:25 volumio volumio[1190]: info: Scanning category miscellanea Aug 18 00:53:25 volumio volumio[1190]: info: Scanning category music_service Aug 18 00:53:25 volumio volumio[1190]: info: Scanning category plugins.json Aug 18 00:53:25 volumio volumio[1190]: info: Scanning category system_controller Aug 18 00:53:25 volumio volumio[1190]: info: Scanning category user_interface Aug 18 00:53:25 volumio volumio[1190]: info: Scanning into folder /data/plugins/ Aug 18 00:53:25 volumio volumio[1190]: info: Scanning category system_controller Aug 18 00:53:25 volumio volumio[1190]: info: Scanning category system_hardware Aug 18 00:53:25 volumio volumio[1190]: info: Scanning category user_interface Aug 18 00:53:25 volumio volumio[1190]: info: Plugin folders cleanup completed Aug 18 00:53:25 volumio volumio[1190]: info: ------------------------------------------- Aug 18 00:53:25 volumio volumio[1190]: info: ----- Core plugins startup ---- Aug 18 00:53:25 volumio volumio[1190]: info: ------------------------------------------- Aug 18 00:53:25 volumio volumio[1190]: info: Loading plugins from folder /volumio/app/plugins/ Aug 18 00:53:25 volumio volumio[1190]: info: Adding plugin upnp to MyMusic Plugins Aug 18 00:53:25 volumio volumio[1190]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 18 00:53:25 volumio volumio[1190]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 18 00:53:25 volumio volumio[1190]: info: Loading plugins from folder /data/plugins/ Aug 18 00:53:25 volumio volumio[1190]: info: Loading plugin "system"... Aug 18 00:53:25 volumio volumio[1190]: info: Loading plugin "appearance"... Aug 18 00:53:25 volumio volumio[1190]: info: Loading plugin "network"... Aug 18 00:53:25 volumio volumio[1190]: info: Refreshing Cached IP Addresses Aug 18 00:53:25 volumio sudo[1221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 18 00:53:25 volumio sudo[1221]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:25 volumio sudo[1221]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:25 volumio sudo[1223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 18 00:53:25 volumio sudo[1223]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:25 volumio volumio[1190]: info: Loading plugin "services"... Aug 18 00:53:25 volumio sudo[1223]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:25 volumio volumio[1190]: info: Loading plugin "alsa_controller"... Aug 18 00:53:25 volumio sudo[1226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 18 00:53:25 volumio sudo[1226]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:25 volumio sudo[1226]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:25 volumio kernel: ieee80211 phy0: brcmf_vif_set_mgmt_ie: vndr ie set error : -52 Aug 18 00:53:25 volumio kernel: ieee80211 phy0: brcmf_cfg80211_scan: scan error (-52) Aug 18 00:53:25 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 18 00:53:25 volumio volumio[1190]: info: Loading plugin "wizard"... Aug 18 00:53:25 volumio volumio[1190]: info: Loading plugin "networkfs"... Aug 18 00:53:25 volumio ntpd[976]: Listen normally on 3 wlan0 192.168.211.1:123 Aug 18 00:53:25 volumio ntpd[976]: new interface(s) found: waking up resolver Aug 18 00:53:25 volumio volumio[1190]: info: Starting Udev Watcher for removable devices Aug 18 00:53:25 volumio sudo[1232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.1.104:/volume1/music /mnt/NAS/music Aug 18 00:53:25 volumio sudo[1232]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:25 volumio volumio[1190]: info: Ignoring mount for partition: boot Aug 18 00:53:25 volumio volumio[1190]: info: Ignoring mount for partition: volumio Aug 18 00:53:25 volumio volumio[1190]: info: Ignoring mount for partition: volumio_data Aug 18 00:53:25 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 18 00:53:25 volumio volumio[1190]: info: Loading plugin "volumio_command_line_client"... Aug 18 00:53:25 volumio volumio[1190]: info: Loading plugin "upnp"... Aug 18 00:53:25 volumio volumio[1190]: info: [1723913605686] Starting Upmpd Daemon Aug 18 00:53:25 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 18 00:53:25 volumio sudo[1232]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:25 volumio volumio[1190]: info: Loading plugin "my_music"... Aug 18 00:53:25 volumio volumio[1190]: info: Loading plugin "mpd"... Aug 18 00:53:25 volumio volumio[1190]: info: Loading plugin "upnp_browser"... Aug 18 00:53:25 volumio volumio[1190]: info: Loading plugin "alarm-clock"... Aug 18 00:53:25 volumio volumio[1190]: info: Loading plugin "airplay_emulation"... Aug 18 00:53:25 volumio volumio[1190]: info: Starting Shairport Sync Aug 18 00:53:25 volumio volumio[1190]: info: Loading plugin "last_100"... Aug 18 00:53:25 volumio volumio[1190]: info: Loading plugin "webradio"... Aug 18 00:53:25 volumio volumio[1190]: info: Loading plugin "i2s_dacs"... Aug 18 00:53:25 volumio volumio[1190]: info: Loading plugin "volumiodiscovery"... Aug 18 00:53:25 volumio volumio[1190]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 18 00:53:25 volumio node[1190]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 18 00:53:25 volumio volumio[1190]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 18 00:53:25 volumio node[1190]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 18 00:53:25 volumio volumio[1190]: *** WARNING *** For more information see Aug 18 00:53:25 volumio node[1190]: *** WARNING *** For more information see Aug 18 00:53:25 volumio volumio[1190]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 18 00:53:25 volumio node[1190]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 18 00:53:25 volumio volumio[1190]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 18 00:53:25 volumio node[1190]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 18 00:53:25 volumio volumio[1190]: *** WARNING *** For more information see Aug 18 00:53:25 volumio node[1190]: *** WARNING *** For more information see Aug 18 00:53:25 volumio volumio[1190]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 18 00:53:25 volumio volumio[1190]: info: Discovery: Started advertising with name: Volumio Aug 18 00:53:25 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 18 00:53:25 volumio volumio[1190]: info: Loading plugin "autostart"... Aug 18 00:53:26 volumio volumio[1190]: info: Applying required configuration parameters for plugin autostart Aug 18 00:53:26 volumio volumio[1190]: info: AutoStart - onVolumioStart - read config.json Aug 18 00:53:26 volumio volumio[1190]: info: Loading plugin "peppy_screensaver"... Aug 18 00:53:26 volumio volumio[1190]: info: Applying required configuration parameters for plugin peppy_screensaver Aug 18 00:53:26 volumio volumio[1190]: info: Loading plugin "outputs"... Aug 18 00:53:26 volumio volumio[1190]: info: Loading plugin "albumart"... Aug 18 00:53:26 volumio volumio[1190]: info: Plugin example_plugin is not enabled Aug 18 00:53:26 volumio volumio[1190]: info: Loading plugin "inputs"... Aug 18 00:53:26 volumio volumio[1190]: info: Loading plugin "updater_comm"... Aug 18 00:53:26 volumio volumio[1190]: info: Plugin mpdemulation is not enabled Aug 18 00:53:26 volumio volumio[1190]: info: Loading plugin "rest_api"... Aug 18 00:53:26 volumio volumio[1190]: info: Loading plugin "websocket"... Aug 18 00:53:26 volumio volumio[1190]: info: Starting Socket.io Server version 2.3.0 Aug 18 00:53:26 volumio volumio[1190]: info: Loading plugin "ir_controller"... Aug 18 00:53:26 volumio volumio[1190]: Forking 3 albumart workers Aug 18 00:53:26 volumio volumio[1190]: info: Applying required configuration parameters for plugin ir_controller Aug 18 00:53:26 volumio volumio[1190]: info: Loading plugin "touch_display"... Aug 18 00:53:26 volumio volumio[1190]: info: Applying required configuration parameters for plugin touch_display Aug 18 00:53:26 volumio volumio[1190]: info: Loading i18n strings for locale zh_TW Aug 18 00:53:26 volumio volumio[1190]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Aug 18 00:53:26 volumio volumio[1190]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Aug 18 00:53:26 volumio volumio[1190]: Updating browse sources language Aug 18 00:53:26 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 00:53:26 volumio volumio[1190]: Starting albumart workers Aug 18 00:53:26 volumio volumio[1190]: Starting albumart workers Aug 18 00:53:26 volumio volumio[1190]: Starting albumart workers Aug 18 00:53:26 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 00:53:26 volumio volumio[1190]: info: CoreCommandRouter::initPlayerControls Aug 18 00:53:26 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:26 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:26 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:26 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:26 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:26 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:26 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:26 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:26 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 00:53:26 volumio volumio[1190]: Express server listening on port 3000 Aug 18 00:53:26 volumio volumio[1190]: [Metrics] WebUI: 1s 789.92ms Aug 18 00:53:26 volumio volumio[1190]: info: CoreStateMachine::resetVolumioState Aug 18 00:53:26 volumio volumio[1190]: info: CoreStateMachine::getcurrentVolume Aug 18 00:53:26 volumio volumio[1190]: info: CoreCommandRouter::volumioRetrievevolume Aug 18 00:53:26 volumio volumio[1190]: info: Cannot mount NAS music at system boot, trial number 1 ,retrying in 5 seconds Aug 18 00:53:26 volumio volumio[1190]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Aug 18 00:53:26 volumio volumio[1190]: wlan0 Interface doesn't support scanning : Invalid exchange Aug 18 00:53:26 volumio volumio[1190]: info: Cannot use regular scanning, forcing with ap-force Aug 18 00:53:26 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 18 00:53:26 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Aug 18 00:53:26 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 18 00:53:26 volumio sudo[1282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Aug 18 00:53:26 volumio sudo[1282]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:26 volumio systemd[1]: systemd-fsckd.service: Succeeded. Aug 18 00:53:27 volumio sh[742]: timed out Aug 18 00:53:27 volumio dhcpcd[785]: timed out Aug 18 00:53:27 volumio sh[742]: dhcpcd exited Aug 18 00:53:27 volumio dhcpcd[785]: dhcpcd exited Aug 18 00:53:27 volumio sh[742]: ifup: failed to bring up eth0 Aug 18 00:53:27 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Aug 18 00:53:27 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Aug 18 00:53:27 volumio nmbd[960]: [2024/08/18 00:53:27.887177, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 18 00:53:27 volumio nmbd[960]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Aug 18 00:53:27 volumio systemd[1]: Started Samba NMB Daemon. Aug 18 00:53:27 volumio systemd[1]: Starting Samba Winbind Daemon... Aug 18 00:53:27 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Aug 18 00:53:27 volumio winbindd[1309]: [2024/08/18 00:53:27.999431, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Aug 18 00:53:27 volumio winbindd[1309]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Aug 18 00:53:28 volumio winbindd[1309]: [2024/08/18 00:53:28.004599, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 18 00:53:28 volumio winbindd[1309]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Aug 18 00:53:28 volumio systemd[1]: Started Samba Winbind Daemon. Aug 18 00:53:28 volumio systemd[1]: Starting Samba SMB Daemon... Aug 18 00:53:28 volumio sudo[1282]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:28 volumio volumio[1190]: info: CoreStateMachine::pushState Aug 18 00:53:28 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Aug 18 00:53:28 volumio volumio[1190]: info: CoreStateMachine::updateTrackBlock Aug 18 00:53:28 volumio volumio[1190]: info: CorePlayQueue::getTrackBlock Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::volumioRetrievevolume Aug 18 00:53:28 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 18 00:53:28 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Aug 18 00:53:28 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 18 00:53:28 volumio volumio[1190]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Aug 18 00:53:28 volumio volumio[1190]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 00:53:28 volumio volumio[1190]: info: Reloading queue from file Aug 18 00:53:28 volumio winbindd[1309]: [2024/08/18 00:53:28.134861, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Aug 18 00:53:28 volumio winbindd[1309]: res_names->count = 3, expected 4 Aug 18 00:53:28 volumio smbd[1316]: [2024/08/18 00:53:28.149070, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 18 00:53:28 volumio smbd[1316]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Aug 18 00:53:28 volumio systemd[1]: Started Samba SMB Daemon. Aug 18 00:53:28 volumio systemd[1]: Reached target Multi-User System. Aug 18 00:53:28 volumio systemd[1]: Reached target Graphical Interface. Aug 18 00:53:28 volumio volumio[1190]: info: CoreStateMachine::pushState Aug 18 00:53:28 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Aug 18 00:53:28 volumio volumio[1190]: info: CoreStateMachine::setRepeat false single undefined Aug 18 00:53:28 volumio volumio[1190]: info: CoreStateMachine::pushState Aug 18 00:53:28 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Aug 18 00:53:28 volumio volumio[1190]: info: CoreStateMachine::setRandom false Aug 18 00:53:28 volumio volumio[1190]: info: CoreStateMachine::pushState Aug 18 00:53:28 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Aug 18 00:53:28 volumio volumio[1190]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 18 00:53:28 volumio volumio[1190]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04170 Aug 18 00:53:28 volumio volumio[1190]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 18 00:53:28 volumio volumio[1190]: info: Setting Device type: Raspberry PI Aug 18 00:53:28 volumio volumio[1190]: info: Completed loading Core Plugins Aug 18 00:53:28 volumio volumio[1190]: info: Preparing to generate the ALSA configuration file Aug 18 00:53:28 volumio volumio[1190]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 18 00:53:28 volumio volumio[1190]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Aug 18 00:53:28 volumio volumio[1190]: info: Reading ALSA contributions from plugins. Aug 18 00:53:28 volumio volumio[1190]: info: Discovery: adding e23344dc-d599-4652-9b69-c0380eeec771 Aug 18 00:53:28 volumio volumio[1190]: info: Discovery: Found device Volumio Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Aug 18 00:53:28 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Aug 18 00:53:28 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Aug 18 00:53:28 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Aug 18 00:53:28 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Aug 18 00:53:28 volumio systemd[1]: Startup finished in 10.588s (kernel) + 32.627s (userspace) = 43.215s. Aug 18 00:53:28 volumio volumio[1190]: info: Asound.conf file unchanged, so no further update is needed Aug 18 00:53:28 volumio volumio[1190]: info: Output device has changed, restarting MPD Aug 18 00:53:28 volumio sudo[1337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 18 00:53:28 volumio sudo[1337]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:28 volumio volumio[1190]: info: Output device has changed, restarting Shairport Sync Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 00:53:28 volumio sudo[1337]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:28 volumio sudo[1339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 18 00:53:28 volumio sudo[1339]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:28 volumio volumio[1190]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 18 00:53:28 volumio volumio[1190]: info: ___________ START PLUGINS ___________ Aug 18 00:53:28 volumio systemd[1]: Listening on mpd.socket. Aug 18 00:53:28 volumio volumio[1190]: info: ControllerMpd::onStart: Initializing MPD Aug 18 00:53:28 volumio volumio[1190]: info: Creating MPD Configuration file Aug 18 00:53:28 volumio systemd[1]: Starting Music Player Daemon... Aug 18 00:53:28 volumio sudo[1348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 18 00:53:28 volumio volumio[1190]: info: [1723913608232] CoreMusicLibrary::Adding element Media Servers Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 00:53:28 volumio sudo[1348]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:28 volumio sudo[1348]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 00:53:28 volumio sudo[1350]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 18 00:53:28 volumio sudo[1346]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 18 00:53:28 volumio sudo[1346]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:28 volumio sudo[1350]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:28 volumio sudo[1346]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 18 00:53:28 volumio sudo[1346]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:28 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Aug 18 00:53:28 volumio systemd[1]: mpd.service: Succeeded. Aug 18 00:53:28 volumio systemd[1]: Stopped Music Player Daemon. Aug 18 00:53:28 volumio volumio[1190]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 00:53:28 volumio systemd[1]: Starting Music Player Daemon... Aug 18 00:53:28 volumio volumio[1190]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 18 00:53:28 volumio volumio[1190]: info: [1723913608266] CoreMusicLibrary::Adding element Last_100 Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 18 00:53:28 volumio volumio[1190]: info: [1723913608267] CoreMusicLibrary::Adding element Webradio Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 18 00:53:28 volumio volumio[1190]: info: Initializing BBC Radios Aug 18 00:53:28 volumio sudo[1358]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 18 00:53:28 volumio sudo[1358]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:28 volumio sudo[1358]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 18 00:53:28 volumio sudo[1358]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 00:53:28 volumio volumio[1190]: info: AutoStart - onStart Aug 18 00:53:28 volumio volumio[1190]: info: Loading i18n strings for locale zh_TW Aug 18 00:53:28 volumio volumio[1190]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Aug 18 00:53:28 volumio volumio[1190]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Aug 18 00:53:28 volumio volumio[1190]: info: /tmp/myfifo created Aug 18 00:53:28 volumio volumio[1190]: info: /tmp/myfifosa created Aug 18 00:53:28 volumio sudo[1379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 Aug 18 00:53:28 volumio sudo[1379]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:28 volumio sudo[1379]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:28 volumio volumio[1190]: info: snd-dummy loaded Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 00:53:28 volumio volumio[1190]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 18 00:53:28 volumio volumio[1190]: info: Loading i18n strings for locale zh_TW Aug 18 00:53:28 volumio volumio[1190]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Aug 18 00:53:28 volumio volumio[1190]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 18 00:53:28 volumio volumio[1190]: info: Loading i18n strings for locale zh_TW Aug 18 00:53:28 volumio volumio[1190]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Aug 18 00:53:28 volumio volumio[1190]: error: ir_controller: Fetching language file: Error: i18n file complementing the system language not found. Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 18 00:53:28 volumio volumio[1190]: info: Volumio Calling Home Aug 18 00:53:28 volumio sudo[1401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R a+rwX /etc/lirc/irexec.lircrc /etc/lirc/lirc_options.conf /etc/lirc/lircd.conf /etc/lirc/lircd.conf.d /etc/lirc/lircmd.conf /etc/lirc/lircrc Aug 18 00:53:28 volumio sudo[1401]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:28 volumio sudo[1401]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:28 volumio sudo[1406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Aug 18 00:53:28 volumio sudo[1406]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:28 volumio sudo[1405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Aug 18 00:53:28 volumio sudo[1405]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:28 volumio sudo[1409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Aug 18 00:53:28 volumio sudo[1409]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:28 volumio volumio[1190]: info: touch_display: No backlight interface detected. Aug 18 00:53:28 volumio volumio[1190]: info: ir_controller: File permissions successfully set on /etc/lirc/*. Aug 18 00:53:28 volumio volumio[1190]: info: MPD Permissions set Aug 18 00:53:28 volumio volumio[1190]: info: MPD Permissions set Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Aug 18 00:53:28 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Aug 18 00:53:28 volumio systemd[1]: Reloading. Aug 18 00:53:28 volumio volumio[1190]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:28 volumio volumio[1190]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Aug 18 00:53:28 volumio volumio[1190]: info: Starting Shairport Sync Aug 18 00:53:28 volumio sudo[1435]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Aug 18 00:53:28 volumio sudo[1435]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:28 volumio volumio[1190]: info: Starting Shairport Sync Aug 18 00:53:28 volumio volumio[1190]: info: Starting Shairport Sync Aug 18 00:53:28 volumio sudo[1435]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:28 volumio sudo[1439]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 18 00:53:28 volumio sudo[1439]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:28 volumio sudo[1441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 18 00:53:28 volumio sudo[1441]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:28 volumio sudo[1436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Aug 18 00:53:28 volumio sudo[1436]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:28 volumio sudo[1436]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:28 volumio sudo[1446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 18 00:53:28 volumio sudo[1446]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:28 volumio volumio[1190]: info: Starting Shairport Sync Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Aug 18 00:53:28 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Aug 18 00:53:28 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 18 00:53:28 volumio volumio[1190]: info: ir_controller: LIRC correctly updated. Aug 18 00:53:28 volumio volumio[1190]: info: ir_controller: HAT did not load /proc/device-tree/ir_receiver! Aug 18 00:53:28 volumio sudo[1450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 18 00:53:28 volumio sudo[1450]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:28 volumio volumio[1190]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Aug 18 00:53:28 volumio volumio[1190]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Aug 18 00:53:28 volumio volumio[1190]: info: ir_controller: Raspberry Pi revision code: c04170 Aug 18 00:53:28 volumio sudo[1457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl Aug 18 00:53:28 volumio sudo[1457]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:28 volumio sudo[1457]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:28 volumio sudo[1462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 18 00:53:28 volumio sudo[1462]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 00:53:28 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:28 volumio systemd[1]: serial-getty@ttyAMA10.service: Current command vanished from the unit file, execution of the command list won't be resumed. Aug 18 00:53:28 volumio sudo[1462]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:28 volumio volumio[1190]: info: MPD Permissions set Aug 18 00:53:28 volumio sudo[1469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r -1 Aug 18 00:53:28 volumio sudo[1469]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:28 volumio sudo[1469]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:28 volumio sudo[1476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-ir gpio_pin=26 gpio_pull=up Aug 18 00:53:28 volumio sudo[1476]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:28 volumio kernel: Registered IR keymap rc-rc6-mce Aug 18 00:53:28 volumio kernel: IR RC6 protocol handler initialized Aug 18 00:53:28 volumio sudo[1476]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:28 volumio volumio[1190]: info: ir_controller: Overlay gpio-ir gpio_pin=26 gpio_pull=up loaded. Aug 18 00:53:28 volumio sudo[1409]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:28 volumio systemd[1]: Reloading. Aug 18 00:53:28 volumio volumio[1190]: info: touch_display: systemctl daemon-reload succeeded. Aug 18 00:53:28 volumio kernel: rc rc2: gpio_ir_recv as /devices/platform/ir-receiver@1a/rc/rc2 Aug 18 00:53:28 volumio kernel: rc rc2: lirc_dev: driver gpio_ir_recv registered at minor = 0, raw IR receiver, no transmitter Aug 18 00:53:28 volumio kernel: input: gpio_ir_recv as /devices/platform/ir-receiver@1a/rc/rc2/input5 Aug 18 00:53:28 volumio sudo[1487]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service Aug 18 00:53:28 volumio sudo[1487]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:28 volumio sudo[1488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Aug 18 00:53:28 volumio sudo[1488]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:28 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 18 00:53:28 volumio systemd-udevd[1235]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Aug 18 00:53:28 volumio systemd-logind[793]: Watching system buttons on /dev/input/event4 (gpio_ir_recv) Aug 18 00:53:29 volumio mpd[1369]: Aug 18 00:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 18 00:53:29 volumio sudo[1406]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:29 volumio volumio[1190]: info: touch_display: systemctl disable getty@tty1.service succeeded. Aug 18 00:53:29 volumio systemd[1]: Started Volumio Kiosk. Aug 18 00:53:29 volumio systemd[1]: Starting lircd(8) initialization helper tool... Aug 18 00:53:29 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 18 00:53:29 volumio systemd[1]: Started Music Player Daemon. Aug 18 00:53:29 volumio sudo[1350]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:29 volumio sudo[1339]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:29 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 18 00:53:29 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 18 00:53:29 volumio sudo[1488]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:29 volumio volumio[1190]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Aug 18 00:53:29 volumio volumio[1190]: info: touch_display: Volumio Kiosk started. Aug 18 00:53:29 volumio sudo[1405]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:29 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 18 00:53:29 volumio sudo[1446]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:29 volumio sudo[1439]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:29 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Aug 18 00:53:29 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Aug 18 00:53:29 volumio volumio[1190]: info: Completed starting Core Plugins Aug 18 00:53:29 volumio volumio[1190]: info: ------------------------------------------- Aug 18 00:53:29 volumio volumio[1190]: info: ----- MyVolumio plugins startup ---- Aug 18 00:53:29 volumio volumio[1190]: info: ------------------------------------------- Aug 18 00:53:29 volumio volumio[1190]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 18 00:53:29 volumio sudo[1450]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:29 volumio sudo[1441]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:29 volumio lircd-setup[1512]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. Aug 18 00:53:29 volumio lircd-setup[1512]: parser = configparser.SafeConfigParser() Aug 18 00:53:29 volumio systemd[1]: lircd-setup.service: Succeeded. Aug 18 00:53:29 volumio systemd[1]: Started lircd(8) initialization helper tool. Aug 18 00:53:29 volumio systemd[1]: Starting Flexible IR remote input/output application support... Aug 18 00:53:29 volumio lircd-0.10.1[1541]: Info: lircd: Opening log, level: Info Aug 18 00:53:29 volumio lircd-0.10.1[1541]: Notice: Version: lircd 0.10.1 Aug 18 00:53:29 volumio lircd-0.10.1[1541]: Notice: System info: Linux volumio 6.1.77-v8+ #1730 SMP PREEMPT Thu Feb 8 15:26:11 GMT 2024 aarch64 GNU/Linux Aug 18 00:53:29 volumio volumio[1190]: error: MPD error: The expression evaluated to a falsy value: Aug 18 00:53:29 volumio volumio[1190]: assert.ok(self.idling) Aug 18 00:53:29 volumio volumio[1190]: error: The expression evaluated to a falsy value: Aug 18 00:53:29 volumio volumio[1190]: assert.ok(self.idling) Aug 18 00:53:29 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Aug 18 00:53:29 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Aug 18 00:53:29 volumio volumio[1190]: info: Shairport-Sync Started Aug 18 00:53:29 volumio volumio[1190]: Error adding Membership: Error: addMembership EINVAL Aug 18 00:53:29 volumio volumio[1190]: info: Shairport-Sync Started Aug 18 00:53:29 volumio volumio[1190]: info: MPD running with PID1369 Aug 18 00:53:29 volumio volumio[1190]: ,establishing connection Aug 18 00:53:29 volumio volumio[1190]: info: touch_display: systemctl stop getty@tty1.service succeeded. Aug 18 00:53:29 volumio volumio[1190]: info: Shairport-Sync Started Aug 18 00:53:29 volumio volumio[1190]: info: Shairport-Sync Started Aug 18 00:53:29 volumio volumio[1190]: error: MPD error: The expression evaluated to a falsy value: Aug 18 00:53:29 volumio volumio[1190]: assert.ok(self.idling) Aug 18 00:53:29 volumio volumio[1190]: error: The expression evaluated to a falsy value: Aug 18 00:53:29 volumio volumio[1190]: assert.ok(self.idling) Aug 18 00:53:29 volumio startx[1508]: X.Org X Server 1.20.4 Aug 18 00:53:29 volumio startx[1508]: X Protocol Version 11, Revision 0 Aug 18 00:53:29 volumio volumio[1190]: info: touch_display: X display number found: Aug 18 00:53:29 volumio startx[1508]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Aug 18 00:53:29 volumio startx[1508]: Current Operating System: Linux volumio 6.1.77-v8+ #1730 SMP PREEMPT Thu Feb 8 15:26:11 GMT 2024 aarch64 Aug 18 00:53:29 volumio startx[1508]: Kernel command line: reboot=w coherent_pool=1M 8250.nr_uarts=1 pci=pcie_bus_safe snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 smsc95xx.macaddr=2C:CF:67:38:FC:76 vc_mem.mem_base=0x3fc00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyAMA10,115200 console=tty1 imgpart=UUID=826680f7-a407-4ab9-8bd5-1855bda526fd imgfile=/volumio_current.sqsh bootpart=UUID=FD90-ED9E datapart=UUID=fecb0823-2e5b-4bba-9498-dd37b5084148 uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Aug 18 00:53:29 volumio startx[1508]: Build Date: 04 April 2023 07:50:56AM Aug 18 00:53:29 volumio startx[1508]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Aug 18 00:53:29 volumio startx[1508]: Current version of pixman: 0.36.0 Aug 18 00:53:29 volumio startx[1508]: Before reporting problems, check http://wiki.x.org Aug 18 00:53:29 volumio startx[1508]: to make sure that you have the latest version. Aug 18 00:53:29 volumio startx[1508]: Markers: (--) probed, (**) from config file, (==) default setting, Aug 18 00:53:29 volumio startx[1508]: (++) from command line, (!!) notice, (II) informational, Aug 18 00:53:29 volumio volumio[1190]: error: updateQueue error: null Aug 18 00:53:29 volumio startx[1508]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Aug 18 00:53:29 volumio startx[1508]: (==) Log file: "/var/log/Xorg.0.log", Time: Sun Aug 18 00:53:29 2024 Aug 18 00:53:29 volumio startx[1508]: (==) Using config directory: "/etc/X11/xorg.conf.d" Aug 18 00:53:29 volumio startx[1508]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Aug 18 00:53:29 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Aug 18 00:53:29 volumio lircd-0.10.1[1541]: Info: Initial device: /dev/lirc0 Aug 18 00:53:29 volumio lircd-0.10.1[1541]: Notice: 'lirc' written to protocols file /sys/class/rc/rc2/protocols Aug 18 00:53:29 volumio lircd-0.10.1[1541]: Info: Initial device: /dev/lirc0 Aug 18 00:53:29 volumio lircd-0.10.1[1541]: Info: [lirc] protocol is enabled Aug 18 00:53:29 volumio lircd-0.10.1[1541]: Notice: Options: driver: default Aug 18 00:53:29 volumio lircd-0.10.1[1541]: Notice: Options: output: /var/run/lirc/lircd Aug 18 00:53:29 volumio lircd[1541]: lircd-0.10.1[1541]: Info: lircd: Opening log, level: Info Aug 18 00:53:29 volumio lircd-0.10.1[1541]: Notice: Options: nodaemon: 1 Aug 18 00:53:29 volumio lircd[1541]: lircd-0.10.1[1541]: Notice: Using systemd fd Aug 18 00:53:29 volumio lircd[1541]: lircd-0.10.1[1541]: Warning: Running as root Aug 18 00:53:29 volumio lircd[1541]: lircd-0.10.1[1541]: Info: Using remote: /home/volumio/lircd.conf. Aug 18 00:53:29 volumio lircd-0.10.1[1541]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Aug 18 00:53:29 volumio lircd-0.10.1[1541]: Notice: Options: logfile: syslog Aug 18 00:53:29 volumio lircd-0.10.1[1541]: Notice: Options: immediate-init: 0 Aug 18 00:53:29 volumio lircd-0.10.1[1541]: Notice: Options: permission: 666 Aug 18 00:53:29 volumio lircd[1541]: lircd-0.10.1[1541]: Notice: lircd(default) ready, using /var/run/lirc/lircd Aug 18 00:53:29 volumio lircd-0.10.1[1541]: Notice: Options: driver-options: Aug 18 00:53:29 volumio lircd-0.10.1[1541]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Aug 18 00:53:29 volumio systemd[1]: Started Flexible IR remote input/output application support. Aug 18 00:53:29 volumio lircd-0.10.1[1541]: Notice: Options: listen: 0 Aug 18 00:53:29 volumio lircd-0.10.1[1541]: Notice: Options: connect: (null) Aug 18 00:53:29 volumio lircd-0.10.1[1541]: Notice: Options: userelease: 0 Aug 18 00:53:29 volumio lircd-0.10.1[1541]: Notice: Options: effective_user: (null) Aug 18 00:53:29 volumio lircd-0.10.1[1541]: Notice: Options: release_suffix: _EVUP Aug 18 00:53:29 volumio lircd-0.10.1[1541]: Notice: Options: allow_simulate: 0 Aug 18 00:53:29 volumio lircd-0.10.1[1541]: Notice: Options: repeat_max: 600 Aug 18 00:53:29 volumio lircd-0.10.1[1541]: Notice: Options: configfile: /etc/lirc/lircd.conf Aug 18 00:53:29 volumio lircd-0.10.1[1541]: Notice: Options: dynamic_codes: (null) Aug 18 00:53:29 volumio lircd-0.10.1[1541]: Notice: Current driver: default Aug 18 00:53:29 volumio lircd-0.10.1[1541]: Notice: Driver API version: 3 Aug 18 00:53:29 volumio lircd-0.10.1[1541]: Notice: Driver version: 0.10.0 Aug 18 00:53:29 volumio lircd-0.10.1[1541]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Aug 18 00:53:29 volumio lircd-0.10.1[1541]: Info: lircd: Opening log, level: Info Aug 18 00:53:29 volumio lircd-0.10.1[1541]: Notice: Using systemd fd Aug 18 00:53:29 volumio lircd-0.10.1[1541]: Warning: Running as root Aug 18 00:53:29 volumio lircd-0.10.1[1541]: Info: Using remote: /home/volumio/lircd.conf. Aug 18 00:53:29 volumio lircd-0.10.1[1541]: Notice: lircd(default) ready, using /var/run/lirc/lircd Aug 18 00:53:29 volumio sudo[1487]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:29 volumio volumio[1190]: info: ir_controller: systemctl restart lircd.service succeeded. Aug 18 00:53:29 volumio sudo[1545]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 18 00:53:29 volumio sudo[1545]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:29 volumio systemd[1]: Stopping Music Player Daemon... Aug 18 00:53:29 volumio sudo[1548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service Aug 18 00:53:29 volumio sudo[1548]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:29 volumio systemd[1]: Started Handle events from IR remotes decoded by lircd(8). Aug 18 00:53:29 volumio systemd[1]: mpd.service: Succeeded. Aug 18 00:53:29 volumio systemd[1]: Stopped Music Player Daemon. Aug 18 00:53:29 volumio lircd[1541]: lircd-0.10.1[1541]: Notice: accepted new client on /var/run/lirc/lircd Aug 18 00:53:29 volumio lircd-0.10.1[1541]: Notice: accepted new client on /var/run/lirc/lircd Aug 18 00:53:29 volumio lircd[1541]: lircd-0.10.1[1541]: Info: [lirc] protocol is enabled Aug 18 00:53:29 volumio lircd-0.10.1[1541]: Info: [lirc] protocol is enabled Aug 18 00:53:29 volumio systemd[1]: Starting Music Player Daemon... Aug 18 00:53:29 volumio sudo[1548]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:29 volumio volumio[1190]: info: ir_controller: systemctl restart irexec.service succeeded. Aug 18 00:53:29 volumio sudo[1551]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 18 00:53:29 volumio sudo[1551]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:29 volumio sudo[1551]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:29 volumio volumio[1190]: info: touch_display: X display number found: 0 Aug 18 00:53:29 volumio dhcpcd[1095]: wlan0: using static address 192.168.1.65/24 Aug 18 00:53:29 volumio avahi-daemon[796]: Registering new address record for 192.168.1.65 on wlan0.IPv4. Aug 18 00:53:29 volumio dhcpcd[1095]: wlan0: adding route to 192.168.1.0/24 Aug 18 00:53:29 volumio dhcpcd[1095]: wlan0: adding default route via 192.168.1.1 Aug 18 00:53:29 volumio dnsmasq[1180]: reading /etc/resolv.conf Aug 18 00:53:29 volumio dnsmasq[1180]: using nameserver 192.168.1.1#53 Aug 18 00:53:29 volumio dnsmasq[1180]: using nameserver 208.67.222.222#53 Aug 18 00:53:29 volumio dnsmasq[1180]: using nameserver 208.67.222.222#53 Aug 18 00:53:29 volumio dnsmasq[1180]: using nameserver 208.67.220.220#53 Aug 18 00:53:29 volumio volumio[1190]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Aug 18 00:53:29 volumio volumio[1190]: info: touch_display: X display number found: 0 Aug 18 00:53:29 volumio mpd[1555]: Aug 18 00:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 18 00:53:29 volumio systemd[1]: Started Music Player Daemon. Aug 18 00:53:29 volumio sudo[1545]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:29 volumio volumio[1190]: error: updateQueue error: null Aug 18 00:53:29 volumio sudo[1583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service Aug 18 00:53:29 volumio sudo[1583]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:29 volumio lircd[1541]: lircd-0.10.1[1541]: Notice: caught signal Aug 18 00:53:29 volumio lircd-0.10.1[1541]: Notice: caught signal Aug 18 00:53:29 volumio systemd[1]: Stopping Flexible IR remote input/output application support... Aug 18 00:53:29 volumio systemd[1]: lircd.service: Main process exited, code=killed, status=15/TERM Aug 18 00:53:29 volumio systemd[1]: lircd.service: Succeeded. Aug 18 00:53:29 volumio systemd[1]: Stopped Flexible IR remote input/output application support. Aug 18 00:53:29 volumio systemd[1]: irexec.service: Succeeded. Aug 18 00:53:29 volumio systemd[1]: Starting lircd(8) initialization helper tool... Aug 18 00:53:29 volumio lircd-setup[1585]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. Aug 18 00:53:29 volumio lircd-setup[1585]: parser = configparser.SafeConfigParser() Aug 18 00:53:29 volumio systemd[1]: lircd-setup.service: Succeeded. Aug 18 00:53:29 volumio systemd[1]: Started lircd(8) initialization helper tool. Aug 18 00:53:29 volumio systemd[1]: Starting Flexible IR remote input/output application support... Aug 18 00:53:29 volumio lircd-0.10.1[1587]: Info: lircd: Opening log, level: Info Aug 18 00:53:29 volumio lircd-0.10.1[1587]: Notice: Version: lircd 0.10.1 Aug 18 00:53:29 volumio lircd-0.10.1[1587]: Notice: System info: Linux volumio 6.1.77-v8+ #1730 SMP PREEMPT Thu Feb 8 15:26:11 GMT 2024 aarch64 GNU/Linux Aug 18 00:53:29 volumio lircd-0.10.1[1587]: Info: Initial device: /dev/lirc0 Aug 18 00:53:29 volumio lircd-0.10.1[1587]: Info: [lirc] protocol is enabled Aug 18 00:53:29 volumio lircd-0.10.1[1587]: Info: Initial device: /dev/lirc0 Aug 18 00:53:29 volumio lircd-0.10.1[1587]: Info: [lirc] protocol is enabled Aug 18 00:53:29 volumio lircd-0.10.1[1587]: Notice: Options: driver: default Aug 18 00:53:29 volumio lircd-0.10.1[1587]: Notice: Options: output: /var/run/lirc/lircd Aug 18 00:53:29 volumio lircd[1587]: lircd-0.10.1[1587]: Info: lircd: Opening log, level: Info Aug 18 00:53:29 volumio lircd-0.10.1[1587]: Notice: Options: nodaemon: 1 Aug 18 00:53:29 volumio lircd[1587]: lircd-0.10.1[1587]: Notice: Using systemd fd Aug 18 00:53:29 volumio lircd[1587]: lircd-0.10.1[1587]: Warning: Running as root Aug 18 00:53:29 volumio lircd[1587]: lircd-0.10.1[1587]: Info: Using remote: /home/volumio/lircd.conf. Aug 18 00:53:29 volumio lircd-0.10.1[1587]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Aug 18 00:53:29 volumio lircd-0.10.1[1587]: Notice: Options: logfile: syslog Aug 18 00:53:29 volumio lircd-0.10.1[1587]: Notice: Options: immediate-init: 0 Aug 18 00:53:29 volumio lircd-0.10.1[1587]: Notice: Options: permission: 666 Aug 18 00:53:29 volumio lircd[1587]: lircd-0.10.1[1587]: Notice: lircd(default) ready, using /var/run/lirc/lircd Aug 18 00:53:29 volumio lircd-0.10.1[1587]: Notice: Options: driver-options: Aug 18 00:53:29 volumio lircd-0.10.1[1587]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Aug 18 00:53:29 volumio systemd[1]: Started Flexible IR remote input/output application support. Aug 18 00:53:29 volumio lircd-0.10.1[1587]: Notice: Options: listen: 0 Aug 18 00:53:29 volumio lircd-0.10.1[1587]: Notice: Options: connect: (null) Aug 18 00:53:29 volumio lircd-0.10.1[1587]: Notice: Options: userelease: 0 Aug 18 00:53:29 volumio lircd-0.10.1[1587]: Notice: Options: effective_user: (null) Aug 18 00:53:29 volumio lircd-0.10.1[1587]: Notice: Options: release_suffix: _EVUP Aug 18 00:53:29 volumio lircd-0.10.1[1587]: Notice: Options: allow_simulate: 0 Aug 18 00:53:29 volumio lircd-0.10.1[1587]: Notice: Options: repeat_max: 600 Aug 18 00:53:29 volumio lircd-0.10.1[1587]: Notice: Options: configfile: /etc/lirc/lircd.conf Aug 18 00:53:29 volumio lircd-0.10.1[1587]: Notice: Options: dynamic_codes: (null) Aug 18 00:53:29 volumio lircd-0.10.1[1587]: Notice: Current driver: default Aug 18 00:53:29 volumio lircd-0.10.1[1587]: Notice: Driver API version: 3 Aug 18 00:53:29 volumio lircd-0.10.1[1587]: Notice: Driver version: 0.10.0 Aug 18 00:53:29 volumio lircd-0.10.1[1587]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Aug 18 00:53:29 volumio lircd-0.10.1[1587]: Info: lircd: Opening log, level: Info Aug 18 00:53:29 volumio lircd-0.10.1[1587]: Notice: Using systemd fd Aug 18 00:53:29 volumio lircd-0.10.1[1587]: Warning: Running as root Aug 18 00:53:29 volumio lircd-0.10.1[1587]: Info: Using remote: /home/volumio/lircd.conf. Aug 18 00:53:29 volumio lircd-0.10.1[1587]: Notice: lircd(default) ready, using /var/run/lirc/lircd Aug 18 00:53:29 volumio sudo[1583]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:29 volumio volumio[1190]: info: ir_controller: systemctl restart lircd.service succeeded. Aug 18 00:53:29 volumio sudo[1591]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service Aug 18 00:53:29 volumio sudo[1591]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:29 volumio startx[1508]: V3D 7.1 not supported by this version of Mesa. Aug 18 00:53:29 volumio systemd[1]: Started Handle events from IR remotes decoded by lircd(8). Aug 18 00:53:29 volumio sudo[1591]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:29 volumio lircd[1587]: lircd-0.10.1[1587]: Notice: accepted new client on /var/run/lirc/lircd Aug 18 00:53:29 volumio lircd[1587]: lircd-0.10.1[1587]: Info: [lirc] protocol is enabled Aug 18 00:53:29 volumio lircd-0.10.1[1587]: Notice: accepted new client on /var/run/lirc/lircd Aug 18 00:53:29 volumio lircd-0.10.1[1587]: Info: [lirc] protocol is enabled Aug 18 00:53:29 volumio volumio[1190]: info: ir_controller: systemctl restart irexec.service succeeded. Aug 18 00:53:29 volumio kernel: broken atomic modeset userspace detected, disabling atomic Aug 18 00:53:30 volumio volumio-remote-updater[821]: [2024-08-18 00:53:30] [connect] Successful connection Aug 18 00:53:30 volumio volumio-remote-updater[821]: [2024-08-18 00:53:30] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1723913610 101 Aug 18 00:53:30 volumio volumio[1190]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 4 Aug 18 00:53:30 volumio startx[1508]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. Aug 18 00:53:30 volumio volumio[1190]: info: touch_display: Setting screensaver timeout to 9007199254740991 seconds. Aug 18 00:53:30 volumio ntpd[976]: Listen normally on 4 wlan0 192.168.1.65:123 Aug 18 00:53:30 volumio ntpd[976]: new interface(s) found: waking up resolver Aug 18 00:53:30 volumio kernel: pcieport 0000:00:00.0: AER: Corrected error message received from 0000:00:00.0 Aug 18 00:53:30 volumio kernel: pcieport 0000:00:00.0: PCIe Bus Error: severity=Corrected, type=Data Link Layer, (Receiver ID) Aug 18 00:53:30 volumio kernel: pcieport 0000:00:00.0: device [14e4:2712] error status/mask=00000040/00002000 Aug 18 00:53:30 volumio kernel: pcieport 0000:00:00.0: [ 6] BadTLP Aug 18 00:53:31 volumio kernel: pcieport 0000:00:00.0: AER: Corrected error message received from 0000:00:00.0 Aug 18 00:53:31 volumio kernel: pcieport 0000:00:00.0: PCIe Bus Error: severity=Corrected, type=Data Link Layer, (Receiver ID) Aug 18 00:53:31 volumio kernel: pcieport 0000:00:00.0: device [14e4:2712] error status/mask=00000040/00002000 Aug 18 00:53:31 volumio kernel: pcieport 0000:00:00.0: [ 6] BadTLP Aug 18 00:53:31 volumio sudo[1796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.1.104:/volume1/music /mnt/NAS/music Aug 18 00:53:31 volumio sudo[1796]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:32 volumio volumio[1190]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Aug 18 00:53:32 volumio volumio[1190]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Aug 18 00:53:32 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 18 00:53:32 volumio volumio[1190]: info: CoreCommandRouter::volumioGetVisibleSources Aug 18 00:53:32 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 00:53:32 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Aug 18 00:53:32 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Aug 18 00:53:32 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 18 00:53:32 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 18 00:53:32 volumio volumio[1190]: info: Received Get System Info Aug 18 00:53:32 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 00:53:32 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 00:53:32 volumio volumio[1190]: info: Discovery: Getting this device information Aug 18 00:53:32 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Aug 18 00:53:32 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Aug 18 00:53:32 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 00:53:32 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Aug 18 00:53:32 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Aug 18 00:53:32 volumio volumio[1190]: info: Listing playlists Aug 18 00:53:32 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 18 00:53:32 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 18 00:53:34 volumio volumio[1190]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 18 00:53:34 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 18 00:53:35 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 18 00:53:35 volumio volumio[1190]: info: Received Get System Info Aug 18 00:53:35 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 00:53:35 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 00:53:35 volumio volumio[1190]: info: Discovery: Getting this device information Aug 18 00:53:35 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Aug 18 00:53:35 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Aug 18 00:53:35 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 00:53:35 volumio sudo[1804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 18 00:53:35 volumio sudo[1804]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:35 volumio sudo[1804]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:35 volumio sudo[1807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 18 00:53:35 volumio sudo[1807]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:35 volumio sudo[1807]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:35 volumio sudo[1810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 18 00:53:35 volumio sudo[1810]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:35 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Aug 18 00:53:35 volumio sudo[1810]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:35 volumio volumio[1190]: info: Upmpdcli Daemon Started Aug 18 00:53:35 volumio volumio[1812]: Generating RSA private key, 4096 bit long modulus (2 primes) Aug 18 00:53:38 volumio volumio[1190]: info: AutoStart - Plugin is starting Aug 18 00:53:38 volumio volumio[1190]: info: CoreCommandRouter::volumioGetQueue Aug 18 00:53:38 volumio volumio[1190]: info: CoreStateMachine::getQueue Aug 18 00:53:38 volumio volumio[1190]: info: CorePlayQueue::getQueue Aug 18 00:53:38 volumio volumio[1190]: info: AutoStart - start playing Aug 18 00:53:38 volumio volumio[1190]: info: AutoStart - start playing with no specific position Aug 18 00:53:38 volumio volumio[1190]: info: CoreCommandRouter::volumioPlay Aug 18 00:53:38 volumio volumio[1190]: info: CoreStateMachine::play index 0 Aug 18 00:53:38 volumio volumio[1190]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 18 00:53:38 volumio volumio[1190]: info: CoreStateMachine::stop Aug 18 00:53:38 volumio volumio[1190]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 18 00:53:38 volumio volumio[1190]: info: CoreStateMachine::play index undefined Aug 18 00:53:38 volumio volumio[1190]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 18 00:53:38 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Aug 18 00:53:38 volumio volumio[1190]: info: CoreStateMachine::startPlaybackTimer Aug 18 00:53:38 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Aug 18 00:53:38 volumio volumio[1190]: verbose: ControllerMpd::clearAddPlayTracks NAS/music/【MQA】2020 冯允谦《Awaken》24-48/01. 冯允谦 - 开始倒数.flac Aug 18 00:53:38 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand stop Aug 18 00:53:38 volumio volumio[1190]: info: sendMpdCommand stop took 0 milliseconds Aug 18 00:53:38 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand clear Aug 18 00:53:38 volumio volumio[1190]: info: Aug 18 00:53:38 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Aug 18 00:53:38 volumio volumio[1190]: info: Ignoring MPD Status Update Aug 18 00:53:38 volumio volumio[1190]: info: sendMpdCommand clear took 0 milliseconds Aug 18 00:53:38 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand add "NAS/music/【MQA】2020 冯允谦《Awaken》24-48/01. 冯允谦 - 开始倒数.flac" Aug 18 00:53:38 volumio volumio[1190]: error: updateQueue error: null Aug 18 00:53:38 volumio volumio[1190]: info: Aug 18 00:53:38 volumio volumio[1190]: ---------------------------- MPD announces system playlist update Aug 18 00:53:38 volumio volumio[1190]: info: Ignoring MPD Status Update Aug 18 00:53:38 volumio volumio[1190]: info: ------------------------------ 1ms Aug 18 00:53:38 volumio volumio[1190]: info: sendMpdCommand add "NAS/music/【MQA】2020 冯允谦《Awaken》24-48/01. 冯允谦 - 开始倒数.flac" took 1 milliseconds Aug 18 00:53:38 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand play Aug 18 00:53:38 volumio volumio[1190]: info: ------------------------------ 0ms Aug 18 00:53:38 volumio volumio[1190]: info: sendMpdCommand play took 0 milliseconds Aug 18 00:53:38 volumio volumio[1190]: info: Aug 18 00:53:38 volumio volumio[1190]: ---------------------------- MPD announces state update: player Aug 18 00:53:38 volumio volumio[1190]: info: ControllerMpd::getState Aug 18 00:53:38 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand status Aug 18 00:53:38 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 18 00:53:38 volumio volumio[1190]: info: sendMpdCommand status took 2 milliseconds Aug 18 00:53:38 volumio volumio[1190]: verbose: ControllerMpd::parseState Aug 18 00:53:38 volumio volumio[1190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 18 00:53:38 volumio volumio[1190]: info: sendMpdCommand clearerror took 2 milliseconds Aug 18 00:53:38 volumio volumio[1190]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 18 00:53:38 volumio volumio[1190]: verbose: ControllerMpd::parseTrackInfo Aug 18 00:53:38 volumio volumio[1190]: info: ControllerMpd::pushState Aug 18 00:53:38 volumio volumio[1190]: info: CoreCommandRouter::servicePushState Aug 18 00:53:38 volumio volumio[1190]: info: CoreStateMachine::pushState Aug 18 00:53:38 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Aug 18 00:53:38 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Aug 18 00:53:38 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Aug 18 00:53:38 volumio volumio[1190]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"開始倒數","artist":"冯允谦","album":"awaken","uri":"NAS/music/【MQA】2020 冯允谦《Awaken》24-48/01. 冯允谦 - 开始倒数.flac","trackType":"flac"} Aug 18 00:53:38 volumio volumio[1190]: verbose: CURRENT POSITION 0 Aug 18 00:53:38 volumio volumio[1190]: info: CoreStateMachine::syncState stateService stop Aug 18 00:53:38 volumio volumio[1190]: info: CoreStateMachine::syncState currentStatus stop Aug 18 00:53:38 volumio volumio[1190]: info: CoreStateMachine::pushState Aug 18 00:53:38 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Aug 18 00:53:38 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Aug 18 00:53:38 volumio volumio[1190]: info: No code Aug 18 00:53:38 volumio volumio[1190]: info: CoreStateMachine::pushState Aug 18 00:53:38 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Aug 18 00:53:38 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Aug 18 00:53:38 volumio volumio[1190]: info: ------------------------------ 15ms Aug 18 00:53:38 volumio volumio[1190]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.703&uuid=be53557408334f035edd245bb021902f" http://updates.volumio.org/downloader-v1/track-device Aug 18 00:53:38 volumio volumio[1190]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 18 00:53:38 volumio volumio[1190]: Dload Upload Total Spent Left Speed Aug 18 00:53:38 volumio volumio[1190]: [132B blob data] Aug 18 00:53:38 volumio volumio[1190]: retrying in 5 seconds, trial 0 Aug 18 00:53:38 volumio volumio[1190]: info: Volumio Calling Home Aug 18 00:53:42 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 18 00:53:43 volumio volumio[1812]: ........................................................................................................................................................++++ Aug 18 00:53:45 volumio volumio[1812]: .......................................++++ Aug 18 00:53:45 volumio volumio[1812]: e is 65537 (0x010001) Aug 18 00:53:45 volumio volumio[1812]: writing RSA key Aug 18 00:53:49 volumio volumio[1190]: error: MyVolumio Plugin failed to start in a timely fashion Aug 18 00:53:49 volumio volumio[1190]: info: BOOT COMPLETED Aug 18 00:53:49 volumio volumio[1190]: [Metrics] CommandRouter: 24s 159.73ms Aug 18 00:53:49 volumio volumio[1190]: info: CoreCommandRouter::volumiosetStartupVolume Aug 18 00:53:49 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 18 00:53:49 volumio volumio[1190]: info: VolumeController:: Setting startup Volume 60 Aug 18 00:53:49 volumio volumio[1190]: info: VolumeController::SetAlsaVolume60 Aug 18 00:53:49 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 00:53:49 volumio volumio[1190]: info: CoreCommandRouter::Close All Modals sent Aug 18 00:53:49 volumio volumio[1190]: info: CoreCommandRouter::Close All Modals sent Aug 18 00:53:49 volumio volumio[1190]: info: CoreStateMachine::pushState Aug 18 00:53:49 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Aug 18 00:53:49 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 18 00:53:49 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000020] -121 Aug 18 00:53:49 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 18 00:53:49 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 18 00:53:49 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState Aug 18 00:53:49 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 18 00:53:50 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 18 00:53:50 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 18 00:53:50 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 18 00:53:53 volumio hostapd[1187]: wlan0: STA 22:f4:15:61:03:be IEEE 802.11: associated Aug 18 00:53:53 volumio hostapd[1187]: wlan0: STA 22:f4:15:61:03:be RADIUS: starting accounting session B813550D67E7E0DE Aug 18 00:53:54 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Aug 18 00:53:57 volumio dnsmasq-dhcp[1180]: DHCPDISCOVER(wlan0) 22:f4:15:61:03:be Aug 18 00:53:57 volumio dnsmasq-dhcp[1180]: DHCPOFFER(wlan0) 192.168.211.111 22:f4:15:61:03:be Aug 18 00:53:57 volumio dnsmasq-dhcp[1180]: DHCPDISCOVER(wlan0) 22:f4:15:61:03:be Aug 18 00:53:57 volumio dnsmasq-dhcp[1180]: DHCPOFFER(wlan0) 192.168.211.111 22:f4:15:61:03:be Aug 18 00:53:57 volumio dnsmasq-dhcp[1180]: DHCPDISCOVER(wlan0) 22:f4:15:61:03:be Aug 18 00:53:57 volumio dnsmasq-dhcp[1180]: DHCPOFFER(wlan0) 192.168.211.111 22:f4:15:61:03:be Aug 18 00:53:57 volumio dnsmasq-dhcp[1180]: DHCPREQUEST(wlan0) 192.168.211.111 22:f4:15:61:03:be Aug 18 00:53:57 volumio dnsmasq-dhcp[1180]: DHCPACK(wlan0) 192.168.211.111 22:f4:15:61:03:be vivo-X-Fold3 Aug 18 00:53:58 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 18 00:53:58 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 18 00:53:58 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 18 00:53:59 volumio sudo[1933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 18 00:53:59 volumio sudo[1933]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:59 volumio sudo[1933]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:59 volumio sudo[1935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 18 00:53:59 volumio sudo[1935]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:53:59 volumio sudo[1935]: pam_unix(sudo:session): session closed for user root Aug 18 00:53:59 volumio volumio[1190]: verbose: New Socket.io Connection to 192.168.211.1 from 192.168.211.111 UA: Mozilla/5.0 (Linux; Android 16; V2303A Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.177 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Aug 18 00:54:00 volumio sudo[1939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 18 00:54:00 volumio sudo[1939]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:00 volumio sudo[1939]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:00 volumio sudo[1941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 18 00:54:00 volumio sudo[1941]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:00 volumio sudo[1941]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:00 volumio volumio[1190]: verbose: New Socket.io Connection to 192.168.211.1 from 192.168.211.111 UA: Mozilla/5.0 (Linux; Android 16; V2303A Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.177 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Aug 18 00:54:00 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 18 00:54:00 volumio volumio[1190]: info: CoreCommandRouter::volumioGetVisibleSources Aug 18 00:54:00 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 00:54:00 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Aug 18 00:54:00 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Aug 18 00:54:00 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 18 00:54:00 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 18 00:54:00 volumio volumio[1190]: info: Received Get System Info Aug 18 00:54:00 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 00:54:00 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 00:54:00 volumio volumio[1190]: info: Discovery: Getting this device information Aug 18 00:54:00 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Aug 18 00:54:00 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Aug 18 00:54:00 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 00:54:00 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Aug 18 00:54:00 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Aug 18 00:54:00 volumio volumio[1190]: info: Listing playlists Aug 18 00:54:00 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 18 00:54:00 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 18 00:54:01 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 18 00:54:02 volumio kernel: pcieport 0000:00:00.0: AER: Corrected error message received from 0000:00:00.0 Aug 18 00:54:02 volumio kernel: pcieport 0000:00:00.0: PCIe Bus Error: severity=Corrected, type=Data Link Layer, (Receiver ID) Aug 18 00:54:02 volumio kernel: pcieport 0000:00:00.0: device [14e4:2712] error status/mask=00000040/00002000 Aug 18 00:54:02 volumio kernel: pcieport 0000:00:00.0: [ 6] BadTLP Aug 18 00:54:02 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 18 00:54:02 volumio volumio[1190]: info: Received Get System Info Aug 18 00:54:02 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 00:54:02 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 00:54:02 volumio volumio[1190]: info: Discovery: Getting this device information Aug 18 00:54:02 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Aug 18 00:54:02 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Aug 18 00:54:02 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 00:54:05 volumio volumio[1190]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 18 00:54:05 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 18 00:54:05 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Aug 18 00:54:05 volumio sudo[1947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Aug 18 00:54:05 volumio sudo[1947]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:05 volumio sudo[1947]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:05 volumio sudo[1952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 18 00:54:05 volumio sudo[1952]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:05 volumio sudo[1952]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:05 volumio sudo[1960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 18 00:54:05 volumio sudo[1960]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:05 volumio sudo[1960]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:05 volumio sudo[1966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 18 00:54:05 volumio sudo[1966]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:05 volumio sudo[1966]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:05 volumio sudo[1973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 18 00:54:05 volumio sudo[1973]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:05 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Aug 18 00:54:05 volumio sudo[1973]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:05 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Aug 18 00:54:05 volumio sudo[1975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 18 00:54:05 volumio sudo[1975]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:05 volumio sudo[1975]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:05 volumio sudo[1979]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 18 00:54:05 volumio sudo[1979]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:07 volumio sudo[1979]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:10 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 18 00:54:16 volumio volumio[1190]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.703&uuid=be53557408334f035edd245bb021902f" http://updates.volumio.org/downloader-v1/track-device Aug 18 00:54:16 volumio volumio[1190]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 18 00:54:16 volumio volumio[1190]: Dload Upload Total Spent Left Speed Aug 18 00:54:16 volumio volumio[1190]: [2.2K blob data] Aug 18 00:54:16 volumio volumio[1190]: retrying in 5 seconds, trial 1 Aug 18 00:54:16 volumio volumio[1190]: info: Volumio Calling Home Aug 18 00:54:25 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Aug 18 00:54:25 volumio volumio[1190]: info: Saving new wireless network Aug 18 00:54:25 volumio sudo[2172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Aug 18 00:54:25 volumio sudo[2172]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:25 volumio sudo[2172]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:25 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Aug 18 00:54:25 volumio volumio[1190]: info: Discovery: Restarting Advertising due to device name change Aug 18 00:54:25 volumio sudo[2175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Aug 18 00:54:25 volumio sudo[2175]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:25 volumio dhcpcd[1095]: received SIGTERM, stopping Aug 18 00:54:25 volumio dhcpcd[1095]: wlan0: removing interface Aug 18 00:54:25 volumio systemd[1]: Stopping Wireless Services... Aug 18 00:54:25 volumio avahi-daemon[796]: Withdrawing address record for 192.168.1.65 on wlan0. Aug 18 00:54:25 volumio dhcpcd[1095]: wlan0: deleting route to 192.168.1.0/24 Aug 18 00:54:25 volumio dhcpcd[1095]: wlan0: deleting default route via 192.168.1.1 Aug 18 00:54:25 volumio dnsmasq[1180]: reading /etc/resolv.conf Aug 18 00:54:25 volumio dnsmasq[1180]: using nameserver 208.67.222.222#53 Aug 18 00:54:25 volumio dnsmasq[1180]: using nameserver 208.67.220.220#53 Aug 18 00:54:25 volumio dhcpcd[1095]: eth0: removing interface Aug 18 00:54:25 volumio dhcpcd[1095]: dhcpcd exited Aug 18 00:54:25 volumio systemd[1]: wireless.service: Succeeded. Aug 18 00:54:25 volumio systemd[1]: Stopped Wireless Services. Aug 18 00:54:25 volumio systemd[1]: Starting Wireless Services... Aug 18 00:54:25 volumio wireless.js[2192]: Cleaning previous... Aug 18 00:54:25 volumio systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Aug 18 00:54:26 volumio systemd[1]: hostapd.service: Succeeded. Aug 18 00:54:26 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Aug 18 00:54:26 volumio sudo[2206]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 18 00:54:26 volumio sudo[2206]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:26 volumio avahi-daemon[796]: Withdrawing address record for 192.168.211.1 on wlan0. Aug 18 00:54:26 volumio avahi-daemon[796]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Aug 18 00:54:26 volumio avahi-daemon[796]: Interface wlan0.IPv4 no longer relevant for mDNS. Aug 18 00:54:26 volumio sudo[2206]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:26 volumio volumio[1190]: info: Discovery: A device disappeared from network Aug 18 00:54:26 volumio volumio[1190]: info: Discovery: Device volumio disappeared from network Aug 18 00:54:26 volumio sudo[2208]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Aug 18 00:54:26 volumio sudo[2208]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:26 volumio sudo[2208]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:26 volumio sudo[1796]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:26 volumio wireless.js[2192]: Stopped aP Aug 18 00:54:26 volumio sudo[2215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Aug 18 00:54:26 volumio sudo[2215]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:26 volumio sudo[2215]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:26 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 18 00:54:26 volumio sudo[2217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Aug 18 00:54:26 volumio sudo[2217]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:27 volumio sudo[2217]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:27 volumio sudo[2224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Aug 18 00:54:27 volumio sudo[2224]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:27 volumio sudo[2224]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:27 volumio sudo[2226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Aug 18 00:54:27 volumio sudo[2226]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:27 volumio sudo[2226]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:27 volumio wireless.js[2192]: SETTING APPROPRIATE REG DOMAIN: CN Aug 18 00:54:27 volumio sudo[2231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Aug 18 00:54:27 volumio sudo[2231]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:27 volumio sudo[2231]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:27 volumio sudo[2233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set CN Aug 18 00:54:27 volumio sudo[2233]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:27 volumio sudo[2233]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:27 volumio wireless.js[2192]: SUCCESSFULLY SET NEW REGDOMAIN: CN Aug 18 00:54:27 volumio wireless.js[2192]: Start wireless flow Aug 18 00:54:27 volumio wireless.js[2192]: Stopped hotspot (if there).. Aug 18 00:54:27 volumio sudo[2238]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 18 00:54:27 volumio sudo[2238]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:27 volumio sudo[2238]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:28 volumio sudo[2240]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Aug 18 00:54:28 volumio sudo[2240]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:28 volumio sudo[2240]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:28 volumio wireless.js[2192]: DHCP IP Aug 18 00:54:28 volumio wireless.js[2192]: Start ap Aug 18 00:54:28 volumio wpa_supplicant[2242]: Successfully initialized wpa_supplicant Aug 18 00:54:28 volumio sudo[2243]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Aug 18 00:54:28 volumio sudo[2243]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:28 volumio dhcpcd[2244]: dev: loaded udev Aug 18 00:54:28 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 18 00:54:28 volumio dhcpcd[2244]: wlan0: connected to Access Point `' Aug 18 00:54:28 volumio dhcpcd[2244]: no interfaces have a carrier Aug 18 00:54:28 volumio dhcpcd[2244]: forked to background, child pid 2275 Aug 18 00:54:28 volumio dhcpcd[2275]: eth0: waiting for carrier Aug 18 00:54:28 volumio dhcpcd[2275]: wlan0: waiting for carrier Aug 18 00:54:28 volumio sudo[2243]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:29 volumio wireless.js[2192]: trying... Aug 18 00:54:29 volumio sudo[2277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 18 00:54:29 volumio sudo[2277]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:29 volumio sudo[2277]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:29 volumio wpa_supplicant[2264]: wlan0: Trying to associate with SSID 'ME Home (5GHz)' Aug 18 00:54:30 volumio wireless.js[2192]: trying... Aug 18 00:54:30 volumio sudo[2280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 18 00:54:30 volumio sudo[2280]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:30 volumio sudo[2280]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:30 volumio wpa_supplicant[2264]: wlan0: Associated with 50:88:11:eb:c5:97 Aug 18 00:54:30 volumio wpa_supplicant[2264]: wlan0: CTRL-EVENT-CONNECTED - Connection to 50:88:11:eb:c5:97 completed [id=0 id_str=] Aug 18 00:54:30 volumio dhcpcd[2275]: wlan0: carrier acquired Aug 18 00:54:30 volumio wpa_supplicant[2264]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 18 00:54:30 volumio dhcpcd[2275]: wlan0: connected to Access Point `ME Home (5GHz)' Aug 18 00:54:30 volumio dhcpcd[2275]: DUID 00:01:00:01:2d:f2:1d:98:2c:cf:67:11:b0:20 Aug 18 00:54:30 volumio dhcpcd[2275]: wlan0: IAID 67:38:fc:77 Aug 18 00:54:30 volumio dhcpcd[2275]: wlan0: adding address fe80::2ecf:67ff:fe38:fc77 Aug 18 00:54:30 volumio dhcpcd[2275]: ipv6_addaddr1: Permission denied Aug 18 00:54:30 volumio dhcpcd[2275]: wlan0: probing address 192.168.1.65/24 Aug 18 00:54:30 volumio ntpd[976]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Aug 18 00:54:30 volumio ntpd[976]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Aug 18 00:54:30 volumio ntpd[976]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Aug 18 00:54:30 volumio ntpd[976]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Aug 18 00:54:30 volumio volumio[1190]: info: Discovery: Started advertising with name: Volumio Aug 18 00:54:30 volumio wpa_supplicant[2264]: RRM: Ignoring radio measurement request: Not RRM network Aug 18 00:54:31 volumio dhcpcd[2275]: wlan0: soliciting an IPv6 router Aug 18 00:54:31 volumio wireless.js[2192]: trying... Aug 18 00:54:31 volumio sudo[2285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 18 00:54:31 volumio sudo[2285]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:31 volumio sudo[2285]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:31 volumio ntpd[976]: Deleting interface #3 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=66 secs Aug 18 00:54:31 volumio ntpd[976]: Deleting interface #4 wlan0, 192.168.1.65#123, interface stats: received=0, sent=0, dropped=0, active_time=61 secs Aug 18 00:54:32 volumio wireless.js[2192]: trying... Aug 18 00:54:32 volumio sudo[2288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 18 00:54:32 volumio sudo[2288]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:32 volumio sudo[2288]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:33 volumio wireless.js[2192]: trying... Aug 18 00:54:33 volumio sudo[2291]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 18 00:54:33 volumio sudo[2291]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:33 volumio sudo[2291]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:34 volumio wireless.js[2192]: trying... Aug 18 00:54:34 volumio sudo[2294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 18 00:54:34 volumio sudo[2294]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:34 volumio sudo[2294]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:35 volumio volumio[1190]: info: Cannot mount NAS music at system boot, trial number 2 ,retrying in 5 seconds Aug 18 00:54:35 volumio wireless.js[2192]: trying... Aug 18 00:54:35 volumio sudo[2300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 18 00:54:35 volumio sudo[2300]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:35 volumio sudo[2300]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:35 volumio dhcpcd[2275]: wlan0: using static address 192.168.1.65/24 Aug 18 00:54:35 volumio avahi-daemon[796]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.65. Aug 18 00:54:35 volumio avahi-daemon[796]: New relevant interface wlan0.IPv4 for mDNS. Aug 18 00:54:35 volumio dhcpcd[2275]: wlan0: adding route to 192.168.1.0/24 Aug 18 00:54:35 volumio avahi-daemon[796]: Registering new address record for 192.168.1.65 on wlan0.IPv4. Aug 18 00:54:35 volumio dhcpcd[2275]: wlan0: adding default route via 192.168.1.1 Aug 18 00:54:35 volumio dnsmasq[1180]: reading /etc/resolv.conf Aug 18 00:54:35 volumio dnsmasq[1180]: using nameserver 192.168.1.1#53 Aug 18 00:54:35 volumio dnsmasq[1180]: using nameserver 208.67.222.222#53 Aug 18 00:54:35 volumio dnsmasq[1180]: using nameserver 208.67.222.222#53 Aug 18 00:54:35 volumio dnsmasq[1180]: using nameserver 208.67.220.220#53 Aug 18 00:54:35 volumio wpa_supplicant[2264]: RRM: Ignoring radio measurement request: Not RRM network Aug 18 00:54:36 volumio wireless.js[2192]: trying... Aug 18 00:54:36 volumio sudo[2325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 18 00:54:36 volumio sudo[2325]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:36 volumio sudo[2325]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:36 volumio wireless.js[2192]: Connected to: ----ME Home (5GHz) Aug 18 00:54:36 volumio wireless.js[2192]: ---- Aug 18 00:54:36 volumio sudo[2328]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 18 00:54:36 volumio sudo[2328]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:36 volumio sudo[2328]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:36 volumio wireless.js[2192]: ... joined AP, wlan0 IPv4 is 192.168.1.65, ipV6 is undefined Aug 18 00:54:36 volumio wireless.js[2192]: It's done! AP Aug 18 00:54:36 volumio volumio[1190]: info: Discovery: adding e23344dc-d599-4652-9b69-c0380eeec771 Aug 18 00:54:36 volumio volumio[1190]: info: Discovery: Found device Volumio Aug 18 00:54:36 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Aug 18 00:54:36 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Aug 18 00:54:36 volumio systemd[1]: Started Wireless Services. Aug 18 00:54:36 volumio sudo[2175]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:36 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 18 00:54:36 volumio ntpd[976]: Listen normally on 5 wlan0 192.168.1.65:123 Aug 18 00:54:36 volumio ntpd[976]: new interface(s) found: waking up resolver Aug 18 00:54:37 volumio volumio[1190]: verbose: New Socket.io Connection to 192.168.1.65:3000 from 192.168.1.82 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Aug 18 00:54:37 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 00:54:37 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 00:54:37 volumio volumio[1190]: info: Discovery: Getting this device information Aug 18 00:54:37 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Aug 18 00:54:37 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Aug 18 00:54:37 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 00:54:37 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 00:54:37 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 00:54:37 volumio volumio[1190]: info: Discovery: Getting this device information Aug 18 00:54:37 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Aug 18 00:54:37 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Aug 18 00:54:37 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 00:54:37 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 00:54:37 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 00:54:37 volumio volumio[1190]: info: Discovery: Getting this device information Aug 18 00:54:37 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Aug 18 00:54:37 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Aug 18 00:54:37 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 00:54:37 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 18 00:54:37 volumio volumio[1190]: verbose: New Socket.io Connection to 192.168.1.65:3000 from 192.168.1.82 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Aug 18 00:54:37 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Aug 18 00:54:37 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 18 00:54:37 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 18 00:54:37 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Aug 18 00:54:37 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Aug 18 00:54:37 volumio sudo[2334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Aug 18 00:54:37 volumio sudo[2334]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:37 volumio sudo[2334]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:37 volumio sudo[2339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 18 00:54:37 volumio sudo[2339]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:37 volumio sudo[2339]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:37 volumio sudo[2348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 18 00:54:37 volumio sudo[2348]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:37 volumio sudo[2348]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:37 volumio sudo[2353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 18 00:54:37 volumio sudo[2353]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:37 volumio sudo[2359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 18 00:54:37 volumio sudo[2359]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:37 volumio sudo[2353]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:37 volumio sudo[2359]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:37 volumio sudo[2362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 18 00:54:37 volumio sudo[2362]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:37 volumio sudo[2362]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:37 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 18 00:54:37 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 18 00:54:37 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 00:54:37 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 00:54:37 volumio volumio[1190]: info: Discovery: Getting this device information Aug 18 00:54:37 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Aug 18 00:54:37 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Aug 18 00:54:37 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 00:54:37 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 00:54:37 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 00:54:37 volumio volumio[1190]: info: Discovery: Getting this device information Aug 18 00:54:37 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Aug 18 00:54:37 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Aug 18 00:54:37 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 00:54:37 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Aug 18 00:54:37 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Aug 18 00:54:37 volumio volumio[1190]: verbose: New Socket.io Connection to 192.168.1.65:3000 from 192.168.1.82 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Aug 18 00:54:37 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 18 00:54:37 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 18 00:54:39 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 18 00:54:39 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 18 00:54:40 volumio sudo[2371]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 18 00:54:40 volumio sudo[2371]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:40 volumio sudo[2371]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:40 volumio sudo[2373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 18 00:54:40 volumio sudo[2373]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:40 volumio sudo[2373]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:40 volumio volumio[1190]: verbose: New Socket.io Connection to 192.168.1.65 from 192.168.1.82 UA: Mozilla/5.0 (Linux; Android 16; V2303A Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.177 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Aug 18 00:54:40 volumio sudo[2377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.1.104:/volume1/music /mnt/NAS/music Aug 18 00:54:40 volumio sudo[2377]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:40 volumio systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly. Aug 18 00:54:40 volumio systemd[1]: Starting Preprocess NFS configuration... Aug 18 00:54:40 volumio systemd[1]: nfs-config.service: Succeeded. Aug 18 00:54:40 volumio systemd[1]: Started Preprocess NFS configuration. Aug 18 00:54:40 volumio systemd[1]: Starting NFS status monitor for NFSv2/3 locking.... Aug 18 00:54:40 volumio systemd[1]: Starting Notify NFS peers of a restart... Aug 18 00:54:40 volumio sm-notify[2389]: Version 1.3.3 starting Aug 18 00:54:40 volumio systemd[1]: rpc-statd-notify.service: Succeeded. Aug 18 00:54:40 volumio systemd[1]: Started Notify NFS peers of a restart. Aug 18 00:54:40 volumio rpc.statd[2390]: Version 1.3.3 starting Aug 18 00:54:40 volumio rpc.statd[2390]: Flags: TI-RPC Aug 18 00:54:40 volumio systemd[1]: Started NFS status monitor for NFSv2/3 locking.. Aug 18 00:54:40 volumio sudo[2392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 18 00:54:40 volumio sudo[2392]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:40 volumio sudo[2392]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:40 volumio sudo[2394]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 18 00:54:40 volumio sudo[2394]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:40 volumio sudo[2394]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:40 volumio sudo[2377]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:40 volumio volumio[1190]: verbose: New Socket.io Connection to 192.168.1.65 from 192.168.1.82 UA: Mozilla/5.0 (Linux; Android 16; V2303A Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.177 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Aug 18 00:54:40 volumio volumio[1190]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.703&uuid=be53557408334f035edd245bb021902f" http://updates.volumio.org/downloader-v1/track-device Aug 18 00:54:40 volumio volumio[1190]: % Total % Received % Xferd Average Speed Time Time Time Current Aug 18 00:54:40 volumio volumio[1190]: Dload Upload Total Spent Left Speed Aug 18 00:54:40 volumio volumio[1190]: [1.2K blob data] Aug 18 00:54:40 volumio volumio[1190]: retrying in 5 seconds, trial 2 Aug 18 00:54:40 volumio volumio[1190]: info: Volumio Calling Home Aug 18 00:54:40 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 18 00:54:40 volumio volumio[1190]: info: CoreCommandRouter::volumioGetVisibleSources Aug 18 00:54:40 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 18 00:54:40 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Aug 18 00:54:40 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Aug 18 00:54:40 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 18 00:54:40 volumio volumio[1190]: info: CoreCommandRouter::volumioGetQueue Aug 18 00:54:40 volumio volumio[1190]: info: CoreStateMachine::getQueue Aug 18 00:54:40 volumio volumio[1190]: info: CorePlayQueue::getQueue Aug 18 00:54:40 volumio volumio[1190]: info: Listing playlists Aug 18 00:54:40 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 18 00:54:40 volumio volumio[1190]: info: Received Get System Info Aug 18 00:54:40 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 00:54:40 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 00:54:40 volumio volumio[1190]: info: Discovery: Getting this device information Aug 18 00:54:40 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Aug 18 00:54:40 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Aug 18 00:54:40 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 00:54:40 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Aug 18 00:54:40 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Aug 18 00:54:40 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 18 00:54:40 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 18 00:54:40 volumio wpa_supplicant[2264]: RRM: Ignoring radio measurement request: Not RRM network Aug 18 00:54:41 volumio volumio[1190]: info: Volumio called home Aug 18 00:54:41 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Aug 18 00:54:41 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Aug 18 00:54:41 volumio sudo[2403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 18 00:54:41 volumio sudo[2403]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:41 volumio sudo[2403]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:41 volumio sudo[2406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 18 00:54:41 volumio sudo[2406]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:41 volumio sudo[2406]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:42 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 18 00:54:42 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 18 00:54:42 volumio volumio[1190]: info: Received Get System Info Aug 18 00:54:42 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 00:54:42 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 00:54:42 volumio volumio[1190]: info: Discovery: Getting this device information Aug 18 00:54:42 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Aug 18 00:54:42 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Aug 18 00:54:42 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 00:54:43 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Aug 18 00:54:43 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 18 00:54:43 volumio volumio[1190]: info: Received Get System Info Aug 18 00:54:43 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 18 00:54:43 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 18 00:54:43 volumio volumio[1190]: info: Discovery: Getting this device information Aug 18 00:54:43 volumio volumio[1190]: info: CoreCommandRouter::volumioGetState Aug 18 00:54:43 volumio volumio[1190]: info: CorePlayQueue::getTrack 0 Aug 18 00:54:43 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 18 00:54:43 volumio nmbd[960]: [2024/08/18 00:54:43.965121, 0] ../source3/libsmb/nmblib.c:917(send_udp) Aug 18 00:54:43 volumio nmbd[960]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable Aug 18 00:54:44 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Aug 18 00:54:44 volumio volumio[1190]: Searching plugin music_service/mpd Aug 18 00:54:44 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: mpd , search Aug 18 00:54:44 volumio volumio[1190]: info: All search sources collected, pushing search results Aug 18 00:54:45 volumio volumio[1190]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Aug 18 00:54:45 volumio wpa_supplicant[2264]: RRM: Ignoring radio measurement request: Not RRM network Aug 18 00:54:45 volumio volumio[1190]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Aug 18 00:54:45 volumio volumio[1190]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Aug 18 00:54:46 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Aug 18 00:54:46 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Aug 18 00:54:46 volumio volumio[1190]: info: Refreshing Cached IP Addresses Aug 18 00:54:46 volumio sudo[2413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Aug 18 00:54:46 volumio sudo[2413]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:46 volumio sudo[2415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 18 00:54:46 volumio sudo[2415]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:46 volumio sudo[2413]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:46 volumio sudo[2418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 18 00:54:46 volumio sudo[2418]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 18 00:54:46 volumio systemd[1]: upmpdcli.service: Succeeded. Aug 18 00:54:46 volumio sudo[2415]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:46 volumio sudo[2418]: pam_unix(sudo:session): session closed for user root Aug 18 00:54:47 volumio volumio[1190]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Aug 18 00:54:47 volumio volumio[1190]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 00:54:47 volumio volumio[1190]: Error: certificate is not yet valid Aug 18 00:54:47 volumio volumio[1190]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Aug 18 00:54:47 volumio volumio[1190]: at TLSSocket.emit (events.js:315:20) Aug 18 00:54:47 volumio volumio[1190]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Aug 18 00:54:47 volumio volumio[1190]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Aug 18 00:54:47 volumio volumio[1190]: code: 'CERT_NOT_YET_VALID' Aug 18 00:54:47 volumio volumio[1190]: } Aug 18 00:54:47 volumio volumio[1190]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 18 00:54:47 volumio sudo[2430]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-18 00:53 Aug 18 00:54:47 volumio sudo[2430]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"