-- Logs begin at Sat 2021-09-25 04:37:22 UTC, end at Sun 2025-12-14 01:48:51 UTC. -- Dec 14 01:48:29 volumio systemd[1]: Time has been changed Dec 14 01:48:30 volumio kernel: usb 1-1.2: USB disconnect, device number 4 Dec 14 01:48:30 volumio kernel: usb 1-1.2: new high-speed USB device number 7 using dwc_otg Dec 14 01:48:31 volumio volumio[1002]: info: Loading plugin "network"... Dec 14 01:48:31 volumio kernel: usb 1-1.2: device descriptor read/all, error -71 Dec 14 01:48:31 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 Dec 14 01:48:31 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 Dec 14 01:48:31 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 Dec 14 01:48:31 volumio kernel: hub 1-1:1.0: hub_ext_port_status failed (err = -71) Dec 14 01:48:31 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 Dec 14 01:48:31 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 Dec 14 01:48:31 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 Dec 14 01:48:31 volumio kernel: usb 1-1-port2: cannot reset (err = -71) Dec 14 01:48:31 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 Dec 14 01:48:31 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 Dec 14 01:48:31 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 Dec 14 01:48:31 volumio kernel: usb 1-1-port2: cannot reset (err = -71) Dec 14 01:48:31 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 Dec 14 01:48:31 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 Dec 14 01:48:31 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 Dec 14 01:48:31 volumio kernel: usb 1-1-port2: cannot reset (err = -71) Dec 14 01:48:31 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 Dec 14 01:48:31 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 Dec 14 01:48:31 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 Dec 14 01:48:31 volumio kernel: usb 1-1-port2: cannot reset (err = -71) Dec 14 01:48:31 volumio kernel: usb 1-1-port2: Cannot enable. Maybe the USB cable is bad? Dec 14 01:48:31 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 Dec 14 01:48:31 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 Dec 14 01:48:31 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 Dec 14 01:48:31 volumio kernel: usb 1-1-port2: cannot disable (err = -71) Dec 14 01:48:31 volumio kernel: usb 1-1-port2: attempt power cycle Dec 14 01:48:31 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 Dec 14 01:48:31 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 Dec 14 01:48:31 volumio kernel: NYET/NAK/ACK/other in non-error case, 0x00000002 Dec 14 01:48:31 volumio volumio[1002]: info: Refreshing Cached IP Addresses Dec 14 01:48:31 volumio sudo[1034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 14 01:48:31 volumio volumio[1002]: info: Loading plugin "touch_display"... Dec 14 01:48:31 volumio sudo[1034]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 01:48:31 volumio sudo[1034]: pam_unix(sudo:session): session closed for user root Dec 14 01:48:31 volumio sudo[1037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 14 01:48:31 volumio sudo[1037]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 01:48:31 volumio kernel: usb usb1-port1: disabled by hub (EMI?), re-enabling... Dec 14 01:48:31 volumio kernel: usb 1-1: USB disconnect, device number 2 Dec 14 01:48:31 volumio sudo[1037]: pam_unix(sudo:session): session closed for user root Dec 14 01:48:31 volumio kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to read register index 0x00000120. ret = -19 Dec 14 01:48:31 volumio kernel: usb 1-1.1: USB disconnect, device number 3 Dec 14 01:48:31 volumio kernel: usb 1-1.1.1: USB disconnect, device number 6 Dec 14 01:48:31 volumio kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to read register index 0x00000120. ret = -19 Dec 14 01:48:31 volumio kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to read register index 0x00000120. ret = -19 Dec 14 01:48:31 volumio kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to read register index 0x00000098. ret = -19 Dec 14 01:48:31 volumio kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to write register index 0x00000098. ret = -19 Dec 14 01:48:31 volumio kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to read register index 0x00000098. ret = -19 Dec 14 01:48:31 volumio kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to write register index 0x00000098. ret = -19 Dec 14 01:48:31 volumio kernel: lan78xx 1-1.1.1:1.0 eth0: Failed to read register index 0x00000120. ret = -19 Dec 14 01:48:31 volumio dhcpcd[994]: eth0: removing interface Dec 14 01:48:31 volumio dhcpcd[510]: eth0: removing interface Dec 14 01:48:31 volumio avahi-daemon[664]: Withdrawing workstation service for eth0. Dec 14 01:48:31 volumio ifup[476]: dhcpcd[510]: eth0: removing interface Dec 14 01:48:31 volumio systemd[1]: Stopping ifup for eth0... Dec 14 01:48:31 volumio dhcpcd[510]: script_runreason: /lib/dhcpcd/dhcpcd-run-hooks: Terminated Dec 14 01:48:31 volumio dhcpcd[510]: exited Dec 14 01:48:31 volumio ifup[476]: dhcpcd[510]: script_runreason: /lib/dhcpcd/dhcpcd-run-hooks: Terminated Dec 14 01:48:31 volumio ifup[476]: dhcpcd[510]: exited Dec 14 01:48:31 volumio kernel: usb 1-1.1.3: USB disconnect, device number 5 Dec 14 01:48:31 volumio kernel: WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 7 Dec 14 01:48:31 volumio kernel: RTL871X: rtw_cmd_thread: DriverStopped(True) SurpriseRemoved(True) break at line 581 Dec 14 01:48:31 volumio wpa_supplicant[908]: wlan0: CTRL-EVENT-DISCONNECTED bssid=54:84:dc:ce:e3:c8 reason=3 locally_generated=1 Dec 14 01:48:31 volumio avahi-daemon[664]: Interface wlan0.IPv4 no longer relevant for mDNS. Dec 14 01:48:31 volumio avahi-daemon[664]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.3. Dec 14 01:48:31 volumio dhcpcd[994]: wlan0: carrier lost Dec 14 01:48:31 volumio dhcpcd[994]: wlan0: deleting host route to 192.168.1.3 via 127.0.0.1 Dec 14 01:48:31 volumio dhcpcd[1091]: sending commands to master dhcpcd process Dec 14 01:48:31 volumio ifdown[1061]: dhcpcd[1091]: sending commands to master dhcpcd process Dec 14 01:48:31 volumio kernel: RTL871X: rtw_ndev_uninit(wlan0) if1 Dec 14 01:48:31 volumio avahi-daemon[664]: Withdrawing address record for 192.168.1.3 on wlan0. Dec 14 01:48:31 volumio dhcpcd[994]: wlan0: deleting route to 192.168.1.0/24 Dec 14 01:48:31 volumio dhcpcd[994]: wlan0: deleting default route via 192.168.1.1 Dec 14 01:48:31 volumio avahi-daemon[664]: Withdrawing workstation service for wlan0. Dec 14 01:48:31 volumio ifdown[1061]: Cannot find device "eth0" Dec 14 01:48:31 volumio systemd[1]: Stopping ifup for wlan0... Dec 14 01:48:31 volumio dhcpcd[994]: wlan0: removing interface Dec 14 01:48:31 volumio kernel: RTL871X: rtw_dev_unload: driver not in IPS Dec 14 01:48:31 volumio systemd[1]: Stopped ifup for eth0. Dec 14 01:48:31 volumio systemd[1]: Stopped ifup for wlan0. Dec 14 01:48:31 volumio dhcpcd[994]: control command: dhcpcd -k eth0 Dec 14 01:48:31 volumio kernel: Indeed it is in host mode hprt0 = 00001501 Dec 14 01:48:32 volumio volumio[1002]: info: Applying required configuration parameters for plugin touch_display Dec 14 01:48:32 volumio kernel: usb 1-1: new high-speed USB device number 11 using dwc_otg Dec 14 01:48:32 volumio kernel: Indeed it is in host mode hprt0 = 00001101 Dec 14 01:48:32 volumio volumio[1002]: info: Loading plugin "services"... Dec 14 01:48:32 volumio kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=2514, bcdDevice= b.b3 Dec 14 01:48:32 volumio kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Dec 14 01:48:32 volumio kernel: hub 1-1:1.0: USB hub found Dec 14 01:48:32 volumio kernel: hub 1-1:1.0: 4 ports detected Dec 14 01:48:32 volumio volumio[1002]: info: Loading plugin "alsa_controller"... Dec 14 01:48:32 volumio volumio-remote-updater[600]: [2025-12-14 01:48:32] [connect] Successful connection Dec 14 01:48:32 volumio volumio[1002]: info: Loading plugin "wizard"... Dec 14 01:48:32 volumio volumio[1002]: info: Loading plugin "volumio_command_line_client"... Dec 14 01:48:32 volumio volumio[1002]: info: Loading plugin "upnp"... Dec 14 01:48:32 volumio volumio[1002]: info: [1765676912677] Starting Upmpd Daemon Dec 14 01:48:32 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 14 01:48:32 volumio volumio[1002]: info: Loading plugin "my_music"... Dec 14 01:48:32 volumio volumio[1002]: info: Loading plugin "mpd"... Dec 14 01:48:32 volumio kernel: usb 1-1.1: new high-speed USB device number 12 using dwc_otg Dec 14 01:48:32 volumio kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=2514, bcdDevice= b.b3 Dec 14 01:48:32 volumio kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Dec 14 01:48:32 volumio kernel: hub 1-1.1:1.0: USB hub found Dec 14 01:48:32 volumio kernel: hub 1-1.1:1.0: 3 ports detected Dec 14 01:48:32 volumio ntpd[729]: Deleting interface #3 wlan0, 192.168.1.3#123, interface stats: received=15, sent=15, dropped=3, active_time=12 secs Dec 14 01:48:32 volumio ntpd[729]: 160.25.135.55 interface 192.168.1.3 -> (none) Dec 14 01:48:32 volumio ntpd[729]: 153.127.38.46 interface 192.168.1.3 -> (none) Dec 14 01:48:32 volumio ntpd[729]: 199.189.156.6 interface 192.168.1.3 -> (none) Dec 14 01:48:32 volumio ntpd[729]: 138.3.209.153 interface 192.168.1.3 -> (none) Dec 14 01:48:32 volumio ntpd[729]: peers refreshed Dec 14 01:48:33 volumio volumio[1002]: info: Loading plugin "upnp_browser"... Dec 14 01:48:34 volumio volumio[1002]: info: Loading plugin "networkfs"... Dec 14 01:48:34 volumio kernel: usb 1-1-port2: Cannot enable. Maybe the USB cable is bad? Dec 14 01:48:34 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 14 01:48:34 volumio volumio[1002]: info: Loading plugin "alarm-clock"... Dec 14 01:48:34 volumio volumio[1002]: info: Loading plugin "airplay_emulation"... Dec 14 01:48:34 volumio volumio[1002]: info: Starting Shairport Sync Dec 14 01:48:34 volumio volumio[1002]: info: Loading plugin "last_100"... Dec 14 01:48:34 volumio volumio[1002]: info: Loading plugin "webradio"... Dec 14 01:48:34 volumio volumio[1002]: info: Loading plugin "i2s_dacs"... Dec 14 01:48:34 volumio volumio[1002]: info: I2S DAC not set, start Auto-detection Dec 14 01:48:34 volumio volumio[1002]: info: Loading plugin "volumiodiscovery"... Dec 14 01:48:34 volumio volumio[1002]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 14 01:48:34 volumio node[1002]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 14 01:48:34 volumio node[1002]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 14 01:48:34 volumio node[1002]: *** WARNING *** For more information see Dec 14 01:48:34 volumio node[1002]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 14 01:48:34 volumio node[1002]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 14 01:48:34 volumio node[1002]: *** WARNING *** For more information see Dec 14 01:48:34 volumio volumio[1002]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 14 01:48:34 volumio volumio[1002]: *** WARNING *** For more information see Dec 14 01:48:34 volumio volumio[1002]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 14 01:48:34 volumio volumio[1002]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 14 01:48:34 volumio volumio[1002]: *** WARNING *** For more information see Dec 14 01:48:34 volumio volumio[1002]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 14 01:48:34 volumio volumio[1002]: Discovery: StartAdv! undefined Dec 14 01:48:34 volumio volumio[1002]: Discovery: Started advertising... Volumio - undefined Dec 14 01:48:34 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 14 01:48:34 volumio volumio[1002]: info: Loading plugin "auto_play"... Dec 14 01:48:34 volumio volumio[1002]: info: ControllerAutoPlay - connecting mpd on host: localhost; port: 6600 Dec 14 01:48:34 volumio volumio[1002]: info: Loading plugin "outputs"... Dec 14 01:48:34 volumio volumio[1002]: info: Loading plugin "albumart"... Dec 14 01:48:34 volumio kernel: usb 1-1.2: new high-speed USB device number 15 using dwc_otg Dec 14 01:48:34 volumio volumio[1002]: info: Plugin example_plugin is not enabled Dec 14 01:48:34 volumio volumio[1002]: info: Loading plugin "inputs"... Dec 14 01:48:34 volumio nmbd[890]: [2025/12/14 01:48:34.793410, 0] ../source3/libsmb/nmblib.c:873(send_udp) Dec 14 01:48:34 volumio nmbd[890]: Packet send failed to 192.168.1.255(138) ERRNO=Network is unreachable Dec 14 01:48:34 volumio nmbd[890]: [2025/12/14 01:48:34.793658, 0] ../source3/libsmb/nmblib.c:873(send_udp) Dec 14 01:48:34 volumio nmbd[890]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Dec 14 01:48:34 volumio nmbd[890]: [2025/12/14 01:48:34.793721, 0] ../source3/nmbd/nmbd_packets.c:179(send_netbios_packet) Dec 14 01:48:34 volumio nmbd[890]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Dec 14 01:48:34 volumio nmbd[890]: [2025/12/14 01:48:34.793784, 0] ../source3/libsmb/nmblib.c:873(send_udp) Dec 14 01:48:34 volumio nmbd[890]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Dec 14 01:48:34 volumio nmbd[890]: [2025/12/14 01:48:34.793836, 0] ../source3/nmbd/nmbd_packets.c:179(send_netbios_packet) Dec 14 01:48:34 volumio nmbd[890]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Dec 14 01:48:34 volumio nmbd[890]: [2025/12/14 01:48:34.793893, 0] ../source3/libsmb/nmblib.c:873(send_udp) Dec 14 01:48:34 volumio nmbd[890]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Dec 14 01:48:34 volumio nmbd[890]: [2025/12/14 01:48:34.793945, 0] ../source3/nmbd/nmbd_packets.c:179(send_netbios_packet) Dec 14 01:48:34 volumio nmbd[890]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Dec 14 01:48:34 volumio nmbd[890]: [2025/12/14 01:48:34.795047, 0] ../source3/libsmb/nmblib.c:873(send_udp) Dec 14 01:48:34 volumio nmbd[890]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Dec 14 01:48:34 volumio nmbd[890]: [2025/12/14 01:48:34.796016, 0] ../source3/nmbd/nmbd_packets.c:179(send_netbios_packet) Dec 14 01:48:34 volumio nmbd[890]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Dec 14 01:48:34 volumio nmbd[890]: [2025/12/14 01:48:34.797226, 0] ../source3/libsmb/nmblib.c:873(send_udp) Dec 14 01:48:34 volumio nmbd[890]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Dec 14 01:48:34 volumio nmbd[890]: [2025/12/14 01:48:34.798393, 0] ../source3/nmbd/nmbd_packets.c:179(send_netbios_packet) Dec 14 01:48:34 volumio nmbd[890]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Dec 14 01:48:34 volumio nmbd[890]: [2025/12/14 01:48:34.800020, 0] ../source3/libsmb/nmblib.c:873(send_udp) Dec 14 01:48:34 volumio nmbd[890]: Packet send failed to 192.168.1.255(137) ERRNO=Network is unreachable Dec 14 01:48:34 volumio nmbd[890]: [2025/12/14 01:48:34.801327, 0] ../source3/nmbd/nmbd_packets.c:179(send_netbios_packet) Dec 14 01:48:34 volumio nmbd[890]: send_netbios_packet: send_packet() to IP 192.168.1.255 port 137 failed Dec 14 01:48:34 volumio nmbd[890]: [2025/12/14 01:48:34.802579, 0] ../source3/nmbd/nmbd_namequery.c:245(query_name) Dec 14 01:48:34 volumio nmbd[890]: query_name: Failed to send packet trying to query name WORKGROUP<1d> Dec 14 01:48:34 volumio nmbd[890]: [2025/12/14 01:48:34.804597, 0] ../source3/nmbd/nmbd.c:362(reload_interfaces) Dec 14 01:48:34 volumio nmbd[890]: reload_interfaces: No subnets to listen to. Waiting.. Dec 14 01:48:34 volumio volumio[1002]: info: Loading plugin "updater_comm"... Dec 14 01:48:34 volumio kernel: usb 1-1.2: New USB device found, idVendor=17d9, idProduct=002b, bcdDevice= 3.30 Dec 14 01:48:34 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 14 01:48:34 volumio kernel: usb 1-1.2: Product: Rasteme RUA22A USB HD Audio Dec 14 01:48:34 volumio kernel: usb 1-1.2: Manufacturer: Rasteme Dec 14 01:48:34 volumio kernel: usb 1-1.2: SerialNumber: 0000 Dec 14 01:48:35 volumio kernel: usb 1-1.1.3: new high-speed USB device number 14 using dwc_otg Dec 14 01:48:35 volumio volumio[1002]: info: Plugin mpdemulation is not enabled Dec 14 01:48:35 volumio volumio[1002]: info: Loading plugin "rest_api"... Dec 14 01:48:35 volumio volumio[1002]: info: Loading plugin "websocket"... Dec 14 01:48:35 volumio kernel: usb 1-1.1.3: New USB device found, idVendor=2357, idProduct=0109, bcdDevice= 2.00 Dec 14 01:48:35 volumio kernel: usb 1-1.1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 14 01:48:35 volumio kernel: usb 1-1.1.3: Product: 802.11n NIC Dec 14 01:48:35 volumio kernel: usb 1-1.1.3: Manufacturer: Realtek Dec 14 01:48:35 volumio kernel: usb 1-1.1.3: SerialNumber: 00e04c000001 Dec 14 01:48:35 volumio volumio[1002]: info: Loading plugin "Systeminfo"... Dec 14 01:48:35 volumio volumio[1002]: Forking 3 albumart workers Dec 14 01:48:35 volumio kernel: RTL871X: hal_com_config_channel_plan chplan:0x21 Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (CCK,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: Wrong rate section: (OFDM,2T) Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation FCC][bw 20M][rate section HT][rf path 2T][chnl 01] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation ETSI][bw 20M][rate section HT][rf path 2T][chnl 01] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation MKK][bw 20M][rate section HT][rf path 2T][chnl 01] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation FCC][bw 20M][rate section HT][rf path 2T][chnl 02] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation ETSI][bw 20M][rate section HT][rf path 2T][chnl 02] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation MKK][bw 20M][rate section HT][rf path 2T][chnl 02] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation FCC][bw 20M][rate section HT][rf path 2T][chnl 03] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation ETSI][bw 20M][rate section HT][rf path 2T][chnl 03] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation MKK][bw 20M][rate section HT][rf path 2T][chnl 03] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation FCC][bw 20M][rate section HT][rf path 2T][chnl 04] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation ETSI][bw 20M][rate section HT][rf path 2T][chnl 04] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation MKK][bw 20M][rate section HT][rf path 2T][chnl 04] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation FCC][bw 20M][rate section HT][rf path 2T][chnl 05] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation ETSI][bw 20M][rate section HT][rf path 2T][chnl 05] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation MKK][bw 20M][rate section HT][rf path 2T][chnl 05] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation FCC][bw 20M][rate section HT][rf path 2T][chnl 06] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation ETSI][bw 20M][rate section HT][rf path 2T][chnl 06] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation MKK][bw 20M][rate section HT][rf path 2T][chnl 06] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation FCC][bw 20M][rate section HT][rf path 2T][chnl 07] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation ETSI][bw 20M][rate section HT][rf path 2T][chnl 07] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation MKK][bw 20M][rate section HT][rf path 2T][chnl 07] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation FCC][bw 20M][rate section HT][rf path 2T][chnl 08] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation ETSI][bw 20M][rate section HT][rf path 2T][chnl 08] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation MKK][bw 20M][rate section HT][rf path 2T][chnl 08] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation FCC][bw 20M][rate section HT][rf path 2T][chnl 09] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation ETSI][bw 20M][rate section HT][rf path 2T][chnl 09] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation MKK][bw 20M][rate section HT][rf path 2T][chnl 09] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation FCC][bw 20M][rate section HT][rf path 2T][chnl 10] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation ETSI][bw 20M][rate section HT][rf path 2T][chnl 10] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation MKK][bw 20M][rate section HT][rf path 2T][chnl 10] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation FCC][bw 20M][rate section HT][rf path 2T][chnl 11] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation ETSI][bw 20M][rate section HT][rf path 2T][chnl 11] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation MKK][bw 20M][rate section HT][rf path 2T][chnl 11] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation ETSI][bw 20M][rate section HT][rf path 2T][chnl 12] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation MKK][bw 20M][rate section HT][rf path 2T][chnl 12] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation ETSI][bw 20M][rate section HT][rf path 2T][chnl 13] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation MKK][bw 20M][rate section HT][rf path 2T][chnl 13] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation FCC][bw 40M][rate section HT][rf path 2T][chnl 03] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation ETSI][bw 40M][rate section HT][rf path 2T][chnl 03] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation MKK][bw 40M][rate section HT][rf path 2T][chnl 03] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation FCC][bw 40M][rate section HT][rf path 2T][chnl 04] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation ETSI][bw 40M][rate section HT][rf path 2T][chnl 04] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation MKK][bw 40M][rate section HT][rf path 2T][chnl 04] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation FCC][bw 40M][rate section HT][rf path 2T][chnl 05] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation ETSI][bw 40M][rate section HT][rf path 2T][chnl 05] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation MKK][bw 40M][rate section HT][rf path 2T][chnl 05] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation FCC][bw 40M][rate section HT][rf path 2T][chnl 06] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation ETSI][bw 40M][rate section HT][rf path 2T][chnl 06] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation MKK][bw 40M][rate section HT][rf path 2T][chnl 06] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation FCC][bw 40M][rate section HT][rf path 2T][chnl 07] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation ETSI][bw 40M][rate section HT][rf path 2T][chnl 07] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation MKK][bw 40M][rate section HT][rf path 2T][chnl 07] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation FCC][bw 40M][rate section HT][rf path 2T][chnl 08] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation ETSI][bw 40M][rate section HT][rf path 2T][chnl 08] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation MKK][bw 40M][rate section HT][rf path 2T][chnl 08] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation FCC][bw 40M][rate section HT][rf path 2T][chnl 09] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation ETSI][bw 40M][rate section HT][rf path 2T][chnl 09] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation MKK][bw 40M][rate section HT][rf path 2T][chnl 09] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation FCC][bw 40M][rate section HT][rf path 2T][chnl 10] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation ETSI][bw 40M][rate section HT][rf path 2T][chnl 10] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation MKK][bw 40M][rate section HT][rf path 2T][chnl 10] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation FCC][bw 40M][rate section HT][rf path 2T][chnl 11] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation ETSI][bw 40M][rate section HT][rf path 2T][chnl 11] Dec 14 01:48:35 volumio kernel: RTL871X: duplicate tx power limit combination [band 2.4G][regulation MKK][bw 40M][rate section HT][rf path 2T][chnl 11] Dec 14 01:48:35 volumio kernel: RTL871X: rtw_ndev_init(wlan0) if1 mac_addr=7c:c2:c6:0c:ac:8f Dec 14 01:48:35 volumio kernel: usb 1-1.2: 1:1: cannot set freq 192000 (v2/v3): err -71 Dec 14 01:48:35 volumio kernel: usb 1-1.2: cannot get ctl value: req = 0x83, wValue = 0x201, wIndex = 0xa00, type = 4 Dec 14 01:48:35 volumio kernel: usb 1-1.2: 10:0: cannot get min/max values for control 2 (id 10) Dec 14 01:48:35 volumio kernel: usb 1-1.2: cannot get ctl value: req = 0x83, wValue = 0x200, wIndex = 0xa00, type = 4 Dec 14 01:48:35 volumio kernel: usb 1-1.2: 10:0: cannot get min/max values for control 2 (id 10) Dec 14 01:48:35 volumio kernel: usb 1-1.2: USB disconnect, device number 15 Dec 14 01:48:35 volumio kernel: usb 1-1.1.1: new high-speed USB device number 16 using dwc_otg Dec 14 01:48:35 volumio kernel: usb 1-1.1.1: New USB device found, idVendor=0424, idProduct=7800, bcdDevice= 3.00 Dec 14 01:48:35 volumio kernel: usb 1-1.1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Dec 14 01:48:35 volumio kernel: usb 1-1.2: new high-speed USB device number 17 using dwc_otg Dec 14 01:48:35 volumio kernel: usb 1-1.2: New USB device found, idVendor=17d9, idProduct=002b, bcdDevice= 3.30 Dec 14 01:48:35 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 14 01:48:35 volumio kernel: usb 1-1.2: Product: Rasteme RUA22A USB HD Audio Dec 14 01:48:35 volumio kernel: usb 1-1.2: Manufacturer: Rasteme Dec 14 01:48:35 volumio kernel: usb 1-1.2: SerialNumber: 0000 Dec 14 01:48:36 volumio kernel: lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): No External EEPROM. Setting MAC Speed Dec 14 01:48:36 volumio kernel: libphy: lan78xx-mdiobus: probed Dec 14 01:48:36 volumio kernel: lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): int urb period 64 Dec 14 01:48:36 volumio kernel: usb 1-1.2: USB disconnect, device number 17 Dec 14 01:48:36 volumio volumio[1002]: info: Loading plugin "minidlna"... Dec 14 01:48:37 volumio ifplugd(eth0)[705]: Using detection mode: SIOCETHTOOL Dec 14 01:48:37 volumio dhcpcd[994]: wlan0: waiting for carrier Dec 14 01:48:37 volumio volumio[1002]: Starting albumart workers Dec 14 01:48:37 volumio dhcpcd[994]: eth0: waiting for carrier Dec 14 01:48:37 volumio volumio[1002]: Starting albumart workers Dec 14 01:48:37 volumio volumio[1002]: info: Applying required configuration parameters for plugin minidlna Dec 14 01:48:37 volumio kernel: usb 1-1.2: new high-speed USB device number 18 using dwc_otg Dec 14 01:48:37 volumio volumio[1002]: Starting albumart workers Dec 14 01:48:37 volumio kernel: usb 1-1.2: New USB device found, idVendor=17d9, idProduct=002b, bcdDevice= 3.30 Dec 14 01:48:37 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 14 01:48:37 volumio kernel: usb 1-1.2: Product: Rasteme RUA22A USB HD Audio Dec 14 01:48:37 volumio kernel: usb 1-1.2: Manufacturer: Rasteme Dec 14 01:48:37 volumio kernel: usb 1-1.2: SerialNumber: 0000 Dec 14 01:48:37 volumio volumio[1002]: info: Loading plugin "80s80s"... Dec 14 01:48:38 volumio kernel: usb 1-1.2: USB disconnect, device number 18 Dec 14 01:48:38 volumio kernel: usb 1-1.2: new high-speed USB device number 19 using dwc_otg Dec 14 01:48:38 volumio volumio[1002]: info: Applying required configuration parameters for plugin 80s80s Dec 14 01:48:38 volumio volumio[1002]: info: [1765676918681] [80s80s] API delay: 30 Dec 14 01:48:38 volumio volumio[1002]: info: Loading plugin "lms"... Dec 14 01:48:38 volumio wpa_supplicant[908]: wlan0: Trying to associate with 54:84:dc:ce:e3:c8 (SSID='F660A-3exC-G' freq=2462 MHz) Dec 14 01:48:38 volumio kernel: usb 1-1.2: device not accepting address 19, error -71 Dec 14 01:48:38 volumio kernel: RTL871X: rtw_set_802_11_connect(wlan0) fw_state=0x00000008 Dec 14 01:48:38 volumio kernel: RTL871X: start auth Dec 14 01:48:39 volumio kernel: RTL871X: auth success, start assoc Dec 14 01:48:39 volumio kernel: RTL871X: rtw_cfg80211_indicate_connect(wlan0) BSS not found !! Dec 14 01:48:39 volumio kernel: RTL871X: assoc success Dec 14 01:48:39 volumio wpa_supplicant[908]: wlan0: Associated with 54:84:dc:ce:e3:c8 Dec 14 01:48:39 volumio kernel: RTL871X: recv eapol packet Dec 14 01:48:39 volumio kernel: RTL871X: send eapol packet Dec 14 01:48:39 volumio volumio[1002]: info: Loading plugin "spop"... Dec 14 01:48:39 volumio kernel: RTL871X: recv eapol packet Dec 14 01:48:39 volumio kernel: RTL871X: send eapol packet Dec 14 01:48:39 volumio wpa_supplicant[908]: wlan0: WPA: Key negotiation completed with 54:84:dc:ce:e3:c8 [PTK=CCMP GTK=TKIP] Dec 14 01:48:39 volumio kernel: RTL871X: set pairwise key camid:4, addr:54:84:dc:ce:e3:c8, kid:0, type:AES Dec 14 01:48:39 volumio kernel: RTL871X: set group key camid:5, addr:54:84:dc:ce:e3:c8, kid:1, type:TKIP Dec 14 01:48:39 volumio wpa_supplicant[908]: wlan0: CTRL-EVENT-CONNECTED - Connection to 54:84:dc:ce:e3:c8 completed [id=0 id_str=] Dec 14 01:48:39 volumio dhcpcd[994]: wlan0: carrier acquired Dec 14 01:48:39 volumio wpa_supplicant[908]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=JP Dec 14 01:48:39 volumio dhcpcd[994]: wlan0: soliciting a DHCP lease Dec 14 01:48:39 volumio kernel: usb 1-1.2: new high-speed USB device number 20 using dwc_otg Dec 14 01:48:39 volumio kernel: usb 1-1.2: New USB device found, idVendor=17d9, idProduct=002b, bcdDevice= 3.30 Dec 14 01:48:39 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 14 01:48:39 volumio kernel: usb 1-1.2: Product: Rasteme RUA22A USB HD Audio Dec 14 01:48:39 volumio kernel: usb 1-1.2: Manufacturer: Rasteme Dec 14 01:48:39 volumio kernel: usb 1-1.2: SerialNumber: 0000 Dec 14 01:48:40 volumio kernel: usb 1-1.2: USB disconnect, device number 20 Dec 14 01:48:40 volumio kernel: usb 1-1.2: new high-speed USB device number 21 using dwc_otg Dec 14 01:48:40 volumio volumio[1002]: info: Loading plugin "squeezelite"... Dec 14 01:48:41 volumio kernel: usb 1-1.2: New USB device found, idVendor=17d9, idProduct=002b, bcdDevice= 3.30 Dec 14 01:48:41 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 14 01:48:41 volumio kernel: usb 1-1.2: Product: Rasteme RUA22A USB HD Audio Dec 14 01:48:41 volumio kernel: usb 1-1.2: Manufacturer: Rasteme Dec 14 01:48:41 volumio kernel: usb 1-1.2: SerialNumber: 0000 Dec 14 01:48:41 volumio dhcpcd[994]: wlan0: offered 192.168.1.3 from 192.168.1.1 Dec 14 01:48:41 volumio kernel: usb 1-1.2: USB disconnect, device number 21 Dec 14 01:48:41 volumio dhcpcd[994]: wlan0: leased 192.168.1.3 for 86400 seconds Dec 14 01:48:41 volumio avahi-daemon[664]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.3. Dec 14 01:48:41 volumio avahi-daemon[664]: New relevant interface wlan0.IPv4 for mDNS. Dec 14 01:48:41 volumio dhcpcd[994]: wlan0: adding host route to 192.168.1.3 via 127.0.0.1 Dec 14 01:48:41 volumio avahi-daemon[664]: Registering new address record for 192.168.1.3 on wlan0.IPv4. Dec 14 01:48:41 volumio dhcpcd[994]: wlan0: adding route to 192.168.1.0/24 Dec 14 01:48:41 volumio dhcpcd[994]: wlan0: adding default route via 192.168.1.1 Dec 14 01:48:41 volumio volumio[1002]: info: Squeezelite initiated Dec 14 01:48:41 volumio volumio[1002]: info: Loading plugin "volspotconnect2"... Dec 14 01:48:41 volumio kernel: usb 1-1.2: new high-speed USB device number 22 using dwc_otg Dec 14 01:48:41 volumio kernel: usb 1-1.2: New USB device found, idVendor=17d9, idProduct=002b, bcdDevice= 3.30 Dec 14 01:48:41 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 14 01:48:41 volumio kernel: usb 1-1.2: Product: Rasteme RUA22A USB HD Audio Dec 14 01:48:41 volumio kernel: usb 1-1.2: Manufacturer: Rasteme Dec 14 01:48:41 volumio kernel: usb 1-1.2: SerialNumber: 0000 Dec 14 01:48:42 volumio kernel: usb 1-1.2: USB disconnect, device number 22 Dec 14 01:48:42 volumio kernel: usb 1-1.2: new high-speed USB device number 23 using dwc_otg Dec 14 01:48:42 volumio ntpd[729]: Listen normally on 4 wlan0 192.168.1.3 UDP 123 Dec 14 01:48:42 volumio ntpd[729]: peers refreshed Dec 14 01:48:43 volumio kernel: usb 1-1.2: New USB device found, idVendor=17d9, idProduct=002b, bcdDevice= 3.30 Dec 14 01:48:43 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 14 01:48:43 volumio kernel: usb 1-1.2: Product: Rasteme RUA22A USB HD Audio Dec 14 01:48:43 volumio kernel: usb 1-1.2: Manufacturer: Rasteme Dec 14 01:48:43 volumio kernel: usb 1-1.2: SerialNumber: 0000 Dec 14 01:48:43 volumio kernel: usb 1-1.2: USB disconnect, device number 23 Dec 14 01:48:43 volumio volumio[1002]: info: Loading plugin "backup_restore"... Dec 14 01:48:43 volumio kernel: usb 1-1.2: new high-speed USB device number 24 using dwc_otg Dec 14 01:48:43 volumio kernel: usb 1-1.2: New USB device found, idVendor=17d9, idProduct=002b, bcdDevice= 3.30 Dec 14 01:48:43 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 14 01:48:43 volumio kernel: usb 1-1.2: Product: Rasteme RUA22A USB HD Audio Dec 14 01:48:43 volumio kernel: usb 1-1.2: Manufacturer: Rasteme Dec 14 01:48:43 volumio kernel: usb 1-1.2: SerialNumber: 0000 Dec 14 01:48:43 volumio volumio[1002]: info: ___________ START PLUGINS ___________ Dec 14 01:48:43 volumio volumio[1002]: info: Loading i18n strings for locale ja Dec 14 01:48:43 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 01:48:43 volumio volumio[1002]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 14 01:48:43 volumio volumio[1002]: info: [1765676923927] CoreMusicLibrary::Adding element Media Servers Dec 14 01:48:43 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 01:48:43 volumio sudo[1381]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 14 01:48:43 volumio sudo[1381]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 01:48:43 volumio systemd[1]: Reloading. Dec 14 01:48:44 volumio volumio[1002]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 14 01:48:44 volumio systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' Dec 14 01:48:44 volumio systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' Dec 14 01:48:44 volumio systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' Dec 14 01:48:44 volumio systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' Dec 14 01:48:44 volumio kernel: usb 1-1.2: USB disconnect, device number 24 Dec 14 01:48:44 volumio volumio[1002]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 14 01:48:44 volumio volumio[1002]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 14 01:48:44 volumio volumio[1002]: info: [1765676924211] CoreMusicLibrary::Adding element Last_100 Dec 14 01:48:44 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 01:48:44 volumio volumio[1002]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 14 01:48:44 volumio volumio[1002]: info: [1765676924223] CoreMusicLibrary::Adding element Webradio Dec 14 01:48:44 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 01:48:44 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 14 01:48:44 volumio systemd[1]: Out of memory. Dec 14 01:48:44 volumio volumio[1002]: info: Loading i18n strings for locale ja Dec 14 01:48:44 volumio sudo[1381]: pam_unix(sudo:session): session closed for user root Dec 14 01:48:44 volumio volumio[1002]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 14 01:48:44 volumio volumio[1002]: info: [1765676924299] CoreMusicLibrary::Adding element 80s80s Radio Dec 14 01:48:44 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 01:48:44 volumio sudo[1407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Dec 14 01:48:44 volumio sudo[1407]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 01:48:44 volumio sudo[1409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start spop.service Dec 14 01:48:44 volumio volumio[1002]: [SpotifyConnect] Creating VLS config file Dec 14 01:48:44 volumio sudo[1409]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 01:48:44 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 01:48:44 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 01:48:44 volumio systemd[1]: Starting Logitech Media Server Daemon... Dec 14 01:48:44 volumio volumio[1002]: [SpotifyConnect] <> or Mixer found, using softvol Dec 14 01:48:44 volumio volumio[1002]: [SpotifyConnect] Starting metadata listener Dec 14 01:48:44 volumio sudo[1416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 14 01:48:44 volumio sudo[1416]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 01:48:44 volumio volumio[1002]: info: ------------------------------------------- Dec 14 01:48:44 volumio volumio[1002]: info: ----- MyVolumio plugins startup ---- Dec 14 01:48:44 volumio volumio[1002]: info: ------------------------------------------- Dec 14 01:48:44 volumio volumio[1002]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 14 01:48:44 volumio sudo[1427]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Dec 14 01:48:44 volumio volumio[1002]: info: Loading i18n strings for locale ja Dec 14 01:48:44 volumio kernel: usb 1-1.2: new high-speed USB device number 25 using dwc_otg Dec 14 01:48:44 volumio sudo[1427]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 01:48:44 volumio volumio[1002]: Updating browse sources language Dec 14 01:48:44 volumio volumio[1002]: Cannot find translation for sourceMedia Servers Dec 14 01:48:44 volumio volumio[1002]: Cannot find translation for source80s80s Radio Dec 14 01:48:44 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 14 01:48:44 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 01:48:44 volumio volumio[1002]: info: CoreCommandRouter::initPlayerControls Dec 14 01:48:44 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 01:48:44 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Dec 14 01:48:44 volumio kernel: usb 1-1.2: New USB device found, idVendor=17d9, idProduct=002b, bcdDevice= 3.30 Dec 14 01:48:44 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 14 01:48:44 volumio kernel: usb 1-1.2: Product: Rasteme RUA22A USB HD Audio Dec 14 01:48:44 volumio kernel: usb 1-1.2: Manufacturer: Rasteme Dec 14 01:48:44 volumio kernel: usb 1-1.2: SerialNumber: 0000 Dec 14 01:48:44 volumio systemd[1]: Started Logitech Media Server Daemon. Dec 14 01:48:44 volumio sudo[1407]: pam_unix(sudo:session): session closed for user root Dec 14 01:48:44 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 01:48:44 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 01:48:44 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 01:48:44 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 01:48:44 volumio systemd[1]: Configuration file /lib/systemd/system/spop.service is marked executable. Please remove executable permission bits. Proceeding anyway. Dec 14 01:48:44 volumio systemd[1]: Configuration file /lib/systemd/system/spop.service is marked world-writable. Please remove world writability permission bits. Proceeding anyway. Dec 14 01:48:44 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 01:48:44 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 14 01:48:44 volumio systemd[1]: Reloading. Dec 14 01:48:44 volumio volumio[1002]: info: BOOT COMPLETED Dec 14 01:48:44 volumio volumio[1002]: [Metrics] CommandRouter: 20s 586.35ms Dec 14 01:48:44 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 01:48:44 volumio volumio[1002]: ALSA lib pcm_hw.c:1713:(_snd_pcm_hw_open) Invalid value for card Dec 14 01:48:44 volumio volumio[1002]: aplay: main:722: audio open error: No such file or directory Dec 14 01:48:44 volumio volumio[1002]: Cannot play startup sound Dec 14 01:48:44 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent Dec 14 01:48:44 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent Dec 14 01:48:44 volumio volumio[1002]: Express server listening on port 3000 Dec 14 01:48:44 volumio volumio[1002]: [Metrics] WebUI: 21s 959.58ms Dec 14 01:48:44 volumio volumio[1002]: Volumio Calling Home Dec 14 01:48:44 volumio systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' Dec 14 01:48:44 volumio systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' Dec 14 01:48:44 volumio systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' Dec 14 01:48:44 volumio systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' Dec 14 01:48:45 volumio systemd[1]: Configuration file /lib/systemd/system/spop.service is marked executable. Please remove executable permission bits. Proceeding anyway. Dec 14 01:48:45 volumio systemd[1]: Configuration file /lib/systemd/system/spop.service is marked world-writable. Please remove world writability permission bits. Proceeding anyway. Dec 14 01:48:45 volumio sudo[1416]: pam_unix(sudo:session): session closed for user root Dec 14 01:48:45 volumio volumio[1002]: info: Starting minidlna.service Dec 14 01:48:45 volumio systemd[1]: Starting Volspotconnect2 Daemon... Dec 14 01:48:45 volumio systemd[1]: Started Volspotconnect2 Daemon. Dec 14 01:48:45 volumio systemd[1]: Starting Spotify Daemon... Dec 14 01:48:45 volumio sudo[1427]: pam_unix(sudo:session): session closed for user root Dec 14 01:48:45 volumio systemd[1]: Started Spotify Daemon. Dec 14 01:48:45 volumio sudo[1409]: pam_unix(sudo:session): session closed for user root Dec 14 01:48:45 volumio volumio[1002]: info: CoreStateMachine::resetVolumioState Dec 14 01:48:45 volumio volumio[1002]: info: CoreStateMachine::getcurrentVolume Dec 14 01:48:45 volumio sudo[1469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Dec 14 01:48:45 volumio volumio[1002]: info: CoreCommandRouter::volumioRetrievevolume Dec 14 01:48:45 volumio sudo[1469]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 01:48:45 volumio volumio[1465]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Dec 14 01:48:45 volumio volumio[1465]: Reading Config from "volspotify.toml" Dec 14 01:48:45 volumio volumio[1465]: [Vollibrespot] : Using Alsa backend with device: plughw:5 Dec 14 01:48:45 volumio systemd[1]: Starting MiniDLNA UPnP-A/V and DLNA media server... Dec 14 01:48:45 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 01:48:45 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 01:48:45 volumio volumio[1466]: 2025-12-14 01:48:45 [SPTF] 01:48:45.498 I [offline_authorizer.cpp:297] Unable to login offline: no such user Dec 14 01:48:45 volumio volumio[1466]: 2025-12-14 01:48:45 [SPTF] 01:48:45.509 I [ap:1752] Connecting to AP ap.spotify.com:4070 Dec 14 01:48:45 volumio sudo[1488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 14 01:48:45 volumio sudo[1488]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 01:48:45 volumio sudo[1492]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 14 01:48:45 volumio sudo[1488]: pam_unix(sudo:session): session closed for user root Dec 14 01:48:45 volumio sudo[1492]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 01:48:45 volumio sudo[1492]: pam_unix(sudo:session): session closed for user root Dec 14 01:48:45 volumio volumio[1466]: 2025-12-14 01:48:45 [SPTF] 01:48:45.680 I [ap:1226] Connected to AP: 104.199.241.202:4070 Dec 14 01:48:45 volumio sudo[1469]: pam_unix(sudo:session): session closed for user root Dec 14 01:48:45 volumio systemd[1]: Started MiniDLNA UPnP-A/V and DLNA media server. Dec 14 01:48:45 volumio volumio[1465]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Dec 14 01:48:45 volumio volumio[1002]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Dec 14 01:48:45 volumio volumio[1002]: info: Setting Device type: Raspberry PI Dec 14 01:48:45 volumio volumio[1002]: [SpotifyConnect] Vollibrespot Daemon service started! Dec 14 01:48:45 volumio volumio[1002]: [Metrics] SpotifyConnect: 1s 412.50ms Dec 14 01:48:45 volumio volumio[1002]: info: squeezelite reloaded Dec 14 01:48:45 volumio volumio[1002]: info: SpopD Daemon Started Dec 14 01:48:45 volumio volumio[1466]: 2025-12-14 01:48:45 [SPTF] 01:48:45.848 E [ap:1694] AP Socket Error: Software caused connection abort (103) Dec 14 01:48:45 volumio volumio[1466]: 2025-12-14 01:48:45 [SPTF] 01:48:45.849 E [ap:3953] Connection error: 112 Dec 14 01:48:45 volumio volumio[1466]: 2025-12-14 01:48:45 [SPTF] 01:48:45.849 I [ap:1752] Connecting to AP ap.spotify.com:443 Dec 14 01:48:45 volumio volumio[1002]: info: logitechmediaserver started Dec 14 01:48:45 volumio volumio[1002]: info: touch_display: systemctl daemon-reload succeeded. Dec 14 01:48:45 volumio sudo[1513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart squeezelite Dec 14 01:48:45 volumio sudo[1513]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 01:48:45 volumio volumio[1466]: 2025-12-14 01:48:45 [SPTF] 01:48:45.897 I [ap:1226] Connected to AP: 104.199.241.202:443 Dec 14 01:48:45 volumio sudo[1519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Dec 14 01:48:45 volumio volumio[1002]: info: MPD running with PID672 Dec 14 01:48:45 volumio volumio[1002]: ,establishing connection Dec 14 01:48:45 volumio systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... Dec 14 01:48:45 volumio systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Dec 14 01:48:45 volumio sudo[1519]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 01:48:46 volumio sudo[1513]: pam_unix(sudo:session): session closed for user root Dec 14 01:48:46 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 14 01:48:46 volumio systemd[1]: Starting Volumio Kiosk... Dec 14 01:48:46 volumio systemd[1]: Started Volumio Kiosk. Dec 14 01:48:46 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 14 01:48:46 volumio sudo[1519]: pam_unix(sudo:session): session closed for user root Dec 14 01:48:46 volumio sudo[1524]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Dec 14 01:48:46 volumio sudo[1524]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 01:48:46 volumio volumio[1466]: 2025-12-14 01:48:46 [SPTF] 01:48:46.181 E [ap:1694] AP Socket Error: Software caused connection abort (103) Dec 14 01:48:46 volumio volumio[1466]: 2025-12-14 01:48:46 [SPTF] 01:48:46.182 E [ap:3953] Connection error: 112 Dec 14 01:48:46 volumio volumio[1466]: 2025-12-14 01:48:46 [SPTF] 01:48:46.183 I [ap:1752] Connecting to AP ap.spotify.com:80 Dec 14 01:48:46 volumio sudo[1524]: pam_unix(sudo:session): session closed for user root Dec 14 01:48:46 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 14 01:48:46 volumio volumio[1466]: 2025-12-14 01:48:46 [SPTF] 01:48:46.233 I [ap:1226] Connected to AP: 104.199.241.202:80 Dec 14 01:48:46 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 14 01:48:46 volumio volumio[1466]: 2025-12-14 01:48:46 [SPTF] 01:48:46.421 E [ap:1694] AP Socket Error: Software caused connection abort (103) Dec 14 01:48:46 volumio volumio[1466]: 2025-12-14 01:48:46 [SPTF] 01:48:46.422 E [ap:3953] Connection error: 112 Dec 14 01:48:46 volumio volumio[1466]: 2025-12-14 01:48:46 [SPTF] 01:48:46.422 I [ap:1752] Connecting to AP ap.spotify.com:4070 Dec 14 01:48:46 volumio startx[1532]: X.Org X Server 1.18.4 Dec 14 01:48:46 volumio startx[1532]: Release Date: 2016-07-19 Dec 14 01:48:46 volumio startx[1532]: X Protocol Version 11, Revision 0 Dec 14 01:48:46 volumio startx[1532]: Build Operating System: Linux 4.4.26-v7+ armv7l Raspbian Dec 14 01:48:46 volumio startx[1532]: Current Operating System: Linux volumio 4.19.118-v7+ #1311 SMP Mon Apr 27 14:21:24 BST 2020 armv7l Dec 14 01:48:46 volumio startx[1532]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash quiet plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 console=ttyS0,115200 kgdboc=ttyS0,115200 console=tty1 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh elevator=noop rootwait bootdelay=5 logo.nologo vt.global_cursor_default=0 loglevel=0 snd-bcm2835.enable_compat_alsa=1 snd_bcm2835.enable_headphones=1 Dec 14 01:48:46 volumio startx[1532]: Build Date: 11 November 2016 11:59:59AM Dec 14 01:48:46 volumio startx[1532]: xorg-server 2:1.18.4-2+rpi1 (https://www.debian.org/support) Dec 14 01:48:46 volumio startx[1532]: Current version of pixman: 0.32.6 Dec 14 01:48:46 volumio startx[1532]: Before reporting problems, check http://wiki.x.org Dec 14 01:48:46 volumio startx[1532]: to make sure that you have the latest version. Dec 14 01:48:46 volumio startx[1532]: Markers: (--) probed, (**) from config file, (==) default setting, Dec 14 01:48:46 volumio startx[1532]: (++) from command line, (!!) notice, (II) informational, Dec 14 01:48:46 volumio startx[1532]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Dec 14 01:48:46 volumio startx[1532]: (==) Log file: "/var/log/Xorg.0.log", Time: Sun Dec 14 01:48:46 2025 Dec 14 01:48:46 volumio startx[1532]: (==) Using config directory: "/etc/X11/xorg.conf.d" Dec 14 01:48:46 volumio startx[1532]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Dec 14 01:48:46 volumio volumio[1466]: 2025-12-14 01:48:46 [SPTF] 01:48:46.468 I [ap:1226] Connected to AP: 104.199.241.202:4070 Dec 14 01:48:46 volumio volumio[1002]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Dec 14 01:48:46 volumio volumio[1002]: info: touch_display: Volumio Kiosk started Dec 14 01:48:46 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 01:48:46 volumio volumio[1002]: info: CoreCommandRouter::volumioGetState Dec 14 01:48:46 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 14 01:48:46 volumio volumio[1002]: info: squeezelite started Dec 14 01:48:46 volumio volumio[1002]: info: VolumeController:: Volume=undefined Mute =false Dec 14 01:48:46 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 14 01:48:46 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 14 01:48:46 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 14 01:48:46 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 01:48:46 volumio volumio[1002]: info: CoreStateMachine::updateTrackBlock Dec 14 01:48:46 volumio volumio[1002]: info: CorePlayQueue::getTrackBlock Dec 14 01:48:46 volumio volumio[1002]: info: CoreCommandRouter::volumioRetrievevolume Dec 14 01:48:46 volumio volumio[1002]: info: systemctl start minidlna.service succeeded. Dec 14 01:48:46 volumio volumio[1002]: info: Volumio called home Dec 14 01:48:46 volumio startx[1532]: modprobe: FATAL: Module g2d_23 not found. Dec 14 01:48:46 volumio volumio[1466]: 2025-12-14 01:48:46 [SPTF] 01:48:46.662 E [ap:1694] AP Socket Error: Software caused connection abort (103) Dec 14 01:48:46 volumio volumio[1466]: 2025-12-14 01:48:46 [SPTF] 01:48:46.663 E [ap:3953] Connection error: 112 Dec 14 01:48:46 volumio volumio[1466]: 2025-12-14 01:48:46 [SPTF] 01:48:46.664 I [ap:1752] Connecting to AP ap.spotify.com:443 Dec 14 01:48:46 volumio volumio[1002]: info: Dec 14 01:48:46 volumio volumio[1002]: ---------------------------- USB Audio Device Detached Dec 14 01:48:46 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 14 01:48:46 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 14 01:48:46 volumio volumio[1466]: 2025-12-14 01:48:46 [SPTF] 01:48:46.712 I [ap:1226] Connected to AP: 104.199.241.202:443 Dec 14 01:48:46 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 14 01:48:46 volumio volumio[1002]: info: Dec 14 01:48:46 volumio volumio[1002]: ---------------------------- Client requests Volume 80 Dec 14 01:48:46 volumio volumio[1002]: info: VolumeController::SetAlsaVolume80 Dec 14 01:48:46 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 14 01:48:46 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 14 01:48:46 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 14 01:48:46 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 01:48:46 volumio volumio[1466]: 2025-12-14 01:48:46 [SPTF] 01:48:46.940 E [ap:1694] AP Socket Error: Software caused connection abort (103) Dec 14 01:48:46 volumio volumio[1466]: 2025-12-14 01:48:46 [SPTF] 01:48:46.940 E [ap:3953] Connection error: 112 Dec 14 01:48:46 volumio volumio[1466]: 2025-12-14 01:48:46 [SPTF] 01:48:46.941 I [ap:1752] Connecting to AP ap.spotify.com:80 Dec 14 01:48:46 volumio volumio[1466]: 2025-12-14 01:48:46 [SPTF] 01:48:46.991 I [ap:1226] Connected to AP: 104.199.241.202:80 Dec 14 01:48:47 volumio systemd[1]: Starting Load/Save RF Kill Switch Status of rfkill3... Dec 14 01:48:47 volumio systemd[1]: Started Load/Save RF Kill Switch Status of rfkill3. Dec 14 01:48:47 volumio volumio[1002]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Dec 14 01:48:47 volumio volumio[1002]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Dec 14 01:48:47 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 14 01:48:47 volumio systemd[1]: Starting ifup for eth0... Dec 14 01:48:47 volumio systemd[1]: Started ifup for eth0. Dec 14 01:48:47 volumio kernel: usb 1-1.2: cannot get ctl value: req = 0x81, wValue = 0x202, wIndex = 0xa00, type = 4 Dec 14 01:48:47 volumio kernel: usb 1-1.2: cannot get ctl value: req = 0x81, wValue = 0x202, wIndex = 0xa00, type = 4 Dec 14 01:48:47 volumio systemd[1]: Starting ifup for wlan0... Dec 14 01:48:47 volumio systemd[1]: Started ifup for wlan0. Dec 14 01:48:47 volumio volumio[1002]: info: Reloading queue from file Dec 14 01:48:47 volumio volumio[1002]: error: updateQueue error: null Dec 14 01:48:47 volumio volumio[1002]: error: Failed LSINFO: Error: [50@0] {} No such directory Dec 14 01:48:47 volumio ifup[1603]: dhcpcd[1625]: sending commands to master dhcpcd process Dec 14 01:48:47 volumio dhcpcd[1625]: sending commands to master dhcpcd process Dec 14 01:48:47 volumio dhcpcd[994]: control command: dhcpcd eth0 Dec 14 01:48:47 volumio kernel: usb 1-1.2: cannot get ctl value: req = 0x81, wValue = 0x202, wIndex = 0xa00, type = 4 Dec 14 01:48:47 volumio volumio-remote-updater[600]: [2025-12-14 01:48:47] [connect] Successful connection Dec 14 01:48:47 volumio kernel: usb 1-1.2: USB disconnect, device number 25 Dec 14 01:48:47 volumio volumio[1466]: 2025-12-14 01:48:47 [SPTF] 01:48:47.621 E [ap:1694] AP Socket Error: Software caused connection abort (103) Dec 14 01:48:47 volumio volumio[1466]: 2025-12-14 01:48:47 [SPTF] 01:48:47.622 E [ap:3953] Connection error: 112 Dec 14 01:48:47 volumio volumio[1466]: 2025-12-14 01:48:47 [WARN] Login failed: Cannot connect to Spotify Dec 14 01:48:47 volumio volumio[1466]: 2025-12-14 01:48:47 [ERR ] Could not get the playlist container. Dec 14 01:48:47 volumio systemd[1]: spop.service: main process exited, code=killed, status=5/TRAP Dec 14 01:48:47 volumio systemd[1]: Unit spop.service entered failed state. Dec 14 01:48:47 volumio volumio[1002]: info: VolumeController:: Volume=undefined Mute =false Dec 14 01:48:47 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 14 01:48:47 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 14 01:48:47 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 14 01:48:47 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 01:48:47 volumio volumio[1002]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Dec 14 01:48:47 volumio volumio[1002]: info: Setting volume on startup at 80 Dec 14 01:48:47 volumio volumio[1002]: info: CoreStateMachine::setRepeat true single undefined Dec 14 01:48:47 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 14 01:48:47 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 14 01:48:47 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 14 01:48:47 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 01:48:47 volumio volumio[1002]: info: CoreStateMachine::setRandom undefined Dec 14 01:48:47 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 14 01:48:47 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 14 01:48:47 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 14 01:48:47 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 14 01:48:47 volumio volumio[1002]: info: CoreCommandRouter::volumioGetState Dec 14 01:48:47 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 14 01:48:47 volumio kernel: usb 1-1.2: new high-speed USB device number 26 using dwc_otg Dec 14 01:48:47 volumio volumio-remote-updater[600]: [2025-12-14 01:48:47] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1765676927 101 Dec 14 01:48:47 volumio volumio[1002]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Dec 14 01:48:47 volumio volumio[1002]: info: Starting Shairport Sync Dec 14 01:48:47 volumio kernel: usb 1-1.2: New USB device found, idVendor=17d9, idProduct=002b, bcdDevice= 3.30 Dec 14 01:48:47 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 14 01:48:47 volumio kernel: usb 1-1.2: Product: Rasteme RUA22A USB HD Audio Dec 14 01:48:47 volumio kernel: usb 1-1.2: Manufacturer: Rasteme Dec 14 01:48:47 volumio kernel: usb 1-1.2: SerialNumber: 0000 Dec 14 01:48:47 volumio volumio[1002]: info: Starting Shairport Sync Dec 14 01:48:47 volumio sudo[1671]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 01:48:48 volumio sudo[1671]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 01:48:48 volumio volumio[1002]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Dec 14 01:48:48 volumio sudo[1674]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 14 01:48:48 volumio sudo[1674]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 01:48:48 volumio sudo[1678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 14 01:48:48 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Dec 14 01:48:48 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 14 01:48:48 volumio sudo[1671]: pam_unix(sudo:session): session closed for user root Dec 14 01:48:48 volumio sudo[1678]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 01:48:48 volumio volumio[1002]: info: Dec 14 01:48:48 volumio volumio[1002]: ---------------------------- USB Audio Device Attached Dec 14 01:48:48 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 14 01:48:48 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 14 01:48:48 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... Dec 14 01:48:48 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Dec 14 01:48:48 volumio sudo[1674]: pam_unix(sudo:session): session closed for user root Dec 14 01:48:48 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Dec 14 01:48:48 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 14 01:48:48 volumio sudo[1678]: pam_unix(sudo:session): session closed for user root Dec 14 01:48:48 volumio volumio[1002]: info: CoreCommandRouter::Close All Modals sent Dec 14 01:48:48 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 14 01:48:48 volumio volumio[1002]: info: Output device has changed, restarting MPD Dec 14 01:48:48 volumio volumio[1002]: info: Output device has changed, restarting Shairport Sync Dec 14 01:48:48 volumio sudo[1781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 14 01:48:48 volumio sudo[1684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sed -i -e /Option "TransformationMatrix"/d /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Dec 14 01:48:48 volumio sudo[1781]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 01:48:48 volumio sudo[1684]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 01:48:48 volumio sudo[1793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 14 01:48:48 volumio sudo[1781]: pam_unix(sudo:session): session closed for user root Dec 14 01:48:48 volumio sudo[1793]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 14 01:48:48 volumio sudo[1684]: pam_unix(sudo:session): session closed for user root Dec 14 01:48:48 volumio volumio[1002]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 14 01:48:48 volumio volumio[1002]: [SpotifyConnect] Creating VLS config file Dec 14 01:48:48 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 01:48:48 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 14 01:48:48 volumio volumio[1002]: [SpotifyConnect] <> or Mixer found, using softvol Dec 14 01:48:48 volumio systemd[1]: Stopping Music Player Daemon... Dec 14 01:48:48 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 14 01:48:48 volumio volumio[1002]: Invalid card number. Dec 14 01:48:48 volumio systemd[1]: Starting Music Player Daemon... Dec 14 01:48:49 volumio volumio[1002]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 14 01:48:49 volumio volumio[1002]: info: Updating Volume Controller Parameters: Device: 5 Name: Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 14 01:48:49 volumio volumio[1002]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 14 01:48:49 volumio volumio[1002]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 14 01:48:49 volumio volumio[1002]: { Error: write EPIPE Dec 14 01:48:49 volumio volumio[1002]: at _errnoException (util.js:1022:11) Dec 14 01:48:49 volumio volumio[1002]: at WriteWrap.afterWrite [as oncomplete] (net.js:880:14) code: 'EPIPE', errno: 'EPIPE', syscall: 'write' } Dec 14 01:48:49 volumio volumio[1002]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 14 01:48:50 volumio systemd[1]: Started Music Player Daemon. Dec 14 01:48:50 volumio sudo[1793]: pam_unix(sudo:session): session closed for user root Dec 14 01:48:50 volumio squeezeboxserver[1438]: Your locale was detected as C, you may have problems with non-Latin filenames. Consider changing your LANG variable to the correct locale, i.e. en_US.utf8 Dec 14 01:48:51 volumio sudo[1886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-14 01:47 Dec 14 01:48:51 volumio sudo[1886]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cd11dc738d0d82822d0aa776d0729686b82e3758" VOLUMIO_FE_VERSION="537e3f50b5a8aa9bdf42f94adc80b348609b5c47" VOLUMIO_BE_VERSION="d7e6c614214d5dc616fddcbcfefc47e1990da7b1" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Sep 8 12:10:14 CEST 2021" VOLUMIO_VERSION="2.909" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="3aea8955ace41c92b011ae3a243c3f0d"