Dec 28 00:31:00 volumio4 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Dec 28 00:31:00 volumio4 ntpd[1386]: INIT: ntpd ntpsec-1.2.2: Starting Dec 28 00:31:00 volumio4 ntpd[1386]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec Dec 28 00:31:00 volumio4 ntp-systemd-wrapper[1386]: 2025-12-28T00:31:00 ntpd[1386]: INIT: ntpd ntpsec-1.2.2: Starting Dec 28 00:31:00 volumio4 ntp-systemd-wrapper[1386]: 2025-12-28T00:31:00 ntpd[1386]: INIT: Command line: /usr/sbin/ntpd -p /run/ntpd.pid -c /etc/ntpsec/ntp.conf -g -N -u ntpsec:ntpsec Dec 28 00:31:00 volumio4 systemd[1]: Started volumio-ipchange.service - Volumio IP Change Monitor. Dec 28 00:31:00 volumio4 systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 28 00:31:00 volumio4 ntpd[1395]: INIT: precision = 0.057 usec (-24) Dec 28 00:31:00 volumio4 ntpd[1395]: INIT: successfully locked into RAM Dec 28 00:31:00 volumio4 ntpd[1395]: CONFIG: readconfig: parsing file: /etc/ntpsec/ntp.conf Dec 28 00:31:00 volumio4 ntpd[1395]: CONFIG: restrict nopeer ignored Dec 28 00:31:00 volumio4 systemd[1]: soundcard-init.service: Deactivated successfully. Dec 28 00:31:00 volumio4 ntpd[1395]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Dec 28 00:31:00 volumio4 ntpd[1395]: CLOCK: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2026-06-28T00:00Z last=2017-01-01T00:00Z ofs=37 Dec 28 00:31:00 volumio4 ntpd[1395]: INIT: Using SO_TIMESTAMPNS(ns) Dec 28 00:31:00 volumio4 ntpd[1395]: IO: Listen and drop on 0 v6wildcard [::]:123 Dec 28 00:31:00 volumio4 ntpd[1395]: IO: Listen and drop on 1 v4wildcard 0.0.0.0:123 Dec 28 00:31:00 volumio4 ntpd[1395]: IO: Listen normally on 2 lo 127.0.0.1:123 Dec 28 00:31:00 volumio4 ntpd[1395]: IO: Listen normally on 3 eth0 192.168.2.61:123 Dec 28 00:31:00 volumio4 ntpd[1395]: IO: Listen normally on 4 lo [::1]:123 Dec 28 00:31:00 volumio4 ntpd[1395]: IO: bind(21) AF_INET6 fe80::b29b:3e84:e2b3:fdcb%2#123 flags 0x1 failed: Cannot assign requested address Dec 28 00:31:00 volumio4 ntpd[1395]: IO: unable to create socket on eth0 (5) for fe80::b29b:3e84:e2b3:fdcb%2#123 Dec 28 00:31:00 volumio4 ntpd[1395]: IO: failed to init interface for address fe80::b29b:3e84:e2b3:fdcb%2 Dec 28 00:31:00 volumio4 ntpd[1395]: IO: Listening on routing socket on fd #21 for interface updates Dec 28 00:31:00 volumio4 systemd[1]: Started ntpsec.service - Network Time Service. Dec 28 00:31:00 volumio4 systemd[1]: Started rc-local.service - /etc/rc.local Compatibility. Dec 28 00:31:00 volumio4 systemd[1]: Started rpc-statd-notify.service - Notify NFS peers of a restart. Dec 28 00:31:00 volumio4 welcome[1396]: Resolved ip:[1] 192.168.2.61 Dec 28 00:31:00 volumio4 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Dec 28 00:31:00 volumio4 systemd[1]: Starting plymouth-quit-wait.service - Hold until boot process finishes up... Dec 28 00:31:00 volumio4 systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... Dec 28 00:31:00 volumio4 ntpd[1395]: INIT: MRU 10922 entries, 13 hash bits, 65536 bytes Dec 28 00:31:00 volumio4 ntpd[1395]: INIT: Built with OpenSSL 3.0.9 30 May 2023, 30000090 Dec 28 00:31:00 volumio4 ntpd[1395]: INIT: Running with OpenSSL 3.0.17 1 Jul 2025, 30000110 Dec 28 00:31:00 volumio4 ntpd[1395]: NTSc: Using system default root certificates. Dec 28 00:31:00 volumio4 ntpd[1395]: statistics directory /var/log/ntpsec/ does not exist or is unwriteable, error No such file or directory Dec 28 00:31:00 volumio4 systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 28 00:31:00 volumio4 systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. Dec 28 00:31:00 volumio4 systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. Dec 28 00:31:00 volumio4 systemd[1]: Received SIGRTMIN+21 from PID 493 (n/a). Dec 28 00:31:00 volumio4 sshd[1416]: Server listening on 0.0.0.0 port 22. Dec 28 00:31:00 volumio4 systemd[1]: Received SIGRTMIN+21 from PID 493 (n/a). Dec 28 00:31:00 volumio4 sshd[1416]: Server listening on :: port 22. Dec 28 00:31:00 volumio4 systemd[1]: Started ssh.service - OpenBSD Secure Shell server. Dec 28 00:31:00 volumio4 systemd[1]: Started getty@tty1.service - Getty on tty1. Dec 28 00:31:00 volumio4 systemd[1]: Reached target getty.target - Login Prompts. Dec 28 00:31:00 volumio4 ifplugd(eth0)[1428]: ifplugd 0.28 initializing. Dec 28 00:31:00 volumio4 ifplugd(eth0)[1428]: Using interface eth0/00:0C:29:A2:EB:43 with driver (version: 6.12.57-volumio) Dec 28 00:31:00 volumio4 ifplugd(eth0)[1428]: Using detection mode: SIOCETHTOOL Dec 28 00:31:00 volumio4 ifplugd(eth0)[1428]: Initialization complete, link beat detected. Dec 28 00:31:00 volumio4 ifplugd(eth0)[1428]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Dec 28 00:31:00 volumio4 NetworkManager[910]: [1766853060.5811] dhcp6 (eth0): activation: beginning transaction (timeout in 45 seconds) Dec 28 00:31:00 volumio4 dhcpcd[1452]: dhcp6_openudp: Address already in use Dec 28 00:31:00 volumio4 NetworkManager[910]: [1766853060.5812] dhcp6 (eth0): canceled DHCP transaction Dec 28 00:31:00 volumio4 dhcpcd[1452]: ps_inet_listenin6: Address already in use Dec 28 00:31:00 volumio4 NetworkManager[910]: [1766853060.5812] dhcp6 (eth0): state changed no lease Dec 28 00:31:00 volumio4 dhcpcd[1452]: ps_root_recvmsg: Address already in use Dec 28 00:31:00 volumio4 NetworkManager[910]: [1766853060.5814] policy: set 'Ifupdown (eth0)' (eth0) as default for IPv6 routing and DNS Dec 28 00:31:00 volumio4 dhcpcd[928]: eth0: Router Advertisement from fe80::1eb7:2cff:fe74:17f0 Dec 28 00:31:00 volumio4 dhcpcd[764]: eth0: Router Advertisement from fe80::1eb7:2cff:fe74:17f0 Dec 28 00:31:00 volumio4 dhcpcd[928]: eth0: adding address 240e:34c:81:5ee0:b29b:3e84:e2b3:fdcb/64 Dec 28 00:31:00 volumio4 avahi-daemon[873]: Leaving mDNS multicast group on interface eth0.IPv6 with address fe80::b29b:3e84:e2b3:fdcb. Dec 28 00:31:00 volumio4 dhcpcd[928]: eth0: adding route to 240e:34c:81:5ee0::/64 Dec 28 00:31:00 volumio4 dhcpcd[764]: eth0: adding address 240e:34c:81:5ee0:b29b:3e84:e2b3:fdcb/64 Dec 28 00:31:00 volumio4 dhcpcd[928]: eth0: requesting DHCPv6 information Dec 28 00:31:00 volumio4 dhcpcd[928]: eth0: adding default route via fe80::1eb7:2cff:fe74:17f0 Dec 28 00:31:00 volumio4 dhcpcd[764]: eth0: adding route to 240e:34c:81:5ee0::/64 Dec 28 00:31:00 volumio4 dhcpcd[764]: eth0: requesting DHCPv6 information Dec 28 00:31:00 volumio4 dhcpcd[764]: eth0: adding default route via fe80::1eb7:2cff:fe74:17f0 Dec 28 00:31:00 volumio4 systemd[1]: welcome.service: Deactivated successfully. Dec 28 00:31:00 volumio4 avahi-daemon[873]: Joining mDNS multicast group on interface eth0.IPv6 with address 240e:34c:81:5ee0:b29b:3e84:e2b3:fdcb. Dec 28 00:31:00 volumio4 systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 28 00:31:00 volumio4 systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 28 00:31:00 volumio4 ifplugd(eth0)[1428]: client: ifup: interface eth0 already configured Dec 28 00:31:00 volumio4 avahi-daemon[873]: Registering new address record for 240e:34c:81:5ee0:b29b:3e84:e2b3:fdcb on eth0.*. Dec 28 00:31:00 volumio4 avahi-daemon[873]: Withdrawing address record for fe80::b29b:3e84:e2b3:fdcb on eth0. Dec 28 00:31:00 volumio4 avahi-daemon[873]: Registering new address record for 240e:34c:81:5ee0:cf50:d0c3:11a:ab65 on eth0.*. Dec 28 00:31:00 volumio4 systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 28 00:31:00 volumio4 welcome[1455]: Resolved ip:[3] 192.168.2.61 240e:34c:81:5ee0:cf50:d0c3:11a:ab65 240e:34c:81:5ee0:b29b:3e84:e2b3:fdcb Dec 28 00:31:00 volumio4 systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 28 00:31:00 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:00 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:00 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 00:31:00 volumio4 systemd[1]: welcome.service: Deactivated successfully. Dec 28 00:31:00 volumio4 systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 28 00:31:00 volumio4 systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 28 00:31:00 volumio4 ifplugd(eth0)[1428]: Program executed successfully. Dec 28 00:31:00 volumio4 ifplugd[1367]: Network Interface Plugging Daemon...start eth0...done. Dec 28 00:31:00 volumio4 systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 28 00:31:00 volumio4 systemd[1]: Started ifplugd.service - LSB: Brings up/down network automatically. Dec 28 00:31:00 volumio4 welcome[1464]: Resolved ip:[3] 192.168.2.61 240e:34c:81:5ee0:cf50:d0c3:11a:ab65 240e:34c:81:5ee0:b29b:3e84:e2b3:fdcb Dec 28 00:31:00 volumio4 ModemManager[923]: [base-manager] couldn't check support for device '/sys/devices/pci0000:00/0000:00:11.0/0000:02:01.0': not supported by any plugin Dec 28 00:31:00 volumio4 systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 28 00:31:00 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:00 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:00 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 00:31:00 volumio4 systemd[1]: welcome.service: Deactivated successfully. Dec 28 00:31:00 volumio4 systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 28 00:31:00 volumio4 systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 28 00:31:00 volumio4 systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 28 00:31:00 volumio4 welcome[1471]: Resolved ip:[3] 192.168.2.61 240e:34c:81:5ee0:cf50:d0c3:11a:ab65 240e:34c:81:5ee0:b29b:3e84:e2b3:fdcb Dec 28 00:31:00 volumio4 systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 28 00:31:00 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:00 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:00 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 00:31:00 volumio4 systemd[1]: welcome.service: Deactivated successfully. Dec 28 00:31:00 volumio4 systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 28 00:31:00 volumio4 systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 28 00:31:01 volumio4 systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 28 00:31:01 volumio4 systemd[1]: samba-ad-dc.service: Skipped due to 'exec-condition'. Dec 28 00:31:01 volumio4 systemd[1]: Condition check resulted in samba-ad-dc.service - Samba AD Daemon being skipped. Dec 28 00:31:01 volumio4 welcome[1477]: Resolved ip:[3] 192.168.2.61 240e:34c:81:5ee0:cf50:d0c3:11a:ab65 240e:34c:81:5ee0:b29b:3e84:e2b3:fdcb Dec 28 00:31:01 volumio4 systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 28 00:31:01 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:01 volumio4 nmbd[1484]: [2025/12/28 00:31:01.328870, 0] ../../source3/nmbd/nmbd.c:901(main) Dec 28 00:31:01 volumio4 nmbd[1484]: nmbd version 4.17.12-Debian started. Dec 28 00:31:01 volumio4 nmbd[1484]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Dec 28 00:31:01 volumio4 ntpd[1395]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 28 00:31:01 volumio4 nmbd[1484]: [2025/12/28 00:31:01.355727, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Dec 28 00:31:01 volumio4 nmbd[1484]: started asyncdns process 1486 Dec 28 00:31:01 volumio4 ntpd[1395]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Dec 28 00:31:01 volumio4 ntpd[1395]: DNS: Pool taking: 108.59.2.24 Dec 28 00:31:01 volumio4 ntpd[1395]: DNS: Pool taking: 193.182.111.12 Dec 28 00:31:01 volumio4 ntpd[1395]: DNS: Pool taking: 111.230.189.174 Dec 28 00:31:01 volumio4 ntpd[1395]: DNS: Pool taking: 139.199.214.202 Dec 28 00:31:01 volumio4 ntpd[1395]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Dec 28 00:31:01 volumio4 systemd[1]: Started nmbd.service - Samba NMB Daemon. Dec 28 00:31:01 volumio4 systemd[1]: Starting winbind.service - Samba Winbind Daemon... Dec 28 00:31:01 volumio4 sshd[1463]: Accepted password for volumio from 192.168.2.20 port 51472 ssh2 Dec 28 00:31:01 volumio4 sshd[1463]: pam_unix(sshd:session): session opened for user volumio(uid=1000) by (uid=0) Dec 28 00:31:01 volumio4 systemd[1]: Created slice user-1000.slice - User Slice of UID 1000. Dec 28 00:31:01 volumio4 systemd[1]: Starting user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Dec 28 00:31:01 volumio4 systemd-logind[889]: New session 1 of user volumio. Dec 28 00:31:01 volumio4 dhcpcd[928]: eth0: REPLY6 received from fe80::1eb7:2cff:fe74:17f0 Dec 28 00:31:01 volumio4 dhcpcd[928]: eth0: refresh in 86400 seconds Dec 28 00:31:01 volumio4 systemd[1]: Finished user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Dec 28 00:31:01 volumio4 systemd[1]: Starting user@1000.service - User Manager for UID 1000... Dec 28 00:31:01 volumio4 (systemd)[1501]: pam_unix(systemd-user:session): session opened for user volumio(uid=1000) by (uid=0) Dec 28 00:31:01 volumio4 dhcpcd[1515]: dhcp6_openudp: Address already in use Dec 28 00:31:01 volumio4 dhcpcd[1515]: ps_inet_listenin6: Address already in use Dec 28 00:31:01 volumio4 dhcpcd[1515]: ps_root_recvmsg: Address already in use Dec 28 00:31:01 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:01 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 00:31:01 volumio4 systemd[1]: welcome.service: Deactivated successfully. Dec 28 00:31:01 volumio4 systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 28 00:31:01 volumio4 systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 28 00:31:01 volumio4 systemd[1]: welcome.service: Start request repeated too quickly. Dec 28 00:31:01 volumio4 systemd[1]: welcome.service: Failed with result 'start-limit-hit'. Dec 28 00:31:01 volumio4 systemd[1]: Failed to start welcome.service - Show a welcome message on console. Dec 28 00:31:01 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:01 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:01 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 00:31:01 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:02 volumio4 systemd[1501]: Queued start job for default target default.target. Dec 28 00:31:02 volumio4 dhcpcd[1572]: dhcp6_openudp: Address already in use Dec 28 00:31:02 volumio4 dhcpcd[1572]: ps_inet_listenin6: Address already in use Dec 28 00:31:02 volumio4 dhcpcd[1572]: ps_root_recvmsg: Address already in use Dec 28 00:31:02 volumio4 systemd[1501]: Created slice app.slice - User Application Slice. Dec 28 00:31:02 volumio4 systemd[1501]: Reached target paths.target - Paths. Dec 28 00:31:02 volumio4 systemd[1501]: Reached target timers.target - Timers. Dec 28 00:31:02 volumio4 systemd[1501]: Starting dbus.socket - D-Bus User Message Bus Socket... Dec 28 00:31:02 volumio4 systemd[1501]: Listening on dirmngr.socket - GnuPG network certificate management daemon. Dec 28 00:31:02 volumio4 systemd[1501]: Listening on gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Dec 28 00:31:02 volumio4 systemd[1501]: Listening on gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Dec 28 00:31:02 volumio4 systemd[1501]: Listening on gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Dec 28 00:31:02 volumio4 systemd[1501]: Listening on gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Dec 28 00:31:02 volumio4 systemd[1501]: Listening on dbus.socket - D-Bus User Message Bus Socket. Dec 28 00:31:02 volumio4 systemd[1501]: Reached target sockets.target - Sockets. Dec 28 00:31:02 volumio4 systemd[1501]: Reached target basic.target - Basic System. Dec 28 00:31:02 volumio4 systemd[1]: Started user@1000.service - User Manager for UID 1000. Dec 28 00:31:02 volumio4 systemd[1]: Started session-1.scope - Session 1 of User volumio. Dec 28 00:31:02 volumio4 systemd[1501]: Started mpris-proxy.service - Bluetooth mpris proxy. Dec 28 00:31:02 volumio4 systemd[1501]: Reached target default.target - Main User Target. Dec 28 00:31:02 volumio4 systemd[1501]: Startup finished in 316ms. Dec 28 00:31:02 volumio4 systemd[1501]: Created slice session.slice - User Core Session Slice. Dec 28 00:31:02 volumio4 systemd[1501]: Starting dbus.service - D-Bus User Message Bus... Dec 28 00:31:02 volumio4 systemd[1501]: Started dbus.service - D-Bus User Message Bus. Dec 28 00:31:02 volumio4 winbindd[1491]: [2025/12/28 00:31:02.126802, 0] ../../source3/winbindd/winbindd.c:1440(main) Dec 28 00:31:02 volumio4 winbindd[1491]: winbindd version 4.17.12-Debian started. Dec 28 00:31:02 volumio4 winbindd[1491]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Dec 28 00:31:02 volumio4 winbindd[1491]: [2025/12/28 00:31:02.165131, 0] ../../source3/winbindd/winbindd_cache.c:3117(initialize_winbindd_cache) Dec 28 00:31:02 volumio4 winbindd[1491]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Dec 28 00:31:02 volumio4 systemd[1]: Started winbind.service - Samba Winbind Daemon. Dec 28 00:31:02 volumio4 sshd[1463]: pam_env(sshd:session): deprecated reading of user environment enabled Dec 28 00:31:02 volumio4 systemd[1]: Starting smbd.service - Samba SMB Daemon... Dec 28 00:31:02 volumio4 ntpd[1395]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 28 00:31:02 volumio4 ntpd[1395]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Dec 28 00:31:02 volumio4 ntpd[1395]: DNS: Pool taking: 84.16.67.12 Dec 28 00:31:02 volumio4 ntpd[1395]: DNS: Pool taking: 193.182.111.141 Dec 28 00:31:02 volumio4 ntpd[1395]: DNS: Pool taking: 139.199.215.251 Dec 28 00:31:02 volumio4 ntpd[1395]: DNS: Pool taking: 84.16.73.33 Dec 28 00:31:02 volumio4 ntpd[1395]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Dec 28 00:31:02 volumio4 smbd[1611]: [2025/12/28 00:31:02.744127, 0] ../../source3/smbd/server.c:1741(main) Dec 28 00:31:02 volumio4 smbd[1611]: smbd version 4.17.12-Debian started. Dec 28 00:31:02 volumio4 smbd[1611]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Dec 28 00:31:02 volumio4 systemd[1]: systemd-rfkill.service: Deactivated successfully. Dec 28 00:31:02 volumio4 volumio-remote-updater[892]: [2025-12-28 00:31:02] [connect] Successful connection Dec 28 00:31:03 volumio4 systemd[1]: Started smbd.service - Samba SMB Daemon. Dec 28 00:31:03 volumio4 volumio[1207]: info: ------------------------------------------- Dec 28 00:31:03 volumio4 volumio[1207]: info: ----- Volumio3 ---- Dec 28 00:31:03 volumio4 volumio[1207]: info: ------------------------------------------- Dec 28 00:31:03 volumio4 volumio[1207]: info: ----- System startup ---- Dec 28 00:31:03 volumio4 volumio[1207]: info: ------------------------------------------- Dec 28 00:31:03 volumio4 ntpd[1395]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 28 00:31:03 volumio4 ntpd[1395]: IO: Listen normally on 6 eth0 [240e:34c:81:5ee0:cf50:d0c3:11a:ab65]:123 Dec 28 00:31:03 volumio4 ntpd[1395]: IO: Listen normally on 7 eth0 [240e:34c:81:5ee0:b29b:3e84:e2b3:fdcb]:123 Dec 28 00:31:03 volumio4 ntpd[1395]: IO: Listen normally on 8 eth0 [fe80::b29b:3e84:e2b3:fdcb%2]:123 Dec 28 00:31:03 volumio4 ntpd[1395]: IO: new interface(s) found: waking up resolver Dec 28 00:31:03 volumio4 ntpd[1395]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Dec 28 00:31:03 volumio4 ntpd[1395]: DNS: Pool taking: 240e:92c:0:8000::72:a7 Dec 28 00:31:03 volumio4 ntpd[1395]: DNS: Pool taking: 2402:f000:1:416:101:6:6:172 Dec 28 00:31:03 volumio4 ntpd[1395]: DNS: Pool taking: 2001:da8:215:4020::118:123 Dec 28 00:31:03 volumio4 ntpd[1395]: DNS: Pool taking: 2a04:3543:1000:2310:d862:f5ff:fe4e:2b59 Dec 28 00:31:03 volumio4 ntpd[1395]: DNS: Pool skipping: 84.16.73.33 Dec 28 00:31:03 volumio4 ntpd[1395]: DNS: Pool skipping: 108.59.2.24 Dec 28 00:31:03 volumio4 ntpd[1395]: DNS: Pool skipping: 139.199.215.251 Dec 28 00:31:03 volumio4 ntpd[1395]: DNS: Pool skipping: 193.182.111.141 Dec 28 00:31:03 volumio4 ntpd[1395]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Dec 28 00:31:03 volumio4 volumio[1207]: info: MYVOLUMIO Environment detected Dec 28 00:31:03 volumio4 volumio[1207]: info: Plugin folders cleanup Dec 28 00:31:03 volumio4 volumio[1207]: info: Scanning into folder /volumio/app/plugins/ Dec 28 00:31:03 volumio4 volumio[1207]: info: Scanning category audio_interface Dec 28 00:31:03 volumio4 volumio[1207]: info: Scanning category miscellanea Dec 28 00:31:03 volumio4 volumio[1207]: info: Scanning category music_service Dec 28 00:31:03 volumio4 volumio[1207]: info: Scanning category plugins.json Dec 28 00:31:03 volumio4 volumio[1207]: info: Scanning category system_controller Dec 28 00:31:03 volumio4 volumio[1207]: info: Scanning category user_interface Dec 28 00:31:03 volumio4 volumio[1207]: info: Scanning into folder /data/plugins/ Dec 28 00:31:03 volumio4 volumio[1207]: info: Scanning category user_interface Dec 28 00:31:03 volumio4 volumio[1207]: info: Plugin folders cleanup completed Dec 28 00:31:03 volumio4 volumio[1207]: info: ------------------------------------------- Dec 28 00:31:03 volumio4 volumio[1207]: info: ----- Core plugins startup ---- Dec 28 00:31:03 volumio4 volumio[1207]: info: ------------------------------------------- Dec 28 00:31:03 volumio4 volumio[1207]: info: Loading plugins from folder /volumio/app/plugins/ Dec 28 00:31:03 volumio4 volumio[1207]: info: Adding plugin upnp to MyMusic Plugins Dec 28 00:31:03 volumio4 volumio[1207]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 28 00:31:03 volumio4 volumio[1207]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 28 00:31:03 volumio4 volumio[1207]: info: Loading plugins from folder /data/plugins/ Dec 28 00:31:03 volumio4 volumio[1207]: info: Loading plugin "system"... Dec 28 00:31:03 volumio4 volumio[1207]: info: Loading plugin "appearance"... Dec 28 00:31:03 volumio4 volumio[1207]: info: Loading plugin "network"... Dec 28 00:31:03 volumio4 volumio[1207]: info: Refreshing Cached IP Addresses Dec 28 00:31:03 volumio4 volumio[1207]: info: Loading plugin "services"... Dec 28 00:31:03 volumio4 volumio[1207]: info: Loading plugin "alsa_controller"... Dec 28 00:31:03 volumio4 sudo[1647]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 28 00:31:03 volumio4 sudo[1647]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 00:31:03 volumio4 sudo[1641]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 28 00:31:03 volumio4 sudo[1641]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 00:31:03 volumio4 sudo[1639]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 28 00:31:03 volumio4 sudo[1639]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 00:31:03 volumio4 sudo[1639]: pam_unix(sudo:session): session closed for user root Dec 28 00:31:03 volumio4 sudo[1641]: pam_unix(sudo:session): session closed for user root Dec 28 00:31:03 volumio4 sudo[1647]: pam_unix(sudo:session): session closed for user root Dec 28 00:31:04 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 28 00:31:04 volumio4 volumio[1207]: info: Loading plugin "wizard"... Dec 28 00:31:04 volumio4 volumio[1207]: info: Loading plugin "networkfs"... Dec 28 00:31:04 volumio4 volumio[1207]: info: Starting Udev Watcher for removable devices Dec 28 00:31:04 volumio4 sudo[1676]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=guest,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.2.1/Hatich500G\\MusicBox /mnt/NAS/MusicBox Dec 28 00:31:04 volumio4 sudo[1676]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 00:31:04 volumio4 volumio[1207]: info: Ignoring mount for partition: boot Dec 28 00:31:04 volumio4 volumio[1207]: info: Ignoring mount for partition: volumio Dec 28 00:31:04 volumio4 volumio[1207]: info: Ignoring mount for partition: volumio_data Dec 28 00:31:04 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 28 00:31:04 volumio4 volumio[1207]: info: Loading plugin "volumio_command_line_client"... Dec 28 00:31:04 volumio4 volumio[1207]: info: Loading plugin "upnp"... Dec 28 00:31:04 volumio4 volumio[1207]: info: [1766853064059] Starting Upmpd Daemon Dec 28 00:31:04 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 28 00:31:04 volumio4 volumio[1207]: info: Loading plugin "my_music"... Dec 28 00:31:04 volumio4 volumio[1207]: info: Loading plugin "mpd"... Dec 28 00:31:04 volumio4 kernel: netfs: FS-Cache loaded Dec 28 00:31:04 volumio4 kernel: Key type dns_resolver registered Dec 28 00:31:04 volumio4 ntpd[1395]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 28 00:31:04 volumio4 ntpd[1395]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Dec 28 00:31:04 volumio4 ntpd[1395]: DNS: Pool skipping: 84.16.73.33 Dec 28 00:31:04 volumio4 ntpd[1395]: DNS: Pool taking: 106.54.244.49 Dec 28 00:31:04 volumio4 ntpd[1395]: DNS: Pool skipping: 139.199.215.251 Dec 28 00:31:04 volumio4 ntpd[1395]: DNS: Pool taking: 162.159.200.1 Dec 28 00:31:04 volumio4 ntpd[1395]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Dec 28 00:31:04 volumio4 systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 28 00:31:04 volumio4 systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 28 00:31:04 volumio4 systemd[1]: Reached target multi-user.target - Multi-User System. Dec 28 00:31:04 volumio4 systemd[1]: Reached target graphical.target - Graphical Interface. Dec 28 00:31:04 volumio4 systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Dec 28 00:31:04 volumio4 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Dec 28 00:31:04 volumio4 systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Dec 28 00:31:04 volumio4 systemd[1]: Startup finished in 18.041s (kernel) + 10.353s (userspace) = 28.395s. Dec 28 00:31:04 volumio4 kernel: Key type cifs.spnego registered Dec 28 00:31:04 volumio4 kernel: Key type cifs.idmap registered Dec 28 00:31:04 volumio4 kernel: CIFS: Attempting to mount //192.168.2.1/Hatich500G/MusicBox Dec 28 00:31:04 volumio4 volumio[1207]: info: Loading plugin "upnp_browser"... Dec 28 00:31:04 volumio4 sudo[1676]: pam_unix(sudo:session): session closed for user root Dec 28 00:31:05 volumio4 volumio[1207]: info: Starting UPNP Browser Dec 28 00:31:05 volumio4 volumio[1207]: info: Loading plugin "alarm-clock"... Dec 28 00:31:05 volumio4 volumio[1207]: info: Loading plugin "airplay_emulation"... Dec 28 00:31:05 volumio4 volumio[1207]: info: Starting Shairport Sync Dec 28 00:31:05 volumio4 volumio[1207]: info: Loading plugin "last_100"... Dec 28 00:31:05 volumio4 volumio[1207]: info: Loading plugin "webradio"... Dec 28 00:31:05 volumio4 volumio[1207]: info: Loading plugin "i2s_dacs"... Dec 28 00:31:05 volumio4 ntpd[1395]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 28 00:31:05 volumio4 ntpd[1395]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Dec 28 00:31:05 volumio4 ntpd[1395]: DNS: Pool taking: 119.28.206.193 Dec 28 00:31:05 volumio4 ntpd[1395]: DNS: Pool taking: 119.28.183.184 Dec 28 00:31:05 volumio4 ntpd[1395]: DNS: Pool taking: 185.209.85.222 Dec 28 00:31:05 volumio4 ntpd[1395]: DNS: Pool taking: 193.182.111.143 Dec 28 00:31:05 volumio4 ntpd[1395]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Dec 28 00:31:05 volumio4 volumio[1207]: info: I2S DAC not set, start Auto-detection Dec 28 00:31:05 volumio4 volumio[1207]: info: Loading plugin "volumiodiscovery"... Dec 28 00:31:05 volumio4 volumio[1207]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 28 00:31:05 volumio4 node[1207]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 28 00:31:05 volumio4 volumio[1207]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 28 00:31:05 volumio4 volumio[1207]: *** WARNING *** For more information see Dec 28 00:31:05 volumio4 volumio[1207]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 28 00:31:05 volumio4 volumio[1207]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 28 00:31:05 volumio4 volumio[1207]: *** WARNING *** For more information see Dec 28 00:31:05 volumio4 node[1207]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 28 00:31:05 volumio4 node[1207]: *** WARNING *** For more information see Dec 28 00:31:05 volumio4 node[1207]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 28 00:31:05 volumio4 node[1207]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 28 00:31:05 volumio4 node[1207]: *** WARNING *** For more information see Dec 28 00:31:05 volumio4 volumio[1207]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 28 00:31:05 volumio4 volumio[1207]: info: Discovery: Started advertising with name: Volumio4 Dec 28 00:31:05 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 28 00:31:05 volumio4 volumio[1207]: info: Loading plugin "peppy_screensaver"... Dec 28 00:31:05 volumio4 volumio[1207]: info: Applying required configuration parameters for plugin peppy_screensaver Dec 28 00:31:05 volumio4 volumio[1207]: info: Loading plugin "outputs"... Dec 28 00:31:05 volumio4 volumio[1207]: info: Loading plugin "albumart"... Dec 28 00:31:05 volumio4 volumio[1207]: info: Plugin example_plugin is not enabled Dec 28 00:31:05 volumio4 volumio[1207]: info: Loading plugin "inputs"... Dec 28 00:31:05 volumio4 volumio[1207]: info: Loading plugin "updater_comm"... Dec 28 00:31:05 volumio4 volumio[1777]: Forking 1 albumart workers Dec 28 00:31:06 volumio4 volumio[1207]: info: Plugin mpdemulation is not enabled Dec 28 00:31:06 volumio4 volumio[1207]: info: Loading plugin "rest_api"... Dec 28 00:31:06 volumio4 volumio[1207]: info: Loading plugin "websocket"... Dec 28 00:31:06 volumio4 volumio[1207]: info: Starting Socket.io Server version 1.7.4 Dec 28 00:31:06 volumio4 volumio[1207]: info: Loading i18n strings for locale zh Dec 28 00:31:06 volumio4 volumio[1207]: Updating browse sources language Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 00:31:06 volumio4 ntpd[1395]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 28 00:31:06 volumio4 ntpd[1395]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Dec 28 00:31:06 volumio4 ntpd[1395]: DNS: Pool skipping: 108.59.2.24 Dec 28 00:31:06 volumio4 ntpd[1395]: DNS: Pool skipping: 193.182.111.12 Dec 28 00:31:06 volumio4 ntpd[1395]: DNS: Pool skipping: 111.230.189.174 Dec 28 00:31:06 volumio4 ntpd[1395]: DNS: Pool skipping: 139.199.214.202 Dec 28 00:31:06 volumio4 ntpd[1395]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Dec 28 00:31:06 volumio4 volumio[1795]: Starting albumart workers Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreCommandRouter::initPlayerControls Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 00:31:06 volumio4 volumio[1207]: Express server listening on port 3000 Dec 28 00:31:06 volumio4 volumio[1207]: [Metrics] WebUI: 3s 697.26ms Dec 28 00:31:06 volumio4 volumio[1207]: info: Setting Device type: x86 Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreStateMachine::resetVolumioState Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreStateMachine::getcurrentVolume Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreCommandRouter::volumioRetrievevolume Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 00:31:06 volumio4 volumio[1207]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 28 00:31:06 volumio4 volumio[1207]: info: Completed loading Core Plugins Dec 28 00:31:06 volumio4 volumio[1207]: info: Preparing to generate the ALSA configuration file Dec 28 00:31:06 volumio4 volumio[1207]: info: Volumio Network Manager: Network status updated: 1 Dec 28 00:31:06 volumio4 volumio[1207]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Dec 28 00:31:06 volumio4 volumio[1207]: wlan0 Interface doesn't support scanning. Dec 28 00:31:06 volumio4 volumio[1207]: info: Cannot use regular scanning, forcing with ap-force Dec 28 00:31:06 volumio4 sudo[1832]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Dec 28 00:31:06 volumio4 sudo[1832]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 00:31:06 volumio4 sudo[1832]: pam_unix(sudo:session): session closed for user root Dec 28 00:31:06 volumio4 volumio[1207]: command failed: No such device (-19) Dec 28 00:31:06 volumio4 volumio[1207]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Dec 28 00:31:06 volumio4 volumio[1207]: command failed: No such device (-19) Dec 28 00:31:06 volumio4 volumio[1207]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Dec 28 00:31:06 volumio4 volumio[1207]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Dec 28 00:31:06 volumio4 volumio[1207]: info: Reading ALSA contributions from plugins. Dec 28 00:31:06 volumio4 volumio[1207]: verbose: New Socket.io Connection to 192.168.2.61 from 192.168.2.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/141.0.0.0 Safari/537.36 Edg/141.0.0.0 Engine version: 3 Transport: polling Total Clients: 1 Dec 28 00:31:06 volumio4 volumio[1207]: info: VolumeController:: Volume=94 Mute =false Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreStateMachine::pushState Dec 28 00:31:06 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreCommandRouter::volumioPushState Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreStateMachine::updateTrackBlock Dec 28 00:31:06 volumio4 volumio[1207]: info: CorePlayQueue::getTrackBlock Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreCommandRouter::volumioRetrievevolume Dec 28 00:31:06 volumio4 volumio-remote-updater[892]: [2025-12-28 00:31:06] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1766853062 101 Dec 28 00:31:06 volumio4 volumio[1207]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 2 Dec 28 00:31:06 volumio4 volumio[1207]: info: Reloading queue from file Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreStateMachine::setRepeat null single undefined Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreStateMachine::pushState Dec 28 00:31:06 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreCommandRouter::volumioPushState Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreStateMachine::setRandom null Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreStateMachine::pushState Dec 28 00:31:06 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreCommandRouter::volumioPushState Dec 28 00:31:06 volumio4 volumio[1207]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 28 00:31:06 volumio4 volumio[1207]: info: VolumeController:: Volume=94 Mute =false Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreStateMachine::pushState Dec 28 00:31:06 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreCommandRouter::volumioPushState Dec 28 00:31:06 volumio4 volumio[1207]: info: Discovery: adding a87b4771-d555-404a-9afb-09bb07281f8f Dec 28 00:31:06 volumio4 volumio[1207]: info: Discovery: Found device Volumio4 Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreCommandRouter::volumioGetState Dec 28 00:31:06 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:06 volumio4 volumio[1207]: info: Discovery: this is already registered, a87b4771-d555-404a-9afb-09bb07281f8f Dec 28 00:31:06 volumio4 volumio[1207]: info: Discovery: Found device Volumio4 Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreCommandRouter::volumioGetState Dec 28 00:31:06 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:06 volumio4 volumio[1207]: info: Asound.conf file unchanged, so no further update is needed Dec 28 00:31:06 volumio4 volumio[1207]: info: Output device has changed, restarting MPD Dec 28 00:31:06 volumio4 volumio[1207]: info: Output device has changed, restarting Shairport Sync Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 00:31:06 volumio4 sudo[1852]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 28 00:31:06 volumio4 sudo[1854]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 28 00:31:06 volumio4 sudo[1854]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 00:31:06 volumio4 sudo[1852]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 00:31:06 volumio4 sudo[1852]: pam_unix(sudo:session): session closed for user root Dec 28 00:31:06 volumio4 volumio[1207]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 28 00:31:06 volumio4 volumio[1207]: info: ___________ START PLUGINS ___________ Dec 28 00:31:06 volumio4 volumio[1207]: info: ControllerMpd::onStart: Initializing MPD Dec 28 00:31:06 volumio4 volumio[1207]: info: Creating MPD Configuration file Dec 28 00:31:06 volumio4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 00:31:06 volumio4 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 28 00:31:06 volumio4 volumio[1207]: info: [1766853066840] CoreMusicLibrary::Adding element 媒体服务器 Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 00:31:06 volumio4 sudo[1863]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 28 00:31:06 volumio4 sudo[1863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 00:31:06 volumio4 sudo[1863]: pam_unix(sudo:session): session closed for user root Dec 28 00:31:06 volumio4 volumio[1207]: info: UPNP Browser: Client initialized successfully Dec 28 00:31:06 volumio4 sudo[1866]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 28 00:31:06 volumio4 sudo[1866]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 00:31:06 volumio4 systemd[1]: mpd.service: Deactivated successfully. Dec 28 00:31:06 volumio4 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 28 00:31:06 volumio4 systemd[1]: mpd.socket: Deactivated successfully. Dec 28 00:31:06 volumio4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 28 00:31:06 volumio4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 28 00:31:06 volumio4 volumio[1207]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 00:31:06 volumio4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 28 00:31:06 volumio4 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 28 00:31:06 volumio4 volumio[1207]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 28 00:31:06 volumio4 volumio[1207]: info: [1766853066925] CoreMusicLibrary::Adding element Last_100 Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 28 00:31:06 volumio4 volumio[1207]: info: [1766853066926] CoreMusicLibrary::Adding element Webradio Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 28 00:31:06 volumio4 volumio[1207]: info: Initializing BBC Radios Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 28 00:31:06 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 00:31:07 volumio4 volumio[1207]: info: Loading i18n strings for locale zh Dec 28 00:31:07 volumio4 volumio[1207]: info: /tmp/myfifo created Dec 28 00:31:07 volumio4 volumio[1207]: info: /tmp/myfifosa created Dec 28 00:31:07 volumio4 sudo[1878]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 28 00:31:07 volumio4 sudo[1878]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 28 00:31:07 volumio4 sudo[1900]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 Dec 28 00:31:07 volumio4 sudo[1900]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 00:31:07 volumio4 sudo[1901]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 28 00:31:07 volumio4 sudo[1878]: pam_unix(sudo:session): session closed for user root Dec 28 00:31:07 volumio4 sudo[1900]: pam_unix(sudo:session): session closed for user root Dec 28 00:31:07 volumio4 volumio[1207]: info: snd-dummy loaded Dec 28 00:31:07 volumio4 systemd[1501]: Reached target sound.target - Sound Card. Dec 28 00:31:07 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 00:31:07 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:07 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:07 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync Dec 28 00:31:07 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:07 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 00:31:07 volumio4 volumio[1207]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 28 00:31:07 volumio4 volumio[1207]: info: Volumio Calling Home Dec 28 00:31:07 volumio4 volumio[1207]: info: Discovery: this is already registered, a87b4771-d555-404a-9afb-09bb07281f8f Dec 28 00:31:07 volumio4 volumio[1207]: info: Discovery: Found device Volumio4 Dec 28 00:31:07 volumio4 volumio[1207]: info: CoreCommandRouter::volumioGetState Dec 28 00:31:07 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:07 volumio4 volumio[1207]: verbose: New Socket.io Connection to 192.168.2.61 from 192.168.2.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/141.0.0.0 Safari/537.36 Edg/141.0.0.0 Engine version: 3 Transport: polling Total Clients: 4 Dec 28 00:31:08 volumio4 ntpd[1395]: CLOCK: time stepped by 0.751338 Dec 28 00:31:08 volumio4 ntpd[1395]: INIT: MRU 10922 entries, 13 hash bits, 65536 bytes Dec 28 00:31:08 volumio4 volumio[1207]: warn: peppy_screensaver: An error occurred on pygame check Command failed: python3 -c "import pygame" Dec 28 00:31:08 volumio4 volumio[1207]: Traceback (most recent call last): Dec 28 00:31:08 volumio4 volumio[1207]: File "", line 1, in Dec 28 00:31:08 volumio4 volumio[1207]: ModuleNotFoundError: No module named 'pygame' Dec 28 00:31:08 volumio4 volumio[1207]: {"cmd":"python3 -c \"import pygame\"","code":1,"killed":false,"signal":null,"stack":"Error: Command failed: python3 -c \"import pygame\"\nTraceback (most recent call last):\n File \"\", line 1, in \nModuleNotFoundError: No module named 'pygame'\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Dec 28 00:31:08 volumio4 volumio[1207]: info: MPD Permissions set Dec 28 00:31:08 volumio4 volumio[1207]: info: MPD Permissions set Dec 28 00:31:08 volumio4 volumio[1207]: info: CoreCommandRouter::volumioGetState Dec 28 00:31:08 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:08 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 28 00:31:08 volumio4 volumio[1207]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 28 00:31:08 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:08 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:08 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:08 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:08 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:08 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:08 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:08 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:08 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 00:31:08 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 28 00:31:08 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:08 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:08 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:08 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:08 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:08 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:08 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:08 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:08 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:08 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 00:31:08 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 28 00:31:08 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:08 volumio4 volumio[1207]: info: CoreCommandRouter::volumioGetVisibleSources Dec 28 00:31:08 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 00:31:08 volumio4 volumio[1207]: info: CoreCommandRouter::volumioGetState Dec 28 00:31:08 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:08 volumio4 volumio[1207]: info: CoreCommandRouter::volumioGetState Dec 28 00:31:08 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:08 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 28 00:31:08 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 28 00:31:08 volumio4 volumio[1207]: info: Received Get System Info Dec 28 00:31:08 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 28 00:31:08 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 28 00:31:08 volumio4 volumio[1207]: info: Discovery: Getting this device information Dec 28 00:31:08 volumio4 volumio[1207]: info: CoreCommandRouter::volumioGetState Dec 28 00:31:08 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:08 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 28 00:31:08 volumio4 volumio[1207]: info: CoreCommandRouter::volumioGetState Dec 28 00:31:08 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:08 volumio4 volumio[1207]: info: Listing playlists Dec 28 00:31:08 volumio4 volumio[1207]: info: CoreCommandRouter::volumioGetQueue Dec 28 00:31:08 volumio4 volumio[1207]: info: CoreStateMachine::getQueue Dec 28 00:31:08 volumio4 volumio[1207]: info: CorePlayQueue::getQueue Dec 28 00:31:08 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:08 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 00:31:08 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:08 volumio4 sudo[1968]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl Dec 28 00:31:08 volumio4 sudo[1968]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 00:31:08 volumio4 sudo[1968]: pam_unix(sudo:session): session closed for user root Dec 28 00:31:08 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:08 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 00:31:08 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:08 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:08 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 00:31:08 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:08 volumio4 volumio[1207]: info: CoreCommandRouter::volumioGetState Dec 28 00:31:08 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:08 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile Dec 28 00:31:08 volumio4 volumio[1207]: info: Starting Shairport Sync Dec 28 00:31:08 volumio4 volumio[1207]: info: Starting Shairport Sync Dec 28 00:31:08 volumio4 sudo[2007]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 28 00:31:08 volumio4 sudo[2007]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 00:31:08 volumio4 sudo[2002]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 28 00:31:08 volumio4 volumio[1207]: info: Starting Shairport Sync Dec 28 00:31:08 volumio4 sudo[2002]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 00:31:08 volumio4 sudo[2002]: pam_unix(sudo:session): session closed for user root Dec 28 00:31:08 volumio4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 28 00:31:08 volumio4 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 28 00:31:08 volumio4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 28 00:31:08 volumio4 sudo[2012]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 28 00:31:08 volumio4 sudo[2012]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 00:31:08 volumio4 sudo[2007]: pam_unix(sudo:session): session closed for user root Dec 28 00:31:08 volumio4 volumio[1207]: info: Starting Shairport Sync Dec 28 00:31:08 volumio4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 28 00:31:09 volumio4 sudo[2018]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 28 00:31:09 volumio4 sudo[2018]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 00:31:09 volumio4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 28 00:31:09 volumio4 sudo[2021]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 28 00:31:09 volumio4 sudo[2021]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 00:31:09 volumio4 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 28 00:31:09 volumio4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 28 00:31:09 volumio4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 28 00:31:09 volumio4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 28 00:31:09 volumio4 sudo[2018]: pam_unix(sudo:session): session closed for user root Dec 28 00:31:09 volumio4 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 28 00:31:09 volumio4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 28 00:31:09 volumio4 sudo[2021]: pam_unix(sudo:session): session closed for user root Dec 28 00:31:09 volumio4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 28 00:31:09 volumio4 sudo[2012]: pam_unix(sudo:session): session closed for user root Dec 28 00:31:09 volumio4 volumio[1207]: info: Shairport-Sync Started Dec 28 00:31:09 volumio4 volumio[1207]: Error adding Membership: Error: addMembership EINVAL Dec 28 00:31:09 volumio4 volumio[1207]: info: Shairport-Sync Started Dec 28 00:31:09 volumio4 volumio[1207]: info: Shairport-Sync Started Dec 28 00:31:09 volumio4 volumio[1207]: info: Shairport-Sync Started Dec 28 00:31:09 volumio4 volumio[1207]: info: MPD Permissions set Dec 28 00:31:09 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Dec 28 00:31:09 volumio4 sudo[2043]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 28 00:31:09 volumio4 sudo[2043]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 00:31:09 volumio4 volumio[1207]: info: Volumio called home Dec 28 00:31:09 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:09 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:09 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:09 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:09 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:09 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:09 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:09 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:09 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 00:31:09 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 28 00:31:09 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:09 volumio4 systemd[1]: NetworkManager-dispatcher.service: Deactivated successfully. Dec 28 00:31:09 volumio4 systemd[1]: mpd.service: Deactivated successfully. Dec 28 00:31:09 volumio4 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 28 00:31:09 volumio4 systemd[1]: mpd.socket: Deactivated successfully. Dec 28 00:31:09 volumio4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 28 00:31:09 volumio4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 28 00:31:09 volumio4 volumio[1207]: info: CoreCommandRouter::volumioGetState Dec 28 00:31:09 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:09 volumio4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 28 00:31:09 volumio4 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 28 00:31:10 volumio4 sudo[2056]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 28 00:31:10 volumio4 sudo[2056]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 28 00:31:10 volumio4 sudo[2064]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 28 00:31:10 volumio4 sudo[2056]: pam_unix(sudo:session): session closed for user root Dec 28 00:31:11 volumio4 dhcpcd[764]: eth0: failed to request DHCPv6 information Dec 28 00:31:11 volumio4 dhcpcd[764]: eth0: requesting DHCPv6 information Dec 28 00:31:11 volumio4 mpd[2066]: 2025-12-28T00:31:11 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 28 00:31:12 volumio4 systemd[1]: Started mpd.service - Music Player Daemon. Dec 28 00:31:12 volumio4 sudo[1854]: pam_unix(sudo:session): session closed for user root Dec 28 00:31:12 volumio4 sudo[2043]: pam_unix(sudo:session): session closed for user root Dec 28 00:31:12 volumio4 sudo[1866]: pam_unix(sudo:session): session closed for user root Dec 28 00:31:12 volumio4 volumio[1207]: info: Completed starting Core Plugins Dec 28 00:31:12 volumio4 volumio[1207]: info: ------------------------------------------- Dec 28 00:31:12 volumio4 volumio[1207]: info: ----- MyVolumio plugins startup ---- Dec 28 00:31:12 volumio4 volumio[1207]: info: ------------------------------------------- Dec 28 00:31:12 volumio4 volumio[1207]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 28 00:31:12 volumio4 volumio[1207]: error: MPD error: The expression evaluated to a falsy value: Dec 28 00:31:12 volumio4 volumio[1207]: assert.ok(self.idling) Dec 28 00:31:12 volumio4 volumio[1207]: error: The expression evaluated to a falsy value: Dec 28 00:31:12 volumio4 volumio[1207]: assert.ok(self.idling) Dec 28 00:31:12 volumio4 volumio[1207]: error: MPD error: The expression evaluated to a falsy value: Dec 28 00:31:12 volumio4 volumio[1207]: assert.ok(self.idling) Dec 28 00:31:12 volumio4 volumio[1207]: error: The expression evaluated to a falsy value: Dec 28 00:31:12 volumio4 volumio[1207]: assert.ok(self.idling) Dec 28 00:31:12 volumio4 volumio[1207]: info: MPD running with PID2066 Dec 28 00:31:12 volumio4 volumio[1207]: ,establishing connection Dec 28 00:31:12 volumio4 volumio[1207]: error: updateQueue error: null Dec 28 00:31:12 volumio4 volumio[1207]: error: updateQueue error: null Dec 28 00:31:13 volumio4 volumio[1207]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Dec 28 00:31:13 volumio4 volumio[1207]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Dec 28 00:31:13 volumio4 volumio[1207]: info: CoreCommandRouter::volumioGetState Dec 28 00:31:13 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:14 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 28 00:31:14 volumio4 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Dec 28 00:31:14 volumio4 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 28 00:31:14 volumio4 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 28 00:31:14 volumio4 upmpdcli[2170]: Could not open config: /tmp/upmpdcli.conf Dec 28 00:31:14 volumio4 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 28 00:31:14 volumio4 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 28 00:31:14 volumio4 volumio[1207]: info: CoreCommandRouter::volumioGetVisibleSources Dec 28 00:31:14 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 00:31:14 volumio4 volumio[1207]: info: CoreCommandRouter::volumioGetState Dec 28 00:31:14 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:14 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 28 00:31:14 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 28 00:31:14 volumio4 volumio[1207]: info: Received Get System Info Dec 28 00:31:14 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 28 00:31:14 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 28 00:31:14 volumio4 volumio[1207]: info: Discovery: Getting this device information Dec 28 00:31:14 volumio4 volumio[1207]: info: CoreCommandRouter::volumioGetState Dec 28 00:31:14 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:14 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 28 00:31:14 volumio4 volumio[1207]: info: CoreCommandRouter::volumioGetState Dec 28 00:31:14 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:14 volumio4 volumio[1207]: info: Listing playlists Dec 28 00:31:14 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 28 00:31:14 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 28 00:31:14 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 28 00:31:14 volumio4 volumio[1207]: info: CoreCommandRouter::volumioGetState Dec 28 00:31:14 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:14 volumio4 sudo[2192]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 28 00:31:14 volumio4 sudo[2192]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 00:31:14 volumio4 sudo[2190]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 28 00:31:14 volumio4 sudo[2192]: pam_unix(sudo:session): session closed for user root Dec 28 00:31:14 volumio4 sudo[2190]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 00:31:14 volumio4 sudo[2190]: pam_unix(sudo:session): session closed for user root Dec 28 00:31:14 volumio4 sudo[2198]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 28 00:31:14 volumio4 sudo[2198]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 00:31:15 volumio4 sudo[2201]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 28 00:31:15 volumio4 sudo[2201]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 00:31:15 volumio4 sudo[2204]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 28 00:31:15 volumio4 sudo[2204]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 00:31:15 volumio4 sudo[2206]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 28 00:31:15 volumio4 sudo[2206]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 00:31:15 volumio4 systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 28 00:31:15 volumio4 mpd_monitor.sh[2208]: MPD Monitor Service: Starting MPD Monitor Service Dec 28 00:31:15 volumio4 sudo[2201]: pam_unix(sudo:session): session closed for user root Dec 28 00:31:15 volumio4 systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 28 00:31:15 volumio4 systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 28 00:31:15 volumio4 volumio[1207]: info: Successfully started MPD Monitor Dec 28 00:31:15 volumio4 systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 28 00:31:15 volumio4 systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 28 00:31:15 volumio4 sudo[2206]: pam_unix(sudo:session): session closed for user root Dec 28 00:31:15 volumio4 sudo[2204]: pam_unix(sudo:session): session closed for user root Dec 28 00:31:15 volumio4 volumio[1207]: info: Successfully started MPD Monitor Dec 28 00:31:15 volumio4 volumio[1207]: info: Successfully started MPD Monitor Dec 28 00:31:15 volumio4 mpd_monitor.sh[2212]: MPD Monitor Service: Starting MPD Monitor Service Dec 28 00:31:15 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:15 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 00:31:15 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:15 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:15 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 00:31:15 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:15 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:15 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 00:31:15 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:15 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 28 00:31:15 volumio4 volumio[1207]: info: Received Get System Info Dec 28 00:31:15 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 28 00:31:15 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 28 00:31:15 volumio4 volumio[1207]: info: Discovery: Getting this device information Dec 28 00:31:15 volumio4 volumio[1207]: info: CoreCommandRouter::volumioGetState Dec 28 00:31:15 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:15 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 28 00:31:15 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 28 00:31:16 volumio4 volumio[1207]: info: CoreCommandRouter::Close All Modals sent Dec 28 00:31:17 volumio4 volumio[1207]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 28 00:31:19 volumio4 volumio[1207]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 00:31:19 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 00:31:20 volumio4 volumio[1207]: info: CoreCommandRouter::volumioGetState Dec 28 00:31:20 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:21 volumio4 dhcpcd[764]: eth0: requesting DHCPv6 information Dec 28 00:31:22 volumio4 volumio[1207]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 28 00:31:22 volumio4 volumio[1207]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 28 00:31:22 volumio4 volumio[1207]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 28 00:31:22 volumio4 volumio[1207]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 28 00:31:22 volumio4 volumio[1207]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 28 00:31:22 volumio4 volumio[1207]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 28 00:31:22 volumio4 volumio[1207]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 28 00:31:22 volumio4 volumio[1207]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 28 00:31:22 volumio4 volumio[1207]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 28 00:31:22 volumio4 volumio[1207]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 28 00:31:22 volumio4 volumio[1207]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 28 00:31:22 volumio4 volumio[1207]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 28 00:31:22 volumio4 volumio[1207]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 28 00:31:22 volumio4 volumio[1207]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 28 00:31:22 volumio4 volumio[1207]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 28 00:31:22 volumio4 volumio[1207]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 28 00:31:22 volumio4 volumio[1207]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 28 00:31:22 volumio4 volumio[1207]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 28 00:31:22 volumio4 volumio[1207]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 28 00:31:22 volumio4 volumio[1207]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 28 00:31:22 volumio4 volumio[1207]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 28 00:31:22 volumio4 volumio[1207]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 28 00:31:22 volumio4 volumio[1207]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 28 00:31:22 volumio4 volumio[1207]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 28 00:31:22 volumio4 volumio[1207]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 28 00:31:22 volumio4 volumio[1207]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 28 00:31:22 volumio4 volumio[1207]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 28 00:31:22 volumio4 volumio[1207]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 28 00:31:22 volumio4 volumio[1207]: info: Adding plugin bluetooth to MyMusic Plugins Dec 28 00:31:22 volumio4 volumio[1207]: info: Adding plugin multiroom to MyMusic Plugins Dec 28 00:31:22 volumio4 volumio[1207]: info: Adding plugin metavolumio to MyMusic Plugins Dec 28 00:31:22 volumio4 volumio[1207]: info: Adding plugin cd_controller to MyMusic Plugins Dec 28 00:31:22 volumio4 volumio[1207]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 28 00:31:22 volumio4 volumio[1207]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 28 00:31:22 volumio4 volumio[1207]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 28 00:31:22 volumio4 volumio[1207]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 28 00:31:22 volumio4 volumio[1207]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 28 00:31:22 volumio4 volumio[1207]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 28 00:31:22 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 00:31:22 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 00:31:22 volumio4 volumio[1207]: info: Starting MyVolumio Remote Streaming Endpoints Dec 28 00:31:22 volumio4 volumio[1207]: info: MyVolumio login type: Token Dec 28 00:31:22 volumio4 volumio[1207]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 28 00:31:22 volumio4 volumio[1207]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 28 00:31:23 volumio4 volumio[1207]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 28 00:31:23 volumio4 volumio[1207]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 28 00:31:23 volumio4 volumio[1207]: info: Streaming services startup Dec 28 00:31:23 volumio4 volumio[1207]: info: Starting Streaming Daemon Dec 28 00:31:23 volumio4 volumio[1207]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 28 00:31:23 volumio4 sudo[2279]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 28 00:31:23 volumio4 sudo[2279]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 00:31:23 volumio4 sudo[2279]: pam_unix(sudo:session): session closed for user root Dec 28 00:31:23 volumio4 volumio[1207]: error: Cannot start Volumio Streaming Daemon Dec 28 00:31:23 volumio4 volumio[1207]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 28 00:31:23 volumio4 volumio[1207]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 28 00:31:23 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:23 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 00:31:23 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:23 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:23 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 00:31:23 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:23 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:23 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 00:31:23 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:23 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 28 00:31:24 volumio4 volumio[1207]: (node:1207) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 close listeners added to [TLSSocket]. Use emitter.setMaxListeners() to increase limit Dec 28 00:31:24 volumio4 volumio[1207]: (Use `node --trace-warnings ...` to show where the warning was created) Dec 28 00:31:25 volumio4 volumio[1207]: info: CALLMETHOD: user_interface peppy_screensaver saveVUMeterConf [object Object] Dec 28 00:31:25 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: peppy_screensaver , saveVUMeterConf Dec 28 00:31:25 volumio4 volumio[1207]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 00:31:25 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 00:31:26 volumio4 systemd[1]: systemd-fsckd.service: Deactivated successfully. Dec 28 00:31:29 volumio4 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Dec 28 00:31:29 volumio4 volumio[1207]: info: CoreCommandRouter::volumioGetState Dec 28 00:31:29 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:29 volumio4 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Dec 28 00:31:29 volumio4 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 28 00:31:29 volumio4 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 28 00:31:29 volumio4 sudo[2198]: pam_unix(sudo:session): session closed for user root Dec 28 00:31:29 volumio4 volumio[1207]: info: Upmpdcli Daemon Started Dec 28 00:31:29 volumio4 upmpdcli[2370]: writing RSA key Dec 28 00:31:30 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:30 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 00:31:30 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:30 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:30 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 00:31:30 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:30 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:30 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 00:31:30 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:30 volumio4 volumio[1207]: info: CoreCommandRouter::volumioPlay Dec 28 00:31:30 volumio4 volumio[1207]: info: CoreStateMachine::play index undefined Dec 28 00:31:30 volumio4 volumio[1207]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 00:31:30 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:30 volumio4 volumio[1207]: info: CoreStateMachine::startPlaybackTimer Dec 28 00:31:30 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:30 volumio4 volumio[1207]: verbose: ControllerMpd::clearAddPlayTracks NAS/MusicBox/10邓丽君/《一意专心：惦记邓丽君三十年》96kHz／24bit/1-01 漫步人生路.flac Dec 28 00:31:30 volumio4 volumio[1207]: verbose: ControllerMpd::sendMpdCommand stop Dec 28 00:31:30 volumio4 volumio[1207]: info: sendMpdCommand stop took 1 milliseconds Dec 28 00:31:30 volumio4 volumio[1207]: verbose: ControllerMpd::sendMpdCommand clear Dec 28 00:31:30 volumio4 volumio[1207]: info: Dec 28 00:31:30 volumio4 volumio[1207]: ---------------------------- MPD announces system playlist update Dec 28 00:31:30 volumio4 volumio[1207]: info: Ignoring MPD Status Update Dec 28 00:31:30 volumio4 volumio[1207]: info: sendMpdCommand clear took 1 milliseconds Dec 28 00:31:30 volumio4 volumio[1207]: verbose: ControllerMpd::sendMpdCommand add "NAS/MusicBox/10邓丽君/《一意专心：惦记邓丽君三十年》96kHz／24bit/1-01 漫步人生路.flac" Dec 28 00:31:30 volumio4 volumio[1207]: info: Dec 28 00:31:30 volumio4 volumio[1207]: ---------------------------- MPD announces system playlist update Dec 28 00:31:30 volumio4 volumio[1207]: info: Ignoring MPD Status Update Dec 28 00:31:30 volumio4 volumio[1207]: info: Dec 28 00:31:30 volumio4 volumio[1207]: ---------------------------- MPD announces system playlist update Dec 28 00:31:30 volumio4 volumio[1207]: info: Ignoring MPD Status Update Dec 28 00:31:30 volumio4 volumio[1207]: info: Dec 28 00:31:30 volumio4 volumio[1207]: ---------------------------- MPD announces system playlist update Dec 28 00:31:30 volumio4 volumio[1207]: info: Ignoring MPD Status Update Dec 28 00:31:30 volumio4 volumio[1207]: error: updateQueue error: null Dec 28 00:31:30 volumio4 volumio[1207]: info: Dec 28 00:31:30 volumio4 volumio[1207]: ---------------------------- MPD announces system playlist update Dec 28 00:31:30 volumio4 volumio[1207]: info: Ignoring MPD Status Update Dec 28 00:31:30 volumio4 volumio[1207]: info: ------------------------------ 4ms Dec 28 00:31:30 volumio4 volumio[1207]: info: sendMpdCommand add "NAS/MusicBox/10邓丽君/《一意专心：惦记邓丽君三十年》96kHz／24bit/1-01 漫步人生路.flac" took 3 milliseconds Dec 28 00:31:30 volumio4 volumio[1207]: info: ------------------------------ 3ms Dec 28 00:31:30 volumio4 volumio[1207]: info: ------------------------------ 2ms Dec 28 00:31:30 volumio4 volumio[1207]: info: ------------------------------ 2ms Dec 28 00:31:30 volumio4 volumio[1207]: verbose: ControllerMpd::sendMpdCommand play Dec 28 00:31:30 volumio4 volumio[1207]: info: Dec 28 00:31:30 volumio4 volumio[1207]: ---------------------------- MPD announces system playlist update Dec 28 00:31:30 volumio4 volumio[1207]: info: Ignoring MPD Status Update Dec 28 00:31:30 volumio4 volumio[1207]: info: Dec 28 00:31:30 volumio4 volumio[1207]: ---------------------------- MPD announces system playlist update Dec 28 00:31:30 volumio4 volumio[1207]: info: Ignoring MPD Status Update Dec 28 00:31:30 volumio4 volumio[1207]: info: Dec 28 00:31:30 volumio4 volumio[1207]: ---------------------------- MPD announces system playlist update Dec 28 00:31:30 volumio4 volumio[1207]: info: Ignoring MPD Status Update Dec 28 00:31:30 volumio4 volumio[1207]: info: ------------------------------ 5ms Dec 28 00:31:30 volumio4 volumio[1207]: info: sendMpdCommand play took 4 milliseconds Dec 28 00:31:30 volumio4 volumio[1207]: info: ------------------------------ 3ms Dec 28 00:31:30 volumio4 volumio[1207]: info: ------------------------------ 3ms Dec 28 00:31:30 volumio4 volumio[1207]: info: ------------------------------ 3ms Dec 28 00:31:31 volumio4 dhcpcd[764]: eth0: requesting DHCPv6 information Dec 28 00:31:31 volumio4 volumio[1207]: info: Dec 28 00:31:31 volumio4 volumio[1207]: ---------------------------- MPD announces state update: player Dec 28 00:31:31 volumio4 volumio[1207]: info: ControllerMpd::getState Dec 28 00:31:31 volumio4 volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Dec 28 00:31:31 volumio4 volumio[1207]: info: Dec 28 00:31:31 volumio4 volumio[1207]: ---------------------------- MPD announces state update: player Dec 28 00:31:31 volumio4 volumio[1207]: info: ControllerMpd::getState Dec 28 00:31:31 volumio4 volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Dec 28 00:31:31 volumio4 volumio[1207]: info: Dec 28 00:31:31 volumio4 volumio[1207]: ---------------------------- MPD announces state update: player Dec 28 00:31:31 volumio4 volumio[1207]: info: ControllerMpd::getState Dec 28 00:31:31 volumio4 volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Dec 28 00:31:31 volumio4 volumio[1207]: info: Dec 28 00:31:31 volumio4 volumio[1207]: ---------------------------- MPD announces state update: player Dec 28 00:31:31 volumio4 volumio[1207]: info: ControllerMpd::getState Dec 28 00:31:31 volumio4 volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Dec 28 00:31:31 volumio4 volumio[1207]: info: sendMpdCommand status took 33 milliseconds Dec 28 00:31:31 volumio4 volumio[1207]: verbose: ControllerMpd::parseState Dec 28 00:31:31 volumio4 volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 00:31:31 volumio4 volumio[1207]: info: Dec 28 00:31:31 volumio4 volumio[1207]: ---------------------------- MPD announces state update: player Dec 28 00:31:31 volumio4 volumio[1207]: info: ControllerMpd::getState Dec 28 00:31:31 volumio4 volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Dec 28 00:31:31 volumio4 volumio[1207]: info: Dec 28 00:31:31 volumio4 volumio[1207]: ---------------------------- MPD announces state update: player Dec 28 00:31:31 volumio4 volumio[1207]: info: ControllerMpd::getState Dec 28 00:31:31 volumio4 volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Dec 28 00:31:31 volumio4 volumio[1207]: info: sendMpdCommand status took 242 milliseconds Dec 28 00:31:31 volumio4 volumio[1207]: info: sendMpdCommand status took 243 milliseconds Dec 28 00:31:31 volumio4 volumio[1207]: info: sendMpdCommand status took 244 milliseconds Dec 28 00:31:31 volumio4 volumio[1207]: info: sendMpdCommand playlistinfo took 208 milliseconds Dec 28 00:31:31 volumio4 volumio[1207]: verbose: ControllerMpd::parseState Dec 28 00:31:31 volumio4 volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 00:31:31 volumio4 volumio[1207]: verbose: ControllerMpd::parseState Dec 28 00:31:31 volumio4 volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 00:31:31 volumio4 volumio[1207]: verbose: ControllerMpd::parseState Dec 28 00:31:31 volumio4 volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 00:31:31 volumio4 volumio[1207]: verbose: ControllerMpd::parseTrackInfo Dec 28 00:31:31 volumio4 volumio[1207]: info: ControllerMpd::pushState Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreCommandRouter::servicePushState Dec 28 00:31:31 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:31 volumio4 volumio[1207]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":221,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1779 Kbps","isStreaming":false,"title":"漫步人生路","artist":"邓丽君","album":"一意专心：惦记邓丽君三十年 CD1","uri":"NAS/MusicBox/10邓丽君/《一意专心：惦记邓丽君三十年》96kHz／24bit/1-01 漫步人生路.flac","trackType":"flac"} Dec 28 00:31:31 volumio4 volumio[1207]: verbose: CURRENT POSITION 0 Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreStateMachine::syncState stateService play Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreStateMachine::syncState currentStatus stop Dec 28 00:31:31 volumio4 volumio[1207]: info: ------------------------------ 259ms Dec 28 00:31:31 volumio4 volumio[1207]: info: Dec 28 00:31:31 volumio4 volumio[1207]: ---------------------------- MPD announces state update: player Dec 28 00:31:31 volumio4 volumio[1207]: info: ControllerMpd::getState Dec 28 00:31:31 volumio4 volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Dec 28 00:31:31 volumio4 volumio[1207]: info: Dec 28 00:31:31 volumio4 volumio[1207]: ---------------------------- MPD announces state update: player Dec 28 00:31:31 volumio4 volumio[1207]: info: ControllerMpd::getState Dec 28 00:31:31 volumio4 volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Dec 28 00:31:31 volumio4 volumio[1207]: info: sendMpdCommand status took 213 milliseconds Dec 28 00:31:31 volumio4 volumio[1207]: info: sendMpdCommand status took 13 milliseconds Dec 28 00:31:31 volumio4 volumio[1207]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 28 00:31:31 volumio4 volumio[1207]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 28 00:31:31 volumio4 volumio[1207]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 28 00:31:31 volumio4 volumio[1207]: info: sendMpdCommand status took 3 milliseconds Dec 28 00:31:31 volumio4 volumio[1207]: info: sendMpdCommand status took 2 milliseconds Dec 28 00:31:31 volumio4 volumio[1207]: verbose: ControllerMpd::parseState Dec 28 00:31:31 volumio4 volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 00:31:31 volumio4 volumio[1207]: verbose: ControllerMpd::parseState Dec 28 00:31:31 volumio4 volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 00:31:31 volumio4 volumio[1207]: verbose: ControllerMpd::parseTrackInfo Dec 28 00:31:31 volumio4 volumio[1207]: verbose: ControllerMpd::parseTrackInfo Dec 28 00:31:31 volumio4 volumio[1207]: verbose: ControllerMpd::parseTrackInfo Dec 28 00:31:31 volumio4 volumio[1207]: verbose: ControllerMpd::parseState Dec 28 00:31:31 volumio4 volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 00:31:31 volumio4 volumio[1207]: verbose: ControllerMpd::parseState Dec 28 00:31:31 volumio4 volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 00:31:31 volumio4 volumio[1207]: info: ControllerMpd::pushState Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreCommandRouter::servicePushState Dec 28 00:31:31 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:31 volumio4 volumio[1207]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":221,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2477 Kbps","isStreaming":false,"title":"漫步人生路","artist":"邓丽君","album":"一意专心：惦记邓丽君三十年 CD1","uri":"NAS/MusicBox/10邓丽君/《一意专心：惦记邓丽君三十年》96kHz／24bit/1-01 漫步人生路.flac","trackType":"flac"} Dec 28 00:31:31 volumio4 volumio[1207]: verbose: CURRENT POSITION 0 Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreStateMachine::syncState stateService play Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreStateMachine::syncState currentStatus play Dec 28 00:31:31 volumio4 volumio[1207]: info: Received an update from plugin. extracting info from payload Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreStateMachine::pushState Dec 28 00:31:31 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreCommandRouter::volumioPushState Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreStateMachine::pushState Dec 28 00:31:31 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreCommandRouter::volumioPushState Dec 28 00:31:31 volumio4 volumio[1207]: info: ControllerMpd::pushState Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreCommandRouter::servicePushState Dec 28 00:31:31 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:31 volumio4 volumio[1207]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":221,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2477 Kbps","isStreaming":false,"title":"漫步人生路","artist":"邓丽君","album":"一意专心：惦记邓丽君三十年 CD1","uri":"NAS/MusicBox/10邓丽君/《一意专心：惦记邓丽君三十年》96kHz／24bit/1-01 漫步人生路.flac","trackType":"flac"} Dec 28 00:31:31 volumio4 volumio[1207]: verbose: CURRENT POSITION 0 Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreStateMachine::syncState stateService play Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreStateMachine::syncState currentStatus play Dec 28 00:31:31 volumio4 volumio[1207]: info: Received an update from plugin. extracting info from payload Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreStateMachine::pushState Dec 28 00:31:31 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreCommandRouter::volumioPushState Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreStateMachine::pushState Dec 28 00:31:31 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreCommandRouter::volumioPushState Dec 28 00:31:31 volumio4 volumio[1207]: info: ControllerMpd::pushState Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreCommandRouter::servicePushState Dec 28 00:31:31 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:31 volumio4 volumio[1207]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":221,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2477 Kbps","isStreaming":false,"title":"漫步人生路","artist":"邓丽君","album":"一意专心：惦记邓丽君三十年 CD1","uri":"NAS/MusicBox/10邓丽君/《一意专心：惦记邓丽君三十年》96kHz／24bit/1-01 漫步人生路.flac","trackType":"flac"} Dec 28 00:31:31 volumio4 volumio[1207]: verbose: CURRENT POSITION 0 Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreStateMachine::syncState stateService play Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreStateMachine::syncState currentStatus play Dec 28 00:31:31 volumio4 volumio[1207]: info: Received an update from plugin. extracting info from payload Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreStateMachine::pushState Dec 28 00:31:31 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreCommandRouter::volumioPushState Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreStateMachine::pushState Dec 28 00:31:31 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreCommandRouter::volumioPushState Dec 28 00:31:31 volumio4 volumio[1207]: info: ------------------------------ 302ms Dec 28 00:31:31 volumio4 volumio[1207]: info: ------------------------------ 300ms Dec 28 00:31:31 volumio4 volumio[1207]: info: ------------------------------ 299ms Dec 28 00:31:31 volumio4 volumio[1207]: info: sendMpdCommand playlistinfo took 48 milliseconds Dec 28 00:31:31 volumio4 volumio[1207]: info: sendMpdCommand playlistinfo took 48 milliseconds Dec 28 00:31:31 volumio4 volumio[1207]: info: sendMpdCommand playlistinfo took 47 milliseconds Dec 28 00:31:31 volumio4 volumio[1207]: info: sendMpdCommand playlistinfo took 47 milliseconds Dec 28 00:31:31 volumio4 volumio[1207]: verbose: ControllerMpd::parseTrackInfo Dec 28 00:31:31 volumio4 volumio[1207]: verbose: ControllerMpd::parseTrackInfo Dec 28 00:31:31 volumio4 volumio[1207]: verbose: ControllerMpd::parseTrackInfo Dec 28 00:31:31 volumio4 volumio[1207]: verbose: ControllerMpd::parseTrackInfo Dec 28 00:31:31 volumio4 volumio[1207]: info: ControllerMpd::pushState Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreCommandRouter::servicePushState Dec 28 00:31:31 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:31 volumio4 volumio[1207]: verbose: STATE SERVICE {"status":"play","position":0,"seek":340,"duration":221,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2496 Kbps","isStreaming":false,"title":"漫步人生路","artist":"邓丽君","album":"一意专心：惦记邓丽君三十年 CD1","uri":"NAS/MusicBox/10邓丽君/《一意专心：惦记邓丽君三十年》96kHz／24bit/1-01 漫步人生路.flac","trackType":"flac"} Dec 28 00:31:31 volumio4 volumio[1207]: verbose: CURRENT POSITION 0 Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreStateMachine::syncState stateService play Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreStateMachine::syncState currentStatus play Dec 28 00:31:31 volumio4 volumio[1207]: info: Received an update from plugin. extracting info from payload Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreStateMachine::pushState Dec 28 00:31:31 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreCommandRouter::volumioPushState Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreStateMachine::pushState Dec 28 00:31:31 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreCommandRouter::volumioPushState Dec 28 00:31:31 volumio4 volumio[1207]: info: ControllerMpd::pushState Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreCommandRouter::servicePushState Dec 28 00:31:31 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:31 volumio4 volumio[1207]: verbose: STATE SERVICE {"status":"play","position":0,"seek":351,"duration":221,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2496 Kbps","isStreaming":false,"title":"漫步人生路","artist":"邓丽君","album":"一意专心：惦记邓丽君三十年 CD1","uri":"NAS/MusicBox/10邓丽君/《一意专心：惦记邓丽君三十年》96kHz／24bit/1-01 漫步人生路.flac","trackType":"flac"} Dec 28 00:31:31 volumio4 volumio[1207]: verbose: CURRENT POSITION 0 Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreStateMachine::syncState stateService play Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreStateMachine::syncState currentStatus play Dec 28 00:31:31 volumio4 volumio[1207]: info: Received an update from plugin. extracting info from payload Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreStateMachine::pushState Dec 28 00:31:31 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreCommandRouter::volumioPushState Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreStateMachine::pushState Dec 28 00:31:31 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreCommandRouter::volumioPushState Dec 28 00:31:31 volumio4 volumio[1207]: info: ControllerMpd::pushState Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreCommandRouter::servicePushState Dec 28 00:31:31 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:31 volumio4 volumio[1207]: verbose: STATE SERVICE {"status":"play","position":0,"seek":356,"duration":221,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2496 Kbps","isStreaming":false,"title":"漫步人生路","artist":"邓丽君","album":"一意专心：惦记邓丽君三十年 CD1","uri":"NAS/MusicBox/10邓丽君/《一意专心：惦记邓丽君三十年》96kHz／24bit/1-01 漫步人生路.flac","trackType":"flac"} Dec 28 00:31:31 volumio4 volumio[1207]: verbose: CURRENT POSITION 0 Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreStateMachine::syncState stateService play Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreStateMachine::syncState currentStatus play Dec 28 00:31:31 volumio4 volumio[1207]: info: Received an update from plugin. extracting info from payload Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreStateMachine::pushState Dec 28 00:31:31 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreCommandRouter::volumioPushState Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreStateMachine::pushState Dec 28 00:31:31 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreCommandRouter::volumioPushState Dec 28 00:31:31 volumio4 volumio[1207]: info: ControllerMpd::pushState Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreCommandRouter::servicePushState Dec 28 00:31:31 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:31 volumio4 volumio[1207]: verbose: STATE SERVICE {"status":"play","position":0,"seek":356,"duration":221,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2496 Kbps","isStreaming":false,"title":"漫步人生路","artist":"邓丽君","album":"一意专心：惦记邓丽君三十年 CD1","uri":"NAS/MusicBox/10邓丽君/《一意专心：惦记邓丽君三十年》96kHz／24bit/1-01 漫步人生路.flac","trackType":"flac"} Dec 28 00:31:31 volumio4 volumio[1207]: verbose: CURRENT POSITION 0 Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreStateMachine::syncState stateService play Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreStateMachine::syncState currentStatus play Dec 28 00:31:31 volumio4 volumio[1207]: info: Received an update from plugin. extracting info from payload Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreStateMachine::pushState Dec 28 00:31:31 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreCommandRouter::volumioPushState Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreStateMachine::pushState Dec 28 00:31:31 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:31 volumio4 volumio[1207]: info: CoreCommandRouter::volumioPushState Dec 28 00:31:31 volumio4 volumio[1207]: info: ------------------------------ 344ms Dec 28 00:31:31 volumio4 volumio[1207]: info: ------------------------------ 345ms Dec 28 00:31:31 volumio4 volumio[1207]: info: ------------------------------ 134ms Dec 28 00:31:31 volumio4 volumio[1207]: info: ------------------------------ 133ms Dec 28 00:31:32 volumio4 volumio[1207]: info: CoreCommandRouter::volumioGetState Dec 28 00:31:32 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:33 volumio4 volumio[1207]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.28.1 Engine version: 3 Transport: polling Total Clients: 8 Dec 28 00:31:33 volumio4 volumio[1207]: info: CoreCommandRouter::volumioGetState Dec 28 00:31:33 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:37 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 28 00:31:37 volumio4 volumio[1207]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 28 00:31:37 volumio4 volumio[1207]: info: CoreCommandRouter::volumioGetState Dec 28 00:31:37 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:37 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 28 00:31:37 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 00:31:37 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 28 00:31:38 volumio4 systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 28 00:31:38 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:38 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 00:31:38 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:38 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:38 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 00:31:38 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:38 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:38 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 00:31:38 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:38 volumio4 volumio-remote-updater[892]: Test mode disabled Dec 28 00:31:38 volumio4 volumio-remote-updater[892]: Alpha mode disabled Dec 28 00:31:38 volumio4 volumio-remote-updater[892]: Alpha legacy test mode disabled Dec 28 00:31:38 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 28 00:31:39 volumio4 volumio[1207]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 28 00:31:39 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 28 00:31:41 volumio4 dhcpcd[764]: eth0: requesting DHCPv6 information Dec 28 00:31:42 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:42 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:42 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 28 00:31:42 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 28 00:31:42 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 28 00:31:42 volumio4 volumio[1207]: info: CoreCommandRouter::volumioGetBrowseSources Dec 28 00:31:42 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 28 00:31:43 volumio4 volumio[1207]: error: MyVolumio Plugin failed to authenticate in a timely fashion Dec 28 00:31:43 volumio4 volumio[1207]: info: Completed starting MyVolumio Plugin Dec 28 00:31:43 volumio4 volumio[1207]: [Metrics] CommandRouter: 39s 200.64ms Dec 28 00:31:43 volumio4 volumio[1207]: info: CoreCommandRouter::volumiosetStartupVolume Dec 28 00:31:43 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 00:31:43 volumio4 volumio[1207]: info: VolumeController:: Setting startup Volume 95 Dec 28 00:31:43 volumio4 volumio[1207]: info: VolumeController::SetAlsaVolume95 Dec 28 00:31:43 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 00:31:43 volumio4 volumio[1207]: info: CoreCommandRouter::Close All Modals sent Dec 28 00:31:43 volumio4 volumio[1207]: info: CoreCommandRouter::Close All Modals sent Dec 28 00:31:43 volumio4 volumio[1207]: info: CoreStateMachine::pushState Dec 28 00:31:43 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:43 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 00:31:43 volumio4 volumio[1207]: info: CoreCommandRouter::volumioPushState Dec 28 00:31:43 volumio4 volumio[1207]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Dec 28 00:31:43 volumio4 volumio[1207]: aplay: main:831: audio open error: Device or resource busy Dec 28 00:31:44 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 28 00:31:44 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 28 00:31:44 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 28 00:31:44 volumio4 volumio[1207]: info: CoreCommandRouter::volumioGetState Dec 28 00:31:44 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:31:46 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:46 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 00:31:46 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:46 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:46 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 00:31:46 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:46 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:46 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 00:31:46 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:50 volumio4 volumio[1207]: info: BOOT COMPLETED Dec 28 00:31:51 volumio4 dhcpcd[764]: eth0: requesting DHCPv6 information Dec 28 00:31:53 volumio4 volumio[1207]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Dec 28 00:31:53 volumio4 volumio[1207]: info: Retrying Login Due to Network Error Dec 28 00:31:53 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:53 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 00:31:53 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:53 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:53 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 00:31:53 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:53 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:53 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 00:31:53 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:31:59 volumio4 volumio[1207]: info: CoreCommandRouter::volumioGetState Dec 28 00:31:59 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:32:01 volumio4 dhcpcd[764]: eth0: requesting DHCPv6 information Dec 28 00:32:01 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:32:01 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 00:32:01 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:32:01 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:32:01 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 00:32:01 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:32:01 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:32:01 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 00:32:01 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:32:01 volumio4 systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 28 00:32:01 volumio4 systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 28 00:32:03 volumio4 volumio[1207]: info: MyVolumio login type: Token Dec 28 00:32:04 volumio4 volumio[1207]: (node:1207) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 close listeners added to [TLSSocket]. Use emitter.setMaxListeners() to increase limit Dec 28 00:32:09 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:32:09 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 00:32:09 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:32:09 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:32:09 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 00:32:09 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:32:09 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:32:09 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 00:32:09 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:32:11 volumio4 dhcpcd[764]: eth0: requesting DHCPv6 information Dec 28 00:32:14 volumio4 volumio[1207]: info: CoreCommandRouter::volumioGetState Dec 28 00:32:14 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:32:17 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:32:17 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 00:32:17 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:32:17 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:32:17 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 00:32:17 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:32:17 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:32:17 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 00:32:17 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:32:21 volumio4 dhcpcd[764]: eth0: requesting DHCPv6 information Dec 28 00:32:24 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:32:24 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 00:32:24 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:32:24 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:32:24 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 00:32:24 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:32:24 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:32:24 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 00:32:24 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:32:29 volumio4 volumio[1207]: info: CoreCommandRouter::volumioGetState Dec 28 00:32:29 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:32:31 volumio4 dhcpcd[764]: eth0: requesting DHCPv6 information Dec 28 00:32:31 volumio4 volumio[1207]: info: CoreCommandRouter::volumioPause Dec 28 00:32:31 volumio4 volumio[1207]: info: CoreStateMachine::pause Dec 28 00:32:31 volumio4 volumio[1207]: info: CoreStateMachine::stPlaybackTimer Dec 28 00:32:31 volumio4 volumio[1207]: info: CoreStateMachine::servicePause Dec 28 00:32:31 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:32:31 volumio4 volumio[1207]: info: CoreCommandRouter::servicePause Dec 28 00:32:31 volumio4 volumio[1207]: info: ControllerMpd::pause Dec 28 00:32:31 volumio4 volumio[1207]: verbose: ControllerMpd::sendMpdCommand pause Dec 28 00:32:31 volumio4 volumio[1207]: info: sendMpdCommand pause took 2 milliseconds Dec 28 00:32:31 volumio4 volumio[1207]: info: Dec 28 00:32:31 volumio4 volumio[1207]: ---------------------------- MPD announces state update: player Dec 28 00:32:31 volumio4 volumio[1207]: info: ControllerMpd::getState Dec 28 00:32:31 volumio4 volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Dec 28 00:32:31 volumio4 volumio[1207]: info: Dec 28 00:32:31 volumio4 volumio[1207]: ---------------------------- MPD announces state update: player Dec 28 00:32:31 volumio4 volumio[1207]: info: ControllerMpd::getState Dec 28 00:32:31 volumio4 volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Dec 28 00:32:31 volumio4 volumio[1207]: info: Dec 28 00:32:31 volumio4 volumio[1207]: ---------------------------- MPD announces state update: player Dec 28 00:32:31 volumio4 volumio[1207]: info: ControllerMpd::getState Dec 28 00:32:31 volumio4 volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Dec 28 00:32:31 volumio4 volumio[1207]: info: Dec 28 00:32:31 volumio4 volumio[1207]: ---------------------------- MPD announces state update: player Dec 28 00:32:31 volumio4 volumio[1207]: info: ControllerMpd::getState Dec 28 00:32:31 volumio4 volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Dec 28 00:32:31 volumio4 volumio[1207]: info: sendMpdCommand status took 1 milliseconds Dec 28 00:32:31 volumio4 volumio[1207]: info: sendMpdCommand status took 1 milliseconds Dec 28 00:32:31 volumio4 volumio[1207]: info: sendMpdCommand status took 1 milliseconds Dec 28 00:32:31 volumio4 volumio[1207]: verbose: ControllerMpd::parseState Dec 28 00:32:31 volumio4 volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 00:32:31 volumio4 volumio[1207]: verbose: ControllerMpd::parseState Dec 28 00:32:31 volumio4 volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 00:32:31 volumio4 volumio[1207]: verbose: ControllerMpd::parseState Dec 28 00:32:31 volumio4 volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 00:32:31 volumio4 volumio[1207]: info: sendMpdCommand status took 2 milliseconds Dec 28 00:32:31 volumio4 volumio[1207]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 28 00:32:31 volumio4 volumio[1207]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 28 00:32:31 volumio4 volumio[1207]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 28 00:32:31 volumio4 volumio[1207]: verbose: ControllerMpd::parseState Dec 28 00:32:31 volumio4 volumio[1207]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 00:32:31 volumio4 volumio[1207]: verbose: ControllerMpd::parseTrackInfo Dec 28 00:32:31 volumio4 volumio[1207]: verbose: ControllerMpd::parseTrackInfo Dec 28 00:32:31 volumio4 volumio[1207]: verbose: ControllerMpd::parseTrackInfo Dec 28 00:32:31 volumio4 volumio[1207]: info: ControllerMpd::pushState Dec 28 00:32:31 volumio4 volumio[1207]: info: CoreCommandRouter::servicePushState Dec 28 00:32:31 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:32:31 volumio4 volumio[1207]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":60941,"duration":221,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2651 Kbps","isStreaming":false,"title":"漫步人生路","artist":"邓丽君","album":"一意专心：惦记邓丽君三十年 CD1","uri":"NAS/MusicBox/10邓丽君/《一意专心：惦记邓丽君三十年》96kHz／24bit/1-01 漫步人生路.flac","trackType":"flac"} Dec 28 00:32:31 volumio4 volumio[1207]: verbose: CURRENT POSITION 0 Dec 28 00:32:31 volumio4 volumio[1207]: info: CoreStateMachine::syncState stateService pause Dec 28 00:32:31 volumio4 volumio[1207]: info: CoreStateMachine::syncState currentStatus pause Dec 28 00:32:31 volumio4 volumio[1207]: info: CoreStateMachine::pushState Dec 28 00:32:31 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:32:31 volumio4 volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 00:32:31 volumio4 volumio[1207]: info: CoreCommandRouter::volumioPushState Dec 28 00:32:31 volumio4 volumio[1207]: info: CoreStateMachine::stPlaybackTimer Dec 28 00:32:31 volumio4 volumio[1207]: info: ControllerMpd::pushState Dec 28 00:32:31 volumio4 volumio[1207]: info: CoreCommandRouter::servicePushState Dec 28 00:32:31 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:32:31 volumio4 volumio[1207]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":60941,"duration":221,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2651 Kbps","isStreaming":false,"title":"漫步人生路","artist":"邓丽君","album":"一意专心：惦记邓丽君三十年 CD1","uri":"NAS/MusicBox/10邓丽君/《一意专心：惦记邓丽君三十年》96kHz／24bit/1-01 漫步人生路.flac","trackType":"flac"} Dec 28 00:32:31 volumio4 volumio[1207]: verbose: CURRENT POSITION 0 Dec 28 00:32:31 volumio4 volumio[1207]: info: CoreStateMachine::syncState stateService pause Dec 28 00:32:31 volumio4 volumio[1207]: info: CoreStateMachine::syncState currentStatus pause Dec 28 00:32:31 volumio4 volumio[1207]: info: CoreStateMachine::pushState Dec 28 00:32:31 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:32:31 volumio4 volumio[1207]: info: CoreCommandRouter::volumioPushState Dec 28 00:32:31 volumio4 volumio[1207]: info: CoreStateMachine::stPlaybackTimer Dec 28 00:32:31 volumio4 volumio[1207]: info: ControllerMpd::pushState Dec 28 00:32:31 volumio4 volumio[1207]: info: CoreCommandRouter::servicePushState Dec 28 00:32:31 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:32:31 volumio4 volumio[1207]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":60941,"duration":221,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2651 Kbps","isStreaming":false,"title":"漫步人生路","artist":"邓丽君","album":"一意专心：惦记邓丽君三十年 CD1","uri":"NAS/MusicBox/10邓丽君/《一意专心：惦记邓丽君三十年》96kHz／24bit/1-01 漫步人生路.flac","trackType":"flac"} Dec 28 00:32:31 volumio4 volumio[1207]: verbose: CURRENT POSITION 0 Dec 28 00:32:31 volumio4 volumio[1207]: info: CoreStateMachine::syncState stateService pause Dec 28 00:32:31 volumio4 volumio[1207]: info: CoreStateMachine::syncState currentStatus pause Dec 28 00:32:31 volumio4 volumio[1207]: info: CoreStateMachine::pushState Dec 28 00:32:31 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:32:31 volumio4 volumio[1207]: info: CoreCommandRouter::volumioPushState Dec 28 00:32:31 volumio4 volumio[1207]: info: CoreStateMachine::stPlaybackTimer Dec 28 00:32:31 volumio4 volumio[1207]: info: ------------------------------ 51ms Dec 28 00:32:31 volumio4 volumio[1207]: info: ------------------------------ 51ms Dec 28 00:32:31 volumio4 volumio[1207]: info: ------------------------------ 63ms Dec 28 00:32:31 volumio4 volumio[1207]: info: sendMpdCommand playlistinfo took 61 milliseconds Dec 28 00:32:31 volumio4 volumio[1207]: verbose: ControllerMpd::parseTrackInfo Dec 28 00:32:31 volumio4 volumio[1207]: info: ControllerMpd::pushState Dec 28 00:32:31 volumio4 volumio[1207]: info: CoreCommandRouter::servicePushState Dec 28 00:32:31 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:32:31 volumio4 volumio[1207]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":60941,"duration":221,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2651 Kbps","isStreaming":false,"title":"漫步人生路","artist":"邓丽君","album":"一意专心：惦记邓丽君三十年 CD1","uri":"NAS/MusicBox/10邓丽君/《一意专心：惦记邓丽君三十年》96kHz／24bit/1-01 漫步人生路.flac","trackType":"flac"} Dec 28 00:32:31 volumio4 volumio[1207]: verbose: CURRENT POSITION 0 Dec 28 00:32:31 volumio4 volumio[1207]: info: CoreStateMachine::syncState stateService pause Dec 28 00:32:31 volumio4 volumio[1207]: info: CoreStateMachine::syncState currentStatus pause Dec 28 00:32:31 volumio4 volumio[1207]: info: CoreStateMachine::pushState Dec 28 00:32:31 volumio4 volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 28 00:32:31 volumio4 volumio[1207]: info: CoreCommandRouter::volumioPushState Dec 28 00:32:31 volumio4 volumio[1207]: info: CoreStateMachine::stPlaybackTimer Dec 28 00:32:31 volumio4 volumio[1207]: info: ------------------------------ 97ms Dec 28 00:32:31 volumio4 volumio[1207]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 00:32:31 volumio4 volumio[1207]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Dec 28 00:32:31 volumio4 volumio[1207]: at Object.readdirSync (node:fs:1543:3) Dec 28 00:32:31 volumio4 volumio[1207]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Dec 28 00:32:31 volumio4 volumio[1207]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Dec 28 00:32:31 volumio4 volumio[1207]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Dec 28 00:32:31 volumio4 volumio[1207]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Dec 28 00:32:31 volumio4 volumio[1207]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Dec 28 00:32:31 volumio4 volumio[1207]: at Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Dec 28 00:32:31 volumio4 volumio[1207]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Dec 28 00:32:31 volumio4 volumio[1207]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Dec 28 00:32:31 volumio4 volumio[1207]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Dec 28 00:32:31 volumio4 volumio[1207]: at Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Dec 28 00:32:31 volumio4 volumio[1207]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) Dec 28 00:32:31 volumio4 volumio[1207]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Dec 28 00:32:31 volumio4 volumio[1207]: at Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Dec 28 00:32:31 volumio4 volumio[1207]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) Dec 28 00:32:31 volumio4 volumio[1207]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { Dec 28 00:32:31 volumio4 volumio[1207]: errno: -20, Dec 28 00:32:31 volumio4 volumio[1207]: syscall: 'scandir', Dec 28 00:32:31 volumio4 volumio[1207]: code: 'ENOTDIR', Dec 28 00:32:31 volumio4 volumio[1207]: path: '/tmp/peppyrunning' Dec 28 00:32:31 volumio4 volumio[1207]: } Dec 28 00:32:31 volumio4 volumio[1207]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 00:32:32 volumio4 sudo[3120]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-28 00:31' Dec 28 00:32:32 volumio4 sudo[3120]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 00:32:32 volumio4 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 28 00:32:32 volumio4 systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 28 00:32:32 volumio4 systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"