-- Logs begin at Tue 2023-03-28 01:54:07 CST, end at Wed 2024-09-04 16:27:58 CST. -- Sep 04 16:27:01 volumio systemd-resolved[2168]: Positive Trust Anchors: Sep 04 16:27:01 volumio systemd-resolved[2168]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 04 16:27:01 volumio systemd-resolved[2168]: 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 Sep 04 16:27:01 volumio systemd[1]: Started Network Time Synchronization. Sep 04 16:27:01 volumio systemd[1]: Reached target System Initialization. Sep 04 16:27:01 volumio systemd[1]: Started Daily Cleanup of Temporary Directories. Sep 04 16:27:01 volumio systemd[1]: Reached target System Time Set. Sep 04 16:27:01 volumio systemd-resolved[2168]: Using system hostname 'volumio'. Sep 04 16:27:01 volumio systemd[1]: Reached target System Time Synchronized. Sep 04 16:27:01 volumio systemd[1]: Started Daily apt download activities. Sep 04 16:27:01 volumio systemd[1]: Started Daily apt upgrade and clean activities. Sep 04 16:27:01 volumio systemd[1]: Started Periodic ext4 Online Metadata Check for All Filesystems. Sep 04 16:27:01 volumio systemd[1]: Started Discard unused blocks once a week. Sep 04 16:27:01 volumio systemd[1]: Started Daily rotation of log files. Sep 04 16:27:01 volumio systemd[1]: Started Message of the Day. Sep 04 16:27:01 volumio systemd[1]: Reached target Timers. Sep 04 16:27:01 volumio systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Sep 04 16:27:01 volumio systemd[1]: Listening on D-Bus System Message Bus Socket. Sep 04 16:27:01 volumio systemd[1]: Listening on mpd.socket. Sep 04 16:27:01 volumio systemd[1]: Reached target Sockets. Sep 04 16:27:01 volumio systemd[1]: Reached target Basic System. Sep 04 16:27:01 volumio kernel: random: crng init done Sep 04 16:27:01 volumio kernel: random: 7 urandom warning(s) missed due to ratelimiting Sep 04 16:27:01 volumio systemd[1]: Condition check resulted in Manage Sound Card State (restore and store) being skipped. Sep 04 16:27:01 volumio systemd[1]: Starting Save/Restore Sound Card State... Sep 04 16:27:01 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Sep 04 16:27:01 volumio systemd[1]: Started D-Bus System Message Bus. Sep 04 16:27:02 volumio avahi-daemon[2175]: Found user 'avahi' (UID 107) and group 'avahi' (GID 114). Sep 04 16:27:02 volumio avahi-daemon[2175]: Successfully dropped root privileges. Sep 04 16:27:02 volumio systemd[1]: Starting Remove Stale Online ext4 Metadata Check Snapshots... Sep 04 16:27:02 volumio avahi-daemon[2175]: avahi-daemon 0.7 starting up. Sep 04 16:27:02 volumio systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Sep 04 16:27:02 volumio systemd[1]: Started Volumio Iptables Module. Sep 04 16:27:02 volumio avahi-daemon[2175]: Successfully called chroot(). Sep 04 16:27:02 volumio systemd[1]: Starting Dispatcher daemon for systemd-networkd... Sep 04 16:27:02 volumio avahi-daemon[2175]: Successfully dropped remaining capabilities. Sep 04 16:27:02 volumio avahi-daemon[2175]: No service file found in /etc/avahi/services. Sep 04 16:27:02 volumio avahi-daemon[2175]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Sep 04 16:27:02 volumio avahi-daemon[2175]: New relevant interface lo.IPv4 for mDNS. Sep 04 16:27:02 volumio avahi-daemon[2175]: Network interface enumeration completed. Sep 04 16:27:02 volumio avahi-daemon[2175]: Registering new address record for 127.0.0.1 on lo.IPv4. Sep 04 16:27:02 volumio systemd[1]: Started Set the CPU Frequency Scaling governor. Sep 04 16:27:02 volumio systemd[1]: Started SW Update Web Service. Sep 04 16:27:02 volumio systemd[2194]: swupdate_web.service: Failed to execute command: Exec format error Sep 04 16:27:02 volumio systemd[2194]: swupdate_web.service: Failed at step EXEC spawning /workdir/swupdate_web.sh: Exec format error Sep 04 16:27:02 volumio systemd[1]: Starting Login Service... Sep 04 16:27:02 volumio systemd[1]: Starting LSB: Start busybox udhcpd at boot time... Sep 04 16:27:02 volumio systemd[1]: Started mount disks automatically with standby. Sep 04 16:27:02 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Sep 04 16:27:02 volumio systemd[1]: Started Volumio First Start Script. Sep 04 16:27:02 volumio systemd[1]: Started Volumio Log Rotation Service. Sep 04 16:27:02 volumio systemd[1]: Started Volumio SSH enabler. Sep 04 16:27:02 volumio systemd[1]: Starting Wireless Services... Sep 04 16:27:02 volumio volumio_first_start.sh[2209]: no find file Sep 04 16:27:02 volumio systemd[1]: Starting WPA supplicant... Sep 04 16:27:02 volumio dbus-daemon[2176]: [system] Activating systemd to hand-off: service name='org.freedesktop.UDisks' unit='udisks.service' requested by ':1.4' (uid=1000 pid=2206 comm="/usr/bin/udisks-glue --foreground ") Sep 04 16:27:02 volumio systemd[1]: Started Network Name Resolution. Sep 04 16:27:02 volumio systemd[1]: Finished Load/Save Random Seed. Sep 04 16:27:02 volumio systemd[1]: e2scrub_reap.service: Succeeded. Sep 04 16:27:02 volumio systemd[1]: Finished Remove Stale Online ext4 Metadata Check Snapshots. Sep 04 16:27:02 volumio systemd[1]: iptables.service: Succeeded. Sep 04 16:27:02 volumio systemd[1]: swupdate_web.service: Main process exited, code=exited, status=203/EXEC Sep 04 16:27:02 volumio systemd[1]: swupdate_web.service: Failed with result 'exit-code'. Sep 04 16:27:02 volumio systemd[1]: volumiossh.service: Succeeded. Sep 04 16:27:02 volumio systemd[1]: Finished Save/Restore Sound Card State. Sep 04 16:27:02 volumio systemd-logind[2199]: New seat seat0. Sep 04 16:27:02 volumio dbus-daemon[2176]: [system] Successfully activated service 'org.freedesktop.systemd1' Sep 04 16:27:02 volumio wpa_supplicant[2213]: Successfully initialized wpa_supplicant Sep 04 16:27:02 volumio systemd[1]: swupdate_web.service: Scheduled restart job, restart counter is at 1. Sep 04 16:27:02 volumio systemd[1]: Started Login Service. Sep 04 16:27:02 volumio udhcpd[2203]: udhcpd: Disabled. Edit /etc/default/udhcpd to enable it. Sep 04 16:27:02 volumio systemd[1]: Started LSB: Start busybox udhcpd at boot time. Sep 04 16:27:02 volumio sudo[2215]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Sep 04 16:27:02 volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Sep 04 16:27:02 volumio systemd[1]: Started WPA supplicant. Sep 04 16:27:02 volumio sudo[2215]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 16:27:02 volumio systemd[1]: Reached target Network. Sep 04 16:27:02 volumio systemd[1]: Reached target Network is Online. Sep 04 16:27:03 volumio systemd[1]: Reached target Host and Network Name Lookups. Sep 04 16:27:03 volumio avahi-daemon[2175]: Server startup complete. Host name is volumio.local. Local service cookie is 2352275645. Sep 04 16:27:03 volumio wifistart.sh[2257]: command failed: No such device (-19) Sep 04 16:27:03 volumio systemd[1]: Reached target Sound Card. Sep 04 16:27:03 volumio sudo[2215]: pam_unix(sudo:session): session closed for user root Sep 04 16:27:03 volumio systemd[1]: Starting Music Player Daemon... Sep 04 16:27:03 volumio volumio[2208]: Could not open config: /tmp/upmpdcli.conf Sep 04 16:27:03 volumio systemd[1]: Starting Samba NMB Daemon... Sep 04 16:27:03 volumio systemd[1]: Started NQPTP -- Not Quite PTP. Sep 04 16:27:03 volumio systemd[1]: Starting /etc/rc.local Compatibility... Sep 04 16:27:03 volumio systemd[1]: Stopped SW Update Web Service. Sep 04 16:27:03 volumio systemd[1]: Started SW Update Web Service. Sep 04 16:27:03 volumio systemd[2294]: swupdate_web.service: Failed to execute command: Exec format error Sep 04 16:27:03 volumio systemd[2294]: swupdate_web.service: Failed at step EXEC spawning /workdir/swupdate_web.sh: Exec format error Sep 04 16:27:03 volumio systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Sep 04 16:27:03 volumio systemd[1]: Starting Permit User Sessions... Sep 04 16:27:03 volumio systemd-networkd[2059]: eth0: Gained carrier Sep 04 16:27:03 volumio kernel: sunxi-gmac 4500000.eth eth0: Link is Up - 1Gbps/Full - flow control off Sep 04 16:27:03 volumio kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Sep 04 16:27:03 volumio systemd[1]: Starting Disk Manager (legacy version)... Sep 04 16:27:03 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Sep 04 16:27:03 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Sep 04 16:27:03 volumio systemd[1]: Started /etc/rc.local Compatibility. Sep 04 16:27:03 volumio systemd[1]: swupdate_web.service: Main process exited, code=exited, status=203/EXEC Sep 04 16:27:03 volumio systemd[1]: swupdate_web.service: Failed with result 'exit-code'. Sep 04 16:27:03 volumio dbus-daemon[2176]: [system] Successfully activated service 'org.freedesktop.UDisks' Sep 04 16:27:03 volumio systemd[1]: Finished Permit User Sessions. Sep 04 16:27:03 volumio systemd[1]: Started Disk Manager (legacy version). Sep 04 16:27:03 volumio systemd[1]: Started Getty on tty1. Sep 04 16:27:03 volumio dbus-daemon[2176]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.9' (uid=0 pid=2296 comm="/usr/lib/udisks/udisks-daemon --no-debug ") Sep 04 16:27:03 volumio systemd[1]: Started Serial Getty on ttyAS0. Sep 04 16:27:03 volumio systemd[1]: Reached target Login Prompts. Sep 04 16:27:03 volumio systemd[1]: swupdate_web.service: Scheduled restart job, restart counter is at 2. Sep 04 16:27:03 volumio systemd[1]: Starting Authorization Manager... Sep 04 16:27:03 volumio systemd[1]: Stopped SW Update Web Service. Sep 04 16:27:03 volumio systemd[1]: Started SW Update Web Service. Sep 04 16:27:03 volumio systemd[2305]: swupdate_web.service: Failed to execute command: Exec format error Sep 04 16:27:03 volumio systemd[2305]: swupdate_web.service: Failed at step EXEC spawning /workdir/swupdate_web.sh: Exec format error Sep 04 16:27:03 volumio systemd[1]: swupdate_web.service: Main process exited, code=exited, status=203/EXEC Sep 04 16:27:03 volumio systemd[1]: swupdate_web.service: Failed with result 'exit-code'. Sep 04 16:27:03 volumio polkitd[2303]: started daemon version 0.105 using authority implementation `local' version `0.105' Sep 04 16:27:03 volumio dbus-daemon[2176]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Sep 04 16:27:03 volumio systemd[1]: Started Authorization Manager. Sep 04 16:27:04 volumio systemd-networkd[2059]: eth0: DHCPv4 address 192.168.23.181/24 via 192.168.23.1 Sep 04 16:27:04 volumio avahi-daemon[2175]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.23.181. Sep 04 16:27:04 volumio avahi-daemon[2175]: New relevant interface eth0.IPv4 for mDNS. Sep 04 16:27:04 volumio avahi-daemon[2175]: Registering new address record for 192.168.23.181 on eth0.IPv4. Sep 04 16:27:04 volumio systemd-timesyncd[2169]: Network configuration changed, trying to establish connection. Sep 04 16:27:04 volumio systemd[1]: swupdate_web.service: Scheduled restart job, restart counter is at 3. Sep 04 16:27:04 volumio systemd[1]: Stopped SW Update Web Service. Sep 04 16:27:04 volumio systemd[1]: Started SW Update Web Service. Sep 04 16:27:04 volumio systemd[2310]: swupdate_web.service: Failed to execute command: Exec format error Sep 04 16:27:04 volumio systemd[2310]: swupdate_web.service: Failed at step EXEC spawning /workdir/swupdate_web.sh: Exec format error Sep 04 16:27:04 volumio systemd[1]: swupdate_web.service: Main process exited, code=exited, status=203/EXEC Sep 04 16:27:04 volumio systemd[1]: swupdate_web.service: Failed with result 'exit-code'. Sep 04 16:27:04 volumio udisks-glue[2206]: Device file /dev/mmcblk0p6 inserted Sep 04 16:27:04 volumio udisks-glue[2206]: Device file /dev/mmcblk0 inserted Sep 04 16:27:04 volumio avahi-daemon[2175]: Withdrawing address record for 127.0.0.1 on lo. Sep 04 16:27:04 volumio avahi-daemon[2175]: Host name conflict, retrying with volumio-2 Sep 04 16:27:04 volumio avahi-daemon[2175]: Registering new address record for 192.168.23.181 on eth0.IPv4. Sep 04 16:27:04 volumio avahi-daemon[2175]: Registering new address record for 127.0.0.1 on lo.IPv4. Sep 04 16:27:04 volumio udisks-glue[2206]: Device file /dev/mmcblk0p4 inserted Sep 04 16:27:04 volumio udisks-glue[2206]: Device file /dev/mmcblk0p3 inserted Sep 04 16:27:04 volumio udisks-glue[2206]: Device file /dev/mmcblk0p2 inserted Sep 04 16:27:04 volumio nmbd[2275]: [2024/09/04 16:27:04.426550, 0] ../../source3/nmbd/nmbd.c:901(main) Sep 04 16:27:04 volumio nmbd[2275]: nmbd version 4.15.13-Ubuntu started. Sep 04 16:27:04 volumio nmbd[2275]: Copyright Andrew Tridgell and the Samba Team 1992-2021 Sep 04 16:27:04 volumio udisks-glue[2206]: Device file /dev/mmcblk0p8 inserted Sep 04 16:27:04 volumio systemd[1]: swupdate_web.service: Scheduled restart job, restart counter is at 4. Sep 04 16:27:04 volumio udisks-glue[2206]: Device file /dev/mmcblk0p1 inserted Sep 04 16:27:04 volumio udisks-glue[2206]: Trying to automount /dev/mmcblk0p1... Sep 04 16:27:04 volumio systemd[1]: Stopped SW Update Web Service. Sep 04 16:27:04 volumio nmbd[2275]: [2024/09/04 16:27:04.481841, 0] ../../source3/nmbd/asyncdns.c:158(start_async_dns) Sep 04 16:27:19 volumio systemd-timesyncd[2169]: Initial synchronization to time server 91.189.91.157:123 (ntp.ubuntu.com). Sep 04 16:27:19 volumio nmbd[2275]: started asyncdns process 2316 Sep 04 16:27:19 volumio systemd[1]: Started SW Update Web Service. Sep 04 16:27:19 volumio systemd[1]: Started Samba NMB Daemon. Sep 04 16:27:19 volumio systemd[2317]: swupdate_web.service: Failed to execute command: Exec format error Sep 04 16:27:19 volumio systemd[2317]: swupdate_web.service: Failed at step EXEC spawning /workdir/swupdate_web.sh: Exec format error Sep 04 16:27:19 volumio systemd[1]: swupdate_web.service: Main process exited, code=exited, status=203/EXEC Sep 04 16:27:19 volumio systemd[1]: swupdate_web.service: Failed with result 'exit-code'. Sep 04 16:27:19 volumio nmbd[2275]: [2024/09/04 16:27:19.687713, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Sep 04 16:27:19 volumio nmbd[2275]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.23.181 for name WORKGROUP<1d>. Sep 04 16:27:19 volumio nmbd[2275]: This response was from IP 192.168.23.83, reporting an IP address of 192.168.23.83. Sep 04 16:27:19 volumio systemd[1]: Starting Samba SMB Daemon... Sep 04 16:27:19 volumio avahi-daemon[2175]: Joining mDNS multicast group on interface eth0.IPv6 with address fe80::ac52:18ff:fee0:1f7. Sep 04 16:27:19 volumio systemd-networkd[2059]: eth0: Gained IPv6LL Sep 04 16:27:19 volumio avahi-daemon[2175]: New relevant interface eth0.IPv6 for mDNS. Sep 04 16:27:19 volumio avahi-daemon[2175]: Registering new address record for fe80::ac52:18ff:fee0:1f7 on eth0.*. Sep 04 16:27:19 volumio avahi-daemon[2175]: Withdrawing address record for 192.168.23.181 on eth0. Sep 04 16:27:19 volumio avahi-daemon[2175]: Withdrawing address record for 127.0.0.1 on lo. Sep 04 16:27:19 volumio avahi-daemon[2175]: Host name conflict, retrying with volumio-3 Sep 04 16:27:19 volumio avahi-daemon[2175]: Registering new address record for fe80::ac52:18ff:fee0:1f7 on eth0.*. Sep 04 16:27:19 volumio avahi-daemon[2175]: Registering new address record for 192.168.23.181 on eth0.IPv4. Sep 04 16:27:19 volumio avahi-daemon[2175]: Registering new address record for 127.0.0.1 on lo.IPv4. Sep 04 16:27:20 volumio systemd[1]: swupdate_web.service: Scheduled restart job, restart counter is at 5. Sep 04 16:27:20 volumio systemd[1]: Stopped SW Update Web Service. Sep 04 16:27:20 volumio systemd[1]: swupdate_web.service: Start request repeated too quickly. Sep 04 16:27:20 volumio systemd[1]: swupdate_web.service: Failed with result 'exit-code'. Sep 04 16:27:20 volumio systemd[1]: Failed to start SW Update Web Service. Sep 04 16:27:20 volumio kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Sep 04 16:27:20 volumio udisks-glue[2206]: Successfully automounted /dev/mmcblk0p1 at /media/Volumn_____ Sep 04 16:27:20 volumio udisks-glue[2206]: Device file /dev/mmcblk0p7 inserted Sep 04 16:27:20 volumio udisks-glue[2206]: Device file /dev/mmcblk0p1 mounted at /media/Volumn_____ Sep 04 16:27:20 volumio systemd[1]: Started Dispatcher daemon for systemd-networkd. Sep 04 16:27:20 volumio nmbd[2275]: [2024/09/04 16:27:20.605072, 0] ../../source3/nmbd/nmbd_mynames.c:104(my_name_register_failed) Sep 04 16:27:20 volumio nmbd[2275]: my_name_register_failed: Failed to register my name VOLUMIO<20> on subnet 192.168.23.181. Sep 04 16:27:20 volumio nmbd[2275]: [2024/09/04 16:27:20.605643, 0] ../../source3/nmbd/nmbd_namelistdb.c:319(standard_fail_register) Sep 04 16:27:20 volumio nmbd[2275]: standard_fail_register: Failed to register/refresh name VOLUMIO<20> on subnet 192.168.23.181 Sep 04 16:27:20 volumio nmbd[2275]: [2024/09/04 16:27:20.605965, 0] ../../source3/nmbd/nmbd_mynames.c:104(my_name_register_failed) Sep 04 16:27:20 volumio nmbd[2275]: my_name_register_failed: Failed to register my name VOLUMIO<03> on subnet 192.168.23.181. Sep 04 16:27:20 volumio nmbd[2275]: [2024/09/04 16:27:20.606415, 0] ../../source3/nmbd/nmbd_namelistdb.c:319(standard_fail_register) Sep 04 16:27:20 volumio nmbd[2275]: standard_fail_register: Failed to register/refresh name VOLUMIO<03> on subnet 192.168.23.181 Sep 04 16:27:20 volumio nmbd[2275]: [2024/09/04 16:27:20.606715, 0] ../../source3/nmbd/nmbd_mynames.c:104(my_name_register_failed) Sep 04 16:27:20 volumio nmbd[2275]: my_name_register_failed: Failed to register my name VOLUMIO<00> on subnet 192.168.23.181. Sep 04 16:27:20 volumio nmbd[2275]: [2024/09/04 16:27:20.607001, 0] ../../source3/nmbd/nmbd_namelistdb.c:319(standard_fail_register) Sep 04 16:27:20 volumio nmbd[2275]: standard_fail_register: Failed to register/refresh name VOLUMIO<00> on subnet 192.168.23.181 Sep 04 16:27:20 volumio smbd[2320]: [2024/09/04 16:27:20.625079, 0] ../../source3/smbd/server.c:1734(main) Sep 04 16:27:20 volumio smbd[2320]: smbd version 4.15.13-Ubuntu started. Sep 04 16:27:20 volumio smbd[2320]: Copyright Andrew Tridgell and the Samba Team 1992-2021 Sep 04 16:27:20 volumio avahi-daemon[2175]: Withdrawing address record for fe80::ac52:18ff:fee0:1f7 on eth0. Sep 04 16:27:20 volumio avahi-daemon[2175]: Withdrawing address record for 192.168.23.181 on eth0. Sep 04 16:27:20 volumio avahi-daemon[2175]: Host name conflict, retrying with volumio-4 Sep 04 16:27:20 volumio avahi-daemon[2175]: Registering new address record for fe80::ac52:18ff:fee0:1f7 on eth0.*. Sep 04 16:27:20 volumio avahi-daemon[2175]: Registering new address record for 192.168.23.181 on eth0.IPv4. Sep 04 16:27:20 volumio avahi-daemon[2175]: Registering new address record for 127.0.0.1 on lo.IPv4. Sep 04 16:27:21 volumio systemd[1]: Started Samba SMB Daemon. Sep 04 16:27:22 volumio wireless.js[2297]: WIRELESS: No wireless interface, exiting Sep 04 16:27:22 volumio systemd[1]: wireless.service: Control process exited, code=exited, status=1/FAILURE Sep 04 16:27:22 volumio systemd[1]: wireless.service: Failed with result 'exit-code'. Sep 04 16:27:22 volumio systemd[1]: Failed to start Wireless Services. Sep 04 16:27:22 volumio systemd[1]: Started Volumio Backend Module. Sep 04 16:27:22 volumio systemd[1]: Started SocketIO Bridge Service. Sep 04 16:27:22 volumio systemd[1]: Started Demo Service. Sep 04 16:27:22 volumio socketio[2336]: websocket connect Sep 04 16:27:22 volumio socketio[2336]: Error: No active session Sep 04 16:27:22 volumio socketio[2336]: [2024-09-04 16:27:22] [info] asio async_connect error: asio.system:111 (Connection refused) Sep 04 16:27:22 volumio socketio[2336]: [2024-09-04 16:27:22] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Sep 04 16:27:22 volumio socketio[2336]: [2024-09-04 16:27:22] [error] handle_connect error: Connection refused Sep 04 16:27:22 volumio demo[2342]: opt = r Sep 04 16:27:22 volumio demo[2342]: optarg = 90 Sep 04 16:27:22 volumio demo[2342]: optind = 3 Sep 04 16:27:22 volumio demo[2342]: argv[optind - 1] = 90 Sep 04 16:27:22 volumio demo[2342]: opt = t Sep 04 16:27:22 volumio demo[2342]: optarg = /dev/ttyAS2 Sep 04 16:27:22 volumio demo[2342]: optind = 5 Sep 04 16:27:22 volumio demo[2342]: argv[optind - 1] = /dev/ttyAS2 Sep 04 16:27:22 volumio demo[2342]: tty driver /dev/ttyAS2 Sep 04 16:27:22 volumio demo[2342]: SCREEN_SIZE_960X376 Sep 04 16:27:22 volumio set-cpufreq[2189]: Setting ondemand scheduler for all CPUs Sep 04 16:27:22 volumio systemd[1]: ondemand.service: Succeeded. Sep 04 16:27:22 volumio demo[2355]: cat: /workdir/version.txt: No such file or directory Sep 04 16:27:22 volumio avahi-daemon[2175]: Server startup complete. Host name is volumio-4.local. Local service cookie is 2352275645. Sep 04 16:27:22 volumio demo[2366]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Sep 04 16:27:23 volumio mpd[2272]: config_file: config parameter "gapless_mp3_playback" on line 19 is deprecated Sep 04 16:27:23 volumio mpd[2272]: config_file: config parameter "buffer_before_play" on line 102 is deprecated Sep 04 16:27:23 volumio mpd[2272]: config_file: config parameter "id3v1_encoding" on line 116 is deprecated Sep 04 16:27:23 volumio mpd[2272]: Sep 04 16:27 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 04 16:27:23 volumio mpd[2272]: Sep 04 16:27 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Sep 04 16:27:23 volumio mpd[2272]: Sep 04 16:27 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Sep 04 16:27:23 volumio systemd[1]: Started Music Player Daemon. Sep 04 16:27:23 volumio systemd[1]: Reached target Multi-User System. Sep 04 16:27:23 volumio systemd[1]: Reached target Graphical Interface. Sep 04 16:27:23 volumio udisks-glue[2324]: Updating DB (#1) ... Sep 04 16:27:23 volumio udisks-glue[2324]: volume:100% repeat: off random: off single: off consume: off Sep 04 16:27:23 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Sep 04 16:27:23 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Sep 04 16:27:23 volumio systemd[1]: Finished Update UTMP about System Runlevel Changes. Sep 04 16:27:23 volumio systemd[1]: Startup finished in 3.811s (kernel) + 13.494s (userspace) = 17.305s. Sep 04 16:27:23 volumio demo[2375]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Sep 04 16:27:24 volumio demo[2378]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Sep 04 16:27:24 volumio demo[2380]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Sep 04 16:27:25 volumio demo[2382]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Sep 04 16:27:25 volumio demo[2384]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused Sep 04 16:27:27 volumio socketio[2336]: IP is 127.0.0.1 Sep 04 16:27:27 volumio socketio[2336]: Port is 47894 Sep 04 16:27:27 volumio socketio[2336]: 8 Sep 04 16:27:27 volumio socketio[2336]: [2024-09-04 16:27:27] [connect] Successful connection Sep 04 16:27:27 volumio volumio[2335]: info: ------------------------------------------- Sep 04 16:27:27 volumio volumio[2335]: info: ----- Volumio2 ---- Sep 04 16:27:27 volumio volumio[2335]: info: ------------------------------------------- Sep 04 16:27:27 volumio volumio[2335]: info: ----- System startup ---- Sep 04 16:27:27 volumio volumio[2335]: info: ------------------------------------------- Sep 04 16:27:29 volumio volumio[2335]: info: Found new core plugin music_service/youtube2. Adding it Sep 04 16:27:29 volumio volumio[2335]: info: Configured plugin music_service/youtube2 cannot be loaded. Removing from configuration Sep 04 16:27:29 volumio volumio[2335]: info: Plugin folders cleanup Sep 04 16:27:29 volumio volumio[2335]: info: Scanning into folder /volumio/app/plugins/ Sep 04 16:27:29 volumio volumio[2335]: info: Scanning category audio_interface Sep 04 16:27:29 volumio volumio[2335]: info: Scanning category miscellanea Sep 04 16:27:30 volumio volumio[2335]: info: Scanning category music_service Sep 04 16:27:30 volumio volumio[2335]: info: Scanning category plugins.json Sep 04 16:27:30 volumio volumio[2335]: info: Scanning category system_controller Sep 04 16:27:30 volumio volumio[2335]: info: Scanning category user_interface Sep 04 16:27:30 volumio volumio[2335]: info: Scanning into folder /data/plugins/ Sep 04 16:27:30 volumio volumio[2335]: info: Plugin folders cleanup completed Sep 04 16:27:30 volumio volumio[2335]: info: ------------------------------------------- Sep 04 16:27:30 volumio volumio[2335]: info: ----- Core plugins startup ---- Sep 04 16:27:30 volumio volumio[2335]: info: ------------------------------------------- Sep 04 16:27:30 volumio volumio[2335]: info: Loading plugins from folder /volumio/app/plugins/ Sep 04 16:27:30 volumio volumio[2335]: info: Adding plugin upnp to MyMusic Plugins Sep 04 16:27:30 volumio volumio[2335]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 04 16:27:30 volumio volumio[2335]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 04 16:27:30 volumio volumio[2335]: info: Loading plugins from folder /data/plugins/ Sep 04 16:27:30 volumio volumio[2335]: info: Loading plugin "system"... Sep 04 16:27:30 volumio volumio[2335]: info: Loading plugin "appearance"... Sep 04 16:27:30 volumio login[2302]: pam_unix(login:session): session opened for user root by LOGIN(uid=0) Sep 04 16:27:30 volumio systemd-logind[2199]: New session c1 of user root. Sep 04 16:27:30 volumio systemd[1]: Created slice User Slice of UID 0. Sep 04 16:27:30 volumio systemd[1]: Starting User Runtime Directory /run/user/0... Sep 04 16:27:30 volumio systemd[1]: Finished User Runtime Directory /run/user/0. Sep 04 16:27:30 volumio systemd[1]: Starting User Manager for UID 0... Sep 04 16:27:30 volumio systemd[2409]: pam_unix(systemd-user:session): session opened for user root by (uid=0) Sep 04 16:27:31 volumio systemd[2409]: Reached target Paths. Sep 04 16:27:31 volumio systemd[2409]: Reached target Timers. Sep 04 16:27:31 volumio systemd[2409]: Listening on GnuPG network certificate management daemon. Sep 04 16:27:31 volumio systemd[2409]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). Sep 04 16:27:31 volumio systemd[2409]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). Sep 04 16:27:31 volumio systemd[2409]: Listening on GnuPG cryptographic agent (ssh-agent emulation). Sep 04 16:27:31 volumio systemd[2409]: Listening on GnuPG cryptographic agent and passphrase cache. Sep 04 16:27:31 volumio systemd[2417]: mpd.socket: Failed to create listening socket ([::]:6600): Address already in use Sep 04 16:27:31 volumio systemd[2409]: mpd.socket: Failed to receive listening socket ([::]:6600): Input/output error Sep 04 16:27:31 volumio systemd[2409]: mpd.socket: Failed to listen on sockets: Input/output error Sep 04 16:27:31 volumio systemd[2409]: mpd.socket: Failed with result 'resources'. Sep 04 16:27:31 volumio systemd[2409]: Failed to listen on mpd.socket. Sep 04 16:27:31 volumio systemd[2409]: Reached target Sockets. Sep 04 16:27:31 volumio systemd[2409]: Reached target Basic System. Sep 04 16:27:31 volumio systemd[1]: Started User Manager for UID 0. Sep 04 16:27:31 volumio systemd[2409]: Starting Music Player Daemon... Sep 04 16:27:31 volumio systemd[1]: Started Session c1 of user root. Sep 04 16:27:31 volumio login[2422]: ROOT LOGIN on '/dev/ttyAS0' Sep 04 16:27:33 volumio mpd[2418]: config_file: config parameter "gapless_mp3_playback" on line 19 is deprecated Sep 04 16:27:33 volumio mpd[2418]: config_file: config parameter "buffer_before_play" on line 102 is deprecated Sep 04 16:27:33 volumio mpd[2418]: config_file: config parameter "id3v1_encoding" on line 116 is deprecated Sep 04 16:27:33 volumio mpd[2418]: Sep 04 16:27 : exception: Failed to bind to '[::]:6600' Sep 04 16:27:33 volumio mpd[2418]: Sep 04 16:27 : exception: nested: Failed to bind socket: Address already in use Sep 04 16:27:33 volumio systemd[2409]: mpd.service: Main process exited, code=exited, status=1/FAILURE Sep 04 16:27:33 volumio systemd[2409]: mpd.service: Failed with result 'exit-code'. Sep 04 16:27:33 volumio systemd[2409]: Failed to start Music Player Daemon. Sep 04 16:27:33 volumio systemd[2409]: Reached target Main User Target. Sep 04 16:27:33 volumio systemd[2409]: Startup finished in 2.902s. Sep 04 16:27:35 volumio sudo[2430]: root : TTY=ttyAS0 ; PWD=/root ; USER=root ; COMMAND=/sbin/ifconfig Sep 04 16:27:35 volumio sudo[2430]: pam_unix(sudo:session): session opened for user root by root(uid=0) Sep 04 16:27:35 volumio sudo[2430]: pam_unix(sudo:session): session closed for user root Sep 04 16:27:38 volumio volumio[2335]: info: Loading plugin "network"... Sep 04 16:27:38 volumio volumio[2335]: info: Refreshing Cached IP Addresses Sep 04 16:27:38 volumio volumio[2335]: info: Loading plugin "services"... Sep 04 16:27:38 volumio volumio[2335]: info: Plugin sources is not enabled Sep 04 16:27:38 volumio volumio[2335]: info: Loading plugin "alsa_controller"... Sep 04 16:27:38 volumio sudo[2440]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 04 16:27:38 volumio sudo[2442]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 04 16:27:38 volumio sudo[2444]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Sep 04 16:27:38 volumio sudo[2440]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 16:27:38 volumio sudo[2442]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 16:27:38 volumio sudo[2444]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 16:27:38 volumio sudo[2440]: pam_unix(sudo:session): session closed for user root Sep 04 16:27:38 volumio sudo[2444]: pam_unix(sudo:session): session closed for user root Sep 04 16:27:38 volumio sudo[2442]: pam_unix(sudo:session): session closed for user root Sep 04 16:27:38 volumio volumio[2335]: info: Loading plugin "wizard"... Sep 04 16:27:38 volumio volumio[2335]: info: Loading plugin "volumio_command_line_client"... Sep 04 16:27:38 volumio volumio[2335]: info: Loading plugin "upnp"... Sep 04 16:27:38 volumio volumio[2335]: info: [1725438458741] Starting Upmpd Daemon Sep 04 16:27:38 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 04 16:27:38 volumio volumio[2335]: info: Loading plugin "my_music"... Sep 04 16:27:38 volumio volumio[2335]: info: Loading plugin "mpd"... Sep 04 16:27:39 volumio volumio[2335]: info: Creating MPD Configuration file Sep 04 16:27:39 volumio sudo[2476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 04 16:27:39 volumio volumio[2335]: info: Loading plugin "upnp_browser"... Sep 04 16:27:39 volumio kernel: usb0-vbus: disabling Sep 04 16:27:39 volumio socketio[2336]: [2024-09-04 16:27:39] [connect] Successful connection Sep 04 16:27:39 volumio sudo[2476]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 16:27:39 volumio sudo[2476]: pam_unix(sudo:session): session closed for user root Sep 04 16:27:39 volumio sudo[2480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 04 16:27:39 volumio sudo[2480]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 16:27:39 volumio volumio[2335]: error: !!!! WARNING !!!! Sep 04 16:27:39 volumio volumio[2335]: error: The plugin music_service/upnp_browser failed to load, setting it to stopped. Error: Error: Could not locate the bindings file. Tried: Sep 04 16:27:39 volumio volumio[2335]: → /volumio/node_modules/libxmljs/build/xmljs.node Sep 04 16:27:39 volumio volumio[2335]: → /volumio/node_modules/libxmljs/build/Debug/xmljs.node Sep 04 16:27:39 volumio volumio[2335]: → /volumio/node_modules/libxmljs/build/Release/xmljs.node Sep 04 16:27:39 volumio volumio[2335]: → /volumio/node_modules/libxmljs/out/Debug/xmljs.node Sep 04 16:27:39 volumio volumio[2335]: → /volumio/node_modules/libxmljs/Debug/xmljs.node Sep 04 16:27:39 volumio volumio[2335]: → /volumio/node_modules/libxmljs/out/Release/xmljs.node Sep 04 16:27:39 volumio volumio[2335]: → /volumio/node_modules/libxmljs/Release/xmljs.node Sep 04 16:27:39 volumio volumio[2335]: → /volumio/node_modules/libxmljs/build/default/xmljs.node Sep 04 16:27:39 volumio volumio[2335]: → /volumio/node_modules/libxmljs/compiled/10.24.1/linux/arm/xmljs.node Sep 04 16:27:39 volumio volumio[2335]: → /volumio/node_modules/libxmljs/addon-build/release/install-root/xmljs.node Sep 04 16:27:39 volumio volumio[2335]: → /volumio/node_modules/libxmljs/addon-build/debug/install-root/xmljs.node Sep 04 16:27:39 volumio volumio[2335]: → /volumio/node_modules/libxmljs/addon-build/default/install-root/xmljs.node Sep 04 16:27:39 volumio volumio[2335]: → /volumio/node_modules/libxmljs/lib/binding/node-v64-linux-arm/xmljs.node Sep 04 16:27:39 volumio volumio[2335]: error: Stack trace: Error: Could not locate the bindings file. Tried: Sep 04 16:27:39 volumio volumio[2335]: → /volumio/node_modules/libxmljs/build/xmljs.node Sep 04 16:27:39 volumio volumio[2335]: → /volumio/node_modules/libxmljs/build/Debug/xmljs.node Sep 04 16:27:39 volumio volumio[2335]: → /volumio/node_modules/libxmljs/build/Release/xmljs.node Sep 04 16:27:39 volumio volumio[2335]: → /volumio/node_modules/libxmljs/out/Debug/xmljs.node Sep 04 16:27:39 volumio volumio[2335]: → /volumio/node_modules/libxmljs/Debug/xmljs.node Sep 04 16:27:39 volumio volumio[2335]: → /volumio/node_modules/libxmljs/out/Release/xmljs.node Sep 04 16:27:39 volumio volumio[2335]: → /volumio/node_modules/libxmljs/Release/xmljs.node Sep 04 16:27:39 volumio volumio[2335]: → /volumio/node_modules/libxmljs/build/default/xmljs.node Sep 04 16:27:39 volumio volumio[2335]: → /volumio/node_modules/libxmljs/compiled/10.24.1/linux/arm/xmljs.node Sep 04 16:27:39 volumio volumio[2335]: → /volumio/node_modules/libxmljs/addon-build/release/install-root/xmljs.node Sep 04 16:27:39 volumio volumio[2335]: → /volumio/node_modules/libxmljs/addon-build/debug/install-root/xmljs.node Sep 04 16:27:39 volumio volumio[2335]: → /volumio/node_modules/libxmljs/addon-build/default/install-root/xmljs.node Sep 04 16:27:39 volumio volumio[2335]: → /volumio/node_modules/libxmljs/lib/binding/node-v64-linux-arm/xmljs.node Sep 04 16:27:39 volumio volumio[2335]: at bindings (/volumio/node_modules/bindings/bindings.js:126:9) Sep 04 16:27:39 volumio volumio[2335]: at Object. (/volumio/node_modules/libxmljs/lib/bindings.js:1:37) Sep 04 16:27:39 volumio volumio[2335]: at Module._compile (internal/modules/cjs/loader.js:778:30) Sep 04 16:27:39 volumio volumio[2335]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:789:10) Sep 04 16:27:39 volumio volumio[2335]: at Module.load (internal/modules/cjs/loader.js:653:32) Sep 04 16:27:39 volumio volumio[2335]: at tryModuleLoad (internal/modules/cjs/loader.js:593:12) Sep 04 16:27:39 volumio volumio[2335]: at Function.Module._load (internal/modules/cjs/loader.js:585:3) Sep 04 16:27:39 volumio volumio[2335]: at Module.require (internal/modules/cjs/loader.js:692:17) Sep 04 16:27:39 volumio volumio[2335]: at require (internal/modules/cjs/helpers.js:25:18) Sep 04 16:27:39 volumio volumio[2335]: at Object. (/volumio/node_modules/libxmljs/index.js:4:16) Sep 04 16:27:40 volumio volumio[2335]: error: !!!! WARNING !!!! Sep 04 16:27:40 volumio volumio[2335]: info: Loading plugin "networkfs"... Sep 04 16:27:40 volumio systemd[1]: Stopping Music Player Daemon... Sep 04 16:27:40 volumio systemd[1]: mpd.service: Succeeded. Sep 04 16:27:40 volumio systemd[1]: Stopped Music Player Daemon. Sep 04 16:27:40 volumio systemd[1]: Starting Music Player Daemon... Sep 04 16:27:41 volumio volumio[2335]: error: !!!! WARNING !!!! Sep 04 16:27:41 volumio volumio[2335]: error: The plugin system_controller/networkfs failed to load, setting it to stopped. Error: Error: Cannot find module '/volumio/node_modules/udev/build/node-v64-linux-arm/udev.node' Sep 04 16:27:41 volumio volumio[2335]: error: Stack trace: Error: Cannot find module '/volumio/node_modules/udev/build/node-v64-linux-arm/udev.node' Sep 04 16:27:41 volumio volumio[2335]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:636:15) Sep 04 16:27:41 volumio volumio[2335]: at Function.Module._load (internal/modules/cjs/loader.js:562:25) Sep 04 16:27:41 volumio volumio[2335]: at Module.require (internal/modules/cjs/loader.js:692:17) Sep 04 16:27:41 volumio volumio[2335]: at require (internal/modules/cjs/helpers.js:25:18) Sep 04 16:27:41 volumio volumio[2335]: at Object. (/volumio/node_modules/udev/udev.js:4:12) Sep 04 16:27:41 volumio volumio[2335]: at Module._compile (internal/modules/cjs/loader.js:778:30) Sep 04 16:27:41 volumio volumio[2335]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:789:10) Sep 04 16:27:41 volumio volumio[2335]: at Module.load (internal/modules/cjs/loader.js:653:32) Sep 04 16:27:41 volumio volumio[2335]: at tryModuleLoad (internal/modules/cjs/loader.js:593:12) Sep 04 16:27:41 volumio volumio[2335]: at Function.Module._load (internal/modules/cjs/loader.js:585:3) Sep 04 16:27:41 volumio volumio[2335]: error: !!!! WARNING !!!! Sep 04 16:27:41 volumio volumio[2335]: info: Loading plugin "alarm-clock"... Sep 04 16:27:41 volumio mpd[2492]: config_file: config parameter "gapless_mp3_playback" on line 19 is deprecated Sep 04 16:27:41 volumio mpd[2492]: config_file: config parameter "buffer_before_play" on line 102 is deprecated Sep 04 16:27:41 volumio mpd[2492]: config_file: config parameter "id3v1_encoding" on line 116 is deprecated Sep 04 16:27:41 volumio mpd[2492]: Sep 04 16:27 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 04 16:27:41 volumio mpd[2492]: Sep 04 16:27 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Sep 04 16:27:41 volumio mpd[2492]: Sep 04 16:27 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Sep 04 16:27:41 volumio systemd[1]: Started Music Player Daemon. Sep 04 16:27:41 volumio sudo[2480]: pam_unix(sudo:session): session closed for user root Sep 04 16:27:42 volumio volumio[2335]: info: Loading plugin "airplay_emulation"... Sep 04 16:27:42 volumio volumio[2335]: info: Starting Shairport Sync Sep 04 16:27:42 volumio volumio[2335]: info: Loading plugin "last_100"... Sep 04 16:27:42 volumio volumio[2335]: info: Loading plugin "webradio"... Sep 04 16:27:42 volumio volumio[2335]: error: !!!! WARNING !!!! Sep 04 16:27:42 volumio volumio[2335]: error: The plugin music_service/webradio failed to load, setting it to stopped. Error: Error: Could not locate the bindings file. Tried: Sep 04 16:27:42 volumio volumio[2335]: → /volumio/node_modules/libxmljs/build/xmljs.node Sep 04 16:27:42 volumio volumio[2335]: → /volumio/node_modules/libxmljs/build/Debug/xmljs.node Sep 04 16:27:42 volumio volumio[2335]: → /volumio/node_modules/libxmljs/build/Release/xmljs.node Sep 04 16:27:42 volumio volumio[2335]: → /volumio/node_modules/libxmljs/out/Debug/xmljs.node Sep 04 16:27:42 volumio volumio[2335]: → /volumio/node_modules/libxmljs/Debug/xmljs.node Sep 04 16:27:42 volumio volumio[2335]: → /volumio/node_modules/libxmljs/out/Release/xmljs.node Sep 04 16:27:42 volumio volumio[2335]: → /volumio/node_modules/libxmljs/Release/xmljs.node Sep 04 16:27:42 volumio volumio[2335]: → /volumio/node_modules/libxmljs/build/default/xmljs.node Sep 04 16:27:42 volumio volumio[2335]: → /volumio/node_modules/libxmljs/compiled/10.24.1/linux/arm/xmljs.node Sep 04 16:27:42 volumio volumio[2335]: → /volumio/node_modules/libxmljs/addon-build/release/install-root/xmljs.node Sep 04 16:27:42 volumio volumio[2335]: → /volumio/node_modules/libxmljs/addon-build/debug/install-root/xmljs.node Sep 04 16:27:42 volumio volumio[2335]: → /volumio/node_modules/libxmljs/addon-build/default/install-root/xmljs.node Sep 04 16:27:42 volumio volumio[2335]: → /volumio/node_modules/libxmljs/lib/binding/node-v64-linux-arm/xmljs.node Sep 04 16:27:42 volumio volumio[2335]: error: Stack trace: Error: Could not locate the bindings file. Tried: Sep 04 16:27:42 volumio volumio[2335]: → /volumio/node_modules/libxmljs/build/xmljs.node Sep 04 16:27:42 volumio volumio[2335]: → /volumio/node_modules/libxmljs/build/Debug/xmljs.node Sep 04 16:27:42 volumio volumio[2335]: → /volumio/node_modules/libxmljs/build/Release/xmljs.node Sep 04 16:27:42 volumio volumio[2335]: → /volumio/node_modules/libxmljs/out/Debug/xmljs.node Sep 04 16:27:42 volumio volumio[2335]: → /volumio/node_modules/libxmljs/Debug/xmljs.node Sep 04 16:27:42 volumio volumio[2335]: → /volumio/node_modules/libxmljs/out/Release/xmljs.node Sep 04 16:27:42 volumio volumio[2335]: → /volumio/node_modules/libxmljs/Release/xmljs.node Sep 04 16:27:42 volumio volumio[2335]: → /volumio/node_modules/libxmljs/build/default/xmljs.node Sep 04 16:27:42 volumio volumio[2335]: → /volumio/node_modules/libxmljs/compiled/10.24.1/linux/arm/xmljs.node Sep 04 16:27:42 volumio volumio[2335]: → /volumio/node_modules/libxmljs/addon-build/release/install-root/xmljs.node Sep 04 16:27:42 volumio volumio[2335]: → /volumio/node_modules/libxmljs/addon-build/debug/install-root/xmljs.node Sep 04 16:27:42 volumio volumio[2335]: → /volumio/node_modules/libxmljs/addon-build/default/install-root/xmljs.node Sep 04 16:27:42 volumio volumio[2335]: → /volumio/node_modules/libxmljs/lib/binding/node-v64-linux-arm/xmljs.node Sep 04 16:27:42 volumio volumio[2335]: at bindings (/volumio/node_modules/bindings/bindings.js:126:9) Sep 04 16:27:42 volumio volumio[2335]: at Object. (/volumio/node_modules/libxmljs/lib/bindings.js:1:37) Sep 04 16:27:42 volumio volumio[2335]: at Module._compile (internal/modules/cjs/loader.js:778:30) Sep 04 16:27:42 volumio volumio[2335]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:789:10) Sep 04 16:27:42 volumio volumio[2335]: at Module.load (internal/modules/cjs/loader.js:653:32) Sep 04 16:27:42 volumio volumio[2335]: at tryModuleLoad (internal/modules/cjs/loader.js:593:12) Sep 04 16:27:42 volumio volumio[2335]: at Function.Module._load (internal/modules/cjs/loader.js:585:3) Sep 04 16:27:42 volumio volumio[2335]: at Module.require (internal/modules/cjs/loader.js:692:17) Sep 04 16:27:42 volumio volumio[2335]: at require (internal/modules/cjs/helpers.js:25:18) Sep 04 16:27:42 volumio volumio[2335]: at Object. (/volumio/node_modules/libxmljs/index.js:4:16) Sep 04 16:27:42 volumio volumio[2335]: error: !!!! WARNING !!!! Sep 04 16:27:42 volumio volumio[2335]: info: Loading plugin "i2s_dacs"... Sep 04 16:27:42 volumio volumio[2335]: info: I2S DAC not set, start Auto-detection Sep 04 16:27:42 volumio volumio[2335]: info: Loading plugin "volumiodiscovery"... Sep 04 16:27:42 volumio volumio[2335]: error: !!!! WARNING !!!! Sep 04 16:27:42 volumio volumio[2335]: error: The plugin system_controller/volumiodiscovery failed to load, setting it to stopped. Error: Error: Cannot find module '../build/Release/dns_sd_bindings' Sep 04 16:27:42 volumio volumio[2335]: error: Stack trace: Error: Cannot find module '../build/Release/dns_sd_bindings' Sep 04 16:27:42 volumio volumio[2335]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:636:15) Sep 04 16:27:42 volumio volumio[2335]: at Function.Module._load (internal/modules/cjs/loader.js:562:25) Sep 04 16:27:42 volumio volumio[2335]: at Module.require (internal/modules/cjs/loader.js:692:17) Sep 04 16:27:42 volumio volumio[2335]: at require (internal/modules/cjs/helpers.js:25:18) Sep 04 16:27:42 volumio volumio[2335]: at Object. (/volumio/node_modules/mdns/lib/dns_sd.js:32:22) Sep 04 16:27:42 volumio volumio[2335]: at Module._compile (internal/modules/cjs/loader.js:778:30) Sep 04 16:27:42 volumio volumio[2335]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:789:10) Sep 04 16:27:42 volumio volumio[2335]: at Module.load (internal/modules/cjs/loader.js:653:32) Sep 04 16:27:42 volumio volumio[2335]: at tryModuleLoad (internal/modules/cjs/loader.js:593:12) Sep 04 16:27:42 volumio volumio[2335]: at Function.Module._load (internal/modules/cjs/loader.js:585:3) Sep 04 16:27:42 volumio volumio[2335]: error: !!!! WARNING !!!! Sep 04 16:27:42 volumio volumio[2335]: info: Loading plugin "outputs"... Sep 04 16:27:42 volumio volumio[2335]: info: Loading plugin "albumart"... Sep 04 16:27:42 volumio volumio[2335]: info: Plugin chromecast is not enabled Sep 04 16:27:42 volumio volumio[2335]: info: Plugin example_plugin is not enabled Sep 04 16:27:42 volumio volumio[2335]: info: Loading plugin "inputs"... Sep 04 16:27:42 volumio volumio[2335]: info: Loading plugin "pandora"... Sep 04 16:27:43 volumio volumio[2335]: info: Applying required configuration parameters for plugin pandora Sep 04 16:27:43 volumio volumio[2335]: info: Loading plugin "podcast"... Sep 04 16:27:44 volumio volumio[2335]: info: ControllerPodcast::constructor Sep 04 16:27:44 volumio volumio[2335]: info: Loading plugin "qobuz"... Sep 04 16:27:44 volumio volumio[2335]: error: ATTENTION!!!: Plugin qobuz does not return adequate promise from onVolumioStart: please update! Sep 04 16:27:44 volumio volumio[2335]: info: Loading plugin "tidal"... Sep 04 16:27:45 volumio volumio[2335]: info: [1725438465442] ControllerTidalPlugin::constructor Sep 04 16:27:45 volumio volumio[2335]: info: [1725438465444] ControllerTidalPlugin::onVolumioStart Sep 04 16:27:45 volumio volumio[2335]: info: Loading plugin "volspotconnect2"... Sep 04 16:27:47 volumio volumio[2335]: [SpotifyConnect2] Creating VLS config file Sep 04 16:27:47 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 16:27:47 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 16:27:47 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 16:27:47 volumio volumio[2335]: [SpotifyConnect2] <> or Mixer found, using softvol Sep 04 16:27:47 volumio volumio[2335]: info: Loading plugin "updater_comm"... Sep 04 16:27:47 volumio volumio[2335]: info: Plugin mpdemulation is not enabled Sep 04 16:27:47 volumio volumio[2335]: info: Loading plugin "rest_api"... Sep 04 16:27:47 volumio volumio[2335]: info: Loading plugin "websocket"... Sep 04 16:27:47 volumio volumio[2335]: info: ___________ START PLUGINS ___________ Sep 04 16:27:47 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 16:27:47 volumio volumio[2335]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 04 16:27:47 volumio volumio[2335]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 04 16:27:47 volumio volumio[2335]: info: [1725438467627] CoreMusicLibrary::Adding element Last_100 Sep 04 16:27:47 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 04 16:27:47 volumio volumio[2335]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 04 16:27:47 volumio volumio[2335]: info: [1725438467653] CoreMusicLibrary::Adding element Pandora Sep 04 16:27:47 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 04 16:27:47 volumio volumio[2335]: info: [1725438467661] ControllerPandora::initializeMQTT Sep 04 16:27:47 volumio volumio[2335]: info: [1725438467663] [Pandora] initializeMQTT: MQTT is not enabled in Plugin Configuration Sep 04 16:27:47 volumio volumio[2335]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 04 16:27:47 volumio volumio[2335]: info: [1725438467685] CoreMusicLibrary::Adding element Podcast Sep 04 16:27:47 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 04 16:27:47 volumio volumio[2335]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 04 16:27:47 volumio volumio[2335]: info: [1725438467691] CoreMusicLibrary::Adding element Qobuz Sep 04 16:27:47 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 04 16:27:47 volumio volumio[2335]: info: [1725438467706] ControllerTidalPlugin::onStart Sep 04 16:27:47 volumio volumio[2335]: info: [1725438467714] ControllerTidalPlugin::addToBrowseSources Sep 04 16:27:47 volumio volumio[2335]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 04 16:27:47 volumio volumio[2335]: info: [1725438467716] CoreMusicLibrary::Adding element Tidal Sep 04 16:27:47 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 04 16:27:47 volumio volumio[2335]: Loading session from /data/configuration/music_service/tidal/tidal-session-oauth.json... Sep 04 16:27:47 volumio volumio[2335]: Could not load session from /data/configuration/music_service/tidal/tidal-session-oauth.json: Error: ENOENT: no such file or directory, open '/data/configuration/music_service/tidal/tidal-session-oauth.json' Sep 04 16:27:47 volumio volumio[2335]: [SpotifyConnect2] Creating VLS config file Sep 04 16:27:47 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 16:27:47 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 16:27:47 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 16:27:47 volumio volumio[2335]: [SpotifyConnect2] <> or Mixer found, using softvol Sep 04 16:27:47 volumio volumio[2335]: [SpotifyConnect2] Starting metadata listener Sep 04 16:27:47 volumio sudo[2507]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Sep 04 16:27:47 volumio volumio[2335]: info: Loading i18n strings for locale en Sep 04 16:27:47 volumio volumio[2335]: Updating browse sources language Sep 04 16:27:47 volumio volumio[2335]: Cannot find translation for sourcePodcast Sep 04 16:27:47 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 04 16:27:47 volumio sudo[2507]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 16:27:47 volumio systemd[1]: Configuration file /etc/systemd/system/volspotconnect2.service is marked executable. Please remove executable permission bits. Proceeding anyway. Sep 04 16:27:47 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 04 16:27:47 volumio volumio[2335]: info: CoreCommandRouter::initPlayerControls Sep 04 16:27:47 volumio systemd[1]: Started Volspotconnect2 Daemon. Sep 04 16:27:47 volumio sudo[2507]: pam_unix(sudo:session): session closed for user root Sep 04 16:27:47 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 16:27:47 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 16:27:47 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 16:27:47 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 16:27:47 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 16:27:47 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 16:27:47 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 16:27:48 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 04 16:27:48 volumio volumio[2335]: info: BOOT COMPLETED Sep 04 16:27:48 volumio volumio[2335]: [Metrics] CommandRouter: 20s 143.68ms Sep 04 16:27:48 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 16:27:48 volumio volumio[2335]: ALSA lib pcm.c:2642:(snd_pcm_open_noupdate) Unknown PCM softvolume Sep 04 16:27:48 volumio volumio[2335]: aplay: main:852: audio open error: No such file or directory Sep 04 16:27:48 volumio volumio[2335]: Cannot play startup sound Sep 04 16:27:48 volumio volumio[2335]: info: CoreCommandRouter::Close All Modals sent Sep 04 16:27:48 volumio volumio[2335]: info: CoreCommandRouter::Close All Modals sent Sep 04 16:27:48 volumio volumio[2335]: Express server listening on port 3000 Sep 04 16:27:48 volumio volumio[2335]: [Metrics] WebUI: 21s 733.89ms Sep 04 16:27:48 volumio volumio[2516]: vollibrespot v0.2.4 61563b7 2021-07-22 (librespot 08d8bcc 2020-10-07) -- Built On 2021-07-22 Sep 04 16:27:48 volumio volumio[2516]: Reading Config from "volspotify.toml" Sep 04 16:27:48 volumio volumio[2516]: [Vollibrespot] : Using Alsa backend with device: hw:1 Sep 04 16:27:48 volumio volumio[2335]: info: [1725438468220] ControllerPandora::validateAndSetAccountOptions Sep 04 16:27:48 volumio volumio[2335]: error: [1725438468224] [Pandora] validateAndSetAccountOptions: Need email address and password. See plugin settings. Sep 04 16:27:48 volumio volumio[2335]: info: CoreStateMachine::resetVolumioState Sep 04 16:27:48 volumio volumio[2335]: info: CoreStateMachine::getcurrentVolume Sep 04 16:27:48 volumio volumio[2335]: info: CoreCommandRouter::volumioRetrievevolume Sep 04 16:27:48 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 16:27:48 volumio volumio[2335]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Sep 04 16:27:48 volumio volumio[2335]: info: CoreStateMachine::pushState Sep 04 16:27:48 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 16:27:48 volumio volumio[2335]: info: CoreStateMachine::updateTrackBlock Sep 04 16:27:48 volumio volumio[2335]: info: CorePlayQueue::getTrackBlock Sep 04 16:27:48 volumio volumio[2335]: info: CoreCommandRouter::volumioRetrievevolume Sep 04 16:27:48 volumio volumio[2335]: [SpotifyConnect2] Vollibrespot Daemon service started! Sep 04 16:27:48 volumio volumio[2335]: [Metrics] SpotifyConnect: 0s 995.86ms Sep 04 16:27:48 volumio volumio[2335]: info: MPD Permissions set Sep 04 16:27:49 volumio volumio[2335]: info: Permissions for /etc/hostapd/hostapd.conf Sep 04 16:27:49 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 04 16:27:49 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 04 16:27:49 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 04 16:27:49 volumio sudo[2552]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Sep 04 16:27:49 volumio sudo[2552]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 16:27:49 volumio sudo[2556]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 04 16:27:49 volumio sudo[2559]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 04 16:27:49 volumio sudo[2556]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 16:27:49 volumio sudo[2559]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 16:27:49 volumio systemd[1]: Starting Wireless Services... Sep 04 16:27:49 volumio sudo[2556]: pam_unix(sudo:session): session closed for user root Sep 04 16:27:49 volumio sudo[2559]: pam_unix(sudo:session): session closed for user root Sep 04 16:27:49 volumio sudo[2571]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Sep 04 16:27:49 volumio volumio[2335]: info: MPD running with PID2492 Sep 04 16:27:49 volumio volumio[2335]: ,establishing connection Sep 04 16:27:49 volumio volumio[2335]: info: Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Sep 04 16:27:49 volumio volumio[2335]: sudo: /usr/sbin/i2cdetect: command not found Sep 04 16:27:49 volumio volumio[2335]: info: CoreStateMachine::pushState Sep 04 16:27:49 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 16:27:49 volumio volumio[2538]: Forking 1 albumart workers Sep 04 16:27:50 volumio sudo[2571]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 16:27:50 volumio wifistart.sh[2586]: command failed: No such device (-19) Sep 04 16:27:50 volumio sudo[2571]: pam_unix(sudo:session): session closed for user root Sep 04 16:27:50 volumio socketio[2336]: command_len 9 Sep 04 16:27:50 volumio socketio[2336]: getQueue Sep 04 16:27:50 volumio socketio[2336]: Sep 04 16:27:50 volumio volumio[2335]: sendCommand: Command { name: 'playlistinfo', args: [] } Sep 04 16:27:50 volumio volumio[2335]: error: MPD error: The expression evaluated to a falsy value: Sep 04 16:27:50 volumio volumio[2335]: assert.ok(self.idling) Sep 04 16:27:50 volumio volumio[2335]: sendCommand: Command { name: 'search album ""', args: [] } Sep 04 16:27:50 volumio volumio[2335]: error: MPD error: The expression evaluated to a falsy value: Sep 04 16:27:50 volumio volumio[2335]: assert.ok(self.idling) Sep 04 16:27:51 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 16:27:51 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 16:27:51 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 16:27:51 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 16:27:51 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 16:27:51 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 16:27:51 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 16:27:51 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 16:27:51 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 16:27:51 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 04 16:27:51 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 16:27:51 volumio volumio[2335]: info: Reloading queue from file Sep 04 16:27:51 volumio volumio[2335]: info: Sep 04 16:27:51 volumio volumio[2335]: ---------------------------- Client requests Volume 100 Sep 04 16:27:51 volumio volumio[2335]: info: VolumeController::SetAlsaVolume100 Sep 04 16:27:51 volumio volumio[2335]: info: CoreStateMachine::pushState Sep 04 16:27:51 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 16:27:51 volumio volumio[2335]: sendCommand: Command { name: 'playlistinfo', args: [] } Sep 04 16:27:51 volumio volumio[2335]: sendCommand: Command { name: 'lsinfo "USB"', args: [] } Sep 04 16:27:52 volumio volumio[2335]: info: CoreStateMachine::setRepeat null single undefined Sep 04 16:27:52 volumio volumio[2335]: info: CoreStateMachine::pushState Sep 04 16:27:52 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 16:27:52 volumio volumio[2335]: info: CoreStateMachine::setRandom null Sep 04 16:27:52 volumio volumio[2335]: info: CoreStateMachine::pushState Sep 04 16:27:52 volumio volumio[2335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 16:27:52 volumio volumio[2335]: error: updateQueue error: null Sep 04 16:27:52 volumio volumio[2335]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 04 16:27:52 volumio volumio[2335]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'SoftMaster',0 Sep 04 16:27:52 volumio volumio[2335]: info: Setting volume on startup at 100 Sep 04 16:27:52 volumio volumio[2335]: info: Starting Shairport Sync Sep 04 16:27:52 volumio volumio[2591]: Starting albumart workers Sep 04 16:27:52 volumio sudo[2616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 04 16:27:53 volumio sudo[2616]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 16:27:53 volumio wireless.js[2593]: WIRELESS: No wireless interface, exiting Sep 04 16:27:53 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 04 16:27:53 volumio sudo[2616]: pam_unix(sudo:session): session closed for user root Sep 04 16:27:53 volumio systemd[1]: wireless.service: Control process exited, code=exited, status=1/FAILURE Sep 04 16:27:53 volumio systemd[1]: wireless.service: Failed with result 'exit-code'. Sep 04 16:27:53 volumio systemd[1]: Failed to start Wireless Services. Sep 04 16:27:53 volumio sudo[2629]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 04 16:27:53 volumio sudo[2552]: pam_unix(sudo:session): session closed for user root Sep 04 16:27:53 volumio sudo[2629]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 16:27:53 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Sep 04 16:27:53 volumio sudo[2629]: pam_unix(sudo:session): session closed for user root Sep 04 16:27:53 volumio volumio[2335]: info: Shairport-Sync Started Sep 04 16:27:54 volumio volumio[2335]: Error adding Membership: Error: addMembership EINVAL Sep 04 16:27:54 volumio volumio[2335]: info: Upmpdcli Daemon Started Sep 04 16:27:55 volumio volumio[2335]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 04 16:27:55 volumio volumio[2335]: TypeError: f.split(...)[0].capitalize is not a function Sep 04 16:27:55 volumio volumio[2335]: at /volumio/app/plugins/miscellanea/appearance/index.js:198:56 Sep 04 16:27:55 volumio volumio[2335]: at Array.forEach () Sep 04 16:27:55 volumio volumio[2335]: at /volumio/app/plugins/miscellanea/appearance/index.js:196:11 Sep 04 16:27:55 volumio volumio[2335]: at go$readdir$cb (/volumio/node_modules/graceful-fs/graceful-fs.js:195:14) Sep 04 16:27:55 volumio volumio[2335]: at FSReqWrap.args [as oncomplete] (fs.js:140:20) Sep 04 16:27:55 volumio volumio[2335]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 04 16:27:55 volumio volumio[2670]: Generating RSA private key, 4096 bit long modulus (2 primes) Sep 04 16:27:56 volumio socketio[2336]: [2024-09-04 16:27:56] [connect] Successful connection Sep 04 16:27:58 volumio sudo[2690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-04 16:26 Sep 04 16:27:58 volumio sudo[2690]: 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"