-- Logs begin at Wed 2024-04-03 07:46:52 CEST, end at Wed 2024-04-03 07:48:10 CEST. -- Apr 03 07:47:00 nvb systemd[1]: Mounted /boot. Apr 03 07:47:00 nvb systemd[1]: Reached target Local File Systems. Apr 03 07:47:00 nvb systemd[1]: Starting Raise network interfaces... Apr 03 07:47:00 nvb systemd[1]: Starting Create Volatile Files and Directories... Apr 03 07:47:00 nvb systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Apr 03 07:47:00 nvb systemd[1]: Starting Preprocess NFS configuration... Apr 03 07:47:00 nvb systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Apr 03 07:47:00 nvb systemd[1]: Started ifup for eth0. Apr 03 07:47:00 nvb systemd[1]: Started ifup for wlan0. Apr 03 07:47:00 nvb systemd[1]: Received SIGRTMIN+20 from PID 273 (plymouthd). Apr 03 07:47:00 nvb systemd[1]: plymouth-read-write.service: Succeeded. Apr 03 07:47:00 nvb systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Apr 03 07:47:00 nvb systemd[1]: nfs-config.service: Succeeded. Apr 03 07:47:00 nvb systemd[1]: Started Preprocess NFS configuration. Apr 03 07:47:00 nvb systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Apr 03 07:47:00 nvb systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Apr 03 07:47:00 nvb systemd[1]: Reached target NFS client services. Apr 03 07:47:00 nvb systemd[1]: Started Create Volatile Files and Directories. Apr 03 07:47:00 nvb systemd[1]: Starting RPC bind portmap service... Apr 03 07:47:00 nvb systemd[1]: Starting Update UTMP about System Boot/Shutdown... Apr 03 07:47:00 nvb systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Apr 03 07:47:00 nvb systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Apr 03 07:47:00 nvb systemd[1]: Started Update UTMP about System Boot/Shutdown. Apr 03 07:47:00 nvb systemd[1]: Reached target System Initialization. Apr 03 07:47:00 nvb systemd[1]: Started Daily apt download activities. Apr 03 07:47:00 nvb systemd[1]: Started Daily apt upgrade and clean activities. Apr 03 07:47:00 nvb systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Apr 03 07:47:00 nvb systemd[1]: Listening on triggerhappy.socket. Apr 03 07:47:00 nvb systemd[1]: Started Daily Cleanup of Temporary Directories. Apr 03 07:47:00 nvb systemd[1]: Reached target Timers. Apr 03 07:47:00 nvb systemd[1]: Listening on D-Bus System Message Bus Socket. Apr 03 07:47:00 nvb systemd[1]: Reached target Sockets. Apr 03 07:47:00 nvb kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Apr 03 07:47:00 nvb kernel: 8021q: 802.1Q VLAN Support v1.8 Apr 03 07:47:00 nvb systemd[1]: Started Volumio Splash. Apr 03 07:47:00 nvb systemd[1]: Reached target Basic System. Apr 03 07:47:00 nvb systemd[537]: volumiosplash.service: Failed to execute command: No such file or directory Apr 03 07:47:00 nvb systemd[537]: volumiosplash.service: Failed at step EXEC spawning /bin/volumiosplash.sh: No such file or directory Apr 03 07:47:00 nvb systemd[1]: Starting Login Service... Apr 03 07:47:00 nvb systemd[1]: Starting triggerhappy global hotkey daemon... Apr 03 07:47:00 nvb systemd[1]: Started D-Bus System Message Bus. Apr 03 07:47:00 nvb systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Apr 03 07:47:00 nvb systemd[1]: Starting Wireless Services... Apr 03 07:47:00 nvb systemd[1]: Started Volumio Log Rotation Service. Apr 03 07:47:00 nvb systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Apr 03 07:47:00 nvb thd[551]: Unable to parse trigger line: Apr 03 07:47:00 nvb thd[551]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Apr 03 07:47:00 nvb thd[551]: Unable to parse trigger line: Apr 03 07:47:00 nvb thd[551]: Found socket passed from systemd Apr 03 07:47:00 nvb systemd[1]: Starting Configure Bluetooth Modems connected by UART... Apr 03 07:47:00 nvb systemd[1]: Started Volumio Iptables Module. Apr 03 07:47:00 nvb systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Apr 03 07:47:00 nvb systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Apr 03 07:47:00 nvb systemd[1]: Starting dhcpcd on all interfaces... Apr 03 07:47:00 nvb systemd[1]: Started volumio-remote-updater.service. Apr 03 07:47:00 nvb systemd[1]: Started Manage Sound Card State (restore and store). Apr 03 07:47:00 nvb alsactl[577]: alsactl 1.1.8 daemon started Apr 03 07:47:00 nvb systemd-logind[550]: New seat seat0. Apr 03 07:47:00 nvb systemd[1]: Starting Save/Restore Sound Card State... Apr 03 07:47:00 nvb systemd[1]: Started UPnP Renderer front-end to MPD. Apr 03 07:47:00 nvb dhcpcd[575]: Not running dhcpcd because /etc/network/interfaces Apr 03 07:47:00 nvb dhcpcd[575]: defines some interfaces that will use a Apr 03 07:47:00 nvb dhcpcd[575]: DHCP client or static address Apr 03 07:47:00 nvb systemd[1]: Starting WPA supplicant... Apr 03 07:47:01 nvb systemd[1]: Started triggerhappy global hotkey daemon. Apr 03 07:47:01 nvb systemd[1]: Started RPC bind portmap service. Apr 03 07:47:01 nvb systemd[1]: volumiosplash.service: Main process exited, code=exited, status=203/EXEC Apr 03 07:47:01 nvb systemd[1]: volumiosplash.service: Failed with result 'exit-code'. Apr 03 07:47:01 nvb systemd[1]: hciuart.service: Succeeded. Apr 03 07:47:01 nvb systemd[1]: Started Configure Bluetooth Modems connected by UART. Apr 03 07:47:01 nvb systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Apr 03 07:47:01 nvb systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Apr 03 07:47:01 nvb systemd[1]: Failed to start dhcpcd on all interfaces. Apr 03 07:47:01 nvb systemd[1]: Starting Raspberry Pi bluetooth helper... Apr 03 07:47:01 nvb systemd[1]: Reached target RPC Port Mapper. Apr 03 07:47:01 nvb systemd[1]: Reached target Remote File Systems (Pre). Apr 03 07:47:01 nvb systemd[1]: Reached target Remote File Systems. Apr 03 07:47:01 nvb systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Apr 03 07:47:01 nvb systemd[1]: Started Save/Restore Sound Card State. Apr 03 07:47:01 nvb sh[471]: wlan0=wlan0 Apr 03 07:47:01 nvb systemd[1]: Reached target Sound Card. Apr 03 07:47:01 nvb bthelper[585]: Raspberry Pi BDADDR already set Apr 03 07:47:01 nvb avahi-daemon[570]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Apr 03 07:47:01 nvb avahi-daemon[570]: Successfully dropped root privileges. Apr 03 07:47:01 nvb avahi-daemon[570]: avahi-daemon 0.7 starting up. Apr 03 07:47:01 nvb sh[470]: eth0: waiting for carrier Apr 03 07:47:01 nvb dhcpcd[514]: eth0: waiting for carrier Apr 03 07:47:01 nvb kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Apr 03 07:47:01 nvb kernel: bcmgenet fd580000.ethernet eth0: Link is Down Apr 03 07:47:02 nvb systemd[1]: Started Raspberry Pi bluetooth helper. Apr 03 07:47:02 nvb wpa_supplicant[581]: Successfully initialized wpa_supplicant Apr 03 07:47:02 nvb avahi-daemon[570]: Successfully called chroot(). Apr 03 07:47:02 nvb avahi-daemon[570]: Successfully dropped remaining capabilities. Apr 03 07:47:02 nvb avahi-daemon[570]: Loading service file /services/volumio.service. Apr 03 07:47:02 nvb avahi-daemon[570]: Network interface enumeration completed. Apr 03 07:47:02 nvb avahi-daemon[570]: Server startup complete. Host name is nvb.local. Local service cookie is 2938980434. Apr 03 07:47:02 nvb avahi-daemon[570]: Service "nvb" (/services/volumio.service) successfully established. Apr 03 07:47:02 nvb systemd[1]: Started Raise network interfaces. Apr 03 07:47:02 nvb systemd[1]: Started Login Service. Apr 03 07:47:02 nvb systemd[1]: Started WPA supplicant. Apr 03 07:47:02 nvb systemd[1]: Started Avahi mDNS/DNS-SD Stack. Apr 03 07:47:02 nvb systemd[1]: Reached target Network. Apr 03 07:47:02 nvb systemd[1]: Starting Permit User Sessions... Apr 03 07:47:02 nvb systemd[1]: Starting Network Time Service... Apr 03 07:47:02 nvb systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Apr 03 07:47:02 nvb systemd[1]: Reached target Network is Online. Apr 03 07:47:02 nvb systemd[1]: Starting /etc/rc.local Compatibility... Apr 03 07:47:02 nvb systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 03 07:47:02 nvb systemd[1]: Starting Samba NMB Daemon... Apr 03 07:47:02 nvb systemd[1]: Starting LSB: Brings up/down network automatically... Apr 03 07:47:02 nvb systemd[1]: Starting Bluetooth service... Apr 03 07:47:02 nvb systemd[1]: Started /etc/rc.local Compatibility. Apr 03 07:47:02 nvb systemd[1]: Started Permit User Sessions. Apr 03 07:47:02 nvb systemd[1]: Starting Hold until boot process finishes up... Apr 03 07:47:02 nvb volumio-remote-updater[576]: Error: No active session Apr 03 07:47:02 nvb volumio-remote-updater[576]: [2024-04-03 07:47:02] [info] asio async_connect error: system:111 (Connection refused) Apr 03 07:47:02 nvb volumio-remote-updater[576]: [2024-04-03 07:47:02] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 03 07:47:02 nvb volumio-remote-updater[576]: [2024-04-03 07:47:02] [error] handle_connect error: Underlying Transport Error Apr 03 07:47:02 nvb systemd[1]: Starting Terminate Plymouth Boot Screen... Apr 03 07:47:02 nvb systemd[1]: systemd-rfkill.service: Succeeded. Apr 03 07:47:02 nvb systemd[1]: plymouth-start.service: Succeeded. Apr 03 07:47:02 nvb systemd[1]: plymouth-quit-wait.service: Succeeded. Apr 03 07:47:02 nvb systemd[1]: Started Hold until boot process finishes up. Apr 03 07:47:02 nvb systemd[1]: plymouth-quit.service: Succeeded. Apr 03 07:47:02 nvb systemd[1]: Started Terminate Plymouth Boot Screen. Apr 03 07:47:02 nvb systemd[1]: iptables.service: Succeeded. Apr 03 07:47:02 nvb systemd[1]: Received SIGRTMIN+21 from PID 273 (n/a). Apr 03 07:47:02 nvb systemd[1]: Received SIGRTMIN+21 from PID 273 (n/a). Apr 03 07:47:02 nvb volumio[580]: Could not open config: /tmp/upmpdcli.conf Apr 03 07:47:02 nvb loadcpufreq[597]: Loading cpufreq kernel modules...done (none). Apr 03 07:47:02 nvb systemd[1]: Started Getty on tty1. Apr 03 07:47:02 nvb systemd[1]: Reached target Login Prompts. Apr 03 07:47:02 nvb systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 03 07:47:02 nvb systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 03 07:47:02 nvb systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Apr 03 07:47:02 nvb systemd[1]: Starting LSB: set CPUFreq kernel parameters... Apr 03 07:47:02 nvb ntpd[660]: ntpd 4.2.8p12@1.3728-o (1): Starting Apr 03 07:47:02 nvb ntpd[660]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Apr 03 07:47:02 nvb ifplugd(eth0)[715]: ifplugd 0.28 initializing. Apr 03 07:47:02 nvb ifplugd(eth0)[715]: Using interface eth0/D8:3A:DD:99:36:30 with driver (version: 6.1.69-v7l+) Apr 03 07:47:02 nvb ifplugd(eth0)[715]: Using detection mode: SIOCETHTOOL Apr 03 07:47:02 nvb ifplugd(eth0)[715]: Initialization complete, link beat not detected. Apr 03 07:47:02 nvb systemd[1]: Started Network Time Service. Apr 03 07:47:03 nvb ntpd[713]: proto: precision = 2.167 usec (-19) Apr 03 07:47:03 nvb bluetoothd[667]: Bluetooth daemon 5.50 Apr 03 07:47:03 nvb ifplugd[665]: Network Interface Plugging Daemon...start eth0...done. Apr 03 07:47:03 nvb systemd[1]: Started LSB: Brings up/down network automatically. Apr 03 07:47:03 nvb ntpd[713]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Apr 03 07:47:03 nvb ntpd[713]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Apr 03 07:47:03 nvb ntpd[713]: Listen and drop on 0 v6wildcard [::]:123 Apr 03 07:47:03 nvb ntpd[713]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 03 07:47:03 nvb bluetoothd[667]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Apr 03 07:47:03 nvb ntpd[713]: Listen normally on 2 lo 127.0.0.1:123 Apr 03 07:47:03 nvb ntpd[713]: Listening on routing socket on fd #19 for interface updates Apr 03 07:47:03 nvb ntpd[713]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 03 07:47:03 nvb ntpd[713]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 03 07:47:03 nvb systemd[1]: Started Bluetooth service. Apr 03 07:47:03 nvb systemd[1]: Reached target Bluetooth. Apr 03 07:47:03 nvb bluetoothd[667]: Starting SDP server Apr 03 07:47:03 nvb bluetoothd[667]: Excluding (cli) sap Apr 03 07:47:03 nvb cpufrequtils[709]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Apr 03 07:47:03 nvb systemd[1]: Started LSB: set CPUFreq kernel parameters. Apr 03 07:47:03 nvb kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Apr 03 07:47:03 nvb kernel: Bluetooth: BNEP filters: protocol multicast Apr 03 07:47:03 nvb kernel: Bluetooth: BNEP socket layer initialized Apr 03 07:47:03 nvb bluetoothd[667]: Bluetooth management interface 1.22 initialized Apr 03 07:47:03 nvb dbus-daemon[552]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.4' (uid=0 pid=667 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Apr 03 07:47:03 nvb kernel: Bluetooth: MGMT ver 1.22 Apr 03 07:47:03 nvb bluetoothd[667]: Failed to set privacy: Rejected (0x0b) Apr 03 07:47:03 nvb systemd[1]: Starting Hostname Service... Apr 03 07:47:03 nvb dbus-daemon[552]: [system] Successfully activated service 'org.freedesktop.hostname1' Apr 03 07:47:03 nvb systemd[1]: Started Hostname Service. Apr 03 07:47:03 nvb haveged[528]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Apr 03 07:47:03 nvb haveged[528]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Apr 03 07:47:03 nvb haveged[528]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00057 Apr 03 07:47:03 nvb haveged[528]: haveged: fills: 0, generated: 0 Apr 03 07:47:03 nvb nmbd[672]: [2024/04/03 07:47:03.923683, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Apr 03 07:47:03 nvb nmbd[672]: started asyncdns process 737 Apr 03 07:47:03 nvb nmbd[672]: [2024/04/03 07:47:03.926121, 0] ../lib/util/become_daemon.c:149(daemon_status) Apr 03 07:47:03 nvb nmbd[672]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Apr 03 07:47:03 nvb nmbd[672]: [2024/04/03 07:47:03.926276, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Apr 03 07:47:03 nvb nmbd[672]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Apr 03 07:47:04 nvb ntpd[713]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Apr 03 07:47:04 nvb wireless.js[560]: Cleaning previous... Apr 03 07:47:04 nvb sudo[745]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 03 07:47:04 nvb sudo[745]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:47:04 nvb sudo[745]: pam_unix(sudo:session): session closed for user root Apr 03 07:47:04 nvb sudo[747]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 03 07:47:04 nvb sudo[747]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:47:04 nvb sudo[747]: pam_unix(sudo:session): session closed for user root Apr 03 07:47:04 nvb wireless.js[560]: Stopped aP Apr 03 07:47:04 nvb ntpd[713]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Apr 03 07:47:04 nvb sudo[754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 03 07:47:04 nvb sudo[754]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:47:05 nvb kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Apr 03 07:47:05 nvb sudo[754]: pam_unix(sudo:session): session closed for user root Apr 03 07:47:05 nvb sudo[756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Apr 03 07:47:05 nvb sudo[756]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:47:05 nvb sudo[756]: pam_unix(sudo:session): session closed for user root Apr 03 07:47:05 nvb sudo[763]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 03 07:47:05 nvb sudo[763]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:47:05 nvb sudo[763]: pam_unix(sudo:session): session closed for user root Apr 03 07:47:05 nvb sudo[765]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Apr 03 07:47:05 nvb sudo[765]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:47:05 nvb ntpd[713]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Apr 03 07:47:06 nvb bthelper[585]: Changing power off succeeded Apr 03 07:47:06 nvb bthelper[585]: [CHG] Controller D8:3A:DD:99:36:33 Powered: no Apr 03 07:47:06 nvb bthelper[585]: [CHG] Controller D8:3A:DD:99:36:33 Discovering: no Apr 03 07:47:06 nvb bthelper[585]: [CHG] Controller D8:3A:DD:99:36:33 Class: 0x00000000 Apr 03 07:47:06 nvb bthelper[585]: [CHG] Controller D8:3A:DD:99:36:33 Class: 0x0000041c Apr 03 07:47:06 nvb bthelper[585]: Changing power on succeeded Apr 03 07:47:06 nvb ntpd[713]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Apr 03 07:47:07 nvb volumio-remote-updater[576]: [2024-04-03 07:47:07] [info] asio async_connect error: system:111 (Connection refused) Apr 03 07:47:07 nvb volumio-remote-updater[576]: [2024-04-03 07:47:07] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 03 07:47:07 nvb volumio-remote-updater[576]: [2024-04-03 07:47:07] [error] handle_connect error: Underlying Transport Error Apr 03 07:47:07 nvb sudo[765]: pam_unix(sudo:session): session closed for user root Apr 03 07:47:07 nvb wireless.js[560]: SETTING APPROPRIATE REG DOMAIN: DE Apr 03 07:47:07 nvb sudo[777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 03 07:47:07 nvb sudo[777]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:47:07 nvb sudo[777]: pam_unix(sudo:session): session closed for user root Apr 03 07:47:07 nvb sudo[779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set DE Apr 03 07:47:07 nvb sudo[779]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:47:07 nvb sudo[779]: pam_unix(sudo:session): session closed for user root Apr 03 07:47:07 nvb wireless.js[560]: SUCCESSFULLY SET NEW REGDOMAIN: DE Apr 03 07:47:07 nvb wireless.js[560]: Start wireless flow Apr 03 07:47:07 nvb wireless.js[560]: Stopped hotspot (if there).. Apr 03 07:47:07 nvb sudo[784]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 03 07:47:07 nvb sudo[784]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:47:07 nvb sudo[784]: pam_unix(sudo:session): session closed for user root Apr 03 07:47:07 nvb sudo[786]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 03 07:47:07 nvb sudo[786]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:47:08 nvb sudo[786]: pam_unix(sudo:session): session closed for user root Apr 03 07:47:08 nvb wireless.js[560]: DHCP IP Apr 03 07:47:08 nvb wireless.js[560]: Start ap Apr 03 07:47:08 nvb wpa_supplicant[788]: Successfully initialized wpa_supplicant Apr 03 07:47:08 nvb sudo[789]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Apr 03 07:47:08 nvb sudo[789]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:47:08 nvb dhcpcd[790]: dev: loaded udev Apr 03 07:47:08 nvb kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Apr 03 07:47:08 nvb dhcpcd-run-hooks[808]: wlan0: starting wpa_supplicant Apr 03 07:47:08 nvb dhcpcd-run-hooks[813]: wlan0: failed to start wpa_supplicant Apr 03 07:47:08 nvb dhcpcd-run-hooks[814]: 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 Apr 03 07:47:08 nvb dhcpcd[790]: wlan0: connected to Access Point `' Apr 03 07:47:08 nvb dhcpcd[790]: no interfaces have a carrier Apr 03 07:47:08 nvb dhcpcd[790]: forked to background, child pid 824 Apr 03 07:47:08 nvb dhcpcd[824]: eth0: waiting for carrier Apr 03 07:47:08 nvb dhcpcd[824]: wlan0: waiting for carrier Apr 03 07:47:08 nvb sudo[789]: pam_unix(sudo:session): session closed for user root Apr 03 07:47:09 nvb wireless.js[560]: trying... Apr 03 07:47:09 nvb sudo[826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 03 07:47:09 nvb sudo[826]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:47:09 nvb sudo[826]: pam_unix(sudo:session): session closed for user root Apr 03 07:47:10 nvb wireless.js[560]: trying... Apr 03 07:47:10 nvb sudo[829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 03 07:47:10 nvb sudo[829]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:47:10 nvb sudo[829]: pam_unix(sudo:session): session closed for user root Apr 03 07:47:11 nvb wpa_supplicant[812]: wlan0: Trying to associate with SSID 'grafenwlan' Apr 03 07:47:11 nvb wireless.js[560]: trying... Apr 03 07:47:11 nvb sudo[832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 03 07:47:11 nvb sudo[832]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:47:11 nvb sudo[832]: pam_unix(sudo:session): session closed for user root Apr 03 07:47:12 nvb wireless.js[560]: trying... Apr 03 07:47:12 nvb sudo[835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 03 07:47:12 nvb sudo[835]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:47:12 nvb sudo[835]: pam_unix(sudo:session): session closed for user root Apr 03 07:47:13 nvb wireless.js[560]: trying... Apr 03 07:47:13 nvb sudo[838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 03 07:47:13 nvb sudo[838]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:47:13 nvb sudo[838]: pam_unix(sudo:session): session closed for user root Apr 03 07:47:14 nvb dhcpcd[824]: wlan0: carrier acquired Apr 03 07:47:14 nvb dhcpcd[824]: wlan0: connected to Access Point `grafenwlan' Apr 03 07:47:14 nvb wpa_supplicant[812]: wlan0: Associated with f0:b0:14:fc:e7:fd Apr 03 07:47:14 nvb wpa_supplicant[812]: wlan0: CTRL-EVENT-CONNECTED - Connection to f0:b0:14:fc:e7:fd completed [id=0 id_str=] Apr 03 07:47:14 nvb wpa_supplicant[812]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Apr 03 07:47:14 nvb wpa_supplicant[812]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Apr 03 07:47:14 nvb dhcpcd[824]: DUID 00:01:00:01:2d:69:1d:a7:d8:3a:dd:80:2b:94 Apr 03 07:47:14 nvb dhcpcd[824]: wlan0: IAID dd:99:36:32 Apr 03 07:47:14 nvb dhcpcd[824]: wlan0: adding address fe80::22b5:d303:2b4f:763c Apr 03 07:47:14 nvb dhcpcd[824]: ipv6_addaddr1: Permission denied Apr 03 07:47:14 nvb dhcpcd[824]: wlan0: carrier lost Apr 03 07:47:14 nvb dhcpcd[824]: wlan0: carrier acquired Apr 03 07:47:14 nvb dhcpcd[824]: wlan0: IAID dd:99:36:32 Apr 03 07:47:14 nvb wireless.js[560]: trying... Apr 03 07:47:14 nvb sudo[853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 03 07:47:14 nvb sudo[853]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:47:14 nvb sudo[853]: pam_unix(sudo:session): session closed for user root Apr 03 07:47:14 nvb dhcpcd[824]: wlan0: rebinding lease of 192.168.178.53 Apr 03 07:47:14 nvb dhcpcd[824]: wlan0: soliciting an IPv6 router Apr 03 07:47:15 nvb volumio-remote-updater[576]: [2024-04-03 07:47:15] [info] asio async_connect error: system:111 (Connection refused) Apr 03 07:47:15 nvb volumio-remote-updater[576]: [2024-04-03 07:47:15] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 03 07:47:15 nvb volumio-remote-updater[576]: [2024-04-03 07:47:15] [error] handle_connect error: Underlying Transport Error Apr 03 07:47:15 nvb wireless.js[560]: trying... Apr 03 07:47:15 nvb sudo[856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 03 07:47:15 nvb sudo[856]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:47:15 nvb sudo[856]: pam_unix(sudo:session): session closed for user root Apr 03 07:47:16 nvb wireless.js[560]: trying... Apr 03 07:47:16 nvb sudo[859]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 03 07:47:16 nvb sudo[859]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:47:16 nvb sudo[859]: pam_unix(sudo:session): session closed for user root Apr 03 07:47:17 nvb wireless.js[560]: trying... Apr 03 07:47:17 nvb sudo[862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 03 07:47:17 nvb sudo[862]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:47:17 nvb sudo[862]: pam_unix(sudo:session): session closed for user root Apr 03 07:47:18 nvb wireless.js[560]: trying... Apr 03 07:47:18 nvb sudo[865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 03 07:47:18 nvb sudo[865]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:47:18 nvb sudo[865]: pam_unix(sudo:session): session closed for user root Apr 03 07:47:19 nvb wireless.js[560]: trying... Apr 03 07:47:19 nvb sudo[868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 03 07:47:19 nvb sudo[868]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:47:19 nvb sudo[868]: pam_unix(sudo:session): session closed for user root Apr 03 07:47:19 nvb dhcpcd[824]: wlan0: probing for an IPv4LL address Apr 03 07:47:19 nvb dhcpcd[824]: wlan0: DHCP lease expired Apr 03 07:47:19 nvb dhcpcd[824]: wlan0: soliciting a DHCP lease Apr 03 07:47:20 nvb wireless.js[560]: trying... Apr 03 07:47:20 nvb sudo[880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 03 07:47:20 nvb sudo[880]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:47:20 nvb sudo[880]: pam_unix(sudo:session): session closed for user root Apr 03 07:47:21 nvb wireless.js[560]: trying... Apr 03 07:47:21 nvb sudo[883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 03 07:47:21 nvb sudo[883]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:47:21 nvb sudo[883]: pam_unix(sudo:session): session closed for user root Apr 03 07:47:22 nvb wireless.js[560]: trying... Apr 03 07:47:22 nvb sudo[886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 03 07:47:22 nvb sudo[886]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:47:22 nvb sudo[886]: pam_unix(sudo:session): session closed for user root Apr 03 07:47:23 nvb wireless.js[560]: trying... Apr 03 07:47:23 nvb sudo[889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 03 07:47:23 nvb sudo[889]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:47:23 nvb sudo[889]: pam_unix(sudo:session): session closed for user root Apr 03 07:47:24 nvb wireless.js[560]: trying... Apr 03 07:47:24 nvb sudo[892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 03 07:47:24 nvb sudo[892]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:47:24 nvb sudo[892]: pam_unix(sudo:session): session closed for user root Apr 03 07:47:24 nvb dhcpcd[824]: wlan0: using IPv4LL address 169.254.126.23 Apr 03 07:47:24 nvb avahi-daemon[570]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.126.23. Apr 03 07:47:24 nvb avahi-daemon[570]: New relevant interface wlan0.IPv4 for mDNS. Apr 03 07:47:24 nvb dhcpcd[824]: wlan0: adding route to 169.254.0.0/16 Apr 03 07:47:24 nvb avahi-daemon[570]: Registering new address record for 169.254.126.23 on wlan0.IPv4. Apr 03 07:47:24 nvb dhcpcd[824]: wlan0: adding default route Apr 03 07:47:25 nvb volumio-remote-updater[576]: [2024-04-03 07:47:25] [info] asio async_connect error: system:111 (Connection refused) Apr 03 07:47:25 nvb volumio-remote-updater[576]: [2024-04-03 07:47:25] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 03 07:47:25 nvb volumio-remote-updater[576]: [2024-04-03 07:47:25] [error] handle_connect error: Underlying Transport Error Apr 03 07:47:25 nvb wireless.js[560]: trying... Apr 03 07:47:25 nvb sudo[906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 03 07:47:25 nvb sudo[906]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:47:25 nvb sudo[906]: pam_unix(sudo:session): session closed for user root Apr 03 07:47:25 nvb wireless.js[560]: Connected to: ----grafenwlan Apr 03 07:47:25 nvb wireless.js[560]: ---- Apr 03 07:47:25 nvb sudo[909]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 03 07:47:25 nvb sudo[909]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:47:25 nvb sudo[909]: pam_unix(sudo:session): session closed for user root Apr 03 07:47:25 nvb wireless.js[560]: ... joined AP, wlan0 IPv4 is 169.254.126.23, ipV6 is undefined Apr 03 07:47:25 nvb wireless.js[560]: It's done! AP Apr 03 07:47:25 nvb systemd[1]: Started Wireless Services. Apr 03 07:47:25 nvb kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Apr 03 07:47:25 nvb systemd[1]: Started Volumio Backend Module. Apr 03 07:47:25 nvb systemd[1]: Started Volumio Cpu Tweaker. Apr 03 07:47:25 nvb volumio-cpu-tweak[915]: Setting RT Priority for mpd Apr 03 07:47:25 nvb volumio-cpu-tweak[915]: pid 35's current scheduling policy: SCHED_OTHER Apr 03 07:47:25 nvb volumio-cpu-tweak[915]: pid 35's current scheduling priority: 0 Apr 03 07:47:25 nvb volumio-cpu-tweak[915]: Setting MPD Affinity Apr 03 07:47:25 nvb volumio-cpu-tweak[915]: pid 3's current affinity mask: f Apr 03 07:47:25 nvb volumio-cpu-tweak[915]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Apr 03 07:47:25 nvb volumio-cpu-tweak[915]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Apr 03 07:47:25 nvb systemd[1]: volumio_cpu_tweak.service: Succeeded. Apr 03 07:47:25 nvb ntpd[713]: Listen normally on 3 wlan0 169.254.126.23:123 Apr 03 07:47:25 nvb ntpd[713]: new interface(s) found: waking up resolver Apr 03 07:47:27 nvb volumio[914]: info: ------------------------------------------- Apr 03 07:47:27 nvb volumio[914]: info: ----- Volumio3 ---- Apr 03 07:47:27 nvb volumio[914]: info: ------------------------------------------- Apr 03 07:47:27 nvb volumio[914]: info: ----- System startup ---- Apr 03 07:47:27 nvb volumio[914]: info: ------------------------------------------- Apr 03 07:47:27 nvb systemd[1]: systemd-fsckd.service: Succeeded. Apr 03 07:47:28 nvb volumio[914]: info: MYVOLUMIO Environment detected Apr 03 07:47:28 nvb volumio[914]: info: Plugin folders cleanup Apr 03 07:47:28 nvb volumio[914]: info: Scanning into folder /volumio/app/plugins/ Apr 03 07:47:28 nvb volumio[914]: info: Scanning category audio_interface Apr 03 07:47:28 nvb volumio[914]: info: Scanning category miscellanea Apr 03 07:47:28 nvb volumio[914]: info: Scanning category music_service Apr 03 07:47:28 nvb volumio[914]: info: Scanning category plugins.json Apr 03 07:47:28 nvb volumio[914]: info: Scanning category system_controller Apr 03 07:47:28 nvb volumio[914]: info: Scanning category user_interface Apr 03 07:47:28 nvb volumio[914]: info: Scanning into folder /data/plugins/ Apr 03 07:47:28 nvb volumio[914]: info: Plugin folders cleanup completed Apr 03 07:47:28 nvb volumio[914]: info: ------------------------------------------- Apr 03 07:47:28 nvb volumio[914]: info: ----- Core plugins startup ---- Apr 03 07:47:28 nvb volumio[914]: info: ------------------------------------------- Apr 03 07:47:28 nvb volumio[914]: info: Loading plugins from folder /volumio/app/plugins/ Apr 03 07:47:28 nvb volumio[914]: info: Adding plugin upnp to MyMusic Plugins Apr 03 07:47:28 nvb volumio[914]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 03 07:47:28 nvb volumio[914]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 03 07:47:28 nvb volumio[914]: info: Loading plugins from folder /data/plugins/ Apr 03 07:47:28 nvb volumio[914]: info: Loading plugin "system"... Apr 03 07:47:28 nvb volumio[914]: info: Loading plugin "appearance"... Apr 03 07:47:28 nvb nmbd[672]: [2024/04/03 07:47:28.932401, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 03 07:47:28 nvb systemd[1]: Started Samba NMB Daemon. Apr 03 07:47:28 nvb nmbd[672]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Apr 03 07:47:28 nvb systemd[1]: Starting Samba Winbind Daemon... Apr 03 07:47:29 nvb wpa_supplicant[812]: RRM: Ignoring radio measurement request: Not RRM network Apr 03 07:47:29 nvb winbindd[944]: [2024/04/03 07:47:29.245006, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Apr 03 07:47:29 nvb winbindd[944]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Apr 03 07:47:29 nvb winbindd[944]: [2024/04/03 07:47:29.283145, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 03 07:47:29 nvb winbindd[944]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Apr 03 07:47:29 nvb systemd[1]: Started Samba Winbind Daemon. Apr 03 07:47:29 nvb systemd[1]: Starting Samba SMB Daemon... Apr 03 07:47:29 nvb volumio[914]: info: Loading plugin "network"... Apr 03 07:47:29 nvb volumio[914]: info: Refreshing Cached IP Addresses Apr 03 07:47:29 nvb sudo[951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 03 07:47:29 nvb sudo[951]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:47:29 nvb volumio[914]: info: Loading plugin "services"... Apr 03 07:47:29 nvb sudo[953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 03 07:47:29 nvb sudo[953]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:47:29 nvb sudo[951]: pam_unix(sudo:session): session closed for user root Apr 03 07:47:29 nvb volumio[914]: info: Loading plugin "alsa_controller"... Apr 03 07:47:29 nvb sudo[953]: pam_unix(sudo:session): session closed for user root Apr 03 07:47:29 nvb sudo[957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 03 07:47:29 nvb sudo[957]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:47:29 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 03 07:47:29 nvb volumio[914]: info: Loading plugin "wizard"... Apr 03 07:47:29 nvb volumio[914]: info: Loading plugin "networkfs"... Apr 03 07:47:29 nvb volumio[914]: info: Starting Udev Watcher for removable devices Apr 03 07:47:29 nvb volumio[914]: info: Ignoring mount for partition: boot Apr 03 07:47:29 nvb volumio[914]: info: Ignoring mount for partition: volumio Apr 03 07:47:29 nvb volumio[914]: info: Ignoring mount for partition: volumio_data Apr 03 07:47:29 nvb volumio[914]: error: Cannot associate FS Label, not mounting Apr 03 07:47:29 nvb volumio[914]: info: Mounting Device Crucial_X9 Apr 03 07:47:29 nvb sudo[968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda2 /mnt/USB/Crucial_X9 -o noatime Apr 03 07:47:29 nvb sudo[968]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:47:29 nvb winbindd[944]: [2024/04/03 07:47:29.681234, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Apr 03 07:47:29 nvb winbindd[944]: res_names->count = 3, expected 4 Apr 03 07:47:29 nvb systemd[1]: Started Samba SMB Daemon. Apr 03 07:47:29 nvb systemd[1]: Reached target Multi-User System. Apr 03 07:47:29 nvb systemd[1]: Reached target Graphical Interface. Apr 03 07:47:29 nvb smbd[949]: [2024/04/03 07:47:29.734817, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 03 07:47:29 nvb smbd[949]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Apr 03 07:47:29 nvb mount.exfat[972]: volume was not unmounted cleanly Apr 03 07:47:29 nvb systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 03 07:47:29 nvb systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 03 07:47:29 nvb systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 03 07:47:29 nvb systemd[1]: Startup finished in 10.592s (kernel) + 39.666s (userspace) = 50.259s. Apr 03 07:47:29 nvb sudo[968]: pam_unix(sudo:session): session closed for user root Apr 03 07:47:29 nvb volumio[914]: WARN: volume was not unmounted cleanly. Apr 03 07:47:29 nvb kernel: fuse: init (API version 7.37) Apr 03 07:47:29 nvb volumio[914]: error: Cannot associate FS Label, not mounting Apr 03 07:47:29 nvb systemd[1]: Mounting FUSE Control File System... Apr 03 07:47:29 nvb systemd[1]: Mounted FUSE Control File System. Apr 03 07:47:29 nvb volumio[914]: info: Mounting Device Lossless4tb Apr 03 07:47:29 nvb sudo[986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb2 /mnt/USB/Lossless4tb -o noatime Apr 03 07:47:29 nvb sudo[986]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:47:30 nvb mount.exfat[988]: volume was not unmounted cleanly Apr 03 07:47:30 nvb sudo[986]: pam_unix(sudo:session): session closed for user root Apr 03 07:47:30 nvb volumio[914]: WARN: volume was not unmounted cleanly. Apr 03 07:47:30 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 03 07:47:30 nvb volumio[914]: info: Loading plugin "volumio_command_line_client"... Apr 03 07:47:30 nvb volumio[914]: info: Loading plugin "upnp"... Apr 03 07:47:30 nvb volumio[914]: info: [1712123250134] Starting Upmpd Daemon Apr 03 07:47:30 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 03 07:47:30 nvb volumio[914]: info: Loading plugin "my_music"... Apr 03 07:47:30 nvb volumio[914]: info: Loading plugin "mpd"... Apr 03 07:47:30 nvb volumio[914]: info: Loading plugin "upnp_browser"... Apr 03 07:47:30 nvb volumio[914]: info: Loading plugin "alarm-clock"... Apr 03 07:47:30 nvb volumio[914]: info: Loading plugin "airplay_emulation"... Apr 03 07:47:30 nvb volumio[914]: info: Starting Shairport Sync Apr 03 07:47:30 nvb volumio[914]: info: Loading plugin "last_100"... Apr 03 07:47:30 nvb volumio[914]: info: Loading plugin "webradio"... Apr 03 07:47:30 nvb volumio[914]: info: Loading plugin "i2s_dacs"... Apr 03 07:47:31 nvb volumio[914]: info: Loading plugin "volumiodiscovery"... Apr 03 07:47:31 nvb volumio[914]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 03 07:47:31 nvb volumio[914]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 03 07:47:31 nvb node[914]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 03 07:47:31 nvb volumio[914]: *** WARNING *** For more information see Apr 03 07:47:31 nvb volumio[914]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 03 07:47:31 nvb volumio[914]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 03 07:47:31 nvb volumio[914]: *** WARNING *** For more information see Apr 03 07:47:31 nvb node[914]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 03 07:47:31 nvb node[914]: *** WARNING *** For more information see Apr 03 07:47:31 nvb node[914]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 03 07:47:31 nvb node[914]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 03 07:47:31 nvb node[914]: *** WARNING *** For more information see Apr 03 07:47:31 nvb volumio[914]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 03 07:47:31 nvb volumio[914]: info: Discovery: Started advertising with name: nvb Apr 03 07:47:31 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 03 07:47:31 nvb volumio[914]: info: Loading plugin "outputs"... Apr 03 07:47:31 nvb volumio[914]: info: Loading plugin "albumart"... Apr 03 07:47:31 nvb volumio[914]: info: Plugin example_plugin is not enabled Apr 03 07:47:31 nvb volumio[914]: info: Loading plugin "inputs"... Apr 03 07:47:31 nvb volumio[914]: info: Loading plugin "updater_comm"... Apr 03 07:47:31 nvb volumio[914]: info: Plugin mpdemulation is not enabled Apr 03 07:47:31 nvb volumio[914]: info: Loading plugin "rest_api"... Apr 03 07:47:31 nvb volumio[914]: info: Loading plugin "websocket"... Apr 03 07:47:31 nvb volumio[914]: info: Loading i18n strings for locale de Apr 03 07:47:31 nvb volumio[914]: Updating browse sources language Apr 03 07:47:31 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 03 07:47:31 nvb volumio[914]: Forking 3 albumart workers Apr 03 07:47:31 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 07:47:31 nvb volumio[914]: info: CoreCommandRouter::initPlayerControls Apr 03 07:47:31 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 07:47:31 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 07:47:31 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 07:47:31 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 07:47:31 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 07:47:31 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 07:47:31 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 07:47:31 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 07:47:31 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 07:47:31 nvb volumio[914]: Express server listening on port 3000 Apr 03 07:47:31 nvb volumio[914]: [Metrics] WebUI: 4s 856.72ms Apr 03 07:47:31 nvb volumio[914]: info: CoreStateMachine::resetVolumioState Apr 03 07:47:31 nvb volumio[914]: info: CoreStateMachine::getcurrentVolume Apr 03 07:47:31 nvb volumio[914]: info: CoreCommandRouter::volumioRetrievevolume Apr 03 07:47:31 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 07:47:31 nvb volumio[914]: info: Reloading queue from file Apr 03 07:47:31 nvb volumio[914]: info: CoreStateMachine::pushState Apr 03 07:47:31 nvb volumio[914]: info: CorePlayQueue::getTrack 0 Apr 03 07:47:31 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:47:31 nvb volumio[914]: info: CoreCommandRouter::volumioPushState Apr 03 07:47:31 nvb volumio[914]: info: CoreStateMachine::updateTrackBlock Apr 03 07:47:31 nvb volumio[914]: info: CorePlayQueue::getTrackBlock Apr 03 07:47:31 nvb volumio[914]: info: CoreCommandRouter::volumioRetrievevolume Apr 03 07:47:31 nvb volumio[914]: info: CoreStateMachine::setRepeat true single undefined Apr 03 07:47:31 nvb volumio[914]: info: CoreStateMachine::pushState Apr 03 07:47:31 nvb volumio[914]: info: CorePlayQueue::getTrack 0 Apr 03 07:47:31 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 07:47:31 nvb volumio[914]: info: CoreCommandRouter::volumioPushState Apr 03 07:47:31 nvb volumio[914]: info: CoreStateMachine::setRandom null Apr 03 07:47:31 nvb volumio[914]: info: CoreStateMachine::pushState Apr 03 07:47:31 nvb volumio[914]: info: CorePlayQueue::getTrack 0 Apr 03 07:47:31 nvb volumio[914]: info: CoreCommandRouter::volumioPushState Apr 03 07:47:31 nvb volumio[914]: info: Setting Device type: Raspberry PI Apr 03 07:47:31 nvb volumio[914]: info: CoreStateMachine::pushState Apr 03 07:47:31 nvb volumio[914]: info: CorePlayQueue::getTrack 0 Apr 03 07:47:31 nvb volumio[914]: info: CoreCommandRouter::volumioPushState Apr 03 07:47:31 nvb sh[470]: timed out Apr 03 07:47:31 nvb dhcpcd[514]: timed out Apr 03 07:47:31 nvb sh[470]: dhcpcd exited Apr 03 07:47:31 nvb dhcpcd[514]: dhcpcd exited Apr 03 07:47:31 nvb sh[470]: ifup: failed to bring up eth0 Apr 03 07:47:31 nvb systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Apr 03 07:47:31 nvb systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Apr 03 07:47:31 nvb volumio[914]: Starting albumart workers Apr 03 07:47:31 nvb volumio[914]: Starting albumart workers Apr 03 07:47:31 nvb volumio[914]: Starting albumart workers Apr 03 07:47:33 nvb sudo[957]: pam_unix(sudo:session): session closed for user root Apr 03 07:47:33 nvb systemd[1]: systemd-hostnamed.service: Succeeded. Apr 03 07:47:35 nvb volumio-remote-updater[576]: [2024-04-03 07:47:35] [connect] Successful connection Apr 03 07:47:35 nvb volumio-remote-updater[576]: [2024-04-03 07:47:35] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1712123255 101 Apr 03 07:47:35 nvb volumio[914]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Apr 03 07:47:35 nvb wpa_supplicant[812]: RRM: Ignoring radio measurement request: Not RRM network Apr 03 07:47:37 nvb wpa_supplicant[812]: RRM: Ignoring radio measurement request: Not RRM network Apr 03 07:47:39 nvb wpa_supplicant[812]: RRM: Ignoring radio measurement request: Not RRM network Apr 03 07:47:40 nvb dhcpcd[824]: wlan0: carrier lost Apr 03 07:47:40 nvb wpa_supplicant[812]: wlan0: Associated with f0:b0:14:fc:e7:fe Apr 03 07:47:40 nvb wpa_supplicant[812]: wlan0: CTRL-EVENT-CONNECTED - Connection to f0:b0:14:fc:e7:fe completed [id=0 id_str=] Apr 03 07:47:40 nvb wpa_supplicant[812]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Apr 03 07:47:40 nvb avahi-daemon[570]: Withdrawing address record for 169.254.126.23 on wlan0. Apr 03 07:47:40 nvb avahi-daemon[570]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.126.23. Apr 03 07:47:40 nvb avahi-daemon[570]: Interface wlan0.IPv4 no longer relevant for mDNS. Apr 03 07:47:40 nvb dhcpcd[824]: wlan0: deleting route to 169.254.0.0/16 Apr 03 07:47:40 nvb dhcpcd[824]: wlan0: deleting default route Apr 03 07:47:40 nvb volumio[914]: info: Discovery: A device disappeared from network Apr 03 07:47:40 nvb volumio[914]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Apr 03 07:47:40 nvb sudo[1093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 03 07:47:40 nvb sudo[1093]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:47:40 nvb sudo[1093]: pam_unix(sudo:session): session closed for user root Apr 03 07:47:40 nvb sudo[1100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 03 07:47:40 nvb sudo[1100]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:47:40 nvb sudo[1100]: pam_unix(sudo:session): session closed for user root Apr 03 07:47:40 nvb sudo[1103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 03 07:47:40 nvb sudo[1103]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:47:40 nvb dhcpcd[824]: wlan0: carrier acquired Apr 03 07:47:40 nvb dhcpcd[824]: wlan0: IAID dd:99:36:32 Apr 03 07:47:40 nvb systemd[1]: Started UPnP Renderer front-end to MPD. Apr 03 07:47:40 nvb sudo[1103]: pam_unix(sudo:session): session closed for user root Apr 03 07:47:40 nvb volumio[914]: info: Upmpdcli Daemon Started Apr 03 07:47:40 nvb volumio[914]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Apr 03 07:47:40 nvb dhcpcd[824]: wlan0: soliciting an IPv6 router Apr 03 07:47:41 nvb dhcpcd[824]: wlan0: soliciting a DHCP lease Apr 03 07:47:41 nvb dhcpcd[824]: wlan0: offered 192.168.178.53 from 192.168.178.1 Apr 03 07:47:41 nvb dhcpcd[824]: wlan0: probing address 192.168.178.53/24 Apr 03 07:47:41 nvb ntpd[713]: Deleting interface #3 wlan0, 169.254.126.23#123, interface stats: received=0, sent=0, dropped=0, active_time=16 secs Apr 03 07:47:43 nvb systemd[1]: Stopped target Local File Systems. Apr 03 07:47:43 nvb kernel: I/O error, dev mmcblk0, sector 188416 op 0x1:(WRITE) flags 0x103000 phys_seg 1 prio class 2 Apr 03 07:47:43 nvb kernel: mmc0: card e624 removed Apr 03 07:47:43 nvb kernel: Buffer I/O error on dev mmcblk0p2, logical block 0, lost async page write Apr 03 07:47:43 nvb kernel: EXT4-fs warning (device mmcblk0p3): ext4_end_bio:347: I/O error 10 writing to inode 4653363 starting block 3772058) Apr 03 07:47:43 nvb kernel: Buffer I/O error on device mmcblk0p3, logical block 1037978 Apr 03 07:47:43 nvb kernel: EXT4-fs warning (device mmcblk0p3): ext4_end_bio:347: I/O error 10 writing to inode 4653371 starting block 21994006) Apr 03 07:47:43 nvb kernel: Buffer I/O error on device mmcblk0p3, logical block 19259926 Apr 03 07:47:43 nvb kernel: EXT4-fs warning (device mmcblk0p3): ext4_end_bio:347: I/O error 10 writing to inode 4653371 starting block 21995529) Apr 03 07:47:43 nvb kernel: Buffer I/O error on device mmcblk0p3, logical block 19261449 Apr 03 07:47:43 nvb kernel: Buffer I/O error on device mmcblk0p3, logical block 19261450 Apr 03 07:47:43 nvb kernel: Buffer I/O error on device mmcblk0p3, logical block 19261451 Apr 03 07:47:43 nvb kernel: Buffer I/O error on device mmcblk0p3, logical block 19261452 Apr 03 07:47:43 nvb kernel: EXT4-fs warning (device mmcblk0p3): ext4_end_bio:347: I/O error 10 writing to inode 4653371 starting block 21996119) Apr 03 07:47:43 nvb kernel: Buffer I/O error on device mmcblk0p3, logical block 19262039 Apr 03 07:47:43 nvb kernel: Buffer I/O error on device mmcblk0p3, logical block 19262040 Apr 03 07:47:43 nvb kernel: Buffer I/O error on device mmcblk0p3, logical block 19262041 Apr 03 07:47:43 nvb kernel: Buffer I/O error on device mmcblk0p3, logical block 19262042 Apr 03 07:47:43 nvb kernel: EXT4-fs warning (device mmcblk0p3): ext4_end_bio:347: I/O error 10 writing to inode 4653370 starting block 22118402) Apr 03 07:47:43 nvb kernel: EXT4-fs warning (device mmcblk0p3): ext4_end_bio:347: I/O error 10 writing to inode 4658999 starting block 22118407) Apr 03 07:47:43 nvb kernel: EXT4-fs warning (device mmcblk0p3): ext4_end_bio:347: I/O error 10 writing to inode 4653363 starting block 3767809) Apr 03 07:47:43 nvb kernel: EXT4-fs warning (device mmcblk0p3): ext4_end_bio:347: I/O error 10 writing to inode 4653363 starting block 3767812) Apr 03 07:47:43 nvb kernel: JBD2: Detected IO errors while flushing file data on mmcblk0p3-8 Apr 03 07:47:43 nvb kernel: Aborting journal on device mmcblk0p3-8. Apr 03 07:47:43 nvb kernel: JBD2: I/O error when updating journal superblock for mmcblk0p3-8. Apr 03 07:47:43 nvb kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #4653577: comm node: reading directory lblock 0 Apr 03 07:47:43 nvb kernel: EXT4-fs (mmcblk0p3): previous I/O error to superblock detected Apr 03 07:47:43 nvb kernel: EXT4-fs (mmcblk0p3): I/O error while writing superblock Apr 03 07:47:43 nvb kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #4653577: comm node: reading directory lblock 0 Apr 03 07:47:43 nvb kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #4653577: comm node: reading directory lblock 0 Apr 03 07:47:43 nvb kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #4653354: comm (umount): reading directory lblock 0 Apr 03 07:47:43 nvb systemd[1]: Unmounting /boot... Apr 03 07:47:43 nvb volumio[914]: info: Partition removed: {"syspath":"/sys/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:e624/block/mmcblk0/mmcblk0p1","ACTION":"remove","DEVLINKS":"/dev/disk/by-uuid/F102-DE29 /dev/disk/by-path/platform-fe340000.mmc-part1 /dev/disk/by-partuuid/8ca0658c-01 /dev/disk/by-id/mmc-SU32G_0x186a3b0e-part1 /dev/disk/by-label/boot","DEVNAME":"/dev/mmcblk0p1","DEVPATH":"/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:e624/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":"F102-DE29","ID_FS_UUID_ENC":"F102-DE29","ID_FS_VERSION":"FAT16","ID_NAME":"SU32G","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":"8ca0658c-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"8ca0658c","ID_PATH":"platform-fe340000.mmc","ID_PATH_TAG":"platform-fe340000_mmc","ID_SERIAL":"0x186a3b0e","MAJOR":"179","MINOR":"1","PARTN":"1","SEQNUM":"1816","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"14165412"} Apr 03 07:47:43 nvb systemd[1109]: boot.mount: Failed to execute command: Input/output error Apr 03 07:47:43 nvb volumio[914]: info: Partition removed: {"syspath":"/sys/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:e624/block/mmcblk0/mmcblk0p2","ACTION":"remove","DEVLINKS":"/dev/disk/by-path/platform-fe340000.mmc-part2 /dev/disk/by-uuid/db7775c5-9234-4b9a-955e-7341a245d498 /dev/disk/by-id/mmc-SU32G_0x186a3b0e-part2 /dev/disk/by-partuuid/8ca0658c-02 /dev/disk/by-label/volumio","DEVNAME":"/dev/mmcblk0p2","DEVPATH":"/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:e624/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":"db7775c5-9234-4b9a-955e-7341a245d498","ID_FS_UUID_ENC":"db7775c5-9234-4b9a-955e-7341a245d498","ID_FS_VERSION":"1.0","ID_NAME":"SU32G","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":"8ca0658c-02","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"8ca0658c","ID_PATH":"platform-fe340000.mmc","ID_PATH_TAG":"platform-fe340000_mmc","ID_SERIAL":"0x186a3b0e","MAJOR":"179","MINOR":"2","PARTN":"2","SEQNUM":"1817","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"14150058"} Apr 03 07:47:43 nvb systemd[1109]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Apr 03 07:47:43 nvb volumio[914]: info: Partition removed: {"syspath":"/sys/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:e624/block/mmcblk0/mmcblk0p3","ACTION":"remove","DEVLINKS":"/dev/disk/by-partuuid/8ca0658c-03 /dev/disk/by-uuid/4112086d-38d3-4206-a7af-ef93bf4e4008 /dev/disk/by-id/mmc-SU32G_0x186a3b0e-part3 /dev/disk/by-label/volumio_data /dev/disk/by-path/platform-fe340000.mmc-part3","DEVNAME":"/dev/mmcblk0p3","DEVPATH":"/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:e624/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":"4112086d-38d3-4206-a7af-ef93bf4e4008","ID_FS_UUID_ENC":"4112086d-38d3-4206-a7af-ef93bf4e4008","ID_FS_VERSION":"1.0","ID_NAME":"SU32G","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":"56865792","ID_PART_ENTRY_TYPE":"0x83","ID_PART_ENTRY_UUID":"8ca0658c-03","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"8ca0658c","ID_PATH":"platform-fe340000.mmc","ID_PATH_TAG":"platform-fe340000_mmc","ID_SERIAL":"0x186a3b0e","MAJOR":"179","MINOR":"3","PARTN":"3","SEQNUM":"1818","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"14092658"} Apr 03 07:47:43 nvb systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Apr 03 07:47:43 nvb systemd[1]: Failed unmounting /boot. Apr 03 07:47:43 nvb systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Apr 03 07:47:43 nvb systemd[1]: Unmounting /boot... Apr 03 07:47:43 nvb systemd[1111]: boot.mount: Failed to execute command: Input/output error Apr 03 07:47:43 nvb systemd[1111]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Apr 03 07:47:43 nvb systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Apr 03 07:47:43 nvb systemd[1]: Failed unmounting /boot. Apr 03 07:47:43 nvb systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Apr 03 07:47:43 nvb systemd[1]: Unmounting /boot... Apr 03 07:47:43 nvb systemd[1112]: boot.mount: Failed to execute command: Input/output error Apr 03 07:47:43 nvb systemd[1112]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Apr 03 07:47:43 nvb kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #4653354: comm (umount): reading directory lblock 0 Apr 03 07:47:43 nvb kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #4653354: comm (umount): reading directory lblock 0 Apr 03 07:47:43 nvb systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Apr 03 07:47:43 nvb systemd[1]: Failed unmounting /boot. Apr 03 07:47:43 nvb systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Apr 03 07:47:43 nvb systemd[1]: Unmounting /boot... Apr 03 07:47:43 nvb systemd[1113]: boot.mount: Failed to execute command: Input/output error Apr 03 07:47:43 nvb systemd[1113]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Apr 03 07:47:43 nvb systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Apr 03 07:47:43 nvb systemd[1]: Failed unmounting /boot. Apr 03 07:47:43 nvb systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Apr 03 07:47:43 nvb kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #4653354: comm (umount): reading directory lblock 0 Apr 03 07:47:43 nvb systemd[1]: Unmounting /boot... Apr 03 07:47:43 nvb systemd[1114]: boot.mount: Failed to execute command: Input/output error Apr 03 07:47:43 nvb systemd[1114]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Apr 03 07:47:43 nvb systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Apr 03 07:47:43 nvb systemd[1]: Failed unmounting /boot. Apr 03 07:47:43 nvb systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Apr 03 07:47:43 nvb systemd[1]: Unmounting /boot... Apr 03 07:47:43 nvb systemd[1115]: boot.mount: Failed to execute command: Input/output error Apr 03 07:47:43 nvb systemd[1115]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Apr 03 07:47:43 nvb kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #4653354: comm (umount): reading directory lblock 0 Apr 03 07:47:43 nvb kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #4653354: comm (umount): reading directory lblock 0 Apr 03 07:47:43 nvb systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Apr 03 07:47:43 nvb systemd[1]: Failed unmounting /boot. Apr 03 07:47:43 nvb systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Apr 03 07:47:43 nvb systemd[1]: Unmounting /boot... Apr 03 07:47:43 nvb systemd[1116]: boot.mount: Failed to execute command: Input/output error Apr 03 07:47:43 nvb systemd[1116]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Apr 03 07:47:43 nvb systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Apr 03 07:47:43 nvb systemd[1]: Failed unmounting /boot. Apr 03 07:47:43 nvb systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Apr 03 07:47:43 nvb kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #4653354: comm (umount): reading directory lblock 0 Apr 03 07:47:43 nvb systemd[1]: Unmounting /boot... Apr 03 07:47:43 nvb systemd[1117]: boot.mount: Failed to execute command: Input/output error Apr 03 07:47:43 nvb systemd[1117]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Apr 03 07:47:43 nvb systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Apr 03 07:47:43 nvb systemd[1]: Failed unmounting /boot. Apr 03 07:47:43 nvb systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Apr 03 07:47:43 nvb systemd[1]: Unmounting /boot... Apr 03 07:47:43 nvb systemd[1118]: boot.mount: Failed to execute command: Input/output error Apr 03 07:47:43 nvb systemd[1118]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Apr 03 07:47:43 nvb systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Apr 03 07:47:43 nvb systemd[1]: Failed unmounting /boot. Apr 03 07:47:43 nvb systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Apr 03 07:47:43 nvb systemd[1]: Unmounting /boot... Apr 03 07:47:43 nvb systemd[1119]: boot.mount: Failed to execute command: Input/output error Apr 03 07:47:43 nvb systemd[1119]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Apr 03 07:47:43 nvb systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Apr 03 07:47:43 nvb systemd[1]: Failed unmounting /boot. Apr 03 07:47:43 nvb systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Apr 03 07:47:43 nvb systemd[1]: Unmounting /boot... Apr 03 07:47:43 nvb systemd[1120]: boot.mount: Failed to execute command: Input/output error Apr 03 07:47:43 nvb systemd[1120]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Apr 03 07:47:43 nvb systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Apr 03 07:47:43 nvb systemd[1]: Failed unmounting /boot. Apr 03 07:47:43 nvb systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Apr 03 07:47:43 nvb systemd[1]: Unmounting /boot... Apr 03 07:47:43 nvb systemd[1121]: boot.mount: Failed to execute command: Input/output error Apr 03 07:47:43 nvb systemd[1121]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Apr 03 07:47:43 nvb systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Apr 03 07:47:43 nvb systemd[1]: Failed unmounting /boot. Apr 03 07:47:43 nvb systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Apr 03 07:47:43 nvb systemd[1]: Unmounting /boot... Apr 03 07:47:43 nvb systemd[1122]: boot.mount: Failed to execute command: Input/output error Apr 03 07:47:43 nvb systemd[1122]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Apr 03 07:47:43 nvb systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Apr 03 07:47:43 nvb systemd[1]: Failed unmounting /boot. Apr 03 07:47:43 nvb systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Apr 03 07:47:43 nvb systemd[1]: Unmounting /boot... Apr 03 07:47:43 nvb systemd[1123]: boot.mount: Failed to execute command: Input/output error Apr 03 07:47:43 nvb systemd[1123]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Apr 03 07:47:43 nvb systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Apr 03 07:47:43 nvb systemd[1]: Failed unmounting /boot. Apr 03 07:47:43 nvb systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Apr 03 07:47:43 nvb systemd[1]: Unmounting /boot... Apr 03 07:47:43 nvb systemd[1124]: boot.mount: Failed to execute command: Input/output error Apr 03 07:47:43 nvb systemd[1124]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Apr 03 07:47:43 nvb systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Apr 03 07:47:43 nvb systemd[1]: Failed unmounting /boot. Apr 03 07:47:43 nvb systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Apr 03 07:47:43 nvb systemd[1]: Unmounting /boot... Apr 03 07:47:43 nvb systemd[1125]: boot.mount: Failed to execute command: Input/output error Apr 03 07:47:43 nvb systemd[1125]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Apr 03 07:47:43 nvb systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Apr 03 07:47:43 nvb systemd[1]: Failed unmounting /boot. Apr 03 07:47:43 nvb systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Apr 03 07:47:43 nvb systemd[1]: Unmounting /boot... Apr 03 07:47:43 nvb systemd[1126]: boot.mount: Failed to execute command: Input/output error Apr 03 07:47:43 nvb systemd[1126]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Apr 03 07:47:43 nvb systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Apr 03 07:47:43 nvb systemd[1]: Failed unmounting /boot. Apr 03 07:47:43 nvb systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device, but not stopping since we tried this too often recently. Apr 03 07:47:43 nvb systemd[1]: systemd-fsck@dev-mmcblk0p1.service: Succeeded. Apr 03 07:47:43 nvb systemd[1]: Stopped File System Check on /dev/mmcblk0p1. Apr 03 07:47:43 nvb kernel: mmc0: error -110 whilst initialising SD card Apr 03 07:47:44 nvb volumio[914]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Apr 03 07:47:45 nvb dhcpcd[824]: wlan0: leased 192.168.178.53 for 864000 seconds Apr 03 07:47:45 nvb dhcpcd[824]: dhcp_bind: Input/output error Apr 03 07:47:45 nvb avahi-daemon[570]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.53. Apr 03 07:47:45 nvb avahi-daemon[570]: New relevant interface wlan0.IPv4 for mDNS. Apr 03 07:47:45 nvb dhcpcd[824]: wlan0: adding route to 192.168.178.0/24 Apr 03 07:47:45 nvb avahi-daemon[570]: Registering new address record for 192.168.178.53 on wlan0.IPv4. Apr 03 07:47:45 nvb dhcpcd[824]: wlan0: adding default route via 192.168.178.1 Apr 03 07:47:45 nvb kernel: EXT4-fs (mmcblk0p3): Remounting filesystem read-only Apr 03 07:47:46 nvb wpa_supplicant[812]: RRM: Ignoring radio measurement request: Not RRM network Apr 03 07:47:46 nvb volumio[914]: info: Discovery: adding 51b9f286-d8a2-475f-8fc7-a3b2545e52d8 Apr 03 07:47:46 nvb volumio[914]: info: Discovery: Found device nvb Apr 03 07:47:46 nvb volumio[914]: info: CoreCommandRouter::volumioGetState Apr 03 07:47:46 nvb volumio[914]: info: CorePlayQueue::getTrack 0 Apr 03 07:47:46 nvb volumio[914]: info: Discovery: this is already registered, 51b9f286-d8a2-475f-8fc7-a3b2545e52d8 Apr 03 07:47:46 nvb volumio[914]: info: Discovery: Found device nvb Apr 03 07:47:46 nvb volumio[914]: info: CoreCommandRouter::volumioGetState Apr 03 07:47:46 nvb volumio[914]: info: CorePlayQueue::getTrack 0 Apr 03 07:47:46 nvb dhcpcd[824]: wlan0: carrier lost Apr 03 07:47:46 nvb wpa_supplicant[812]: wlan0: Associated with 98:9b:cb:03:24:b9 Apr 03 07:47:46 nvb wpa_supplicant[812]: wlan0: CTRL-EVENT-CONNECTED - Connection to 98:9b:cb:03:24:b9 completed [id=0 id_str=] Apr 03 07:47:46 nvb wpa_supplicant[812]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Apr 03 07:47:46 nvb avahi-daemon[570]: Withdrawing address record for 192.168.178.53 on wlan0. Apr 03 07:47:46 nvb avahi-daemon[570]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.53. Apr 03 07:47:46 nvb dhcpcd[824]: wlan0: deleting route to 192.168.178.0/24 Apr 03 07:47:46 nvb dhcpcd[824]: wlan0: deleting default route via 192.168.178.1 Apr 03 07:47:46 nvb avahi-daemon[570]: Interface wlan0.IPv4 no longer relevant for mDNS. Apr 03 07:47:46 nvb volumio[914]: info: Discovery: A device disappeared from network Apr 03 07:47:46 nvb volumio[914]: info: Discovery: Device nvb disappeared from network Apr 03 07:47:46 nvb dhcpcd[824]: wlan0: carrier acquired Apr 03 07:47:46 nvb dhcpcd[824]: wlan0: IAID dd:99:36:32 Apr 03 07:47:46 nvb dhcpcd[824]: wlan0: soliciting a DHCP lease Apr 03 07:47:46 nvb dhcpcd[824]: wlan0: offered 192.168.178.53 from 192.168.178.1 Apr 03 07:47:47 nvb dhcpcd[824]: wlan0: probing address 192.168.178.53/24 Apr 03 07:47:47 nvb dhcpcd[824]: wlan0: soliciting an IPv6 router Apr 03 07:47:50 nvb volumio[914]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Apr 03 07:47:50 nvb kernel: mmc0: new high speed SDHC card at address e624 Apr 03 07:47:50 nvb kernel: mmcblk0: mmc0:e624 SU32G 29.7 GiB Apr 03 07:47:50 nvb kernel: mmcblk0: p1 p2 p3 Apr 03 07:47:50 nvb kernel: mmcblk0: mmc0:e624 SU32G 29.7 GiB (quirks 0x00004000) Apr 03 07:47:51 nvb volumio[914]: info: Ignoring mount for partition: volumio Apr 03 07:47:51 nvb volumio[914]: info: Ignoring mount for partition: volumio_data Apr 03 07:47:51 nvb volumio[914]: info: Ignoring mount for partition: boot Apr 03 07:47:51 nvb dhcpcd[824]: wlan0: leased 192.168.178.53 for 864000 seconds Apr 03 07:47:51 nvb dhcpcd[824]: dhcp_bind: Read-only file system Apr 03 07:47:51 nvb avahi-daemon[570]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.53. Apr 03 07:47:51 nvb avahi-daemon[570]: New relevant interface wlan0.IPv4 for mDNS. Apr 03 07:47:51 nvb dhcpcd[824]: wlan0: adding route to 192.168.178.0/24 Apr 03 07:47:51 nvb avahi-daemon[570]: Registering new address record for 192.168.178.53 on wlan0.IPv4. Apr 03 07:47:51 nvb dhcpcd[824]: wlan0: adding default route via 192.168.178.1 Apr 03 07:47:52 nvb volumio[914]: info: Partition removed: {"syspath":"/sys/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:e624/block/mmcblk0/mmcblk0p1","ACTION":"remove","DEVLINKS":"/dev/disk/by-uuid/F102-DE29 /dev/disk/by-path/platform-fe340000.mmc-part1 /dev/disk/by-partuuid/8ca0658c-01 /dev/disk/by-id/mmc-SU32G_0x186a3b0e-part1 /dev/disk/by-label/boot","DEVNAME":"/dev/mmcblk0p1","DEVPATH":"/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:e624/block/mmcblk0/mmcblk0p1","DEVTYPE":"partition","DISKSEQ":"29","ID_FS_LABEL":"boot","ID_FS_LABEL_ENC":"boot","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"F102-DE29","ID_FS_UUID_ENC":"F102-DE29","ID_FS_VERSION":"FAT16","ID_NAME":"SU32G","ID_PART_ENTRY_DISK":"179:32","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":"8ca0658c-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"8ca0658c","ID_PATH":"platform-fe340000.mmc","ID_PATH_TAG":"platform-fe340000_mmc","ID_SERIAL":"0x186a3b0e","MAJOR":"179","MINOR":"33","PARTN":"1","SEQNUM":"1830","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"71699317"} Apr 03 07:47:52 nvb volumio[914]: info: Partition removed: {"syspath":"/sys/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:e624/block/mmcblk0/mmcblk0p2","ACTION":"remove","DEVLINKS":"/dev/disk/by-path/platform-fe340000.mmc-part2 /dev/disk/by-uuid/db7775c5-9234-4b9a-955e-7341a245d498 /dev/disk/by-id/mmc-SU32G_0x186a3b0e-part2 /dev/disk/by-partuuid/8ca0658c-02 /dev/disk/by-label/volumio","DEVNAME":"/dev/mmcblk0p2","DEVPATH":"/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:e624/block/mmcblk0/mmcblk0p2","DEVTYPE":"partition","DISKSEQ":"29","ID_FS_LABEL":"volumio","ID_FS_LABEL_ENC":"volumio","ID_FS_TYPE":"ext4","ID_FS_USAGE":"filesystem","ID_FS_UUID":"db7775c5-9234-4b9a-955e-7341a245d498","ID_FS_UUID_ENC":"db7775c5-9234-4b9a-955e-7341a245d498","ID_FS_VERSION":"1.0","ID_NAME":"SU32G","ID_PART_ENTRY_DISK":"179:32","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":"8ca0658c-02","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"8ca0658c","ID_PATH":"platform-fe340000.mmc","ID_PATH_TAG":"platform-fe340000_mmc","ID_SERIAL":"0x186a3b0e","MAJOR":"179","MINOR":"34","PARTN":"2","SEQNUM":"1831","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"71673590"} Apr 03 07:47:52 nvb volumio[914]: info: Partition removed: {"syspath":"/sys/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:e624/block/mmcblk0/mmcblk0p3","ACTION":"remove","DEVLINKS":"/dev/disk/by-partuuid/8ca0658c-03 /dev/disk/by-uuid/4112086d-38d3-4206-a7af-ef93bf4e4008 /dev/disk/by-id/mmc-SU32G_0x186a3b0e-part3 /dev/disk/by-label/volumio_data /dev/disk/by-path/platform-fe340000.mmc-part3","DEVNAME":"/dev/mmcblk0p3","DEVPATH":"/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:e624/block/mmcblk0/mmcblk0p3","DEVTYPE":"partition","DISKSEQ":"29","ID_FS_LABEL":"volumio_data","ID_FS_LABEL_ENC":"volumio_data","ID_FS_TYPE":"ext4","ID_FS_USAGE":"filesystem","ID_FS_UUID":"4112086d-38d3-4206-a7af-ef93bf4e4008","ID_FS_UUID_ENC":"4112086d-38d3-4206-a7af-ef93bf4e4008","ID_FS_VERSION":"1.0","ID_NAME":"SU32G","ID_PART_ENTRY_DISK":"179:32","ID_PART_ENTRY_NUMBER":"3","ID_PART_ENTRY_OFFSET":"5468160","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"56865792","ID_PART_ENTRY_TYPE":"0x83","ID_PART_ENTRY_UUID":"8ca0658c-03","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"8ca0658c","ID_PATH":"platform-fe340000.mmc","ID_PATH_TAG":"platform-fe340000_mmc","ID_SERIAL":"0x186a3b0e","MAJOR":"179","MINOR":"35","PARTN":"3","SEQNUM":"1832","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"71673683"} Apr 03 07:47:52 nvb kernel: mmc0: card e624 removed Apr 03 07:47:52 nvb kernel: EXT4-fs error: 12 callbacks suppressed Apr 03 07:47:52 nvb kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #4653577: comm node: reading directory lblock 0 Apr 03 07:47:52 nvb kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #4653577: comm node: reading directory lblock 0 Apr 03 07:47:52 nvb kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #4653577: comm node: reading directory lblock 0 Apr 03 07:47:52 nvb systemd[1]: Unmounting /boot... Apr 03 07:47:52 nvb systemd[1207]: boot.mount: Failed to execute command: Input/output error Apr 03 07:47:52 nvb systemd[1207]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Apr 03 07:47:52 nvb systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Apr 03 07:47:52 nvb systemd[1]: Failed unmounting /boot. Apr 03 07:47:52 nvb systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device, but not stopping since we tried this too often recently. Apr 03 07:47:52 nvb kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #4653354: comm (umount): reading directory lblock 0 Apr 03 07:47:52 nvb volumio[914]: info: Discovery: adding 51b9f286-d8a2-475f-8fc7-a3b2545e52d8 Apr 03 07:47:52 nvb volumio[914]: info: Discovery: Found device nvb Apr 03 07:47:52 nvb volumio[914]: info: CoreCommandRouter::volumioGetState Apr 03 07:47:52 nvb volumio[914]: info: CorePlayQueue::getTrack 0 Apr 03 07:47:52 nvb ntpd[713]: Listen normally on 4 wlan0 192.168.178.53:123 Apr 03 07:47:52 nvb ntpd[713]: new interface(s) found: waking up resolver Apr 03 07:47:54 nvb kernel: mmc0: new high speed SDHC card at address e624 Apr 03 07:47:54 nvb kernel: mmcblk0: mmc0:e624 SU32G 29.7 GiB Apr 03 07:47:54 nvb kernel: mmcblk0: p1 p2 p3 Apr 03 07:47:54 nvb kernel: mmcblk0: mmc0:e624 SU32G 29.7 GiB (quirks 0x00004000) Apr 03 07:47:54 nvb wpa_supplicant[812]: RRM: Ignoring radio measurement request: Not RRM network Apr 03 07:47:54 nvb volumio[914]: info: Ignoring mount for partition: volumio Apr 03 07:47:54 nvb volumio[914]: info: Ignoring mount for partition: volumio_data Apr 03 07:47:54 nvb volumio[914]: info: Ignoring mount for partition: boot Apr 03 07:47:56 nvb wpa_supplicant[812]: RRM: Ignoring radio measurement request: Not RRM network Apr 03 07:47:58 nvb volumio[914]: error: Failed to update update history file: function createApplication() { Apr 03 07:47:58 nvb volumio[914]: var app = function(req, res, next) { Apr 03 07:47:58 nvb volumio[914]: app.handle(req, res, next); Apr 03 07:47:58 nvb volumio[914]: }; Apr 03 07:47:58 nvb volumio[914]: mixin(app, EventEmitter.prototype, false); Apr 03 07:47:58 nvb volumio[914]: mixin(app, proto, false); Apr 03 07:47:58 nvb volumio[914]: // expose the prototype that will get set on requests Apr 03 07:47:58 nvb volumio[914]: app.request = Object.create(req, { Apr 03 07:47:58 nvb volumio[914]: app: { configurable: true, enumerable: true, writable: true, value: app } Apr 03 07:47:58 nvb volumio[914]: }) Apr 03 07:47:58 nvb volumio[914]: // expose the prototype that will get set on responses Apr 03 07:47:58 nvb volumio[914]: app.response = Object.create(res, { Apr 03 07:47:58 nvb volumio[914]: app: { configurable: true, enumerable: true, writable: true, value: app } Apr 03 07:47:58 nvb volumio[914]: }) Apr 03 07:47:58 nvb volumio[914]: app.init(); Apr 03 07:47:58 nvb volumio[914]: return app; Apr 03 07:47:58 nvb volumio[914]: } Apr 03 07:47:58 nvb kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #4653404: comm node: reading directory lblock 0 Apr 03 07:47:58 nvb kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #4653404: comm node: reading directory lblock 0 Apr 03 07:47:58 nvb volumio[914]: info: Partition removed: {"syspath":"/sys/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:e624/block/mmcblk0/mmcblk0p1","ACTION":"remove","DEVLINKS":"/dev/disk/by-uuid/F102-DE29 /dev/disk/by-path/platform-fe340000.mmc-part1 /dev/disk/by-partuuid/8ca0658c-01 /dev/disk/by-id/mmc-SU32G_0x186a3b0e-part1 /dev/disk/by-label/boot","DEVNAME":"/dev/mmcblk0p1","DEVPATH":"/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:e624/block/mmcblk0/mmcblk0p1","DEVTYPE":"partition","DISKSEQ":"30","ID_FS_LABEL":"boot","ID_FS_LABEL_ENC":"boot","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"F102-DE29","ID_FS_UUID_ENC":"F102-DE29","ID_FS_VERSION":"FAT16","ID_NAME":"SU32G","ID_PART_ENTRY_DISK":"179:32","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":"8ca0658c-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"8ca0658c","ID_PATH":"platform-fe340000.mmc","ID_PATH_TAG":"platform-fe340000_mmc","ID_SERIAL":"0x186a3b0e","MAJOR":"179","MINOR":"33","PARTN":"1","SEQNUM":"1844","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"75297915"} Apr 03 07:47:58 nvb volumio[914]: info: Partition removed: {"syspath":"/sys/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:e624/block/mmcblk0/mmcblk0p2","ACTION":"remove","DEVLINKS":"/dev/disk/by-path/platform-fe340000.mmc-part2 /dev/disk/by-uuid/db7775c5-9234-4b9a-955e-7341a245d498 /dev/disk/by-id/mmc-SU32G_0x186a3b0e-part2 /dev/disk/by-partuuid/8ca0658c-02 /dev/disk/by-label/volumio","DEVNAME":"/dev/mmcblk0p2","DEVPATH":"/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:e624/block/mmcblk0/mmcblk0p2","DEVTYPE":"partition","DISKSEQ":"30","ID_FS_LABEL":"volumio","ID_FS_LABEL_ENC":"volumio","ID_FS_TYPE":"ext4","ID_FS_USAGE":"filesystem","ID_FS_UUID":"db7775c5-9234-4b9a-955e-7341a245d498","ID_FS_UUID_ENC":"db7775c5-9234-4b9a-955e-7341a245d498","ID_FS_VERSION":"1.0","ID_NAME":"SU32G","ID_PART_ENTRY_DISK":"179:32","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":"8ca0658c-02","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"8ca0658c","ID_PATH":"platform-fe340000.mmc","ID_PATH_TAG":"platform-fe340000_mmc","ID_SERIAL":"0x186a3b0e","MAJOR":"179","MINOR":"34","PARTN":"2","SEQNUM":"1845","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"75259506"} Apr 03 07:47:58 nvb kernel: mmc0: card e624 removed Apr 03 07:47:58 nvb kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #4653577: comm node: reading directory lblock 0 Apr 03 07:47:58 nvb kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #4653577: comm node: reading directory lblock 0 Apr 03 07:47:58 nvb kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #4653577: comm node: reading directory lblock 0 Apr 03 07:47:58 nvb volumio[914]: info: Partition removed: {"syspath":"/sys/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:e624/block/mmcblk0/mmcblk0p3","ACTION":"remove","DEVLINKS":"/dev/disk/by-partuuid/8ca0658c-03 /dev/disk/by-uuid/4112086d-38d3-4206-a7af-ef93bf4e4008 /dev/disk/by-id/mmc-SU32G_0x186a3b0e-part3 /dev/disk/by-label/volumio_data /dev/disk/by-path/platform-fe340000.mmc-part3","DEVNAME":"/dev/mmcblk0p3","DEVPATH":"/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:e624/block/mmcblk0/mmcblk0p3","DEVTYPE":"partition","DISKSEQ":"30","ID_FS_LABEL":"volumio_data","ID_FS_LABEL_ENC":"volumio_data","ID_FS_TYPE":"ext4","ID_FS_USAGE":"filesystem","ID_FS_UUID":"4112086d-38d3-4206-a7af-ef93bf4e4008","ID_FS_UUID_ENC":"4112086d-38d3-4206-a7af-ef93bf4e4008","ID_FS_VERSION":"1.0","ID_NAME":"SU32G","ID_PART_ENTRY_DISK":"179:32","ID_PART_ENTRY_NUMBER":"3","ID_PART_ENTRY_OFFSET":"5468160","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"56865792","ID_PART_ENTRY_TYPE":"0x83","ID_PART_ENTRY_UUID":"8ca0658c-03","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"8ca0658c","ID_PATH":"platform-fe340000.mmc","ID_PATH_TAG":"platform-fe340000_mmc","ID_SERIAL":"0x186a3b0e","MAJOR":"179","MINOR":"35","PARTN":"3","SEQNUM":"1846","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"75259691"} Apr 03 07:47:58 nvb wpa_supplicant[812]: RRM: Ignoring radio measurement request: Not RRM network Apr 03 07:47:58 nvb systemd[1]: Unmounting /boot... Apr 03 07:47:58 nvb systemd[1212]: boot.mount: Failed to execute command: Input/output error Apr 03 07:47:58 nvb systemd[1212]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Apr 03 07:47:58 nvb kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #4653354: comm (umount): reading directory lblock 0 Apr 03 07:47:58 nvb systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Apr 03 07:47:58 nvb systemd[1]: Failed unmounting /boot. Apr 03 07:47:58 nvb systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Apr 03 07:47:58 nvb systemd[1]: Unmounting /boot... Apr 03 07:47:58 nvb systemd[1213]: boot.mount: Failed to execute command: Input/output error Apr 03 07:47:58 nvb systemd[1213]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Apr 03 07:47:58 nvb systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Apr 03 07:47:58 nvb systemd[1]: Failed unmounting /boot. Apr 03 07:47:58 nvb systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Apr 03 07:47:58 nvb systemd[1]: Unmounting /boot... Apr 03 07:47:58 nvb systemd[1214]: boot.mount: Failed to execute command: Input/output error Apr 03 07:47:58 nvb systemd[1214]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Apr 03 07:47:58 nvb kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #4653354: comm (umount): reading directory lblock 0 Apr 03 07:47:58 nvb kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #4653354: comm (umount): reading directory lblock 0 Apr 03 07:47:58 nvb systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Apr 03 07:47:58 nvb systemd[1]: Failed unmounting /boot. Apr 03 07:47:58 nvb systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Apr 03 07:47:58 nvb systemd[1]: Unmounting /boot... Apr 03 07:47:58 nvb systemd[1215]: boot.mount: Failed to execute command: Input/output error Apr 03 07:47:58 nvb systemd[1215]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Apr 03 07:47:58 nvb kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #4653354: comm (umount): reading directory lblock 0 Apr 03 07:47:58 nvb systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Apr 03 07:47:58 nvb systemd[1]: Failed unmounting /boot. Apr 03 07:47:58 nvb systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Apr 03 07:47:58 nvb systemd[1]: Unmounting /boot... Apr 03 07:47:58 nvb systemd[1216]: boot.mount: Failed to execute command: Input/output error Apr 03 07:47:58 nvb systemd[1216]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Apr 03 07:47:58 nvb systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Apr 03 07:47:58 nvb kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #4653354: comm (umount): reading directory lblock 0 Apr 03 07:47:58 nvb systemd[1]: Failed unmounting /boot. Apr 03 07:47:58 nvb systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Apr 03 07:47:58 nvb systemd[1]: Unmounting /boot... Apr 03 07:47:58 nvb systemd[1217]: boot.mount: Failed to execute command: Input/output error Apr 03 07:47:58 nvb systemd[1217]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Apr 03 07:47:58 nvb systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Apr 03 07:47:58 nvb systemd[1]: Failed unmounting /boot. Apr 03 07:47:58 nvb systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Apr 03 07:47:58 nvb systemd[1]: Unmounting /boot... Apr 03 07:47:58 nvb systemd[1218]: boot.mount: Failed to execute command: Input/output error Apr 03 07:47:58 nvb systemd[1218]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Apr 03 07:47:58 nvb systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Apr 03 07:47:58 nvb systemd[1]: Failed unmounting /boot. Apr 03 07:47:58 nvb systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Apr 03 07:47:58 nvb systemd[1]: Unmounting /boot... Apr 03 07:47:58 nvb systemd[1219]: boot.mount: Failed to execute command: Input/output error Apr 03 07:47:58 nvb systemd[1219]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Apr 03 07:47:58 nvb systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Apr 03 07:47:58 nvb systemd[1]: Failed unmounting /boot. Apr 03 07:47:58 nvb systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Apr 03 07:47:58 nvb systemd[1]: Unmounting /boot... Apr 03 07:47:58 nvb systemd[1220]: boot.mount: Failed to execute command: Input/output error Apr 03 07:47:58 nvb systemd[1220]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Apr 03 07:47:58 nvb systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Apr 03 07:47:58 nvb systemd[1]: Failed unmounting /boot. Apr 03 07:47:58 nvb systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Apr 03 07:47:58 nvb systemd[1]: Unmounting /boot... Apr 03 07:47:58 nvb systemd[1221]: boot.mount: Failed to execute command: Input/output error Apr 03 07:47:58 nvb systemd[1221]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Apr 03 07:47:58 nvb systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Apr 03 07:47:58 nvb systemd[1]: Failed unmounting /boot. Apr 03 07:47:58 nvb systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Apr 03 07:47:58 nvb systemd[1]: Unmounting /boot... Apr 03 07:47:58 nvb systemd[1222]: boot.mount: Failed to execute command: Input/output error Apr 03 07:47:58 nvb systemd[1222]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Apr 03 07:47:58 nvb systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Apr 03 07:47:58 nvb systemd[1]: Failed unmounting /boot. Apr 03 07:47:58 nvb systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Apr 03 07:47:58 nvb systemd[1]: Unmounting /boot... Apr 03 07:47:58 nvb systemd[1223]: boot.mount: Failed to execute command: Input/output error Apr 03 07:47:58 nvb systemd[1223]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Apr 03 07:47:58 nvb systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Apr 03 07:47:58 nvb systemd[1]: Failed unmounting /boot. Apr 03 07:47:58 nvb systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Apr 03 07:47:58 nvb systemd[1]: Unmounting /boot... Apr 03 07:47:58 nvb systemd[1224]: boot.mount: Failed to execute command: Input/output error Apr 03 07:47:58 nvb systemd[1224]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Apr 03 07:47:58 nvb systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Apr 03 07:47:58 nvb systemd[1]: Failed unmounting /boot. Apr 03 07:47:58 nvb systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Apr 03 07:47:58 nvb systemd[1]: Unmounting /boot... Apr 03 07:47:58 nvb systemd[1225]: boot.mount: Failed to execute command: Input/output error Apr 03 07:47:58 nvb systemd[1225]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Apr 03 07:47:58 nvb systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Apr 03 07:47:58 nvb systemd[1]: Failed unmounting /boot. Apr 03 07:47:58 nvb systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Apr 03 07:47:58 nvb systemd[1]: Unmounting /boot... Apr 03 07:47:58 nvb systemd[1226]: boot.mount: Failed to execute command: Input/output error Apr 03 07:47:58 nvb systemd[1226]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Apr 03 07:47:58 nvb systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Apr 03 07:47:58 nvb systemd[1]: Failed unmounting /boot. Apr 03 07:47:58 nvb systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Apr 03 07:47:58 nvb systemd[1]: Unmounting /boot... Apr 03 07:47:58 nvb systemd[1227]: boot.mount: Failed to execute command: Input/output error Apr 03 07:47:58 nvb systemd[1227]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Apr 03 07:47:58 nvb systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Apr 03 07:47:58 nvb systemd[1]: Failed unmounting /boot. Apr 03 07:47:58 nvb systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device. Stopping, too. Apr 03 07:47:58 nvb systemd[1]: Unmounting /boot... Apr 03 07:47:58 nvb systemd[1228]: boot.mount: Failed to execute command: Input/output error Apr 03 07:47:58 nvb systemd[1228]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Apr 03 07:47:58 nvb systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Apr 03 07:47:58 nvb systemd[1]: Failed unmounting /boot. Apr 03 07:47:58 nvb systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device, but not stopping since we tried this too often recently. Apr 03 07:47:58 nvb kernel: mmc0: new high speed SDHC card at address e624 Apr 03 07:47:58 nvb kernel: mmcblk0: mmc0:e624 SU32G 29.7 GiB Apr 03 07:47:58 nvb kernel: mmc0: Got data interrupt 0x00000002 even though no data operation was in progress. Apr 03 07:47:58 nvb kernel: mmc0: Got data interrupt 0x00000002 even though no data operation was in progress. Apr 03 07:47:59 nvb kernel: mmc0: Got data interrupt 0x00000002 even though no data operation was in progress. Apr 03 07:47:59 nvb kernel: I/O error, dev mmcblk0, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Apr 03 07:47:59 nvb kernel: Buffer I/O error on dev mmcblk0, logical block 0, async page read Apr 03 07:47:59 nvb kernel: mmc0: Got data interrupt 0x00000002 even though no data operation was in progress. Apr 03 07:47:59 nvb kernel: mmc0: Got data interrupt 0x00000002 even though no data operation was in progress. Apr 03 07:47:59 nvb kernel: mmc0: Got data interrupt 0x00000002 even though no data operation was in progress. Apr 03 07:47:59 nvb kernel: I/O error, dev mmcblk0, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Apr 03 07:47:59 nvb kernel: Buffer I/O error on dev mmcblk0, logical block 0, async page read Apr 03 07:47:59 nvb kernel: mmc0: Got data interrupt 0x00000002 even though no data operation was in progress. Apr 03 07:47:59 nvb kernel: mmc0: Got data interrupt 0x00000002 even though no data operation was in progress. Apr 03 07:47:59 nvb kernel: mmc0: Got data interrupt 0x00000002 even though no data operation was in progress. Apr 03 07:47:59 nvb kernel: I/O error, dev mmcblk0, sector 0 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Apr 03 07:47:59 nvb kernel: Buffer I/O error on dev mmcblk0, logical block 0, async page read Apr 03 07:47:59 nvb kernel: mmcblk0: unable to read partition table Apr 03 07:47:59 nvb kernel: mmcblk0: mmc0:e624 SU32G 29.7 GiB (quirks 0x00004000) Apr 03 07:47:59 nvb kernel: mmc0: Got data interrupt 0x00000002 even though no data operation was in progress. Apr 03 07:47:59 nvb kernel: mmc0: Got data interrupt 0x00000002 even though no data operation was in progress. Apr 03 07:47:59 nvb kernel: mmc0: Got data interrupt 0x00000002 even though no data operation was in progress. Apr 03 07:47:59 nvb kernel: I/O error, dev mmcblk0, sector 62333824 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 Apr 03 07:47:59 nvb kernel: mmc0: Got data interrupt 0x00000002 even though no data operation was in progress. Apr 03 07:47:59 nvb kernel: mmc0: Got data interrupt 0x00000002 even though no data operation was in progress. Apr 03 07:47:59 nvb kernel: mmc0: Got data interrupt 0x00000002 even though no data operation was in progress. Apr 03 07:47:59 nvb kernel: I/O error, dev mmcblk0, sector 62333824 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Apr 03 07:47:59 nvb kernel: Buffer I/O error on dev mmcblk0, logical block 7791728, async page read Apr 03 07:48:00 nvb kernel: mmc0: card e624 removed Apr 03 07:48:00 nvb volumio[914]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Apr 03 07:48:01 nvb volumio[914]: error: Plugin system_controller system failed to complete 'onVolumioStart' in a timely fashion Apr 03 07:48:01 nvb volumio[914]: info: Preparing to generate the ALSA configuration file Apr 03 07:48:01 nvb volumio[914]: warn: Unable to scan plugin alsa_controller for ALSA configuration: Error: EIO: i/o error, scandir '/data/configuration/audio_interface/alsa_controller/asound' Apr 03 07:48:01 nvb volumio[914]: error: Cannot write /etc/asound.conf: Error: EROFS: read-only file system, open '/home/volumio/.asoundrc' Apr 03 07:48:01 nvb kernel: EXT4-fs warning (device mmcblk0p3): htree_dirblock_to_tree:1082: inode #4657298: lblock 0: comm node: error -5 reading directory block Apr 03 07:48:01 nvb volumio[914]: info: Output device has changed, restarting MPD Apr 03 07:48:01 nvb sudo[1231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 03 07:48:01 nvb volumio[914]: info: Output device has changed, restarting Shairport Sync Apr 03 07:48:01 nvb sudo[1231]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:48:01 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 07:48:01 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 07:48:01 nvb sudo[1231]: pam_unix(sudo:session): session closed for user root Apr 03 07:48:01 nvb sudo[1233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 03 07:48:01 nvb sudo[1233]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:48:01 nvb systemd[1]: Failed to canonicalize path '/etc/systemd/system/mpd.service.wants': Input/output error Apr 03 07:48:01 nvb systemd[1]: Failed to canonicalize path '/etc/systemd/system/mpd.service.requires': Input/output error Apr 03 07:48:01 nvb volumio[914]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 03 07:48:01 nvb volumio[914]: info: ___________ START PLUGINS ___________ Apr 03 07:48:01 nvb volumio[914]: info: ControllerMpd::onStart: Initializing MPD Apr 03 07:48:01 nvb volumio[914]: info: Creating MPD Configuration file Apr 03 07:48:01 nvb sudo[1241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 03 07:48:01 nvb sudo[1241]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:48:01 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 07:48:01 nvb volumio[914]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 03 07:48:01 nvb volumio[914]: info: [1712123281407] CoreMusicLibrary::Adding element Medienserver Apr 03 07:48:01 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 03 07:48:01 nvb sudo[1241]: pam_unix(sudo:session): session closed for user root Apr 03 07:48:01 nvb sudo[1243]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 03 07:48:01 nvb systemd[1]: Starting Music Player Daemon... Apr 03 07:48:01 nvb sudo[1243]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:48:01 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 07:48:01 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 07:48:01 nvb systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 03 07:48:01 nvb systemd[1]: mpd.service: Succeeded. Apr 03 07:48:01 nvb systemd[1]: Stopped Music Player Daemon. Apr 03 07:48:01 nvb systemd[1]: Starting Music Player Daemon... Apr 03 07:48:01 nvb volumio[914]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 03 07:48:01 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 07:48:01 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 07:48:01 nvb systemd[1]: mpd.service: Main process exited, code=killed, status=11/SEGV Apr 03 07:48:01 nvb systemd[1]: mpd.service: Failed with result 'signal'. Apr 03 07:48:01 nvb systemd[1]: Failed to start Music Player Daemon. Apr 03 07:48:01 nvb kernel: I/O error, dev loop0, sector 393582 op 0x0:(READ) flags 0x800 phys_seg 13 prio class 2 Apr 03 07:48:01 nvb kernel: SQUASHFS error: Failed to read block 0xc02dc3f: -5 Apr 03 07:48:01 nvb kernel: I/O error, dev loop0, sector 393582 op 0x0:(READ) flags 0x800 phys_seg 13 prio class 2 Apr 03 07:48:01 nvb kernel: SQUASHFS error: Failed to read block 0xc02dc3f: -5 Apr 03 07:48:01 nvb kernel: SQUASHFS error: Unable to read data cache entry [c02dc3f] Apr 03 07:48:01 nvb kernel: SQUASHFS error: Unable to read page, block c02dc3f, size c003 Apr 03 07:48:01 nvb kernel: SQUASHFS error: Unable to read data cache entry [c02dc3f] Apr 03 07:48:01 nvb kernel: SQUASHFS error: Unable to read page, block c02dc3f, size c003 Apr 03 07:48:01 nvb sudo[1243]: pam_unix(sudo:session): session closed for user root Apr 03 07:48:01 nvb sudo[1233]: pam_unix(sudo:session): session closed for user root Apr 03 07:48:01 nvb volumio[914]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 03 07:48:01 nvb volumio[914]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 03 07:48:01 nvb volumio[914]: info: [1712123281519] CoreMusicLibrary::Adding element Last_100 Apr 03 07:48:01 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 03 07:48:01 nvb volumio[914]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 03 07:48:01 nvb volumio[914]: info: [1712123281521] CoreMusicLibrary::Adding element Webradio Apr 03 07:48:01 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 03 07:48:01 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 03 07:48:01 nvb volumio[914]: info: Volumio Calling Home Apr 03 07:48:01 nvb volumio[914]: error: Cannot start MPD on system Start: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Apr 03 07:48:01 nvb volumio[914]: Job for mpd.service failed because a fatal signal was delivered to the control process. Apr 03 07:48:01 nvb volumio[914]: See "systemctl status mpd.service" and "journalctl -xe" for details. Apr 03 07:48:01 nvb volumio[914]: info: Completed starting Core Plugins Apr 03 07:48:01 nvb volumio[914]: info: ------------------------------------------- Apr 03 07:48:01 nvb volumio[914]: info: ----- MyVolumio plugins startup ---- Apr 03 07:48:01 nvb volumio[914]: info: ------------------------------------------- Apr 03 07:48:01 nvb volumio[914]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 03 07:48:01 nvb volumio[914]: info: Error setting mpd conf file perms: Error: Command failed: /usr/bin/sudo /bin/chmod 777 /etc/mpd.conf Apr 03 07:48:01 nvb volumio[914]: /bin/chmod: cannot access '/etc/mpd.conf': Input/output error Apr 03 07:48:01 nvb volumio[914]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Apr 03 07:48:01 nvb volumio[914]: Job for mpd.service failed because a fatal signal was delivered to the control process. Apr 03 07:48:01 nvb volumio[914]: See "systemctl status mpd.service" and "journalctl -xe" for details. Apr 03 07:48:01 nvb volumio[914]: info: Error setting mpd conf file perms: Error: Command failed: /usr/bin/sudo /bin/chmod 777 /etc/mpd.conf Apr 03 07:48:01 nvb volumio[914]: /bin/chmod: cannot access '/etc/mpd.conf': Input/output error Apr 03 07:48:01 nvb volumio[914]: error: MPD error: Error: connect ENOENT /run/mpd/socket Apr 03 07:48:01 nvb volumio[914]: error: connect ENOENT /run/mpd/socket {"errno":-2,"code":"ENOENT","syscall":"connect","address":"/run/mpd/socket"} Apr 03 07:48:01 nvb volumio[914]: error: MPD error: Error: connect ENOENT /run/mpd/socket Apr 03 07:48:01 nvb volumio[914]: error: connect ENOENT /run/mpd/socket {"errno":-2,"code":"ENOENT","syscall":"connect","address":"/run/mpd/socket"} Apr 03 07:48:01 nvb volumio[914]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Apr 03 07:48:01 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 07:48:01 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 07:48:01 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 07:48:01 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 07:48:01 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 07:48:01 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 07:48:01 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 07:48:01 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 07:48:01 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 07:48:01 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 07:48:01 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 07:48:01 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 07:48:01 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 07:48:01 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 07:48:01 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 07:48:01 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 07:48:01 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 07:48:01 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 07:48:01 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 03 07:48:01 nvb volumio[914]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 03 07:48:01 nvb volumio[914]: info: Could not write mpd.conf:Error: EIO: i/o error, open '/etc/mpd.conf' Apr 03 07:48:01 nvb volumio[914]: info: Could not write mpd.conf:Error: EIO: i/o error, open '/etc/mpd.conf' Apr 03 07:48:01 nvb volumio[914]: info: Starting Shairport Sync Apr 03 07:48:01 nvb volumio[914]: info: Starting Shairport Sync Apr 03 07:48:01 nvb sudo[1300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 03 07:48:01 nvb sudo[1300]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:48:01 nvb volumio[914]: info: Starting Shairport Sync Apr 03 07:48:01 nvb sudo[1302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 03 07:48:01 nvb sudo[1302]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:48:01 nvb systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 03 07:48:01 nvb systemd[1]: shairport-sync.service: Succeeded. Apr 03 07:48:01 nvb systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 03 07:48:01 nvb sudo[1306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 03 07:48:01 nvb sudo[1306]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 03 07:48:01 nvb volumio[914]: info: Volumio called home Apr 03 07:48:01 nvb volumio[914]: info: CoreCommandRouter::volumioGetState Apr 03 07:48:01 nvb volumio[914]: info: CorePlayQueue::getTrack 0 Apr 03 07:48:01 nvb systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 03 07:48:01 nvb systemd[1]: Failed to canonicalize path '/etc/systemd/system/shairport-.service.d': Input/output error Apr 03 07:48:01 nvb sudo[1300]: pam_unix(sudo:session): session closed for user root Apr 03 07:48:01 nvb volumio[914]: info: Shairport-Sync Started Apr 03 07:48:01 nvb volumio[914]: Error adding Membership: Error: addMembership EINVAL Apr 03 07:48:01 nvb systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 03 07:48:01 nvb systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 03 07:48:01 nvb systemd[1]: shairport-sync.service: Succeeded. Apr 03 07:48:01 nvb systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 03 07:48:01 nvb systemd[1]: Failed to canonicalize path '/etc/systemd/system/shairport-.service.d': Input/output error Apr 03 07:48:01 nvb systemd[1]: Failed to canonicalize path '/etc/systemd/system/shairport-.service.d': Input/output error Apr 03 07:48:01 nvb systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 03 07:48:01 nvb sudo[1302]: pam_unix(sudo:session): session closed for user root Apr 03 07:48:01 nvb sudo[1306]: pam_unix(sudo:session): session closed for user root Apr 03 07:48:01 nvb volumio[914]: info: Shairport-Sync Started Apr 03 07:48:01 nvb volumio[914]: info: Shairport-Sync Started Apr 03 07:48:02 nvb volumio[914]: Configuration write error: Error: EROFS: read-only file system, open '/data/configuration/plugins.json' Apr 03 07:48:05 nvb kernel: mmc0: new high speed SDHC card at address e624 Apr 03 07:48:05 nvb kernel: mmcblk0: mmc0:e624 SU32G 29.7 GiB Apr 03 07:48:05 nvb kernel: mmcblk0: p1 p2 p3 Apr 03 07:48:05 nvb kernel: mmcblk0: mmc0:e624 SU32G 29.7 GiB (quirks 0x00004000) Apr 03 07:48:05 nvb volumio[914]: info: Ignoring mount for partition: volumio_data Apr 03 07:48:05 nvb volumio[914]: info: Ignoring mount for partition: volumio Apr 03 07:48:05 nvb volumio[914]: info: Ignoring mount for partition: boot Apr 03 07:48:06 nvb volumio[914]: info: Partition removed: {"syspath":"/sys/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:e624/block/mmcblk0/mmcblk0p1","ACTION":"remove","DEVLINKS":"/dev/disk/by-uuid/F102-DE29 /dev/disk/by-path/platform-fe340000.mmc-part1 /dev/disk/by-partuuid/8ca0658c-01 /dev/disk/by-id/mmc-SU32G_0x186a3b0e-part1 /dev/disk/by-label/boot","DEVNAME":"/dev/mmcblk0p1","DEVPATH":"/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:e624/block/mmcblk0/mmcblk0p1","DEVTYPE":"partition","DISKSEQ":"32","ID_FS_LABEL":"boot","ID_FS_LABEL_ENC":"boot","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"F102-DE29","ID_FS_UUID_ENC":"F102-DE29","ID_FS_VERSION":"FAT16","ID_NAME":"SU32G","ID_PART_ENTRY_DISK":"179:32","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":"8ca0658c-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"8ca0658c","ID_PATH":"platform-fe340000.mmc","ID_PATH_TAG":"platform-fe340000_mmc","ID_SERIAL":"0x186a3b0e","MAJOR":"179","MINOR":"33","PARTN":"1","SEQNUM":"1866","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"85906453"} Apr 03 07:48:06 nvb volumio[914]: info: Partition removed: {"syspath":"/sys/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:e624/block/mmcblk0/mmcblk0p2","ACTION":"remove","DEVLINKS":"/dev/disk/by-path/platform-fe340000.mmc-part2 /dev/disk/by-uuid/db7775c5-9234-4b9a-955e-7341a245d498 /dev/disk/by-id/mmc-SU32G_0x186a3b0e-part2 /dev/disk/by-partuuid/8ca0658c-02 /dev/disk/by-label/volumio","DEVNAME":"/dev/mmcblk0p2","DEVPATH":"/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:e624/block/mmcblk0/mmcblk0p2","DEVTYPE":"partition","DISKSEQ":"32","ID_FS_LABEL":"volumio","ID_FS_LABEL_ENC":"volumio","ID_FS_TYPE":"ext4","ID_FS_USAGE":"filesystem","ID_FS_UUID":"db7775c5-9234-4b9a-955e-7341a245d498","ID_FS_UUID_ENC":"db7775c5-9234-4b9a-955e-7341a245d498","ID_FS_VERSION":"1.0","ID_NAME":"SU32G","ID_PART_ENTRY_DISK":"179:32","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":"8ca0658c-02","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"8ca0658c","ID_PATH":"platform-fe340000.mmc","ID_PATH_TAG":"platform-fe340000_mmc","ID_SERIAL":"0x186a3b0e","MAJOR":"179","MINOR":"34","PARTN":"2","SEQNUM":"1867","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"85893564"} Apr 03 07:48:06 nvb kernel: mmc0: card e624 removed Apr 03 07:48:06 nvb kernel: EXT4-fs error: 27 callbacks suppressed Apr 03 07:48:06 nvb kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #4653577: comm node: reading directory lblock 0 Apr 03 07:48:06 nvb kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #4653577: comm node: reading directory lblock 0 Apr 03 07:48:06 nvb volumio[914]: info: Partition removed: {"syspath":"/sys/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:e624/block/mmcblk0/mmcblk0p3","ACTION":"remove","DEVLINKS":"/dev/disk/by-partuuid/8ca0658c-03 /dev/disk/by-uuid/4112086d-38d3-4206-a7af-ef93bf4e4008 /dev/disk/by-id/mmc-SU32G_0x186a3b0e-part3 /dev/disk/by-label/volumio_data /dev/disk/by-path/platform-fe340000.mmc-part3","DEVNAME":"/dev/mmcblk0p3","DEVPATH":"/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:e624/block/mmcblk0/mmcblk0p3","DEVTYPE":"partition","DISKSEQ":"32","ID_FS_LABEL":"volumio_data","ID_FS_LABEL_ENC":"volumio_data","ID_FS_TYPE":"ext4","ID_FS_USAGE":"filesystem","ID_FS_UUID":"4112086d-38d3-4206-a7af-ef93bf4e4008","ID_FS_UUID_ENC":"4112086d-38d3-4206-a7af-ef93bf4e4008","ID_FS_VERSION":"1.0","ID_NAME":"SU32G","ID_PART_ENTRY_DISK":"179:32","ID_PART_ENTRY_NUMBER":"3","ID_PART_ENTRY_OFFSET":"5468160","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"56865792","ID_PART_ENTRY_TYPE":"0x83","ID_PART_ENTRY_UUID":"8ca0658c-03","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"8ca0658c","ID_PATH":"platform-fe340000.mmc","ID_PATH_TAG":"platform-fe340000_mmc","ID_SERIAL":"0x186a3b0e","MAJOR":"179","MINOR":"35","PARTN":"3","SEQNUM":"1868","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"85893532"} Apr 03 07:48:06 nvb kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #4653577: comm node: reading directory lblock 0 Apr 03 07:48:06 nvb systemd[1]: Unmounting /boot... Apr 03 07:48:06 nvb systemd[1317]: boot.mount: Failed to execute command: Input/output error Apr 03 07:48:06 nvb systemd[1317]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Apr 03 07:48:06 nvb systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Apr 03 07:48:06 nvb systemd[1]: Failed unmounting /boot. Apr 03 07:48:06 nvb systemd[1]: boot.mount: Unit is bound to inactive unit dev-mmcblk0p1.device, but not stopping since we tried this too often recently. Apr 03 07:48:06 nvb kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #4653354: comm (umount): reading directory lblock 0 Apr 03 07:48:06 nvb kernel: sdhci-iproc fe340000.mmc: card claims to support voltages below defined range Apr 03 07:48:06 nvb kernel: sdhci-iproc fe340000.mmc: no support for card's volts Apr 03 07:48:06 nvb kernel: mmc0: error -22 whilst initialising SDIO card Apr 03 07:48:10 nvb ntpd[713]: Soliciting pool server 194.36.144.87 Apr 03 07:48:10 nvb volumio[914]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 03 07:48:10 nvb volumio[914]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 03 07:48:10 nvb volumio[914]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 03 07:48:10 nvb volumio[914]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 03 07:48:10 nvb volumio[914]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 03 07:48:10 nvb volumio[914]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 03 07:48:10 nvb volumio[914]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 03 07:48:10 nvb volumio[914]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 03 07:48:10 nvb volumio[914]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 03 07:48:10 nvb volumio[914]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 03 07:48:10 nvb volumio[914]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 03 07:48:10 nvb volumio[914]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 03 07:48:10 nvb volumio[914]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 03 07:48:10 nvb volumio[914]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 03 07:48:10 nvb volumio[914]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 03 07:48:10 nvb volumio[914]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 03 07:48:10 nvb volumio[914]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 03 07:48:10 nvb volumio[914]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 03 07:48:10 nvb volumio[914]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 03 07:48:10 nvb volumio[914]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 03 07:48:10 nvb volumio[914]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 03 07:48:10 nvb volumio[914]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 03 07:48:10 nvb volumio[914]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 03 07:48:10 nvb volumio[914]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 03 07:48:10 nvb volumio[914]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 03 07:48:10 nvb volumio[914]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 03 07:48:10 nvb volumio[914]: info: Adding plugin bluetooth to MyMusic Plugins Apr 03 07:48:10 nvb volumio[914]: info: Adding plugin multiroom to MyMusic Plugins Apr 03 07:48:10 nvb volumio[914]: info: Adding plugin metavolumio to MyMusic Plugins Apr 03 07:48:10 nvb volumio[914]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/cd_controller/package.json: EIO: i/o error, read Apr 03 07:48:10 nvb kernel: I/O error, dev loop0, sector 301802 op 0x0:(READ) flags 0x800 phys_seg 19 prio class 2 Apr 03 07:48:10 nvb kernel: SQUASHFS error: Failed to read block 0x935d635: -5 Apr 03 07:48:10 nvb kernel: SQUASHFS error: Unable to read fragment cache entry [935d635] Apr 03 07:48:10 nvb kernel: SQUASHFS error: Unable to read fragment cache entry [935d635] Apr 03 07:48:10 nvb kernel: SQUASHFS error: Unable to read page, block 935d635, size 12ad7 Apr 03 07:48:10 nvb kernel: I/O error, dev loop0, sector 302148 op 0x0:(READ) flags 0x800 phys_seg 23 prio class 2 Apr 03 07:48:10 nvb kernel: SQUASHFS error: Failed to read block 0x9388b20: -5 Apr 03 07:48:10 nvb kernel: SQUASHFS error: Unable to read fragment cache entry [9388b20] Apr 03 07:48:10 nvb kernel: SQUASHFS error: Unable to read fragment cache entry [9388b20] Apr 03 07:48:10 nvb kernel: SQUASHFS error: Unable to read page, block 9388b20, size 155c5 Apr 03 07:48:10 nvb volumio[914]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/hi_res_audio/package.json: EIO: i/o error, read Apr 03 07:48:10 nvb volumio[914]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/qobuz/package.json: EIO: i/o error, read Apr 03 07:48:10 nvb volumio[914]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/smart_inputs/package.json: EIO: i/o error, read Apr 03 07:48:10 nvb volumio[914]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services/package.json: EIO: i/o error, read Apr 03 07:48:10 nvb volumio[914]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/tidal/package.json: EIO: i/o error, read Apr 03 07:48:10 nvb volumio[914]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/tidalconnect/package.json: EIO: i/o error, read Apr 03 07:48:10 nvb volumio[914]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/system_controller/my_volumio/package.json: EIO: i/o error, read Apr 03 07:48:10 nvb volumio[914]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/system_controller/my_volumio/package.json: EIO: i/o error, read Apr 03 07:48:10 nvb volumio[914]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 03 07:48:10 nvb kernel: I/O error, dev loop0, sector 333426 op 0x0:(READ) flags 0x800 phys_seg 21 prio class 2 Apr 03 07:48:10 nvb kernel: SQUASHFS error: Failed to read block 0xa2ce554: -5 Apr 03 07:48:10 nvb kernel: SQUASHFS error: Unable to read fragment cache entry [a2ce554] Apr 03 07:48:10 nvb kernel: SQUASHFS error: Unable to read fragment cache entry [a2ce554] Apr 03 07:48:10 nvb kernel: SQUASHFS error: Unable to read page, block a2ce554, size 16546 Apr 03 07:48:10 nvb kernel: SQUASHFS error: Unable to read fragment cache entry [a2ce554] Apr 03 07:48:10 nvb kernel: SQUASHFS error: Unable to read fragment cache entry [a2ce554] Apr 03 07:48:10 nvb kernel: SQUASHFS error: Unable to read page, block a2ce554, size 16546 Apr 03 07:48:10 nvb kernel: I/O error, dev loop0, sector 334556 op 0x0:(READ) flags 0x800 phys_seg 14 prio class 2 Apr 03 07:48:10 nvb kernel: SQUASHFS error: Failed to read block 0xa35bb4a: -5 Apr 03 07:48:10 nvb kernel: SQUASHFS error: Unable to read fragment cache entry [a35bb4a] Apr 03 07:48:10 nvb kernel: SQUASHFS error: Unable to read fragment cache entry [a35bb4a] Apr 03 07:48:10 nvb kernel: SQUASHFS error: Unable to read page, block a35bb4a, size c6bb Apr 03 07:48:10 nvb kernel: SQUASHFS error: Unable to read fragment cache entry [a35bb4a] Apr 03 07:48:10 nvb kernel: SQUASHFS error: Unable to read fragment cache entry [a35bb4a] Apr 03 07:48:10 nvb kernel: SQUASHFS error: Unable to read page, block a35bb4a, size c6bb Apr 03 07:48:10 nvb kernel: SQUASHFS error: Unable to read fragment cache entry [a35bb4a] Apr 03 07:48:10 nvb kernel: SQUASHFS error: Unable to read fragment cache entry [a35bb4a] Apr 03 07:48:10 nvb kernel: SQUASHFS error: Unable to read page, block a35bb4a, size c6bb Apr 03 07:48:10 nvb kernel: SQUASHFS error: Unable to read fragment cache entry [a35bb4a] Apr 03 07:48:10 nvb kernel: SQUASHFS error: Unable to read fragment cache entry [a35bb4a] Apr 03 07:48:10 nvb kernel: SQUASHFS error: Unable to read page, block a35bb4a, size c6bb Apr 03 07:48:10 nvb kernel: SQUASHFS error: Unable to read fragment cache entry [a35bb4a] Apr 03 07:48:10 nvb kernel: SQUASHFS error: Unable to read page, block a35bb4a, size c6bb Apr 03 07:48:10 nvb volumio[914]: TypeError: Cannot read property 'volumio_info' of undefined Apr 03 07:48:10 nvb volumio[914]: at MyVolumioPluginManager.loadMyVolumioPlugin (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:12309) Apr 03 07:48:10 nvb volumio[914]: at MyVolumioPluginManager.loadMyVolumio (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:6489) Apr 03 07:48:10 nvb volumio[914]: at MyVolumioPluginManager.initMyVolumio (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:1533) Apr 03 07:48:10 nvb volumio[914]: at /myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:3480 Apr 03 07:48:10 nvb volumio[914]: at Request.handleRequestResponse [as _callback] (/volumio/node_modules/unirest/index.js:552:70) Apr 03 07:48:10 nvb volumio[914]: at Request.self.callback (/volumio/node_modules/request/request.js:185:22) Apr 03 07:48:10 nvb volumio[914]: at Request.emit (events.js:315:20) Apr 03 07:48:10 nvb volumio[914]: at Request. (/volumio/node_modules/request/request.js:1154:10) Apr 03 07:48:10 nvb volumio[914]: at Request.emit (events.js:315:20) Apr 03 07:48:10 nvb volumio[914]: at IncomingMessage. (/volumio/node_modules/request/request.js:1076:12) Apr 03 07:48:10 nvb volumio[914]: at Object.onceWrapper (events.js:421:28) Apr 03 07:48:10 nvb volumio[914]: at IncomingMessage.emit (events.js:327:22) Apr 03 07:48:10 nvb volumio[914]: at endReadableNT (internal/streams/readable.js:1327:12) Apr 03 07:48:10 nvb volumio[914]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Apr 03 07:48:10 nvb volumio[914]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 03 07:48:10 nvb sudo[1327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-03 07:47 Apr 03 07:48:10 nvb sudo[1327]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"