-- Logs begin at Wed 2025-05-28 02:37:09 CEST, end at Wed 2025-05-28 15:44:19 CEST. -- May 28 15:43:39 imperum-pml systemd[1117]: Time has been changed May 28 15:43:39 imperum-pml systemd[1]: Time has been changed May 28 15:43:40 imperum-pml volumio-remote-updater[608]: [2025-05-28 15:43:40] [info] asio async_connect error: system:111 (Connection refused) May 28 15:43:40 imperum-pml volumio-remote-updater[608]: [2025-05-28 15:43:40] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 28 15:43:40 imperum-pml volumio-remote-updater[608]: [2025-05-28 15:43:40] [error] handle_connect error: Underlying Transport Error May 28 15:43:40 imperum-pml wireless.js[1002]: trying... May 28 15:43:40 imperum-pml sudo[1449]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 28 15:43:40 imperum-pml sudo[1449]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:43:40 imperum-pml sudo[1449]: pam_unix(sudo:session): session closed for user root May 28 15:43:41 imperum-pml wireless.js[1002]: trying... May 28 15:43:41 imperum-pml sudo[1458]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 28 15:43:41 imperum-pml sudo[1458]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:43:41 imperum-pml sudo[1458]: pam_unix(sudo:session): session closed for user root May 28 15:43:42 imperum-pml wireless.js[1002]: trying... May 28 15:43:42 imperum-pml sudo[1467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 28 15:43:42 imperum-pml sudo[1467]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:43:42 imperum-pml sudo[1467]: pam_unix(sudo:session): session closed for user root May 28 15:43:43 imperum-pml wireless.js[1002]: trying... May 28 15:43:43 imperum-pml sudo[1476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 28 15:43:43 imperum-pml sudo[1476]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:43:43 imperum-pml sudo[1476]: pam_unix(sudo:session): session closed for user root May 28 15:43:43 imperum-pml wpa_supplicant[1349]: wlan0: Trying to associate with SSID 'SM30' May 28 15:43:44 imperum-pml wireless.js[1002]: trying... May 28 15:43:44 imperum-pml sudo[1485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 28 15:43:44 imperum-pml sudo[1485]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:43:44 imperum-pml sudo[1485]: pam_unix(sudo:session): session closed for user root May 28 15:43:45 imperum-pml wireless.js[1002]: trying... May 28 15:43:45 imperum-pml sudo[1494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 28 15:43:45 imperum-pml sudo[1494]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:43:45 imperum-pml sudo[1494]: pam_unix(sudo:session): session closed for user root May 28 15:43:46 imperum-pml wireless.js[1002]: trying... May 28 15:43:46 imperum-pml sudo[1503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 28 15:43:46 imperum-pml sudo[1503]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:43:46 imperum-pml sudo[1503]: pam_unix(sudo:session): session closed for user root May 28 15:43:46 imperum-pml wpa_supplicant[1349]: wlan0: Associated with c8:3a:35:25:fb:70 May 28 15:43:46 imperum-pml wpa_supplicant[1349]: wlan0: WPA: Key negotiation completed with c8:3a:35:25:fb:70 [PTK=CCMP GTK=TKIP] May 28 15:43:46 imperum-pml wpa_supplicant[1349]: wlan0: CTRL-EVENT-CONNECTED - Connection to c8:3a:35:25:fb:70 completed [id=0 id_str=] May 28 15:43:46 imperum-pml dhcpcd[1371]: wlan0: carrier acquired May 28 15:43:46 imperum-pml dhcpcd[1371]: wlan0: soliciting an IPv6 router May 28 15:43:46 imperum-pml dhcpcd[1371]: wlan0: rebinding lease of 192.168.1.132 May 28 15:43:47 imperum-pml wireless.js[1002]: trying... May 28 15:43:47 imperum-pml sudo[1529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 28 15:43:47 imperum-pml sudo[1529]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:43:47 imperum-pml sudo[1529]: pam_unix(sudo:session): session closed for user root May 28 15:43:48 imperum-pml wireless.js[1002]: trying... May 28 15:43:48 imperum-pml sudo[1538]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 28 15:43:48 imperum-pml sudo[1538]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:43:48 imperum-pml sudo[1538]: pam_unix(sudo:session): session closed for user root May 28 15:43:49 imperum-pml wireless.js[1002]: trying... May 28 15:43:49 imperum-pml sudo[1548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 28 15:43:49 imperum-pml sudo[1548]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:43:49 imperum-pml sudo[1548]: pam_unix(sudo:session): session closed for user root May 28 15:43:50 imperum-pml volumio-remote-updater[608]: [2025-05-28 15:43:50] [info] asio async_connect error: system:111 (Connection refused) May 28 15:43:50 imperum-pml volumio-remote-updater[608]: [2025-05-28 15:43:50] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 28 15:43:50 imperum-pml volumio-remote-updater[608]: [2025-05-28 15:43:50] [error] handle_connect error: Underlying Transport Error May 28 15:43:50 imperum-pml wireless.js[1002]: trying... May 28 15:43:50 imperum-pml sudo[1557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 28 15:43:50 imperum-pml sudo[1557]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:43:50 imperum-pml sudo[1557]: pam_unix(sudo:session): session closed for user root May 28 15:43:51 imperum-pml wireless.js[1002]: trying... May 28 15:43:51 imperum-pml sudo[1566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 28 15:43:51 imperum-pml sudo[1566]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:43:51 imperum-pml sudo[1566]: pam_unix(sudo:session): session closed for user root May 28 15:43:52 imperum-pml wireless.js[1002]: trying... May 28 15:43:52 imperum-pml sudo[1575]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 28 15:43:52 imperum-pml sudo[1575]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:43:52 imperum-pml sudo[1575]: pam_unix(sudo:session): session closed for user root May 28 15:43:53 imperum-pml wireless.js[1002]: trying... May 28 15:43:53 imperum-pml sudo[1584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 28 15:43:53 imperum-pml sudo[1584]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:43:53 imperum-pml sudo[1584]: pam_unix(sudo:session): session closed for user root May 28 15:43:54 imperum-pml wireless.js[1002]: Overtime, starting plan B May 28 15:43:54 imperum-pml wireless.js[1002]: STARTING HOTSPOT May 28 15:43:54 imperum-pml wpa_supplicant[1349]: wlan0: CTRL-EVENT-DISCONNECTED bssid=c8:3a:35:25:fb:70 reason=3 locally_generated=1 May 28 15:43:54 imperum-pml dhcpcd[1371]: wlan0: carrier lost May 28 15:43:54 imperum-pml dhcpcd[1371]: eth0: adding route to 192.168.1.0/24 May 28 15:43:54 imperum-pml dhcpcd[1371]: eth0: adding default route via 192.168.1.1 May 28 15:43:54 imperum-pml dhcpcd[1371]: eth0: removing route to 192.168.1.0/24 May 28 15:43:54 imperum-pml dhcpcd[1371]: eth0: removing default route via 192.168.1.1 May 28 15:43:54 imperum-pml wpa_supplicant[1349]: wlan0: CTRL-EVENT-TERMINATING May 28 15:43:57 imperum-pml systemd[1]: Stopped hotspot.service. May 28 15:43:57 imperum-pml sudo[1618]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 28 15:43:57 imperum-pml sudo[1618]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:43:57 imperum-pml sudo[1618]: pam_unix(sudo:session): session closed for user root May 28 15:43:57 imperum-pml sudo[1626]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 28 15:43:57 imperum-pml sudo[1626]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:43:58 imperum-pml sudo[1626]: pam_unix(sudo:session): session closed for user root May 28 15:43:58 imperum-pml avahi-daemon[678]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. May 28 15:43:58 imperum-pml avahi-daemon[678]: New relevant interface wlan0.IPv4 for mDNS. May 28 15:43:58 imperum-pml avahi-daemon[678]: Registering new address record for 192.168.211.1 on wlan0.IPv4. May 28 15:43:58 imperum-pml kernel: brcmfmac: power management disabled May 28 15:43:58 imperum-pml systemd[1]: Starting hotspot.service... May 28 15:43:58 imperum-pml systemd[1]: Started hotspot.service. May 28 15:43:58 imperum-pml systemd[1]: Starting dhcpd.service... May 28 15:43:58 imperum-pml systemd[1]: Starting dnsmasq.service... May 28 15:43:58 imperum-pml systemd[1]: Started Wireless Services. May 28 15:43:58 imperum-pml systemd[1]: Starting Volumio Backend Module... May 28 15:43:58 imperum-pml systemd[1]: Started Volumio Backend Module. May 28 15:43:58 imperum-pml systemd[1]: Starting Volumio Streaming Daemon... May 28 15:43:58 imperum-pml systemd[1]: Started Volumio Streaming Daemon. May 28 15:43:58 imperum-pml systemd[1]: Starting play from usb card... May 28 15:43:58 imperum-pml systemd[1]: Started play from usb card. May 28 15:43:58 imperum-pml systemd[1]: Starting Uno para gobernarlos a todos... May 28 15:43:58 imperum-pml systemd[1]: Started Uno para gobernarlos a todos. May 28 15:43:58 imperum-pml systemd[1]: Starting Multi-User System. May 28 15:43:58 imperum-pml systemd[1]: Reached target Multi-User System. May 28 15:43:58 imperum-pml systemd[1]: Starting Graphical Interface. May 28 15:43:58 imperum-pml systemd[1]: Reached target Graphical Interface. May 28 15:43:58 imperum-pml systemd[1]: Starting Update UTMP about System Runlevel Changes... May 28 15:43:58 imperum-pml systemd[1]: Started Update UTMP about System Runlevel Changes. May 28 15:43:58 imperum-pml dnsmasq[1660]: started, version 2.72 cachesize 150 May 28 15:43:58 imperum-pml dnsmasq[1660]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect May 28 15:43:58 imperum-pml dnsmasq[1660]: reading /etc/resolv.conf May 28 15:43:58 imperum-pml dnsmasq[1660]: using nameserver 62.37.228.20#53 May 28 15:43:58 imperum-pml dnsmasq[1660]: using nameserver 8.8.8.8#53 May 28 15:43:58 imperum-pml dnsmasq[1660]: using nameserver 208.67.222.222#53 May 28 15:43:58 imperum-pml dnsmasq[1660]: using nameserver 208.67.220.220#53 May 28 15:43:58 imperum-pml dnsmasq[1660]: read /etc/hosts - 1 addresses May 28 15:43:58 imperum-pml systemd[1]: Started dnsmasq.service. May 28 15:43:58 imperum-pml systemd[1]: Starting Host and Network Name Lookups. May 28 15:43:58 imperum-pml systemd[1]: Reached target Host and Network Name Lookups. May 28 15:43:58 imperum-pml dhcpd[1640]: Internet Systems Consortium DHCP Server 4.3.1 May 28 15:43:58 imperum-pml dhcpd[1640]: Copyright 2004-2014 Internet Systems Consortium. May 28 15:43:58 imperum-pml dhcpd[1640]: All rights reserved. May 28 15:43:58 imperum-pml dhcpd[1640]: For info, please visit https://www.isc.org/software/dhcp/ May 28 15:43:58 imperum-pml dhcpd[1640]: Config file: /etc/dhcp/dhcpd.conf May 28 15:43:58 imperum-pml dhcpd[1640]: Database file: /var/lib/dhcp/dhcpd.leases May 28 15:43:58 imperum-pml dhcpd[1640]: PID file: /var/run/dhcpd.pid May 28 15:43:58 imperum-pml hotspot[1640]: Internet Systems Consortium DHCP Server 4.3.1 May 28 15:43:58 imperum-pml hotspot[1640]: Copyright 2004-2014 Internet Systems Consortium. May 28 15:43:58 imperum-pml hotspot[1640]: All rights reserved. May 28 15:43:58 imperum-pml hotspot[1640]: For info, please visit https://www.isc.org/software/dhcp/ May 28 15:43:58 imperum-pml hotspot[1640]: Config file: /etc/dhcp/dhcpd.conf May 28 15:43:58 imperum-pml hotspot[1640]: Database file: /var/lib/dhcp/dhcpd.leases May 28 15:43:58 imperum-pml hotspot[1640]: PID file: /var/run/dhcpd.pid May 28 15:43:58 imperum-pml dhcpd[1640]: Internet Systems Consortium DHCP Server 4.3.1 May 28 15:43:58 imperum-pml dhcpd[1640]: Copyright 2004-2014 Internet Systems Consortium. May 28 15:43:58 imperum-pml dhcpd[1640]: All rights reserved. May 28 15:43:58 imperum-pml dhcpd[1640]: For info, please visit https://www.isc.org/software/dhcp/ May 28 15:43:58 imperum-pml dhcpd[1640]: Wrote 9 leases to leases file. May 28 15:43:58 imperum-pml hotspot[1640]: Wrote 9 leases to leases file. May 28 15:43:58 imperum-pml dhcpd[1640]: May 28 15:43:58 imperum-pml dhcpd[1640]: No subnet declaration for eth0 (192.168.1.129). May 28 15:43:58 imperum-pml dhcpd[1640]: ** Ignoring requests on eth0. If this is not what May 28 15:43:58 imperum-pml dhcpd[1640]: you want, please write a subnet declaration May 28 15:43:58 imperum-pml dhcpd[1640]: in your dhcpd.conf file for the network segment May 28 15:43:58 imperum-pml dhcpd[1640]: to which interface eth0 is attached. ** May 28 15:43:58 imperum-pml dhcpd[1640]: May 28 15:43:58 imperum-pml hotspot[1640]: No subnet declaration for eth0 (192.168.1.129). May 28 15:43:58 imperum-pml hotspot[1640]: ** Ignoring requests on eth0. If this is not what May 28 15:43:58 imperum-pml hotspot[1640]: you want, please write a subnet declaration May 28 15:43:58 imperum-pml hotspot[1640]: in your dhcpd.conf file for the network segment May 28 15:43:58 imperum-pml hotspot[1640]: to which interface eth0 is attached. ** May 28 15:43:58 imperum-pml volumio-streaming-daemon[1643]: ############################ May 28 15:43:58 imperum-pml volumio-streaming-daemon[1643]: # Volumio Streaming Daemon # May 28 15:43:58 imperum-pml volumio-streaming-daemon[1643]: # Running on port 7777 # May 28 15:43:58 imperum-pml volumio-streaming-daemon[1643]: ############################ May 28 15:43:58 imperum-pml volumio-streaming-daemon[1643]: Environment: production May 28 15:43:58 imperum-pml dhcpd[1640]: Listening on LPF/wlan0/b8:27:eb:dd:66:b7/192.168.211.0/24 May 28 15:43:58 imperum-pml dhcpd[1640]: Sending on LPF/wlan0/b8:27:eb:dd:66:b7/192.168.211.0/24 May 28 15:43:58 imperum-pml dhcpd[1640]: Sending on Socket/fallback/fallback-net May 28 15:43:58 imperum-pml hotspot[1640]: Listening on LPF/wlan0/b8:27:eb:dd:66:b7/192.168.211.0/24 May 28 15:43:58 imperum-pml hotspot[1640]: Sending on LPF/wlan0/b8:27:eb:dd:66:b7/192.168.211.0/24 May 28 15:43:58 imperum-pml hotspot[1640]: Sending on Socket/fallback/fallback-net May 28 15:43:58 imperum-pml dhcpd[1666]: Server starting service. May 28 15:43:58 imperum-pml systemd[1]: Started dhcpd.service. May 28 15:43:58 imperum-pml systemd[1]: Startup finished in 12.054s (kernel) + 39.419s (userspace) = 51.473s. May 28 15:43:58 imperum-pml hotspot[1639]: Launching Hostapd 2.8 May 28 15:43:58 imperum-pml hotspot[1639]: Configuration file: /etc/hostapd/hostapd.conf May 28 15:43:58 imperum-pml hotspot[1639]: wlan0: Could not connect to kernel driver May 28 15:43:58 imperum-pml hotspot[1639]: Using interface wlan0 with hwaddr b8:27:eb:dd:66:b7 and ssid "IMPERUM-PML" May 28 15:43:58 imperum-pml dhcpcd[1371]: wlan0: carrier acquired May 28 15:43:58 imperum-pml hotspot[1639]: wlan0: interface state UNINITIALIZED->ENABLED May 28 15:43:58 imperum-pml hotspot[1639]: wlan0: AP-ENABLED May 28 15:43:58 imperum-pml dhcpcd[1371]: wlan0: soliciting an IPv6 router May 28 15:43:59 imperum-pml dhcpcd[1371]: wlan0: rebinding lease of 192.168.1.132 May 28 15:43:59 imperum-pml dhcpd[1666]: DHCPREQUEST for 192.168.1.132 from b8:27:eb:dd:66:b7 via wlan0: ignored (not authoritative). May 28 15:43:59 imperum-pml sudo[1702]: volumio : TTY=unknown ; PWD=/home/volumio/tcnimperum/bin ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 28 15:43:59 imperum-pml sudo[1702]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:43:59 imperum-pml sudo[1702]: pam_unix(sudo:session): session closed for user root May 28 15:43:59 imperum-pml sudo[1713]: volumio : TTY=unknown ; PWD=/home/volumio/tcnimperum/bin ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 28 15:43:59 imperum-pml sudo[1713]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:43:59 imperum-pml bash[1645]: wlan0 Interface doesn't support scanning : Invalid exchange May 28 15:43:59 imperum-pml sudo[1713]: pam_unix(sudo:session): session closed for user root May 28 15:43:59 imperum-pml kernel: brcmfmac: brcmf_vif_set_mgmt_ie: vndr ie set error : -52 May 28 15:43:59 imperum-pml kernel: brcmfmac: brcmf_cfg80211_scan: scan error (-52) May 28 15:43:59 imperum-pml bash[1645]: ALSA lib pcm_hw.c:1713:(_snd_pcm_hw_open) Invalid value for card May 28 15:43:59 imperum-pml bash[1645]: sox FAIL formats: can't open input `hw:Loopback,1,4': snd_pcm_open error: No such device May 28 15:43:59 imperum-pml bash[1645]: curl: (7) Failed to connect to 127.0.0.1 port 3000: Connection refused May 28 15:43:59 imperum-pml bash[1645]: terminate called after throwing an instance of 'std::invalid_argument' May 28 15:43:59 imperum-pml bash[1645]: what(): parse error - unexpected end of input May 28 15:43:59 imperum-pml bash[1645]: /home/volumio/tcnimperum/scripts/watchmen.sh: line 1: 1653 Aborted /home/volumio/tcnimperum/bin/watchmen May 28 15:44:00 imperum-pml volumio-remote-updater[608]: [2025-05-28 15:44:00] [info] asio async_connect error: system:111 (Connection refused) May 28 15:44:00 imperum-pml ntpd[869]: Listen normally on 4 wlan0 192.168.211.1 UDP 123 May 28 15:44:00 imperum-pml ntpd[869]: peers refreshed May 28 15:44:00 imperum-pml volumio-remote-updater[608]: [2025-05-28 15:44:00] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 28 15:44:00 imperum-pml volumio-remote-updater[608]: [2025-05-28 15:44:00] [error] handle_connect error: Underlying Transport Error May 28 15:44:00 imperum-pml sudo[1756]: volumio : TTY=unknown ; PWD=/home/volumio/tcnimperum/bin ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 28 15:44:00 imperum-pml sudo[1756]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:44:00 imperum-pml sudo[1756]: pam_unix(sudo:session): session closed for user root May 28 15:44:00 imperum-pml sudo[1767]: volumio : TTY=unknown ; PWD=/home/volumio/tcnimperum/bin ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 28 15:44:00 imperum-pml volumio[1642]: info: ------------------------------------------- May 28 15:44:00 imperum-pml sudo[1767]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:44:00 imperum-pml volumio[1642]: info: ----- Volumio2 ---- May 28 15:44:00 imperum-pml volumio[1642]: info: ------------------------------------------- May 28 15:44:00 imperum-pml volumio[1642]: info: ----- System startup ---- May 28 15:44:00 imperum-pml volumio[1642]: info: ------------------------------------------- May 28 15:44:01 imperum-pml volumio[1642]: info: MYVOLUMIO Environment detected May 28 15:44:02 imperum-pml volumio[1642]: info: Plugin folders cleanup May 28 15:44:02 imperum-pml volumio[1642]: info: Scanning into folder /volumio/app/plugins/ May 28 15:44:02 imperum-pml volumio[1642]: info: Scanning category audio_interface May 28 15:44:02 imperum-pml volumio[1642]: info: Scanning category miscellanea May 28 15:44:02 imperum-pml volumio[1642]: info: Scanning category music_service May 28 15:44:02 imperum-pml volumio[1642]: info: Scanning category plugins.json May 28 15:44:02 imperum-pml volumio[1642]: info: Scanning category system_controller May 28 15:44:02 imperum-pml volumio[1642]: info: Scanning category user_interface May 28 15:44:02 imperum-pml volumio[1642]: info: Scanning into folder /data/plugins/ May 28 15:44:02 imperum-pml volumio[1642]: info: Scanning category music_service May 28 15:44:02 imperum-pml volumio[1642]: info: Plugin folders cleanup completed May 28 15:44:02 imperum-pml volumio[1642]: info: ------------------------------------------- May 28 15:44:02 imperum-pml volumio[1642]: info: ----- Core plugins startup ---- May 28 15:44:02 imperum-pml volumio[1642]: info: ------------------------------------------- May 28 15:44:02 imperum-pml volumio[1642]: info: Loading plugins from folder /volumio/app/plugins/ May 28 15:44:02 imperum-pml volumio[1642]: info: Adding plugin upnp to MyMusic Plugins May 28 15:44:02 imperum-pml volumio[1642]: info: Adding plugin airplay_emulation to MyMusic Plugins May 28 15:44:02 imperum-pml volumio[1642]: info: Adding plugin upnp_browser to MyMusic Plugins May 28 15:44:02 imperum-pml volumio[1642]: info: Loading plugins from folder /data/plugins/ May 28 15:44:02 imperum-pml volumio[1642]: info: Loading plugin "system"... May 28 15:44:02 imperum-pml volumio[1642]: info: Loading plugin "appearance"... May 28 15:44:02 imperum-pml dhcpd[1666]: DHCPREQUEST for 192.168.1.132 from b8:27:eb:dd:66:b7 via wlan0: ignored (not authoritative). May 28 15:44:03 imperum-pml volumio[1642]: info: Loading plugin "network"... May 28 15:44:03 imperum-pml volumio[1642]: info: Loading plugin "services"... May 28 15:44:03 imperum-pml volumio[1642]: info: Loading plugin "alsa_controller"... May 28 15:44:03 imperum-pml volumio[1642]: info: Loading plugin "wizard"... May 28 15:44:03 imperum-pml volumio[1642]: info: Loading plugin "volumio_command_line_client"... May 28 15:44:03 imperum-pml volumio[1642]: info: Loading plugin "upnp"... May 28 15:44:03 imperum-pml volumio[1642]: info: [1748439843439] Starting Upmpd Daemon May 28 15:44:03 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 28 15:44:03 imperum-pml volumio[1642]: info: Loading plugin "cardplay_monitor"... May 28 15:44:03 imperum-pml volumio[1642]: info: Loading plugin "fakeAlsaController"... May 28 15:44:03 imperum-pml volumio[1642]: info: Loading plugin "fake_spop"... May 28 15:44:03 imperum-pml volumio[1642]: info: Loading plugin "mainmenu"... May 28 15:44:03 imperum-pml volumio[1642]: info: Loading plugin "menuOptions"... May 28 15:44:04 imperum-pml dhcpcd[1371]: wlan0: soliciting a DHCP lease May 28 15:44:04 imperum-pml dhcpd[1666]: DHCPDISCOVER from b8:27:eb:dd:66:b7 via wlan0 May 28 15:44:04 imperum-pml volumio[1642]: info: Loading plugin "my_music"... May 28 15:44:04 imperum-pml volumio[1642]: info: Loading plugin "user_data"... May 28 15:44:04 imperum-pml volumio[1642]: info: Loading plugin "mpd"... May 28 15:44:04 imperum-pml volumio[1642]: info: Loading plugin "upnp_browser"... May 28 15:44:04 imperum-pml sudo[1767]: pam_unix(sudo:session): session closed for user root May 28 15:44:04 imperum-pml sudo[1793]: volumio : TTY=unknown ; PWD=/home/volumio/tcnimperum/bin ; USER=root ; COMMAND=/bin/systemctl restart wireless.service May 28 15:44:04 imperum-pml sudo[1793]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:44:05 imperum-pml systemd[1]: Stopping Wireless Services... May 28 15:44:05 imperum-pml dhcpcd[1371]: received SIGTERM from PID 1, stopping May 28 15:44:05 imperum-pml dhcpcd[1371]: wlan0: removing interface May 28 15:44:05 imperum-pml dhcpcd[1371]: eth0: adding route to 192.168.1.0/24 May 28 15:44:05 imperum-pml dhcpcd[1371]: eth0: adding default route via 192.168.1.1 May 28 15:44:05 imperum-pml dhcpd[1666]: DHCPOFFER on 192.168.211.11 to b8:27:eb:dd:66:b7 (imperum-pml) via wlan0 May 28 15:44:05 imperum-pml dhcpcd[1371]: eth0: removing interface May 28 15:44:05 imperum-pml dhcpcd[1371]: eth0: deleting host route to 192.168.1.129 via 127.0.0.1 May 28 15:44:05 imperum-pml dhcpcd[1371]: eth0: deleting route to 192.168.1.0/24 May 28 15:44:05 imperum-pml dhcpcd[1371]: eth0: deleting default route via 192.168.1.1 May 28 15:44:05 imperum-pml avahi-daemon[678]: Withdrawing address record for 192.168.1.129 on eth0. May 28 15:44:05 imperum-pml avahi-daemon[678]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.1.129. May 28 15:44:05 imperum-pml avahi-daemon[678]: Interface eth0.IPv4 no longer relevant for mDNS. May 28 15:44:05 imperum-pml dhcpcd[1161]: eth0: removing IP address 192.168.1.129/24 May 28 15:44:05 imperum-pml dhcpcd[1371]: exited May 28 15:44:05 imperum-pml systemd[1]: wireless.service: main process exited, code=exited, status=1/FAILURE May 28 15:44:05 imperum-pml systemd[1]: Unit wireless.service entered failed state. May 28 15:44:05 imperum-pml systemd[1]: Starting Wireless Services... May 28 15:44:05 imperum-pml sudo[1830]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off May 28 15:44:05 imperum-pml sudo[1830]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:44:05 imperum-pml sudo[1830]: pam_unix(sudo:session): session closed for user root May 28 15:44:05 imperum-pml volumio[1642]: info: Loading plugin "networkfs"... May 28 15:44:05 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 28 15:44:05 imperum-pml volumio[1642]: info: Loading plugin "alarm-clock"... May 28 15:44:05 imperum-pml wireless.js[1839]: Cleaning previous... May 28 15:44:05 imperum-pml systemd[1]: Stopping dhcpd.service... May 28 15:44:05 imperum-pml systemd[1]: Stopping Host and Network Name Lookups. May 28 15:44:05 imperum-pml systemd[1]: Stopped target Host and Network Name Lookups. May 28 15:44:05 imperum-pml systemd[1]: Stopping dnsmasq.service... May 28 15:44:05 imperum-pml dnsmasq[1660]: exiting on receipt of SIGTERM May 28 15:44:05 imperum-pml systemd[1]: Stopped dnsmasq.service. May 28 15:44:05 imperum-pml systemd[1]: Stopped dhcpd.service. May 28 15:44:05 imperum-pml systemd[1]: Stopping hotspot.service... May 28 15:44:05 imperum-pml volumio[1642]: info: Loading plugin "airplay_emulation"... May 28 15:44:05 imperum-pml volumio[1642]: info: Starting Shairport Sync May 28 15:44:05 imperum-pml volumio[1642]: info: Loading plugin "last_100"... May 28 15:44:05 imperum-pml volumio[1642]: info: Loading plugin "webradio"... May 28 15:44:05 imperum-pml volumio[1642]: info: Loading plugin "i2s_dacs"... May 28 15:44:05 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 15:44:05 imperum-pml volumio[1642]: info: Loading plugin "volumiodiscovery"... May 28 15:44:05 imperum-pml volumio[1642]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 28 15:44:05 imperum-pml node[1642]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 28 15:44:05 imperum-pml node[1642]: *** WARNING *** Please fix your application to use the native API of Avahi! May 28 15:44:05 imperum-pml node[1642]: *** WARNING *** For more information see May 28 15:44:05 imperum-pml node[1642]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 28 15:44:05 imperum-pml node[1642]: *** WARNING *** Please fix your application to use the native API of Avahi! May 28 15:44:05 imperum-pml node[1642]: *** WARNING *** For more information see May 28 15:44:05 imperum-pml volumio[1642]: *** WARNING *** Please fix your application to use the native API of Avahi! May 28 15:44:05 imperum-pml volumio[1642]: *** WARNING *** For more information see May 28 15:44:05 imperum-pml volumio[1642]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 28 15:44:05 imperum-pml volumio[1642]: *** WARNING *** Please fix your application to use the native API of Avahi! May 28 15:44:05 imperum-pml volumio[1642]: *** WARNING *** For more information see May 28 15:44:05 imperum-pml volumio[1642]: info: Applying required configuration parameters for plugin volumiodiscovery May 28 15:44:06 imperum-pml volumio[1642]: Discovery: StartAdv! undefined May 28 15:44:06 imperum-pml volumio[1642]: Discovery: Started advertising... imperum-pml - undefined May 28 15:44:06 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 28 15:44:06 imperum-pml volumio[1642]: info: Loading plugin "outputs"... May 28 15:44:06 imperum-pml volumio[1642]: info: Loading plugin "albumart"... May 28 15:44:06 imperum-pml volumio[1642]: info: Plugin example_plugin is not enabled May 28 15:44:06 imperum-pml volumio[1642]: info: Loading plugin "inputs"... May 28 15:44:06 imperum-pml volumio[1642]: info: Loading plugin "initialCheck"... May 28 15:44:06 imperum-pml volumio[1642]: info: MYVOLUMIO Environment detected May 28 15:44:06 imperum-pml sudo[1862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/vcgencmd display_power 0 May 28 15:44:06 imperum-pml sudo[1862]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:44:06 imperum-pml sudo[1864]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd_aloop May 28 15:44:06 imperum-pml volumio[1642]: info: Loading plugin "installCheck"... May 28 15:44:06 imperum-pml sudo[1864]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:44:06 imperum-pml volumio[1642]: info: Loading plugin "updater_comm"... May 28 15:44:06 imperum-pml sudo[1862]: pam_unix(sudo:session): session closed for user root May 28 15:44:06 imperum-pml sudo[1864]: pam_unix(sudo:session): session closed for user root May 28 15:44:06 imperum-pml volumio[1642]: info: Plugin mpdemulation is not enabled May 28 15:44:06 imperum-pml volumio[1642]: info: Loading plugin "rest_api"... May 28 15:44:06 imperum-pml volumio[1642]: Forking 3 albumart workers May 28 15:44:06 imperum-pml volumio[1642]: info: Loading plugin "websocket"... May 28 15:44:06 imperum-pml volumio[1642]: info: Loading plugin "spop"... May 28 15:44:07 imperum-pml ntpd[869]: Deleting interface #3 eth0, 192.168.1.129#123, interface stats: received=29, sent=32, dropped=0, active_time=35 secs May 28 15:44:07 imperum-pml ntpd[869]: 195.20.235.143 interface 192.168.1.129 -> (none) May 28 15:44:07 imperum-pml ntpd[869]: 195.95.153.59 interface 192.168.1.129 -> (none) May 28 15:44:07 imperum-pml ntpd[869]: 185.198.109.227 interface 192.168.1.129 -> (none) May 28 15:44:07 imperum-pml ntpd[869]: 195.95.153.43 interface 192.168.1.129 -> (none) May 28 15:44:07 imperum-pml ntpd[869]: peers refreshed May 28 15:44:07 imperum-pml volumio[1642]: Starting albumart workers May 28 15:44:07 imperum-pml volumio[1642]: Starting albumart workers May 28 15:44:07 imperum-pml volumio[1642]: Starting albumart workers May 28 15:44:07 imperum-pml volumio[1642]: info: Loading plugin "volspotconnect2"... May 28 15:44:07 imperum-pml volumio[1642]: info: Loading plugin "zoutputDevice"... May 28 15:44:08 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: spop , rebuildSPOPDAndRestartDaemon May 28 15:44:08 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: volspotconnect2 , rebuildRestartDaemon May 28 15:44:08 imperum-pml volumio[1642]: Creating VLS config file May 28 15:44:08 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 15:44:08 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 15:44:08 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: mpd , mpdInit May 28 15:44:08 imperum-pml volumio[1642]: info: Creating MPD Configuration file May 28 15:44:08 imperum-pml sudo[1923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 28 15:44:08 imperum-pml volumio[1642]: info: ___________ START PLUGINS ___________ May 28 15:44:08 imperum-pml sudo[1923]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:44:08 imperum-pml sudo[1925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 28 15:44:08 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 15:44:08 imperum-pml volumio[1642]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 28 15:44:08 imperum-pml volumio[1642]: info: [1748439848144] CoreMusicLibrary::Adding element Media Servers May 28 15:44:08 imperum-pml sudo[1923]: pam_unix(sudo:session): session closed for user root May 28 15:44:08 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 15:44:08 imperum-pml sudo[1925]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:44:08 imperum-pml systemd[1]: Stopping Music Player Daemon... May 28 15:44:08 imperum-pml volumio[1642]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 28 15:44:08 imperum-pml volumio[1642]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 28 15:44:08 imperum-pml volumio[1642]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 28 15:44:08 imperum-pml volumio[1642]: info: [1748439848270] CoreMusicLibrary::Adding element Last_100 May 28 15:44:08 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 15:44:08 imperum-pml volumio[1642]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 28 15:44:08 imperum-pml volumio[1642]: info: [1748439848274] CoreMusicLibrary::Adding element Webradio May 28 15:44:08 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 15:44:08 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 28 15:44:08 imperum-pml volumio[1642]: Creating VLS config file May 28 15:44:08 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 15:44:08 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 15:44:08 imperum-pml systemd[1]: Starting Music Player Daemon... May 28 15:44:08 imperum-pml volumio[1642]: [SpotifyConnect] Starting metadata listener May 28 15:44:08 imperum-pml sudo[1945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start spop.service May 28 15:44:08 imperum-pml volumio[1642]: info: ------------------------------------------- May 28 15:44:08 imperum-pml volumio[1642]: info: ----- MyVolumio plugins startup ---- May 28 15:44:08 imperum-pml volumio[1642]: info: ------------------------------------------- May 28 15:44:08 imperum-pml volumio[1642]: info: [MyVolumio PluginManager] Fetching plans data.... May 28 15:44:08 imperum-pml sudo[1948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service May 28 15:44:08 imperum-pml sudo[1945]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:44:08 imperum-pml volumio[1642]: info: Loading i18n strings for locale es May 28 15:44:08 imperum-pml sudo[1948]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:44:08 imperum-pml volumio[1642]: Updating browse sources language May 28 15:44:08 imperum-pml volumio[1642]: Cannot find translation for sourceMedia Servers May 28 15:44:08 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 28 15:44:08 imperum-pml systemd[1]: Configuration file /lib/systemd/system/spop.service is marked executable. Please remove executable permission bits. Proceeding anyway. May 28 15:44:08 imperum-pml systemd[1]: Configuration file /lib/systemd/system/spop.service is marked world-writable. Please remove world writability permission bits. Proceeding anyway. May 28 15:44:08 imperum-pml systemd[1]: Starting Spotify Daemon... May 28 15:44:08 imperum-pml systemd[1]: Started Spotify Daemon. May 28 15:44:08 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 15:44:08 imperum-pml volumio[1642]: info: CoreCommandRouter::initPlayerControls May 28 15:44:08 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 15:44:08 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards May 28 15:44:08 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 15:44:08 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 15:44:08 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 15:44:08 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 15:44:08 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 15:44:08 imperum-pml volumio[1957]: 2025-05-28 15:44:08 [SPTF] 13:44:08.493 I [offline_authorizer.cpp:297] Unable to login offline: no such user May 28 15:44:08 imperum-pml volumio[1957]: 2025-05-28 15:44:08 [SPTF] 13:44:08.494 I [ap:1752] Connecting to AP ap.spotify.com:4070 May 28 15:44:08 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 28 15:44:08 imperum-pml volumio[1642]: info: BOOT COMPLETED May 28 15:44:08 imperum-pml volumio[1642]: [Metrics] CommandRouter: 7s 644.66ms May 28 15:44:08 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 15:44:08 imperum-pml sudo[1945]: pam_unix(sudo:session): session closed for user root May 28 15:44:08 imperum-pml systemd[1]: Starting Volspotconnect2 Daemon... May 28 15:44:08 imperum-pml kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 May 28 15:44:08 imperum-pml systemd[1]: Started Volspotconnect2 Daemon. May 28 15:44:08 imperum-pml sudo[1948]: pam_unix(sudo:session): session closed for user root May 28 15:44:08 imperum-pml volumio[1967]: vollibrespot v0.2.0 0c51076 2020-04-17 (librespot 8d65d82 2020-03-10) -- Built On 2020-04-17 May 28 15:44:08 imperum-pml volumio[1967]: Reading Config from "volspotify.toml" May 28 15:44:08 imperum-pml volumio[1967]: Malformed config key: missing field `Authentication` May 28 15:44:08 imperum-pml systemd[1]: volspotconnect2.service: main process exited, code=exited, status=1/FAILURE May 28 15:44:08 imperum-pml systemd[1]: Unit volspotconnect2.service entered failed state. May 28 15:44:08 imperum-pml systemd[1]: Started Music Player Daemon. May 28 15:44:08 imperum-pml sudo[1925]: pam_unix(sudo:session): session closed for user root May 28 15:44:08 imperum-pml volumio[1957]: 2025-05-28 15:44:08 [SPTF] 13:44:08.892 E [ap:1694] AP Socket Error: Host not found (20001) May 28 15:44:08 imperum-pml volumio[1957]: 2025-05-28 15:44:08 [SPTF] 13:44:08.892 E [ap:3953] Connection error: 4 May 28 15:44:08 imperum-pml volumio[1957]: 2025-05-28 15:44:08 [SPTF] 13:44:08.892 I [ap:1752] Connecting to AP ap.spotify.com:443 May 28 15:44:09 imperum-pml volumio[1957]: 2025-05-28 15:44:09 [SPTF] 13:44:09.144 E [ap:1694] AP Socket Error: Host not found (20001) May 28 15:44:09 imperum-pml volumio[1957]: 2025-05-28 15:44:09 [SPTF] 13:44:09.144 E [ap:3953] Connection error: 4 May 28 15:44:09 imperum-pml volumio[1957]: 2025-05-28 15:44:09 [SPTF] 13:44:09.145 I [ap:1752] Connecting to AP ap.spotify.com:80 May 28 15:44:09 imperum-pml volumio[1957]: 2025-05-28 15:44:09 [SPTF] 13:44:09.396 E [ap:1694] AP Socket Error: Host not found (20001) May 28 15:44:09 imperum-pml volumio[1957]: 2025-05-28 15:44:09 [SPTF] 13:44:09.397 E [ap:3953] Connection error: 4 May 28 15:44:09 imperum-pml volumio[1957]: 2025-05-28 15:44:09 [SPTF] 13:44:09.397 I [ap:1752] Connecting to AP ap.spotify.com:4070 May 28 15:44:09 imperum-pml volumio[1957]: 2025-05-28 15:44:09 [SPTF] 13:44:09.649 E [ap:1694] AP Socket Error: Host not found (20001) May 28 15:44:09 imperum-pml volumio[1957]: 2025-05-28 15:44:09 [SPTF] 13:44:09.649 E [ap:3953] Connection error: 4 May 28 15:44:09 imperum-pml volumio[1957]: 2025-05-28 15:44:09 [SPTF] 13:44:09.649 I [ap:1752] Connecting to AP ap.spotify.com:443 May 28 15:44:09 imperum-pml volumio[1957]: 2025-05-28 15:44:09 [SPTF] 13:44:09.901 E [ap:1694] AP Socket Error: Host not found (20001) May 28 15:44:09 imperum-pml volumio[1957]: 2025-05-28 15:44:09 [SPTF] 13:44:09.901 E [ap:3953] Connection error: 4 May 28 15:44:09 imperum-pml volumio[1957]: 2025-05-28 15:44:09 [SPTF] 13:44:09.901 I [ap:1752] Connecting to AP ap.spotify.com:80 May 28 15:44:10 imperum-pml volumio-remote-updater[608]: [2025-05-28 15:44:10] [connect] Successful connection May 28 15:44:10 imperum-pml volumio[1957]: 2025-05-28 15:44:10 [SPTF] 13:44:10.153 E [ap:1694] AP Socket Error: Host not found (20001) May 28 15:44:10 imperum-pml volumio[1957]: 2025-05-28 15:44:10 [SPTF] 13:44:10.153 E [ap:3953] Connection error: 4 May 28 15:44:10 imperum-pml volumio[1957]: 2025-05-28 15:44:10 [WARN] Login failed: General permanent error May 28 15:44:10 imperum-pml volumio[1957]: 2025-05-28 15:44:10 [ERR ] Could not get the playlist container. May 28 15:44:10 imperum-pml systemd[1]: spop.service: main process exited, code=killed, status=5/TRAP May 28 15:44:10 imperum-pml systemd[1]: Unit spop.service entered failed state. May 28 15:44:10 imperum-pml systemd[1]: volspotconnect2.service holdoff time over, scheduling restart. May 28 15:44:10 imperum-pml systemd[1]: Stopping Volspotconnect2 Daemon... May 28 15:44:10 imperum-pml systemd[1]: Starting Volspotconnect2 Daemon... May 28 15:44:10 imperum-pml systemd[1]: Started Volspotconnect2 Daemon. May 28 15:44:10 imperum-pml volumio[1983]: vollibrespot v0.2.0 0c51076 2020-04-17 (librespot 8d65d82 2020-03-10) -- Built On 2020-04-17 May 28 15:44:10 imperum-pml volumio[1983]: Reading Config from "volspotify.toml" May 28 15:44:10 imperum-pml volumio[1983]: Malformed config key: missing field `Authentication` May 28 15:44:10 imperum-pml systemd[1]: volspotconnect2.service: main process exited, code=exited, status=1/FAILURE May 28 15:44:10 imperum-pml systemd[1]: Unit volspotconnect2.service entered failed state. May 28 15:44:10 imperum-pml hotspot[1850]: Killing Hostapd May 28 15:44:10 imperum-pml sudo[1993]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall hostapd May 28 15:44:10 imperum-pml sudo[1993]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:44:10 imperum-pml hotspot[1850]: hostapd: no process found May 28 15:44:10 imperum-pml sudo[1993]: pam_unix(sudo:session): session closed for user root May 28 15:44:10 imperum-pml hotspot[1850]: Killing Dhcpd May 28 15:44:10 imperum-pml sudo[2001]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall dhcpd May 28 15:44:10 imperum-pml sudo[2001]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:44:10 imperum-pml hotspot[1850]: dhcpd: no process found May 28 15:44:10 imperum-pml sudo[2001]: pam_unix(sudo:session): session closed for user root May 28 15:44:10 imperum-pml systemd[1]: hotspot.service: control process exited, code=exited status=1 May 28 15:44:10 imperum-pml systemd[1]: Stopped hotspot.service. May 28 15:44:10 imperum-pml systemd[1]: Unit hotspot.service entered failed state. May 28 15:44:10 imperum-pml sudo[2011]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 28 15:44:10 imperum-pml sudo[2011]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:44:10 imperum-pml avahi-daemon[678]: Withdrawing address record for 192.168.211.1 on wlan0. May 28 15:44:10 imperum-pml avahi-daemon[678]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. May 28 15:44:10 imperum-pml avahi-daemon[678]: Interface wlan0.IPv4 no longer relevant for mDNS. May 28 15:44:10 imperum-pml sudo[2011]: pam_unix(sudo:session): session closed for user root May 28 15:44:10 imperum-pml sudo[2019]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 28 15:44:10 imperum-pml sudo[2019]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:44:11 imperum-pml sudo[2019]: pam_unix(sudo:session): session closed for user root May 28 15:44:11 imperum-pml sudo[1793]: pam_unix(sudo:session): session closed for user root May 28 15:44:11 imperum-pml wireless.js[1839]: Stopped aP May 28 15:44:11 imperum-pml wireless.js[1839]: Start wireless flow May 28 15:44:11 imperum-pml wireless.js[1839]: Stopped hotspot (if there).. May 28 15:44:11 imperum-pml sudo[2036]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 28 15:44:11 imperum-pml sudo[2036]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:44:11 imperum-pml sudo[2036]: pam_unix(sudo:session): session closed for user root May 28 15:44:11 imperum-pml sudo[2044]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 28 15:44:12 imperum-pml sudo[2044]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:44:12 imperum-pml sudo[2044]: pam_unix(sudo:session): session closed for user root May 28 15:44:12 imperum-pml wireless.js[1839]: DHCP IP May 28 15:44:12 imperum-pml wireless.js[1839]: Start ap May 28 15:44:12 imperum-pml wpa_supplicant[2052]: Successfully initialized wpa_supplicant May 28 15:44:12 imperum-pml sudo[2053]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd May 28 15:44:12 imperum-pml wpa_supplicant[2052]: nl80211: Could not configure driver mode May 28 15:44:12 imperum-pml sudo[2053]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:44:12 imperum-pml kernel: brcmfmac: power management disabled May 28 15:44:12 imperum-pml dhcpcd[2059]: version 6.0.5 starting May 28 15:44:12 imperum-pml dhcpcd[2059]: eth0: soliciting an IPv6 router May 28 15:44:12 imperum-pml dhcpcd[2059]: eth0: rebinding lease of 192.168.1.129 May 28 15:44:12 imperum-pml dhcpcd[2059]: wlan0: waiting for carrier May 28 15:44:12 imperum-pml CRON[2091]: pam_unix(cron:session): session opened for user volumio by (uid=0) May 28 15:44:12 imperum-pml CRON[2095]: (volumio) CMD (/home/volumio/protocoloTCN/bin/client > /dev/null 2>&1) May 28 15:44:12 imperum-pml CRON[2091]: pam_unix(cron:session): session closed for user volumio May 28 15:44:12 imperum-pml systemd[1]: volspotconnect2.service holdoff time over, scheduling restart. May 28 15:44:12 imperum-pml systemd[1]: Stopping Volspotconnect2 Daemon... May 28 15:44:12 imperum-pml systemd[1]: Starting Volspotconnect2 Daemon... May 28 15:44:12 imperum-pml systemd[1]: Started Volspotconnect2 Daemon. May 28 15:44:12 imperum-pml volumio[2100]: vollibrespot v0.2.0 0c51076 2020-04-17 (librespot 8d65d82 2020-03-10) -- Built On 2020-04-17 May 28 15:44:12 imperum-pml volumio[2100]: Reading Config from "volspotify.toml" May 28 15:44:12 imperum-pml volumio[2100]: Malformed config key: missing field `Authentication` May 28 15:44:12 imperum-pml systemd[1]: volspotconnect2.service: main process exited, code=exited, status=1/FAILURE May 28 15:44:12 imperum-pml systemd[1]: Unit volspotconnect2.service entered failed state. May 28 15:44:13 imperum-pml wireless.js[1839]: trying... May 28 15:44:13 imperum-pml sudo[2104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 28 15:44:13 imperum-pml sudo[2104]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:44:13 imperum-pml sudo[2104]: pam_unix(sudo:session): session closed for user root May 28 15:44:13 imperum-pml volumio[1642]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo May 28 15:44:13 imperum-pml volumio[1642]: info: CoreCommandRouter::Close All Modals sent May 28 15:44:13 imperum-pml volumio[1642]: info: CoreCommandRouter::Close All Modals sent May 28 15:44:13 imperum-pml volumio[1642]: Express server listening on port 3000 May 28 15:44:13 imperum-pml volumio[1642]: [Metrics] WebUI: 13s 455.74ms May 28 15:44:13 imperum-pml volumio[1642]: Volumio Calling Home May 28 15:44:13 imperum-pml volumio[1642]: (node:1642) [DEP0013] DeprecationWarning: Calling an asynchronous function without callback is deprecated. May 28 15:44:13 imperum-pml volumio[1642]: (node:1642) [DEP0013] DeprecationWarning: Calling an asynchronous function without callback is deprecated. May 28 15:44:13 imperum-pml volumio[1642]: (node:1642) [DEP0013] DeprecationWarning: Calling an asynchronous function without callback is deprecated. May 28 15:44:13 imperum-pml volumio[1642]: (node:1642) [DEP0013] DeprecationWarning: Calling an asynchronous function without callback is deprecated. May 28 15:44:13 imperum-pml volumio[1642]: info: CoreStateMachine::resetVolumioState May 28 15:44:13 imperum-pml volumio[1642]: info: CoreStateMachine::getcurrentVolume May 28 15:44:13 imperum-pml volumio[1642]: info: CoreCommandRouter::volumioRetrievevolume May 28 15:44:13 imperum-pml volumio[1642]: [SpotifyConnect] Restarting Vollibrespot Daemon May 28 15:44:13 imperum-pml sudo[2120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect2.service May 28 15:44:14 imperum-pml sudo[2120]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:44:14 imperum-pml systemd[1]: Stopping Volspotconnect2 Daemon... May 28 15:44:14 imperum-pml systemd[1]: Starting Volspotconnect2 Daemon... May 28 15:44:14 imperum-pml systemd[1]: Started Volspotconnect2 Daemon. May 28 15:44:14 imperum-pml ntpd[869]: Deleting interface #4 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=14 secs May 28 15:44:14 imperum-pml ntpd[869]: peers refreshed May 28 15:44:14 imperum-pml sudo[2120]: pam_unix(sudo:session): session closed for user root May 28 15:44:14 imperum-pml volumio[2125]: vollibrespot v0.2.0 0c51076 2020-04-17 (librespot 8d65d82 2020-03-10) -- Built On 2020-04-17 May 28 15:44:14 imperum-pml volumio[2125]: Reading Config from "volspotify.toml" May 28 15:44:14 imperum-pml volumio[2125]: Malformed config key: missing field `Authentication` May 28 15:44:14 imperum-pml systemd[1]: volspotconnect2.service: main process exited, code=exited, status=1/FAILURE May 28 15:44:14 imperum-pml systemd[1]: Unit volspotconnect2.service entered failed state. May 28 15:44:14 imperum-pml wireless.js[1839]: trying... May 28 15:44:14 imperum-pml sudo[2134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 28 15:44:14 imperum-pml sudo[2134]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:44:14 imperum-pml volumio[1642]: info: CoreCommandRouter::Close All Modals sent May 28 15:44:14 imperum-pml sudo[2134]: pam_unix(sudo:session): session closed for user root May 28 15:44:14 imperum-pml sudo[2146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 15:44:14 imperum-pml sudo[2146]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:44:14 imperum-pml sudo[2146]: pam_unix(sudo:session): session closed for user root May 28 15:44:14 imperum-pml volumio[1642]: [SpotifyConnect] Vollibrespot Daemon service restarted! May 28 15:44:14 imperum-pml volumio[1642]: info: Setting Device type: Raspberry PI May 28 15:44:14 imperum-pml volumio[1642]: [SpotifyConnect] Vollibrespot Daemon service started! May 28 15:44:14 imperum-pml volumio[1642]: [Metrics] SpotifyConnect: 6s 171.59ms May 28 15:44:14 imperum-pml volumio[1642]: info: SpopD Daemon Started May 28 15:44:14 imperum-pml volumio[1642]: info: MPD Permissions set May 28 15:44:14 imperum-pml volumio[1642]: info: MPD running with PID682 ,establishing connection May 28 15:44:14 imperum-pml volumio-remote-updater[608]: [2025-05-28 15:44:14] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1748439850 101 May 28 15:44:14 imperum-pml volumio[1642]: info: VolumeController:: Volume=100 Mute =false May 28 15:44:14 imperum-pml volumio[1642]: info: CoreStateMachine::pushState May 28 15:44:14 imperum-pml volumio[1642]: info: CoreStateMachine::getState May 28 15:44:14 imperum-pml volumio[1642]: info: CorePlayQueue::getTrack 0 May 28 15:44:14 imperum-pml volumio[1642]: info: CoreCommandRouter::volumioPushState May 28 15:44:14 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 15:44:14 imperum-pml volumio[1642]: info: CoreStateMachine::updateTrackBlock May 28 15:44:14 imperum-pml volumio[1642]: info: CorePlayQueue::getTrackBlock May 28 15:44:14 imperum-pml volumio[1642]: info: CoreCommandRouter::volumioRetrievevolume May 28 15:44:14 imperum-pml volumio[1642]: An internal error occurred while serving an albumart. Details: TypeError: Cannot read property 'username' of undefined May 28 15:44:14 imperum-pml volumio[1642]: at autorizacion (/volumio/http/index.js:53:62) May 28 15:44:14 imperum-pml volumio[1642]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) May 28 15:44:14 imperum-pml volumio[1642]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) May 28 15:44:14 imperum-pml volumio[1642]: at /volumio/node_modules/express/lib/router/index.js:284:7 May 28 15:44:14 imperum-pml volumio[1642]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) May 28 15:44:14 imperum-pml volumio[1642]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) May 28 15:44:14 imperum-pml volumio[1642]: at allowCrossDomain (/volumio/http/index.js:83:9) May 28 15:44:14 imperum-pml volumio[1642]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) May 28 15:44:14 imperum-pml volumio[1642]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) May 28 15:44:14 imperum-pml volumio[1642]: at /volumio/node_modules/express/lib/router/index.js:284:7 May 28 15:44:14 imperum-pml volumio[1642]: at Function.process_params (/volumio/node_modules/express/lib/router/index.js:335:12) May 28 15:44:14 imperum-pml volumio[1642]: at next (/volumio/node_modules/express/lib/router/index.js:275:10) May 28 15:44:14 imperum-pml volumio[1642]: at /volumio/node_modules/connect-busboy/index.js:14:14 May 28 15:44:14 imperum-pml volumio[1642]: at Layer.handle [as handle_request] (/volumio/node_modules/express/lib/router/layer.js:95:5) May 28 15:44:14 imperum-pml volumio[1642]: at trim_prefix (/volumio/node_modules/express/lib/router/index.js:317:13) May 28 15:44:14 imperum-pml volumio[1642]: at /volumio/node_modules/express/lib/router/index.js:284:7 May 28 15:44:14 imperum-pml volumio[1642]: error: MPD error: false == true May 28 15:44:14 imperum-pml volumio[1642]: error: MPD error: false == true May 28 15:44:14 imperum-pml volumio[1642]: info: MPD running with PID1946 ,establishing connection May 28 15:44:14 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 15:44:14 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 15:44:14 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 15:44:14 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 15:44:14 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 15:44:14 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 15:44:14 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 15:44:14 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 15:44:14 imperum-pml volumio[1642]: info: Reloading queue from file May 28 15:44:14 imperum-pml volumio[1642]: info: May 28 15:44:14 imperum-pml volumio[1642]: info: message= [50@0] {} No such directory, stack=Error: [50@0] {} No such directory May 28 15:44:14 imperum-pml volumio[1642]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:63:14) May 28 15:44:14 imperum-pml volumio[1642]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:10) May 28 15:44:14 imperum-pml volumio[1642]: at emitOne (events.js:116:13) May 28 15:44:14 imperum-pml volumio[1642]: at Socket.emit (events.js:211:7) May 28 15:44:14 imperum-pml volumio[1642]: at addChunk (_stream_readable.js:263:12) May 28 15:44:14 imperum-pml volumio[1642]: at readableAddChunk (_stream_readable.js:246:13) May 28 15:44:14 imperum-pml volumio[1642]: at Socket.Readable.push (_stream_readable.js:208:10) May 28 15:44:14 imperum-pml volumio[1642]: at Pipe.onread (net.js:607:20) May 28 15:44:14 imperum-pml volumio[1642]: info: CoreStateMachine::setRepeat false single undefined May 28 15:44:14 imperum-pml volumio[1642]: info: CoreStateMachine::pushState May 28 15:44:14 imperum-pml volumio[1642]: info: CoreStateMachine::getState May 28 15:44:14 imperum-pml volumio[1642]: info: CorePlayQueue::getTrack 0 May 28 15:44:14 imperum-pml volumio[1642]: info: CoreCommandRouter::volumioPushState May 28 15:44:14 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 15:44:14 imperum-pml volumio[1642]: info: CoreStateMachine::setRandom true May 28 15:44:14 imperum-pml volumio[1642]: info: CoreStateMachine::pushState May 28 15:44:14 imperum-pml volumio[1642]: info: CoreStateMachine::getState May 28 15:44:14 imperum-pml volumio[1642]: info: CorePlayQueue::getTrack 0 May 28 15:44:14 imperum-pml volumio[1642]: info: CoreCommandRouter::volumioPushState May 28 15:44:14 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 15:44:14 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 28 15:44:14 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: raat , onStop May 28 15:44:14 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 28 15:44:14 imperum-pml volumio[1642]: info: mDNS: A device disapperared from network May 28 15:44:14 imperum-pml volumio[1642]: info: mDNS Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined May 28 15:44:14 imperum-pml volumio[1642]: info: May 28 15:44:14 imperum-pml volumio[1642]: info: VolumeController:: Volume=100 Mute =false May 28 15:44:14 imperum-pml volumio[1642]: info: CoreStateMachine::pushState May 28 15:44:14 imperum-pml volumio[1642]: info: CoreStateMachine::getState May 28 15:44:14 imperum-pml volumio[1642]: info: CorePlayQueue::getTrack 0 May 28 15:44:14 imperum-pml volumio[1642]: info: CoreCommandRouter::volumioPushState May 28 15:44:14 imperum-pml volumio[1642]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 15:44:14 imperum-pml volumio[1642]: info: Starting Shairport Sync May 28 15:44:14 imperum-pml sudo[2169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 28 15:44:14 imperum-pml volumio[1642]: info: Starting Shairport Sync May 28 15:44:14 imperum-pml sudo[2169]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:44:14 imperum-pml sudo[2171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 28 15:44:14 imperum-pml wpa_supplicant[2090]: wlan0: Trying to associate with c8:3a:35:25:fb:70 (SSID='SM30' freq=2427 MHz) May 28 15:44:14 imperum-pml sudo[2171]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:44:14 imperum-pml systemd[1]: Starting UPnP Renderer front-end to MPD... May 28 15:44:14 imperum-pml systemd[1]: Started UPnP Renderer front-end to MPD. May 28 15:44:15 imperum-pml sudo[2180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 28 15:44:15 imperum-pml sudo[2169]: pam_unix(sudo:session): session closed for user root May 28 15:44:15 imperum-pml systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... May 28 15:44:15 imperum-pml systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 28 15:44:15 imperum-pml sudo[2171]: pam_unix(sudo:session): session closed for user root May 28 15:44:15 imperum-pml sudo[2180]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:44:15 imperum-pml systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 28 15:44:15 imperum-pml systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... May 28 15:44:15 imperum-pml systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 28 15:44:15 imperum-pml sudo[2180]: pam_unix(sudo:session): session closed for user root May 28 15:44:15 imperum-pml wpa_supplicant[2090]: wlan0: Associated with c8:3a:35:25:fb:70 May 28 15:44:15 imperum-pml wpa_supplicant[2090]: wlan0: WPA: Key negotiation completed with c8:3a:35:25:fb:70 [PTK=CCMP GTK=TKIP] May 28 15:44:15 imperum-pml wpa_supplicant[2090]: wlan0: CTRL-EVENT-CONNECTED - Connection to c8:3a:35:25:fb:70 completed [id=0 id_str=] May 28 15:44:15 imperum-pml dhcpcd[2059]: wlan0: carrier acquired May 28 15:44:15 imperum-pml wireless.js[1839]: trying... May 28 15:44:15 imperum-pml dhcpcd[2059]: wlan0: soliciting an IPv6 router May 28 15:44:15 imperum-pml volumio[1642]: info: Shairport-Sync Started May 28 15:44:15 imperum-pml sudo[2224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 28 15:44:15 imperum-pml volumio[1642]: Error adding Membership: Error: addMembership EINVAL May 28 15:44:15 imperum-pml volumio[1642]: info: Upmpdcli Daemon Started May 28 15:44:15 imperum-pml sudo[2224]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:44:15 imperum-pml sudo[2224]: pam_unix(sudo:session): session closed for user root May 28 15:44:15 imperum-pml volumio[1642]: info: Setting volume on startup at 100 May 28 15:44:15 imperum-pml dhcpcd[2059]: wlan0: rebinding lease of 192.168.1.132 May 28 15:44:15 imperum-pml sudo[2231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start spop.service May 28 15:44:15 imperum-pml sudo[2231]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:44:15 imperum-pml volumio[1642]: info: Shairport-Sync Started May 28 15:44:15 imperum-pml systemd[1]: Starting Spotify Daemon... May 28 15:44:15 imperum-pml volumio[1642]: info: CoreCommandRouter::volumioGetState May 28 15:44:15 imperum-pml systemd[1]: Started Spotify Daemon. May 28 15:44:15 imperum-pml volumio[1642]: info: CoreStateMachine::getState May 28 15:44:15 imperum-pml sudo[2231]: pam_unix(sudo:session): session closed for user root May 28 15:44:15 imperum-pml volumio[1642]: info: CorePlayQueue::getTrack 0 May 28 15:44:15 imperum-pml volumio[2238]: 2025-05-28 15:44:15 [SPTF] 13:44:15.262 I [offline_authorizer.cpp:297] Unable to login offline: no such user May 28 15:44:15 imperum-pml volumio[2238]: 2025-05-28 15:44:15 [ERR ] Can't bind socket: Cannot assign requested address May 28 15:44:15 imperum-pml systemd[1]: spop.service: main process exited, code=killed, status=5/TRAP May 28 15:44:15 imperum-pml systemd[1]: Unit spop.service entered failed state. May 28 15:44:15 imperum-pml volumio[1642]: info: SpopD Daemon Started May 28 15:44:16 imperum-pml wireless.js[1839]: trying... May 28 15:44:16 imperum-pml systemd[1]: volspotconnect2.service holdoff time over, scheduling restart. May 28 15:44:16 imperum-pml systemd[1]: Stopping Volspotconnect2 Daemon... May 28 15:44:16 imperum-pml systemd[1]: Starting Volspotconnect2 Daemon... May 28 15:44:16 imperum-pml systemd[1]: Started Volspotconnect2 Daemon. May 28 15:44:16 imperum-pml sudo[2250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 28 15:44:16 imperum-pml volumio[2251]: vollibrespot v0.2.0 0c51076 2020-04-17 (librespot 8d65d82 2020-03-10) -- Built On 2020-04-17 May 28 15:44:16 imperum-pml volumio[2251]: Reading Config from "volspotify.toml" May 28 15:44:16 imperum-pml sudo[2250]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:44:16 imperum-pml sudo[2250]: pam_unix(sudo:session): session closed for user root May 28 15:44:16 imperum-pml volumio[2251]: [Vollibrespot] : Using Alsa backend with device: compresor May 28 15:44:16 imperum-pml volumio[2251]: thread 'main' panicked at 'Discovery error!: Os { code: 19, kind: Other, message: "No such device" }', src/main.rs:229:17 May 28 15:44:16 imperum-pml volumio[2251]: stack backtrace: May 28 15:44:16 imperum-pml volumio[2251]: 0: 0xb16730 - backtrace::backtrace::libunwind::trace::h100c6c70e4af496c May 28 15:44:16 imperum-pml volumio[2251]: at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.40/src/backtrace/libunwind.rs:88 May 28 15:44:16 imperum-pml volumio[2251]: 1: 0xb16730 - backtrace::backtrace::trace_unsynchronized::hf78ddc194bf45009 May 28 15:44:16 imperum-pml volumio[2251]: at /cargo/registry/src/github.com-1ecc6299db9ec823/backtrace-0.3.40/src/backtrace/mod.rs:66 May 28 15:44:16 imperum-pml volumio[2251]: 2: 0xb16730 - std::sys_common::backtrace::_print_fmt::h967a212a8894e4a4 May 28 15:44:16 imperum-pml volumio[2251]: at src/libstd/sys_common/backtrace.rs:77 May 28 15:44:16 imperum-pml volumio[2251]: 3: 0xb16730 - ::fmt::h5fe383ce2118859a May 28 15:44:16 imperum-pml volumio[2251]: at src/libstd/sys_common/backtrace.rs:59 May 28 15:44:16 imperum-pml dhcpcd[2059]: eth0: leased 192.168.1.129 for 86400 seconds May 28 15:44:16 imperum-pml dhcpcd[2059]: eth0: adding host route to 192.168.1.129 via 127.0.0.1 May 28 15:44:16 imperum-pml dhcpcd[2059]: eth0: adding route to 192.168.1.0/24 May 28 15:44:16 imperum-pml dhcpcd[2059]: eth0: adding default route via 192.168.1.1 May 28 15:44:16 imperum-pml avahi-daemon[678]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.129. May 28 15:44:16 imperum-pml avahi-daemon[678]: New relevant interface eth0.IPv4 for mDNS. May 28 15:44:16 imperum-pml avahi-daemon[678]: Registering new address record for 192.168.1.129 on eth0.IPv4. May 28 15:44:16 imperum-pml volumio[2251]: 4: 0xb31f20 - core::fmt::write::h937f59172f6eeeaf May 28 15:44:16 imperum-pml volumio[2251]: at src/libcore/fmt/mod.rs:1052 May 28 15:44:16 imperum-pml volumio[2251]: 5: 0xb106ac - std::io::Write::write_fmt::hc70e2634948b89c8 May 28 15:44:16 imperum-pml volumio[2251]: at src/libstd/io/mod.rs:1426 May 28 15:44:16 imperum-pml volumio[2251]: 6: 0xb18bf8 - std::sys_common::backtrace::_print::hbfc81757b29a60f4 May 28 15:44:16 imperum-pml volumio[2251]: at src/libstd/sys_common/backtrace.rs:62 May 28 15:44:16 imperum-pml volumio[2251]: 7: 0xb18bf8 - std::sys_common::backtrace::print::h8bc349c117633211 May 28 15:44:16 imperum-pml volumio[2251]: at src/libstd/sys_common/backtrace.rs:49 May 28 15:44:16 imperum-pml volumio[2251]: 8: 0xb18bf8 - std::panicking::default_hook::{{closure}}::h82c47dcbb8503118 May 28 15:44:16 imperum-pml volumio[2251]: at src/libstd/panicking.rs:204 May 28 15:44:16 imperum-pml volumio[2251]: 9: 0xb1886c - std::panicking::default_hook::hc04800be16711189 May 28 15:44:16 imperum-pml volumio[2251]: at src/libstd/panicking.rs:224 May 28 15:44:16 imperum-pml systemd[1]: volspotconnect2.service: main process exited, code=exited, status=101/n/a May 28 15:44:16 imperum-pml systemd[1]: Unit volspotconnect2.service entered failed state. May 28 15:44:16 imperum-pml volumio[2251]: 10: 0xb192a8 - std::panicking::rust_panic_with_hook::h2413ff75e44cd581 May 28 15:44:16 imperum-pml volumio[2251]: at src/libstd/panicking.rs:472 May 28 15:44:16 imperum-pml volumio[2251]: 11: 0xb18ebc - rust_begin_unwind May 28 15:44:16 imperum-pml volumio[2251]: at src/libstd/panicking.rs:380 May 28 15:44:16 imperum-pml volumio[2251]: 12: 0xb2f98c - core::panicking::panic_fmt::h2c56481ca2ee7c2f May 28 15:44:16 imperum-pml volumio[2251]: at src/libcore/panicking.rs:85 May 28 15:44:16 imperum-pml volumio[2251]: 13: 0xb2f7e8 - core::option::expect_none_failed::ha234f7f2802df4d8 May 28 15:44:16 imperum-pml volumio[2251]: at src/libcore/option.rs:1199 May 28 15:44:16 imperum-pml volumio[2251]: 14: 0x551d88 - vollibrespot::main::h9f38da6aba824ea5 May 28 15:44:16 imperum-pml volumio[2251]: 15: 0x584ec4 - std::rt::lang_start::{{closure}}::h15358b854b572f97 May 28 15:44:16 imperum-pml volumio[2251]: 16: 0xb18d6c - std::rt::lang_start_internal::{{closure}}::habef164db2ffdce8 May 28 15:44:16 imperum-pml volumio[2251]: at src/libstd/rt.rs:52 May 28 15:44:16 imperum-pml volumio[2251]: 17: 0xb18d6c - std::panicking::try::do_call::ha24ef807c5f4c553 May 28 15:44:16 imperum-pml volumio[2251]: at src/libstd/panicking.rs:305 May 28 15:44:16 imperum-pml volumio[2251]: 18: 0xb1d4dc - __rust_maybe_catch_panic May 28 15:44:16 imperum-pml volumio[2251]: at src/libpanic_unwind/lib.rs:86 May 28 15:44:16 imperum-pml volumio[2251]: 19: 0xb196ec - std::panicking::try::hbf32b3a5688b523f May 28 15:44:16 imperum-pml volumio[2251]: at src/libstd/panicking.rs:281 May 28 15:44:16 imperum-pml volumio[2251]: 20: 0xb196ec - std::panic::catch_unwind::haaedb267e2d3c6e6 May 28 15:44:16 imperum-pml volumio[2251]: at src/libstd/panic.rs:394 May 28 15:44:16 imperum-pml volumio[2251]: 21: 0xb196ec - std::rt::lang_start_internal::hdc1e9a94360026b9 May 28 15:44:16 imperum-pml volumio[2251]: at src/libstd/rt.rs:51 May 28 15:44:16 imperum-pml volumio[2251]: 22: 0x55243c - main May 28 15:44:16 imperum-pml volumio[2251]: 23: 0x76cd2294 - __libc_start_main May 28 15:44:16 imperum-pml dhcpcd[2059]: forked to background, child pid 2283 May 28 15:44:16 imperum-pml sudo[2053]: pam_unix(sudo:session): session closed for user root May 28 15:44:17 imperum-pml wireless.js[1839]: trying... May 28 15:44:17 imperum-pml sudo[2298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 28 15:44:17 imperum-pml sudo[2298]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:44:17 imperum-pml sudo[2298]: pam_unix(sudo:session): session closed for user root May 28 15:44:17 imperum-pml volumio[1642]: Discovery: adding df3b82f0-ab0c-479e-8a85-90fc398574a7 May 28 15:44:17 imperum-pml volumio[1642]: info: mDNS: Found device imperum-pml May 28 15:44:17 imperum-pml volumio[1642]: info: CoreCommandRouter::volumioGetState May 28 15:44:17 imperum-pml volumio[1642]: info: CoreStateMachine::getState May 28 15:44:17 imperum-pml volumio[1642]: info: CorePlayQueue::getTrack 0 May 28 15:44:17 imperum-pml sudo[2406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 15:44:17 imperum-pml sudo[2406]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:44:17 imperum-pml sudo[2406]: pam_unix(sudo:session): session closed for user root May 28 15:44:17 imperum-pml sudo[2410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 28 15:44:17 imperum-pml sudo[2410]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:44:17 imperum-pml sudo[2410]: pam_unix(sudo:session): session closed for user root May 28 15:44:18 imperum-pml ntpd[869]: Listen normally on 5 eth0 192.168.1.129 UDP 123 May 28 15:44:18 imperum-pml ntpd[869]: peers refreshed May 28 15:44:18 imperum-pml wireless.js[1839]: trying... May 28 15:44:18 imperum-pml sudo[2430]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 28 15:44:18 imperum-pml sudo[2430]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:44:18 imperum-pml sudo[2430]: pam_unix(sudo:session): session closed for user root May 28 15:44:18 imperum-pml volumio[1642]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 15:44:18 imperum-pml volumio[1642]: { Error: addMembership ENODEV May 28 15:44:18 imperum-pml volumio[1642]: at _errnoException (util.js:1022:11) May 28 15:44:18 imperum-pml volumio[1642]: at Socket.addMembership (dgram.js:615:11) May 28 15:44:18 imperum-pml volumio[1642]: at Timeout.addMembership [as _onTimeout] (/volumio/node_modules/node-ssdp/lib/index.js:264:16) May 28 15:44:18 imperum-pml volumio[1642]: at ontimeout (timers.js:482:11) May 28 15:44:18 imperum-pml volumio[1642]: at tryOnTimeout (timers.js:317:5) May 28 15:44:18 imperum-pml volumio[1642]: at Timer.listOnTimeout (timers.js:277:5) code: 'ENODEV', errno: 'ENODEV', syscall: 'addMembership' } May 28 15:44:18 imperum-pml volumio[1642]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 15:44:18 imperum-pml systemd[1]: volspotconnect2.service holdoff time over, scheduling restart. May 28 15:44:18 imperum-pml systemd[1]: Stopping Volspotconnect2 Daemon... May 28 15:44:18 imperum-pml systemd[1]: Starting Volspotconnect2 Daemon... May 28 15:44:18 imperum-pml systemd[1]: Started Volspotconnect2 Daemon. May 28 15:44:18 imperum-pml volumio[2445]: vollibrespot v0.2.0 0c51076 2020-04-17 (librespot 8d65d82 2020-03-10) -- Built On 2020-04-17 May 28 15:44:18 imperum-pml volumio[2445]: Reading Config from "volspotify.toml" May 28 15:44:18 imperum-pml volumio[2445]: [Vollibrespot] : Using Alsa backend with device: compresor May 28 15:44:19 imperum-pml volumio[2445]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } May 28 15:44:19 imperum-pml wireless.js[1839]: trying... May 28 15:44:19 imperum-pml sudo[2449]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 28 15:44:19 imperum-pml sudo[2449]: pam_unix(sudo:session): session opened for user root by (uid=0) May 28 15:44:19 imperum-pml sudo[2449]: pam_unix(sudo:session): session closed for user root May 28 15:44:19 imperum-pml sudo[2458]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-28 15:43 May 28 15:44:19 imperum-pml sudo[2458]: 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="ba22215fabbe766d668749499a1c25fcefd90f0a" VOLUMIO_FE_VERSION="" VOLUMIO_BE_VERSION="043dcaee624f27d121213b83af11a9f2feac0945" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Mar 18 01:32:43 CET 2020" VOLUMIO_VERSION="2.729" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="5a0b29326faa8f5d0f5fe95d9e58c069"