-- Logs begin at Sun 2024-08-25 01:54:54 CEST, end at Sun 2024-08-25 01:56:19 CEST. -- Aug 25 01:54:59 volumio ntpd[733]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Aug 25 01:55:00 volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters... Aug 25 01:55:00 volumio ifplugd[734]: Network Interface Plugging Daemon...start eth0...done. Aug 25 01:55:00 volumio systemd[1]: Started Network Time Service. Aug 25 01:55:00 volumio bluetoothd[722]: Starting SDP server Aug 25 01:55:00 volumio bluetoothd[722]: Excluding (cli) sap Aug 25 01:55:00 volumio systemd[1]: Started LSB: Brings up/down network automatically. Aug 25 01:55:00 volumio systemd[1]: Started Bluetooth service. Aug 25 01:55:00 volumio systemd[1]: Reached target Bluetooth. Aug 25 01:55:00 volumio ntpd[783]: proto: precision = 2.055 usec (-19) Aug 25 01:55:00 volumio ntpd[783]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Aug 25 01:55:00 volumio ntpd[783]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Aug 25 01:55:00 volumio ntpd[783]: Listen and drop on 0 v6wildcard [::]:123 Aug 25 01:55:00 volumio ntpd[783]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 25 01:55:00 volumio ntpd[783]: Listen normally on 2 lo 127.0.0.1:123 Aug 25 01:55:00 volumio ntpd[783]: Listening on routing socket on fd #19 for interface updates Aug 25 01:55:00 volumio ntpd[783]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 25 01:55:00 volumio ntpd[783]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 25 01:55:00 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Aug 25 01:55:00 volumio kernel: Bluetooth: BNEP filters: protocol multicast Aug 25 01:55:00 volumio kernel: Bluetooth: BNEP socket layer initialized Aug 25 01:55:00 volumio bluetoothd[722]: Bluetooth management interface 1.22 initialized Aug 25 01:55:00 volumio dbus-daemon[646]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.4' (uid=0 pid=722 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Aug 25 01:55:00 volumio kernel: Bluetooth: MGMT ver 1.22 Aug 25 01:55:00 volumio cpufrequtils[782]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Aug 25 01:55:00 volumio bluetoothd[722]: Failed to set privacy: Rejected (0x0b) Aug 25 01:55:00 volumio systemd[1]: Started LSB: set CPUFreq kernel parameters. Aug 25 01:55:00 volumio systemd[1]: Starting Hostname Service... Aug 25 01:55:00 volumio dbus-daemon[646]: [system] Successfully activated service 'org.freedesktop.hostname1' Aug 25 01:55:00 volumio systemd[1]: Started Hostname Service. Aug 25 01:55:00 volumio nmbd[744]: [2024/08/25 01:55:00.750842, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Aug 25 01:55:00 volumio nmbd[744]: started asyncdns process 807 Aug 25 01:55:00 volumio nmbd[744]: [2024/08/25 01:55:00.763933, 0] ../lib/util/become_daemon.c:149(daemon_status) Aug 25 01:55:00 volumio nmbd[744]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Aug 25 01:55:00 volumio nmbd[744]: [2024/08/25 01:55:00.764875, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Aug 25 01:55:00 volumio nmbd[744]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Aug 25 01:55:00 volumio haveged[570]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Aug 25 01:55:00 volumio haveged[570]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Aug 25 01:55:00 volumio haveged[570]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99939 Aug 25 01:55:00 volumio haveged[570]: haveged: fills: 0, generated: 0 Aug 25 01:55:01 volumio ntpd[783]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Aug 25 01:55:01 volumio wireless.js[640]: Cleaning previous... Aug 25 01:55:01 volumio sudo[815]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 25 01:55:01 volumio sudo[815]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 01:55:01 volumio sudo[815]: pam_unix(sudo:session): session closed for user root Aug 25 01:55:01 volumio sudo[817]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Aug 25 01:55:01 volumio sudo[817]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 01:55:01 volumio sudo[817]: pam_unix(sudo:session): session closed for user root Aug 25 01:55:01 volumio wireless.js[640]: Stopped aP Aug 25 01:55:01 volumio sudo[824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Aug 25 01:55:01 volumio sudo[824]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 01:55:01 volumio sudo[824]: pam_unix(sudo:session): session closed for user root Aug 25 01:55:01 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 25 01:55:01 volumio sudo[826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Aug 25 01:55:01 volumio sudo[826]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 01:55:01 volumio sudo[826]: pam_unix(sudo:session): session closed for user root Aug 25 01:55:01 volumio sudo[833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Aug 25 01:55:01 volumio sudo[833]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 01:55:01 volumio sudo[833]: pam_unix(sudo:session): session closed for user root Aug 25 01:55:01 volumio sudo[835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Aug 25 01:55:01 volumio sudo[835]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 01:55:01 volumio ntpd[783]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Aug 25 01:55:02 volumio systemd[1]: systemd-rfkill.service: Succeeded. Aug 25 01:55:02 volumio ntpd[783]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Aug 25 01:55:03 volumio volumio-remote-updater[601]: [2024-08-25 01:55:03] [info] asio async_connect error: system:111 (Connection refused) Aug 25 01:55:03 volumio volumio-remote-updater[601]: [2024-08-25 01:55:03] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 25 01:55:03 volumio volumio-remote-updater[601]: [2024-08-25 01:55:03] [error] handle_connect error: Underlying Transport Error Aug 25 01:55:03 volumio ntpd[783]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Aug 25 01:55:04 volumio bthelper[669]: Changing power off succeeded Aug 25 01:55:04 volumio bthelper[669]: [CHG] Controller DC:A6:32:F8:53:B1 Class: 0x0000041c Aug 25 01:55:04 volumio bthelper[669]: Changing power on succeeded Aug 25 01:55:04 volumio sudo[835]: pam_unix(sudo:session): session closed for user root Aug 25 01:55:04 volumio wireless.js[640]: SETTING APPROPRIATE REG DOMAIN: DE Aug 25 01:55:04 volumio sudo[847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Aug 25 01:55:04 volumio sudo[847]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 01:55:04 volumio sudo[847]: pam_unix(sudo:session): session closed for user root Aug 25 01:55:04 volumio sudo[849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE Aug 25 01:55:04 volumio sudo[849]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 01:55:04 volumio sudo[849]: pam_unix(sudo:session): session closed for user root Aug 25 01:55:04 volumio wireless.js[640]: SUCCESSFULLY SET NEW REGDOMAIN: DE Aug 25 01:55:04 volumio wireless.js[640]: Start wireless flow Aug 25 01:55:04 volumio wireless.js[640]: Stopped hotspot (if there).. Aug 25 01:55:04 volumio sudo[854]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Aug 25 01:55:04 volumio sudo[854]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 01:55:04 volumio sudo[854]: pam_unix(sudo:session): session closed for user root Aug 25 01:55:04 volumio sudo[856]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Aug 25 01:55:04 volumio sudo[856]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 01:55:05 volumio sudo[856]: pam_unix(sudo:session): session closed for user root Aug 25 01:55:05 volumio wireless.js[640]: DHCP IP Aug 25 01:55:05 volumio wireless.js[640]: Start ap Aug 25 01:55:05 volumio wpa_supplicant[858]: Successfully initialized wpa_supplicant Aug 25 01:55:05 volumio sudo[859]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Aug 25 01:55:05 volumio sudo[859]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 01:55:05 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 25 01:55:05 volumio dhcpcd[860]: dev: loaded udev Aug 25 01:55:05 volumio dhcpcd-run-hooks[878]: wlan0: starting wpa_supplicant Aug 25 01:55:05 volumio dhcpcd-run-hooks[883]: wlan0: failed to start wpa_supplicant Aug 25 01:55:05 volumio dhcpcd-run-hooks[884]: 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 25 01:55:05 volumio dhcpcd[860]: wlan0: connected to Access Point `' Aug 25 01:55:05 volumio dhcpcd[860]: no interfaces have a carrier Aug 25 01:55:05 volumio dhcpcd[860]: forked to background, child pid 894 Aug 25 01:55:05 volumio dhcpcd[894]: eth0: waiting for carrier Aug 25 01:55:05 volumio dhcpcd[894]: wlan0: waiting for carrier Aug 25 01:55:05 volumio sudo[859]: pam_unix(sudo:session): session closed for user root Aug 25 01:55:06 volumio wireless.js[640]: trying... Aug 25 01:55:06 volumio sudo[896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 25 01:55:06 volumio sudo[896]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 01:55:06 volumio sudo[896]: pam_unix(sudo:session): session closed for user root Aug 25 01:55:07 volumio wireless.js[640]: trying... Aug 25 01:55:07 volumio sudo[899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 25 01:55:07 volumio sudo[899]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 01:55:07 volumio sudo[899]: pam_unix(sudo:session): session closed for user root Aug 25 01:55:07 volumio wpa_supplicant[882]: wlan0: Trying to associate with SSID 'FRITZ!Box 7520 ZY' Aug 25 01:55:08 volumio wireless.js[640]: trying... Aug 25 01:55:08 volumio sudo[902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 25 01:55:08 volumio sudo[902]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 01:55:08 volumio sudo[902]: pam_unix(sudo:session): session closed for user root Aug 25 01:55:09 volumio wireless.js[640]: trying... Aug 25 01:55:09 volumio sudo[905]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 25 01:55:09 volumio sudo[905]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 01:55:09 volumio sudo[905]: pam_unix(sudo:session): session closed for user root Aug 25 01:55:10 volumio wireless.js[640]: trying... Aug 25 01:55:10 volumio sudo[908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 25 01:55:10 volumio sudo[908]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 01:55:10 volumio sudo[908]: pam_unix(sudo:session): session closed for user root Aug 25 01:55:10 volumio wpa_supplicant[882]: RRM: Ignoring radio measurement request: Not associated Aug 25 01:55:10 volumio dhcpcd[894]: wlan0: carrier acquired Aug 25 01:55:10 volumio dhcpcd[894]: wlan0: connected to Access Point `FRITZ!Box 7520 ZY' Aug 25 01:55:10 volumio wpa_supplicant[882]: wlan0: Associated with 98:9b:cb:98:b2:e9 Aug 25 01:55:10 volumio wpa_supplicant[882]: wlan0: CTRL-EVENT-CONNECTED - Connection to 98:9b:cb:98:b2:e9 completed [id=0 id_str=] Aug 25 01:55:10 volumio wpa_supplicant[882]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 25 01:55:10 volumio wpa_supplicant[882]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Aug 25 01:55:10 volumio dhcpcd[894]: DUID 00:01:00:01:2c:2b:36:40:dc:a6:32:f8:53:b0 Aug 25 01:55:10 volumio dhcpcd[894]: wlan0: IAID 32:f8:53:b0 Aug 25 01:55:10 volumio dhcpcd[894]: wlan0: adding address fe80::dea6:32ff:fef8:53b0 Aug 25 01:55:10 volumio dhcpcd[894]: ipv6_addaddr1: Permission denied Aug 25 01:55:10 volumio dhcpcd[894]: wlan0: carrier lost Aug 25 01:55:10 volumio dhcpcd[894]: wlan0: carrier acquired Aug 25 01:55:10 volumio dhcpcd[894]: wlan0: IAID 32:f8:53:b0 Aug 25 01:55:11 volumio dhcpcd[894]: wlan0: rebinding lease of 192.168.178.28 Aug 25 01:55:11 volumio wireless.js[640]: trying... Aug 25 01:55:11 volumio volumio-remote-updater[601]: [2024-08-25 01:55:11] [info] asio async_connect error: system:111 (Connection refused) Aug 25 01:55:11 volumio volumio-remote-updater[601]: [2024-08-25 01:55:11] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 25 01:55:11 volumio volumio-remote-updater[601]: [2024-08-25 01:55:11] [error] handle_connect error: Underlying Transport Error Aug 25 01:55:11 volumio dhcpcd[894]: wlan0: probing address 192.168.178.28/24 Aug 25 01:55:11 volumio sudo[923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 25 01:55:11 volumio sudo[923]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 01:55:11 volumio sudo[923]: pam_unix(sudo:session): session closed for user root Aug 25 01:55:11 volumio dhcpcd[894]: wlan0: soliciting an IPv6 router Aug 25 01:55:12 volumio wireless.js[640]: trying... Aug 25 01:55:12 volumio sudo[926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 25 01:55:12 volumio sudo[926]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 01:55:12 volumio sudo[926]: pam_unix(sudo:session): session closed for user root Aug 25 01:55:12 volumio wpa_supplicant[882]: RRM: Ignoring radio measurement request: Not RRM network Aug 25 01:55:13 volumio wireless.js[640]: trying... Aug 25 01:55:13 volumio sudo[929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 25 01:55:13 volumio sudo[929]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 01:55:13 volumio sudo[929]: pam_unix(sudo:session): session closed for user root Aug 25 01:55:14 volumio wireless.js[640]: trying... Aug 25 01:55:14 volumio sudo[932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 25 01:55:14 volumio sudo[932]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 01:55:14 volumio sudo[932]: pam_unix(sudo:session): session closed for user root Aug 25 01:55:15 volumio wireless.js[640]: trying... Aug 25 01:55:15 volumio sudo[935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 25 01:55:15 volumio sudo[935]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 01:55:15 volumio sudo[935]: pam_unix(sudo:session): session closed for user root Aug 25 01:55:16 volumio wireless.js[640]: trying... Aug 25 01:55:16 volumio sudo[938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 25 01:55:16 volumio sudo[938]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 01:55:16 volumio sudo[938]: pam_unix(sudo:session): session closed for user root Aug 25 01:55:16 volumio dhcpcd[894]: wlan0: leased 192.168.178.28 for 864000 seconds Aug 25 01:55:16 volumio avahi-daemon[607]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.28. Aug 25 01:55:16 volumio avahi-daemon[607]: New relevant interface wlan0.IPv4 for mDNS. Aug 25 01:55:16 volumio dhcpcd[894]: wlan0: adding route to 192.168.178.0/24 Aug 25 01:55:16 volumio avahi-daemon[607]: Registering new address record for 192.168.178.28 on wlan0.IPv4. Aug 25 01:55:16 volumio dhcpcd[894]: wlan0: adding default route via 192.168.178.1 Aug 25 01:55:16 volumio ntpd[783]: ntpd exiting on signal 15 (Terminated) Aug 25 01:55:16 volumio systemd[1]: Stopping Network Time Service... Aug 25 01:55:16 volumio systemd[1]: ntp.service: Succeeded. Aug 25 01:55:16 volumio systemd[1]: Stopped Network Time Service. Aug 25 01:55:16 volumio systemd[1]: Starting Network Time Service... Aug 25 01:55:16 volumio ntpd[971]: ntpd 4.2.8p12@1.3728-o (1): Starting Aug 25 01:55:16 volumio ntpd[971]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 Aug 25 01:55:16 volumio systemd[1]: Started Network Time Service. Aug 25 01:55:16 volumio ntpd[978]: proto: precision = 1.055 usec (-20) Aug 25 01:55:16 volumio ntpd[978]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Aug 25 01:55:16 volumio ntpd[978]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Aug 25 01:55:16 volumio ntpd[978]: Listen and drop on 0 v6wildcard [::]:123 Aug 25 01:55:16 volumio ntpd[978]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 25 01:55:16 volumio ntpd[978]: Listen normally on 2 lo 127.0.0.1:123 Aug 25 01:55:16 volumio ntpd[978]: Listen normally on 3 wlan0 192.168.178.28:123 Aug 25 01:55:16 volumio ntpd[978]: Listening on routing socket on fd #20 for interface updates Aug 25 01:55:16 volumio ntpd[978]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 25 01:55:16 volumio ntpd[978]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 25 01:55:17 volumio wireless.js[640]: trying... Aug 25 01:55:17 volumio sudo[981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 25 01:55:17 volumio sudo[981]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 01:55:17 volumio sudo[981]: pam_unix(sudo:session): session closed for user root Aug 25 01:55:17 volumio wireless.js[640]: Connected to: ----FRITZ!Box 7520 ZY Aug 25 01:55:17 volumio wireless.js[640]: ---- Aug 25 01:55:17 volumio sudo[984]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 25 01:55:17 volumio sudo[984]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 01:55:17 volumio sudo[984]: pam_unix(sudo:session): session closed for user root Aug 25 01:55:17 volumio wireless.js[640]: ... joined AP, wlan0 IPv4 is 192.168.178.28, ipV6 is undefined Aug 25 01:55:17 volumio wireless.js[640]: It's done! AP Aug 25 01:55:17 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Aug 25 01:55:17 volumio systemd[1]: Started Wireless Services. Aug 25 01:55:17 volumio systemd[1]: Started Volumio Backend Module. Aug 25 01:55:17 volumio systemd[1]: Started Volumio Cpu Tweaker. Aug 25 01:55:17 volumio volumio-cpu-tweak[990]: Setting RT Priority for mpd Aug 25 01:55:17 volumio volumio-cpu-tweak[990]: pid 35's current scheduling policy: SCHED_OTHER Aug 25 01:55:17 volumio volumio-cpu-tweak[990]: pid 35's current scheduling priority: 0 Aug 25 01:55:17 volumio volumio-cpu-tweak[990]: Setting MPD Affinity Aug 25 01:55:17 volumio volumio-cpu-tweak[990]: pid 3's current affinity mask: f Aug 25 01:55:17 volumio volumio-cpu-tweak[990]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Aug 25 01:55:17 volumio volumio-cpu-tweak[990]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Aug 25 01:55:17 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Aug 25 01:55:17 volumio ntpd[978]: Soliciting pool server 144.91.116.85 Aug 25 01:55:18 volumio ntpd[978]: Soliciting pool server 162.159.200.1 Aug 25 01:55:18 volumio ntpd[978]: Soliciting pool server 212.132.97.26 Aug 25 01:55:19 volumio volumio[989]: info: ------------------------------------------- Aug 25 01:55:19 volumio volumio[989]: info: ----- Volumio3 ---- Aug 25 01:55:19 volumio volumio[989]: info: ------------------------------------------- Aug 25 01:55:19 volumio volumio[989]: info: ----- System startup ---- Aug 25 01:55:19 volumio volumio[989]: info: ------------------------------------------- Aug 25 01:55:19 volumio ntpd[978]: Soliciting pool server 178.254.6.85 Aug 25 01:55:19 volumio ntpd[978]: Soliciting pool server 148.251.5.46 Aug 25 01:55:19 volumio ntpd[978]: Soliciting pool server 141.98.136.83 Aug 25 01:55:19 volumio volumio[989]: info: MYVOLUMIO Environment detected Aug 25 01:55:19 volumio volumio[989]: info: Plugin folders cleanup Aug 25 01:55:19 volumio volumio[989]: info: Scanning into folder /volumio/app/plugins/ Aug 25 01:55:19 volumio volumio[989]: info: Scanning category audio_interface Aug 25 01:55:19 volumio volumio[989]: info: Scanning category miscellanea Aug 25 01:55:19 volumio volumio[989]: info: Scanning category music_service Aug 25 01:55:19 volumio volumio[989]: info: Scanning category plugins.json Aug 25 01:55:19 volumio volumio[989]: info: Scanning category system_controller Aug 25 01:55:19 volumio volumio[989]: info: Scanning category user_interface Aug 25 01:55:19 volumio volumio[989]: info: Scanning into folder /data/plugins/ Aug 25 01:55:19 volumio volumio[989]: info: Scanning category music_service Aug 25 01:55:19 volumio volumio[989]: info: Scanning category user_interface Aug 25 01:55:19 volumio volumio[989]: info: Plugin folders cleanup completed Aug 25 01:55:19 volumio volumio[989]: info: ------------------------------------------- Aug 25 01:55:19 volumio volumio[989]: info: ----- Core plugins startup ---- Aug 25 01:55:19 volumio volumio[989]: info: ------------------------------------------- Aug 25 01:55:19 volumio volumio[989]: info: Loading plugins from folder /volumio/app/plugins/ Aug 25 01:55:19 volumio volumio[989]: info: Adding plugin upnp to MyMusic Plugins Aug 25 01:55:19 volumio volumio[989]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 25 01:55:19 volumio volumio[989]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 25 01:55:19 volumio volumio[989]: info: Loading plugins from folder /data/plugins/ Aug 25 01:55:19 volumio volumio[989]: info: Loading plugin "system"... Aug 25 01:55:20 volumio volumio[989]: info: Loading plugin "appearance"... Aug 25 01:55:20 volumio ntpd[978]: Soliciting pool server 178.63.52.31 Aug 25 01:55:20 volumio nmbd[744]: [2024/08/25 01:55:20.772967, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 25 01:55:20 volumio nmbd[744]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Aug 25 01:55:20 volumio systemd[1]: Started Samba NMB Daemon. Aug 25 01:55:20 volumio ntpd[978]: Soliciting pool server 185.248.189.10 Aug 25 01:55:20 volumio ntpd[978]: Soliciting pool server 49.13.14.46 Aug 25 01:55:20 volumio ntpd[978]: Soliciting pool server 161.97.164.152 Aug 25 01:55:20 volumio systemd[1]: Starting Samba Winbind Daemon... Aug 25 01:55:21 volumio volumio[989]: info: Loading plugin "network"... Aug 25 01:55:21 volumio volumio[989]: info: Refreshing Cached IP Addresses Aug 25 01:55:21 volumio sudo[1022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 25 01:55:21 volumio sudo[1022]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 01:55:21 volumio sudo[1022]: pam_unix(sudo:session): session closed for user root Aug 25 01:55:21 volumio winbindd[1019]: [2024/08/25 01:55:21.067541, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Aug 25 01:55:21 volumio winbindd[1019]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Aug 25 01:55:21 volumio sudo[1025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 25 01:55:21 volumio volumio[989]: info: Loading plugin "services"... Aug 25 01:55:21 volumio sudo[1025]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 01:55:21 volumio volumio[989]: info: Loading plugin "alsa_controller"... Aug 25 01:55:21 volumio winbindd[1019]: [2024/08/25 01:55:21.083134, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 25 01:55:21 volumio winbindd[1019]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Aug 25 01:55:21 volumio systemd[1]: Started Samba Winbind Daemon. Aug 25 01:55:21 volumio sudo[1025]: pam_unix(sudo:session): session closed for user root Aug 25 01:55:21 volumio sudo[1028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 25 01:55:21 volumio sudo[1028]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 01:55:21 volumio systemd[1]: Starting Samba SMB Daemon... Aug 25 01:55:21 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 25 01:55:21 volumio volumio[989]: info: Loading plugin "wizard"... Aug 25 01:55:21 volumio volumio-remote-updater[601]: [2024-08-25 01:55:21] [connect] Successful connection Aug 25 01:55:21 volumio volumio[989]: info: Loading plugin "networkfs"... Aug 25 01:55:21 volumio volumio[989]: info: Starting Udev Watcher for removable devices Aug 25 01:55:21 volumio volumio[989]: info: Ignoring mount for partition: boot Aug 25 01:55:21 volumio volumio[989]: info: Ignoring mount for partition: volumio Aug 25 01:55:21 volumio volumio[989]: info: Ignoring mount for partition: volumio_data Aug 25 01:55:21 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 25 01:55:21 volumio volumio[989]: info: Loading plugin "volumio_command_line_client"... Aug 25 01:55:21 volumio volumio[989]: info: Loading plugin "upnp"... Aug 25 01:55:21 volumio volumio[989]: info: [1724543721295] Starting Upmpd Daemon Aug 25 01:55:21 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 25 01:55:21 volumio volumio[989]: info: Loading plugin "my_music"... Aug 25 01:55:21 volumio volumio[989]: info: Loading plugin "mpd"... Aug 25 01:55:21 volumio smbd[1037]: [2024/08/25 01:55:21.509972, 0] ../lib/util/become_daemon.c:138(daemon_ready) Aug 25 01:55:21 volumio smbd[1037]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Aug 25 01:55:21 volumio systemd[1]: Started Samba SMB Daemon. Aug 25 01:55:21 volumio systemd[1]: Reached target Multi-User System. Aug 25 01:55:21 volumio systemd[1]: Reached target Graphical Interface. Aug 25 01:55:21 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Aug 25 01:55:21 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Aug 25 01:55:21 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Aug 25 01:55:21 volumio systemd[1]: Startup finished in 17.245s (kernel) + 29.455s (userspace) = 46.701s. Aug 25 01:55:21 volumio volumio[989]: info: Loading plugin "upnp_browser"... Aug 25 01:55:21 volumio ntpd[978]: Soliciting pool server 167.71.55.144 Aug 25 01:55:21 volumio ntpd[978]: Soliciting pool server 78.47.249.55 Aug 25 01:55:21 volumio ntpd[978]: Soliciting pool server 85.214.83.151 Aug 25 01:55:21 volumio volumio[989]: info: Loading plugin "alarm-clock"... Aug 25 01:55:22 volumio volumio[989]: info: Loading plugin "airplay_emulation"... Aug 25 01:55:22 volumio volumio[989]: info: Starting Shairport Sync Aug 25 01:55:22 volumio volumio[989]: info: Loading plugin "last_100"... Aug 25 01:55:22 volumio volumio[989]: info: Loading plugin "webradio"... Aug 25 01:55:22 volumio volumio[989]: info: Loading plugin "i2s_dacs"... Aug 25 01:55:22 volumio volumio[989]: info: I2S DAC not set, start Auto-detection Aug 25 01:55:22 volumio volumio[989]: info: Loading plugin "volumiodiscovery"... Aug 25 01:55:22 volumio volumio[989]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 25 01:55:22 volumio volumio[989]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 25 01:55:22 volumio node[989]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 25 01:55:22 volumio volumio[989]: *** WARNING *** For more information see Aug 25 01:55:22 volumio volumio[989]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 25 01:55:22 volumio volumio[989]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 25 01:55:22 volumio volumio[989]: *** WARNING *** For more information see Aug 25 01:55:22 volumio node[989]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 25 01:55:22 volumio node[989]: *** WARNING *** For more information see Aug 25 01:55:22 volumio node[989]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 25 01:55:22 volumio node[989]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 25 01:55:22 volumio node[989]: *** WARNING *** For more information see Aug 25 01:55:22 volumio volumio[989]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 25 01:55:22 volumio volumio[989]: info: Discovery: Started advertising with name: Volumio Aug 25 01:55:22 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 25 01:55:22 volumio volumio[989]: info: Loading plugin "youtube2"... Aug 25 01:55:22 volumio ntpd[978]: Soliciting pool server 107.189.12.98 Aug 25 01:55:22 volumio ntpd[978]: Soliciting pool server 2a12:8d02:2100:293:5054:ff:fe3a:161a Aug 25 01:55:23 volumio volumio[989]: info: Loading plugin "ytmusic"... Aug 25 01:55:24 volumio volumio[989]: info: Plugin now_playing is not enabled Aug 25 01:55:24 volumio volumio[989]: info: Loading plugin "outputs"... Aug 25 01:55:24 volumio volumio[989]: info: Loading plugin "albumart"... Aug 25 01:55:24 volumio volumio[989]: info: Plugin example_plugin is not enabled Aug 25 01:55:24 volumio volumio[989]: info: Loading plugin "inputs"... Aug 25 01:55:24 volumio volumio[989]: info: Loading plugin "updater_comm"... Aug 25 01:55:24 volumio volumio[989]: info: Plugin mpdemulation is not enabled Aug 25 01:55:24 volumio volumio[989]: info: Loading plugin "rest_api"... Aug 25 01:55:24 volumio volumio[989]: info: Loading plugin "websocket"... Aug 25 01:55:24 volumio volumio[989]: info: Starting Socket.io Server version 2.3.0 Aug 25 01:55:24 volumio volumio[989]: info: Loading plugin "podcast"... Aug 25 01:55:24 volumio volumio[989]: Forking 3 albumart workers Aug 25 01:55:24 volumio sudo[1028]: pam_unix(sudo:session): session closed for user root Aug 25 01:55:24 volumio volumio[989]: info: ControllerPodcast::constructor Aug 25 01:55:24 volumio volumio[989]: info: Plugin touch_display is not enabled Aug 25 01:55:24 volumio volumio[989]: info: Loading i18n strings for locale en Aug 25 01:55:24 volumio volumio[989]: Updating browse sources language Aug 25 01:55:24 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 01:55:24 volumio volumio[989]: Starting albumart workers Aug 25 01:55:24 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 01:55:24 volumio volumio[989]: info: CoreCommandRouter::initPlayerControls Aug 25 01:55:24 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 01:55:24 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 01:55:24 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 01:55:24 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 01:55:24 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 01:55:24 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 01:55:24 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 01:55:24 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 01:55:24 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 01:55:24 volumio volumio[989]: Express server listening on port 3000 Aug 25 01:55:24 volumio volumio[989]: [Metrics] WebUI: 6s 419.31ms Aug 25 01:55:24 volumio volumio[989]: Starting albumart workers Aug 25 01:55:24 volumio volumio[989]: info: CoreStateMachine::resetVolumioState Aug 25 01:55:24 volumio volumio[989]: info: CoreStateMachine::getcurrentVolume Aug 25 01:55:24 volumio volumio[989]: info: CoreCommandRouter::volumioRetrievevolume Aug 25 01:55:24 volumio volumio[989]: Starting albumart workers Aug 25 01:55:24 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 01:55:25 volumio volumio-remote-updater[601]: [2024-08-25 01:55:25] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1724543721 101 Aug 25 01:55:25 volumio volumio[989]: 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: 1 Aug 25 01:55:25 volumio volumio[989]: info: Reloading queue from file Aug 25 01:55:25 volumio volumio[989]: info: Setting Device type: Raspberry PI Aug 25 01:55:25 volumio volumio[989]: info: VolumeController:: Volume=9 Mute =false Aug 25 01:55:25 volumio volumio[989]: info: CoreStateMachine::pushState Aug 25 01:55:25 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Aug 25 01:55:25 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 01:55:25 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Aug 25 01:55:25 volumio volumio[989]: info: CoreStateMachine::updateTrackBlock Aug 25 01:55:25 volumio volumio[989]: info: CorePlayQueue::getTrackBlock Aug 25 01:55:25 volumio volumio[989]: info: CoreCommandRouter::volumioRetrievevolume Aug 25 01:55:25 volumio volumio[989]: info: CoreStateMachine::setRepeat true single undefined Aug 25 01:55:25 volumio volumio[989]: info: CoreStateMachine::pushState Aug 25 01:55:25 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Aug 25 01:55:25 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 01:55:25 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Aug 25 01:55:25 volumio volumio[989]: info: CoreStateMachine::setRandom null Aug 25 01:55:25 volumio volumio[989]: info: CoreStateMachine::pushState Aug 25 01:55:25 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Aug 25 01:55:25 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Aug 25 01:55:25 volumio volumio[989]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 25 01:55:25 volumio volumio[989]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Aug 25 01:55:25 volumio volumio[989]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 25 01:55:25 volumio volumio[989]: info: Completed loading Core Plugins Aug 25 01:55:25 volumio volumio[989]: info: Preparing to generate the ALSA configuration file Aug 25 01:55:25 volumio volumio[989]: info: Asound.conf file unchanged, so no further update is needed Aug 25 01:55:25 volumio volumio[989]: info: Output device has changed, restarting MPD Aug 25 01:55:25 volumio volumio[989]: info: Output device has changed, restarting Shairport Sync Aug 25 01:55:25 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 01:55:25 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 01:55:25 volumio sudo[1095]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 25 01:55:25 volumio sudo[1095]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 01:55:25 volumio sudo[1095]: pam_unix(sudo:session): session closed for user root Aug 25 01:55:25 volumio sudo[1099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 25 01:55:25 volumio sudo[1099]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 01:55:25 volumio volumio[989]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 25 01:55:25 volumio volumio[989]: info: ___________ START PLUGINS ___________ Aug 25 01:55:25 volumio volumio[989]: info: ControllerMpd::onStart: Initializing MPD Aug 25 01:55:25 volumio volumio[989]: info: Creating MPD Configuration file Aug 25 01:55:25 volumio systemd[1]: Listening on mpd.socket. Aug 25 01:55:25 volumio systemd[1]: Starting Music Player Daemon... Aug 25 01:55:25 volumio sudo[1119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 25 01:55:25 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 01:55:25 volumio sudo[1119]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 01:55:25 volumio volumio[989]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 25 01:55:25 volumio volumio[989]: info: [1724543725487] CoreMusicLibrary::Adding element Media Servers Aug 25 01:55:25 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 01:55:25 volumio sudo[1119]: pam_unix(sudo:session): session closed for user root Aug 25 01:55:25 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 01:55:25 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 01:55:25 volumio sudo[1127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 25 01:55:25 volumio sudo[1127]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 01:55:25 volumio sudo[1120]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 25 01:55:25 volumio sudo[1120]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 01:55:25 volumio sudo[1120]: pam_unix(sudo:session): session closed for user root Aug 25 01:55:25 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 25 01:55:25 volumio systemd[1]: mpd.service: Succeeded. Aug 25 01:55:25 volumio systemd[1]: Stopped Music Player Daemon. Aug 25 01:55:25 volumio systemd[1]: Starting Music Player Daemon... Aug 25 01:55:25 volumio volumio[989]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 25 01:55:25 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 01:55:25 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 01:55:25 volumio volumio[989]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 25 01:55:25 volumio volumio[989]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 25 01:55:25 volumio volumio[989]: info: [1724543725607] CoreMusicLibrary::Adding element Last_100 Aug 25 01:55:25 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 01:55:25 volumio volumio[989]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 25 01:55:25 volumio volumio[989]: info: [1724543725610] CoreMusicLibrary::Adding element Webradio Aug 25 01:55:25 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 01:55:25 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 25 01:55:25 volumio sudo[1133]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 25 01:55:25 volumio sudo[1133]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 01:55:25 volumio volumio[989]: info: Initializing BBC Radios Aug 25 01:55:25 volumio sudo[1133]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 25 01:55:25 volumio sudo[1133]: pam_unix(sudo:session): session closed for user root Aug 25 01:55:25 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 25 01:55:25 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 01:56:08 volumio volumio[989]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 25 01:56:08 volumio volumio[989]: info: [1724543768686] CoreMusicLibrary::Adding element Podcast Aug 25 01:56:08 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 01:56:08 volumio volumio[989]: Cannot find translation for source Podcast Aug 25 01:56:08 volumio volumio[989]: info: Volumio Calling Home Aug 25 01:56:08 volumio ntpd[978]: receive: Unexpected origin timestamp 0xea74f16d.c5ab9519 does not match aorg 0000000000.00000000 from server@141.98.136.83 xmt 0xea74f198.ae33c1b6 Aug 25 01:56:08 volumio ntpd[978]: receive: Unexpected origin timestamp 0xea74f16d.c5a627b3 does not match aorg 0000000000.00000000 from server@178.63.52.31 xmt 0xea74f198.aea7723b Aug 25 01:56:08 volumio ntpd[978]: receive: Unexpected origin timestamp 0xea74f16d.c5b9084d does not match aorg 0000000000.00000000 from server@144.91.116.85 xmt 0xea74f198.aea94206 Aug 25 01:56:08 volumio ntpd[978]: receive: Unexpected origin timestamp 0xea74f16d.c5b083ac does not match aorg 0000000000.00000000 from server@212.132.97.26 xmt 0xea74f198.af363e14 Aug 25 01:56:08 volumio ntpd[978]: receive: Unexpected origin timestamp 0xea74f16d.c59cf9e8 does not match aorg 0000000000.00000000 from server@85.214.83.151 xmt 0xea74f198.af997bba Aug 25 01:56:08 volumio volumio[989]: info: Discovery: adding 14273eb0-dc24-4e3d-b174-0ef381e5934b Aug 25 01:56:08 volumio volumio[989]: info: Discovery: Found device Volumio Aug 25 01:56:08 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Aug 25 01:56:08 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Aug 25 01:56:08 volumio volumio[989]: info: MPD Permissions set Aug 25 01:56:08 volumio volumio[989]: info: MPD Permissions set Aug 25 01:56:08 volumio volumio[989]: info: VolumeController:: Volume=9 Mute =false Aug 25 01:56:08 volumio volumio[989]: info: CoreStateMachine::pushState Aug 25 01:56:08 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Aug 25 01:56:08 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Aug 25 01:56:08 volumio volumio[989]: 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 25 01:56:08 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 01:56:08 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 01:56:08 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 01:56:08 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 01:56:08 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 01:56:08 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 01:56:08 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 01:56:08 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 01:56:08 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 01:56:08 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 01:56:08 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 01:56:08 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 01:56:08 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 01:56:08 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 01:56:08 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 01:56:08 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 01:56:08 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 01:56:08 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 01:56:08 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 01:56:08 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 01:56:09 volumio volumio[989]: info: Volumio called home Aug 25 01:56:09 volumio volumio[989]: info: Starting Shairport Sync Aug 25 01:56:09 volumio volumio[989]: info: Starting Shairport Sync Aug 25 01:56:09 volumio volumio[989]: info: Starting Shairport Sync Aug 25 01:56:09 volumio sudo[1161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 25 01:56:09 volumio sudo[1161]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 01:56:09 volumio sudo[1163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 25 01:56:09 volumio sudo[1163]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 01:56:09 volumio sudo[1167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 25 01:56:09 volumio sudo[1167]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 01:56:09 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 25 01:56:09 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 25 01:56:09 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 25 01:56:09 volumio volumio[989]: info: CoreCommandRouter::volumioGetState Aug 25 01:56:09 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Aug 25 01:56:09 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 25 01:56:09 volumio sudo[1161]: pam_unix(sudo:session): session closed for user root Aug 25 01:56:09 volumio sudo[1163]: pam_unix(sudo:session): session closed for user root Aug 25 01:56:09 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 25 01:56:09 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 25 01:56:09 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 25 01:56:09 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 25 01:56:09 volumio sudo[1167]: pam_unix(sudo:session): session closed for user root Aug 25 01:56:09 volumio volumio[989]: info: Shairport-Sync Started Aug 25 01:56:09 volumio volumio[989]: Error adding Membership: Error: addMembership EINVAL Aug 25 01:56:09 volumio volumio[989]: info: Shairport-Sync Started Aug 25 01:56:09 volumio volumio[989]: info: Shairport-Sync Started Aug 25 01:56:10 volumio volumio[989]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 25 01:56:10 volumio volumio[989]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 25 01:56:10 volumio volumio[989]: info: [1724543770035] CoreMusicLibrary::Adding element YouTube2 Aug 25 01:56:10 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 01:56:10 volumio volumio[989]: Cannot find translation for source Podcast Aug 25 01:56:10 volumio volumio[989]: Cannot find translation for source YouTube2 Aug 25 01:56:10 volumio volumio[989]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 25 01:56:10 volumio volumio[989]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 25 01:56:10 volumio volumio[989]: info: [1724543770171] CoreMusicLibrary::Adding element YouTube Music Aug 25 01:56:10 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 01:56:10 volumio volumio[989]: Cannot find translation for source Podcast Aug 25 01:56:10 volumio volumio[989]: Cannot find translation for source YouTube2 Aug 25 01:56:10 volumio volumio[989]: Cannot find translation for source YouTube Music Aug 25 01:56:10 volumio volumio[989]: error: [youtube2] Error in ConfigModel.#fetchAccountMenu(): Request to https://www.youtube.com/youtubei/vtrue/account/account_menu?key=AIzaSyAO_FJ2SlqU8Q4STEHLGCilw_Y9_11qcW8&prettyPrint=false&alt=json failed with status 400 Error: Request to https://www.youtube.com/youtubei/vtrue/account/account_menu?key=AIzaSyAO_FJ2SlqU8Q4STEHLGCilw_Y9_11qcW8&prettyPrint=false&alt=json failed with status 400 Aug 25 01:56:10 volumio volumio[989]: at HTTPClient. (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:22010:13) Aug 25 01:56:10 volumio volumio[989]: at Generator.next () Aug 25 01:56:10 volumio volumio[989]: at fulfilled (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:215:30) Aug 25 01:56:10 volumio volumio[989]: at runMicrotasks () Aug 25 01:56:10 volumio volumio[989]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Aug 25 01:56:10 volumio volumio[989]: (node:989) UnhandledPromiseRejectionWarning: TypeError: Cannot convert undefined or null to object Aug 25 01:56:10 volumio volumio[989]: at Function.entries () Aug 25 01:56:10 volumio volumio[989]: at findInObject (/data/plugins/music_service/youtube2/dist/lib/util/index.js:27:43) Aug 25 01:56:10 volumio volumio[989]: at ConfigModel.getI18nOptions (/data/plugins/music_service/youtube2/dist/lib/model/ConfigModel.js:84:54) Aug 25 01:56:10 volumio volumio[989]: at runMicrotasks () Aug 25 01:56:10 volumio volumio[989]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Aug 25 01:56:10 volumio volumio[989]: (Use `node --trace-warnings ...` to show where the warning was created) Aug 25 01:56:10 volumio volumio[989]: (node:989) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 1) Aug 25 01:56:10 volumio volumio[989]: (node:989) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Aug 25 01:56:10 volumio volumio[989]: error: [ytmusic] Error in ConfigModel._fetchSettingsPage(): Request to https://www.youtube.com/youtubei/vtrue/account/get_setting?key=AIzaSyAO_FJ2SlqU8Q4STEHLGCilw_Y9_11qcW8&prettyPrint=false&alt=json failed with status 400 Error: Request to https://www.youtube.com/youtubei/vtrue/account/get_setting?key=AIzaSyAO_FJ2SlqU8Q4STEHLGCilw_Y9_11qcW8&prettyPrint=false&alt=json failed with status 400 Aug 25 01:56:10 volumio volumio[989]: at HTTPClient. (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:22010:13) Aug 25 01:56:10 volumio volumio[989]: at Generator.next () Aug 25 01:56:10 volumio volumio[989]: at fulfilled (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:215:30) Aug 25 01:56:10 volumio volumio[989]: at runMicrotasks () Aug 25 01:56:10 volumio volumio[989]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Aug 25 01:56:10 volumio systemd[1]: systemd-fsckd.service: Succeeded. Aug 25 01:56:10 volumio mpd[1139]: Aug 25 01:56 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 25 01:56:10 volumio systemd[1]: Started Music Player Daemon. Aug 25 01:56:10 volumio sudo[1127]: pam_unix(sudo:session): session closed for user root Aug 25 01:56:10 volumio sudo[1099]: pam_unix(sudo:session): session closed for user root Aug 25 01:56:10 volumio volumio[989]: info: Completed starting Core Plugins Aug 25 01:56:10 volumio volumio[989]: info: ------------------------------------------- Aug 25 01:56:10 volumio volumio[989]: info: ----- MyVolumio plugins startup ---- Aug 25 01:56:10 volumio volumio[989]: info: ------------------------------------------- Aug 25 01:56:10 volumio volumio[989]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 25 01:56:10 volumio volumio[989]: error: MPD error: The expression evaluated to a falsy value: Aug 25 01:56:10 volumio volumio[989]: assert.ok(self.idling) Aug 25 01:56:10 volumio volumio[989]: error: The expression evaluated to a falsy value: Aug 25 01:56:10 volumio volumio[989]: assert.ok(self.idling) Aug 25 01:56:10 volumio volumio[989]: info: MPD running with PID1139 Aug 25 01:56:10 volumio volumio[989]: ,establishing connection Aug 25 01:56:10 volumio volumio[989]: error: MPD error: The expression evaluated to a falsy value: Aug 25 01:56:10 volumio volumio[989]: assert.ok(self.idling) Aug 25 01:56:10 volumio volumio[989]: error: The expression evaluated to a falsy value: Aug 25 01:56:10 volumio volumio[989]: assert.ok(self.idling) Aug 25 01:56:10 volumio volumio[989]: error: updateQueue error: null Aug 25 01:56:10 volumio volumio[989]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 25 01:56:10 volumio volumio[989]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 25 01:56:11 volumio volumio[989]: error: [youtube2] Error in ConfigModel.#fetchAccountMenu(): Request to https://www.youtube.com/youtubei/vtrue/account/account_menu?key=AIzaSyAO_FJ2SlqU8Q4STEHLGCilw_Y9_11qcW8&prettyPrint=false&alt=json failed with status 400 Error: Request to https://www.youtube.com/youtubei/vtrue/account/account_menu?key=AIzaSyAO_FJ2SlqU8Q4STEHLGCilw_Y9_11qcW8&prettyPrint=false&alt=json failed with status 400 Aug 25 01:56:11 volumio volumio[989]: at HTTPClient. (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:22010:13) Aug 25 01:56:11 volumio volumio[989]: at Generator.next () Aug 25 01:56:11 volumio volumio[989]: at fulfilled (/data/plugins/music_service/youtube2/node_modules/volumio-youtubei.js/bundle/node.cjs:215:30) Aug 25 01:56:11 volumio volumio[989]: at runMicrotasks () Aug 25 01:56:11 volumio volumio[989]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Aug 25 01:56:11 volumio volumio[989]: (node:989) UnhandledPromiseRejectionWarning: TypeError: Cannot convert undefined or null to object Aug 25 01:56:11 volumio volumio[989]: at Function.entries () Aug 25 01:56:11 volumio volumio[989]: at findInObject (/data/plugins/music_service/youtube2/dist/lib/util/index.js:27:43) Aug 25 01:56:11 volumio volumio[989]: at ConfigModel.getI18nOptions (/data/plugins/music_service/youtube2/dist/lib/model/ConfigModel.js:84:54) Aug 25 01:56:11 volumio volumio[989]: at runMicrotasks () Aug 25 01:56:11 volumio volumio[989]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Aug 25 01:56:11 volumio volumio[989]: (node:989) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 2) Aug 25 01:56:11 volumio volumio[989]: error: [ytmusic] Error in ConfigModel._fetchSettingsPage(): Request to https://www.youtube.com/youtubei/vtrue/account/get_setting?key=AIzaSyAO_FJ2SlqU8Q4STEHLGCilw_Y9_11qcW8&prettyPrint=false&alt=json failed with status 400 Error: Request to https://www.youtube.com/youtubei/vtrue/account/get_setting?key=AIzaSyAO_FJ2SlqU8Q4STEHLGCilw_Y9_11qcW8&prettyPrint=false&alt=json failed with status 400 Aug 25 01:56:11 volumio volumio[989]: at HTTPClient. (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:22010:13) Aug 25 01:56:11 volumio volumio[989]: at Generator.next () Aug 25 01:56:11 volumio volumio[989]: at fulfilled (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:215:30) Aug 25 01:56:11 volumio volumio[989]: at runMicrotasks () Aug 25 01:56:11 volumio volumio[989]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Aug 25 01:56:12 volumio sh[534]: timed out Aug 25 01:56:12 volumio dhcpcd[583]: timed out Aug 25 01:56:12 volumio sh[534]: dhcpcd exited Aug 25 01:56:12 volumio dhcpcd[583]: dhcpcd exited Aug 25 01:56:12 volumio sh[534]: ifup: failed to bring up eth0 Aug 25 01:56:12 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Aug 25 01:56:12 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Aug 25 01:56:13 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Aug 25 01:56:13 volumio systemd[1]: Stopped target Local File Systems. Aug 25 01:56:13 volumio volumio[989]: info: Partition removed: {"syspath":"/sys/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:aaaa/block/mmcblk0/mmcblk0p1","ACTION":"remove","DEVLINKS":"/dev/disk/by-uuid/E8CB-9B11 /dev/disk/by-label/boot /dev/disk/by-partuuid/5e3b350d-01 /dev/disk/by-path/platform-fe340000.mmc-part1 /dev/disk/by-id/mmc-SC64G_0xba36adab-part1","DEVNAME":"/dev/mmcblk0p1","DEVPATH":"/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:aaaa/block/mmcblk0/mmcblk0p1","DEVTYPE":"partition","DISKSEQ":"25","ID_FS_LABEL":"boot","ID_FS_LABEL_ENC":"boot","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"E8CB-9B11","ID_FS_UUID_ENC":"E8CB-9B11","ID_FS_VERSION":"FAT16","ID_NAME":"SC64G","ID_PART_ENTRY_DISK":"179:0","ID_PART_ENTRY_FLAGS":"0x80","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"1","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"187500","ID_PART_ENTRY_TYPE":"0xc","ID_PART_ENTRY_UUID":"5e3b350d-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"5e3b350d","ID_PATH":"platform-fe340000.mmc","ID_PATH_TAG":"platform-fe340000_mmc","ID_SERIAL":"0xba36adab","MAJOR":"179","MINOR":"1","PARTN":"1","SEQNUM":"2307","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"3205488"} Aug 25 01:56:13 volumio kernel: mmc0: card aaaa removed Aug 25 01:56:13 volumio kernel: EXT4-fs warning (device mmcblk0p3): ext4_end_bio:347: I/O error 10 writing to inode 1310775 starting block 6943234) Aug 25 01:56:13 volumio kernel: EXT4-fs warning (device mmcblk0p3): ext4_end_bio:347: I/O error 10 writing to inode 1310775 starting block 6943235) Aug 25 01:56:13 volumio kernel: EXT4-fs warning (device mmcblk0p3): ext4_end_bio:347: I/O error 10 writing to inode 1310791 starting block 6948364) Aug 25 01:56:13 volumio kernel: EXT4-fs warning (device mmcblk0p3): ext4_end_bio:347: I/O error 10 writing to inode 1310791 starting block 6948367) Aug 25 01:56:13 volumio kernel: EXT4-fs warning (device mmcblk0p3): ext4_end_bio:347: I/O error 10 writing to inode 1310790 starting block 6948369) Aug 25 01:56:13 volumio kernel: EXT4-fs warning (device mmcblk0p3): ext4_end_bio:347: I/O error 10 writing to inode 1310791 starting block 6948371) Aug 25 01:56:13 volumio kernel: EXT4-fs warning (device mmcblk0p3): ext4_end_bio:347: I/O error 10 writing to inode 1348006 starting block 6948375) Aug 25 01:56:13 volumio kernel: EXT4-fs error (device mmcblk0p3): ext4_check_bdev_write_error:218: comm kworker/u8:1: Error while async write back metadata Aug 25 01:56:13 volumio kernel: Buffer I/O error on device mmcblk0p3, logical block 6259714 Aug 25 01:56:13 volumio kernel: Buffer I/O error on device mmcblk0p3, logical block 6259715 Aug 25 01:56:13 volumio kernel: Buffer I/O error on device mmcblk0p3, logical block 6264844 Aug 25 01:56:13 volumio kernel: Buffer I/O error on device mmcblk0p3, logical block 6264847 Aug 25 01:56:13 volumio kernel: Buffer I/O error on device mmcblk0p3, logical block 6264851 Aug 25 01:56:13 volumio kernel: Buffer I/O error on device mmcblk0p3, logical block 6264852 Aug 25 01:56:13 volumio kernel: Buffer I/O error on device mmcblk0p3, logical block 6264853 Aug 25 01:56:13 volumio kernel: Buffer I/O error on device mmcblk0p3, logical block 6264854 Aug 25 01:56:13 volumio kernel: Buffer I/O error on device mmcblk0p3, logical block 6264849 Aug 25 01:56:13 volumio kernel: Buffer I/O error on device mmcblk0p3, logical block 6264850 Aug 25 01:56:13 volumio kernel: Aborting journal on device mmcblk0p3-8. Aug 25 01:56:13 volumio kernel: JBD2: I/O error when updating journal superblock for mmcblk0p3-8. Aug 25 01:56:13 volumio kernel: EXT4-fs (mmcblk0p3): I/O error while writing superblock Aug 25 01:56:13 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #1310863: comm node: reading directory lblock 0 Aug 25 01:56:13 volumio volumio[989]: info: Partition removed: {"syspath":"/sys/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:aaaa/block/mmcblk0/mmcblk0p2","ACTION":"remove","DEVLINKS":"/dev/disk/by-label/volumio /dev/disk/by-id/mmc-SC64G_0xba36adab-part2 /dev/disk/by-path/platform-fe340000.mmc-part2 /dev/disk/by-uuid/59add7c1-f5c7-4787-a0bf-7fec952d5a50 /dev/disk/by-partuuid/5e3b350d-02","DEVNAME":"/dev/mmcblk0p2","DEVPATH":"/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:aaaa/block/mmcblk0/mmcblk0p2","DEVTYPE":"partition","DISKSEQ":"25","ID_FS_LABEL":"volumio","ID_FS_LABEL_ENC":"volumio","ID_FS_TYPE":"ext4","ID_FS_USAGE":"filesystem","ID_FS_UUID":"59add7c1-f5c7-4787-a0bf-7fec952d5a50","ID_FS_UUID_ENC":"59add7c1-f5c7-4787-a0bf-7fec952d5a50","ID_FS_VERSION":"1.0","ID_NAME":"SC64G","ID_PART_ENTRY_DISK":"179:0","ID_PART_ENTRY_NUMBER":"2","ID_PART_ENTRY_OFFSET":"188416","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"5279744","ID_PART_ENTRY_TYPE":"0x83","ID_PART_ENTRY_UUID":"5e3b350d-02","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"5e3b350d","ID_PATH":"platform-fe340000.mmc","ID_PATH_TAG":"platform-fe340000_mmc","ID_SERIAL":"0xba36adab","MAJOR":"179","MINOR":"2","PARTN":"2","SEQNUM":"2308","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"3242782"} Aug 25 01:56:13 volumio kernel: EXT4-fs (mmcblk0p3): I/O error while writing superblock Aug 25 01:56:13 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #1310863: comm node: reading directory lblock 0 Aug 25 01:56:13 volumio kernel: EXT4-fs (mmcblk0p3): I/O error while writing superblock Aug 25 01:56:13 volumio volumio[989]: info: Partition removed: {"syspath":"/sys/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:aaaa/block/mmcblk0/mmcblk0p3","ACTION":"remove","DEVLINKS":"/dev/disk/by-partuuid/5e3b350d-03 /dev/disk/by-path/platform-fe340000.mmc-part3 /dev/disk/by-id/mmc-SC64G_0xba36adab-part3 /dev/disk/by-label/volumio_data /dev/disk/by-uuid/1611491c-7e1a-4d40-97d7-a6ff7c508103","DEVNAME":"/dev/mmcblk0p3","DEVPATH":"/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:aaaa/block/mmcblk0/mmcblk0p3","DEVTYPE":"partition","DISKSEQ":"25","ID_FS_LABEL":"volumio_data","ID_FS_LABEL_ENC":"volumio_data","ID_FS_TYPE":"ext4","ID_FS_USAGE":"filesystem","ID_FS_UUID":"1611491c-7e1a-4d40-97d7-a6ff7c508103","ID_FS_UUID_ENC":"1611491c-7e1a-4d40-97d7-a6ff7c508103","ID_FS_VERSION":"1.0","ID_NAME":"SC64G","ID_PART_ENTRY_DISK":"179:0","ID_PART_ENTRY_NUMBER":"3","ID_PART_ENTRY_OFFSET":"5468160","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"119267328","ID_PART_ENTRY_TYPE":"0x83","ID_PART_ENTRY_UUID":"5e3b350d-03","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"5e3b350d","ID_PATH":"platform-fe340000.mmc","ID_PATH_TAG":"platform-fe340000_mmc","ID_SERIAL":"0xba36adab","MAJOR":"179","MINOR":"3","PARTN":"3","SEQNUM":"2309","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"3185060"} Aug 25 01:56:13 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #1310863: comm node: reading directory lblock 0 Aug 25 01:56:13 volumio systemd[1]: Unmounting /boot... Aug 25 01:56:13 volumio systemd[1183]: boot.mount: Failed to execute command: Input/output error Aug 25 01:56:13 volumio systemd[1183]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Aug 25 01:56:13 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Aug 25 01:56:13 volumio systemd[1]: Failed unmounting /boot. Aug 25 01:56:13 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-E8CB\x2d9B11.device. Stopping, too. Aug 25 01:56:13 volumio systemd[1]: Unmounting /boot... Aug 25 01:56:13 volumio systemd[1185]: boot.mount: Failed to execute command: Input/output error Aug 25 01:56:13 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #1310769: comm (umount): reading directory lblock 0 Aug 25 01:56:13 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #1310769: comm (umount): reading directory lblock 0 Aug 25 01:56:13 volumio systemd[1185]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Aug 25 01:56:13 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Aug 25 01:56:13 volumio systemd[1]: Failed unmounting /boot. Aug 25 01:56:13 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-E8CB\x2d9B11.device. Stopping, too. Aug 25 01:56:13 volumio systemd[1]: Unmounting /boot... Aug 25 01:56:13 volumio systemd[1186]: boot.mount: Failed to execute command: Input/output error Aug 25 01:56:13 volumio systemd[1186]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Aug 25 01:56:13 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Aug 25 01:56:13 volumio systemd[1]: Failed unmounting /boot. Aug 25 01:56:13 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-E8CB\x2d9B11.device. Stopping, too. Aug 25 01:56:13 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #1310769: comm (umount): reading directory lblock 0 Aug 25 01:56:13 volumio systemd[1]: Unmounting /boot... Aug 25 01:56:13 volumio systemd[1187]: boot.mount: Failed to execute command: Input/output error Aug 25 01:56:13 volumio systemd[1187]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Aug 25 01:56:13 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Aug 25 01:56:13 volumio systemd[1]: Failed unmounting /boot. Aug 25 01:56:13 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-E8CB\x2d9B11.device. Stopping, too. Aug 25 01:56:13 volumio systemd[1]: Unmounting /boot... Aug 25 01:56:13 volumio systemd[1188]: boot.mount: Failed to execute command: Input/output error Aug 25 01:56:13 volumio systemd[1188]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Aug 25 01:56:13 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #1310769: comm (umount): reading directory lblock 0 Aug 25 01:56:13 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #1310769: comm (umount): reading directory lblock 0 Aug 25 01:56:13 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Aug 25 01:56:13 volumio systemd[1]: Failed unmounting /boot. Aug 25 01:56:13 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-E8CB\x2d9B11.device. Stopping, too. Aug 25 01:56:13 volumio systemd[1]: Unmounting /boot... Aug 25 01:56:13 volumio systemd[1189]: boot.mount: Failed to execute command: Input/output error Aug 25 01:56:13 volumio systemd[1189]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Aug 25 01:56:13 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Aug 25 01:56:13 volumio systemd[1]: Failed unmounting /boot. Aug 25 01:56:13 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-E8CB\x2d9B11.device. Stopping, too. Aug 25 01:56:13 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #1310769: comm (umount): reading directory lblock 0 Aug 25 01:56:13 volumio systemd[1]: Unmounting /boot... Aug 25 01:56:13 volumio systemd[1190]: boot.mount: Failed to execute command: Input/output error Aug 25 01:56:13 volumio systemd[1190]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Aug 25 01:56:13 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Aug 25 01:56:13 volumio systemd[1]: Failed unmounting /boot. Aug 25 01:56:13 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-E8CB\x2d9B11.device. Stopping, too. Aug 25 01:56:13 volumio systemd[1]: Unmounting /boot... Aug 25 01:56:13 volumio systemd[1191]: boot.mount: Failed to execute command: Input/output error Aug 25 01:56:13 volumio systemd[1191]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Aug 25 01:56:13 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Aug 25 01:56:13 volumio systemd[1]: Failed unmounting /boot. Aug 25 01:56:13 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-E8CB\x2d9B11.device. Stopping, too. Aug 25 01:56:13 volumio systemd[1]: Unmounting /boot... Aug 25 01:56:13 volumio systemd[1192]: boot.mount: Failed to execute command: Input/output error Aug 25 01:56:13 volumio systemd[1192]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Aug 25 01:56:13 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Aug 25 01:56:13 volumio systemd[1]: Failed unmounting /boot. Aug 25 01:56:13 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-E8CB\x2d9B11.device. Stopping, too. Aug 25 01:56:13 volumio systemd[1]: Unmounting /boot... Aug 25 01:56:13 volumio systemd[1193]: boot.mount: Failed to execute command: Input/output error Aug 25 01:56:13 volumio systemd[1193]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Aug 25 01:56:13 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Aug 25 01:56:13 volumio systemd[1]: Failed unmounting /boot. Aug 25 01:56:13 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-E8CB\x2d9B11.device. Stopping, too. Aug 25 01:56:13 volumio systemd[1]: Unmounting /boot... Aug 25 01:56:13 volumio systemd[1194]: boot.mount: Failed to execute command: Input/output error Aug 25 01:56:13 volumio systemd[1194]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Aug 25 01:56:13 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Aug 25 01:56:13 volumio systemd[1]: Failed unmounting /boot. Aug 25 01:56:13 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-E8CB\x2d9B11.device. Stopping, too. Aug 25 01:56:13 volumio systemd[1]: Unmounting /boot... Aug 25 01:56:13 volumio systemd[1195]: boot.mount: Failed to execute command: Input/output error Aug 25 01:56:13 volumio systemd[1195]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Aug 25 01:56:13 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Aug 25 01:56:13 volumio systemd[1]: Failed unmounting /boot. Aug 25 01:56:13 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-E8CB\x2d9B11.device. Stopping, too. Aug 25 01:56:13 volumio systemd[1]: Unmounting /boot... Aug 25 01:56:13 volumio systemd[1196]: boot.mount: Failed to execute command: Input/output error Aug 25 01:56:13 volumio systemd[1196]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Aug 25 01:56:13 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Aug 25 01:56:13 volumio systemd[1]: Failed unmounting /boot. Aug 25 01:56:13 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-E8CB\x2d9B11.device. Stopping, too. Aug 25 01:56:13 volumio systemd[1]: Unmounting /boot... Aug 25 01:56:13 volumio systemd[1197]: boot.mount: Failed to execute command: Input/output error Aug 25 01:56:13 volumio systemd[1197]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Aug 25 01:56:13 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Aug 25 01:56:13 volumio systemd[1]: Failed unmounting /boot. Aug 25 01:56:13 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-E8CB\x2d9B11.device. Stopping, too. Aug 25 01:56:13 volumio systemd[1]: Unmounting /boot... Aug 25 01:56:13 volumio systemd[1198]: boot.mount: Failed to execute command: Input/output error Aug 25 01:56:13 volumio systemd[1198]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Aug 25 01:56:13 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Aug 25 01:56:13 volumio systemd[1]: Failed unmounting /boot. Aug 25 01:56:13 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-E8CB\x2d9B11.device. Stopping, too. Aug 25 01:56:13 volumio systemd[1]: Unmounting /boot... Aug 25 01:56:13 volumio systemd[1199]: boot.mount: Failed to execute command: Input/output error Aug 25 01:56:13 volumio systemd[1199]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Aug 25 01:56:13 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Aug 25 01:56:13 volumio systemd[1]: Failed unmounting /boot. Aug 25 01:56:13 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-E8CB\x2d9B11.device. Stopping, too. Aug 25 01:56:13 volumio systemd[1]: Unmounting /boot... Aug 25 01:56:13 volumio systemd[1200]: boot.mount: Failed to execute command: Input/output error Aug 25 01:56:13 volumio systemd[1200]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Aug 25 01:56:13 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Aug 25 01:56:13 volumio systemd[1]: Failed unmounting /boot. Aug 25 01:56:13 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-E8CB\x2d9B11.device, but not stopping since we tried this too often recently. Aug 25 01:56:13 volumio systemd[1]: systemd-fsck@dev-disk-by\x2duuid-E8CB\x2d9B11.service: Succeeded. Aug 25 01:56:13 volumio systemd[1]: Stopped File System Check on /dev/disk/by-uuid/E8CB-9B11. Aug 25 01:56:14 volumio sudo[1202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 25 01:56:14 volumio sudo[1202]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 01:56:14 volumio sudo[1202]: pam_unix(sudo:session): session closed for user root Aug 25 01:56:14 volumio sudo[1205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 25 01:56:14 volumio sudo[1205]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 01:56:14 volumio sudo[1205]: pam_unix(sudo:session): session closed for user root Aug 25 01:56:14 volumio sudo[1208]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 25 01:56:14 volumio sudo[1208]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 01:56:14 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Aug 25 01:56:14 volumio sudo[1208]: pam_unix(sudo:session): session closed for user root Aug 25 01:56:14 volumio volumio[989]: info: Upmpdcli Daemon Started Aug 25 01:56:15 volumio volumio[989]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 25 01:56:18 volumio kernel: mmc0: new ultra high speed DDR50 SDXC card at address e624 Aug 25 01:56:18 volumio kernel: mmcblk0: mmc0:e624 SB64G 59.5 GiB Aug 25 01:56:18 volumio kernel: mmcblk0: p1 p2 Aug 25 01:56:18 volumio kernel: mmcblk0: mmc0:e624 SB64G 59.5 GiB (quirks 0x00004000) Aug 25 01:56:18 volumio kernel: EXT4-fs error: 42 callbacks suppressed Aug 25 01:56:18 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #1310895: comm systemd: reading directory lblock 0 Aug 25 01:56:18 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #1310895: comm systemd: reading directory lblock 0 Aug 25 01:56:18 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #1310895: comm systemd: reading directory lblock 0 Aug 25 01:56:18 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #1310895: comm systemd: reading directory lblock 0 Aug 25 01:56:18 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #1310895: comm systemd: reading directory lblock 0 Aug 25 01:56:18 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #1310895: comm systemd: reading directory lblock 0 Aug 25 01:56:18 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #1310895: comm systemd: reading directory lblock 0 Aug 25 01:56:18 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #1325721: comm sh: reading directory lblock 0 Aug 25 01:56:18 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #1310895: comm systemd: reading directory lblock 0 Aug 25 01:56:18 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #1325255: comm sh: reading directory lblock 0 Aug 25 01:56:18 volumio volumio[989]: /bin/sh: 1: lsblk: Input/output error Aug 25 01:56:18 volumio volumio[989]: /bin/sh: 1: /bin/lsblk: Input/output error Aug 25 01:56:18 volumio volumio[989]: error: Failed USB identification: Error: Command failed: /bin/lsblk -rno tran "/dev/$(lsblk -rno PKNAME /dev/mmcblk0p1)" Aug 25 01:56:18 volumio volumio[989]: /bin/sh: 1: lsblk: Input/output error Aug 25 01:56:18 volumio volumio[989]: /bin/sh: 1: /bin/lsblk: Input/output error Aug 25 01:56:18 volumio volumio[989]: info: Ignoring mount for partition: bootfs Aug 25 01:56:18 volumio volumio[989]: /bin/sh: 1: lsblk: Input/output error Aug 25 01:56:18 volumio volumio[989]: /bin/sh: 1: /bin/lsblk: Input/output error Aug 25 01:56:18 volumio volumio[989]: error: Failed USB identification: Error: Command failed: /bin/lsblk -rno tran "/dev/$(lsblk -rno PKNAME /dev/mmcblk0p2)" Aug 25 01:56:18 volumio volumio[989]: /bin/sh: 1: lsblk: Input/output error Aug 25 01:56:18 volumio volumio[989]: /bin/sh: 1: /bin/lsblk: Input/output error Aug 25 01:56:18 volumio volumio[989]: info: Ignoring mount for partition: rootfs Aug 25 01:56:19 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 25 01:56:19 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 25 01:56:19 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 25 01:56:19 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 25 01:56:19 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 25 01:56:19 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 25 01:56:19 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 25 01:56:19 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 25 01:56:19 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 25 01:56:19 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 25 01:56:19 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 25 01:56:19 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 25 01:56:19 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 25 01:56:19 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 25 01:56:19 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 25 01:56:19 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 25 01:56:19 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 25 01:56:19 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 25 01:56:19 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 25 01:56:19 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 25 01:56:19 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 25 01:56:19 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 25 01:56:19 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 25 01:56:19 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 25 01:56:19 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 25 01:56:19 volumio volumio[989]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 25 01:56:19 volumio volumio[989]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 01:56:19 volumio kernel: EXT4-fs warning (device mmcblk0p3): htree_dirblock_to_tree:1082: inode #1310959: lblock 0: comm node: error -5 reading directory block Aug 25 01:56:19 volumio volumio[989]: Error: EIO: i/o error, scandir '/myvolumio/plugins' Aug 25 01:56:19 volumio volumio[989]: at Object.readdirSync (fs.js:1021:3) Aug 25 01:56:19 volumio volumio[989]: at MyVolumioPluginManager.loadMyVolumioPluginsMyMusic (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:16515) Aug 25 01:56:19 volumio volumio[989]: at MyVolumioPluginManager.initMyVolumio (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:1501) Aug 25 01:56:19 volumio volumio[989]: at /myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:3480 Aug 25 01:56:19 volumio volumio[989]: at Request.handleRequestResponse [as _callback] (/volumio/node_modules/unirest/index.js:552:70) Aug 25 01:56:19 volumio volumio[989]: at Request.self.callback (/volumio/node_modules/request/request.js:185:22) Aug 25 01:56:19 volumio volumio[989]: at Request.emit (events.js:315:20) Aug 25 01:56:19 volumio volumio[989]: at Request. (/volumio/node_modules/request/request.js:1154:10) Aug 25 01:56:19 volumio volumio[989]: at Request.emit (events.js:315:20) Aug 25 01:56:19 volumio volumio[989]: at IncomingMessage. (/volumio/node_modules/request/request.js:1076:12) Aug 25 01:56:19 volumio volumio[989]: at Object.onceWrapper (events.js:421:28) Aug 25 01:56:19 volumio volumio[989]: at IncomingMessage.emit (events.js:327:22) Aug 25 01:56:19 volumio volumio[989]: at endReadableNT (internal/streams/readable.js:1327:12) Aug 25 01:56:19 volumio volumio[989]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Aug 25 01:56:19 volumio volumio[989]: errno: -5, Aug 25 01:56:19 volumio volumio[989]: syscall: 'scandir', Aug 25 01:56:19 volumio volumio[989]: code: 'EIO', Aug 25 01:56:19 volumio volumio[989]: path: '/myvolumio/plugins' Aug 25 01:56:19 volumio volumio[989]: } Aug 25 01:56:19 volumio volumio[989]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 01:56:19 volumio sudo[1250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-25 01:55 Aug 25 01:56:19 volumio sudo[1250]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"