-- Logs begin at Fri 2025-03-07 22:38:51 CST, end at Fri 2025-03-07 22:40:02 CST. -- Mar 07 22:39:06 volumio systemd[1]: Started RPC bind portmap service. Mar 07 22:39:06 volumio systemd[1]: Started triggerhappy global hotkey daemon. Mar 07 22:39:06 volumio avahi-daemon[813]: Successfully called chroot(). Mar 07 22:39:06 volumio systemd[1]: systemd-rfkill.service: Succeeded. Mar 07 22:39:06 volumio avahi-daemon[813]: Successfully dropped remaining capabilities. Mar 07 22:39:06 volumio systemd[1]: Started Save/Restore Sound Card State. Mar 07 22:39:06 volumio wpa_supplicant[817]: Successfully initialized wpa_supplicant Mar 07 22:39:06 volumio systemd[1]: Started Login Service. Mar 07 22:39:06 volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Mar 07 22:39:06 volumio systemd[1]: Started Bluetooth service. Mar 07 22:39:06 volumio systemd[1]: Started WPA supplicant. Mar 07 22:39:06 volumio systemd[1]: Reached target Bluetooth. Mar 07 22:39:06 volumio systemd[1]: Reached target Sound Card. Mar 07 22:39:06 volumio systemd[1]: Reached target Remote File Systems (Pre). Mar 07 22:39:06 volumio systemd[1]: Reached target Remote File Systems. Mar 07 22:39:06 volumio bluetoothd[808]: Starting SDP server Mar 07 22:39:06 volumio bluetoothd[808]: Excluding (cli) sap Mar 07 22:39:06 volumio sh[745]: wlan0=wlan0 Mar 07 22:39:06 volumio avahi-daemon[813]: No service file found in /etc/avahi/services. Mar 07 22:39:06 volumio avahi-daemon[813]: Network interface enumeration completed. Mar 07 22:39:06 volumio avahi-daemon[813]: Server startup complete. Host name is volumio.local. Local service cookie is 2104286648. Mar 07 22:39:06 volumio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Mar 07 22:39:06 volumio systemd[1]: Reached target RPC Port Mapper. Mar 07 22:39:06 volumio systemd[1]: Started Raise network interfaces. Mar 07 22:39:06 volumio systemd[1]: Reached target Network. Mar 07 22:39:06 volumio systemd[1]: Reached target Network is Online. Mar 07 22:39:06 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Mar 07 22:39:06 volumio kernel: Bluetooth: BNEP filters: protocol multicast Mar 07 22:39:06 volumio kernel: Bluetooth: BNEP socket layer initialized Mar 07 22:39:06 volumio systemd[1]: Starting LSB: Brings up/down network automatically... Mar 07 22:39:06 volumio bluetoothd[808]: Bluetooth management interface 1.22 initialized Mar 07 22:39:06 volumio dbus-daemon[782]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.3' (uid=0 pid=808 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Mar 07 22:39:06 volumio systemd[1]: Starting /etc/rc.local Compatibility... Mar 07 22:39:06 volumio kernel: Bluetooth: MGMT ver 1.22 Mar 07 22:39:06 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 07 22:39:06 volumio systemd[1]: Starting Samba NMB Daemon... Mar 07 22:39:06 volumio systemd[1]: Starting Permit User Sessions... Mar 07 22:39:06 volumio systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Mar 07 22:39:06 volumio systemd[1]: Starting Network Time Service... Mar 07 22:39:06 volumio systemd[1]: Started /etc/rc.local Compatibility. Mar 07 22:39:06 volumio volumio-remote-updater[801]: Error: No active session Mar 07 22:39:06 volumio dhcpcd[818]: no interfaces have a carrier Mar 07 22:39:06 volumio dhcpcd[818]: forked to background, child pid 956 Mar 07 22:39:06 volumio dhcpcd[818]: no interfaces have a carrier Mar 07 22:39:06 volumio dhcpcd[818]: forked to background, child pid 956 Mar 07 22:39:06 volumio kernel: RTL8226B_RTL8221B 2.5Gbps PHY r8169-0-200:00: attached PHY driver (mii_bus:phy_addr=r8169-0-200:00, irq=MAC) Mar 07 22:39:06 volumio volumio[807]: Could not open config: /tmp/upmpdcli.conf Mar 07 22:39:06 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 07 22:39:06 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 07 22:39:06 volumio ntpd[925]: ntpd 4.2.8p12@1.3728-o (1): Starting Mar 07 22:39:06 volumio ntpd[925]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Mar 07 22:39:06 volumio systemd[1]: Started DHCP Client Daemon. Mar 07 22:39:06 volumio systemd[1]: Started Permit User Sessions. Mar 07 22:39:06 volumio systemd[1]: Started Network Time Service. Mar 07 22:39:06 volumio systemd[1]: iptables.service: Succeeded. Mar 07 22:39:06 volumio systemd[1]: Starting Hostname Service... Mar 07 22:39:06 volumio sh[743]: eth0: waiting for carrier Mar 07 22:39:06 volumio sh[743]: eth0: carrier acquired Mar 07 22:39:06 volumio dhcpcd[797]: eth0: waiting for carrier Mar 07 22:39:06 volumio dhcpcd[797]: eth0: carrier acquired Mar 07 22:39:06 volumio systemd[1]: Starting Hold until boot process finishes up... Mar 07 22:39:06 volumio kernel: r8169 0000:02:00.0 eth0: Link is Down Mar 07 22:39:06 volumio systemd[1]: Starting Terminate Plymouth Boot Screen... Mar 07 22:39:06 volumio sh[743]: DUID 00:04:03:00:02:00:04:00:05:00:00:06:00:07:00:08:00:09 Mar 07 22:39:06 volumio sh[743]: eth0: IAID 6f:12:a9:a0 Mar 07 22:39:06 volumio dhcpcd[797]: DUID 00:04:03:00:02:00:04:00:05:00:00:06:00:07:00:08:00:09 Mar 07 22:39:06 volumio dhcpcd[797]: eth0: IAID 6f:12:a9:a0 Mar 07 22:39:06 volumio sh[743]: eth0: adding address fe80::30e8:9a90:704c:5be9 Mar 07 22:39:06 volumio dhcpcd[797]: eth0: adding address fe80::30e8:9a90:704c:5be9 Mar 07 22:39:06 volumio sh[743]: ipv6_addaddr1: Permission denied Mar 07 22:39:06 volumio sh[743]: eth0: carrier lost Mar 07 22:39:06 volumio dhcpcd[797]: ipv6_addaddr1: Permission denied Mar 07 22:39:06 volumio dhcpcd[797]: eth0: carrier lost Mar 07 22:39:06 volumio dhcpcd[956]: eth0: waiting for carrier Mar 07 22:39:06 volumio volumio-remote-updater[801]: [2025-03-07 14:39:06] [info] asio async_connect error: system:111 (Connection refused) Mar 07 22:39:06 volumio volumio-remote-updater[801]: [2025-03-07 14:39:06] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 07 22:39:06 volumio systemd[1]: Received SIGRTMIN+21 from PID 369 (plymouthd). Mar 07 22:39:06 volumio ntpd[968]: proto: precision = 0.093 usec (-23) Mar 07 22:39:06 volumio systemd[1]: plymouth-quit-wait.service: Succeeded. Mar 07 22:39:06 volumio systemd[1]: Started Hold until boot process finishes up. Mar 07 22:39:06 volumio systemd[1]: plymouth-quit.service: Succeeded. Mar 07 22:39:06 volumio systemd[1]: Started Terminate Plymouth Boot Screen. Mar 07 22:39:06 volumio systemd[1]: Received SIGRTMIN+21 from PID 369 (n/a). Mar 07 22:39:06 volumio ifplugd(eth0)[1001]: ifplugd 0.28 initializing. Mar 07 22:39:06 volumio volumio-remote-updater[801]: [2025-03-07 14:39:06] [error] handle_connect error: Underlying Transport Error Mar 07 22:39:06 volumio systemd[1]: Started Getty on tty1. Mar 07 22:39:06 volumio systemd[1]: Reached target Login Prompts. Mar 07 22:39:06 volumio ntpd[968]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Mar 07 22:39:06 volumio ntpd[968]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Mar 07 22:39:06 volumio ntpd[968]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 801 days ago Mar 07 22:39:06 volumio ntpd[968]: Listen and drop on 0 v6wildcard [::]:123 Mar 07 22:39:06 volumio ntpd[968]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Mar 07 22:39:06 volumio kernel: RTL8226B_RTL8221B 2.5Gbps PHY r8169-0-300:00: attached PHY driver (mii_bus:phy_addr=r8169-0-300:00, irq=MAC) Mar 07 22:39:06 volumio dbus-daemon[782]: [system] Successfully activated service 'org.freedesktop.hostname1' Mar 07 22:39:06 volumio systemd[1]: Started Hostname Service. Mar 07 22:39:06 volumio loadcpufreq[884]: Loading cpufreq kernel modules...done (acpi-cpufreq). Mar 07 22:39:06 volumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Mar 07 22:39:06 volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters... Mar 07 22:39:06 volumio cpufrequtils[1029]: CPUFreq Utilities: Setting ondemand CPUFreq governor...disabled, governor not available...done. Mar 07 22:39:06 volumio systemd[1]: Started LSB: set CPUFreq kernel parameters. Mar 07 22:39:06 volumio nmbd[927]: [2025/03/07 14:39:06.818066, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Mar 07 22:39:06 volumio nmbd[927]: started asyncdns process 1038 Mar 07 22:39:06 volumio dhcpcd[956]: eth1: waiting for carrier Mar 07 22:39:06 volumio dhcpcd[956]: wlan0: waiting for carrier Mar 07 22:39:06 volumio dhcpcd[956]: wlan0: carrier acquired Mar 07 22:39:06 volumio ntpd[968]: Listen normally on 2 lo 127.0.0.1:123 Mar 07 22:39:06 volumio ntpd[968]: Listening on routing socket on fd #19 for interface updates Mar 07 22:39:06 volumio nmbd[927]: [2025/03/07 14:39:06.830106, 0] ../lib/util/become_daemon.c:149(daemon_status) Mar 07 22:39:06 volumio ntpd[968]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 07 22:39:06 volumio nmbd[927]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Mar 07 22:39:06 volumio ntpd[968]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 07 22:39:06 volumio nmbd[927]: [2025/03/07 14:39:06.830141, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Mar 07 22:39:06 volumio nmbd[927]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Mar 07 22:39:06 volumio ifplugd(eth0)[1001]: Using interface eth0/B0:41:6F:12:A9:A0 with driver (version: 6.6.32-volumio) Mar 07 22:39:06 volumio kernel: r8169 0000:03:00.0 eth1: Link is Down Mar 07 22:39:06 volumio dhcpcd[956]: DUID 00:04:03:00:02:00:04:00:05:00:00:06:00:07:00:08:00:09 Mar 07 22:39:06 volumio dhcpcd[956]: wlan0: IAID 29:ed:a0:c6 Mar 07 22:39:06 volumio dhcpcd[956]: wlan0: adding address fe80::ded6:5fdf:f497:58d8 Mar 07 22:39:06 volumio ifplugd(eth0)[1001]: Using detection mode: SIOCETHTOOL Mar 07 22:39:06 volumio ifplugd(eth0)[1001]: Initialization complete, link beat not detected. Mar 07 22:39:06 volumio dhcpcd[956]: ipv6_addaddr1: Permission denied Mar 07 22:39:06 volumio dhcpcd[956]: wlan0: carrier lost Mar 07 22:39:06 volumio dhcpcd[956]: eth0: carrier acquired Mar 07 22:39:06 volumio dhcpcd[956]: eth0: IAID 6f:12:a9:a0 Mar 07 22:39:06 volumio dhcpcd[956]: eth0: adding address fe80::30e8:9a90:704c:5be9 Mar 07 22:39:06 volumio dhcpcd[956]: ipv6_addaddr1: Permission denied Mar 07 22:39:06 volumio dhcpcd[956]: eth0: carrier lost Mar 07 22:39:06 volumio dhcpcd[956]: eth1: carrier acquired Mar 07 22:39:06 volumio dhcpcd[956]: eth1: IAID 6f:12:a9:a1 Mar 07 22:39:06 volumio dhcpcd[956]: eth1: adding address fe80::5a90:5c4:f1c0:1941 Mar 07 22:39:06 volumio dhcpcd[956]: ipv6_addaddr1: Permission denied Mar 07 22:39:06 volumio wireless.js[819]: Cleaning previous... Mar 07 22:39:06 volumio sudo[1064]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 07 22:39:06 volumio sudo[1064]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:06 volumio sudo[1064]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:06 volumio sudo[1066]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 07 22:39:06 volumio sudo[1066]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:06 volumio dhcpcd[956]: eth1: soliciting a DHCP lease Mar 07 22:39:06 volumio sudo[1066]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:06 volumio ifplugd[916]: Network Interface Plugging Daemon...start eth0...done. Mar 07 22:39:06 volumio systemd[1]: Started LSB: Brings up/down network automatically. Mar 07 22:39:06 volumio wireless.js[819]: Stopped aP Mar 07 22:39:06 volumio sudo[1073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 07 22:39:06 volumio sudo[1073]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:07 volumio sudo[1073]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:07 volumio sudo[1075]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Mar 07 22:39:07 volumio sudo[1075]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:07 volumio sudo[1075]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:07 volumio sudo[1082]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 07 22:39:07 volumio sudo[1082]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:07 volumio sudo[1082]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:07 volumio sudo[1084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Mar 07 22:39:07 volumio sudo[1084]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:07 volumio dhcpcd[956]: eth1: soliciting an IPv6 router Mar 07 22:39:07 volumio ntpd[968]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Mar 07 22:39:07 volumio dhcpcd[956]: eth1: carrier lost Mar 07 22:39:08 volumio ntpd[968]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Mar 07 22:39:09 volumio sh[743]: eth0: carrier acquired Mar 07 22:39:09 volumio kernel: r8169 0000:02:00.0 eth0: Link is Up - 1Gbps/Full - flow control rx/tx Mar 07 22:39:09 volumio dhcpcd[797]: eth0: carrier acquired Mar 07 22:39:09 volumio dhcpcd[956]: eth0: carrier acquired Mar 07 22:39:09 volumio sh[743]: eth0: IAID 6f:12:a9:a0 Mar 07 22:39:09 volumio dhcpcd[956]: eth0: IAID 6f:12:a9:a0 Mar 07 22:39:09 volumio dhcpcd[797]: eth0: IAID 6f:12:a9:a0 Mar 07 22:39:09 volumio dhcpcd[956]: eth0: soliciting a DHCP lease Mar 07 22:39:09 volumio dhcpcd[956]: eth0: offered 192.168.110.214 from 192.168.110.1 Mar 07 22:39:09 volumio dhcpcd[956]: eth0: probing address 192.168.110.214/24 Mar 07 22:39:09 volumio sh[743]: eth0: soliciting an IPv6 router Mar 07 22:39:09 volumio dhcpcd[797]: eth0: soliciting an IPv6 router Mar 07 22:39:09 volumio ntpd[968]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Mar 07 22:39:09 volumio sh[743]: eth0: soliciting a DHCP lease Mar 07 22:39:09 volumio dhcpcd[797]: eth0: soliciting a DHCP lease Mar 07 22:39:09 volumio sh[743]: eth0: offered 192.168.110.214 from 192.168.110.1 Mar 07 22:39:09 volumio dhcpcd[797]: eth0: offered 192.168.110.214 from 192.168.110.1 Mar 07 22:39:09 volumio sh[743]: eth0: probing address 192.168.110.214/24 Mar 07 22:39:09 volumio dhcpcd[797]: eth0: probing address 192.168.110.214/24 Mar 07 22:39:09 volumio ifplugd(eth0)[1001]: Link beat detected. Mar 07 22:39:09 volumio dhcpcd[956]: eth0: soliciting an IPv6 router Mar 07 22:39:10 volumio ifplugd(eth0)[1001]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Mar 07 22:39:10 volumio ifplugd(eth0)[1001]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Mar 07 22:39:10 volumio sudo[1084]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:10 volumio wireless.js[819]: SETTING APPROPRIATE REG DOMAIN: CN Mar 07 22:39:10 volumio sudo[1110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 07 22:39:10 volumio sudo[1110]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:10 volumio sudo[1110]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:10 volumio sudo[1112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set CN Mar 07 22:39:10 volumio sudo[1112]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:10 volumio sudo[1112]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:10 volumio wireless.js[819]: SUCCESSFULLY SET NEW REGDOMAIN: CN Mar 07 22:39:10 volumio ntpd[968]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Mar 07 22:39:10 volumio sudo[1119]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 07 22:39:10 volumio sudo[1119]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:10 volumio sudo[1119]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:10 volumio sudo[1121]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 07 22:39:10 volumio sudo[1121]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:10 volumio sudo[1121]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:10 volumio avahi-daemon[813]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Mar 07 22:39:10 volumio avahi-daemon[813]: New relevant interface wlan0.IPv4 for mDNS. Mar 07 22:39:10 volumio avahi-daemon[813]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Mar 07 22:39:10 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Mar 07 22:39:10 volumio hostapd[1127]: Configuration file: /etc/hostapd/hostapd.conf Mar 07 22:39:10 volumio avahi-daemon[813]: Interface wlan0.IPv4 no longer relevant for mDNS. Mar 07 22:39:10 volumio avahi-daemon[813]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Mar 07 22:39:11 volumio avahi-daemon[813]: Withdrawing address record for 192.168.211.1 on wlan0. Mar 07 22:39:11 volumio avahi-daemon[813]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Mar 07 22:39:11 volumio avahi-daemon[813]: New relevant interface wlan0.IPv4 for mDNS. Mar 07 22:39:11 volumio avahi-daemon[813]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Mar 07 22:39:11 volumio hostapd[1127]: Using interface wlan0 with hwaddr e8:c8:29:ed:a0:c6 and ssid "Volumio" Mar 07 22:39:11 volumio dhcpcd[956]: wlan0: carrier acquired Mar 07 22:39:11 volumio dhcpcd[956]: wlan0: IAID 29:ed:a0:c6 Mar 07 22:39:11 volumio hostapd[1127]: wlan0: interface state UNINITIALIZED->ENABLED Mar 07 22:39:11 volumio hostapd[1127]: wlan0: AP-ENABLED Mar 07 22:39:11 volumio systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Mar 07 22:39:11 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Mar 07 22:39:11 volumio iwconfig[1147]: Error for wireless request "Set Power Management" (8B2C) : Mar 07 22:39:11 volumio iwconfig[1147]: SET failed on device wlan0 ; Invalid argument. Mar 07 22:39:11 volumio systemd[1]: wireless.service: Succeeded. Mar 07 22:39:11 volumio systemd[1]: Started Wireless Services. Mar 07 22:39:11 volumio systemd[1]: Started Volumio Backend Module. Mar 07 22:39:11 volumio systemd[1]: Started First Start. Mar 07 22:39:11 volumio dnsmasq[1145]: dnsmasq: syntax check OK. Mar 07 22:39:11 volumio systemd[1]: Started Intel SST and HDA soundcard init service. Mar 07 22:39:11 volumio systemd[1]: Started Start Volumio Kiosk. Mar 07 22:39:11 volumio systemd[1]: Started Volumio Cpu Tweaker. Mar 07 22:39:11 volumio firststart.sh[1149]: Volumio first start configuration script Mar 07 22:39:11 volumio firststart.sh[1149]: Configuring unconfigured packages Mar 07 22:39:11 volumio volumio-cpu-tweak[1152]: Setting RT Priority for mpd Mar 07 22:39:11 volumio volumio-cpu-tweak[1152]: pid 35's current scheduling policy: SCHED_FIFO Mar 07 22:39:11 volumio volumio-cpu-tweak[1152]: pid 35's current scheduling priority: 99 Mar 07 22:39:11 volumio volumio-cpu-tweak[1152]: Setting MPD Affinity Mar 07 22:39:11 volumio volumio-cpu-tweak[1152]: pid 3's current affinity mask: f Mar 07 22:39:11 volumio volumio-cpu-tweak[1152]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Mar 07 22:39:11 volumio dhcpcd[956]: wlan0: soliciting a DHCP lease Mar 07 22:39:11 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Mar 07 22:39:11 volumio startx[1151]: xauth: file /root/.Xauthority does not exist Mar 07 22:39:11 volumio dnsmasq[1209]: started, version 2.80 cachesize 150 Mar 07 22:39:11 volumio dnsmasq[1209]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Mar 07 22:39:11 volumio dnsmasq-dhcp[1209]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Mar 07 22:39:11 volumio dnsmasq[1209]: reading /etc/resolv.conf Mar 07 22:39:11 volumio dnsmasq[1209]: using nameserver 208.67.222.222#53 Mar 07 22:39:11 volumio dnsmasq[1209]: using nameserver 208.67.220.220#53 Mar 07 22:39:11 volumio dnsmasq[1209]: read /etc/hosts - 5 addresses Mar 07 22:39:11 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Mar 07 22:39:11 volumio systemd[1]: Reached target Host and Network Name Lookups. Mar 07 22:39:11 volumio firststart.sh[1149]: Creating /var/log/samba/cores folder Mar 07 22:39:11 volumio firststart.sh[1149]: Removing default SSH host keys Mar 07 22:39:11 volumio firststart.sh[1149]: removed '/etc/ssh/ssh_host_ecdsa_key' Mar 07 22:39:11 volumio firststart.sh[1149]: removed '/etc/ssh/ssh_host_ecdsa_key.pub' Mar 07 22:39:11 volumio firststart.sh[1149]: removed '/etc/ssh/ssh_host_ed25519_key' Mar 07 22:39:11 volumio firststart.sh[1149]: removed '/etc/ssh/ssh_host_ed25519_key.pub' Mar 07 22:39:11 volumio firststart.sh[1149]: removed '/etc/ssh/ssh_host_rsa_key' Mar 07 22:39:11 volumio firststart.sh[1149]: removed '/etc/ssh/ssh_host_rsa_key.pub' Mar 07 22:39:11 volumio firststart.sh[1149]: Generating SSH host keys Mar 07 22:39:11 volumio dhcpcd[956]: wlan0: soliciting an IPv6 router Mar 07 22:39:11 volumio startx[1151]: X.Org X Server 1.20.4 Mar 07 22:39:11 volumio startx[1151]: X Protocol Version 11, Revision 0 Mar 07 22:39:11 volumio startx[1151]: Build Operating System: Linux 5.10.0-10-amd64 x86_64 Debian Mar 07 22:39:11 volumio startx[1151]: Current Operating System: Linux volumio 6.6.32-volumio #2 SMP PREEMPT_DYNAMIC Wed May 29 23:04:42 CEST 2024 x86_64 Mar 07 22:39:11 volumio startx[1151]: Kernel command line: BOOT_IMAGE=/vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=d0822069-d48a-490f-b8bc-9970c0b6e384 bootpart=UUID=1496-1547 datapart=UUID=a0bf3376-7b72-4c09-9fc2-d0970ff7651c hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor net.ifnames=0 use_kmsg=no Mar 07 22:39:11 volumio startx[1151]: Build Date: 18 December 2021 09:05:36AM Mar 07 22:39:11 volumio startx[1151]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support) Mar 07 22:39:11 volumio startx[1151]: Current version of pixman: 0.36.0 Mar 07 22:39:11 volumio startx[1151]: Before reporting problems, check http://wiki.x.org Mar 07 22:39:11 volumio startx[1151]: to make sure that you have the latest version. Mar 07 22:39:11 volumio startx[1151]: Markers: (--) probed, (**) from config file, (==) default setting, Mar 07 22:39:11 volumio startx[1151]: (++) from command line, (!!) notice, (II) informational, Mar 07 22:39:11 volumio startx[1151]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Mar 07 22:39:11 volumio startx[1151]: (==) Log file: "/var/log/Xorg.0.log", Time: Fri Mar 7 14:39:11 2025 Mar 07 22:39:11 volumio startx[1151]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Mar 07 22:39:11 volumio systemd[1]: soundcard-init.service: Succeeded. Mar 07 22:39:11 volumio volumio-remote-updater[801]: [2025-03-07 14:39:11] [info] asio async_connect error: system:111 (Connection refused) Mar 07 22:39:11 volumio volumio-remote-updater[801]: [2025-03-07 14:39:11] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 07 22:39:11 volumio volumio-remote-updater[801]: [2025-03-07 14:39:11] [error] handle_connect error: Underlying Transport Error Mar 07 22:39:11 volumio startx[1151]: pci id for fd 14: 8086:46d1, driver (null) Mar 07 22:39:11 volumio nmbd[927]: [2025/03/07 14:39:11.834570, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 07 22:39:11 volumio nmbd[927]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Mar 07 22:39:11 volumio systemd[1]: Started Samba NMB Daemon. Mar 07 22:39:11 volumio systemd[1]: Starting Samba Winbind Daemon... Mar 07 22:39:12 volumio winbindd[1296]: [2025/03/07 14:39:12.082388, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Mar 07 22:39:12 volumio winbindd[1296]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Mar 07 22:39:12 volumio winbindd[1296]: [2025/03/07 14:39:12.208796, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 07 22:39:12 volumio systemd[1]: Started Samba Winbind Daemon. Mar 07 22:39:12 volumio winbindd[1296]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Mar 07 22:39:12 volumio systemd[1]: Starting Samba SMB Daemon... Mar 07 22:39:12 volumio kernel: broken atomic modeset userspace detected, disabling atomic Mar 07 22:39:12 volumio firststart.sh[1149]: debconf: unable to initialize frontend: Dialog Mar 07 22:39:12 volumio firststart.sh[1149]: debconf: (TERM is not set, so the dialog frontend is not usable.) Mar 07 22:39:12 volumio firststart.sh[1149]: debconf: falling back to frontend: Readline Mar 07 22:39:12 volumio firststart.sh[1149]: debconf: unable to initialize frontend: Readline Mar 07 22:39:12 volumio firststart.sh[1149]: debconf: (This frontend requires a controlling tty.) Mar 07 22:39:12 volumio firststart.sh[1149]: debconf: falling back to frontend: Teletype Mar 07 22:39:12 volumio startx[1151]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. Mar 07 22:39:12 volumio ntpd[968]: Listen normally on 3 wlan0 192.168.211.1:123 Mar 07 22:39:12 volumio ntpd[968]: new interface(s) found: waking up resolver Mar 07 22:39:12 volumio smbd[1305]: [2025/03/07 14:39:12.819065, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 07 22:39:12 volumio systemd[1]: Started Samba SMB Daemon. Mar 07 22:39:12 volumio smbd[1305]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Mar 07 22:39:12 volumio systemd[1]: Reached target Multi-User System. Mar 07 22:39:12 volumio systemd[1]: Reached target Graphical Interface. Mar 07 22:39:12 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 07 22:39:12 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 07 22:39:12 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 07 22:39:12 volumio systemd[1]: Startup finished in 9.747s (firmware) + 2.376s (loader) + 45.791s (kernel) + 21.855s (userspace) = 1min 19.771s. Mar 07 22:39:12 volumio firststart.sh[1149]: Creating SSH2 RSA key; this may take some time ... Mar 07 22:39:12 volumio firststart.sh[1149]: 2048 SHA256:UPSbcBtjh6vYYvUQYw+9EBUJYE3j50cRmlP/1ZaZb24 root@volumio (RSA) Mar 07 22:39:13 volumio volumio[1148]: info: ------------------------------------------- Mar 07 22:39:13 volumio volumio[1148]: info: ----- Volumio3 ---- Mar 07 22:39:13 volumio volumio[1148]: info: ------------------------------------------- Mar 07 22:39:13 volumio volumio[1148]: info: ----- System startup ---- Mar 07 22:39:13 volumio volumio[1148]: info: ------------------------------------------- Mar 07 22:39:13 volumio firststart.sh[1149]: Creating SSH2 DSA key; this may take some time ... Mar 07 22:39:13 volumio firststart.sh[1149]: 1024 SHA256:bHPbf9rIvrEvdG+r1f8Z+PCxRBqfKkn8Pnxq/Pe9gNA root@volumio (DSA) Mar 07 22:39:13 volumio firststart.sh[1149]: Creating SSH2 ECDSA key; this may take some time ... Mar 07 22:39:13 volumio firststart.sh[1149]: 256 SHA256:02eMPpaOlL8nB9EGpGEnVNmkWMk4oqHQtM3XWWUVicY root@volumio (ECDSA) Mar 07 22:39:13 volumio volumio[1148]: info: File /data/configuration/plugins.json does not exist. Copying from Volumio Mar 07 22:39:13 volumio volumio[1148]: info: MYVOLUMIO Environment detected Mar 07 22:39:13 volumio volumio[1148]: info: Plugin folders cleanup Mar 07 22:39:13 volumio volumio[1148]: info: Scanning into folder /volumio/app/plugins/ Mar 07 22:39:13 volumio volumio[1148]: info: Scanning category audio_interface Mar 07 22:39:13 volumio volumio[1148]: info: Scanning category miscellanea Mar 07 22:39:13 volumio volumio[1148]: info: Scanning category music_service Mar 07 22:39:13 volumio volumio[1148]: info: Scanning category plugins.json Mar 07 22:39:13 volumio volumio[1148]: info: Scanning category system_controller Mar 07 22:39:13 volumio volumio[1148]: info: Scanning category user_interface Mar 07 22:39:13 volumio volumio[1148]: info: Scanning into folder /data/plugins/ Mar 07 22:39:13 volumio volumio[1148]: info: Plugin folders cleanup completed Mar 07 22:39:13 volumio volumio[1148]: info: ------------------------------------------- Mar 07 22:39:13 volumio volumio[1148]: info: ----- Core plugins startup ---- Mar 07 22:39:13 volumio volumio[1148]: info: ------------------------------------------- Mar 07 22:39:13 volumio volumio[1148]: info: Loading plugins from folder /volumio/app/plugins/ Mar 07 22:39:13 volumio volumio[1148]: info: Adding plugin upnp to MyMusic Plugins Mar 07 22:39:13 volumio volumio[1148]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 07 22:39:13 volumio volumio[1148]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 07 22:39:13 volumio volumio[1148]: info: Loading plugins from folder /data/plugins/ Mar 07 22:39:13 volumio volumio[1148]: info: Loading plugin "system"... Mar 07 22:39:13 volumio volumio[1148]: info: No id defined. Creating one Mar 07 22:39:13 volumio volumio[1148]: info: Loading plugin "appearance"... Mar 07 22:39:13 volumio systemd[1]: Reloading. Mar 07 22:39:13 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Mar 07 22:39:13 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Mar 07 22:39:13 volumio systemd[1]: ifup@eth0.service: Current command vanished from the unit file, execution of the command list won't be resumed. Mar 07 22:39:13 volumio firststart.sh[1149]: rescue-ssh.target is a disabled or a static unit, not starting it. Mar 07 22:39:13 volumio firststart.sh[1149]: insserv: warning: current start runlevel(s) (empty) of script `ssh' overrides LSB defaults (2 3 4 5). Mar 07 22:39:13 volumio firststart.sh[1149]: insserv: warning: current stop runlevel(s) (2 3 4 5) of script `ssh' overrides LSB defaults (empty). Mar 07 22:39:13 volumio systemd[1]: Reloading. Mar 07 22:39:13 volumio volumio[1148]: info: Loading plugin "network"... Mar 07 22:39:13 volumio volumio[1148]: info: Refreshing Cached IP Addresses Mar 07 22:39:13 volumio sudo[1531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 07 22:39:13 volumio sudo[1531]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:13 volumio sudo[1531]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:13 volumio sudo[1533]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 07 22:39:13 volumio sudo[1533]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:13 volumio volumio[1148]: info: Loading plugin "services"... Mar 07 22:39:13 volumio sudo[1533]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:13 volumio volumio[1148]: info: Loading plugin "alsa_controller"... Mar 07 22:39:13 volumio sudo[1536]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 07 22:39:13 volumio sudo[1536]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:13 volumio sudo[1536]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:13 volumio volumio[1148]: warn: The ALSA output card is not set, defaulting to card [object Object] Mar 07 22:39:13 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 07 22:39:13 volumio volumio[1148]: info: Found match in Cards Database: setting mixer for card Analog Out Mar 07 22:39:13 volumio volumio[1148]: info: Setting mixer Master for card Analog Out Mar 07 22:39:13 volumio volumio[1148]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 07 22:39:13 volumio volumio[1148]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 07 22:39:13 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Mar 07 22:39:13 volumio volumio[1148]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 07 22:39:13 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 07 22:39:13 volumio volumio[1148]: info: Loading plugin "wizard"... Mar 07 22:39:13 volumio volumio[1148]: info: Loading plugin "networkfs"... Mar 07 22:39:13 volumio volumio[1148]: info: Starting Udev Watcher for removable devices Mar 07 22:39:13 volumio volumio[1148]: info: Ignoring mount for partition: boot Mar 07 22:39:13 volumio volumio[1148]: info: Ignoring mount for partition: volumio Mar 07 22:39:13 volumio volumio[1148]: info: Ignoring mount for partition: volumio_data Mar 07 22:39:13 volumio volumio[1148]: info: Ignoring mount for partition: e2fffc98-8fa0-9e1a-05d9-49f7b0bbaec7 Mar 07 22:39:13 volumio volumio[1148]: info: Ignoring mount for partition: c7fc8546-2111-68fa-05d9-49f7b0bbaec7 Mar 07 22:39:13 volumio volumio[1148]: error: Cannot associate FS Label, not mounting Mar 07 22:39:13 volumio volumio[1148]: info: Ignoring mount for partition: SA6400:2 Mar 07 22:39:13 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 07 22:39:13 volumio volumio[1148]: info: Loading plugin "volumio_command_line_client"... Mar 07 22:39:13 volumio volumio[1148]: info: Loading plugin "upnp"... Mar 07 22:39:13 volumio volumio[1148]: info: [1741358353899] Starting Upmpd Daemon Mar 07 22:39:13 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 07 22:39:13 volumio volumio[1148]: info: Loading plugin "my_music"... Mar 07 22:39:13 volumio volumio[1148]: info: Loading plugin "mpd"... Mar 07 22:39:13 volumio nmbd[927]: [2025/03/07 14:39:13.957224, 0] ../source3/libsmb/nmblib.c:917(send_udp) Mar 07 22:39:13 volumio nmbd[927]: Packet send failed to 192.168.110.179(41037) ERRNO=Network is unreachable Mar 07 22:39:13 volumio nmbd[927]: [2025/03/07 14:39:13.957298, 0] ../source3/nmbd/nmbd_packets.c:1027(reply_netbios_packet) Mar 07 22:39:13 volumio nmbd[927]: reply_netbios_packet: send_packet to IP 192.168.110.179 port 41037 failed Mar 07 22:39:13 volumio volumio[1148]: info: Loading plugin "upnp_browser"... Mar 07 22:39:13 volumio nmbd[927]: [2025/03/07 14:39:13.987237, 0] ../source3/libsmb/nmblib.c:917(send_udp) Mar 07 22:39:13 volumio nmbd[927]: Packet send failed to 192.168.110.179(37018) ERRNO=Network is unreachable Mar 07 22:39:13 volumio nmbd[927]: [2025/03/07 14:39:13.987284, 0] ../source3/nmbd/nmbd_packets.c:1027(reply_netbios_packet) Mar 07 22:39:13 volumio nmbd[927]: reply_netbios_packet: send_packet to IP 192.168.110.179 port 37018 failed Mar 07 22:39:13 volumio firststart.sh[1149]: Enabling SSH for first boot Mar 07 22:39:14 volumio systemd[1]: Starting OpenBSD Secure Shell server... Mar 07 22:39:14 volumio sshd[1584]: Server listening on 0.0.0.0 port 22. Mar 07 22:39:14 volumio sshd[1584]: Server listening on :: port 22. Mar 07 22:39:14 volumio systemd[1]: Started OpenBSD Secure Shell server. Mar 07 22:39:14 volumio firststart.sh[1149]: Disabling firststart service Mar 07 22:39:14 volumio firststart.sh[1149]: Removed /etc/systemd/system/multi-user.target.wants/firststart.service. Mar 07 22:39:14 volumio systemd[1]: Reloading. Mar 07 22:39:14 volumio volumio[1148]: info: Loading plugin "alarm-clock"... Mar 07 22:39:14 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Mar 07 22:39:14 volumio dhcpcd[956]: eth0: leased 192.168.110.214 for 1800 seconds Mar 07 22:39:14 volumio dhcpcd[956]: eth0: adding route to 192.168.110.0/24 Mar 07 22:39:14 volumio dhcpcd[956]: eth0: adding default route via 192.168.110.1 Mar 07 22:39:14 volumio avahi-daemon[813]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.110.214. Mar 07 22:39:14 volumio avahi-daemon[813]: New relevant interface eth0.IPv4 for mDNS. Mar 07 22:39:14 volumio avahi-daemon[813]: Registering new address record for 192.168.110.214 on eth0.IPv4. Mar 07 22:39:14 volumio volumio[1148]: info: Loading plugin "airplay_emulation"... Mar 07 22:39:14 volumio dnsmasq[1209]: reading /etc/resolv.conf Mar 07 22:39:14 volumio dnsmasq[1209]: using nameserver 192.168.110.1#53 Mar 07 22:39:14 volumio dnsmasq[1209]: using nameserver 208.67.222.222#53 Mar 07 22:39:14 volumio dnsmasq[1209]: using nameserver 208.67.220.220#53 Mar 07 22:39:14 volumio volumio[1148]: info: Starting Shairport Sync Mar 07 22:39:14 volumio volumio[1148]: info: Loading plugin "last_100"... Mar 07 22:39:14 volumio volumio[1148]: info: Loading plugin "webradio"... Mar 07 22:39:14 volumio volumio[1148]: info: Loading plugin "i2s_dacs"... Mar 07 22:39:14 volumio volumio[1148]: info: I2S DAC not set, start Auto-detection Mar 07 22:39:14 volumio volumio[1148]: info: Loading plugin "volumiodiscovery"... Mar 07 22:39:14 volumio volumio[1148]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 07 22:39:14 volumio volumio[1148]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 07 22:39:14 volumio volumio[1148]: *** WARNING *** For more information see Mar 07 22:39:14 volumio volumio[1148]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 07 22:39:14 volumio volumio[1148]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 07 22:39:14 volumio volumio[1148]: *** WARNING *** For more information see Mar 07 22:39:14 volumio node[1148]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 07 22:39:14 volumio node[1148]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 07 22:39:14 volumio node[1148]: *** WARNING *** For more information see Mar 07 22:39:14 volumio node[1148]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 07 22:39:14 volumio node[1148]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 07 22:39:14 volumio node[1148]: *** WARNING *** For more information see Mar 07 22:39:14 volumio firststart.sh[1149]: Finalizing Mar 07 22:39:14 volumio volumio[1148]: info: Discovery: Started advertising with name: Volumio Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 07 22:39:14 volumio volumio[1148]: info: Loading plugin "outputs"... Mar 07 22:39:14 volumio volumio[1148]: info: Loading plugin "albumart"... Mar 07 22:39:14 volumio volumio[1148]: info: Plugin example_plugin is not enabled Mar 07 22:39:14 volumio volumio[1148]: info: Loading plugin "inputs"... Mar 07 22:39:14 volumio volumio[1148]: info: Loading plugin "updater_comm"... Mar 07 22:39:14 volumio systemd[1]: Stopping Network Time Service... Mar 07 22:39:14 volumio ntpd[968]: ntpd exiting on signal 15 (Terminated) Mar 07 22:39:14 volumio systemd[1]: ntp.service: Succeeded. Mar 07 22:39:14 volumio systemd[1]: Stopped Network Time Service. Mar 07 22:39:14 volumio systemd[1]: Starting Network Time Service... Mar 07 22:39:14 volumio ntpd[1642]: ntpd 4.2.8p12@1.3728-o (1): Starting Mar 07 22:39:14 volumio ntpd[1642]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Mar 07 22:39:14 volumio systemd[1]: Started Network Time Service. Mar 07 22:39:14 volumio ntpd[1648]: proto: precision = 0.044 usec (-24) Mar 07 22:39:14 volumio ntpd[1648]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Mar 07 22:39:14 volumio ntpd[1648]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Mar 07 22:39:14 volumio ntpd[1648]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 801 days ago Mar 07 22:39:14 volumio ntpd[1648]: Listen and drop on 0 v6wildcard [::]:123 Mar 07 22:39:14 volumio ntpd[1648]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Mar 07 22:39:14 volumio ntpd[1648]: Listen normally on 2 lo 127.0.0.1:123 Mar 07 22:39:14 volumio ntpd[1648]: Listen normally on 3 eth0 192.168.110.214:123 Mar 07 22:39:14 volumio ntpd[1648]: Listen normally on 4 wlan0 192.168.211.1:123 Mar 07 22:39:14 volumio ntpd[1648]: Listening on routing socket on fd #21 for interface updates Mar 07 22:39:14 volumio ntpd[1648]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 07 22:39:14 volumio ntpd[1648]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 07 22:39:14 volumio volumio[1148]: Forking 3 albumart workers Mar 07 22:39:14 volumio volumio[1148]: info: Plugin mpdemulation is not enabled Mar 07 22:39:14 volumio volumio[1148]: info: Loading plugin "rest_api"... Mar 07 22:39:14 volumio volumio[1148]: info: Loading plugin "websocket"... Mar 07 22:39:14 volumio volumio[1148]: info: Starting Socket.io Server version 2.3.0 Mar 07 22:39:14 volumio volumio[1148]: info: Loading i18n strings for locale en Mar 07 22:39:14 volumio volumio[1148]: Updating browse sources language Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 07 22:39:14 volumio volumio[1148]: Starting albumart workers Mar 07 22:39:14 volumio volumio[1148]: Starting albumart workers Mar 07 22:39:14 volumio volumio[1148]: Starting albumart workers Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::initPlayerControls Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 07 22:39:14 volumio volumio[1148]: Express server listening on port 3000 Mar 07 22:39:14 volumio volumio[1148]: [Metrics] WebUI: 1s 602.63ms Mar 07 22:39:14 volumio volumio[1148]: info: Setting Device type: x86 Mar 07 22:39:14 volumio volumio[1148]: info: CoreStateMachine::resetVolumioState Mar 07 22:39:14 volumio volumio[1148]: info: CoreStateMachine::getcurrentVolume Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::volumioRetrievevolume Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 22:39:14 volumio volumio[1148]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Mar 07 22:39:14 volumio volumio[1148]: info: Completed loading Core Plugins Mar 07 22:39:14 volumio volumio[1148]: info: Preparing to generate the ALSA configuration file Mar 07 22:39:14 volumio volumio[1148]: info: Cannot read play queue from file Mar 07 22:39:14 volumio volumio[1148]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Mar 07 22:39:14 volumio volumio[1148]: wlan0 Interface doesn't support scanning : Operation not supported Mar 07 22:39:14 volumio volumio[1148]: info: Cannot use regular scanning, forcing with ap-force Mar 07 22:39:14 volumio sudo[1697]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Mar 07 22:39:14 volumio sudo[1697]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:14 volumio sudo[1697]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:14 volumio volumio[1148]: command failed: Operation not supported (-95) Mar 07 22:39:14 volumio volumio[1148]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Mar 07 22:39:14 volumio volumio[1148]: command failed: Operation not supported (-95) Mar 07 22:39:14 volumio volumio[1148]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Mar 07 22:39:14 volumio volumio[1148]: info: Asound.conf file written Mar 07 22:39:14 volumio sudo[1705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 07 22:39:14 volumio sudo[1705]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:14 volumio sudo[1705]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:14 volumio volumio[1148]: No state is present for card PCH Mar 07 22:39:14 volumio volumio[1148]: Found hardware: "HDA-Intel" "Realtek ALC897" "HDA:10ec0897,10ec1268,00100402 HDA:8086281c,80860101,00100000" "0x8086" "0x7270" Mar 07 22:39:14 volumio volumio[1148]: Hardware is initialized using a generic method Mar 07 22:39:14 volumio volumio[1148]: No state is present for card PCH Mar 07 22:39:14 volumio volumio[1148]: info: Output device has changed, restarting MPD Mar 07 22:39:14 volumio systemd[1]: firststart.service: Succeeded. Mar 07 22:39:14 volumio sudo[1710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 07 22:39:14 volumio volumio[1148]: info: Output device has changed, restarting Shairport Sync Mar 07 22:39:14 volumio sudo[1710]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 22:39:14 volumio sudo[1712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 07 22:39:14 volumio sudo[1712]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:14 volumio volumio[1148]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 07 22:39:14 volumio volumio[1148]: info: ___________ START PLUGINS ___________ Mar 07 22:39:14 volumio systemd[1]: Listening on mpd.socket. Mar 07 22:39:14 volumio volumio[1148]: info: Setting Hotspot Unique name on first start: Volumio-D210C Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: network , getConfigParam Mar 07 22:39:14 volumio volumio[1148]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getConfigParam] in plugin network Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::Reload Ui Mar 07 22:39:14 volumio volumio[1148]: info: ControllerMpd::onStart: Initializing MPD Mar 07 22:39:14 volumio volumio[1148]: info: Creating MPD Configuration file Mar 07 22:39:14 volumio sudo[1710]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:14 volumio sudo[1720]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 07 22:39:14 volumio volumio[1148]: info: [1741358354539] CoreMusicLibrary::Adding element Media Servers Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 22:39:14 volumio systemd[1]: Starting Music Player Daemon... Mar 07 22:39:14 volumio sudo[1720]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:14 volumio sudo[1720]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:14 volumio sudo[1722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 07 22:39:14 volumio sudo[1722]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:14 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Mar 07 22:39:14 volumio systemd[1]: mpd.service: Succeeded. Mar 07 22:39:14 volumio systemd[1]: Stopped Music Player Daemon. Mar 07 22:39:14 volumio systemd[1]: Starting Music Player Daemon... Mar 07 22:39:14 volumio volumio[1148]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 22:39:14 volumio volumio[1148]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 07 22:39:14 volumio volumio[1148]: info: [1741358354562] CoreMusicLibrary::Adding element Last_100 Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 07 22:39:14 volumio volumio[1148]: info: [1741358354563] CoreMusicLibrary::Adding element Webradio Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 07 22:39:14 volumio volumio[1148]: info: Initializing BBC Radios Mar 07 22:39:14 volumio sudo[1728]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 07 22:39:14 volumio sudo[1728]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:14 volumio sudo[1728]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Mar 07 22:39:14 volumio sudo[1728]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 22:39:14 volumio volumio[1148]: info: Volumio Calling Home Mar 07 22:39:14 volumio volumio[1148]: info: VolumeController:: Volume=41 Mute =false Mar 07 22:39:14 volumio volumio[1148]: info: CoreStateMachine::pushState Mar 07 22:39:14 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Mar 07 22:39:14 volumio volumio[1148]: info: CoreStateMachine::updateTrackBlock Mar 07 22:39:14 volumio volumio[1148]: info: CorePlayQueue::getTrackBlock Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::volumioRetrievevolume Mar 07 22:39:14 volumio volumio[1148]: info: MPD Permissions set Mar 07 22:39:14 volumio volumio[1148]: info: MPD Permissions set Mar 07 22:39:14 volumio volumio[1148]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 22:39:14 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Mar 07 22:39:14 volumio volumio[1148]: info: VolumeController:: Volume=41 Mute =false Mar 07 22:39:14 volumio volumio[1148]: info: CoreStateMachine::pushState Mar 07 22:39:14 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Mar 07 22:39:14 volumio volumio[1148]: info: Starting Shairport Sync Mar 07 22:39:14 volumio volumio[1148]: info: Starting Shairport Sync Mar 07 22:39:14 volumio volumio[1148]: info: Starting Shairport Sync Mar 07 22:39:14 volumio sh[743]: eth0: leased 192.168.110.214 for 1800 seconds Mar 07 22:39:14 volumio dhcpcd[797]: eth0: leased 192.168.110.214 for 1800 seconds Mar 07 22:39:14 volumio sudo[1766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 07 22:39:14 volumio sudo[1766]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:14 volumio sudo[1764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 07 22:39:14 volumio sudo[1764]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:14 volumio sudo[1770]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 07 22:39:14 volumio sudo[1770]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:14 volumio sh[743]: eth0: adding route to 192.168.110.0/24 Mar 07 22:39:14 volumio sh[743]: eth0: adding default route via 192.168.110.1 Mar 07 22:39:14 volumio dhcpcd[797]: eth0: adding route to 192.168.110.0/24 Mar 07 22:39:14 volumio dhcpcd[797]: eth0: adding default route via 192.168.110.1 Mar 07 22:39:14 volumio volumio[1148]: info: CoreCommandRouter::volumioGetState Mar 07 22:39:14 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Mar 07 22:39:14 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 07 22:39:14 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 07 22:39:14 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 07 22:39:14 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 07 22:39:14 volumio sudo[1764]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:14 volumio sudo[1766]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:14 volumio sudo[1770]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:14 volumio volumio[1148]: info: Shairport-Sync Started Mar 07 22:39:14 volumio volumio[1148]: Error adding Membership: Error: addMembership EINVAL Mar 07 22:39:14 volumio volumio[1148]: info: Shairport-Sync Started Mar 07 22:39:14 volumio volumio[1148]: info: Shairport-Sync Started Mar 07 22:39:14 volumio ntpd[1648]: ntpd exiting on signal 15 (Terminated) Mar 07 22:39:14 volumio systemd[1]: Stopping Network Time Service... Mar 07 22:39:14 volumio systemd[1]: ntp.service: Succeeded. Mar 07 22:39:14 volumio systemd[1]: Stopped Network Time Service. Mar 07 22:39:14 volumio systemd[1]: Starting Network Time Service... Mar 07 22:39:14 volumio ntpd[1807]: ntpd 4.2.8p12@1.3728-o (1): Starting Mar 07 22:39:14 volumio ntpd[1807]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Mar 07 22:39:14 volumio systemd[1]: Started Network Time Service. Mar 07 22:39:14 volumio ntpd[1813]: proto: precision = 0.075 usec (-24) Mar 07 22:39:14 volumio ntpd[1813]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Mar 07 22:39:14 volumio ntpd[1813]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Mar 07 22:39:14 volumio ntpd[1813]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 801 days ago Mar 07 22:39:14 volumio ntpd[1813]: Listen and drop on 0 v6wildcard [::]:123 Mar 07 22:39:14 volumio ntpd[1813]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Mar 07 22:39:14 volumio ntpd[1813]: Listen normally on 2 lo 127.0.0.1:123 Mar 07 22:39:14 volumio ntpd[1813]: Listen normally on 3 eth0 192.168.110.214:123 Mar 07 22:39:14 volumio ntpd[1813]: Listen normally on 4 wlan0 192.168.211.1:123 Mar 07 22:39:14 volumio ntpd[1813]: Listening on routing socket on fd #21 for interface updates Mar 07 22:39:14 volumio ntpd[1813]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 07 22:39:14 volumio ntpd[1813]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 07 22:39:14 volumio sh[743]: forked to background, child pid 1815 Mar 07 22:39:14 volumio dhcpcd[797]: forked to background, child pid 1815 Mar 07 22:39:14 volumio ifplugd(eth0)[1001]: client: ifup: interface eth0 already configured Mar 07 22:39:14 volumio sh[743]: eth0=eth0 Mar 07 22:39:15 volumio ifplugd(eth0)[1001]: Program executed successfully. Mar 07 22:39:15 volumio volumio[1148]: info: Discovery: adding 3ab38dce-390d-4c97-aabf-dd6a6551c51d Mar 07 22:39:15 volumio volumio[1148]: info: Discovery: Found device Volumio Mar 07 22:39:15 volumio volumio[1148]: info: CoreCommandRouter::volumioGetState Mar 07 22:39:15 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Mar 07 22:39:15 volumio volumio[1148]: info: Discovery: this is already registered, 3ab38dce-390d-4c97-aabf-dd6a6551c51d Mar 07 22:39:15 volumio volumio[1148]: info: Discovery: Found device Volumio Mar 07 22:39:15 volumio volumio[1148]: info: CoreCommandRouter::volumioGetState Mar 07 22:39:15 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Mar 07 22:39:15 volumio mpd[1739]: Mar 07 14:39 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 07 22:39:15 volumio mpd[1739]: Mar 07 14:39 : exception: Database corrupted Mar 07 22:39:15 volumio systemd[1]: Started Music Player Daemon. Mar 07 22:39:15 volumio sudo[1712]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:15 volumio sudo[1722]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:15 volumio volumio[1148]: info: Completed starting Core Plugins Mar 07 22:39:15 volumio volumio[1148]: info: ------------------------------------------- Mar 07 22:39:15 volumio volumio[1148]: info: ----- MyVolumio plugins startup ---- Mar 07 22:39:15 volumio volumio[1148]: info: ------------------------------------------- Mar 07 22:39:15 volumio volumio[1148]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 07 22:39:15 volumio volumio[1148]: info: MPD running with PID1739 Mar 07 22:39:15 volumio volumio[1148]: ,establishing connection Mar 07 22:39:15 volumio volumio[1148]: error: MPD error: The expression evaluated to a falsy value: Mar 07 22:39:15 volumio volumio[1148]: assert.ok(self.idling) Mar 07 22:39:15 volumio volumio[1148]: error: The expression evaluated to a falsy value: Mar 07 22:39:15 volumio volumio[1148]: assert.ok(self.idling) Mar 07 22:39:15 volumio volumio[1148]: error: MPD error: The expression evaluated to a falsy value: Mar 07 22:39:15 volumio volumio[1148]: assert.ok(self.idling) Mar 07 22:39:15 volumio volumio[1148]: error: The expression evaluated to a falsy value: Mar 07 22:39:15 volumio volumio[1148]: assert.ok(self.idling) Mar 07 22:39:15 volumio volumio[1148]: error: updateQueue error: null Mar 07 22:39:15 volumio volumio[1148]: info: Volumio called home Mar 07 22:39:15 volumio sudo[1847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Mar 07 22:39:15 volumio sudo[1847]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:15 volumio sudo[1847]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:15 volumio volumio[1148]: info: Permissions for /etc/hostapd/hostapd.conf Mar 07 22:39:15 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Mar 07 22:39:15 volumio volumio[1148]: info: Discovery: Restarting Advertising due to device name change Mar 07 22:39:15 volumio sudo[1850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Mar 07 22:39:15 volumio sudo[1850]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:15 volumio systemd[1]: Starting Wireless Services... Mar 07 22:39:15 volumio wireless.js[1852]: Cleaning previous... Mar 07 22:39:15 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Mar 07 22:39:15 volumio dnsmasq[1209]: exiting on receipt of SIGTERM Mar 07 22:39:15 volumio systemd[1]: dnsmasq.service: Succeeded. Mar 07 22:39:15 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Mar 07 22:39:15 volumio systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Mar 07 22:39:15 volumio dhcpcd[956]: wlan0: carrier lost Mar 07 22:39:15 volumio avahi-daemon[813]: Interface wlan0.IPv4 no longer relevant for mDNS. Mar 07 22:39:15 volumio avahi-daemon[813]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Mar 07 22:39:16 volumio avahi-daemon[813]: Withdrawing address record for 192.168.211.1 on wlan0. Mar 07 22:39:16 volumio ntpd[1813]: Soliciting pool server 45.76.221.157 Mar 07 22:39:16 volumio avahi-daemon[813]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Mar 07 22:39:16 volumio avahi-daemon[813]: New relevant interface wlan0.IPv4 for mDNS. Mar 07 22:39:16 volumio avahi-daemon[813]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Mar 07 22:39:16 volumio systemd[1]: hostapd.service: Succeeded. Mar 07 22:39:16 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Mar 07 22:39:16 volumio volumio[1148]: info: Discovery: A device disappeared from network Mar 07 22:39:16 volumio volumio[1148]: info: Discovery: Device volumio disappeared from network Mar 07 22:39:16 volumio sudo[1900]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 07 22:39:16 volumio sudo[1900]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:16 volumio avahi-daemon[813]: Withdrawing address record for 192.168.211.1 on wlan0. Mar 07 22:39:16 volumio avahi-daemon[813]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Mar 07 22:39:16 volumio avahi-daemon[813]: Interface wlan0.IPv4 no longer relevant for mDNS. Mar 07 22:39:16 volumio sudo[1900]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:16 volumio sudo[1902]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 07 22:39:16 volumio sudo[1902]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:16 volumio sudo[1902]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:16 volumio wireless.js[1852]: Stopped aP Mar 07 22:39:16 volumio sudo[1909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 07 22:39:16 volumio sudo[1909]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:16 volumio sudo[1909]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:16 volumio sudo[1912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Mar 07 22:39:16 volumio sudo[1912]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:16 volumio sudo[1912]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:16 volumio sudo[1919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 07 22:39:16 volumio sudo[1919]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:16 volumio sudo[1919]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:16 volumio sudo[1921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Mar 07 22:39:16 volumio sudo[1921]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:16 volumio volumio[1148]: info: Discovery: A device disappeared from network Mar 07 22:39:16 volumio ntpd[1813]: Soliciting pool server 193.182.111.12 Mar 07 22:39:16 volumio ntpd[1813]: Soliciting pool server 162.159.200.123 Mar 07 22:39:16 volumio nmbd[927]: [2025/03/07 14:39:16.990871, 0] ../source3/libsmb/nmblib.c:917(send_udp) Mar 07 22:39:16 volumio nmbd[927]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable Mar 07 22:39:17 volumio volumio[1148]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Mar 07 22:39:17 volumio volumio[1148]: info: CoreStateMachine::getcurrentVolume Mar 07 22:39:17 volumio volumio[1148]: info: CoreCommandRouter::volumioRetrievevolume Mar 07 22:39:17 volumio volumio[1148]: info: VolumeController:: Volume=41 Mute =false Mar 07 22:39:17 volumio volumio[1148]: info: CoreStateMachine::pushState Mar 07 22:39:17 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Mar 07 22:39:17 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Mar 07 22:39:17 volumio volumio[1148]: info: CoreStateMachine::updateTrackBlock Mar 07 22:39:17 volumio volumio[1148]: info: CorePlayQueue::getTrackBlock Mar 07 22:39:17 volumio volumio[1148]: info: CoreCommandRouter::volumioRetrievevolume Mar 07 22:39:17 volumio volumio[1148]: info: VolumeController:: Volume=41 Mute =false Mar 07 22:39:17 volumio volumio[1148]: info: CoreStateMachine::pushState Mar 07 22:39:17 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Mar 07 22:39:17 volumio volumio[1148]: info: CoreCommandRouter::volumioPushState Mar 07 22:39:17 volumio ntpd[1813]: Soliciting pool server 193.182.111.143 Mar 07 22:39:17 volumio ntpd[1813]: Soliciting pool server 78.46.102.180 Mar 07 22:39:17 volumio ntpd[1813]: Deleting interface #4 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=3 secs Mar 07 22:39:17 volumio ntpd[1813]: Soliciting pool server 47.92.198.198 Mar 07 22:39:18 volumio ntpd[1813]: Soliciting pool server 119.28.183.184 Mar 07 22:39:18 volumio ntpd[1813]: Soliciting pool server 119.28.206.193 Mar 07 22:39:18 volumio ntpd[1813]: Soliciting pool server 81.16.177.123 Mar 07 22:39:19 volumio volumio-remote-updater[801]: [2025-03-07 14:39:19] [connect] Successful connection Mar 07 22:39:19 volumio volumio-remote-updater[801]: [2025-03-07 14:39:19] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1741358359 101 Mar 07 22:39:19 volumio volumio[1148]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Mar 07 22:39:19 volumio sudo[1921]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:19 volumio wireless.js[1852]: SETTING APPROPRIATE REG DOMAIN: CN Mar 07 22:39:19 volumio sudo[1930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 07 22:39:19 volumio sudo[1930]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:19 volumio sudo[1930]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:19 volumio sudo[1932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set CN Mar 07 22:39:19 volumio sudo[1932]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:19 volumio sudo[1932]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:19 volumio wireless.js[1852]: SUCCESSFULLY SET NEW REGDOMAIN: CN Mar 07 22:39:19 volumio sudo[1939]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 07 22:39:19 volumio sudo[1939]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:19 volumio sudo[1939]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:19 volumio sudo[1941]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 07 22:39:19 volumio sudo[1941]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:19 volumio sudo[1941]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:19 volumio ntpd[1813]: Soliciting pool server 5.79.108.34 Mar 07 22:39:19 volumio ntpd[1813]: Soliciting pool server 162.159.200.1 Mar 07 22:39:19 volumio avahi-daemon[813]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Mar 07 22:39:19 volumio avahi-daemon[813]: New relevant interface wlan0.IPv4 for mDNS. Mar 07 22:39:19 volumio avahi-daemon[813]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Mar 07 22:39:19 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Mar 07 22:39:19 volumio hostapd[1947]: Configuration file: /etc/hostapd/hostapd.conf Mar 07 22:39:19 volumio avahi-daemon[813]: Interface wlan0.IPv4 no longer relevant for mDNS. Mar 07 22:39:19 volumio avahi-daemon[813]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Mar 07 22:39:19 volumio avahi-daemon[813]: Withdrawing address record for 192.168.211.1 on wlan0. Mar 07 22:39:20 volumio avahi-daemon[813]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Mar 07 22:39:20 volumio avahi-daemon[813]: New relevant interface wlan0.IPv4 for mDNS. Mar 07 22:39:20 volumio avahi-daemon[813]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Mar 07 22:39:20 volumio hostapd[1947]: Using interface wlan0 with hwaddr e8:c8:29:ed:a0:c6 and ssid "Volumio-D210C" Mar 07 22:39:20 volumio dhcpcd[956]: wlan0: carrier acquired Mar 07 22:39:20 volumio hostapd[1947]: wlan0: interface state UNINITIALIZED->ENABLED Mar 07 22:39:20 volumio hostapd[1947]: wlan0: AP-ENABLED Mar 07 22:39:20 volumio systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Mar 07 22:39:20 volumio dhcpcd[956]: wlan0: IAID 29:ed:a0:c6 Mar 07 22:39:20 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Mar 07 22:39:20 volumio dnsmasq[1950]: dnsmasq: syntax check OK. Mar 07 22:39:20 volumio iwconfig[1953]: Error for wireless request "Set Power Management" (8B2C) : Mar 07 22:39:20 volumio iwconfig[1953]: SET failed on device wlan0 ; Invalid argument. Mar 07 22:39:20 volumio systemd[1]: wireless.service: Succeeded. Mar 07 22:39:20 volumio systemd[1]: Started Wireless Services. Mar 07 22:39:20 volumio sudo[1850]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:20 volumio dnsmasq[1958]: started, version 2.80 cachesize 150 Mar 07 22:39:20 volumio dnsmasq[1958]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Mar 07 22:39:20 volumio dnsmasq-dhcp[1958]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Mar 07 22:39:20 volumio dnsmasq[1958]: reading /etc/resolv.conf Mar 07 22:39:20 volumio dnsmasq[1958]: using nameserver 192.168.110.1#53 Mar 07 22:39:20 volumio dnsmasq[1958]: using nameserver 208.67.222.222#53 Mar 07 22:39:20 volumio dnsmasq[1958]: using nameserver 208.67.220.220#53 Mar 07 22:39:20 volumio dnsmasq[1958]: read /etc/hosts - 5 addresses Mar 07 22:39:20 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Mar 07 22:39:20 volumio volumio[1148]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 07 22:39:20 volumio dhcpcd[956]: wlan0: soliciting a DHCP lease Mar 07 22:39:20 volumio volumio[1148]: info: Discovery: Started advertising with name: Volumio Mar 07 22:39:20 volumio dhcpcd[956]: wlan0: soliciting an IPv6 router Mar 07 22:39:20 volumio ntpd[1813]: Soliciting pool server 116.203.151.74 Mar 07 22:39:20 volumio ntpd[1813]: Soliciting pool server 193.182.111.14 Mar 07 22:39:21 volumio volumio[1148]: info: Discovery: adding 3ab38dce-390d-4c97-aabf-dd6a6551c51d Mar 07 22:39:21 volumio volumio[1148]: info: Discovery: Found device Volumio Mar 07 22:39:21 volumio volumio[1148]: info: CoreCommandRouter::volumioGetState Mar 07 22:39:21 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Mar 07 22:39:21 volumio ntpd[1813]: Soliciting pool server 2001:1600:4:1::123 Mar 07 22:39:21 volumio ntpd[1813]: Listen normally on 5 wlan0 192.168.211.1:123 Mar 07 22:39:21 volumio ntpd[1813]: new interface(s) found: waking up resolver Mar 07 22:39:21 volumio ntpd[1813]: Soliciting pool server 193.182.111.143 Mar 07 22:39:22 volumio systemd[1]: systemd-fsckd.service: Succeeded. Mar 07 22:39:22 volumio ntpd[1813]: Soliciting pool server 47.115.137.18 Mar 07 22:39:23 volumio sudo[1968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 07 22:39:23 volumio sudo[1968]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:23 volumio sudo[1968]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:23 volumio sudo[1970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 07 22:39:23 volumio sudo[1970]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:23 volumio sudo[1970]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:23 volumio sudo[1974]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 07 22:39:23 volumio sudo[1974]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:23 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Mar 07 22:39:23 volumio sudo[1974]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:23 volumio volumio[1148]: info: Upmpdcli Daemon Started Mar 07 22:39:23 volumio volumio[1976]: Generating RSA private key, 4096 bit long modulus (2 primes) Mar 07 22:39:29 volumio ntpd[1813]: receive: Unexpected origin timestamp 0xeb75839b.e5d10a6f does not match aorg 0000000000.00000000 from server@193.182.111.14 xmt 0xeb7583a1.4aca9148 Mar 07 22:39:29 volumio volumio[1976]: ............................................................................................................++++ Mar 07 22:39:29 volumio volumio[1976]: ........++++ Mar 07 22:39:29 volumio volumio[1976]: e is 65537 (0x010001) Mar 07 22:39:29 volumio volumio[1976]: writing RSA key Mar 07 22:39:30 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Mar 07 22:39:30 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Mar 07 22:39:30 volumio sudo[2075]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 07 22:39:30 volumio sudo[2075]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:30 volumio sudo[2075]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:30 volumio dhcpcd[956]: wlan0: probing for an IPv4LL address Mar 07 22:39:35 volumio volumio[1148]: info: Discovery: this is already registered, 3ab38dce-390d-4c97-aabf-dd6a6551c51d Mar 07 22:39:35 volumio volumio[1148]: info: Discovery: Found device Volumio Mar 07 22:39:35 volumio volumio[1148]: info: CoreCommandRouter::volumioGetState Mar 07 22:39:35 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Mar 07 22:39:35 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Mar 07 22:39:35 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Mar 07 22:39:35 volumio volumio[1148]: info: Refreshing Cached IP Addresses Mar 07 22:39:35 volumio sudo[2163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 07 22:39:35 volumio sudo[2163]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:35 volumio sudo[2163]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:35 volumio sudo[2165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 07 22:39:35 volumio sudo[2165]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:35 volumio sudo[2165]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:35 volumio sudo[2161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Mar 07 22:39:35 volumio sudo[2161]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:35 volumio sudo[2161]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:35 volumio systemd[1]: upmpdcli.service: Succeeded. Mar 07 22:39:35 volumio dhcpcd[956]: wlan0: using IPv4LL address 169.254.145.38 Mar 07 22:39:35 volumio dhcpcd[956]: wlan0: adding route to 169.254.0.0/16 Mar 07 22:39:35 volumio avahi-daemon[813]: Registering new address record for 169.254.145.38 on wlan0.IPv4. Mar 07 22:39:36 volumio volumio[1148]: 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/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Mar 07 22:39:36 volumio volumio[1148]: info: CoreCommandRouter::volumioGetVisibleSources Mar 07 22:39:36 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 07 22:39:36 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Mar 07 22:39:36 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Mar 07 22:39:36 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Mar 07 22:39:36 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Mar 07 22:39:36 volumio volumio[1148]: info: Received Get System Info Mar 07 22:39:36 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 07 22:39:36 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 07 22:39:36 volumio volumio[1148]: info: Discovery: Getting this device information Mar 07 22:39:36 volumio volumio[1148]: info: CoreCommandRouter::volumioGetState Mar 07 22:39:36 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Mar 07 22:39:36 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 07 22:39:36 volumio volumio-remote-updater[801]: No test mode Mar 07 22:39:36 volumio volumio-remote-updater[801]: No alpha test mode Mar 07 22:39:36 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 07 22:39:36 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 07 22:39:36 volumio volumio[1148]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:39:37 volumio ntpd[1813]: Listen normally on 6 wlan0 169.254.145.38:123 Mar 07 22:39:37 volumio ntpd[1813]: new interface(s) found: waking up resolver Mar 07 22:39:37 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Mar 07 22:39:37 volumio volumio[1148]: info: Loading i18n strings for locale en Mar 07 22:39:37 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Mar 07 22:39:37 volumio volumio[1148]: info: Setting timezone to UTC Mar 07 22:39:37 volumio sudo[2235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Mar 07 22:39:37 volumio sudo[2235]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:37 volumio sudo[2235]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:37 volumio sudo[2238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/UTC /etc/localtime Mar 07 22:39:37 volumio sudo[2238]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:37 volumio sudo[2238]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:37 volumio sudo[2241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Mar 07 22:39:37 volumio sudo[2241]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:37 volumio sudo[2241]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:37 volumio sudo[2244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC Mar 07 22:39:37 volumio sudo[2244]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:37 volumio dbus-daemon[782]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.19' (uid=0 pid=2245 comm="/usr/bin/timedatectl set-timezone UTC ") Mar 07 22:39:37 volumio systemd[1]: Starting Time & Date Service... Mar 07 22:39:37 volumio dbus-daemon[782]: [system] Successfully activated service 'org.freedesktop.timedate1' Mar 07 22:39:37 volumio systemd[1]: Started Time & Date Service. Mar 07 22:39:37 volumio sudo[2244]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:37 volumio volumio[1148]: Updating browse sources language Mar 07 22:39:37 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 07 22:39:38 volumio volumio[1148]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 07 22:39:38 volumio volumio[1148]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 07 22:39:38 volumio volumio[1148]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 07 22:39:38 volumio volumio[1148]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 07 22:39:38 volumio volumio[1148]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 07 22:39:38 volumio volumio[1148]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 07 22:39:38 volumio volumio[1148]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 07 22:39:38 volumio volumio[1148]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 07 22:39:38 volumio volumio[1148]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 07 22:39:38 volumio volumio[1148]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 07 22:39:38 volumio volumio[1148]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 07 22:39:38 volumio volumio[1148]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 07 22:39:38 volumio volumio[1148]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 07 22:39:38 volumio volumio[1148]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 07 22:39:38 volumio volumio[1148]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 07 22:39:38 volumio volumio[1148]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 07 22:39:38 volumio volumio[1148]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 07 22:39:38 volumio volumio[1148]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 07 22:39:38 volumio volumio[1148]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 07 22:39:38 volumio volumio[1148]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 07 22:39:38 volumio volumio[1148]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 07 22:39:38 volumio volumio[1148]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 07 22:39:38 volumio volumio[1148]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 07 22:39:38 volumio volumio[1148]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 07 22:39:38 volumio volumio[1148]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 07 22:39:38 volumio volumio[1148]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 07 22:39:38 volumio volumio[1148]: info: Adding plugin bluetooth to MyMusic Plugins Mar 07 22:39:38 volumio volumio[1148]: info: Adding plugin multiroom to MyMusic Plugins Mar 07 22:39:38 volumio volumio[1148]: info: Adding plugin metavolumio to MyMusic Plugins Mar 07 22:39:38 volumio volumio[1148]: info: Adding plugin cd_controller to MyMusic Plugins Mar 07 22:39:38 volumio volumio[1148]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 07 22:39:38 volumio volumio[1148]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 07 22:39:38 volumio volumio[1148]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 07 22:39:38 volumio volumio[1148]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 07 22:39:38 volumio volumio[1148]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 07 22:39:38 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 22:39:38 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 22:39:38 volumio volumio[1148]: info: Starting MyVolumio Remote Streaming Endpoints Mar 07 22:39:38 volumio volumio[1148]: info: MyVolumio not started Mar 07 22:39:38 volumio volumio[1148]: info: Initializing device activation check Mar 07 22:39:38 volumio volumio[1148]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 07 22:39:38 volumio volumio[1148]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 07 22:39:38 volumio volumio[1148]: info: Starting Streaming Service Transparent Proxy Mar 07 22:39:38 volumio volumio[1148]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 07 22:39:38 volumio volumio[1148]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 07 22:39:38 volumio volumio[1148]: info: Streaming services startup Mar 07 22:39:38 volumio volumio[1148]: info: Starting Streaming Daemon Mar 07 22:39:38 volumio sudo[2254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 07 22:39:38 volumio sudo[2254]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:38 volumio volumio[1148]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 07 22:39:38 volumio sudo[2254]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:38 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 07 22:39:38 volumio volumio[1148]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

    • Improved translations

 

NEW ADDITIONS

    • Fix potential issues with QOBUZ and TIDAL Streaming on slow connections

 

","title":"Update v3.795","updateavailable":true} Mar 07 22:39:38 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 07 22:39:38 volumio volumio[1148]: error: Cannot start Volumio Streaming Daemon Mar 07 22:39:38 volumio volumio[1148]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 07 22:39:38 volumio volumio[1148]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 07 22:39:38 volumio volumio[1148]: STREAMING PROXY: Starting server on port 3245 Mar 07 22:39:38 volumio volumio[1148]: Node JS runtime: 14 Mar 07 22:39:41 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Mar 07 22:39:45 volumio sudo[2265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 07 22:39:45 volumio sudo[2265]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:45 volumio sudo[2265]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:45 volumio sudo[2268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 07 22:39:45 volumio sudo[2268]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:45 volumio sudo[2268]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:45 volumio sudo[2271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 07 22:39:45 volumio sudo[2271]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:45 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Mar 07 22:39:45 volumio sudo[2271]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:45 volumio volumio[1148]: info: Upmpdcli Daemon Started Mar 07 22:39:45 volumio volumio[2273]: writing RSA key Mar 07 22:39:46 volumio sudo[2346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 07 22:39:46 volumio sudo[2346]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:46 volumio sudo[2346]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:46 volumio sudo[2349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 07 22:39:46 volumio sudo[2349]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:46 volumio sudo[2349]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:46 volumio volumio[1148]: verbose: New Socket.io Connection to 192.168.110.214 from 192.168.110.207 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.6261.95 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Mar 07 22:39:46 volumio volumio[1148]: info: CoreCommandRouter::volumioGetVisibleSources Mar 07 22:39:46 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 07 22:39:46 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Mar 07 22:39:46 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Mar 07 22:39:46 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Mar 07 22:39:46 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Mar 07 22:39:46 volumio volumio[1148]: info: Received Get System Info Mar 07 22:39:46 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 07 22:39:46 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 07 22:39:46 volumio volumio[1148]: info: Discovery: Getting this device information Mar 07 22:39:46 volumio volumio[1148]: info: CoreCommandRouter::volumioGetState Mar 07 22:39:46 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Mar 07 22:39:46 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 07 22:39:46 volumio volumio-remote-updater[801]: No test mode Mar 07 22:39:46 volumio volumio-remote-updater[801]: No alpha test mode Mar 07 22:39:46 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 07 22:39:46 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 07 22:39:46 volumio volumio[1148]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:39:46 volumio volumio[1148]: info: Retrieving Cloud Streaming UI Mar 07 22:39:46 volumio volumio[1148]: info: Getting Tidal Cloud Configuration Mar 07 22:39:46 volumio volumio[1148]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:39:46 volumio volumio[1148]: info: Getting Qobuz Cloud Configuration Mar 07 22:39:46 volumio volumio[1148]: info: Asking plugin for UI Config Mar 07 22:39:46 volumio volumio[1148]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:39:46 volumio volumio[1148]: info: Getting Spotify Cloud Configuration Mar 07 22:39:46 volumio volumio[1148]: info: Asking plugin for UI Config Mar 07 22:39:46 volumio volumio[1148]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:39:46 volumio volumio[1148]: info: Saving Spotify Acccount Mar 07 22:39:46 volumio volumio[1148]: info: Got Tidal Cloud Configuration Mar 07 22:39:46 volumio volumio[1148]: info: Got it Mar 07 22:39:46 volumio volumio[1148]: info: Got it Mar 07 22:39:46 volumio volumio[1148]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 07 22:39:46 volumio volumio[1148]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:39:46 volumio volumio[1148]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Mar 07 22:39:47 volumio volumio[1148]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

    • Improved translations

 

NEW ADDITIONS

    • Fix potential issues with QOBUZ and TIDAL Streaming on slow connections

 

","title":"Update v3.795","updateavailable":true} Mar 07 22:39:47 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 07 22:39:47 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Mar 07 22:39:47 volumio volumio[1148]: info: Loading i18n strings for locale zh Mar 07 22:39:47 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Mar 07 22:39:47 volumio volumio[1148]: info: Setting timezone to Asia/Shanghai Mar 07 22:39:47 volumio sudo[2356]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Mar 07 22:39:47 volumio sudo[2356]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:47 volumio sudo[2356]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:47 volumio sudo[2359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Shanghai /etc/localtime Mar 07 22:39:47 volumio sudo[2359]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:47 volumio sudo[2359]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:47 volumio sudo[2362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Mar 07 22:39:47 volumio sudo[2362]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:47 volumio sudo[2362]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:47 volumio sudo[2365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Shanghai Mar 07 22:39:47 volumio sudo[2365]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:47 volumio systemd-timedated[2246]: Failed to set time zone: Permission denied Mar 07 22:39:47 volumio sudo[2365]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:47 volumio volumio[1148]: Failed to set time zone: Access denied Mar 07 22:39:47 volumio volumio[1148]: info: Could not set timezone, retrying Mar 07 22:39:47 volumio volumio[1148]: Updating browse sources language Mar 07 22:39:47 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 07 22:39:48 volumio volumio[1148]: info: Fetching Streaming Services browse cache Mar 07 22:39:48 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 07 22:39:48 volumio sudo[2368]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Shanghai Mar 07 22:39:48 volumio sudo[2368]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:48 volumio sudo[2368]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:49 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 07 22:39:49 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 22:39:49 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 07 22:39:52 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Mar 07 22:39:52 volumio volumio[1148]: info: Loading i18n strings for locale zh Mar 07 22:39:52 volumio volumio[1148]: Updating browse sources language Mar 07 22:39:52 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 07 22:39:52 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Mar 07 22:39:52 volumio volumio[1148]: info: Setting timezone to Asia/Shanghai Mar 07 22:39:52 volumio sudo[2374]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Mar 07 22:39:52 volumio sudo[2374]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:52 volumio sudo[2374]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:52 volumio sudo[2377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Shanghai /etc/localtime Mar 07 22:39:52 volumio sudo[2377]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:52 volumio sudo[2377]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:52 volumio sudo[2380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Mar 07 22:39:52 volumio sudo[2380]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:52 volumio sudo[2380]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:52 volumio sudo[2383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Shanghai Mar 07 22:39:52 volumio sudo[2383]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:52 volumio sudo[2383]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:52 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Mar 07 22:39:52 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Mar 07 22:39:52 volumio sudo[2386]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 07 22:39:52 volumio sudo[2386]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:52 volumio sudo[2386]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:52 volumio volumio[1148]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Mar 07 22:39:52 volumio volumio[1148]: wlan0 Interface doesn't support scanning : Operation not supported Mar 07 22:39:52 volumio volumio[1148]: info: Cannot use regular scanning, forcing with ap-force Mar 07 22:39:52 volumio sudo[2389]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Mar 07 22:39:52 volumio sudo[2389]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:39:52 volumio sudo[2389]: pam_unix(sudo:session): session closed for user root Mar 07 22:39:52 volumio volumio[1148]: command failed: Operation not supported (-95) Mar 07 22:39:52 volumio volumio[1148]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Mar 07 22:39:52 volumio volumio[1148]: command failed: Operation not supported (-95) Mar 07 22:39:52 volumio volumio[1148]: info: Fetching Streaming Services browse cache Mar 07 22:39:53 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 07 22:39:53 volumio volumio[1148]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Mar 07 22:39:53 volumio volumio[1148]: info: CoreCommandRouter::volumioGetState Mar 07 22:39:53 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Mar 07 22:39:57 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Mar 07 22:39:58 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Mar 07 22:39:58 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 07 22:39:58 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 22:39:58 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 07 22:39:58 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 22:39:58 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 22:39:58 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 07 22:39:58 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 07 22:39:58 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 07 22:39:58 volumio volumio[1148]: info: CoreCommandRouter::volumioGetBrowseSources Mar 07 22:39:58 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 07 22:39:58 volumio volumio[1148]: error: MyVolumio Plugin failed to authenticate in a timely fashion Mar 07 22:39:58 volumio volumio[1148]: info: Completed starting MyVolumio Plugin Mar 07 22:39:58 volumio volumio[1148]: info: BOOT COMPLETED Mar 07 22:39:58 volumio volumio[1148]: [Metrics] CommandRouter: 40s 344.33ms Mar 07 22:39:58 volumio volumio[1148]: info: CoreCommandRouter::volumiosetStartupVolume Mar 07 22:39:58 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 07 22:39:58 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 07 22:39:58 volumio volumio[1148]: info: CoreCommandRouter::Close All Modals sent Mar 07 22:39:58 volumio volumio[1148]: info: CoreCommandRouter::Close All Modals sent Mar 07 22:39:58 volumio volumio[1148]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:39:58 volumio volumio[1148]: info: Retrieving Cloud Streaming UI Mar 07 22:39:58 volumio volumio[1148]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:39:58 volumio volumio[1148]: info: Retrieving Cloud Streaming UI Mar 07 22:39:58 volumio volumio[1148]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:39:58 volumio volumio[1148]: info: Retrieving Cloud Streaming UI Mar 07 22:39:58 volumio volumio[1148]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:39:58 volumio volumio[1148]: info: Retrieving Cloud Streaming UI Mar 07 22:39:58 volumio volumio[1148]: info: Getting Tidal Cloud Configuration Mar 07 22:39:58 volumio volumio[1148]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:39:58 volumio volumio[1148]: info: Getting Qobuz Cloud Configuration Mar 07 22:39:58 volumio volumio[1148]: info: Asking plugin for UI Config Mar 07 22:39:58 volumio volumio[1148]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:39:58 volumio volumio[1148]: info: Getting Spotify Cloud Configuration Mar 07 22:39:58 volumio volumio[1148]: info: Asking plugin for UI Config Mar 07 22:39:58 volumio volumio[1148]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:39:58 volumio volumio[1148]: info: Saving Spotify Acccount Mar 07 22:39:58 volumio volumio[1148]: info: Got Tidal Cloud Configuration Mar 07 22:39:58 volumio volumio[1148]: info: Got it Mar 07 22:39:58 volumio volumio[1148]: info: Got it Mar 07 22:39:58 volumio volumio[1148]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 07 22:39:58 volumio volumio[1148]: info: Getting Tidal Cloud Configuration Mar 07 22:39:58 volumio volumio[1148]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:39:58 volumio volumio[1148]: info: Getting Qobuz Cloud Configuration Mar 07 22:39:58 volumio volumio[1148]: info: Asking plugin for UI Config Mar 07 22:39:58 volumio volumio[1148]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:39:58 volumio volumio[1148]: info: Getting Spotify Cloud Configuration Mar 07 22:39:58 volumio volumio[1148]: info: Asking plugin for UI Config Mar 07 22:39:58 volumio volumio[1148]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:39:58 volumio volumio[1148]: info: Saving Spotify Acccount Mar 07 22:39:58 volumio volumio[1148]: info: Got Tidal Cloud Configuration Mar 07 22:39:58 volumio volumio[1148]: info: Got it Mar 07 22:39:58 volumio volumio[1148]: info: Got it Mar 07 22:39:58 volumio volumio[1148]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 07 22:39:58 volumio volumio[1148]: info: Getting Tidal Cloud Configuration Mar 07 22:39:58 volumio volumio[1148]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:39:58 volumio volumio[1148]: info: Getting Qobuz Cloud Configuration Mar 07 22:39:58 volumio volumio[1148]: info: Asking plugin for UI Config Mar 07 22:39:58 volumio volumio[1148]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:39:58 volumio volumio[1148]: info: Getting Spotify Cloud Configuration Mar 07 22:39:58 volumio volumio[1148]: info: Asking plugin for UI Config Mar 07 22:39:58 volumio volumio[1148]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:39:58 volumio volumio[1148]: info: Saving Spotify Acccount Mar 07 22:39:58 volumio volumio[1148]: info: Got Tidal Cloud Configuration Mar 07 22:39:58 volumio volumio[1148]: info: Got it Mar 07 22:39:58 volumio volumio[1148]: info: Got it Mar 07 22:39:58 volumio volumio[1148]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 07 22:39:58 volumio volumio[1148]: info: Getting Tidal Cloud Configuration Mar 07 22:39:58 volumio volumio[1148]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:39:58 volumio volumio[1148]: info: Getting Qobuz Cloud Configuration Mar 07 22:39:58 volumio volumio[1148]: info: Asking plugin for UI Config Mar 07 22:39:58 volumio volumio[1148]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:39:58 volumio volumio[1148]: info: Getting Spotify Cloud Configuration Mar 07 22:39:58 volumio volumio[1148]: info: Asking plugin for UI Config Mar 07 22:39:58 volumio volumio[1148]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:39:58 volumio volumio[1148]: info: Saving Spotify Acccount Mar 07 22:39:58 volumio volumio[1148]: info: Got Tidal Cloud Configuration Mar 07 22:39:58 volumio volumio[1148]: info: Got it Mar 07 22:39:58 volumio volumio[1148]: info: Got it Mar 07 22:39:58 volumio volumio[1148]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 07 22:39:58 volumio volumio[1148]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:39:58 volumio volumio[1148]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Mar 07 22:39:58 volumio volumio[1148]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:39:58 volumio volumio[1148]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Mar 07 22:39:58 volumio volumio[1148]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:39:58 volumio volumio[1148]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Mar 07 22:39:58 volumio volumio[1148]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:39:58 volumio volumio[1148]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Mar 07 22:39:59 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 07 22:39:59 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 07 22:39:59 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 07 22:39:59 volumio volumio[1148]: info: Version has changed, forcing UI Reload Mar 07 22:39:59 volumio volumio[1148]: info: CoreCommandRouter::Reload Ui Mar 07 22:40:00 volumio volumio[1148]: 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/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Mar 07 22:40:00 volumio volumio[1148]: info: CoreCommandRouter::volumioGetVisibleSources Mar 07 22:40:00 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 07 22:40:00 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Mar 07 22:40:00 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Mar 07 22:40:00 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Mar 07 22:40:00 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Mar 07 22:40:00 volumio volumio[1148]: info: Received Get System Info Mar 07 22:40:00 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 07 22:40:00 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 07 22:40:00 volumio volumio[1148]: info: Discovery: Getting this device information Mar 07 22:40:00 volumio volumio[1148]: info: CoreCommandRouter::volumioGetState Mar 07 22:40:00 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Mar 07 22:40:00 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 07 22:40:00 volumio volumio-remote-updater[801]: No test mode Mar 07 22:40:00 volumio volumio-remote-updater[801]: No alpha test mode Mar 07 22:40:00 volumio sudo[2449]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 07 22:40:00 volumio sudo[2449]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:40:00 volumio sudo[2449]: pam_unix(sudo:session): session closed for user root Mar 07 22:40:00 volumio sudo[2452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 07 22:40:00 volumio sudo[2452]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:40:00 volumio sudo[2452]: pam_unix(sudo:session): session closed for user root Mar 07 22:40:00 volumio volumio[1148]: verbose: New Socket.io Connection to 192.168.110.214 from 192.168.110.207 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/122.0.6261.95 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Mar 07 22:40:00 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 07 22:40:00 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 07 22:40:00 volumio volumio[1148]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:40:00 volumio volumio[1148]: info: Retrieving Cloud Streaming UI Mar 07 22:40:00 volumio volumio[1148]: info: Getting Tidal Cloud Configuration Mar 07 22:40:00 volumio volumio[1148]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:40:00 volumio volumio[1148]: info: Getting Qobuz Cloud Configuration Mar 07 22:40:00 volumio volumio[1148]: info: Asking plugin for UI Config Mar 07 22:40:00 volumio volumio[1148]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:40:00 volumio volumio[1148]: info: Getting Spotify Cloud Configuration Mar 07 22:40:00 volumio volumio[1148]: info: Asking plugin for UI Config Mar 07 22:40:00 volumio volumio[1148]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:40:00 volumio volumio[1148]: info: Saving Spotify Acccount Mar 07 22:40:00 volumio volumio[1148]: info: Got Tidal Cloud Configuration Mar 07 22:40:00 volumio volumio[1148]: info: Got it Mar 07 22:40:00 volumio volumio[1148]: info: Got it Mar 07 22:40:00 volumio volumio[1148]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 07 22:40:00 volumio volumio[1148]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:40:00 volumio volumio[1148]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Mar 07 22:40:00 volumio volumio[1148]: info: CoreCommandRouter::volumioGetVisibleSources Mar 07 22:40:00 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 07 22:40:00 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Mar 07 22:40:00 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Mar 07 22:40:00 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Mar 07 22:40:00 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Mar 07 22:40:00 volumio volumio[1148]: info: Received Get System Info Mar 07 22:40:00 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 07 22:40:00 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 07 22:40:00 volumio volumio[1148]: info: Discovery: Getting this device information Mar 07 22:40:00 volumio volumio[1148]: info: CoreCommandRouter::volumioGetState Mar 07 22:40:00 volumio volumio[1148]: info: CorePlayQueue::getTrack 0 Mar 07 22:40:00 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 07 22:40:00 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 07 22:40:00 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 07 22:40:00 volumio volumio[1148]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:40:00 volumio volumio[1148]: info: Retrieving Cloud Streaming UI Mar 07 22:40:00 volumio volumio[1148]: info: Getting Tidal Cloud Configuration Mar 07 22:40:00 volumio volumio[1148]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:40:00 volumio volumio[1148]: info: Getting Qobuz Cloud Configuration Mar 07 22:40:00 volumio volumio[1148]: info: Asking plugin for UI Config Mar 07 22:40:00 volumio volumio[1148]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:40:00 volumio volumio[1148]: info: Getting Spotify Cloud Configuration Mar 07 22:40:00 volumio volumio[1148]: info: Asking plugin for UI Config Mar 07 22:40:00 volumio volumio[1148]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:40:00 volumio volumio[1148]: info: Saving Spotify Acccount Mar 07 22:40:00 volumio volumio[1148]: info: Got Tidal Cloud Configuration Mar 07 22:40:00 volumio volumio[1148]: info: Got it Mar 07 22:40:00 volumio volumio[1148]: info: Got it Mar 07 22:40:00 volumio volumio[1148]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 07 22:40:00 volumio volumio[1148]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 07 22:40:00 volumio volumio[1148]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Mar 07 22:40:01 volumio volumio-remote-updater[801]: No test mode Mar 07 22:40:01 volumio volumio-remote-updater[801]: No alpha test mode Mar 07 22:40:01 volumio volumio[1148]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

    • Improved translations

 

NEW ADDITIONS

    • Fix potential issues with QOBUZ and TIDAL Streaming on slow connections

 

","title":"Update v3.795","updateavailable":true} Mar 07 22:40:01 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 07 22:40:01 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Mar 07 22:40:01 volumio volumio[1148]: info: Loading i18n strings for locale en Mar 07 22:40:01 volumio volumio[1148]: Updating browse sources language Mar 07 22:40:01 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 07 22:40:01 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Mar 07 22:40:01 volumio volumio[1148]: info: Setting timezone to UTC Mar 07 22:40:01 volumio sudo[2459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Mar 07 22:40:01 volumio sudo[2459]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:40:01 volumio sudo[2459]: pam_unix(sudo:session): session closed for user root Mar 07 22:40:01 volumio sudo[2462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/UTC /etc/localtime Mar 07 22:40:01 volumio sudo[2462]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:40:01 volumio sudo[2462]: pam_unix(sudo:session): session closed for user root Mar 07 22:40:01 volumio sudo[2465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Mar 07 22:40:01 volumio sudo[2465]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:40:01 volumio sudo[2465]: pam_unix(sudo:session): session closed for user root Mar 07 22:40:01 volumio sudo[2468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC Mar 07 22:40:01 volumio sudo[2468]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:40:01 volumio systemd-timedated[2246]: Failed to set time zone: Permission denied Mar 07 22:40:01 volumio sudo[2468]: pam_unix(sudo:session): session closed for user root Mar 07 22:40:01 volumio volumio[1148]: Failed to set time zone: Access denied Mar 07 22:40:01 volumio volumio[1148]: info: Could not set timezone, retrying Mar 07 22:40:01 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Mar 07 22:40:01 volumio volumio[1148]: info: Loading i18n strings for locale zh Mar 07 22:40:01 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone Mar 07 22:40:01 volumio volumio[1148]: info: Setting timezone to Asia/Shanghai Mar 07 22:40:01 volumio sudo[2471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime Mar 07 22:40:01 volumio sudo[2471]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:40:01 volumio sudo[2471]: pam_unix(sudo:session): session closed for user root Mar 07 22:40:01 volumio sudo[2474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Shanghai /etc/localtime Mar 07 22:40:01 volumio sudo[2474]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:40:01 volumio sudo[2474]: pam_unix(sudo:session): session closed for user root Mar 07 22:40:01 volumio sudo[2477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime Mar 07 22:40:01 volumio sudo[2477]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:40:01 volumio sudo[2477]: pam_unix(sudo:session): session closed for user root Mar 07 22:40:01 volumio sudo[2480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Shanghai Mar 07 22:40:01 volumio sudo[2480]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:40:01 volumio systemd-timedated[2246]: Failed to set time zone: Permission denied Mar 07 22:40:01 volumio sudo[2480]: pam_unix(sudo:session): session closed for user root Mar 07 22:40:01 volumio volumio[1148]: Failed to set time zone: Access denied Mar 07 22:40:01 volumio volumio[1148]: info: Could not set timezone, retrying Mar 07 22:40:01 volumio volumio[1148]: Updating browse sources language Mar 07 22:40:01 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 07 22:40:01 volumio volumio[1148]: info: Fetching Streaming Services browse cache Mar 07 22:40:02 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 07 22:40:02 volumio volumio[1148]: info: Fetching Streaming Services browse cache Mar 07 22:40:02 volumio volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 07 22:40:02 volumio sudo[2483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone UTC Mar 07 22:40:02 volumio sudo[2483]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 07 22:40:02 volumio systemd-timedated[2246]: Failed to set time zone: Permission denied Mar 07 22:40:02 volumio sudo[2483]: pam_unix(sudo:session): session closed for user root Mar 07 22:40:02 volumio volumio[1148]: Failed to set time zone: Access denied Mar 07 22:40:02 volumio volumio[1148]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 07 22:40:02 volumio volumio[1148]: Error: Command failed: /usr/bin/sudo /usr/bin/timedatectl set-timezone 'UTC' Mar 07 22:40:02 volumio volumio[1148]: Failed to set time zone: Access denied Mar 07 22:40:02 volumio volumio[1148]: at checkExecSyncError (child_process.js:790:11) Mar 07 22:40:02 volumio volumio[1148]: at execSync (child_process.js:863:15) Mar 07 22:40:02 volumio volumio[1148]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/system/index.js:1575:9) Mar 07 22:40:02 volumio volumio[1148]: at listOnTimeout (internal/timers.js:557:17) Mar 07 22:40:02 volumio volumio[1148]: at processTimers (internal/timers.js:500:7) { Mar 07 22:40:02 volumio volumio[1148]: status: 1, Mar 07 22:40:02 volumio volumio[1148]: signal: null, Mar 07 22:40:02 volumio volumio[1148]: output: [ null, '', 'Failed to set time zone: Access denied\n' ], Mar 07 22:40:02 volumio volumio[1148]: pid: 2482, Mar 07 22:40:02 volumio volumio[1148]: stdout: '', Mar 07 22:40:02 volumio volumio[1148]: stderr: 'Failed to set time zone: Access denied\n' Mar 07 22:40:02 volumio volumio[1148]: } Mar 07 22:40:02 volumio volumio[1148]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 07 22:40:02 volumio sudo[2494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-07 22:39 Mar 07 22:40:02 volumio sudo[2494]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"