-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Thu 2022-06-09 16:02:06 UTC. -- Jun 09 16:01:00 volumio avahi-daemon[504]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Jun 09 16:01:00 volumio avahi-daemon[504]: Successfully dropped root privileges. Jun 09 16:01:00 volumio avahi-daemon[504]: avahi-daemon 0.7 starting up. Jun 09 16:01:00 volumio systemd[1]: Started triggerhappy global hotkey daemon. Jun 09 16:01:00 volumio systemd[1]: Started RPC bind portmap service. Jun 09 16:01:00 volumio avahi-daemon[504]: Successfully called chroot(). Jun 09 16:01:00 volumio avahi-daemon[504]: Successfully dropped remaining capabilities. Jun 09 16:01:00 volumio systemd[1]: Started Login Service. Jun 09 16:01:00 volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jun 09 16:01:00 volumio avahi-daemon[504]: No service file found in /etc/avahi/services. Jun 09 16:01:00 volumio avahi-daemon[504]: Network interface enumeration completed. Jun 09 16:01:00 volumio systemd[1]: Reached target RPC Port Mapper. Jun 09 16:01:00 volumio systemd[1]: Reached target Remote File Systems (Pre). Jun 09 16:01:00 volumio systemd[1]: Reached target Remote File Systems. Jun 09 16:01:00 volumio avahi-daemon[504]: Server startup complete. Host name is volumio.local. Local service cookie is 1703639808. Jun 09 16:01:00 volumio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Jun 09 16:01:00 volumio systemd[1]: Started Raise network interfaces. Jun 09 16:01:01 volumio loadcpufreq[558]: Loading cpufreq kernel modules...done (none). Jun 09 16:01:01 volumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Jun 09 16:01:01 volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters... Jun 09 16:01:01 volumio systemd[1]: iptables.service: Succeeded. Jun 09 16:01:01 volumio cpufrequtils[592]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Jun 09 16:01:01 volumio systemd[1]: Started LSB: set CPUFreq kernel parameters. Jun 09 16:01:01 volumio haveged[448]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Jun 09 16:01:01 volumio haveged[448]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Jun 09 16:01:01 volumio haveged[448]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99846 Jun 09 16:01:01 volumio haveged[448]: haveged: fills: 0, generated: 0 Jun 09 16:01:01 volumio systemd[1]: Started WPA supplicant. Jun 09 16:01:01 volumio wpa_supplicant[511]: Successfully initialized wpa_supplicant Jun 09 16:01:01 volumio systemd[1]: Reached target Network. Jun 09 16:01:01 volumio systemd[1]: Reached target Network is Online. Jun 09 16:01:01 volumio systemd[1]: Starting LSB: Brings up/down network automatically... Jun 09 16:01:01 volumio systemd[1]: Starting Network Time Service... Jun 09 16:01:01 volumio systemd[1]: Starting Samba NMB Daemon... Jun 09 16:01:01 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 09 16:01:01 volumio systemd[1]: Starting Music Player Daemon... Jun 09 16:01:01 volumio systemd[1]: Starting Permit User Sessions... Jun 09 16:01:01 volumio systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Jun 09 16:01:01 volumio systemd[1]: Starting /etc/rc.local Compatibility... Jun 09 16:01:01 volumio systemd[1]: Started /etc/rc.local Compatibility. Jun 09 16:01:01 volumio systemd[1]: Started Permit User Sessions. Jun 09 16:01:01 volumio systemd[1]: Starting Hold until boot process finishes up... Jun 09 16:01:01 volumio systemd[1]: Starting Terminate Plymouth Boot Screen... Jun 09 16:01:01 volumio systemd[1]: Received SIGRTMIN+21 from PID 256 (plymouthd). Jun 09 16:01:01 volumio ifplugd(eth0)[636]: ifplugd 0.28 initializing. Jun 09 16:01:01 volumio ifplugd(eth0)[636]: Using interface eth0/DC:A6:32:B0:D2:17 with driver (version: 5.10.92-v7l+) Jun 09 16:01:01 volumio ifplugd(eth0)[636]: Using detection mode: SIOCETHTOOL Jun 09 16:01:01 volumio ifplugd(eth0)[636]: Initialization complete, link beat not detected. Jun 09 16:01:01 volumio systemd[1]: plymouth-quit-wait.service: Succeeded. Jun 09 16:01:01 volumio systemd[1]: Started Hold until boot process finishes up. Jun 09 16:01:01 volumio systemd[1]: plymouth-start.service: Succeeded. Jun 09 16:01:01 volumio systemd[1]: plymouth-quit.service: Succeeded. Jun 09 16:01:01 volumio systemd[1]: Started Terminate Plymouth Boot Screen. Jun 09 16:01:01 volumio systemd[1]: Received SIGRTMIN+21 from PID 256 (n/a). Jun 09 16:01:01 volumio systemd[1]: Started Getty on tty1. Jun 09 16:01:01 volumio systemd[1]: Reached target Login Prompts. Jun 09 16:01:01 volumio ifplugd[609]: Network Interface Plugging Daemon...start eth0...done. Jun 09 16:01:01 volumio systemd[1]: Started LSB: Brings up/down network automatically. Jun 09 16:01:01 volumio ntpd[611]: ntpd 4.2.8p12@1.3728-o (1): Starting Jun 09 16:01:01 volumio ntpd[611]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Jun 09 16:01:01 volumio systemd[1]: Started Network Time Service. Jun 09 16:01:02 volumio ntpd[640]: proto: precision = 0.796 usec (-20) Jun 09 16:01:02 volumio ntpd[640]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jun 09 16:01:02 volumio ntpd[640]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jun 09 16:01:02 volumio ntpd[640]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): will expire in less than 19 days Jun 09 16:01:02 volumio ntpd[640]: Listen and drop on 0 v6wildcard [::]:123 Jun 09 16:01:02 volumio ntpd[640]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 09 16:01:02 volumio ntpd[640]: Listen normally on 2 lo 127.0.0.1:123 Jun 09 16:01:02 volumio ntpd[640]: Listening on routing socket on fd #19 for interface updates Jun 09 16:01:02 volumio ntpd[640]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 09 16:01:02 volumio ntpd[640]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 09 16:01:02 volumio volumio-remote-updater[491]: Error: No active session Jun 09 16:01:02 volumio volumio-remote-updater[491]: [2022-06-09 16:01:02] [info] asio async_connect error: system:111 (Connection refused) Jun 09 16:01:02 volumio volumio-remote-updater[491]: [2022-06-09 16:01:02] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 09 16:01:02 volumio volumio-remote-updater[491]: [2022-06-09 16:01:02] [error] handle_connect error: Underlying Transport Error Jun 09 16:01:02 volumio volumio[486]: Could not open config: /tmp/upmpdcli.conf Jun 09 16:01:02 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jun 09 16:01:02 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jun 09 16:01:03 volumio ntpd[640]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jun 09 16:01:03 volumio systemd[1]: systemd-rfkill.service: Succeeded. Jun 09 16:01:03 volumio nmbd[619]: [2022/06/09 16:01:03.829423, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jun 09 16:01:03 volumio nmbd[619]: started asyncdns process 651 Jun 09 16:01:03 volumio nmbd[619]: [2022/06/09 16:01:03.832640, 0] ../lib/util/become_daemon.c:149(daemon_status) Jun 09 16:01:03 volumio nmbd[619]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jun 09 16:01:03 volumio nmbd[619]: [2022/06/09 16:01:03.833169, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Jun 09 16:01:03 volumio nmbd[619]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jun 09 16:01:04 volumio ntpd[640]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jun 09 16:01:04 volumio wireless.js[480]: Cleaning previous... Jun 09 16:01:04 volumio sudo[660]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 09 16:01:04 volumio sudo[660]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:01:04 volumio sudo[660]: pam_unix(sudo:session): session closed for user root Jun 09 16:01:04 volumio sudo[662]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 09 16:01:04 volumio sudo[662]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:01:05 volumio ntpd[640]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jun 09 16:01:05 volumio mpd[638]: Jun 09 16:01 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 09 16:01:05 volumio sudo[662]: pam_unix(sudo:session): session closed for user root Jun 09 16:01:05 volumio mpd[638]: Jun 09 16:01 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jun 09 16:01:05 volumio mpd[638]: Jun 09 16:01 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jun 09 16:01:05 volumio wireless.js[480]: Stopped aP Jun 09 16:01:05 volumio systemd[1]: Started Music Player Daemon. Jun 09 16:01:05 volumio wireless.js[480]: Start wireless flow Jun 09 16:01:05 volumio wireless.js[480]: Stopped hotspot (if there).. Jun 09 16:01:05 volumio sudo[671]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 09 16:01:05 volumio sudo[671]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:01:05 volumio sudo[671]: pam_unix(sudo:session): session closed for user root Jun 09 16:01:05 volumio sudo[673]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 09 16:01:05 volumio sudo[673]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:01:05 volumio sudo[673]: pam_unix(sudo:session): session closed for user root Jun 09 16:01:05 volumio wireless.js[480]: DHCP IP Jun 09 16:01:05 volumio wireless.js[480]: Start ap Jun 09 16:01:05 volumio wpa_supplicant[675]: Successfully initialized wpa_supplicant Jun 09 16:01:05 volumio sudo[676]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jun 09 16:01:05 volumio sudo[676]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:01:05 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 09 16:01:05 volumio dhcpcd[677]: dev: loaded udev Jun 09 16:01:05 volumio dhcpcd-run-hooks[695]: wlan0: starting wpa_supplicant Jun 09 16:01:05 volumio dhcpcd-run-hooks[700]: wlan0: failed to start wpa_supplicant Jun 09 16:01:05 volumio dhcpcd-run-hooks[701]: 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 Jun 09 16:01:05 volumio dhcpcd[677]: wlan0: connected to Access Point `' Jun 09 16:01:05 volumio dhcpcd[677]: no interfaces have a carrier Jun 09 16:01:05 volumio dhcpcd[677]: forked to background, child pid 711 Jun 09 16:01:05 volumio dhcpcd[711]: eth0: waiting for carrier Jun 09 16:01:05 volumio dhcpcd[711]: wlan0: waiting for carrier Jun 09 16:01:05 volumio sudo[676]: pam_unix(sudo:session): session closed for user root Jun 09 16:01:06 volumio ntpd[640]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jun 09 16:01:06 volumio wireless.js[480]: trying... Jun 09 16:01:06 volumio sudo[713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 09 16:01:06 volumio sudo[713]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:01:06 volumio sudo[713]: pam_unix(sudo:session): session closed for user root Jun 09 16:01:06 volumio kernel: Bluetooth: Core ver 2.22 Jun 09 16:01:06 volumio kernel: NET: Registered protocol family 31 Jun 09 16:01:06 volumio kernel: Bluetooth: HCI device and connection manager initialized Jun 09 16:01:06 volumio kernel: Bluetooth: HCI socket layer initialized Jun 09 16:01:06 volumio kernel: Bluetooth: L2CAP socket layer initialized Jun 09 16:01:06 volumio kernel: Bluetooth: SCO socket layer initialized Jun 09 16:01:06 volumio btuart[509]: bcm43xx_init Jun 09 16:01:06 volumio btuart[509]: Flash firmware /lib/firmware/brcm/BCM4345C0.hcd Jun 09 16:01:06 volumio btuart[509]: Set Controller UART speed to 3000000 bit/s Jun 09 16:01:06 volumio btuart[509]: Device setup complete Jun 09 16:01:06 volumio systemd[1]: Starting Load/Save RF Kill Switch Status... Jun 09 16:01:06 volumio systemd[1]: Started Configure Bluetooth Modems connected by UART. Jun 09 16:01:06 volumio kernel: Bluetooth: HCI UART driver ver 2.3 Jun 09 16:01:06 volumio kernel: Bluetooth: HCI UART protocol H4 registered Jun 09 16:01:06 volumio kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Jun 09 16:01:06 volumio kernel: Bluetooth: HCI UART protocol Broadcom registered Jun 09 16:01:06 volumio systemd[1]: Started Load/Save RF Kill Switch Status. Jun 09 16:01:06 volumio systemd[1]: Created slice system-bthelper.slice. Jun 09 16:01:06 volumio systemd[1]: Starting Raspberry Pi bluetooth helper... Jun 09 16:01:06 volumio bthelper[724]: Raspberry Pi BDADDR already set Jun 09 16:01:06 volumio systemd[1]: Started Raspberry Pi bluetooth helper. Jun 09 16:01:06 volumio systemd[1]: Starting Bluetooth service... Jun 09 16:01:06 volumio bluetoothd[733]: Bluetooth daemon 5.50 Jun 09 16:01:06 volumio bluetoothd[733]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jun 09 16:01:06 volumio systemd[1]: Started Bluetooth service. Jun 09 16:01:06 volumio systemd[1]: Reached target Bluetooth. Jun 09 16:01:06 volumio bluetoothd[733]: Starting SDP server Jun 09 16:01:06 volumio bluetoothd[733]: Excluding (cli) sap Jun 09 16:01:06 volumio bluetoothd[733]: Bluetooth management interface 1.18 initialized Jun 09 16:01:06 volumio dbus-daemon[508]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=733 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Jun 09 16:01:06 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jun 09 16:01:06 volumio kernel: Bluetooth: BNEP filters: protocol multicast Jun 09 16:01:06 volumio kernel: Bluetooth: BNEP socket layer initialized Jun 09 16:01:06 volumio systemd[1]: Starting Hostname Service... Jun 09 16:01:06 volumio dbus-daemon[508]: [system] Successfully activated service 'org.freedesktop.hostname1' Jun 09 16:01:06 volumio systemd[1]: Started Hostname Service. Jun 09 16:01:06 volumio bluetoothd[733]: Failed to set privacy: Rejected (0x0b) Jun 09 16:01:07 volumio volumio-remote-updater[491]: [2022-06-09 16:01:07] [info] asio async_connect error: system:111 (Connection refused) Jun 09 16:01:07 volumio volumio-remote-updater[491]: [2022-06-09 16:01:07] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 09 16:01:07 volumio volumio-remote-updater[491]: [2022-06-09 16:01:07] [error] handle_connect error: Underlying Transport Error Jun 09 16:01:07 volumio wireless.js[480]: trying... Jun 09 16:01:07 volumio sudo[738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 09 16:01:07 volumio sudo[738]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:01:07 volumio sudo[738]: pam_unix(sudo:session): session closed for user root Jun 09 16:01:08 volumio wpa_supplicant[699]: wlan0: Trying to associate with SSID 'MKmotors' Jun 09 16:01:08 volumio wireless.js[480]: trying... Jun 09 16:01:08 volumio sudo[741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 09 16:01:08 volumio sudo[741]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:01:08 volumio sudo[741]: pam_unix(sudo:session): session closed for user root Jun 09 16:01:09 volumio wireless.js[480]: trying... Jun 09 16:01:09 volumio sudo[744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 09 16:01:09 volumio sudo[744]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:01:09 volumio sudo[744]: pam_unix(sudo:session): session closed for user root Jun 09 16:01:10 volumio wireless.js[480]: trying... Jun 09 16:01:10 volumio sudo[747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 09 16:01:10 volumio sudo[747]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:01:10 volumio sudo[747]: pam_unix(sudo:session): session closed for user root Jun 09 16:01:10 volumio dhcpcd[711]: wlan0: carrier acquired Jun 09 16:01:10 volumio dhcpcd[711]: wlan0: connected to Access Point `MKmotors' Jun 09 16:01:10 volumio wpa_supplicant[699]: wlan0: Associated with 64:e5:99:60:e7:34 Jun 09 16:01:10 volumio wpa_supplicant[699]: wlan0: CTRL-EVENT-CONNECTED - Connection to 64:e5:99:60:e7:34 completed [id=0 id_str=] Jun 09 16:01:10 volumio wpa_supplicant[699]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 09 16:01:10 volumio dhcpcd[711]: DUID 00:01:00:01:2a:34:d6:19:dc:a6:32:b0:d2:18 Jun 09 16:01:10 volumio dhcpcd[711]: wlan0: IAID 32:b0:d2:18 Jun 09 16:01:10 volumio dhcpcd[711]: wlan0: adding address fe80::6c68:30b9:b5:2935 Jun 09 16:01:10 volumio dhcpcd[711]: ipv6_addaddr1: Permission denied Jun 09 16:01:10 volumio dhcpcd[711]: wlan0: carrier lost Jun 09 16:01:10 volumio dhcpcd[711]: wlan0: carrier acquired Jun 09 16:01:10 volumio dhcpcd[711]: wlan0: IAID 32:b0:d2:18 Jun 09 16:01:11 volumio dhcpcd[711]: wlan0: rebinding lease of 192.168.0.2 Jun 09 16:01:11 volumio dhcpcd[711]: wlan0: probing address 192.168.0.2/24 Jun 09 16:01:11 volumio wireless.js[480]: trying... Jun 09 16:01:11 volumio sudo[762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 09 16:01:11 volumio sudo[762]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:01:11 volumio sudo[762]: pam_unix(sudo:session): session closed for user root Jun 09 16:01:11 volumio systemd[1]: systemd-rfkill.service: Succeeded. Jun 09 16:01:11 volumio bthelper[724]: Changing power off succeeded Jun 09 16:01:11 volumio dhcpcd[711]: wlan0: soliciting an IPv6 router Jun 09 16:01:11 volumio bthelper[724]: [CHG] Controller DC:A6:32:B0:D2:19 Class: 0x0000041c Jun 09 16:01:11 volumio bthelper[724]: Changing power on succeeded Jun 09 16:01:12 volumio wireless.js[480]: trying... Jun 09 16:01:12 volumio sudo[772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 09 16:01:12 volumio sudo[772]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:01:12 volumio sudo[772]: pam_unix(sudo:session): session closed for user root Jun 09 16:01:13 volumio wireless.js[480]: trying... Jun 09 16:01:13 volumio sudo[775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 09 16:01:13 volumio sudo[775]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:01:13 volumio sudo[775]: pam_unix(sudo:session): session closed for user root Jun 09 16:01:14 volumio wireless.js[480]: trying... Jun 09 16:01:14 volumio sudo[778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 09 16:01:14 volumio sudo[778]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:01:14 volumio sudo[778]: pam_unix(sudo:session): session closed for user root Jun 09 16:01:14 volumio kernel: cam-dummy-reg: disabling Jun 09 16:01:14 volumio kernel: cam1-reg: disabling Jun 09 16:01:14 volumio volumio-remote-updater[491]: [2022-06-09 16:01:14] [info] asio async_connect error: system:111 (Connection refused) Jun 09 16:01:14 volumio volumio-remote-updater[491]: [2022-06-09 16:01:14] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 09 16:01:14 volumio volumio-remote-updater[491]: [2022-06-09 16:01:14] [error] handle_connect error: Underlying Transport Error Jun 09 16:01:15 volumio wireless.js[480]: trying... Jun 09 16:01:15 volumio sudo[781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 09 16:01:15 volumio sudo[781]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:01:15 volumio sudo[781]: pam_unix(sudo:session): session closed for user root Jun 09 16:01:16 volumio dhcpcd[711]: wlan0: leased 192.168.0.2 for 7200 seconds Jun 09 16:01:16 volumio avahi-daemon[504]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.2. Jun 09 16:01:16 volumio dhcpcd[711]: wlan0: adding route to 192.168.0.0/24 Jun 09 16:01:16 volumio dhcpcd[711]: wlan0: adding default route via 192.168.0.1 Jun 09 16:01:16 volumio avahi-daemon[504]: New relevant interface wlan0.IPv4 for mDNS. Jun 09 16:01:16 volumio avahi-daemon[504]: Registering new address record for 192.168.0.2 on wlan0.IPv4. Jun 09 16:01:16 volumio wireless.js[480]: trying... Jun 09 16:01:16 volumio sudo[804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 09 16:01:16 volumio sudo[804]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:01:16 volumio sudo[804]: pam_unix(sudo:session): session closed for user root Jun 09 16:01:16 volumio wireless.js[480]: Connected to: ----MKmotors Jun 09 16:01:16 volumio wireless.js[480]: ---- Jun 09 16:01:16 volumio sudo[807]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 09 16:01:16 volumio sudo[807]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:01:16 volumio sudo[807]: pam_unix(sudo:session): session closed for user root Jun 09 16:01:16 volumio wireless.js[480]: ... joined AP, wlan0 IPv4 is 192.168.0.2, ipV6 is undefined Jun 09 16:01:16 volumio wireless.js[480]: It's done! AP Jun 09 16:01:16 volumio systemd[1]: Started Wireless Services. Jun 09 16:01:16 volumio systemd[1]: Started Volumio Backend Module. Jun 09 16:01:16 volumio systemd[1]: Started Volumio Streaming Daemon. Jun 09 16:01:16 volumio volumio-streaming-daemon[812]: ############################ Jun 09 16:01:16 volumio volumio-streaming-daemon[812]: # Volumio Streaming Daemon # Jun 09 16:01:16 volumio volumio-streaming-daemon[812]: # Running on port 7777 # Jun 09 16:01:16 volumio volumio-streaming-daemon[812]: ############################ Jun 09 16:01:16 volumio volumio-streaming-daemon[812]: Environment: production Jun 09 16:01:18 volumio ntpd[640]: Listen normally on 3 wlan0 192.168.0.2:123 Jun 09 16:01:18 volumio ntpd[640]: new interface(s) found: waking up resolver Jun 09 16:01:18 volumio volumio[811]: info: ------------------------------------------- Jun 09 16:01:18 volumio volumio[811]: info: ----- Volumio3 ---- Jun 09 16:01:18 volumio volumio[811]: info: ------------------------------------------- Jun 09 16:01:18 volumio volumio[811]: info: ----- System startup ---- Jun 09 16:01:18 volumio volumio[811]: info: ------------------------------------------- Jun 09 16:01:18 volumio nmbd[619]: [2022/06/09 16:01:18.840205, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 09 16:01:18 volumio nmbd[619]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jun 09 16:01:18 volumio systemd[1]: Started Samba NMB Daemon. Jun 09 16:01:18 volumio systemd[1]: Starting Samba Winbind Daemon... Jun 09 16:01:19 volumio winbindd[832]: [2022/06/09 16:01:19.070260, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jun 09 16:01:19 volumio winbindd[832]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jun 09 16:01:19 volumio winbindd[832]: [2022/06/09 16:01:19.095540, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 09 16:01:19 volumio systemd[1]: Started Samba Winbind Daemon. Jun 09 16:01:19 volumio winbindd[832]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jun 09 16:01:19 volumio systemd[1]: Starting Samba SMB Daemon... Jun 09 16:01:19 volumio volumio[811]: info: MYVOLUMIO Environment detected Jun 09 16:01:19 volumio volumio[811]: info: Plugin folders cleanup Jun 09 16:01:19 volumio volumio[811]: info: Scanning into folder /volumio/app/plugins/ Jun 09 16:01:19 volumio volumio[811]: info: Scanning category audio_interface Jun 09 16:01:19 volumio volumio[811]: info: Scanning category miscellanea Jun 09 16:01:19 volumio volumio[811]: info: Scanning category music_service Jun 09 16:01:19 volumio volumio[811]: info: Scanning category plugins.json Jun 09 16:01:19 volumio volumio[811]: info: Scanning category system_controller Jun 09 16:01:19 volumio volumio[811]: info: Scanning category user_interface Jun 09 16:01:19 volumio volumio[811]: info: Scanning into folder /data/plugins/ Jun 09 16:01:19 volumio volumio[811]: info: Plugin folders cleanup completed Jun 09 16:01:19 volumio volumio[811]: info: ------------------------------------------- Jun 09 16:01:19 volumio volumio[811]: info: ----- Core plugins startup ---- Jun 09 16:01:19 volumio volumio[811]: info: ------------------------------------------- Jun 09 16:01:19 volumio volumio[811]: info: Loading plugins from folder /volumio/app/plugins/ Jun 09 16:01:19 volumio volumio[811]: info: Adding plugin upnp to MyMusic Plugins Jun 09 16:01:19 volumio volumio[811]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 09 16:01:19 volumio volumio[811]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 09 16:01:19 volumio winbindd[832]: [2022/06/09 16:01:19.531447, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Jun 09 16:01:19 volumio winbindd[832]: res_names->count = 1, expected 2 Jun 09 16:01:19 volumio volumio[811]: info: Loading plugins from folder /data/plugins/ Jun 09 16:01:19 volumio volumio[811]: info: Loading plugin "system"... Jun 09 16:01:19 volumio smbd[837]: [2022/06/09 16:01:19.550611, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 09 16:01:19 volumio smbd[837]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jun 09 16:01:19 volumio systemd[1]: Started Samba SMB Daemon. Jun 09 16:01:19 volumio systemd[1]: Reached target Multi-User System. Jun 09 16:01:19 volumio systemd[1]: Reached target Graphical Interface. Jun 09 16:01:19 volumio volumio[811]: info: Loading plugin "appearance"... Jun 09 16:01:19 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 09 16:01:19 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 09 16:01:19 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 09 16:01:19 volumio systemd[1]: Startup finished in 9.725s (kernel) + 28.257s (userspace) = 37.982s. Jun 09 16:01:20 volumio volumio[811]: info: Loading plugin "network"... Jun 09 16:01:20 volumio volumio[811]: info: Refreshing Cached IP Addresses Jun 09 16:01:20 volumio sudo[849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 09 16:01:20 volumio sudo[849]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:01:20 volumio sudo[849]: pam_unix(sudo:session): session closed for user root Jun 09 16:01:20 volumio sudo[851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 09 16:01:20 volumio sudo[851]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:01:20 volumio sudo[851]: pam_unix(sudo:session): session closed for user root Jun 09 16:01:20 volumio volumio[811]: info: Loading plugin "services"... Jun 09 16:01:20 volumio volumio[811]: info: Loading plugin "alsa_controller"... Jun 09 16:01:20 volumio sudo[855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 09 16:01:20 volumio sudo[855]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:01:20 volumio volumio[811]: info: Loading plugin "wizard"... Jun 09 16:01:20 volumio volumio[811]: info: Loading plugin "volumio_command_line_client"... Jun 09 16:01:20 volumio volumio[811]: info: Loading plugin "upnp"... Jun 09 16:01:20 volumio volumio[811]: info: [1654790480715] Starting Upmpd Daemon Jun 09 16:01:20 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 09 16:01:20 volumio volumio[811]: info: Loading plugin "my_music"... Jun 09 16:01:20 volumio volumio[811]: info: Loading plugin "mpd"... Jun 09 16:01:21 volumio volumio[811]: info: Creating MPD Configuration file Jun 09 16:01:21 volumio volumio[811]: info: Loading plugin "upnp_browser"... Jun 09 16:01:21 volumio sudo[860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 09 16:01:21 volumio sudo[860]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:01:21 volumio sudo[860]: pam_unix(sudo:session): session closed for user root Jun 09 16:01:21 volumio sudo[862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 09 16:01:21 volumio sudo[862]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:01:21 volumio systemd[1]: Stopping Music Player Daemon... Jun 09 16:01:21 volumio systemd[1]: mpd.service: Succeeded. Jun 09 16:01:21 volumio systemd[1]: Stopped Music Player Daemon. Jun 09 16:01:21 volumio systemd[1]: Starting Music Player Daemon... Jun 09 16:01:21 volumio volumio[811]: info: Loading plugin "networkfs"... Jun 09 16:01:21 volumio volumio[811]: info: Starting Udev Watcher for removable devices Jun 09 16:01:21 volumio mpd[867]: Jun 09 16:01 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 09 16:01:22 volumio volumio[811]: info: Ignoring mount for partition: boot Jun 09 16:01:22 volumio volumio[811]: info: Ignoring mount for partition: volumio Jun 09 16:01:22 volumio volumio[811]: info: Ignoring mount for partition: volumio_data Jun 09 16:01:22 volumio volumio[811]: info: Mounting Device F2AF-32CC Jun 09 16:01:22 volumio mpd[867]: Jun 09 16:01 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jun 09 16:01:22 volumio mpd[867]: Jun 09 16:01 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jun 09 16:01:22 volumio systemd[1]: Started Music Player Daemon. Jun 09 16:01:22 volumio sudo[862]: pam_unix(sudo:session): session closed for user root Jun 09 16:01:22 volumio sudo[873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/F2AF-32CC -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jun 09 16:01:22 volumio sudo[873]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:01:22 volumio kernel: scsi_io_completion_action: 3 callbacks suppressed Jun 09 16:01:22 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=0x08 cmd_age=0s Jun 09 16:01:22 volumio kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Jun 09 16:01:22 volumio kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Jun 09 16:01:22 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 01 28 00 00 50 00 Jun 09 16:01:22 volumio kernel: print_req_error: 3 callbacks suppressed Jun 09 16:01:22 volumio kernel: blk_update_request: critical medium error, dev sda, sector 296 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 0 Jun 09 16:01:22 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=0x08 cmd_age=0s Jun 09 16:01:22 volumio kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Jun 09 16:01:22 volumio kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Jun 09 16:01:22 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 01 60 00 00 08 00 Jun 09 16:01:22 volumio kernel: blk_update_request: critical medium error, dev sda, sector 352 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 0 Jun 09 16:01:22 volumio kernel: Buffer I/O error on dev sda1, logical block 40, async page read Jun 09 16:01:22 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=0x08 cmd_age=0s Jun 09 16:01:22 volumio kernel: sd 0:0:0:0: [sda] tag#0 Sense Key : 0x3 [current] Jun 09 16:01:22 volumio kernel: sd 0:0:0:0: [sda] tag#0 ASC=0x11 ASCQ=0x0 Jun 09 16:01:22 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 00 01 68 00 00 08 00 Jun 09 16:01:22 volumio kernel: blk_update_request: critical medium error, dev sda, sector 360 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 0 Jun 09 16:01:22 volumio kernel: Buffer I/O error on dev sda1, logical block 41, async page read Jun 09 16:01:22 volumio kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Jun 09 16:01:22 volumio sudo[873]: pam_unix(sudo:session): session closed for user root Jun 09 16:01:22 volumio kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Jun 09 16:01:22 volumio volumio[811]: info: Mounting Device 1316-0A10 Jun 09 16:01:22 volumio sudo[883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/1316-0A10 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jun 09 16:01:22 volumio sudo[883]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:01:23 volumio kernel: FAT-fs (sdb1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Jun 09 16:01:23 volumio kernel: FAT-fs (sdb1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Jun 09 16:01:23 volumio sudo[883]: pam_unix(sudo:session): session closed for user root Jun 09 16:01:23 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 09 16:01:23 volumio volumio[811]: info: Loading plugin "alarm-clock"... Jun 09 16:01:23 volumio volumio[811]: info: Loading plugin "airplay_emulation"... Jun 09 16:01:23 volumio volumio[811]: info: Starting Shairport Sync Jun 09 16:01:23 volumio volumio[811]: info: Loading plugin "last_100"... Jun 09 16:01:23 volumio volumio[811]: info: Loading plugin "webradio"... Jun 09 16:01:23 volumio volumio[811]: info: Loading plugin "i2s_dacs"... Jun 09 16:01:23 volumio volumio[811]: info: I2S DAC not set, start Auto-detection Jun 09 16:01:23 volumio volumio[811]: info: Loading plugin "volumiodiscovery"... Jun 09 16:01:23 volumio volumio[811]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 09 16:01:23 volumio volumio[811]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 09 16:01:23 volumio volumio[811]: *** WARNING *** For more information see Jun 09 16:01:23 volumio node[811]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 09 16:01:23 volumio volumio[811]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 09 16:01:23 volumio volumio[811]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 09 16:01:23 volumio volumio[811]: *** WARNING *** For more information see Jun 09 16:01:23 volumio node[811]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 09 16:01:23 volumio node[811]: *** WARNING *** For more information see Jun 09 16:01:23 volumio node[811]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 09 16:01:23 volumio node[811]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 09 16:01:23 volumio node[811]: *** WARNING *** For more information see Jun 09 16:01:23 volumio volumio[811]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 09 16:01:23 volumio volumio[811]: info: Discovery: Started advertising with name: Volumio Jun 09 16:01:23 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 09 16:01:23 volumio volumio[811]: info: Loading plugin "outputs"... Jun 09 16:01:23 volumio volumio[811]: info: Loading plugin "albumart"... Jun 09 16:01:23 volumio volumio[811]: info: Plugin example_plugin is not enabled Jun 09 16:01:23 volumio volumio[811]: info: Loading plugin "inputs"... Jun 09 16:01:23 volumio volumio[811]: info: Loading plugin "updater_comm"... Jun 09 16:01:23 volumio volumio[811]: info: Plugin mpdemulation is not enabled Jun 09 16:01:23 volumio volumio[811]: info: Loading plugin "rest_api"... Jun 09 16:01:23 volumio volumio[811]: info: Loading plugin "websocket"... Jun 09 16:01:23 volumio volumio[811]: info: Loading i18n strings for locale ko Jun 09 16:01:23 volumio volumio[811]: Updating browse sources language Jun 09 16:01:23 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 16:01:23 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 09 16:01:23 volumio volumio[811]: info: CoreCommandRouter::initPlayerControls Jun 09 16:01:23 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:01:23 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:01:23 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:01:23 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:01:23 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:01:23 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:01:23 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:01:23 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:01:23 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 09 16:01:23 volumio volumio[811]: Express server listening on port 3000 Jun 09 16:01:23 volumio volumio[811]: [Metrics] WebUI: 5s 860.02ms Jun 09 16:01:23 volumio volumio[811]: Forking 3 albumart workers Jun 09 16:01:23 volumio volumio[811]: info: CoreStateMachine::resetVolumioState Jun 09 16:01:23 volumio volumio[811]: info: CoreStateMachine::getcurrentVolume Jun 09 16:01:23 volumio volumio[811]: info: CoreCommandRouter::volumioRetrievevolume Jun 09 16:01:23 volumio volumio[811]: info: CoreStateMachine::pushState Jun 09 16:01:23 volumio volumio[811]: info: CorePlayQueue::getTrack 0 Jun 09 16:01:23 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 16:01:23 volumio volumio[811]: info: CoreCommandRouter::volumioPushState Jun 09 16:01:23 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 16:01:23 volumio volumio[811]: info: MPD Permissions set Jun 09 16:01:23 volumio volumio[811]: verbose: New Socket.io Connection to 192.168.0.2 from 192.168.0.8 UA: Mozilla/5.0 (Linux; Android 16; SM-S918N Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.116 Mobile Safari/537.36 Total Clients: 1 Jun 09 16:01:23 volumio volumio[811]: verbose: New Socket.io Connection to 192.168.0.2:3000 from 192.168.0.8 UA: Dart/3.9 (dart:io) Total Clients: 2 Jun 09 16:01:23 volumio volumio[811]: verbose: New Socket.io Connection to 192.168.0.2 from 192.168.0.8 UA: Mozilla/5.0 (Linux; Android 16; SM-S918N Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.116 Mobile Safari/537.36 Total Clients: 3 Jun 09 16:01:23 volumio volumio[811]: info: MPD running with PID867 Jun 09 16:01:23 volumio volumio[811]: ,establishing connection Jun 09 16:01:23 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:01:23 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:01:23 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:01:23 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:01:23 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:01:23 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:01:23 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:01:23 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:01:23 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 16:01:23 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:01:23 volumio volumio[811]: info: Reloading queue from file Jun 09 16:01:24 volumio volumio[811]: error: updateQueue error: null Jun 09 16:01:24 volumio volumio[811]: info: CoreStateMachine::setRepeat null single undefined Jun 09 16:01:24 volumio volumio[811]: info: CoreStateMachine::pushState Jun 09 16:01:24 volumio volumio[811]: info: CorePlayQueue::getTrack 0 Jun 09 16:01:24 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 16:01:24 volumio volumio[811]: info: CoreCommandRouter::volumioPushState Jun 09 16:01:24 volumio volumio[811]: info: CoreStateMachine::setRandom null Jun 09 16:01:24 volumio volumio[811]: info: CoreStateMachine::pushState Jun 09 16:01:24 volumio volumio[811]: info: CorePlayQueue::getTrack 0 Jun 09 16:01:24 volumio volumio[811]: info: CoreCommandRouter::volumioPushState Jun 09 16:01:24 volumio volumio[811]: info: Setting Device type: Raspberry PI Jun 09 16:01:24 volumio volumio[811]: info: Completed loading Core Plugins Jun 09 16:01:24 volumio volumio[811]: info: Preparing to generate the ALSA configuration file Jun 09 16:01:24 volumio volumio[811]: info: Asound.conf file unchanged, so no further update is needed Jun 09 16:01:24 volumio volumio[811]: info: Output device has changed, restarting MPD Jun 09 16:01:24 volumio volumio[811]: info: Output device has changed, restarting Shairport Sync Jun 09 16:01:24 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:01:24 volumio sudo[931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 09 16:01:24 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 16:01:24 volumio sudo[931]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:01:24 volumio sudo[931]: pam_unix(sudo:session): session closed for user root Jun 09 16:01:24 volumio sudo[932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 09 16:01:24 volumio sudo[932]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:01:24 volumio systemd[1]: Stopping Music Player Daemon... Jun 09 16:01:24 volumio volumio[811]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 09 16:01:24 volumio systemd[1]: mpd.service: Succeeded. Jun 09 16:01:24 volumio systemd[1]: Stopped Music Player Daemon. Jun 09 16:01:24 volumio systemd[1]: Starting Music Player Daemon... Jun 09 16:01:24 volumio volumio[811]: info: ___________ START PLUGINS ___________ Jun 09 16:01:24 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 09 16:01:24 volumio volumio[811]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 09 16:01:24 volumio volumio[811]: info: [1654790484174] CoreMusicLibrary::Adding element Media Servers Jun 09 16:01:24 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 16:01:24 volumio volumio[811]: Cannot find translation for sourceMedia Servers Jun 09 16:01:24 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:01:24 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 16:01:24 volumio volumio[811]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 09 16:01:24 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:01:24 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 16:01:24 volumio volumio[811]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 09 16:01:24 volumio volumio[811]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 09 16:01:24 volumio volumio[811]: info: [1654790484298] CoreMusicLibrary::Adding element Last_100 Jun 09 16:01:24 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 16:01:24 volumio volumio[811]: Cannot find translation for sourceMedia Servers Jun 09 16:01:24 volumio volumio[811]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 09 16:01:24 volumio volumio[811]: info: [1654790484307] CoreMusicLibrary::Adding element Webradio Jun 09 16:01:24 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 09 16:01:24 volumio volumio[811]: Cannot find translation for sourceMedia Servers Jun 09 16:01:24 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 09 16:01:24 volumio volumio[811]: info: Volumio Calling Home Jun 09 16:01:24 volumio volumio[811]: Starting albumart workers Jun 09 16:01:24 volumio sudo[855]: pam_unix(sudo:session): session closed for user root Jun 09 16:01:24 volumio volumio[811]: info: Completed starting Core Plugins Jun 09 16:01:24 volumio volumio[811]: info: ------------------------------------------- Jun 09 16:01:24 volumio volumio[811]: info: ----- MyVolumio plugins startup ---- Jun 09 16:01:24 volumio volumio[811]: info: ------------------------------------------- Jun 09 16:01:24 volumio volumio[811]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 09 16:01:24 volumio volumio[811]: Starting albumart workers Jun 09 16:01:24 volumio volumio[811]: error: MPD error: Error: write EPIPE Jun 09 16:01:24 volumio volumio[811]: info: MPD Permissions set Jun 09 16:01:24 volumio volumio[811]: info: Discovery: adding 484d347b-1a4c-4199-a904-4a9fe1213441 Jun 09 16:01:24 volumio volumio[811]: info: mDNS: Found device Volumio Jun 09 16:01:24 volumio volumio[811]: info: CoreCommandRouter::volumioGetState Jun 09 16:01:24 volumio volumio[811]: info: CorePlayQueue::getTrack 0 Jun 09 16:01:24 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 09 16:01:24 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 09 16:01:24 volumio volumio[811]: info: Discovery: Getting this device information Jun 09 16:01:24 volumio volumio[811]: info: CoreCommandRouter::volumioGetState Jun 09 16:01:24 volumio volumio[811]: info: CorePlayQueue::getTrack 0 Jun 09 16:01:24 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 09 16:01:24 volumio volumio[811]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Jun 09 16:01:24 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:01:24 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:01:24 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:01:24 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:01:24 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:01:24 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:01:24 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:01:24 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:01:24 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 16:01:24 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:01:24 volumio volumio[811]: info: Starting Shairport Sync Jun 09 16:01:24 volumio volumio-remote-updater[491]: [2022-06-09 16:01:24] [connect] Successful connection Jun 09 16:01:24 volumio volumio[811]: info: Starting Shairport Sync Jun 09 16:01:24 volumio volumio[811]: info: Starting Shairport Sync Jun 09 16:01:24 volumio sudo[955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 09 16:01:24 volumio volumio[811]: Starting albumart workers Jun 09 16:01:24 volumio sudo[955]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:01:24 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 09 16:01:24 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 09 16:01:24 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 09 16:01:24 volumio sudo[959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 09 16:01:24 volumio sudo[959]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:01:24 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 09 16:01:24 volumio sudo[955]: pam_unix(sudo:session): session closed for user root Jun 09 16:01:24 volumio sudo[958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 09 16:01:24 volumio shairport-sync[969]: free(): double free detected in tcache 2 Jun 09 16:01:24 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 09 16:01:24 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=6/ABRT Jun 09 16:01:24 volumio systemd[1]: shairport-sync.service: Failed with result 'signal'. Jun 09 16:01:24 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 09 16:01:24 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 09 16:01:24 volumio sudo[959]: pam_unix(sudo:session): session closed for user root Jun 09 16:01:24 volumio sudo[958]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:01:24 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 09 16:01:24 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 09 16:01:24 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 09 16:01:24 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 09 16:01:25 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 09 16:01:25 volumio sudo[958]: pam_unix(sudo:session): session closed for user root Jun 09 16:01:25 volumio mpd[946]: Jun 09 16:01 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 09 16:01:25 volumio volumio-remote-updater[491]: [2022-06-09 16:01:25] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1654790484 101 Jun 09 16:01:25 volumio volumio[811]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 5 Jun 09 16:01:25 volumio volumio[811]: info: Shairport-Sync Started Jun 09 16:01:25 volumio volumio[811]: Error adding Membership: Error: addMembership EINVAL Jun 09 16:01:25 volumio volumio[811]: info: Volumio called home Jun 09 16:01:25 volumio volumio[811]: info: Shairport-Sync Started Jun 09 16:01:25 volumio volumio[811]: info: Shairport-Sync Started Jun 09 16:01:25 volumio volumio[811]: info: CoreCommandRouter::volumioGetState Jun 09 16:01:25 volumio volumio[811]: info: CorePlayQueue::getTrack 0 Jun 09 16:01:25 volumio mpd[946]: Jun 09 16:01 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jun 09 16:01:25 volumio mpd[946]: Jun 09 16:01 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jun 09 16:01:25 volumio systemd[1]: Started Music Player Daemon. Jun 09 16:01:25 volumio sudo[932]: pam_unix(sudo:session): session closed for user root Jun 09 16:01:25 volumio volumio[811]: error: updateQueue error: null Jun 09 16:01:26 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 09 16:01:26 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 09 16:01:26 volumio sudo[996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 09 16:01:26 volumio sudo[996]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:01:26 volumio sudo[996]: pam_unix(sudo:session): session closed for user root Jun 09 16:01:26 volumio sudo[998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 09 16:01:26 volumio sudo[998]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:01:26 volumio sudo[998]: pam_unix(sudo:session): session closed for user root Jun 09 16:01:26 volumio volumio[811]: verbose: New Socket.io Connection to 192.168.0.2 from 192.168.0.8 UA: Mozilla/5.0 (Linux; Android 16; SM-S918N Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.116 Mobile Safari/537.36 Total Clients: 6 Jun 09 16:01:26 volumio sudo[1002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 09 16:01:27 volumio sudo[1002]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:01:27 volumio sudo[1002]: pam_unix(sudo:session): session closed for user root Jun 09 16:01:27 volumio sudo[1004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 09 16:01:27 volumio sudo[1004]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:01:27 volumio sudo[1004]: pam_unix(sudo:session): session closed for user root Jun 09 16:01:27 volumio volumio[811]: verbose: New Socket.io Connection to 192.168.0.2 from 192.168.0.8 UA: Mozilla/5.0 (Linux; Android 16; SM-S918N Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.116 Mobile Safari/537.36 Total Clients: 7 Jun 09 16:01:27 volumio volumio[811]: info: CoreCommandRouter::volumioGetState Jun 09 16:01:27 volumio volumio[811]: info: CorePlayQueue::getTrack 0 Jun 09 16:01:27 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 09 16:01:27 volumio volumio[811]: info: Listing playlists Jun 09 16:01:27 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 09 16:01:27 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 09 16:01:27 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 09 16:01:27 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 09 16:01:28 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jun 09 16:01:28 volumio volumio[811]: verbose: New Socket.io Connection to 192.168.0.2 from 192.168.0.8 UA: Mozilla/5.0 (Linux; Android 16; SM-S918N Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.116 Mobile Safari/537.36 Total Clients: 8 Jun 09 16:01:28 volumio volumio[811]: info: CoreCommandRouter::volumioGetState Jun 09 16:01:28 volumio volumio[811]: info: CorePlayQueue::getTrack 0 Jun 09 16:01:28 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 09 16:01:28 volumio volumio[811]: info: Listing playlists Jun 09 16:01:28 volumio volumio[811]: info: CoreCommandRouter::volumioGetQueue Jun 09 16:01:28 volumio volumio[811]: info: CoreStateMachine::getQueue Jun 09 16:01:28 volumio volumio[811]: info: CorePlayQueue::getQueue Jun 09 16:01:29 volumio volumio[811]: info: CoreCommandRouter::volumioPlay Jun 09 16:01:29 volumio volumio[811]: verbose: UNSET VOLATILE Jun 09 16:01:29 volumio volumio[811]: info: CoreStateMachine::play index undefined Jun 09 16:01:29 volumio volumio[811]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 09 16:01:29 volumio volumio[811]: info: CorePlayQueue::getTrack 0 Jun 09 16:01:29 volumio volumio[811]: info: CoreStateMachine::startPlaybackTimer Jun 09 16:01:29 volumio volumio[811]: info: CorePlayQueue::getTrack 0 Jun 09 16:01:29 volumio volumio[811]: verbose: ControllerMpd::clearAddPlayTracks USB/1316-0A10/_언플러님/1 VA/02 Alison Krauss - When You Say Nothing at All.flac Jun 09 16:01:29 volumio volumio[811]: verbose: ControllerMpd::sendMpdCommand stop Jun 09 16:01:29 volumio volumio[811]: info: sendMpdCommand stop took 2 milliseconds Jun 09 16:01:29 volumio volumio[811]: verbose: ControllerMpd::sendMpdCommand clear Jun 09 16:01:29 volumio volumio[811]: info: Jun 09 16:01:29 volumio volumio[811]: ---------------------------- MPD announces system playlist update Jun 09 16:01:29 volumio volumio[811]: info: Ignoring MPD Status Update Jun 09 16:01:29 volumio volumio[811]: info: sendMpdCommand clear took 2 milliseconds Jun 09 16:01:29 volumio volumio[811]: verbose: ControllerMpd::sendMpdCommand add "USB/1316-0A10/_언플러님/1 VA/02 Alison Krauss - When You Say Nothing at All.flac" Jun 09 16:01:29 volumio volumio[811]: error: updateQueue error: null Jun 09 16:01:29 volumio volumio[811]: info: Jun 09 16:01:29 volumio volumio[811]: ---------------------------- MPD announces system playlist update Jun 09 16:01:29 volumio volumio[811]: info: Ignoring MPD Status Update Jun 09 16:01:29 volumio volumio[811]: info: ------------------------------ 4ms Jun 09 16:01:29 volumio volumio[811]: info: sendMpdCommand add "USB/1316-0A10/_언플러님/1 VA/02 Alison Krauss - When You Say Nothing at All.flac" took 2 milliseconds Jun 09 16:01:29 volumio volumio[811]: verbose: ControllerMpd::sendMpdCommand play Jun 09 16:01:29 volumio volumio[811]: info: ------------------------------ 4ms Jun 09 16:01:29 volumio volumio[811]: info: sendMpdCommand play took 3 milliseconds Jun 09 16:01:29 volumio volumio[811]: info: Jun 09 16:01:29 volumio volumio[811]: ---------------------------- MPD announces state update: player Jun 09 16:01:29 volumio volumio[811]: info: ControllerMpd::getState Jun 09 16:01:29 volumio volumio[811]: verbose: ControllerMpd::sendMpdCommand status Jun 09 16:01:29 volumio volumio[811]: info: Jun 09 16:01:29 volumio volumio[811]: ---------------------------- MPD announces state update: player Jun 09 16:01:29 volumio volumio[811]: info: sendMpdCommand status took 23 milliseconds Jun 09 16:01:29 volumio volumio[811]: info: ControllerMpd::getState Jun 09 16:01:29 volumio volumio[811]: verbose: ControllerMpd::sendMpdCommand status Jun 09 16:01:29 volumio volumio[811]: verbose: ControllerMpd::parseState Jun 09 16:01:29 volumio volumio[811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 09 16:01:29 volumio volumio[811]: info: sendMpdCommand status took 4 milliseconds Jun 09 16:01:29 volumio volumio[811]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 09 16:01:29 volumio volumio[811]: verbose: ControllerMpd::parseState Jun 09 16:01:29 volumio volumio[811]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 09 16:01:29 volumio volumio[811]: verbose: ControllerMpd::parseTrackInfo Jun 09 16:01:29 volumio volumio[811]: info: ControllerMpd::pushState Jun 09 16:01:29 volumio volumio[811]: info: CoreCommandRouter::servicePushState Jun 09 16:01:29 volumio volumio[811]: info: CorePlayQueue::getTrack 0 Jun 09 16:01:29 volumio volumio[811]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":261,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"02 Alison Krauss - When You Say Nothing at All.flac","artist":null,"album":null,"uri":"USB/1316-0A10/_언플러님/1 VA/02 Alison Krauss - When You Say Nothing at All.flac","trackType":"flac"} Jun 09 16:01:29 volumio volumio[811]: verbose: CURRENT POSITION 0 Jun 09 16:01:29 volumio volumio[811]: info: CoreStateMachine::syncState stateService play Jun 09 16:01:29 volumio volumio[811]: info: CoreStateMachine::syncState currentStatus stop Jun 09 16:01:29 volumio volumio[811]: info: CoreStateMachine::pushState Jun 09 16:01:29 volumio volumio[811]: info: CorePlayQueue::getTrack 0 Jun 09 16:01:29 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 16:01:29 volumio volumio[811]: info: CoreCommandRouter::volumioPushState Jun 09 16:01:29 volumio volumio[811]: info: ------------------------------ 50ms Jun 09 16:01:29 volumio volumio[811]: info: sendMpdCommand playlistinfo took 19 milliseconds Jun 09 16:01:29 volumio volumio[811]: verbose: ControllerMpd::parseTrackInfo Jun 09 16:01:29 volumio volumio[811]: info: ControllerMpd::pushState Jun 09 16:01:29 volumio volumio[811]: info: CoreCommandRouter::servicePushState Jun 09 16:01:29 volumio volumio[811]: info: CorePlayQueue::getTrack 0 Jun 09 16:01:29 volumio volumio[811]: verbose: STATE SERVICE {"status":"play","position":0,"seek":868,"duration":261,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"496 Kbps","isStreaming":false,"title":"02 Alison Krauss - When You Say Nothing at All.flac","artist":null,"album":null,"uri":"USB/1316-0A10/_언플러님/1 VA/02 Alison Krauss - When You Say Nothing at All.flac","trackType":"flac"} Jun 09 16:01:29 volumio volumio[811]: verbose: CURRENT POSITION 0 Jun 09 16:01:29 volumio volumio[811]: info: CoreStateMachine::syncState stateService play Jun 09 16:01:29 volumio volumio[811]: info: CoreStateMachine::syncState currentStatus play Jun 09 16:01:29 volumio volumio[811]: info: Received an update from plugin. extracting info from payload Jun 09 16:01:29 volumio volumio[811]: info: CoreStateMachine::pushState Jun 09 16:01:29 volumio volumio[811]: info: CorePlayQueue::getTrack 0 Jun 09 16:01:29 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 09 16:01:29 volumio volumio[811]: info: CoreCommandRouter::volumioPushState Jun 09 16:01:29 volumio volumio[811]: info: CoreStateMachine::pushState Jun 09 16:01:29 volumio volumio[811]: info: CorePlayQueue::getTrack 0 Jun 09 16:01:29 volumio volumio[811]: info: CoreCommandRouter::volumioPushState Jun 09 16:01:29 volumio volumio[811]: info: ------------------------------ 52ms Jun 09 16:01:29 volumio sh[393]: timed out Jun 09 16:01:29 volumio dhcpcd[440]: timed out Jun 09 16:01:29 volumio sh[393]: dhcpcd exited Jun 09 16:01:29 volumio dhcpcd[440]: dhcpcd exited Jun 09 16:01:29 volumio sh[393]: ifup: failed to bring up eth0 Jun 09 16:01:29 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jun 09 16:01:29 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jun 09 16:01:30 volumio sudo[1016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 09 16:01:30 volumio sudo[1016]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:01:30 volumio sudo[1016]: pam_unix(sudo:session): session closed for user root Jun 09 16:01:30 volumio sudo[1018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 09 16:01:30 volumio sudo[1018]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:01:30 volumio sudo[1018]: pam_unix(sudo:session): session closed for user root Jun 09 16:01:30 volumio sudo[1022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 09 16:01:30 volumio sudo[1022]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:01:30 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jun 09 16:01:30 volumio sudo[1022]: pam_unix(sudo:session): session closed for user root Jun 09 16:01:30 volumio volumio[811]: info: Upmpdcli Daemon Started Jun 09 16:01:30 volumio volumio[1024]: Generating RSA private key, 4096 bit long modulus (2 primes) Jun 09 16:01:33 volumio volumio[1024]: .........................++++ Jun 09 16:01:36 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Jun 09 16:01:44 volumio volumio[1024]: ...........................................................................................................................++++ Jun 09 16:01:44 volumio volumio[1024]: e is 65537 (0x010001) Jun 09 16:01:44 volumio volumio[1024]: writing RSA key Jun 09 16:01:44 volumio volumio[811]: error: MyVolumio Plugin failed to start in a timely fashion Jun 09 16:01:44 volumio volumio[811]: info: BOOT COMPLETED Jun 09 16:01:44 volumio volumio[811]: [Metrics] CommandRouter: 25s 998.95ms Jun 09 16:01:44 volumio volumio[811]: info: CoreCommandRouter::volumiosetStartupVolume Jun 09 16:01:44 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 09 16:01:44 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 16:01:44 volumio volumio[811]: info: CoreCommandRouter::Close All Modals sent Jun 09 16:01:44 volumio volumio[811]: info: CoreCommandRouter::Close All Modals sent Jun 09 16:01:44 volumio volumio[811]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Jun 09 16:01:44 volumio volumio[811]: aplay: main:828: audio open error: Device or resource busy Jun 09 16:01:45 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 09 16:01:45 volumio volumio[811]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 09 16:01:45 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 09 16:01:45 volumio volumio[811]: info: Received Get System Version Jun 09 16:01:45 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 09 16:01:45 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 09 16:01:45 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 09 16:01:45 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 09 16:01:54 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 09 16:01:54 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 09 16:01:55 volumio volumio[811]: verbose: New Socket.io Connection to 192.168.0.2 from 192.168.0.8 UA: Mozilla/5.0 (Linux; Android 16; SM-S918N Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.116 Mobile Safari/537.36 Total Clients: 9 Jun 09 16:01:55 volumio volumio[811]: info: CoreCommandRouter::volumioGetState Jun 09 16:01:55 volumio volumio[811]: info: CorePlayQueue::getTrack 0 Jun 09 16:01:55 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 09 16:01:55 volumio volumio[811]: info: Listing playlists Jun 09 16:01:59 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 09 16:01:59 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 09 16:02:00 volumio sudo[1129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 09 16:02:00 volumio sudo[1129]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:02:00 volumio sudo[1129]: pam_unix(sudo:session): session closed for user root Jun 09 16:02:00 volumio sudo[1131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 09 16:02:00 volumio sudo[1131]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:02:00 volumio sudo[1131]: pam_unix(sudo:session): session closed for user root Jun 09 16:02:00 volumio volumio[811]: verbose: New Socket.io Connection to 192.168.0.2 from 192.168.0.8 UA: Mozilla/5.0 (Linux; Android 16; SM-S918N Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.116 Mobile Safari/537.36 Total Clients: 9 Jun 09 16:02:00 volumio sudo[1137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 09 16:02:00 volumio sudo[1137]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:02:00 volumio sudo[1137]: pam_unix(sudo:session): session closed for user root Jun 09 16:02:00 volumio sudo[1139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 09 16:02:00 volumio sudo[1139]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 09 16:02:00 volumio sudo[1139]: pam_unix(sudo:session): session closed for user root Jun 09 16:02:00 volumio volumio[811]: verbose: New Socket.io Connection to 192.168.0.2 from 192.168.0.8 UA: Mozilla/5.0 (Linux; Android 16; SM-S918N Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.116 Mobile Safari/537.36 Total Clients: 10 Jun 09 16:02:00 volumio volumio[811]: info: CoreCommandRouter::volumioGetState Jun 09 16:02:00 volumio volumio[811]: info: CorePlayQueue::getTrack 0 Jun 09 16:02:00 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 09 16:02:00 volumio volumio[811]: info: Listing playlists Jun 09 16:02:00 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 09 16:02:00 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 09 16:02:00 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 09 16:02:00 volumio volumio[811]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 09 16:02:01 volumio volumio[811]: info: CoreCommandRouter::volumioGetQueue Jun 09 16:02:01 volumio volumio[811]: info: CoreStateMachine::getQueue Jun 09 16:02:01 volumio volumio[811]: info: CorePlayQueue::getQueue Jun 09 16:02:06 volumio volumio[811]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 09 16:02:06 volumio volumio[811]: Error: certificate is not yet valid Jun 09 16:02:06 volumio volumio[811]: at TLSSocket.onConnectSecure (_tls_wrap.js:1497:34) Jun 09 16:02:06 volumio volumio[811]: at TLSSocket.emit (events.js:315:20) Jun 09 16:02:06 volumio volumio[811]: at TLSSocket._finishInit (_tls_wrap.js:932:8) Jun 09 16:02:06 volumio volumio[811]: at TLSWrap.ssl.onhandshakedone (_tls_wrap.js:706:12) { Jun 09 16:02:06 volumio volumio[811]: code: 'CERT_NOT_YET_VALID' Jun 09 16:02:06 volumio volumio[811]: } Jun 09 16:02:06 volumio volumio[811]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 09 16:02:06 volumio sudo[1165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2022-06-09 16:01 Jun 09 16:02:06 volumio sudo[1165]: 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="3cfa1502993c2310fc263d6ebda1707071fe4cc2" VOLUMIO_FE_VERSION="8efe4f08f83e5de574651b8ed2333629c140d365" VOLUMIO_FE3_VERSION="8f1d07fcde806ea34f52cff908ec76359e14970e" VOLUMIO_BE_VERSION="e249dfa388452e6b8443121dc9b4cabca459c531" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 09 Jun 2022 05:55:51 PM CEST" VOLUMIO_VERSION="3.301" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6a8974e587cd4bab189d231f79b65462"