-- Logs begin at Mon 2024-10-21 21:44:16 +07, end at Mon 2024-10-21 22:41:34 +07. -- Oct 21 22:40:02 volumio dhcpcd[835]: wlan0: carrier lost Oct 21 22:40:02 volumio avahi-daemon[744]: Withdrawing address record for 192.168.1.15 on wlan0. Oct 21 22:40:02 volumio avahi-daemon[744]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.15. Oct 21 22:40:02 volumio wpa_supplicant[2533]: wlan0: CTRL-EVENT-DISCONNECTED bssid=cc:71:90:68:83:68 reason=4 locally_generated=1 Oct 21 22:40:02 volumio avahi-daemon[744]: Interface wlan0.IPv4 no longer relevant for mDNS. Oct 21 22:40:02 volumio wpa_supplicant[2533]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Oct 21 22:40:02 volumio wpa_supplicant[2533]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=US Oct 21 22:40:02 volumio volumio[994]: info: Discovery: A device disappeared from network Oct 21 22:40:02 volumio volumio[994]: info: Discovery: Device volumio disappeared from network Oct 21 22:40:02 volumio dhcpcd[835]: wlan0: deleting route to 192.168.1.0/24 Oct 21 22:40:02 volumio dhcpcd[835]: wlan0: deleting default route via 192.168.1.1 Oct 21 22:40:03 volumio ntpd[2600]: Deleting interface #3 wlan0, 192.168.1.15#123, interface stats: received=11, sent=176, dropped=0, active_time=3094 secs Oct 21 22:40:03 volumio ntpd[2600]: 27.71.27.209 local addr 192.168.1.15 -> Oct 21 22:40:03 volumio ntpd[2600]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Oct 21 22:40:03 volumio ntpd[2600]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Oct 21 22:40:06 volumio ntpd[2600]: Soliciting pool server 2404:fbc0:0:2046::c Oct 21 22:40:09 volumio nmbd[861]: [2024/10/21 22:40:09.527489, 0] ../source3/libsmb/nmblib.c:917(send_udp) Oct 21 22:40:09 volumio nmbd[861]: Packet send failed to 192.168.1.255(137) ERRNO=Network is unreachable Oct 21 22:40:09 volumio nmbd[861]: [2024/10/21 22:40:09.527840, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Oct 21 22:40:09 volumio nmbd[861]: send_netbios_packet: send_packet() to IP 192.168.1.255 port 137 failed Oct 21 22:40:09 volumio nmbd[861]: [2024/10/21 22:40:09.527904, 0] ../source3/nmbd/nmbd_namequery.c:245(query_name) Oct 21 22:40:09 volumio nmbd[861]: query_name: Failed to send packet trying to query name WORKGROUP<1d> Oct 21 22:40:09 volumio nmbd[861]: [2024/10/21 22:40:09.531564, 0] ../source3/nmbd/nmbd.c:361(reload_interfaces) Oct 21 22:40:09 volumio nmbd[861]: reload_interfaces: No subnets to listen to. Waiting.. Oct 21 22:40:28 volumio volumio[994]: info: Err:32 http://deb.debian.org/debian buster/main amd64 libpython3.7-dev amd64 3.7.3-2+deb10u3 Oct 21 22:40:28 volumio volumio[994]: info: System error resolving 'deb.debian.org:http' - getaddrinfo (16: Device or resource busy) [IP: 151.101.78.132 80] Oct 21 22:40:28 volumio volumio[994]: info: Err:33 http://deb.debian.org/debian buster/main amd64 libpython3-dev amd64 3.7.3-1 Oct 21 22:40:28 volumio volumio[994]: info: System error resolving 'deb.debian.org:http' - getaddrinfo (16: Device or resource busy) [IP: 151.101.78.132 80] Oct 21 22:40:28 volumio volumio[994]: info: Ign:34 http://deb.debian.org/debian buster/main amd64 python-pip-whl all 18.1-5 Oct 21 22:40:28 volumio volumio[994]: info: Err:35 http://deb.debian.org/debian buster/main amd64 python3-multidict amd64 4.5.2-1 Oct 21 22:40:28 volumio volumio[994]: info: System error resolving 'deb.debian.org:http' - getaddrinfo (16: Device or resource busy) [IP: 151.101.78.132 80] Oct 21 22:40:28 volumio volumio[994]: info: Err:36 http://deb.debian.org/debian buster/main amd64 python3-yarl amd64 1.3.0-1 Oct 21 22:40:28 volumio volumio[994]: info: System error resolving 'deb.debian.org:http' - getaddrinfo (16: Device or resource busy) [IP: 151.101.78.132 80] Oct 21 22:40:28 volumio volumio[994]: info: Ign:37 http://deb.debian.org/debian buster/main amd64 python3-async-timeout all 3.0.1-1 Oct 21 22:40:28 volumio volumio[994]: info: Ign:38 http://deb.debian.org/debian buster/main amd64 python3-attr all 18.2.0-1 Oct 21 22:40:28 volumio volumio[994]: info: Err:39 http://deb.debian.org/debian buster/main amd64 python3-aiohttp amd64 3.5.1-1+deb10u1 Oct 21 22:40:28 volumio volumio[994]: info: System error resolving 'deb.debian.org:http' - getaddrinfo (16: Device or resource busy) [IP: 151.101.78.132 80] Oct 21 22:40:28 volumio volumio[994]: info: Ign:40 http://deb.debian.org/debian buster/main amd64 python3-asn1crypto all 0.24.0-1 Oct 21 22:40:28 volumio volumio[994]: info: Err:41 http://deb.debian.org/debian buster/main amd64 python3-cffi-backend amd64 1.12.2-1 Oct 21 22:40:28 volumio volumio[994]: info: System error resolving 'deb.debian.org:http' - getaddrinfo (16: Device or resource busy) [IP: 151.101.78.132 80] Oct 21 22:40:28 volumio volumio[994]: info: Err:42 http://deb.debian.org/debian buster/main amd64 python3-crypto amd64 2.6.1-9+b1 Oct 21 22:40:28 volumio volumio[994]: info: System error resolving 'deb.debian.org:http' - getaddrinfo (16: Device or resource busy) [IP: 151.101.78.132 80] Oct 21 22:40:28 volumio volumio[994]: info: Err:43 http://deb.debian.org/debian buster/main amd64 python3-cryptography amd64 2.6.1-3+deb10u2 Oct 21 22:40:28 volumio volumio[994]: info: System error resolving 'deb.debian.org:http' - getaddrinfo (16: Device or resource busy) [IP: 151.101.78.132 80] Oct 21 22:40:28 volumio volumio[994]: info: Err:44 http://deb.debian.org/debian buster/main amd64 python3-dbus amd64 1.2.8-3 Oct 21 22:40:28 volumio volumio[994]: info: System error resolving 'deb.debian.org:http' - getaddrinfo (16: Device or resource busy) [IP: 151.101.78.132 80] Oct 21 22:40:28 volumio volumio[994]: info: Err:45 http://deb.debian.org/debian buster/main amd64 python3.7-dev amd64 3.7.3-2+deb10u3 Oct 21 22:40:28 volumio volumio[994]: info: System error resolving 'deb.debian.org:http' - getaddrinfo (16: Device or resource busy) [IP: 151.101.78.132 80] Oct 21 22:40:28 volumio volumio[994]: info: Err:46 http://deb.debian.org/debian buster/main amd64 python3-dev amd64 3.7.3-1 Oct 21 22:40:28 volumio volumio[994]: info: System error resolving 'deb.debian.org:http' - getaddrinfo (16: Device or resource busy) [IP: 151.101.78.132 80] Oct 21 22:40:28 volumio volumio[994]: info: Ign:47 http://deb.debian.org/debian buster/main amd64 python3-entrypoints all 0.3-1 Oct 21 22:40:28 volumio volumio[994]: info: Err:48 http://deb.debian.org/debian buster/main amd64 python3-gi amd64 3.30.4-1 Oct 21 22:40:28 volumio volumio[994]: info: System error resolving 'deb.debian.org:http' - getaddrinfo (16: Device or resource busy) [IP: 151.101.78.132 80] Oct 21 22:40:28 volumio volumio[994]: info: Ign:49 http://deb.debian.org/debian buster/main amd64 python3-secretstorage all 2.3.1-2 Oct 21 22:40:28 volumio volumio[994]: info: Ign:50 http://deb.debian.org/debian buster/main amd64 python3-keyring all 17.1.1-1 Oct 21 22:40:28 volumio sudo[4322]: pam_unix(sudo:session): session closed for user root Oct 21 22:40:28 volumio volumio[994]: info: Ign:51 http://deb.debian.org/debian buster/main amd64 python3-keyrings.alt all 3.1.1-1 Oct 21 22:40:28 volumio volumio[994]: info: Ign:52 http://deb.debian.org/debian buster/main amd64 python3-pip all 18.1-5 Oct 21 22:40:28 volumio volumio[994]: info: Ign:53 http://deb.debian.org/debian buster/main amd64 python3-setuptools all 40.8.0-1 Oct 21 22:40:28 volumio volumio[994]: info: Ign:54 http://deb.debian.org/debian buster/main amd64 python3-wheel all 0.32.3-2 Oct 21 22:40:28 volumio volumio[994]: info: Ign:55 http://deb.debian.org/debian buster/main amd64 python3-xdg all 0.25-5 Oct 21 22:40:28 volumio volumio[994]: info: Err:34 http://deb.debian.org/debian buster/main amd64 python-pip-whl all 18.1-5 Oct 21 22:40:28 volumio volumio[994]: info: System error resolving 'deb.debian.org:http' - getaddrinfo (16: Device or resource busy) [IP: 151.101.78.132 80] Oct 21 22:40:28 volumio volumio[994]: info: Err:37 http://deb.debian.org/debian buster/main amd64 python3-async-timeout all 3.0.1-1 Oct 21 22:40:28 volumio volumio[994]: info: System error resolving 'deb.debian.org:http' - getaddrinfo (16: Device or resource busy) [IP: 151.101.78.132 80] Oct 21 22:40:28 volumio volumio[994]: info: Err:38 http://deb.debian.org/debian buster/main amd64 python3-attr all 18.2.0-1 Oct 21 22:40:28 volumio volumio[994]: info: System error resolving 'deb.debian.org:http' - getaddrinfo (16: Device or resource busy) [IP: 151.101.78.132 80] Oct 21 22:40:28 volumio volumio[994]: info: Err:40 http://deb.debian.org/debian buster/main amd64 python3-asn1crypto all 0.24.0-1 Oct 21 22:40:28 volumio volumio[994]: info: System error resolving 'deb.debian.org:http' - getaddrinfo (16: Device or resource busy) [IP: 151.101.78.132 80] Oct 21 22:40:28 volumio volumio[994]: info: Err:47 http://deb.debian.org/debian buster/main amd64 python3-entrypoints all 0.3-1 Oct 21 22:40:28 volumio volumio[994]: info: System error resolving 'deb.debian.org:http' - getaddrinfo (16: Device or resource busy) [IP: 151.101.78.132 80] Oct 21 22:40:28 volumio volumio[994]: info: Err:49 http://deb.debian.org/debian buster/main amd64 python3-secretstorage all 2.3.1-2 Oct 21 22:40:28 volumio volumio[994]: info: System error resolving 'deb.debian.org:http' - getaddrinfo (16: Device or resource busy) [IP: 151.101.78.132 80] Oct 21 22:40:28 volumio volumio[994]: info: Err:50 http://deb.debian.org/debian buster/main amd64 python3-keyring all 17.1.1-1 Oct 21 22:40:28 volumio volumio[994]: info: System error resolving 'deb.debian.org:http' - getaddrinfo (16: Device or resource busy) [IP: 151.101.78.132 80] Oct 21 22:40:28 volumio volumio[994]: info: Err:51 http://deb.debian.org/debian buster/main amd64 python3-keyrings.alt all 3.1.1-1 Oct 21 22:40:28 volumio volumio[994]: info: System error resolving 'deb.debian.org:http' - getaddrinfo (16: Device or resource busy) [IP: 151.101.78.132 80] Oct 21 22:40:28 volumio volumio[994]: info: Err:52 http://deb.debian.org/debian buster/main amd64 python3-pip all 18.1-5 Oct 21 22:40:28 volumio volumio[994]: info: System error resolving 'deb.debian.org:http' - getaddrinfo (16: Device or resource busy) [IP: 151.101.78.132 80] Oct 21 22:40:28 volumio volumio[994]: info: Err:53 http://deb.debian.org/debian buster/main amd64 python3-setuptools all 40.8.0-1 Oct 21 22:40:28 volumio volumio[994]: info: System error resolving 'deb.debian.org:http' - getaddrinfo (16: Device or resource busy) [IP: 151.101.78.132 80] Oct 21 22:40:28 volumio volumio[994]: info: Err:54 http://deb.debian.org/debian buster/main amd64 python3-wheel all 0.32.3-2 Oct 21 22:40:29 volumio volumio[994]: info: System error resolving 'deb.debian.org:http' - getaddrinfo (16: Device or resource busy) [IP: 151.101.78.132 80] Oct 21 22:40:29 volumio volumio[994]: info: Err:55 http://deb.debian.org/debian buster/main amd64 python3-xdg all 0.25-5 Oct 21 22:40:29 volumio volumio[994]: info: System error resolving 'deb.debian.org:http' - getaddrinfo (16: Device or resource busy) [IP: 151.101.78.132 80] Oct 21 22:40:29 volumio sudo[5752]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C / Oct 21 22:40:29 volumio sudo[5752]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 22:40:29 volumio sudo[5752]: pam_unix(sudo:session): session closed for user root Oct 21 22:40:29 volumio sudo[5756]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chown -R volumio cgui Oct 21 22:40:29 volumio sudo[5756]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 22:40:29 volumio sudo[5756]: pam_unix(sudo:session): session closed for user root Oct 21 22:40:29 volumio sudo[5758]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chgrp -R volumio cgui Oct 21 22:40:29 volumio sudo[5758]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 22:40:29 volumio sudo[5758]: pam_unix(sudo:session): session closed for user root Oct 21 22:40:29 volumio sudo[5760]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/rm cgui-1.0.0.zip Oct 21 22:40:29 volumio sudo[5760]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 22:40:29 volumio sudo[5760]: pam_unix(sudo:session): session closed for user root Oct 21 22:40:29 volumio volumio[994]: info: Fetched 32.8 MB in 26min 42s (20.5 kB/s) Oct 21 22:40:29 volumio volumio[994]: info: lib/systemd/system/fusiondsp.service Oct 21 22:40:29 volumio volumio[994]: info: MiniUnz 1.01b, demo of zLib + Unz package written by Gilles Vollant Oct 21 22:40:29 volumio volumio[994]: info: more info at http://www.winimage.com/zLibDll/unzip.html Oct 21 22:40:29 volumio volumio[994]: info: Oct 21 22:40:29 volumio volumio[994]: info: Cannot open cgui-1.0.0.zip or cgui-1.0.0.zip.zip Oct 21 22:40:29 volumio volumio[994]: info: copying hw detection script Oct 21 22:40:29 volumio volumio[994]: info: Detected cpu architecture as x86_64 Oct 21 22:40:29 volumio sudo[5774]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/usr/bin/apt-get -y install drc Oct 21 22:40:29 volumio sudo[5774]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 22:40:29 volumio volumio[994]: info: Reading package lists... Oct 21 22:40:30 volumio volumio[994]: info: Building dependency tree... Oct 21 22:40:30 volumio volumio[994]: info: Reading state information... Oct 21 22:40:32 volumio volumio[994]: info: The following NEW packages will be installed: Oct 21 22:40:32 volumio volumio[994]: info: drc Oct 21 22:40:32 volumio volumio[994]: info: 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. Oct 21 22:40:32 volumio volumio[994]: info: Need to get 143 kB of archives. Oct 21 22:40:32 volumio volumio[994]: info: After this operation, 609 kB of additional disk space will be used. Oct 21 22:40:32 volumio volumio[994]: info: Err:1 http://deb.debian.org/debian buster/main amd64 drc amd64 3.2.2~dfsg0-2 Oct 21 22:40:32 volumio volumio[994]: info: System error resolving 'deb.debian.org:http' - getaddrinfo (16: Device or resource busy) Oct 21 22:40:32 volumio sudo[5774]: pam_unix(sudo:session): session closed for user root Oct 21 22:40:32 volumio sudo[3629]: pam_unix(sudo:session): session closed for user root Oct 21 22:40:32 volumio volumio[994]: info: Install script completed Oct 21 22:40:32 volumio volumio[994]: info: Adding reference to registry Oct 21 22:40:32 volumio volumio[994]: info: Done installing plugin. Oct 21 22:40:32 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 21 22:40:32 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 21 22:40:32 volumio volumio[994]: info: Folder /tmp/plugins removed Oct 21 22:40:32 volumio volumio[994]: info: Folder /tmp/downloaded_plugin.zip removed Oct 21 22:40:32 volumio volumio[994]: Plugin install end detected on script Oct 21 22:40:32 volumio volumio[994]: info: Folder /data/temp removed Oct 21 22:40:47 volumio wpa_supplicant[2533]: wlan0: SME: Trying to authenticate with cc:71:90:68:83:68 (SSID='VAN TOAN' freq=2422 MHz) Oct 21 22:40:47 volumio kernel: wlan0: authenticate with cc:71:90:68:83:68 Oct 21 22:40:47 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Oct 21 22:40:47 volumio wpa_supplicant[2533]: wlan0: Trying to associate with cc:71:90:68:83:68 (SSID='VAN TOAN' freq=2422 MHz) Oct 21 22:40:47 volumio kernel: wlan0: send auth to cc:71:90:68:83:68 (try 1/3) Oct 21 22:40:47 volumio kernel: wlan0: authenticated Oct 21 22:40:47 volumio kernel: wlan0: associate with cc:71:90:68:83:68 (try 1/3) Oct 21 22:40:47 volumio wpa_supplicant[2533]: wlan0: Associated with cc:71:90:68:83:68 Oct 21 22:40:47 volumio wpa_supplicant[2533]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Oct 21 22:40:47 volumio wpa_supplicant[2533]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Oct 21 22:40:47 volumio kernel: wlan0: RX AssocResp from cc:71:90:68:83:68 (capab=0x411 status=0 aid=10) Oct 21 22:40:47 volumio kernel: wlan0: associated Oct 21 22:40:47 volumio wpa_supplicant[2533]: wlan0: WPA: Key negotiation completed with cc:71:90:68:83:68 [PTK=CCMP GTK=CCMP] Oct 21 22:40:47 volumio wpa_supplicant[2533]: wlan0: CTRL-EVENT-CONNECTED - Connection to cc:71:90:68:83:68 completed [id=0 id_str=] Oct 21 22:40:47 volumio dhcpcd[835]: wlan0: carrier acquired Oct 21 22:40:47 volumio dhcpcd[835]: wlan0: IAID a2:f3:b9:fb Oct 21 22:40:47 volumio dhcpcd[835]: wlan0: rebinding lease of 192.168.1.15 Oct 21 22:40:48 volumio dhcpcd[835]: wlan0: soliciting an IPv6 router Oct 21 22:40:52 volumio dhcpcd[835]: wlan0: probing for an IPv4LL address Oct 21 22:40:52 volumio dhcpcd[835]: wlan0: DHCP lease expired Oct 21 22:40:52 volumio dhcpcd[835]: wlan0: soliciting a DHCP lease Oct 21 22:40:52 volumio dhcpcd[835]: wlan0: offered 192.168.1.15 from 192.168.1.1 Oct 21 22:40:52 volumio dhcpcd[835]: wlan0: probing address 192.168.1.15/24 Oct 21 22:40:56 volumio wpa_supplicant[2533]: wlan0: CTRL-EVENT-BEACON-LOSS Oct 21 22:40:58 volumio dhcpcd[835]: wlan0: leased 192.168.1.15 for 86400 seconds Oct 21 22:40:58 volumio avahi-daemon[744]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.15. Oct 21 22:40:58 volumio dhcpcd[835]: wlan0: adding route to 192.168.1.0/24 Oct 21 22:40:58 volumio dhcpcd[835]: wlan0: adding default route via 192.168.1.1 Oct 21 22:40:58 volumio avahi-daemon[744]: New relevant interface wlan0.IPv4 for mDNS. Oct 21 22:40:58 volumio avahi-daemon[744]: Registering new address record for 192.168.1.15 on wlan0.IPv4. Oct 21 22:40:58 volumio ntpd[2600]: ntpd exiting on signal 15 (Terminated) Oct 21 22:40:58 volumio systemd[1]: Stopping Network Time Service... Oct 21 22:40:58 volumio systemd[1]: ntp.service: Succeeded. Oct 21 22:40:58 volumio systemd[1]: Stopped Network Time Service. Oct 21 22:40:58 volumio systemd[1]: Starting Network Time Service... Oct 21 22:40:58 volumio ntpd[5825]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 21 22:40:58 volumio ntpd[5825]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Oct 21 22:40:58 volumio systemd[1]: Started Network Time Service. Oct 21 22:40:58 volumio ntpd[5831]: proto: precision = 0.163 usec (-22) Oct 21 22:40:58 volumio ntpd[5831]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 21 22:40:58 volumio ntpd[5831]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 21 22:40:58 volumio ntpd[5831]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 664 days ago Oct 21 22:40:58 volumio ntpd[5831]: Listen and drop on 0 v6wildcard [::]:123 Oct 21 22:40:58 volumio ntpd[5831]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 21 22:40:58 volumio ntpd[5831]: Listen normally on 2 lo 127.0.0.1:123 Oct 21 22:40:58 volumio ntpd[5831]: Listen normally on 3 wlan0 192.168.1.15:123 Oct 21 22:40:58 volumio ntpd[5831]: Listening on routing socket on fd #20 for interface updates Oct 21 22:40:58 volumio ntpd[5831]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Oct 21 22:40:58 volumio ntpd[5831]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized Oct 21 22:40:59 volumio volumio[994]: info: Discovery: adding 66151d4f-85ef-4d61-b8cb-9c5fd75e34ef Oct 21 22:40:59 volumio volumio[994]: info: Discovery: Found device Volumio Oct 21 22:40:59 volumio volumio[994]: info: CoreCommandRouter::volumioGetState Oct 21 22:40:59 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Oct 21 22:41:04 volumio ntpd[5831]: Soliciting pool server 103.130.217.41 Oct 21 22:41:04 volumio ntpd[5831]: Soliciting pool server 103.130.217.41 Oct 21 22:41:04 volumio ntpd[5831]: Soliciting pool server 115.165.161.155 Oct 21 22:41:04 volumio ntpd[5831]: Soliciting pool server 103.184.124.23 Oct 21 22:41:05 volumio ntpd[5831]: Soliciting pool server 27.71.27.209 Oct 21 22:41:06 volumio ntpd[5831]: Soliciting pool server 115.165.161.155 Oct 21 22:41:30 volumio volumio[994]: info: Enabling plugin fusiondsp Oct 21 22:41:30 volumio volumio[994]: info: Loading plugin "fusiondsp"... Oct 21 22:41:31 volumio volumio[994]: info: Preparing to generate the ALSA configuration file Oct 21 22:41:31 volumio volumio[994]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 21 22:41:31 volumio volumio[994]: info: Reading ALSA contributions from plugins. Oct 21 22:41:31 volumio volumio[994]: info: Asound.conf file written Oct 21 22:41:31 volumio sudo[5886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 21 22:41:31 volumio sudo[5886]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 22:41:31 volumio sudo[5886]: pam_unix(sudo:session): session closed for user root Oct 21 22:41:31 volumio volumio[994]: No state is present for card HDMI Oct 21 22:41:31 volumio volumio[994]: Found hardware: "HDA-Intel" "ATI R6xx HDMI" "HDA:1002aa01,00aa0100,00100500" "0x103c" "0x2187" Oct 21 22:41:31 volumio volumio[994]: Hardware is initialized using a generic method Oct 21 22:41:31 volumio volumio[994]: No state is present for card HDMI Oct 21 22:41:31 volumio volumio[994]: No state is present for card Generic Oct 21 22:41:31 volumio volumio[994]: Found hardware: "HDA-Intel" "Realtek ALC221" "HDA:10ec0221,103c2187,00100003" "0x103c" "0x2187" Oct 21 22:41:31 volumio volumio[994]: Hardware is initialized using a generic method Oct 21 22:41:31 volumio volumio[994]: No state is present for card Generic Oct 21 22:41:31 volumio volumio[994]: info: Output device has changed, restarting MPD Oct 21 22:41:31 volumio sudo[5891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 21 22:41:31 volumio sudo[5891]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 22:41:31 volumio sudo[5891]: pam_unix(sudo:session): session closed for user root Oct 21 22:41:31 volumio volumio[994]: info: Output device has changed, restarting Shairport Sync Oct 21 22:41:31 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 22:41:31 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 21 22:41:31 volumio sudo[5894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 21 22:41:31 volumio sudo[5894]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 22:41:31 volumio systemd[1]: Stopping Music Player Daemon... Oct 21 22:41:31 volumio systemd[1]: mpd.service: Succeeded. Oct 21 22:41:31 volumio systemd[1]: Stopped Music Player Daemon. Oct 21 22:41:31 volumio volumio[994]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 21 22:41:31 volumio volumio[994]: info: PLUGIN START: fusiondsp Oct 21 22:41:31 volumio systemd[1]: Starting Music Player Daemon... Oct 21 22:41:31 volumio volumio[994]: info: Loading i18n strings for locale vi Oct 21 22:41:31 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 21 22:41:31 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 22:41:31 volumio volumio[994]: info: FusionDsp - mixtype--------------------- Hardware Oct 21 22:41:31 volumio volumio[994]: info: Preparing to generate the ALSA configuration file Oct 21 22:41:31 volumio volumio[994]: info: Done. Oct 21 22:41:31 volumio volumio[994]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 21 22:41:31 volumio volumio[994]: info: Reading ALSA contributions from plugins. Oct 21 22:41:31 volumio volumio[994]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 21 22:41:31 volumio volumio[994]: info: MPD Permissions set Oct 21 22:41:31 volumio sudo[5899]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 21 22:41:31 volumio sudo[5899]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 22:41:31 volumio volumio[994]: info: CoreCommandRouter::volumioGetState Oct 21 22:41:31 volumio volumio[994]: info: CorePlayQueue::getTrack 0 Oct 21 22:41:31 volumio sudo[5899]: pam_unix(sudo:session): session closed for user root Oct 21 22:41:31 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 22:41:31 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 22:41:31 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 22:41:31 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 22:41:31 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 22:41:31 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 22:41:31 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 22:41:31 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 22:41:31 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 21 22:41:31 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 21 22:41:31 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 22:41:31 volumio volumio[994]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 21 22:41:31 volumio volumio[994]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 21 22:41:31 volumio volumio[994]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 21 22:41:31 volumio volumio[994]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 21 22:41:31 volumio volumio[994]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 21 22:41:31 volumio volumio[994]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 21 22:41:31 volumio volumio[994]: info: Starting Shairport Sync Oct 21 22:41:31 volumio volumio[994]: info: Asound.conf file unchanged, so no further update is needed Oct 21 22:41:31 volumio volumio[994]: info: Output device has changed, restarting MPD Oct 21 22:41:32 volumio sudo[5909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 21 22:41:32 volumio sudo[5909]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 22:41:32 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 21 22:41:32 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 21 22:41:32 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 21 22:41:32 volumio sudo[5912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 21 22:41:32 volumio sudo[5912]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 22:41:32 volumio sudo[5912]: pam_unix(sudo:session): session closed for user root Oct 21 22:41:32 volumio volumio[994]: info: Output device has changed, restarting Shairport Sync Oct 21 22:41:32 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 22:41:32 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 21 22:41:32 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 21 22:41:32 volumio sudo[5909]: pam_unix(sudo:session): session closed for user root Oct 21 22:41:32 volumio sudo[5916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 21 22:41:32 volumio sudo[5916]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 22:41:32 volumio volumio[994]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 21 22:41:32 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 21 22:41:32 volumio systemd[1]: mpd.service: Succeeded. Oct 21 22:41:32 volumio systemd[1]: Stopped Music Player Daemon. Oct 21 22:41:32 volumio systemd[1]: Starting Music Player Daemon... Oct 21 22:41:32 volumio sudo[5923]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 21 22:41:32 volumio sudo[5923]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 22:41:32 volumio sudo[5923]: pam_unix(sudo:session): session closed for user root Oct 21 22:41:33 volumio mpd[5925]: Oct 21 22:41 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 21 22:41:33 volumio systemd[1]: Started Music Player Daemon. Oct 21 22:41:33 volumio sudo[5894]: pam_unix(sudo:session): session closed for user root Oct 21 22:41:33 volumio sudo[5916]: pam_unix(sudo:session): session closed for user root Oct 21 22:41:33 volumio volumio[994]: info: MPD Permissions set Oct 21 22:41:33 volumio volumio[994]: info: Shairport-Sync Started Oct 21 22:41:33 volumio volumio[994]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Oct 21 22:41:33 volumio volumio[994]: info: camilladsp service started and running in background, instance 1 Oct 21 22:41:33 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 22:41:33 volumio volumio[994]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 96000 192000 <-AAAAAAAAAAAAA Oct 21 22:41:33 volumio volumio[994]: info: FusionDsp loaded Oct 21 22:41:33 volumio volumio[994]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 21 22:41:33 volumio sudo[5939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Oct 21 22:41:33 volumio sudo[5939]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 22:41:33 volumio volumio[994]: info: FusionDsp - Reporting Fusion DSP Enabled Oct 21 22:41:33 volumio volumio[994]: info: Adding Signal Path Element [object Object] Oct 21 22:41:33 volumio volumio[994]: info: Adding fusiondspeq DSP Signal Path Element Oct 21 22:41:33 volumio volumio[994]: info: FusionDsp - ---- installed callbackRead Oct 21 22:41:33 volumio volumio[994]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 21 22:41:33 volumio volumio[994]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Oct 21 22:41:33 volumio volumio[994]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Oct 21 22:41:33 volumio volumio[994]: at onErrorNT (internal/child_process.js:472:16) Oct 21 22:41:33 volumio volumio[994]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Oct 21 22:41:33 volumio volumio[994]: at runNextTicks (internal/process/task_queues.js:64:3) Oct 21 22:41:33 volumio volumio[994]: at listOnTimeout (internal/timers.js:526:9) Oct 21 22:41:33 volumio volumio[994]: at processTimers (internal/timers.js:500:7) { Oct 21 22:41:33 volumio volumio[994]: errno: -2, Oct 21 22:41:33 volumio volumio[994]: code: 'ENOENT', Oct 21 22:41:33 volumio volumio[994]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Oct 21 22:41:33 volumio volumio[994]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Oct 21 22:41:33 volumio volumio[994]: spawnargs: [ Oct 21 22:41:33 volumio volumio[994]: '-p', Oct 21 22:41:33 volumio volumio[994]: 9876, Oct 21 22:41:33 volumio volumio[994]: '-o', Oct 21 22:41:33 volumio volumio[994]: '/tmp/camilladsp.log', Oct 21 22:41:33 volumio volumio[994]: '-l', Oct 21 22:41:33 volumio volumio[994]: 'warn', Oct 21 22:41:33 volumio volumio[994]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Oct 21 22:41:33 volumio volumio[994]: ] Oct 21 22:41:33 volumio volumio[994]: } Oct 21 22:41:33 volumio volumio[994]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 21 22:41:33 volumio systemd[1]: Started FusionDsp Daemon. Oct 21 22:41:33 volumio sudo[5939]: pam_unix(sudo:session): session closed for user root Oct 21 22:41:34 volumio volumio[5941]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Oct 21 22:41:34 volumio systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Oct 21 22:41:34 volumio systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 21 22:41:34 volumio sudo[5951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-21 22:40 Oct 21 22:41:34 volumio sudo[5951]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"