-- Logs begin at Tue 2023-03-28 01:54:07 CST, end at Mon 2024-08-26 16:43:13 CST. -- Aug 26 16:42:00 volumiodev smbd[2418]: [2024/08/26 16:42:00.053728, 0] ../../source3/smbd/server.c:1734(main) Aug 26 16:42:00 volumiodev smbd[2418]: smbd version 4.15.13-Ubuntu started. Aug 26 16:42:00 volumiodev smbd[2418]: Copyright Andrew Tridgell and the Samba Team 1992-2021 Aug 26 16:42:00 volumiodev sudo[2431]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Aug 26 16:42:00 volumiodev sudo[2431]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:42:00 volumiodev avahi-daemon[2169]: Withdrawing address record for 192.168.100.199 on wlan0. Aug 26 16:42:00 volumiodev avahi-daemon[2169]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.100.199. Aug 26 16:42:00 volumiodev sudo[2431]: pam_unix(sudo:session): session closed for user root Aug 26 16:42:00 volumiodev systemd-timesyncd[2066]: No network connectivity, watching for changes. Aug 26 16:42:00 volumiodev avahi-daemon[2169]: Interface wlan0.IPv4 no longer relevant for mDNS. Aug 26 16:42:00 volumiodev avahi-daemon[2169]: Withdrawing address record for fe80::7edd:90ff:fe40:e38b on wlan0. Aug 26 16:42:00 volumiodev avahi-daemon[2169]: Leaving mDNS multicast group on interface wlan0.IPv6 with address fe80::7edd:90ff:fe40:e38b. Aug 26 16:42:00 volumiodev avahi-daemon[2169]: Interface wlan0.IPv6 no longer relevant for mDNS. Aug 26 16:42:00 volumiodev systemd[1]: Started Samba SMB Daemon. Aug 26 16:42:00 volumiodev sudo[2442]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Aug 26 16:42:00 volumiodev sudo[2442]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:42:01 volumiodev nmbd[2350]: [2024/08/26 16:42:01.128657, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Aug 26 16:42:01 volumiodev nmbd[2350]: Packet send failed to 192.168.100.255(137) ERRNO=Network is unreachable Aug 26 16:42:01 volumiodev nmbd[2350]: [2024/08/26 16:42:01.129355, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Aug 26 16:42:01 volumiodev nmbd[2350]: retransmit_or_expire_response_records: Failed to resend packet id 3492 to IP 192.168.100.255 on subnet 192.168.100.199 Aug 26 16:42:01 volumiodev nmbd[2350]: [2024/08/26 16:42:01.129667, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Aug 26 16:42:01 volumiodev nmbd[2350]: Packet send failed to 192.168.100.255(137) ERRNO=Network is unreachable Aug 26 16:42:01 volumiodev nmbd[2350]: [2024/08/26 16:42:01.129928, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Aug 26 16:42:01 volumiodev nmbd[2350]: retransmit_or_expire_response_records: Failed to resend packet id 3493 to IP 192.168.100.255 on subnet 192.168.100.199 Aug 26 16:42:01 volumiodev nmbd[2350]: [2024/08/26 16:42:01.130168, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Aug 26 16:42:01 volumiodev nmbd[2350]: Packet send failed to 192.168.100.255(137) ERRNO=Network is unreachable Aug 26 16:42:01 volumiodev nmbd[2350]: [2024/08/26 16:42:01.130382, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Aug 26 16:42:01 volumiodev nmbd[2350]: retransmit_or_expire_response_records: Failed to resend packet id 3494 to IP 192.168.100.255 on subnet 192.168.100.199 Aug 26 16:42:01 volumiodev nmbd[2350]: [2024/08/26 16:42:01.130621, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Aug 26 16:42:01 volumiodev nmbd[2350]: Packet send failed to 192.168.100.255(137) ERRNO=Network is unreachable Aug 26 16:42:01 volumiodev nmbd[2350]: [2024/08/26 16:42:01.130837, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Aug 26 16:42:01 volumiodev nmbd[2350]: retransmit_or_expire_response_records: Failed to resend packet id 3495 to IP 192.168.100.255 on subnet 192.168.100.199 Aug 26 16:42:01 volumiodev nmbd[2350]: [2024/08/26 16:42:01.131110, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Aug 26 16:42:01 volumiodev nmbd[2350]: Packet send failed to 192.168.100.255(137) ERRNO=Network is unreachable Aug 26 16:42:01 volumiodev nmbd[2350]: [2024/08/26 16:42:01.131453, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Aug 26 16:42:01 volumiodev nmbd[2350]: retransmit_or_expire_response_records: Failed to resend packet id 3496 to IP 192.168.100.255 on subnet 192.168.100.199 Aug 26 16:42:01 volumiodev nmbd[2350]: [2024/08/26 16:42:01.131684, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Aug 26 16:42:01 volumiodev nmbd[2350]: Packet send failed to 192.168.100.255(137) ERRNO=Network is unreachable Aug 26 16:42:01 volumiodev nmbd[2350]: [2024/08/26 16:42:01.131892, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Aug 26 16:42:01 volumiodev nmbd[2350]: retransmit_or_expire_response_records: Failed to resend packet id 3503 to IP 192.168.100.255 on subnet 192.168.100.199 Aug 26 16:42:01 volumiodev dhcpcd[2172]: wlan0: carrier lost Aug 26 16:42:01 volumiodev systemd-networkd[2079]: wlan0: Link DOWN Aug 26 16:42:01 volumiodev dhcpcd[2172]: wlan0: carrier lost Aug 26 16:42:01 volumiodev sudo[2442]: pam_unix(sudo:session): session closed for user root Aug 26 16:42:01 volumiodev systemd-networkd[2079]: wlan0: Lost carrier Aug 26 16:42:01 volumiodev wireless.js[2273]: Stopped aP Aug 26 16:42:01 volumiodev wireless.js[2273]: Start wireless flow Aug 26 16:42:01 volumiodev wireless.js[2273]: Stopped hotspot (if there).. Aug 26 16:42:01 volumiodev mpd[2346]: config_file: config parameter "gapless_mp3_playback" on line 19 is deprecated Aug 26 16:42:01 volumiodev mpd[2346]: config_file: config parameter "buffer_before_play" on line 85 is deprecated Aug 26 16:42:01 volumiodev mpd[2346]: config_file: config parameter "id3v1_encoding" on line 99 is deprecated Aug 26 16:42:01 volumiodev mpd[2346]: Aug 26 16:42 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 26 16:42:01 volumiodev sudo[2467]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Aug 26 16:42:01 volumiodev mpd[2346]: Aug 26 16:42 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 26 16:42:01 volumiodev mpd[2346]: Aug 26 16:42 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 26 16:42:01 volumiodev sudo[2467]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:42:01 volumiodev systemd[1]: Started Music Player Daemon. Aug 26 16:42:01 volumiodev sudo[2467]: pam_unix(sudo:session): session closed for user root Aug 26 16:42:01 volumiodev udisks-glue[2414]: Updating DB (#1) ... Aug 26 16:42:01 volumiodev udisks-glue[2414]: volume: n/a repeat: off random: off single: off consume: off Aug 26 16:42:01 volumiodev sudo[2479]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Aug 26 16:42:01 volumiodev sudo[2479]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:42:01 volumiodev sudo[2479]: pam_unix(sudo:session): session closed for user root Aug 26 16:42:01 volumiodev wireless.js[2273]: DHCP IP Aug 26 16:42:01 volumiodev wireless.js[2273]: Start ap Aug 26 16:42:01 volumiodev wpa_supplicant[2488]: Successfully initialized wpa_supplicant Aug 26 16:42:01 volumiodev wpa_supplicant[2488]: nl80211: Could not configure driver mode Aug 26 16:42:01 volumiodev wpa_supplicant[2488]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Aug 26 16:42:01 volumiodev sudo[2489]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Aug 26 16:42:01 volumiodev sudo[2489]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:42:01 volumiodev dhcpcd[2494]: sending commands to master dhcpcd process Aug 26 16:42:01 volumiodev dhcpcd[2172]: control command: /sbin/dhcpcd Aug 26 16:42:01 volumiodev dhcpcd[2172]: control command: /sbin/dhcpcd Aug 26 16:42:01 volumiodev sudo[2489]: pam_unix(sudo:session): session closed for user root Aug 26 16:42:01 volumiodev systemd[1]: systemd-rfkill.service: Succeeded. Aug 26 16:42:02 volumiodev nmbd[2350]: [2024/08/26 16:42:02.133481, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Aug 26 16:42:02 volumiodev nmbd[2350]: Packet send failed to 192.168.100.255(137) ERRNO=Network is unreachable Aug 26 16:42:02 volumiodev nmbd[2350]: [2024/08/26 16:42:02.134337, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Aug 26 16:42:02 volumiodev nmbd[2350]: retransmit_or_expire_response_records: Failed to resend packet id 3492 to IP 192.168.100.255 on subnet 192.168.100.199 Aug 26 16:42:02 volumiodev nmbd[2350]: [2024/08/26 16:42:02.134821, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Aug 26 16:42:02 volumiodev nmbd[2350]: Packet send failed to 192.168.100.255(137) ERRNO=Network is unreachable Aug 26 16:42:02 volumiodev nmbd[2350]: [2024/08/26 16:42:02.135349, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Aug 26 16:42:02 volumiodev nmbd[2350]: retransmit_or_expire_response_records: Failed to resend packet id 3493 to IP 192.168.100.255 on subnet 192.168.100.199 Aug 26 16:42:02 volumiodev nmbd[2350]: [2024/08/26 16:42:02.135766, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Aug 26 16:42:02 volumiodev nmbd[2350]: Packet send failed to 192.168.100.255(137) ERRNO=Network is unreachable Aug 26 16:42:02 volumiodev nmbd[2350]: [2024/08/26 16:42:02.136172, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Aug 26 16:42:02 volumiodev nmbd[2350]: retransmit_or_expire_response_records: Failed to resend packet id 3494 to IP 192.168.100.255 on subnet 192.168.100.199 Aug 26 16:42:02 volumiodev nmbd[2350]: [2024/08/26 16:42:02.136604, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Aug 26 16:42:02 volumiodev nmbd[2350]: Packet send failed to 192.168.100.255(137) ERRNO=Network is unreachable Aug 26 16:42:02 volumiodev nmbd[2350]: [2024/08/26 16:42:02.137050, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Aug 26 16:42:02 volumiodev nmbd[2350]: retransmit_or_expire_response_records: Failed to resend packet id 3495 to IP 192.168.100.255 on subnet 192.168.100.199 Aug 26 16:42:02 volumiodev nmbd[2350]: [2024/08/26 16:42:02.137479, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Aug 26 16:42:02 volumiodev nmbd[2350]: Packet send failed to 192.168.100.255(137) ERRNO=Network is unreachable Aug 26 16:42:02 volumiodev nmbd[2350]: [2024/08/26 16:42:02.137897, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Aug 26 16:42:02 volumiodev nmbd[2350]: retransmit_or_expire_response_records: Failed to resend packet id 3496 to IP 192.168.100.255 on subnet 192.168.100.199 Aug 26 16:42:02 volumiodev nmbd[2350]: [2024/08/26 16:42:02.138289, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Aug 26 16:42:02 volumiodev nmbd[2350]: Packet send failed to 192.168.100.255(137) ERRNO=Network is unreachable Aug 26 16:42:02 volumiodev nmbd[2350]: [2024/08/26 16:42:02.138676, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Aug 26 16:42:02 volumiodev nmbd[2350]: retransmit_or_expire_response_records: Failed to resend packet id 3503 to IP 192.168.100.255 on subnet 192.168.100.199 Aug 26 16:42:02 volumiodev systemd-networkd[2079]: wlan0: Link UP Aug 26 16:42:02 volumiodev wpa_supplicant[2488]: ioctl[SIOCSIWENCODEEXT]: Invalid argument Aug 26 16:42:02 volumiodev wpa_supplicant[2488]: ioctl[SIOCSIWENCODEEXT]: Invalid argument Aug 26 16:42:02 volumiodev rc.local[2369]: [2024-08-26 16:42:02] [info] asio async_connect error: asio.system:111 (Connection refused) Aug 26 16:42:02 volumiodev rc.local[2369]: [2024-08-26 16:42:02] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Aug 26 16:42:02 volumiodev rc.local[2369]: [2024-08-26 16:42:02] [error] handle_connect error: Connection refused Aug 26 16:42:02 volumiodev wireless.js[2273]: trying... Aug 26 16:42:02 volumiodev sudo[2502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 26 16:42:02 volumiodev sudo[2502]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:42:02 volumiodev sudo[2502]: pam_unix(sudo:session): session closed for user root Aug 26 16:42:03 volumiodev nmbd[2350]: [2024/08/26 16:42:03.140440, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Aug 26 16:42:03 volumiodev nmbd[2350]: Packet send failed to 192.168.100.255(137) ERRNO=Network is unreachable Aug 26 16:42:03 volumiodev nmbd[2350]: [2024/08/26 16:42:03.141525, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Aug 26 16:42:03 volumiodev nmbd[2350]: retransmit_or_expire_response_records: Failed to resend packet id 3492 to IP 192.168.100.255 on subnet 192.168.100.199 Aug 26 16:42:03 volumiodev nmbd[2350]: [2024/08/26 16:42:03.142159, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Aug 26 16:42:03 volumiodev nmbd[2350]: Packet send failed to 192.168.100.255(137) ERRNO=Network is unreachable Aug 26 16:42:03 volumiodev nmbd[2350]: [2024/08/26 16:42:03.142763, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Aug 26 16:42:03 volumiodev nmbd[2350]: retransmit_or_expire_response_records: Failed to resend packet id 3493 to IP 192.168.100.255 on subnet 192.168.100.199 Aug 26 16:42:03 volumiodev nmbd[2350]: [2024/08/26 16:42:03.143653, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Aug 26 16:42:03 volumiodev nmbd[2350]: Packet send failed to 192.168.100.255(137) ERRNO=Network is unreachable Aug 26 16:42:03 volumiodev nmbd[2350]: [2024/08/26 16:42:03.144232, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Aug 26 16:42:03 volumiodev nmbd[2350]: retransmit_or_expire_response_records: Failed to resend packet id 3494 to IP 192.168.100.255 on subnet 192.168.100.199 Aug 26 16:42:03 volumiodev nmbd[2350]: [2024/08/26 16:42:03.145022, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Aug 26 16:42:03 volumiodev nmbd[2350]: Packet send failed to 192.168.100.255(137) ERRNO=Network is unreachable Aug 26 16:42:03 volumiodev nmbd[2350]: [2024/08/26 16:42:03.145652, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Aug 26 16:42:03 volumiodev nmbd[2350]: retransmit_or_expire_response_records: Failed to resend packet id 3495 to IP 192.168.100.255 on subnet 192.168.100.199 Aug 26 16:42:03 volumiodev nmbd[2350]: [2024/08/26 16:42:03.146217, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Aug 26 16:42:03 volumiodev nmbd[2350]: Packet send failed to 192.168.100.255(137) ERRNO=Network is unreachable Aug 26 16:42:03 volumiodev nmbd[2350]: [2024/08/26 16:42:03.146764, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Aug 26 16:42:03 volumiodev nmbd[2350]: retransmit_or_expire_response_records: Failed to resend packet id 3496 to IP 192.168.100.255 on subnet 192.168.100.199 Aug 26 16:42:03 volumiodev nmbd[2350]: [2024/08/26 16:42:03.147400, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Aug 26 16:42:03 volumiodev nmbd[2350]: Packet send failed to 192.168.100.255(137) ERRNO=Network is unreachable Aug 26 16:42:03 volumiodev nmbd[2350]: [2024/08/26 16:42:03.147945, 0] ../../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Aug 26 16:42:03 volumiodev nmbd[2350]: retransmit_or_expire_response_records: Failed to resend packet id 3503 to IP 192.168.100.255 on subnet 192.168.100.199 Aug 26 16:42:03 volumiodev wpa_supplicant[2500]: wlan0: Trying to associate with 00:90:4c:62:2e:36 (SSID='VeriderTech-OFC' freq=2452 MHz) Aug 26 16:42:03 volumiodev wpa_supplicant[2500]: Failed to add supported operating classes IE Aug 26 16:42:03 volumiodev kernel: wlan0: authenticate with 00:90:4c:62:2e:36 Aug 26 16:42:03 volumiodev wireless.js[2273]: trying... Aug 26 16:42:03 volumiodev kernel: wlan0: send auth to 00:90:4c:62:2e:36 (try 1/3) Aug 26 16:42:03 volumiodev kernel: wlan0: authenticated Aug 26 16:42:03 volumiodev kernel: wlan0: associate with 00:90:4c:62:2e:36 (try 1/3) Aug 26 16:42:03 volumiodev kernel: wlan0: RX AssocResp from 00:90:4c:62:2e:36 (capab=0x11 status=0 aid=8) Aug 26 16:42:03 volumiodev kernel: wlan0: associated Aug 26 16:42:03 volumiodev kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready Aug 26 16:42:03 volumiodev systemd-networkd[2079]: wlan0: Gained carrier Aug 26 16:42:03 volumiodev wpa_supplicant[2500]: wlan0: Associated with 00:90:4c:62:2e:36 Aug 26 16:42:03 volumiodev dhcpcd[2172]: wlan0: carrier acquired Aug 26 16:42:03 volumiodev systemd-networkd[2079]: wlan0: Connected WiFi access point: VeriderTech-OFC (00:90:4c:62:2e:36) Aug 26 16:42:03 volumiodev dhcpcd[2172]: wlan0: carrier acquired Aug 26 16:42:03 volumiodev systemd-networkd[2079]: wlan0: found matching network '/etc/systemd/network/11-wlan0.network', based on potentially unpredictable ifname Aug 26 16:42:03 volumiodev avahi-daemon[2169]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.100.199. Aug 26 16:42:03 volumiodev systemd-timesyncd[2066]: Network configuration changed, trying to establish connection. Aug 26 16:42:03 volumiodev avahi-daemon[2169]: New relevant interface wlan0.IPv4 for mDNS. Aug 26 16:42:03 volumiodev avahi-daemon[2169]: Registering new address record for 192.168.100.199 on wlan0.IPv4. Aug 26 16:42:03 volumiodev wpa_supplicant[2500]: wlan0: WPA: Key negotiation completed with 00:90:4c:62:2e:36 [PTK=CCMP GTK=CCMP] Aug 26 16:42:03 volumiodev wpa_supplicant[2500]: wlan0: CTRL-EVENT-CONNECTED - Connection to 00:90:4c:62:2e:36 completed [id=0 id_str=] Aug 26 16:42:03 volumiodev sudo[2512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 26 16:42:04 volumiodev sudo[2512]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:42:04 volumiodev sudo[2512]: pam_unix(sudo:session): session closed for user root Aug 26 16:42:04 volumiodev wireless.js[2273]: Connected to: ----VeriderTech-OFC Aug 26 16:42:04 volumiodev wireless.js[2273]: ---- Aug 26 16:42:04 volumiodev sudo[2538]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 26 16:42:04 volumiodev sudo[2538]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:42:04 volumiodev sudo[2538]: pam_unix(sudo:session): session closed for user root Aug 26 16:42:04 volumiodev dhcpcd[2172]: wlan0: soliciting a DHCP lease Aug 26 16:42:04 volumiodev dhcpcd[2172]: wlan0: soliciting a DHCP lease Aug 26 16:42:04 volumiodev wireless.js[2273]: ... joined AP, wlan0 IPv4 is 192.168.100.199, ipV6 is fe80::7edd:90ff:fe40:e38b Aug 26 16:42:04 volumiodev wireless.js[2273]: It's done! AP Aug 26 16:42:22 volumiodev systemd-timesyncd[2066]: Initial synchronization to time server 91.189.91.157:123 (ntp.ubuntu.com). Aug 26 16:42:22 volumiodev systemd[1]: Started Volspotconnect2 Daemon. Aug 26 16:42:22 volumiodev dhcpcd[2172]: wlan0: offered 192.168.100.102 from 192.168.100.1 Aug 26 16:42:22 volumiodev dhcpcd[2172]: wlan0: offered 192.168.100.102 from 192.168.100.1 Aug 26 16:42:22 volumiodev systemd[1]: Started Wireless Services. Aug 26 16:42:22 volumiodev systemd[1]: Started Volumio Backend Module. Aug 26 16:42:22 volumiodev volumio[2551]: vollibrespot v0.2.4 61563b7 2021-07-22 (librespot 08d8bcc 2020-10-07) -- Built On 2021-07-22 Aug 26 16:42:22 volumiodev volumio[2551]: Reading Config from "volspotify.toml" Aug 26 16:42:22 volumiodev volumio[2551]: [Vollibrespot] : Using Alsa backend with device: plughw:0 Aug 26 16:42:22 volumiodev avahi-daemon[2169]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80::7edd:90ff:fe40:e38b. Aug 26 16:42:22 volumiodev avahi-daemon[2169]: New relevant interface wlan0.IPv6 for mDNS. Aug 26 16:42:22 volumiodev systemd-networkd[2079]: wlan0: Gained IPv6LL Aug 26 16:42:22 volumiodev avahi-daemon[2169]: Registering new address record for fe80::7edd:90ff:fe40:e38b on wlan0.*. Aug 26 16:42:23 volumiodev login[2397]: pam_unix(login:session): session opened for user volumio by LOGIN(uid=0) Aug 26 16:42:23 volumiodev frpc[2330]: 2024/08/26 16:42:23 [I] [service.go:301] [51c2357834e755a6] login to server success, get run id [51c2357834e755a6] Aug 26 16:42:23 volumiodev frpc[2330]: 2024/08/26 16:42:23 [I] [proxy_manager.go:150] [51c2357834e755a6] proxy added: [ssh-miniEvm volumio-miniEvm] Aug 26 16:42:23 volumiodev systemd-logind[2192]: New session c1 of user volumio. Aug 26 16:42:23 volumiodev systemd[1]: Created slice User Slice of UID 1000. Aug 26 16:42:23 volumiodev frpc[2330]: 2024/08/26 16:42:23 [I] [control.go:172] [51c2357834e755a6] [ssh-miniEvm] start proxy success Aug 26 16:42:23 volumiodev frpc[2330]: 2024/08/26 16:42:23 [I] [control.go:172] [51c2357834e755a6] [volumio-miniEvm] start proxy success Aug 26 16:42:23 volumiodev systemd[1]: Starting User Runtime Directory /run/user/1000... Aug 26 16:42:23 volumiodev systemd[1]: Finished User Runtime Directory /run/user/1000. Aug 26 16:42:23 volumiodev systemd[1]: Starting User Manager for UID 1000... Aug 26 16:42:23 volumiodev systemd[2571]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) Aug 26 16:42:24 volumiodev systemd[2571]: Reached target Paths. Aug 26 16:42:24 volumiodev systemd[2571]: Reached target Timers. Aug 26 16:42:24 volumiodev systemd[2571]: Listening on GnuPG network certificate management daemon. Aug 26 16:42:24 volumiodev systemd[2571]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). Aug 26 16:42:24 volumiodev systemd[2571]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). Aug 26 16:42:24 volumiodev systemd[2571]: Listening on GnuPG cryptographic agent (ssh-agent emulation). Aug 26 16:42:24 volumiodev systemd[2571]: Listening on GnuPG cryptographic agent and passphrase cache. Aug 26 16:42:24 volumiodev systemd[2571]: mpd.socket: Failed to create listening socket ([::]:6600): Address already in use Aug 26 16:42:24 volumiodev systemd[2571]: mpd.socket: Failed to listen on sockets: Address already in use Aug 26 16:42:24 volumiodev systemd[2571]: mpd.socket: Failed with result 'resources'. Aug 26 16:42:24 volumiodev systemd[2571]: Failed to listen on mpd.socket. Aug 26 16:42:24 volumiodev systemd[2571]: Reached target Sockets. Aug 26 16:42:24 volumiodev systemd[2571]: Reached target Basic System. Aug 26 16:42:24 volumiodev systemd[1]: Started User Manager for UID 1000. Aug 26 16:42:24 volumiodev systemd[2571]: Starting Music Player Daemon... Aug 26 16:42:24 volumiodev systemd[1]: Started Session c1 of user volumio. Aug 26 16:42:27 volumiodev mpd[2579]: config_file: config parameter "gapless_mp3_playback" on line 19 is deprecated Aug 26 16:42:27 volumiodev mpd[2579]: config_file: config parameter "buffer_before_play" on line 85 is deprecated Aug 26 16:42:27 volumiodev mpd[2579]: config_file: config parameter "id3v1_encoding" on line 99 is deprecated Aug 26 16:42:27 volumiodev mpd[2579]: exception: failed to open log file "/var/log/mpd.log" (config line 7): Permission denied Aug 26 16:42:27 volumiodev rc.local[2369]: [2024-08-26 16:42:27] [connect] Successful connection Aug 26 16:42:27 volumiodev systemd[2571]: mpd.service: Main process exited, code=exited, status=1/FAILURE Aug 26 16:42:27 volumiodev systemd[2571]: mpd.service: Failed with result 'exit-code'. Aug 26 16:42:27 volumiodev systemd[2571]: Failed to start Music Player Daemon. Aug 26 16:42:27 volumiodev systemd[2571]: Reached target Main User Target. Aug 26 16:42:27 volumiodev systemd[2571]: Startup finished in 3.780s. Aug 26 16:42:28 volumiodev volumio[2552]: info: ------------------------------------------- Aug 26 16:42:28 volumiodev volumio[2552]: info: ----- Volumio2 ---- Aug 26 16:42:28 volumiodev volumio[2552]: info: ------------------------------------------- Aug 26 16:42:28 volumiodev volumio[2552]: info: ----- System startup ---- Aug 26 16:42:28 volumiodev volumio[2552]: info: ------------------------------------------- Aug 26 16:42:29 volumiodev su[2603]: pam_unix(su:auth): authentication failure; logname=volumio uid=1000 euid=0 tty=ttyAS0 ruser=volumio rhost= user=root Aug 26 16:42:31 volumiodev volumio[2552]: info: Plugin folders cleanup Aug 26 16:42:31 volumiodev volumio[2552]: info: Scanning into folder /volumio/app/plugins/ Aug 26 16:42:31 volumiodev volumio[2552]: info: Scanning category audio_interface Aug 26 16:42:31 volumiodev volumio[2552]: info: Scanning category miscellanea Aug 26 16:42:31 volumiodev volumio[2552]: info: Scanning category music_service Aug 26 16:42:31 volumiodev volumio[2552]: info: Scanning category plugins.json Aug 26 16:42:31 volumiodev volumio[2552]: info: Scanning category system_controller Aug 26 16:42:31 volumiodev volumio[2552]: info: Scanning category user_interface Aug 26 16:42:31 volumiodev volumio[2552]: info: Scanning into folder /data/plugins/ Aug 26 16:42:31 volumiodev volumio[2552]: info: Plugin folders cleanup completed Aug 26 16:42:31 volumiodev volumio[2552]: info: ------------------------------------------- Aug 26 16:42:31 volumiodev volumio[2552]: info: ----- Core plugins startup ---- Aug 26 16:42:31 volumiodev volumio[2552]: info: ------------------------------------------- Aug 26 16:42:31 volumiodev volumio[2552]: info: Loading plugins from folder /volumio/app/plugins/ Aug 26 16:42:31 volumiodev volumio[2552]: info: Adding plugin upnp to MyMusic Plugins Aug 26 16:42:31 volumiodev volumio[2552]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 26 16:42:31 volumiodev volumio[2552]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 26 16:42:31 volumiodev volumio[2552]: info: Loading plugins from folder /data/plugins/ Aug 26 16:42:31 volumiodev volumio[2552]: info: Loading plugin "system"... Aug 26 16:42:31 volumiodev volumio[2552]: info: Loading plugin "appearance"... Aug 26 16:42:32 volumiodev su[2603]: FAILED SU (to root) volumio on ttyAS0 Aug 26 16:42:34 volumiodev kernel: usb0-vbus: disabling Aug 26 16:42:36 volumiodev su[2608]: (to root) volumio on ttyAS0 Aug 26 16:42:36 volumiodev su[2608]: pam_unix(su:session): session opened for user root by volumio(uid=1000) Aug 26 16:42:38 volumiodev volumio[2552]: info: Loading plugin "network"... Aug 26 16:42:38 volumiodev volumio[2552]: info: Refreshing Cached IP Addresses Aug 26 16:42:38 volumiodev volumio[2552]: info: Loading plugin "services"... Aug 26 16:42:38 volumiodev volumio[2552]: info: Loading plugin "alsa_controller"... Aug 26 16:42:38 volumiodev sudo[2702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 26 16:42:38 volumiodev sudo[2704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 26 16:42:38 volumiodev sudo[2706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Aug 26 16:42:38 volumiodev sudo[2702]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:42:38 volumiodev sudo[2704]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:42:38 volumiodev sudo[2702]: pam_unix(sudo:session): session closed for user root Aug 26 16:42:38 volumiodev sudo[2706]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:42:38 volumiodev sudo[2704]: pam_unix(sudo:session): session closed for user root Aug 26 16:42:38 volumiodev sudo[2706]: pam_unix(sudo:session): session closed for user root Aug 26 16:42:38 volumiodev volumio[2552]: info: Loading plugin "wizard"... Aug 26 16:42:38 volumiodev volumio[2552]: info: Loading plugin "volumio_command_line_client"... Aug 26 16:42:39 volumiodev volumio[2552]: info: Loading plugin "upnp"... Aug 26 16:42:39 volumiodev volumio[2552]: info: [1724661759070] Starting Upmpd Daemon Aug 26 16:42:39 volumiodev volumio[2552]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 26 16:42:39 volumiodev volumio[2552]: info: Loading plugin "my_music"... Aug 26 16:42:39 volumiodev volumio[2552]: info: Loading plugin "mpd"... Aug 26 16:42:40 volumiodev volumio[2552]: info: Loading plugin "upnp_browser"... Aug 26 16:42:41 volumiodev volumio[2552]: info: Loading plugin "networkfs"... Aug 26 16:42:42 volumiodev volumio[2552]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 26 16:42:42 volumiodev volumio[2552]: info: Loading plugin "alarm-clock"... Aug 26 16:42:43 volumiodev dhcpcd[2172]: timed out Aug 26 16:42:43 volumiodev dhcpcd[2172]: timed out Aug 26 16:42:43 volumiodev dhcpcd[2172]: forked to background, child pid 2741 Aug 26 16:42:43 volumiodev dhcpcd[2172]: forked to background, child pid 2741 Aug 26 16:42:43 volumiodev systemd[1]: Started DHCP Client Daemon. Aug 26 16:42:43 volumiodev systemd[1]: Reached target Multi-User System. Aug 26 16:42:43 volumiodev systemd[1]: Reached target Graphical Interface. Aug 26 16:42:43 volumiodev systemd[1]: Starting Update UTMP about System Runlevel Changes... Aug 26 16:42:43 volumiodev volumio[2552]: info: Loading plugin "airplay_emulation"... Aug 26 16:42:43 volumiodev volumio[2552]: info: Starting Shairport Sync Aug 26 16:42:43 volumiodev volumio[2552]: info: Loading plugin "last_100"... Aug 26 16:42:43 volumiodev volumio[2552]: info: Loading plugin "webradio"... Aug 26 16:42:43 volumiodev systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Aug 26 16:42:43 volumiodev systemd[1]: Finished Update UTMP about System Runlevel Changes. Aug 26 16:42:43 volumiodev systemd[1]: Startup finished in 4.616s (kernel) + 38.424s (userspace) = 43.041s. Aug 26 16:42:43 volumiodev volumio[2552]: info: Plugin youtube2 is not enabled Aug 26 16:42:43 volumiodev volumio[2552]: info: Loading plugin "i2s_dacs"... Aug 26 16:42:43 volumiodev volumio[2552]: info: I2S DAC not set, start Auto-detection Aug 26 16:42:43 volumiodev volumio[2552]: info: Loading plugin "volumiodiscovery"... Aug 26 16:42:43 volumiodev rc.local[2369]: [2024-08-26 16:42:43] [connect] Successful connection Aug 26 16:42:43 volumiodev volumio[2552]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 26 16:42:43 volumiodev node[2552]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 26 16:42:43 volumiodev node[2552]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 26 16:42:43 volumiodev volumio[2552]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 26 16:42:43 volumiodev volumio[2552]: *** WARNING *** For more information see Aug 26 16:42:43 volumiodev volumio[2552]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 26 16:42:43 volumiodev volumio[2552]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 26 16:42:43 volumiodev volumio[2552]: *** WARNING *** For more information see Aug 26 16:42:43 volumiodev node[2552]: *** WARNING *** For more information see Aug 26 16:42:43 volumiodev node[2552]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 26 16:42:43 volumiodev node[2552]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 26 16:42:43 volumiodev node[2552]: *** WARNING *** For more information see Aug 26 16:42:43 volumiodev volumio[2552]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 26 16:42:43 volumiodev volumio[2552]: Discovery: StartAdv! undefined Aug 26 16:42:43 volumiodev volumio[2552]: Discovery: Started advertising... Volumiodev - undefined Aug 26 16:42:44 volumiodev volumio[2552]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 26 16:42:44 volumiodev volumio[2552]: info: Loading plugin "outputs"... Aug 26 16:42:44 volumiodev volumio[2552]: info: Loading plugin "albumart"... Aug 26 16:42:44 volumiodev volumio[2552]: info: Plugin example_plugin is not enabled Aug 26 16:42:44 volumiodev volumio[2552]: info: Loading plugin "inputs"... Aug 26 16:42:44 volumiodev volumio[2552]: info: Loading plugin "pandora"... Aug 26 16:42:45 volumiodev volumio[2552]: info: Applying required configuration parameters for plugin pandora Aug 26 16:42:45 volumiodev volumio[2552]: info: Loading plugin "qobuz"... Aug 26 16:42:45 volumiodev volumio[2552]: error: ATTENTION!!!: Plugin qobuz does not return adequate promise from onVolumioStart: please update! Aug 26 16:42:45 volumiodev volumio[2552]: info: Loading plugin "tidal"... Aug 26 16:42:45 volumiodev volumio[2552]: info: [1724661765369] ControllerTidalPlugin::constructor Aug 26 16:42:45 volumiodev volumio[2552]: info: [1724661765371] ControllerTidalPlugin::onVolumioStart Aug 26 16:42:45 volumiodev volumio[2552]: info: Loading plugin "volspotconnect2"... Aug 26 16:42:47 volumiodev sudo[2750]: root : TTY=ttyAS0 ; PWD=/home/volumio ; USER=root ; COMMAND=/usr/bin/mv jenkinsHome.zip /ci/tools/ Aug 26 16:42:47 volumiodev sudo[2750]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) Aug 26 16:42:47 volumiodev volumio[2552]: [SpotifyConnect2] Creating VLS config file Aug 26 16:42:47 volumiodev sudo[2750]: pam_unix(sudo:session): session closed for user root Aug 26 16:42:47 volumiodev volumio[2552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:42:47 volumiodev volumio[2552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:42:47 volumiodev volumio[2552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:42:47 volumiodev volumio[2552]: info: Loading plugin "updater_comm"... Aug 26 16:42:47 volumiodev volumio[2552]: info: Plugin mpdemulation is not enabled Aug 26 16:42:47 volumiodev volumio[2552]: info: Loading plugin "rest_api"... Aug 26 16:42:47 volumiodev volumio[2552]: info: Loading plugin "websocket"... Aug 26 16:42:47 volumiodev volumio[2552]: info: ___________ START PLUGINS ___________ Aug 26 16:42:47 volumiodev volumio[2552]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 16:42:47 volumiodev volumio[2552]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 26 16:42:47 volumiodev volumio[2552]: info: [1724661767870] CoreMusicLibrary::Adding element Media Servers Aug 26 16:42:47 volumiodev volumio[2552]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 16:42:48 volumiodev volumio[2552]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 26 16:42:48 volumiodev volumio[2552]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 26 16:42:48 volumiodev volumio[2552]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 26 16:42:48 volumiodev volumio[2552]: info: [1724661768088] CoreMusicLibrary::Adding element Last_100 Aug 26 16:42:48 volumiodev volumio[2552]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 16:42:48 volumiodev volumio[2552]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 26 16:42:48 volumiodev volumio[2552]: info: [1724661768097] CoreMusicLibrary::Adding element Webradio Aug 26 16:42:48 volumiodev volumio[2552]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 16:42:48 volumiodev volumio[2552]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 26 16:42:48 volumiodev volumio[2552]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 26 16:42:48 volumiodev volumio[2552]: info: [1724661768130] CoreMusicLibrary::Adding element Pandora Radio Aug 26 16:42:48 volumiodev volumio[2552]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 16:42:48 volumiodev volumio[2552]: info: [1724661768136] ControllerPandora::initializeMQTT Aug 26 16:42:48 volumiodev volumio[2552]: info: [1724661768138] [Pandora] initializeMQTT: MQTT is not enabled in Plugin Configuration Aug 26 16:42:48 volumiodev volumio[2552]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 26 16:42:48 volumiodev volumio[2552]: info: [1724661768142] CoreMusicLibrary::Adding element Qobuz Aug 26 16:42:48 volumiodev volumio[2552]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 16:42:48 volumiodev volumio[2552]: info: [1724661768159] ControllerTidalPlugin::onStart Aug 26 16:42:48 volumiodev volumio[2552]: info: [1724661768163] ControllerTidalPlugin::addToBrowseSources Aug 26 16:42:48 volumiodev volumio[2552]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 26 16:42:48 volumiodev volumio[2552]: info: [1724661768165] CoreMusicLibrary::Adding element Tidal Aug 26 16:42:48 volumiodev volumio[2552]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 16:42:48 volumiodev volumio[2552]: [SpotifyConnect2] Creating VLS config file Aug 26 16:42:48 volumiodev volumio[2552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:42:48 volumiodev volumio[2552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:42:48 volumiodev volumio[2552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:42:48 volumiodev volumio[2552]: [SpotifyConnect2] Starting metadata listener Aug 26 16:42:48 volumiodev volumio[2552]: info: Loading i18n strings for locale en Aug 26 16:42:48 volumiodev sudo[2766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Aug 26 16:42:48 volumiodev volumio[2552]: Updating browse sources language Aug 26 16:42:48 volumiodev volumio[2552]: Cannot find translation for sourceMedia Servers Aug 26 16:42:48 volumiodev volumio[2552]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 16:42:48 volumiodev sudo[2766]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:42:48 volumiodev volumio[2552]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 16:42:48 volumiodev volumio[2552]: info: CoreCommandRouter::initPlayerControls Aug 26 16:42:48 volumiodev volumio[2552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:42:48 volumiodev volumio[2552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Aug 26 16:42:48 volumiodev volumio[2552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:42:48 volumiodev sudo[2766]: pam_unix(sudo:session): session closed for user root Aug 26 16:42:48 volumiodev volumio[2552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:42:48 volumiodev volumio[2552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:42:48 volumiodev volumio[2552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:42:48 volumiodev volumio[2552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 16:42:48 volumiodev volumio[2552]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 16:42:48 volumiodev volumio[2552]: info: BOOT COMPLETED Aug 26 16:42:48 volumiodev volumio[2552]: [Metrics] CommandRouter: 19s 780.79ms Aug 26 16:42:48 volumiodev volumio[2552]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 16:42:48 volumiodev kernel: [SNDCODEC][sunxi_card_hw_params][614]:invalid rate setting Aug 26 16:42:48 volumiodev kernel: sunxi-codec-machine 2030340.sound: ASoC: machine hw_params failed: -22 Aug 26 16:42:48 volumiodev volumio[2552]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Aug 26 16:42:48 volumiodev volumio[2552]: aplay: set_params:1432: Unable to install hw params: Aug 26 16:42:48 volumiodev volumio[2552]: ACCESS: RW_INTERLEAVED Aug 26 16:42:48 volumiodev volumio[2552]: FORMAT: S16_LE Aug 26 16:42:48 volumiodev volumio[2552]: SUBFORMAT: STD Aug 26 16:42:48 volumiodev volumio[2552]: SAMPLE_BITS: 16 Aug 26 16:42:48 volumiodev volumio[2552]: FRAME_BITS: 32 Aug 26 16:42:48 volumiodev volumio[2552]: CHANNELS: 2 Aug 26 16:42:48 volumiodev volumio[2552]: RATE: 44100 Aug 26 16:42:48 volumiodev volumio[2552]: PERIOD_TIME: (92879 92880) Aug 26 16:42:48 volumiodev volumio[2552]: PERIOD_SIZE: (4095 4096] Aug 26 16:42:48 volumiodev volumio[2552]: PERIOD_BYTES: (16380 16384] Aug 26 16:42:48 volumiodev volumio[2552]: PERIODS: [4 5) Aug 26 16:42:48 volumiodev volumio[2552]: BUFFER_TIME: (371519 371520) Aug 26 16:42:48 volumiodev volumio[2552]: BUFFER_SIZE: 16384 Aug 26 16:42:48 volumiodev volumio[2552]: BUFFER_BYTES: 65536 Aug 26 16:42:48 volumiodev volumio[2552]: TICK_TIME: 0 Aug 26 16:42:48 volumiodev volumio[2552]: Cannot play startup sound Aug 26 16:42:48 volumiodev volumio[2552]: info: CoreCommandRouter::Close All Modals sent Aug 26 16:42:48 volumiodev volumio[2552]: info: CoreCommandRouter::Close All Modals sent Aug 26 16:42:48 volumiodev volumio[2552]: Express server listening on port 3000 Aug 26 16:42:48 volumiodev volumio[2552]: [Metrics] WebUI: 22s 13.22ms Aug 26 16:42:48 volumiodev volumio[2552]: info: [1724661768783] ControllerPandora::validateAndSetAccountOptions Aug 26 16:42:48 volumiodev volumio[2552]: error: [1724661768786] [Pandora] validateAndSetAccountOptions: Need email address and password. See plugin settings. Aug 26 16:42:48 volumiodev volumio[2552]: info: CoreStateMachine::resetVolumioState Aug 26 16:42:48 volumiodev volumio[2552]: info: CoreStateMachine::getcurrentVolume Aug 26 16:42:48 volumiodev volumio[2552]: info: CoreCommandRouter::volumioRetrievevolume Aug 26 16:42:48 volumiodev volumio[2552]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 16:42:48 volumiodev volumio[2552]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Aug 26 16:42:49 volumiodev systemd-resolved[2117]: Using degraded feature set (UDP) for DNS server 8.8.8.8. Aug 26 16:42:49 volumiodev volumio[2552]: Unable to parse: Aug 26 16:42:49 volumiodev volumio[2552]: Simple mixer control 'Headphone',0 Aug 26 16:42:49 volumiodev volumio[2552]: Capabilities: pswitch pswitch-joined Aug 26 16:42:49 volumiodev volumio[2552]: Playback channels: Mono Aug 26 16:42:49 volumiodev volumio[2552]: Mono: Playback [on] Aug 26 16:42:49 volumiodev volumio[2552]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Aug 26 16:42:49 volumiodev volumio[2552]: [SpotifyConnect2] Vollibrespot Daemon service started! Aug 26 16:42:49 volumiodev volumio[2552]: [Metrics] SpotifyConnect: 1s 360.20ms Aug 26 16:42:49 volumiodev volumio[2552]: info: MPD running with PID2346 Aug 26 16:42:49 volumiodev volumio[2552]: ,establishing connection Aug 26 16:42:49 volumiodev volumio[2552]: info: Permissions for /etc/hostapd/hostapd.conf Aug 26 16:42:49 volumiodev sudo[2793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Aug 26 16:42:49 volumiodev volumio[2552]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 26 16:42:49 volumiodev volumio[2552]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 26 16:42:49 volumiodev sudo[2793]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:42:49 volumiodev volumio[2552]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 26 16:42:49 volumiodev sudo[2801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 26 16:42:49 volumiodev sudo[2799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 26 16:42:50 volumiodev systemd[1]: Stopping Wireless Services... Aug 26 16:42:50 volumiodev sudo[2801]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:42:50 volumiodev sudo[2799]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:42:50 volumiodev kernel: wlan0: deauthenticating from 00:90:4c:62:2e:36 by local choice (Reason: 3=DEAUTH_LEAVING) Aug 26 16:42:50 volumiodev wpa_supplicant[2500]: wlan0: CTRL-EVENT-DISCONNECTED bssid=00:90:4c:62:2e:36 reason=3 locally_generated=1 Aug 26 16:42:50 volumiodev dhcpcd[2741]: wlan0: carrier lost Aug 26 16:42:50 volumiodev hostapd[2407]: wlan0: STA 00:90:4c:62:2e:36 IEEE 802.11: disassociated Aug 26 16:42:50 volumiodev sudo[2801]: pam_unix(sudo:session): session closed for user root Aug 26 16:42:50 volumiodev sudo[2799]: pam_unix(sudo:session): session closed for user root Aug 26 16:42:50 volumiodev systemd-networkd[2079]: wlan0: Lost carrier Aug 26 16:42:50 volumiodev avahi-daemon[2169]: Withdrawing address record for 192.168.100.199 on wlan0. Aug 26 16:42:50 volumiodev avahi-daemon[2169]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.100.199. Aug 26 16:42:50 volumiodev avahi-daemon[2169]: Interface wlan0.IPv4 no longer relevant for mDNS. Aug 26 16:42:50 volumiodev systemd-timesyncd[2066]: No network connectivity, watching for changes. Aug 26 16:42:50 volumiodev volumio[2552]: info: Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Aug 26 16:42:50 volumiodev volumio[2552]: sudo: /usr/sbin/i2cdetect: command not found Aug 26 16:42:50 volumiodev volumio[2552]: Unable to parse: Aug 26 16:42:50 volumiodev volumio[2552]: Simple mixer control 'Headphone',0 Aug 26 16:42:50 volumiodev volumio[2552]: Capabilities: pswitch pswitch-joined Aug 26 16:42:50 volumiodev volumio[2552]: Playback channels: Mono Aug 26 16:42:50 volumiodev volumio[2552]: Mono: Playback [on] Aug 26 16:42:50 volumiodev volumio[2552]: info: VolumeController:: Volume=undefined Mute =false Aug 26 16:42:50 volumiodev volumio[2552]: info: CoreStateMachine::pushState Aug 26 16:42:50 volumiodev volumio[2552]: info: CoreCommandRouter::volumioPushState Aug 26 16:42:50 volumiodev volumio[2552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 16:42:50 volumiodev volumio[2552]: info: CoreStateMachine::updateTrackBlock Aug 26 16:42:50 volumiodev volumio[2552]: info: CorePlayQueue::getTrackBlock Aug 26 16:42:50 volumiodev volumio[2552]: info: CoreCommandRouter::volumioRetrievevolume Aug 26 16:42:50 volumiodev volumio[2552]: info: Reloading queue from file Aug 26 16:42:50 volumiodev volumio[2552]: error: updateQueue error: null Aug 26 16:42:50 volumiodev avahi-daemon[2169]: Interface wlan0.IPv6 no longer relevant for mDNS. Aug 26 16:42:50 volumiodev systemd-networkd[2079]: wlan0: Link DOWN Aug 26 16:42:50 volumiodev avahi-daemon[2169]: Leaving mDNS multicast group on interface wlan0.IPv6 with address fe80::7edd:90ff:fe40:e38b. Aug 26 16:42:50 volumiodev avahi-daemon[2169]: Withdrawing address record for fe80::7edd:90ff:fe40:e38b on wlan0. Aug 26 16:42:50 volumiodev wpa_supplicant[2500]: wlan0: CTRL-EVENT-TERMINATING Aug 26 16:42:50 volumiodev systemd[1]: wireless.service: Succeeded. Aug 26 16:42:50 volumiodev systemd[1]: Stopped Wireless Services. Aug 26 16:42:50 volumiodev systemd[1]: Starting Wireless Services... Aug 26 16:42:50 volumiodev volumio[2552]: Unable to parse: Aug 26 16:42:50 volumiodev volumio[2552]: Simple mixer control 'Headphone',0 Aug 26 16:42:50 volumiodev volumio[2552]: Capabilities: pswitch pswitch-joined Aug 26 16:42:50 volumiodev volumio[2552]: Playback channels: Mono Aug 26 16:42:50 volumiodev volumio[2552]: Mono: Playback [on] Aug 26 16:42:50 volumiodev volumio[2552]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Aug 26 16:42:50 volumiodev sudo[2838]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Aug 26 16:42:51 volumiodev sudo[2838]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:42:51 volumiodev volumio[2784]: Forking 1 albumart workers Aug 26 16:42:51 volumiodev sudo[2838]: pam_unix(sudo:session): session closed for user root Aug 26 16:42:51 volumiodev volumio[2552]: info: CoreStateMachine::setRepeat true single undefined Aug 26 16:42:51 volumiodev volumio[2552]: info: CoreStateMachine::pushState Aug 26 16:42:51 volumiodev volumio[2552]: info: CoreCommandRouter::volumioPushState Aug 26 16:42:51 volumiodev volumio[2552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 16:42:51 volumiodev volumio[2552]: info: CoreStateMachine::setRandom null Aug 26 16:42:51 volumiodev volumio[2552]: info: CoreStateMachine::pushState Aug 26 16:42:51 volumiodev volumio[2552]: info: CoreCommandRouter::volumioPushState Aug 26 16:42:51 volumiodev volumio[2552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 16:42:51 volumiodev volumio[2552]: Unable to parse: Aug 26 16:42:51 volumiodev volumio[2552]: Simple mixer control 'Headphone',0 Aug 26 16:42:51 volumiodev volumio[2552]: Capabilities: pswitch pswitch-joined Aug 26 16:42:51 volumiodev volumio[2552]: Playback channels: Mono Aug 26 16:42:51 volumiodev volumio[2552]: Mono: Playback [on] Aug 26 16:42:51 volumiodev volumio[2552]: info: VolumeController:: Volume=undefined Mute =false Aug 26 16:42:51 volumiodev volumio[2552]: info: CoreStateMachine::pushState Aug 26 16:42:51 volumiodev volumio[2552]: info: CoreCommandRouter::volumioPushState Aug 26 16:42:51 volumiodev volumio[2552]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 16:42:51 volumiodev volumio[2552]: info: Starting Shairport Sync Aug 26 16:42:51 volumiodev volumio[2552]: info: Starting Shairport Sync Aug 26 16:42:51 volumiodev sudo[2865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 26 16:42:51 volumiodev sudo[2865]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:42:51 volumiodev sudo[2868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 26 16:42:51 volumiodev sudo[2868]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:42:51 volumiodev systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 26 16:42:51 volumiodev volumio[2552]: Discovery: adding ec48237d-fca8-40ec-a8cf-208d6a6080a9 Aug 26 16:42:51 volumiodev volumio[2552]: info: mDNS: Found device Volumiodev Aug 26 16:42:51 volumiodev volumio[2552]: error: Could not retrieve plugin audio_interface multiroom Aug 26 16:42:51 volumiodev sudo[2865]: pam_unix(sudo:session): session closed for user root Aug 26 16:42:51 volumiodev volumio[2552]: error: Could not retrieve plugin audio_interface multiroom Aug 26 16:42:51 volumiodev volumio[2552]: Discovery: this is already registered, ec48237d-fca8-40ec-a8cf-208d6a6080a9 Aug 26 16:42:52 volumiodev volumio[2552]: info: mDNS: Found device Volumiodev Aug 26 16:42:52 volumiodev volumio[2552]: error: Could not retrieve plugin audio_interface multiroom Aug 26 16:42:52 volumiodev volumio[2552]: error: Could not retrieve plugin audio_interface multiroom Aug 26 16:42:52 volumiodev systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 26 16:42:52 volumiodev systemd[1]: shairport-sync.service: Succeeded. Aug 26 16:42:52 volumiodev systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 26 16:42:52 volumiodev sudo[2880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 26 16:42:52 volumiodev systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 26 16:42:52 volumiodev sudo[2880]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:42:52 volumiodev sudo[2868]: pam_unix(sudo:session): session closed for user root Aug 26 16:42:52 volumiodev systemd[1]: Started UPnP Renderer front-end to MPD. Aug 26 16:42:52 volumiodev sudo[2880]: pam_unix(sudo:session): session closed for user root Aug 26 16:42:52 volumiodev volumio[2552]: info: Shairport-Sync Started Aug 26 16:42:52 volumiodev systemd[2896]: upmpdcli.service: Failed to execute command: No such file or directory Aug 26 16:42:52 volumiodev volumio[2552]: Error adding Membership: Error: addMembership EINVAL Aug 26 16:42:52 volumiodev volumio[2552]: info: Shairport-Sync Started Aug 26 16:42:52 volumiodev systemd[2896]: upmpdcli.service: Failed at step EXEC spawning /usr/bin/upmpdcli: No such file or directory Aug 26 16:42:52 volumiodev systemd[1]: upmpdcli.service: Main process exited, code=exited, status=203/EXEC Aug 26 16:42:52 volumiodev systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 26 16:42:52 volumiodev volumio[2552]: info: mDNS: A device disapperared from network Aug 26 16:42:52 volumiodev volumio[2552]: error: Could not retrieve plugin audio_interface multiroom Aug 26 16:42:52 volumiodev volumio[2552]: info: mDNS: A device disapperared from network Aug 26 16:42:52 volumiodev volumio[2552]: info: mDNS: Device volumiodev disapperared from network Aug 26 16:42:52 volumiodev volumio[2552]: error: Could not retrieve plugin audio_interface multiroom Aug 26 16:42:52 volumiodev volumio[2552]: info: Upmpdcli Daemon Started Aug 26 16:42:53 volumiodev volumio[2552]: info: mDNS: A device disapperared from network Aug 26 16:42:53 volumiodev volumio[2552]: error: Could not retrieve plugin audio_interface multiroom Aug 26 16:42:53 volumiodev volumio[2552]: info: mDNS: A device disapperared from network Aug 26 16:42:53 volumiodev volumio[2552]: error: Could not retrieve plugin audio_interface multiroom Aug 26 16:42:55 volumiodev wireless.js[2854]: Cleaning previous... Aug 26 16:42:55 volumiodev sudo[2915]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Aug 26 16:42:55 volumiodev sudo[2915]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:42:55 volumiodev sudo[2915]: pam_unix(sudo:session): session closed for user root Aug 26 16:42:55 volumiodev sudo[2924]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Aug 26 16:42:56 volumiodev sudo[2924]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:42:56 volumiodev sudo[2924]: pam_unix(sudo:session): session closed for user root Aug 26 16:42:56 volumiodev volumio[2848]: Starting albumart workers Aug 26 16:42:56 volumiodev wireless.js[2854]: Stopped aP Aug 26 16:42:56 volumiodev wireless.js[2854]: Start wireless flow Aug 26 16:42:56 volumiodev wireless.js[2854]: Stopped hotspot (if there).. Aug 26 16:42:56 volumiodev sudo[2938]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Aug 26 16:42:56 volumiodev volumio[2552]: info: mDNS Browse raised the following error Error: dns service error: unknown Aug 26 16:42:56 volumiodev sudo[2938]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:42:56 volumiodev sudo[2938]: pam_unix(sudo:session): session closed for user root Aug 26 16:42:56 volumiodev sudo[2947]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Aug 26 16:42:56 volumiodev sudo[2947]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:42:56 volumiodev sudo[2947]: pam_unix(sudo:session): session closed for user root Aug 26 16:42:56 volumiodev volumio[2552]: info: mDNS Browse raised the following error Error: dns service error: unknown Aug 26 16:42:56 volumiodev wireless.js[2854]: DHCP IP Aug 26 16:42:56 volumiodev wireless.js[2854]: Start ap Aug 26 16:42:56 volumiodev wpa_supplicant[2956]: Successfully initialized wpa_supplicant Aug 26 16:42:57 volumiodev sudo[2957]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Aug 26 16:42:57 volumiodev sudo[2957]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:42:57 volumiodev dhcpcd[2962]: sending commands to master dhcpcd process Aug 26 16:42:57 volumiodev dhcpcd[2741]: control command: /sbin/dhcpcd Aug 26 16:42:57 volumiodev sudo[2957]: pam_unix(sudo:session): session closed for user root Aug 26 16:42:57 volumiodev volumio[2552]: info: mDNS Browse raised the following error Error: dns service error: unknown Aug 26 16:42:57 volumiodev systemd-networkd[2079]: wlan0: Link UP Aug 26 16:42:57 volumiodev wireless.js[2854]: trying... Aug 26 16:42:57 volumiodev sudo[2968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 26 16:42:58 volumiodev sudo[2968]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:42:58 volumiodev sudo[2968]: pam_unix(sudo:session): session closed for user root Aug 26 16:42:58 volumiodev wireless.js[2854]: trying... Aug 26 16:42:59 volumiodev wpa_supplicant[2966]: wlan0: SME: Trying to authenticate with 00:90:4c:62:2e:36 (SSID='VeriderTech-OFC' freq=2452 MHz) Aug 26 16:42:59 volumiodev kernel: wlan0: authenticate with 00:90:4c:62:2e:36 Aug 26 16:42:59 volumiodev kernel: wlan0: send auth to 00:90:4c:62:2e:36 (try 1/3) Aug 26 16:42:59 volumiodev kernel: wlan0: authenticated Aug 26 16:42:59 volumiodev wpa_supplicant[2966]: wlan0: Trying to associate with 00:90:4c:62:2e:36 (SSID='VeriderTech-OFC' freq=2452 MHz) Aug 26 16:42:59 volumiodev kernel: wlan0: associate with 00:90:4c:62:2e:36 (try 1/3) Aug 26 16:42:59 volumiodev kernel: wlan0: RX AssocResp from 00:90:4c:62:2e:36 (capab=0x11 status=0 aid=8) Aug 26 16:42:59 volumiodev kernel: wlan0: associated Aug 26 16:42:59 volumiodev wpa_supplicant[2966]: nl80211: send_and_recv->nl_recvmsgs failed: -33 Aug 26 16:42:59 volumiodev wpa_supplicant[2966]: wlan0: Associated with 00:90:4c:62:2e:36 Aug 26 16:42:59 volumiodev wpa_supplicant[2966]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 26 16:42:59 volumiodev dhcpcd[2741]: wlan0: carrier acquired Aug 26 16:42:59 volumiodev kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready Aug 26 16:42:59 volumiodev systemd-networkd[2079]: wlan0: Gained carrier Aug 26 16:42:59 volumiodev wpa_supplicant[2966]: wlan0: WPA: Key negotiation completed with 00:90:4c:62:2e:36 [PTK=CCMP GTK=CCMP] Aug 26 16:42:59 volumiodev wpa_supplicant[2966]: wlan0: CTRL-EVENT-CONNECTED - Connection to 00:90:4c:62:2e:36 completed [id=0 id_str=] Aug 26 16:42:59 volumiodev avahi-daemon[2169]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.100.199. Aug 26 16:42:59 volumiodev avahi-daemon[2169]: New relevant interface wlan0.IPv4 for mDNS. Aug 26 16:42:59 volumiodev avahi-daemon[2169]: Registering new address record for 192.168.100.199 on wlan0.IPv4. Aug 26 16:42:59 volumiodev systemd-timesyncd[2066]: Network configuration changed, trying to establish connection. Aug 26 16:42:59 volumiodev sudo[2981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 26 16:42:59 volumiodev wpa_supplicant[2966]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CN Aug 26 16:43:00 volumiodev dhcpcd[2741]: wlan0: soliciting a DHCP lease Aug 26 16:43:00 volumiodev sudo[2981]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:43:00 volumiodev volumio[2552]: Discovery: adding ec48237d-fca8-40ec-a8cf-208d6a6080a9 Aug 26 16:43:00 volumiodev sudo[2981]: pam_unix(sudo:session): session closed for user root Aug 26 16:43:00 volumiodev volumio[2552]: info: mDNS: Found device Volumiodev Aug 26 16:43:00 volumiodev volumio[2552]: error: Could not retrieve plugin audio_interface multiroom Aug 26 16:43:00 volumiodev volumio[2552]: error: Could not retrieve plugin audio_interface multiroom Aug 26 16:43:00 volumiodev wireless.js[2854]: Connected to: ----VeriderTech-OFC Aug 26 16:43:00 volumiodev wireless.js[2854]: ---- Aug 26 16:43:00 volumiodev wireless.js[2854]: trying... Aug 26 16:43:00 volumiodev sudo[2995]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 26 16:43:00 volumiodev sudo[2996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Aug 26 16:43:00 volumiodev sudo[2995]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:43:00 volumiodev sudo[2996]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:43:00 volumiodev sudo[2995]: pam_unix(sudo:session): session closed for user root Aug 26 16:43:00 volumiodev sudo[2996]: pam_unix(sudo:session): session closed for user root Aug 26 16:43:01 volumiodev wireless.js[2854]: Connected to: ----VeriderTech-OFC Aug 26 16:43:01 volumiodev wireless.js[2854]: ---- Aug 26 16:43:01 volumiodev wireless.js[2854]: ... joined AP, wlan0 IPv4 is 192.168.100.199, ipV6 is fe80::7edd:90ff:fe40:e38b Aug 26 16:43:01 volumiodev wireless.js[2854]: It's done! AP Aug 26 16:43:01 volumiodev avahi-daemon[2169]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80::7edd:90ff:fe40:e38b. Aug 26 16:43:01 volumiodev systemd-networkd[2079]: wlan0: Gained IPv6LL Aug 26 16:43:01 volumiodev avahi-daemon[2169]: New relevant interface wlan0.IPv6 for mDNS. Aug 26 16:43:01 volumiodev avahi-daemon[2169]: Registering new address record for fe80::7edd:90ff:fe40:e38b on wlan0.*. Aug 26 16:43:01 volumiodev sudo[3024]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 26 16:43:01 volumiodev sudo[3024]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 16:43:01 volumiodev sudo[3024]: pam_unix(sudo:session): session closed for user root Aug 26 16:43:01 volumiodev systemd-timesyncd[2066]: Network configuration changed, trying to establish connection. Aug 26 16:43:01 volumiodev wireless.js[2854]: ... joined AP, wlan0 IPv4 is 192.168.100.199, ipV6 is fe80::7edd:90ff:fe40:e38b Aug 26 16:43:01 volumiodev wireless.js[2854]: It's done! AP Aug 26 16:43:02 volumiodev systemd-timesyncd[2066]: Initial synchronization to time server 91.189.91.157:123 (ntp.ubuntu.com). Aug 26 16:43:02 volumiodev volumio[2552]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 16:43:02 volumiodev volumio[2552]: TypeError: Cannot read property 'UUID' of undefined Aug 26 16:43:02 volumiodev volumio[2552]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:172:53) Aug 26 16:43:02 volumiodev volumio[2552]: at Browser.emit (events.js:198:13) Aug 26 16:43:02 volumiodev volumio[2552]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Aug 26 16:43:02 volumiodev volumio[2552]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Aug 26 16:43:02 volumiodev volumio[2552]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Aug 26 16:43:02 volumiodev volumio[2552]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Aug 26 16:43:02 volumiodev volumio[2552]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Aug 26 16:43:02 volumiodev volumio[2552]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 16:43:02 volumiodev systemd[1]: Stopping Volspotconnect2 Daemon... Aug 26 16:43:02 volumiodev systemd[1]: volspotconnect2.service: Succeeded. Aug 26 16:43:02 volumiodev systemd[1]: Stopped Volspotconnect2 Daemon. Aug 26 16:43:02 volumiodev systemd[1]: Started Volspotconnect2 Daemon. Aug 26 16:43:03 volumiodev systemd[1]: Started Wireless Services. Aug 26 16:43:03 volumiodev sudo[2793]: pam_unix(sudo:session): session closed for user root Aug 26 16:43:04 volumiodev volumio[3049]: vollibrespot v0.2.4 61563b7 2021-07-22 (librespot 08d8bcc 2020-10-07) -- Built On 2021-07-22 Aug 26 16:43:04 volumiodev volumio[3049]: Reading Config from "volspotify.toml" Aug 26 16:43:04 volumiodev volumio[3049]: [Vollibrespot] : Using Alsa backend with device: plughw:0 Aug 26 16:43:05 volumiodev rc.local[2369]: [2024-08-26 16:43:05] [connect] Successful connection Aug 26 16:43:13 volumiodev sudo[3054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-26 16:42 Aug 26 16:43:13 volumiodev sudo[3054]: 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"