-- Logs begin at Tue 2023-03-28 01:54:07 CST, end at Mon 2024-07-22 11:53:34 CST. -- Jul 22 11:52:25 volumio systemd[1]: Started Network Time Synchronization. Jul 22 11:52:25 volumio systemd[1]: Reached target System Time Set. Jul 22 11:52:25 volumio systemd[1]: Reached target System Time Synchronized. Jul 22 11:52:25 volumio systemd[1]: Started udev Kernel Device Manager. Jul 22 11:52:25 volumio systemd[1]: Reached target System Initialization. Jul 22 11:52:25 volumio systemd[1]: Started Daily apt download activities. Jul 22 11:52:25 volumio systemd[1]: Started Daily apt upgrade and clean activities. Jul 22 11:52:25 volumio systemd[1]: Started Periodic ext4 Online Metadata Check for All Filesystems. Jul 22 11:52:26 volumio systemd[1]: Started Discard unused blocks once a week. Jul 22 11:52:26 volumio systemd[1]: Started Daily rotation of log files. Jul 22 11:52:26 volumio systemd[1]: Started Message of the Day. Jul 22 11:52:26 volumio systemd[1]: Started Daily Cleanup of Temporary Directories. Jul 22 11:52:26 volumio systemd[1]: Reached target Timers. Jul 22 11:52:26 volumio systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Jul 22 11:52:26 volumio systemd[1]: Listening on D-Bus System Message Bus Socket. Jul 22 11:52:26 volumio systemd-udevd[2102]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 22 11:52:26 volumio systemd[1]: Listening on mpd.socket. Jul 22 11:52:26 volumio systemd-udevd[2096]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 22 11:52:26 volumio systemd[1]: Reached target Sockets. Jul 22 11:52:26 volumio systemd[1]: Reached target Basic System. Jul 22 11:52:26 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Jul 22 11:52:26 volumio systemd[1]: Started D-Bus System Message Bus. Jul 22 11:52:26 volumio avahi-daemon[2107]: Found user 'avahi' (UID 107) and group 'avahi' (GID 114). Jul 22 11:52:26 volumio avahi-daemon[2107]: Successfully dropped root privileges. Jul 22 11:52:26 volumio avahi-daemon[2107]: avahi-daemon 0.7 starting up. Jul 22 11:52:26 volumio systemd[1]: Starting Remove Stale Online ext4 Metadata Check Snapshots... Jul 22 11:52:26 volumio systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Jul 22 11:52:26 volumio systemd[1]: Started Volumio Iptables Module. Jul 22 11:52:26 volumio systemd[1]: Starting Dispatcher daemon for systemd-networkd... Jul 22 11:52:26 volumio avahi-daemon[2107]: Successfully called chroot(). Jul 22 11:52:26 volumio avahi-daemon[2107]: Successfully dropped remaining capabilities. Jul 22 11:52:26 volumio avahi-daemon[2115]: chroot.c: open() failed: No such file or directory Jul 22 11:52:26 volumio avahi-daemon[2107]: Failed to open /etc/resolv.conf: Invalid argument Jul 22 11:52:26 volumio avahi-daemon[2107]: No service file found in /etc/avahi/services. Jul 22 11:52:26 volumio systemd[1]: Started Set the CPU Frequency Scaling governor. Jul 22 11:52:26 volumio systemd[1]: Starting Login Service... Jul 22 11:52:26 volumio systemd[1]: Starting Network Service... Jul 22 11:52:26 volumio systemd[1]: Starting LSB: Start busybox udhcpd at boot time... Jul 22 11:52:27 volumio kernel: random: crng init done Jul 22 11:52:27 volumio kernel: random: 7 urandom warning(s) missed due to ratelimiting Jul 22 11:52:27 volumio systemd[1]: Started mount disks automatically with standby. Jul 22 11:52:27 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jul 22 11:52:27 volumio systemd-udevd[2102]: Using default interface naming scheme 'v245'. Jul 22 11:52:27 volumio systemd[1]: Started Volumio Log Rotation Service. Jul 22 11:52:27 volumio systemd[1]: Started Volumio SSH enabler. Jul 22 11:52:27 volumio systemd[1]: Starting Wireless Services... Jul 22 11:52:27 volumio systemd[1]: Starting WPA supplicant... Jul 22 11:52:27 volumio systemd[1]: Finished Load/Save Random Seed. Jul 22 11:52:27 volumio systemd[1]: volumiossh.service: Succeeded. Jul 22 11:52:27 volumio dbus-daemon[2108]: [system] Activating systemd to hand-off: service name='org.freedesktop.UDisks' unit='udisks.service' requested by ':1.2' (uid=1000 pid=2144 comm="/usr/bin/udisks-glue --foreground ") Jul 22 11:52:27 volumio systemd-udevd[2098]: Using default interface naming scheme 'v245'. Jul 22 11:52:27 volumio dbus-daemon[2108]: [system] Successfully activated service 'org.freedesktop.systemd1' Jul 22 11:52:27 volumio systemd-udevd[2094]: Using default interface naming scheme 'v245'. Jul 22 11:52:27 volumio avahi-daemon[2107]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Jul 22 11:52:27 volumio systemd-udevd[2102]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 22 11:52:27 volumio avahi-daemon[2107]: New relevant interface lo.IPv4 for mDNS. Jul 22 11:52:27 volumio systemd-udevd[2102]: ethtool: could not get ethtool features for eth0 Jul 22 11:52:27 volumio avahi-daemon[2107]: Network interface enumeration completed. Jul 22 11:52:27 volumio systemd-udevd[2102]: Could not set offload features of eth0: Device or resource busy Jul 22 11:52:27 volumio avahi-daemon[2107]: Registering new address record for 127.0.0.1 on lo.IPv4. Jul 22 11:52:27 volumio systemd-udevd[2098]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 22 11:52:27 volumio systemd-udevd[2094]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 22 11:52:27 volumio wpa_supplicant[2171]: Successfully initialized wpa_supplicant Jul 22 11:52:27 volumio systemd-networkd[2139]: Configuration file /etc/systemd/network/10-eth0.network is marked world-writable. Please remove world writability permission bits. Proceeding anyway. Jul 22 11:52:27 volumio systemd-networkd[2139]: Configuration file /etc/systemd/network/11-wlan0.network is marked world-writable. Please remove world writability permission bits. Proceeding anyway. Jul 22 11:52:27 volumio udhcpd[2140]: udhcpd: Disabled. Edit /etc/default/udhcpd to enable it. Jul 22 11:52:27 volumio systemd[1]: Started LSB: Start busybox udhcpd at boot time. Jul 22 11:52:28 volumio systemd-logind[2137]: New seat seat0. Jul 22 11:52:28 volumio sudo[2177]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Jul 22 11:52:28 volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jul 22 11:52:28 volumio kernel: libphy: 4500000.eth: probed Jul 22 11:52:28 volumio sudo[2177]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 11:52:28 volumio systemd[1]: Found device /dev/ttyAS0. Jul 22 11:52:28 volumio systemd[1]: Started WPA supplicant. Jul 22 11:52:28 volumio kernel: sunxi-gmac 4500000.eth eth0: eth0: Type(8) PHY ID 0000011a at 0 IRQ poll (4500000.eth-0:00) Jul 22 11:52:28 volumio volumio[2151]: Could not open config: /tmp/upmpdcli.conf Jul 22 11:52:28 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jul 22 11:52:28 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jul 22 11:52:28 volumio avahi-daemon[2107]: Server startup complete. Host name is volumio.local. Local service cookie is 767798838. Jul 22 11:52:28 volumio systemd-udevd[2096]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 22 11:52:28 volumio systemd-udevd[2096]: Using default interface naming scheme 'v245'. Jul 22 11:52:28 volumio systemd-udevd[2096]: ip6tnl0: Could not generate persistent MAC: No data available Jul 22 11:52:28 volumio systemd-udevd[2098]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 22 11:52:28 volumio systemd-udevd[2095]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 22 11:52:28 volumio sudo[2177]: pam_unix(sudo:session): session closed for user root Jul 22 11:52:28 volumio systemd-networkd[2139]: Enumeration completed Jul 22 11:52:28 volumio systemd[1]: Started Network Service. Jul 22 11:52:28 volumio systemd-networkd[2139]: wlan0: found matching network '/etc/systemd/network/11-wlan0.network', based on potentially unpredictable ifname Jul 22 11:52:28 volumio systemd-networkd[2139]: wlan0: IPv6 successfully enabled Jul 22 11:52:28 volumio systemd-networkd[2139]: eth1: Link UP Jul 22 11:52:28 volumio systemd-networkd[2139]: eth1: Gained carrier Jul 22 11:52:28 volumio systemd-networkd[2139]: eth1: Lost carrier Jul 22 11:52:28 volumio systemd-networkd[2139]: eth0: Link UP Jul 22 11:52:28 volumio systemd-networkd[2139]: eth0: IPv6 successfully enabled Jul 22 11:52:29 volumio kernel: ieee80211 phy0: rt2x00lib_request_firmware: Info - Loading firmware file 'rt2870.bin' Jul 22 11:52:29 volumio kernel: ieee80211 phy0: rt2x00lib_request_firmware: Info - Firmware detected - version: 0.36 Jul 22 11:52:29 volumio systemd[1]: iptables.service: Succeeded. Jul 22 11:52:29 volumio systemd[1]: e2scrub_reap.service: Succeeded. Jul 22 11:52:29 volumio systemd[1]: Finished Remove Stale Online ext4 Metadata Check Snapshots. Jul 22 11:52:29 volumio systemd[1]: Started Login Service. Jul 22 11:52:29 volumio systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Jul 22 11:52:29 volumio systemd[1]: Condition check resulted in Manage Sound Card State (restore and store) being skipped. Jul 22 11:52:29 volumio systemd-networkd[2139]: wlan0: Link UP Jul 22 11:52:29 volumio systemd[1]: Starting Save/Restore Sound Card State... Jul 22 11:52:29 volumio systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Jul 22 11:52:29 volumio systemd[1]: Starting Network Name Resolution... Jul 22 11:52:29 volumio systemd[1]: Starting Disk Manager (legacy version)... Jul 22 11:52:29 volumio systemd[1]: Starting Load/Save RF Kill Switch Status... Jul 22 11:52:29 volumio systemd[1]: Finished Save/Restore Sound Card State. Jul 22 11:52:29 volumio systemd[1]: Reached target Sound Card. Jul 22 11:52:29 volumio dbus-daemon[2108]: [system] Successfully activated service 'org.freedesktop.UDisks' Jul 22 11:52:29 volumio systemd[1]: Started Disk Manager (legacy version). Jul 22 11:52:29 volumio systemd[1]: Started Load/Save RF Kill Switch Status. Jul 22 11:52:29 volumio dbus-daemon[2108]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.8' (uid=0 pid=2310 comm="/usr/lib/udisks/udisks-daemon --no-debug ") Jul 22 11:52:29 volumio systemd[1]: Starting Authorization Manager... Jul 22 11:52:29 volumio systemd-resolved[2309]: Positive Trust Anchors: Jul 22 11:52:29 volumio systemd-resolved[2309]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 22 11:52:29 volumio systemd-resolved[2309]: Negative trust anchors: 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Jul 22 11:52:29 volumio polkitd[2317]: started daemon version 0.105 using authority implementation `local' version `0.105' Jul 22 11:52:29 volumio dbus-daemon[2108]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Jul 22 11:52:29 volumio systemd[1]: Started Authorization Manager. Jul 22 11:52:30 volumio systemd[1]: Started Dispatcher daemon for systemd-networkd. Jul 22 11:52:30 volumio systemd-resolved[2309]: Using system hostname 'volumio'. Jul 22 11:52:30 volumio systemd[1]: Started Network Name Resolution. Jul 22 11:52:30 volumio systemd[1]: Reached target Network. Jul 22 11:52:30 volumio systemd[1]: Reached target Network is Online. Jul 22 11:52:30 volumio systemd[1]: Reached target Host and Network Name Lookups. Jul 22 11:52:30 volumio udisks-glue[2144]: Device file /dev/mmcblk0p6 inserted Jul 22 11:52:30 volumio udisks-glue[2144]: Device file /dev/mmcblk0 inserted Jul 22 11:52:30 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jul 22 11:52:30 volumio udisks-glue[2144]: Device file /dev/mmcblk0p4 inserted Jul 22 11:52:30 volumio systemd[1]: Starting Music Player Daemon... Jul 22 11:52:30 volumio udisks-glue[2144]: Device file /dev/mmcblk0p3 inserted Jul 22 11:52:30 volumio systemd[1]: Starting Samba NMB Daemon... Jul 22 11:52:30 volumio hostapd[2322]: Configuration file: /etc/hostapd/hostapd.conf Jul 22 11:52:30 volumio udisks-glue[2144]: Device file /dev/mmcblk0p2 inserted Jul 22 11:52:30 volumio systemd[1]: Started NQPTP -- Not Quite PTP. Jul 22 11:52:30 volumio udisks-glue[2144]: Device file /dev/mmcblk0p8 inserted Jul 22 11:52:30 volumio udisks-glue[2144]: Device file /dev/mmcblk0p1 inserted Jul 22 11:52:30 volumio udisks-glue[2144]: Trying to automount /dev/mmcblk0p1... Jul 22 11:52:30 volumio systemd[1]: Starting /etc/rc.local Compatibility... Jul 22 11:52:30 volumio systemd[1]: Starting Permit User Sessions... Jul 22 11:52:30 volumio rc.local[2328]: no find file Jul 22 11:52:30 volumio systemd[1]: Started /etc/rc.local Compatibility. Jul 22 11:52:30 volumio rc.local[2329]: websocket connect Jul 22 11:52:30 volumio rc.local[2329]: Error: No active session Jul 22 11:52:30 volumio kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Jul 22 11:52:30 volumio systemd[1]: Finished Permit User Sessions. Jul 22 11:52:30 volumio udisks-glue[2144]: Successfully automounted /dev/mmcblk0p1 at /media/Volumn Jul 22 11:52:30 volumio systemd[1]: Started Getty on tty1. Jul 22 11:52:30 volumio udisks-glue[2144]: Device file /dev/mmcblk0p7 inserted Jul 22 11:52:30 volumio udisks-glue[2144]: Device file /dev/mmcblk0p1 mounted at /media/Volumn Jul 22 11:52:30 volumio systemd[1]: Started Serial Getty on ttyAS0. Jul 22 11:52:30 volumio kernel: sunxi-gmac 4500000.eth eth0: Link is Up - 100Mbps/Full - flow control off Jul 22 11:52:30 volumio systemd-networkd[2139]: wlan0: Link DOWN Jul 22 11:52:30 volumio systemd[1]: Reached target Login Prompts. Jul 22 11:52:30 volumio rc.local[2355]: opt = r Jul 22 11:52:30 volumio rc.local[2355]: optarg = 90 Jul 22 11:52:30 volumio rc.local[2355]: optind = 3 Jul 22 11:52:30 volumio rc.local[2355]: argv[optind - 1] = 90 Jul 22 11:52:30 volumio rc.local[2355]: opt = t Jul 22 11:52:30 volumio rc.local[2355]: optarg = /dev/ttyAS2 Jul 22 11:52:30 volumio rc.local[2355]: optind = 5 Jul 22 11:52:30 volumio rc.local[2355]: argv[optind - 1] = /dev/ttyAS2 Jul 22 11:52:30 volumio rc.local[2355]: tty driver /dev/ttyAS2 Jul 22 11:52:31 volumio rc.local[2355]: SCREEN_SIZE_960X376 Jul 22 11:52:31 volumio rc.local[2329]: [2024-07-22 11:52:31] [info] asio async_connect error: asio.system:111 (Connection refused) Jul 22 11:52:31 volumio rc.local[2329]: [2024-07-22 11:52:31] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Jul 22 11:52:31 volumio rc.local[2329]: [2024-07-22 11:52:31] [error] handle_connect error: Connection refused Jul 22 11:52:31 volumio kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Jul 22 11:52:31 volumio nmbd[2324]: [2024/07/22 11:52:31.266510, 0] ../../source3/nmbd/nmbd.c:901(main) Jul 22 11:52:31 volumio systemd-networkd[2139]: wlan0: Link UP Jul 22 11:52:31 volumio rc.local[2359]: cat: /workdir/version.txt: No such file or directory Jul 22 11:52:31 volumio kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready Jul 22 11:52:31 volumio hostapd[2322]: Using interface wlan0 with hwaddr 7c:dd:90:39:2a:5e and ssid "Volumio" Jul 22 11:52:31 volumio nmbd[2324]: nmbd version 4.15.13-Ubuntu started. Jul 22 11:52:31 volumio systemd-networkd[2139]: eth0: Gained carrier Jul 22 11:52:31 volumio nmbd[2324]: Copyright Andrew Tridgell and the Samba Team 1992-2021 Jul 22 11:52:31 volumio systemd-networkd[2139]: wlan0: Gained carrier Jul 22 11:52:31 volumio nmbd[2324]: [2024/07/22 11:52:31.311047, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Jul 22 11:52:31 volumio systemd-networkd[2139]: wlan0: found matching network '/etc/systemd/network/11-wlan0.network', based on potentially unpredictable ifname Jul 22 11:52:31 volumio nmbd[2324]: started asyncdns process 2370 Jul 22 11:52:31 volumio hostapd[2322]: wlan0: interface state UNINITIALIZED->ENABLED Jul 22 11:52:31 volumio nmbd[2324]: [2024/07/22 11:52:31.465990, 0] ../../lib/util/become_daemon.c:150(daemon_status) Jul 22 11:52:31 volumio nmbd[2324]: daemon_status: daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jul 22 11:52:31 volumio nmbd[2324]: [2024/07/22 11:52:31.466425, 0] ../../source3/nmbd/nmbd_subnetdb.c:252(create_subnets) Jul 22 11:52:31 volumio nmbd[2324]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jul 22 11:52:31 volumio hostapd[2322]: wlan0: AP-ENABLED Jul 22 11:52:31 volumio systemd[1]: Started Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 22 11:52:31 volumio rc.local[2372]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Jul 22 11:52:31 volumio set-cpufreq[2133]: Setting ondemand scheduler for all CPUs Jul 22 11:52:31 volumio systemd[1]: ondemand.service: Succeeded. Jul 22 11:52:32 volumio rc.local[2390]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Jul 22 11:52:32 volumio systemd-networkd[2139]: eth0: DHCPv4 address 192.168.23.115/24 via 192.168.23.1 Jul 22 11:52:32 volumio avahi-daemon[2107]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.23.115. Jul 22 11:52:32 volumio avahi-daemon[2107]: New relevant interface eth0.IPv4 for mDNS. Jul 22 11:52:32 volumio avahi-daemon[2107]: Registering new address record for 192.168.23.115 on eth0.IPv4. Jul 22 11:52:32 volumio systemd-timesyncd[2088]: Network configuration changed, trying to establish connection. Jul 22 11:52:32 volumio wireless.js[2291]: Cleaning previous... Jul 22 11:52:32 volumio systemd[1]: Started Samba NMB Daemon. Jul 22 11:52:32 volumio systemd-networkd[2139]: eth0: Gained IPv6LL Jul 22 11:52:32 volumio avahi-daemon[2107]: Joining mDNS multicast group on interface eth0.IPv6 with address fe80::60b3:72ff:fe15:dfbe. Jul 22 11:52:32 volumio systemd-timesyncd[2088]: Network configuration changed, trying to establish connection. Jul 22 11:52:32 volumio avahi-daemon[2107]: New relevant interface eth0.IPv6 for mDNS. Jul 22 11:52:32 volumio avahi-daemon[2107]: Registering new address record for fe80::60b3:72ff:fe15:dfbe on eth0.*. Jul 22 11:52:32 volumio systemd[1]: Starting Samba SMB Daemon... Jul 22 11:52:32 volumio nmbd[2324]: [2024/07/22 11:52:32.575691, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jul 22 11:52:32 volumio nmbd[2324]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.23.115 for name WORKGROUP<1d>. Jul 22 11:52:32 volumio nmbd[2324]: This response was from IP 192.168.23.83, reporting an IP address of 192.168.23.83. Jul 22 11:52:32 volumio rc.local[2402]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Jul 22 11:52:33 volumio avahi-daemon[2107]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80::7edd:90ff:fe39:2a5e. Jul 22 11:52:33 volumio avahi-daemon[2107]: New relevant interface wlan0.IPv6 for mDNS. Jul 22 11:52:33 volumio systemd-networkd[2139]: wlan0: Gained IPv6LL Jul 22 11:52:33 volumio avahi-daemon[2107]: Registering new address record for fe80::7edd:90ff:fe39:2a5e on wlan0.*. Jul 22 11:52:33 volumio systemd-timesyncd[2088]: Network configuration changed, trying to establish connection. Jul 22 11:52:33 volumio wireless.js[2291]: Stopped aP Jul 22 11:52:33 volumio wireless.js[2291]: Start wireless flow Jul 22 11:52:33 volumio wireless.js[2291]: Stopped hotspot (if there).. Jul 22 11:52:33 volumio rc.local[2414]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Jul 22 11:52:33 volumio wireless.js[2291]: DHCP IP Jul 22 11:52:33 volumio wpa_supplicant[2417]: Successfully initialized wpa_supplicant Jul 22 11:52:33 volumio wireless.js[2291]: Start ap Jul 22 11:52:33 volumio wpa_supplicant[2417]: nl80211: Could not configure driver mode Jul 22 11:52:33 volumio wpa_supplicant[2417]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jul 22 11:52:33 volumio systemd-networkd[2139]: wlan0: Lost carrier Jul 22 11:52:33 volumio systemd-timesyncd[2088]: Network configuration changed, trying to establish connection. Jul 22 11:52:34 volumio rc.local[2423]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Jul 22 11:52:34 volumio systemd-networkd[2139]: wlan0: Link DOWN Jul 22 11:52:34 volumio avahi-daemon[2107]: Interface wlan0.IPv6 no longer relevant for mDNS. Jul 22 11:52:34 volumio avahi-daemon[2107]: Leaving mDNS multicast group on interface wlan0.IPv6 with address fe80::7edd:90ff:fe39:2a5e. Jul 22 11:52:34 volumio avahi-daemon[2107]: Withdrawing address record for fe80::7edd:90ff:fe39:2a5e on wlan0. Jul 22 11:52:34 volumio wireless.js[2291]: trying... Jul 22 11:52:34 volumio smbd[2400]: [2024/07/22 11:52:34.472528, 0] ../../source3/smbd/server.c:1734(main) Jul 22 11:52:34 volumio smbd[2400]: smbd version 4.15.13-Ubuntu started. Jul 22 11:52:34 volumio smbd[2400]: Copyright Andrew Tridgell and the Samba Team 1992-2021 Jul 22 11:52:34 volumio systemd-networkd[2139]: wlan0: Link UP Jul 22 11:52:34 volumio systemd-timesyncd[2088]: Network configuration changed, trying to establish connection. Jul 22 11:52:34 volumio systemd-timesyncd[2088]: Network configuration changed, trying to establish connection. Jul 22 11:52:34 volumio wpa_supplicant[2417]: ioctl[SIOCSIWENCODEEXT]: Invalid argument Jul 22 11:52:34 volumio wpa_supplicant[2417]: ioctl[SIOCSIWENCODEEXT]: Invalid argument Jul 22 11:52:34 volumio sudo[2429]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 22 11:52:34 volumio sudo[2429]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 11:52:34 volumio systemd[1]: systemd-rfkill.service: Succeeded. Jul 22 11:52:34 volumio rc.local[2428]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Jul 22 11:52:34 volumio sudo[2429]: pam_unix(sudo:session): session closed for user root Jul 22 11:52:34 volumio systemd[1]: Started Samba SMB Daemon. Jul 22 11:52:35 volumio rc.local[2444]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Jul 22 11:52:35 volumio mpd[2323]: config_file: config parameter "gapless_mp3_playback" on line 19 is deprecated Jul 22 11:52:35 volumio mpd[2323]: config_file: config parameter "buffer_before_play" on line 85 is deprecated Jul 22 11:52:35 volumio mpd[2323]: config_file: config parameter "id3v1_encoding" on line 99 is deprecated Jul 22 11:52:35 volumio mpd[2323]: Jul 22 11:52 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 22 11:52:35 volumio mpd[2323]: Jul 22 11:52 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 22 11:52:35 volumio mpd[2323]: Jul 22 11:52 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 22 11:52:35 volumio systemd[1]: Started Music Player Daemon. Jul 22 11:52:35 volumio udisks-glue[2353]: Updating DB (#1) ... Jul 22 11:52:35 volumio udisks-glue[2353]: volume: n/a repeat: off random: off single: off consume: off Jul 22 11:52:35 volumio wireless.js[2291]: trying... Jul 22 11:52:35 volumio rc.local[2450]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Jul 22 11:52:36 volumio sudo[2452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 22 11:52:36 volumio sudo[2452]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 11:52:36 volumio sudo[2452]: pam_unix(sudo:session): session closed for user root Jul 22 11:52:36 volumio rc.local[2329]: IP is 127.0.0.1 Jul 22 11:52:36 volumio rc.local[2329]: Port is 47670 Jul 22 11:52:36 volumio rc.local[2329]: 8 Jul 22 11:52:36 volumio rc.local[2329]: [2024-07-22 11:52:36] [info] asio async_connect error: asio.system:111 (Connection refused) Jul 22 11:52:36 volumio rc.local[2329]: [2024-07-22 11:52:36] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Jul 22 11:52:36 volumio rc.local[2329]: [2024-07-22 11:52:36] [error] handle_connect error: Connection refused Jul 22 11:52:36 volumio rc.local[2462]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Jul 22 11:52:37 volumio wireless.js[2291]: trying... Jul 22 11:52:37 volumio rc.local[2464]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Jul 22 11:52:37 volumio sudo[2466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 22 11:52:37 volumio sudo[2466]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 11:52:37 volumio sudo[2466]: pam_unix(sudo:session): session closed for user root Jul 22 11:52:37 volumio rc.local[2476]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Jul 22 11:52:38 volumio wireless.js[2291]: trying... Jul 22 11:52:38 volumio rc.local[2478]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Jul 22 11:52:38 volumio sudo[2480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 22 11:52:38 volumio sudo[2480]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 11:52:38 volumio sudo[2480]: pam_unix(sudo:session): session closed for user root Jul 22 11:52:38 volumio rc.local[2490]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Jul 22 11:52:39 volumio rc.local[2492]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Jul 22 11:52:39 volumio wireless.js[2291]: trying... Jul 22 11:52:39 volumio sudo[2494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 22 11:52:39 volumio sudo[2494]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 11:52:39 volumio sudo[2494]: pam_unix(sudo:session): session closed for user root Jul 22 11:52:40 volumio rc.local[2504]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Jul 22 11:52:40 volumio rc.local[2506]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Jul 22 11:52:40 volumio wireless.js[2291]: trying... Jul 22 11:52:40 volumio sudo[2508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 22 11:52:40 volumio sudo[2508]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 11:52:40 volumio sudo[2508]: pam_unix(sudo:session): session closed for user root Jul 22 11:52:41 volumio rc.local[2518]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Jul 22 11:52:41 volumio rc.local[2520]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Jul 22 11:52:41 volumio wireless.js[2291]: trying... Jul 22 11:52:42 volumio sudo[2522]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 22 11:52:42 volumio sudo[2522]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 11:52:42 volumio sudo[2522]: pam_unix(sudo:session): session closed for user root Jul 22 11:52:42 volumio rc.local[2532]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Jul 22 11:52:43 volumio rc.local[2534]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Jul 22 11:52:43 volumio wireless.js[2291]: trying... Jul 22 11:52:43 volumio sudo[2536]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 22 11:52:43 volumio sudo[2536]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 11:52:43 volumio sudo[2536]: pam_unix(sudo:session): session closed for user root Jul 22 11:52:43 volumio rc.local[2546]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Jul 22 11:52:43 volumio rc.local[2329]: [2024-07-22 11:52:43] [info] asio async_connect error: asio.system:111 (Connection refused) Jul 22 11:52:43 volumio rc.local[2329]: [2024-07-22 11:52:43] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Jul 22 11:52:43 volumio rc.local[2329]: [2024-07-22 11:52:43] [error] handle_connect error: Connection refused Jul 22 11:52:44 volumio rc.local[2548]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Jul 22 11:52:44 volumio wireless.js[2291]: trying... Jul 22 11:52:44 volumio sudo[2550]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 22 11:52:44 volumio sudo[2550]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 11:52:44 volumio sudo[2550]: pam_unix(sudo:session): session closed for user root Jul 22 11:52:44 volumio rc.local[2560]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Jul 22 11:52:45 volumio rc.local[2562]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Jul 22 11:52:45 volumio wireless.js[2291]: trying... Jul 22 11:52:45 volumio sudo[2564]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 22 11:52:45 volumio sudo[2564]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 11:52:45 volumio sudo[2564]: pam_unix(sudo:session): session closed for user root Jul 22 11:52:46 volumio rc.local[2574]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Jul 22 11:52:46 volumio rc.local[2576]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Jul 22 11:52:46 volumio wireless.js[2291]: trying... Jul 22 11:52:46 volumio sudo[2578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 22 11:52:46 volumio sudo[2578]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 11:52:46 volumio sudo[2578]: pam_unix(sudo:session): session closed for user root Jul 22 11:52:47 volumio rc.local[2588]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Jul 22 11:52:47 volumio rc.local[2590]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Jul 22 11:52:47 volumio wireless.js[2291]: trying... Jul 22 11:52:47 volumio sudo[2592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 22 11:52:47 volumio sudo[2592]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 11:52:47 volumio sudo[2592]: pam_unix(sudo:session): session closed for user root Jul 22 11:52:48 volumio rc.local[2602]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Jul 22 11:52:48 volumio rc.local[2604]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Jul 22 11:52:48 volumio wireless.js[2291]: trying... Jul 22 11:52:49 volumio sudo[2606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 22 11:52:49 volumio sudo[2606]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 11:52:49 volumio wpa_supplicant[2436]: wlan0: Trying to associate with 14:d8:64:4c:f2:43 (SSID='FORTEX' freq=2412 MHz) Jul 22 11:52:49 volumio wpa_supplicant[2436]: Failed to add supported operating classes IE Jul 22 11:52:49 volumio kernel: wlan0: authenticate with 14:d8:64:4c:f2:43 Jul 22 11:52:49 volumio kernel: wlan0: send auth to 14:d8:64:4c:f2:43 (try 1/3) Jul 22 11:52:49 volumio kernel: wlan0: authenticated Jul 22 11:52:49 volumio sudo[2606]: pam_unix(sudo:session): session closed for user root Jul 22 11:52:49 volumio kernel: wlan0: associate with 14:d8:64:4c:f2:43 (try 1/3) Jul 22 11:52:49 volumio kernel: wlan0: RX AssocResp from 14:d8:64:4c:f2:43 (capab=0x1c11 status=0 aid=2) Jul 22 11:52:49 volumio kernel: wlan0: associated Jul 22 11:52:49 volumio systemd-timesyncd[2088]: Network configuration changed, trying to establish connection. Jul 22 11:52:49 volumio kernel: wlan0: Limiting TX power to 35 (35 - 0) dBm as advertised by 14:d8:64:4c:f2:43 Jul 22 11:52:49 volumio kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready Jul 22 11:52:49 volumio systemd-networkd[2139]: wlan0: Gained carrier Jul 22 11:52:49 volumio wpa_supplicant[2436]: wlan0: Associated with 14:d8:64:4c:f2:43 Jul 22 11:52:49 volumio systemd-networkd[2139]: wlan0: Connected WiFi access point: FORTEX (14:d8:64:4c:f2:43) Jul 22 11:52:49 volumio systemd-networkd[2139]: wlan0: found matching network '/etc/systemd/network/11-wlan0.network', based on potentially unpredictable ifname Jul 22 11:52:49 volumio systemd-networkd[2139]: wlan0: Lost carrier Jul 22 11:52:49 volumio systemd-networkd[2139]: wlan0: Gained carrier Jul 22 11:52:49 volumio wpa_supplicant[2436]: wlan0: WPA: Key negotiation completed with 14:d8:64:4c:f2:43 [PTK=CCMP GTK=CCMP] Jul 22 11:52:49 volumio wpa_supplicant[2436]: wlan0: CTRL-EVENT-CONNECTED - Connection to 14:d8:64:4c:f2:43 completed [id=0 id_str=] Jul 22 11:52:49 volumio systemd-resolved[2309]: Using degraded feature set (UDP) for DNS server 211.136.192.6. Jul 22 11:53:00 volumio systemd-timesyncd[2088]: Initial synchronization to time server 185.125.190.56:123 (ntp.ubuntu.com). Jul 22 11:53:00 volumio rc.local[2620]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Jul 22 11:53:00 volumio systemd-networkd[2139]: wlan0: DHCPv4 address 192.168.23.121/24 via 192.168.23.1 Jul 22 11:53:00 volumio avahi-daemon[2107]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.23.121. Jul 22 11:53:00 volumio avahi-daemon[2107]: New relevant interface wlan0.IPv4 for mDNS. Jul 22 11:53:00 volumio avahi-daemon[2107]: Registering new address record for 192.168.23.121 on wlan0.IPv4. Jul 22 11:53:01 volumio rc.local[2623]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Jul 22 11:53:01 volumio wireless.js[2291]: trying... Jul 22 11:53:01 volumio sudo[2625]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 22 11:53:01 volumio sudo[2625]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 11:53:01 volumio sudo[2625]: pam_unix(sudo:session): session closed for user root Jul 22 11:53:01 volumio wireless.js[2291]: Connected to: ----FORTEX Jul 22 11:53:01 volumio wireless.js[2291]: ---- Jul 22 11:53:01 volumio sudo[2635]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 22 11:53:01 volumio sudo[2635]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 11:53:01 volumio sudo[2635]: pam_unix(sudo:session): session closed for user root Jul 22 11:53:01 volumio wireless.js[2291]: ... joined AP, wlan0 IPv4 is 192.168.23.121, ipV6 is fe80::7edd:90ff:fe39:2a5e Jul 22 11:53:01 volumio wireless.js[2291]: It's done! AP Jul 22 11:53:01 volumio avahi-daemon[2107]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80::7edd:90ff:fe39:2a5e. Jul 22 11:53:01 volumio avahi-daemon[2107]: New relevant interface wlan0.IPv6 for mDNS. Jul 22 11:53:01 volumio systemd-networkd[2139]: wlan0: Gained IPv6LL Jul 22 11:53:01 volumio avahi-daemon[2107]: Registering new address record for fe80::7edd:90ff:fe39:2a5e on wlan0.*. Jul 22 11:53:01 volumio systemd[1]: Started Wireless Services. Jul 22 11:53:01 volumio systemd[1]: Started Volumio Backend Module. Jul 22 11:53:01 volumio systemd[1]: Reached target Multi-User System. Jul 22 11:53:01 volumio systemd[1]: Reached target Graphical Interface. Jul 22 11:53:01 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jul 22 11:53:01 volumio rc.local[2648]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Jul 22 11:53:01 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jul 22 11:53:01 volumio systemd[1]: Finished Update UTMP about System Runlevel Changes. Jul 22 11:53:01 volumio systemd[1]: Startup finished in 3.790s (kernel) + 29.335s (userspace) = 33.126s. Jul 22 11:53:02 volumio rc.local[2656]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Jul 22 11:53:02 volumio kernel: usb0-vbus: disabling Jul 22 11:53:03 volumio rc.local[2658]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Jul 22 11:53:03 volumio rc.local[2660]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Jul 22 11:53:04 volumio rc.local[2662]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Jul 22 11:53:04 volumio rc.local[2664]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Jul 22 11:53:05 volumio rc.local[2666]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Jul 22 11:53:06 volumio rc.local[2329]: [2024-07-22 11:53:06] [connect] Successful connection Jul 22 11:53:07 volumio volumio[2645]: info: ------------------------------------------- Jul 22 11:53:07 volumio volumio[2645]: info: ----- Volumio2 ---- Jul 22 11:53:07 volumio volumio[2645]: info: ------------------------------------------- Jul 22 11:53:07 volumio volumio[2645]: info: ----- System startup ---- Jul 22 11:53:07 volumio volumio[2645]: info: ------------------------------------------- Jul 22 11:53:09 volumio volumio[2645]: info: Plugin folders cleanup Jul 22 11:53:09 volumio volumio[2645]: info: Scanning into folder /volumio/app/plugins/ Jul 22 11:53:09 volumio volumio[2645]: info: Scanning category audio_interface Jul 22 11:53:09 volumio volumio[2645]: info: Scanning category miscellanea Jul 22 11:53:09 volumio volumio[2645]: info: Scanning category music_service Jul 22 11:53:09 volumio volumio[2645]: info: Scanning category plugins.json Jul 22 11:53:09 volumio volumio[2645]: info: Scanning category system_controller Jul 22 11:53:09 volumio volumio[2645]: info: Scanning category user_interface Jul 22 11:53:09 volumio volumio[2645]: info: Scanning into folder /data/plugins/ Jul 22 11:53:09 volumio volumio[2645]: info: Plugin folders cleanup completed Jul 22 11:53:09 volumio volumio[2645]: info: ------------------------------------------- Jul 22 11:53:09 volumio volumio[2645]: info: ----- Core plugins startup ---- Jul 22 11:53:09 volumio volumio[2645]: info: ------------------------------------------- Jul 22 11:53:09 volumio volumio[2645]: info: Loading plugins from folder /volumio/app/plugins/ Jul 22 11:53:09 volumio volumio[2645]: info: Adding plugin upnp to MyMusic Plugins Jul 22 11:53:09 volumio volumio[2645]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 22 11:53:09 volumio volumio[2645]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 22 11:53:09 volumio volumio[2645]: info: Loading plugins from folder /data/plugins/ Jul 22 11:53:09 volumio volumio[2645]: info: Loading plugin "system"... Jul 22 11:53:09 volumio volumio[2645]: info: Loading plugin "appearance"... Jul 22 11:53:16 volumio volumio[2645]: info: Loading plugin "network"... Jul 22 11:53:16 volumio volumio[2645]: info: Refreshing Cached IP Addresses Jul 22 11:53:16 volumio volumio[2645]: info: Loading plugin "services"... Jul 22 11:53:16 volumio sudo[2680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 22 11:53:16 volumio volumio[2645]: info: Loading plugin "alsa_controller"... Jul 22 11:53:16 volumio sudo[2684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 22 11:53:16 volumio sudo[2680]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 11:53:16 volumio sudo[2684]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 11:53:16 volumio sudo[2680]: pam_unix(sudo:session): session closed for user root Jul 22 11:53:16 volumio sudo[2684]: pam_unix(sudo:session): session closed for user root Jul 22 11:53:16 volumio volumio[2645]: info: Loading plugin "wizard"... Jul 22 11:53:16 volumio volumio[2645]: info: Loading plugin "volumio_command_line_client"... Jul 22 11:53:16 volumio volumio[2645]: info: Loading plugin "upnp"... Jul 22 11:53:16 volumio volumio[2645]: info: [1721620396835] Starting Upmpd Daemon Jul 22 11:53:16 volumio volumio[2645]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 22 11:53:16 volumio volumio[2645]: info: Loading plugin "my_music"... Jul 22 11:53:16 volumio volumio[2645]: info: Loading plugin "mpd"... Jul 22 11:53:18 volumio volumio[2645]: info: Loading plugin "upnp_browser"... Jul 22 11:53:19 volumio volumio[2645]: info: Loading plugin "networkfs"... Jul 22 11:53:19 volumio volumio[2645]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 22 11:53:19 volumio volumio[2645]: info: Loading plugin "alarm-clock"... Jul 22 11:53:19 volumio volumio[2645]: info: Loading plugin "airplay_emulation"... Jul 22 11:53:19 volumio volumio[2645]: info: Starting Shairport Sync Jul 22 11:53:20 volumio volumio[2645]: info: Loading plugin "last_100"... Jul 22 11:53:20 volumio volumio[2645]: info: Loading plugin "webradio"... Jul 22 11:53:20 volumio volumio[2645]: info: Loading plugin "i2s_dacs"... Jul 22 11:53:20 volumio volumio[2645]: info: I2S DAC not set, start Auto-detection Jul 22 11:53:20 volumio volumio[2645]: info: Loading plugin "volumiodiscovery"... Jul 22 11:53:20 volumio volumio[2645]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 22 11:53:20 volumio volumio[2645]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 22 11:53:20 volumio node[2645]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 22 11:53:20 volumio volumio[2645]: *** WARNING *** For more information see Jul 22 11:53:20 volumio volumio[2645]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 22 11:53:20 volumio volumio[2645]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 22 11:53:20 volumio volumio[2645]: *** WARNING *** For more information see Jul 22 11:53:20 volumio node[2645]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 22 11:53:20 volumio node[2645]: *** WARNING *** For more information see Jul 22 11:53:20 volumio node[2645]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 22 11:53:20 volumio node[2645]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 22 11:53:20 volumio node[2645]: *** WARNING *** For more information see Jul 22 11:53:20 volumio volumio[2645]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 22 11:53:20 volumio volumio[2645]: Discovery: StartAdv! undefined Jul 22 11:53:20 volumio volumio[2645]: Discovery: Started advertising... Volumio - undefined Jul 22 11:53:20 volumio volumio[2645]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 22 11:53:20 volumio volumio[2645]: info: Loading plugin "outputs"... Jul 22 11:53:20 volumio volumio[2645]: info: Loading plugin "albumart"... Jul 22 11:53:20 volumio volumio[2645]: info: Plugin example_plugin is not enabled Jul 22 11:53:20 volumio volumio[2645]: info: Loading plugin "inputs"... Jul 22 11:53:20 volumio volumio[2645]: info: Loading plugin "updater_comm"... Jul 22 11:53:21 volumio volumio[2709]: Forking 1 albumart workers Jul 22 11:53:21 volumio volumio[2645]: info: Plugin mpdemulation is not enabled Jul 22 11:53:21 volumio volumio[2645]: info: Loading plugin "rest_api"... Jul 22 11:53:21 volumio volumio[2645]: info: Loading plugin "websocket"... Jul 22 11:53:22 volumio volumio[2645]: info: ___________ START PLUGINS ___________ Jul 22 11:53:22 volumio volumio[2645]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 22 11:53:22 volumio volumio[2645]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 22 11:53:22 volumio volumio[2645]: info: [1721620402050] CoreMusicLibrary::Adding element Media Servers Jul 22 11:53:22 volumio volumio[2645]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 22 11:53:22 volumio volumio[2645]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 22 11:53:22 volumio volumio[2645]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 22 11:53:22 volumio volumio[2645]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 22 11:53:22 volumio volumio[2645]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 22 11:53:22 volumio volumio[2645]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 22 11:53:22 volumio volumio[2645]: info: [1721620402524] CoreMusicLibrary::Adding element Last_100 Jul 22 11:53:22 volumio volumio[2645]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 22 11:53:22 volumio volumio[2645]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 22 11:53:22 volumio volumio[2645]: info: [1721620402543] CoreMusicLibrary::Adding element Webradio Jul 22 11:53:22 volumio volumio[2645]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 22 11:53:22 volumio volumio[2645]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 22 11:53:22 volumio volumio[2645]: info: Loading i18n strings for locale zh Jul 22 11:53:22 volumio volumio[2645]: Updating browse sources language Jul 22 11:53:22 volumio volumio[2645]: Cannot find translation for sourceMedia Servers Jul 22 11:53:22 volumio volumio[2645]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 22 11:53:22 volumio volumio[2645]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 22 11:53:22 volumio volumio[2645]: info: CoreCommandRouter::initPlayerControls Jul 22 11:53:22 volumio volumio[2645]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 22 11:53:22 volumio volumio[2645]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 22 11:53:22 volumio volumio[2645]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 22 11:53:22 volumio volumio[2645]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 22 11:53:22 volumio volumio[2645]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 22 11:53:22 volumio volumio[2645]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 22 11:53:22 volumio volumio[2645]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 22 11:53:22 volumio volumio[2715]: Starting albumart workers Jul 22 11:53:22 volumio volumio[2645]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 22 11:53:22 volumio volumio[2645]: info: BOOT COMPLETED Jul 22 11:53:22 volumio volumio[2645]: [Metrics] CommandRouter: 15s 793.76ms Jul 22 11:53:22 volumio volumio[2645]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 22 11:53:23 volumio kernel: sunxi-spdif 2036000.spdif: active: 1 Jul 22 11:53:27 volumio rc.local[2329]: [2024-07-22 11:53:27] [connect] Successful connection Jul 22 11:53:28 volumio volumio[2645]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Jul 22 11:53:28 volumio volumio[2645]: info: CoreCommandRouter::Close All Modals sent Jul 22 11:53:28 volumio volumio[2645]: info: CoreCommandRouter::Close All Modals sent Jul 22 11:53:28 volumio volumio[2645]: Express server listening on port 3000 Jul 22 11:53:28 volumio volumio[2645]: [Metrics] WebUI: 22s 751.12ms Jul 22 11:53:28 volumio volumio[2645]: info: Cannot retrieve data for calling home Jul 22 11:53:28 volumio volumio[2645]: info: CoreStateMachine::resetVolumioState Jul 22 11:53:28 volumio volumio[2645]: info: CoreStateMachine::getcurrentVolume Jul 22 11:53:28 volumio volumio[2645]: info: CoreCommandRouter::volumioRetrievevolume Jul 22 11:53:28 volumio volumio[2645]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 22 11:53:28 volumio sudo[2747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 22 11:53:28 volumio volumio[2645]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jul 22 11:53:29 volumio sudo[2747]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 11:53:29 volumio sudo[2750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 22 11:53:29 volumio sudo[2747]: pam_unix(sudo:session): session closed for user root Jul 22 11:53:29 volumio sudo[2750]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 11:53:29 volumio systemd-resolved[2309]: Using degraded feature set (UDP) for DNS server 211.136.192.6. Jul 22 11:53:29 volumio sudo[2750]: pam_unix(sudo:session): session closed for user root Jul 22 11:53:29 volumio volumio[2645]: info: CoreStateMachine::pushState Jul 22 11:53:29 volumio volumio[2645]: info: CorePlayQueue::getTrack 0 Jul 22 11:53:29 volumio volumio[2645]: info: CoreCommandRouter::volumioPushState Jul 22 11:53:29 volumio volumio[2645]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 22 11:53:29 volumio volumio[2645]: info: CoreStateMachine::updateTrackBlock Jul 22 11:53:29 volumio volumio[2645]: info: CorePlayQueue::getTrackBlock Jul 22 11:53:29 volumio volumio[2645]: info: CoreCommandRouter::volumioRetrievevolume Jul 22 11:53:29 volumio volumio[2645]: info: MPD running with PID2323 Jul 22 11:53:29 volumio volumio[2645]: ,establishing connection Jul 22 11:53:29 volumio volumio[2645]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 22 11:53:29 volumio volumio[2645]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 22 11:53:29 volumio volumio[2645]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 22 11:53:29 volumio rc.local[2329]: [2024-07-22 11:53:29] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1721620407 101 Jul 22 11:53:29 volumio rc.local[2329]: sio connected Jul 22 11:53:29 volumio volumio[2645]: info: Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Jul 22 11:53:29 volumio volumio[2645]: sudo: /usr/sbin/i2cdetect: command not found Jul 22 11:53:29 volumio volumio[2645]: info: CoreStateMachine::pushState Jul 22 11:53:29 volumio volumio[2645]: info: CorePlayQueue::getTrack 0 Jul 22 11:53:29 volumio volumio[2645]: info: CoreCommandRouter::volumioPushState Jul 22 11:53:29 volumio volumio[2645]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 22 11:53:29 volumio volumio[2645]: info: CoreCommandRouter::volumioGetState Jul 22 11:53:29 volumio volumio[2645]: info: CorePlayQueue::getTrack 0 Jul 22 11:53:29 volumio rc.local[2329]: command_len 9 Jul 22 11:53:29 volumio rc.local[2329]: getQueue Jul 22 11:53:29 volumio rc.local[2329]: Jul 22 11:53:29 volumio volumio[2645]: info: CoreCommandRouter::volumioGetQueue Jul 22 11:53:29 volumio volumio[2645]: info: CoreStateMachine::getQueue Jul 22 11:53:29 volumio volumio[2645]: info: CorePlayQueue::getQueue Jul 22 11:53:29 volumio volumio[2645]: info: CoreCommandRouter::volumioGetQueue Jul 22 11:53:29 volumio volumio[2645]: info: CoreStateMachine::getQueue Jul 22 11:53:29 volumio volumio[2645]: info: CorePlayQueue::getQueue Jul 22 11:53:29 volumio rc.local[2329]: socket send Jul 22 11:53:29 volumio rc.local[2329]: socket send Jul 22 11:53:30 volumio volumio[2645]: info: Reloading queue from file Jul 22 11:53:30 volumio volumio[2645]: error: updateQueue error: null Jul 22 11:53:30 volumio volumio[2645]: info: CoreStateMachine::setRepeat null single undefined Jul 22 11:53:30 volumio volumio[2645]: info: CoreStateMachine::pushState Jul 22 11:53:30 volumio volumio[2645]: info: CorePlayQueue::getTrack 0 Jul 22 11:53:30 volumio volumio[2645]: info: CoreCommandRouter::volumioPushState Jul 22 11:53:30 volumio volumio[2645]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 22 11:53:30 volumio volumio[2645]: info: CoreStateMachine::setRandom null Jul 22 11:53:30 volumio volumio[2645]: info: CoreStateMachine::pushState Jul 22 11:53:30 volumio volumio[2645]: info: CorePlayQueue::getTrack 0 Jul 22 11:53:30 volumio volumio[2645]: info: CoreCommandRouter::volumioPushState Jul 22 11:53:30 volumio volumio[2645]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 22 11:53:30 volumio volumio[2645]: Discovery: adding c5f1452e-5715-489b-b731-347381f23cb3 Jul 22 11:53:30 volumio volumio[2645]: info: mDNS: Found device abcd Jul 22 11:53:30 volumio volumio[2645]: error: Could not retrieve plugin audio_interface multiroom Jul 22 11:53:30 volumio volumio[2645]: Discovery: this is already registered, c5f1452e-5715-489b-b731-347381f23cb3 Jul 22 11:53:30 volumio volumio[2645]: info: mDNS: Found device abcd Jul 22 11:53:30 volumio volumio[2645]: info: CoreCommandRouter::volumioGetState Jul 22 11:53:30 volumio volumio[2645]: info: CorePlayQueue::getTrack 0 Jul 22 11:53:30 volumio volumio[2645]: error: Could not retrieve plugin audio_interface multiroom Jul 22 11:53:30 volumio volumio[2645]: error: Could not retrieve plugin audio_interface multiroom Jul 22 11:53:30 volumio volumio[2645]: info: Starting Shairport Sync Jul 22 11:53:30 volumio volumio[2645]: info: Starting Shairport Sync Jul 22 11:53:31 volumio sudo[2784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 22 11:53:31 volumio sudo[2784]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 11:53:31 volumio volumio[2645]: info: CoreCommandRouter::volumioGetState Jul 22 11:53:31 volumio volumio[2645]: info: CorePlayQueue::getTrack 0 Jul 22 11:53:31 volumio sudo[2786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 22 11:53:31 volumio sudo[2786]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 11:53:31 volumio sudo[2792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 22 11:53:31 volumio sudo[2792]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 11:53:31 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 22 11:53:31 volumio sudo[2784]: pam_unix(sudo:session): session closed for user root Jul 22 11:53:31 volumio sudo[2786]: pam_unix(sudo:session): session closed for user root Jul 22 11:53:31 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jul 22 11:53:31 volumio sudo[2792]: pam_unix(sudo:session): session closed for user root Jul 22 11:53:32 volumio volumio[2645]: info: Upmpdcli Daemon Started Jul 22 11:53:32 volumio volumio[2645]: info: Shairport-Sync Started Jul 22 11:53:32 volumio volumio[2645]: Error adding Membership: Error: addMembership EINVAL Jul 22 11:53:32 volumio volumio[2645]: info: Shairport-Sync Started Jul 22 11:53:32 volumio volumio[2645]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 22 11:53:32 volumio volumio[2645]: TypeError: Cannot read property 'UUID' of undefined Jul 22 11:53:32 volumio volumio[2645]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:174:53) Jul 22 11:53:32 volumio volumio[2645]: at emitTwo (events.js:126:13) Jul 22 11:53:32 volumio volumio[2645]: at Browser.emit (events.js:214:7) Jul 22 11:53:32 volumio volumio[2645]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Jul 22 11:53:32 volumio volumio[2645]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Jul 22 11:53:32 volumio volumio[2645]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Jul 22 11:53:32 volumio volumio[2645]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Jul 22 11:53:32 volumio volumio[2645]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Jul 22 11:53:32 volumio volumio[2645]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 22 11:53:34 volumio sudo[2845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-22 11:52 Jul 22 11:53:34 volumio sudo[2845]: pam_unix(sudo:session): session opened for user root by (uid=0) NAME="Ubuntu" VERSION="20.04.5 LTS (Focal Fossa)" ID=ubuntu ID_LIKE=debian PRETTY_NAME="Ubuntu 20.04.5 LTS" VERSION_ID="20.04" HOME_URL="https://www.ubuntu.com/" SUPPORT_URL="https://help.ubuntu.com/" BUG_REPORT_URL="https://bugs.launchpad.net/ubuntu/" PRIVACY_POLICY_URL="https://www.ubuntu.com/legal/terms-and-policies/privacy-policy" VERSION_CODENAME=focal UBUNTU_CODENAME=focal VOLUMIO_BUILD_VERSION="" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="armv7"