-- Logs begin at Sat 2024-07-06 20:35:57 +07, end at Sat 2024-07-06 20:37:01 +07. -- Jul 06 20:36:00 volumio systemd[1]: wireless.service: Succeeded. Jul 06 20:36:00 volumio systemd[1]: Started Wireless Services. Jul 06 20:36:00 volumio dnsmasq[1042]: dnsmasq: syntax check OK. Jul 06 20:36:00 volumio systemd[1]: Started Volumio Backend Module. Jul 06 20:36:00 volumio systemd[1]: Started Start Volumio Kiosk. Jul 06 20:36:00 volumio systemd[1]: Started Intel SST and HDA soundcard init service. Jul 06 20:36:00 volumio systemd[1]: Started Volumio Cpu Tweaker. Jul 06 20:36:00 volumio volumio-cpu-tweak[1048]: Setting RT Priority for mpd Jul 06 20:36:00 volumio volumio-cpu-tweak[1048]: pid 35's current scheduling policy: SCHED_FIFO Jul 06 20:36:00 volumio volumio-cpu-tweak[1048]: pid 35's current scheduling priority: 99 Jul 06 20:36:00 volumio volumio-cpu-tweak[1048]: Setting MPD Affinity Jul 06 20:36:00 volumio volumio-cpu-tweak[1048]: pid 3's current affinity mask: f Jul 06 20:36:00 volumio volumio-cpu-tweak[1048]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jul 06 20:36:00 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Jul 06 20:36:00 volumio dnsmasq[1095]: started, version 2.80 cachesize 150 Jul 06 20:36:00 volumio dnsmasq[1095]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jul 06 20:36:00 volumio dnsmasq-dhcp[1095]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jul 06 20:36:00 volumio dnsmasq[1095]: reading /etc/resolv.conf Jul 06 20:36:00 volumio dnsmasq[1095]: using nameserver 208.67.222.222#53 Jul 06 20:36:00 volumio dnsmasq[1095]: using nameserver 208.67.220.220#53 Jul 06 20:36:00 volumio dnsmasq[1095]: read /etc/hosts - 5 addresses Jul 06 20:36:00 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:00 volumio systemd[1]: Reached target Host and Network Name Lookups. Jul 06 20:36:00 volumio startx[1046]: X.Org X Server 1.20.4 Jul 06 20:36:00 volumio startx[1046]: X Protocol Version 11, Revision 0 Jul 06 20:36:00 volumio startx[1046]: Build Operating System: Linux 5.10.0-10-amd64 x86_64 Debian Jul 06 20:36:00 volumio startx[1046]: Current Operating System: Linux volumio 6.6.31-volumio #2 SMP PREEMPT_DYNAMIC Fri May 17 18:26:28 CEST 2024 x86_64 Jul 06 20:36:00 volumio startx[1046]: Kernel command line: BOOT_IMAGE=/vmlinuz initramfs.clear splash plymouth.ignore-serial-consoles quiet loglevel=0 biosdevname=0 imgpart=UUID=991616eb-1b6c-4a8c-a9f4-cc1f6af606da bootpart=UUID=5579-66E1 datapart=UUID=49461c6e-0f97-4e37-b9ea-cba11f201fc6 hwdevice=x86 uuidconfig=syslinux.cfg,efi/BOOT/grub.cfg imgfile=/volumio_current.sqsh logo.nologo vt.global_cursor_default=0 acpi_backlight=vendor net.ifnames=0 use_kmsg=no Jul 06 20:36:00 volumio startx[1046]: Build Date: 18 December 2021 09:05:36AM Jul 06 20:36:00 volumio startx[1046]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support) Jul 06 20:36:00 volumio startx[1046]: Current version of pixman: 0.36.0 Jul 06 20:36:00 volumio startx[1046]: Before reporting problems, check http://wiki.x.org Jul 06 20:36:00 volumio startx[1046]: to make sure that you have the latest version. Jul 06 20:36:00 volumio startx[1046]: Markers: (--) probed, (**) from config file, (==) default setting, Jul 06 20:36:00 volumio startx[1046]: (++) from command line, (!!) notice, (II) informational, Jul 06 20:36:00 volumio startx[1046]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jul 06 20:36:00 volumio startx[1046]: (==) Log file: "/var/log/Xorg.0.log", Time: Sat Jul 6 20:36:00 2024 Jul 06 20:36:00 volumio startx[1046]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jul 06 20:36:00 volumio kernel: broken atomic modeset userspace detected, disabling atomic Jul 06 20:36:00 volumio startx[1046]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. Jul 06 20:36:00 volumio systemd[1]: soundcard-init.service: Succeeded. Jul 06 20:36:00 volumio ntpd[1001]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jul 06 20:36:01 volumio volumio[1045]: info: ------------------------------------------- Jul 06 20:36:01 volumio volumio[1045]: info: ----- Volumio3 ---- Jul 06 20:36:01 volumio volumio[1045]: info: ------------------------------------------- Jul 06 20:36:01 volumio volumio[1045]: info: ----- System startup ---- Jul 06 20:36:01 volumio volumio[1045]: info: ------------------------------------------- Jul 06 20:36:01 volumio ntpd[1001]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jul 06 20:36:01 volumio volumio[1045]: info: MYVOLUMIO Environment detected Jul 06 20:36:01 volumio volumio[1045]: info: Plugin folders cleanup Jul 06 20:36:01 volumio volumio[1045]: info: Scanning into folder /volumio/app/plugins/ Jul 06 20:36:01 volumio volumio[1045]: info: Scanning category audio_interface Jul 06 20:36:01 volumio volumio[1045]: info: Scanning category miscellanea Jul 06 20:36:01 volumio volumio[1045]: info: Scanning category music_service Jul 06 20:36:01 volumio volumio[1045]: info: Scanning category plugins.json Jul 06 20:36:01 volumio volumio[1045]: info: Scanning category system_controller Jul 06 20:36:01 volumio volumio[1045]: info: Scanning category user_interface Jul 06 20:36:01 volumio volumio[1045]: info: Scanning into folder /data/plugins/ Jul 06 20:36:01 volumio volumio[1045]: info: Scanning category audio_interface Jul 06 20:36:01 volumio volumio[1045]: info: Scanning category music_service Jul 06 20:36:01 volumio volumio[1045]: info: Plugin folders cleanup completed Jul 06 20:36:01 volumio volumio[1045]: info: ------------------------------------------- Jul 06 20:36:01 volumio volumio[1045]: info: ----- Core plugins startup ---- Jul 06 20:36:01 volumio volumio[1045]: info: ------------------------------------------- Jul 06 20:36:01 volumio volumio[1045]: info: Loading plugins from folder /volumio/app/plugins/ Jul 06 20:36:01 volumio volumio[1045]: info: Adding plugin upnp to MyMusic Plugins Jul 06 20:36:01 volumio volumio[1045]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 06 20:36:01 volumio volumio[1045]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 06 20:36:01 volumio volumio[1045]: info: Loading plugins from folder /data/plugins/ Jul 06 20:36:01 volumio volumio[1045]: info: Loading plugin "system"... Jul 06 20:36:01 volumio volumio[1045]: info: Loading plugin "appearance"... Jul 06 20:36:02 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jul 06 20:36:02 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 1. Jul 06 20:36:02 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:02 volumio dnsmasq[1095]: exiting on receipt of SIGTERM Jul 06 20:36:02 volumio systemd[1]: dnsmasq.service: Succeeded. Jul 06 20:36:02 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:02 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:02 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jul 06 20:36:02 volumio hostapd[1276]: Configuration file: /etc/hostapd/hostapd.conf Jul 06 20:36:02 volumio hostapd[1276]: rfkill: WLAN soft blocked Jul 06 20:36:02 volumio hostapd[1276]: wlan0: Could not connect to kernel driver Jul 06 20:36:02 volumio hostapd[1276]: Using interface wlan0 with hwaddr f8:a2:d6:ea:9e:4f and ssid "Volumio-E1AFE" Jul 06 20:36:02 volumio hostapd[1276]: Failed to set beacon parameters Jul 06 20:36:02 volumio hostapd[1276]: wlan0: Could not connect to kernel driver Jul 06 20:36:02 volumio hostapd[1276]: Interface initialization failed Jul 06 20:36:02 volumio hostapd[1276]: wlan0: interface state UNINITIALIZED->DISABLED Jul 06 20:36:02 volumio hostapd[1276]: wlan0: AP-DISABLED Jul 06 20:36:02 volumio hostapd[1276]: wlan0: Unable to setup interface. Jul 06 20:36:02 volumio hostapd[1276]: wlan0: interface state DISABLED->DISABLED Jul 06 20:36:02 volumio hostapd[1276]: wlan0: AP-DISABLED Jul 06 20:36:02 volumio hostapd[1276]: wlan0: CTRL-EVENT-TERMINATING Jul 06 20:36:02 volumio hostapd[1276]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jul 06 20:36:02 volumio hostapd[1276]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jul 06 20:36:02 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jul 06 20:36:02 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jul 06 20:36:02 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:02 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:02 volumio dnsmasq[1277]: dnsmasq: syntax check OK. Jul 06 20:36:02 volumio dnsmasq[1283]: started, version 2.80 cachesize 150 Jul 06 20:36:02 volumio dnsmasq[1283]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jul 06 20:36:02 volumio dnsmasq-dhcp[1283]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jul 06 20:36:02 volumio dnsmasq[1283]: reading /etc/resolv.conf Jul 06 20:36:02 volumio dnsmasq[1283]: using nameserver 208.67.222.222#53 Jul 06 20:36:02 volumio dnsmasq[1283]: using nameserver 208.67.220.220#53 Jul 06 20:36:02 volumio dnsmasq[1283]: read /etc/hosts - 5 addresses Jul 06 20:36:02 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:02 volumio ntpd[1001]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jul 06 20:36:02 volumio volumio[1045]: info: Loading plugin "network"... Jul 06 20:36:02 volumio volumio[1045]: info: Refreshing Cached IP Addresses Jul 06 20:36:02 volumio sudo[1302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 06 20:36:02 volumio sudo[1304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 06 20:36:02 volumio volumio[1045]: info: Loading plugin "services"... Jul 06 20:36:02 volumio sudo[1302]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 20:36:02 volumio sudo[1304]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 20:36:02 volumio sudo[1302]: pam_unix(sudo:session): session closed for user root Jul 06 20:36:02 volumio sudo[1304]: pam_unix(sudo:session): session closed for user root Jul 06 20:36:02 volumio volumio[1045]: info: Loading plugin "alsa_controller"... Jul 06 20:36:02 volumio sudo[1306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 06 20:36:02 volumio sudo[1306]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 20:36:02 volumio sudo[1306]: pam_unix(sudo:session): session closed for user root Jul 06 20:36:02 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 06 20:36:02 volumio volumio[1045]: info: Loading plugin "wizard"... Jul 06 20:36:02 volumio volumio[1045]: info: Loading plugin "networkfs"... Jul 06 20:36:02 volumio volumio[1045]: info: Starting Udev Watcher for removable devices Jul 06 20:36:02 volumio volumio[1045]: info: Ignoring mount for partition: boot Jul 06 20:36:02 volumio volumio[1045]: info: Ignoring mount for partition: volumio Jul 06 20:36:02 volumio volumio[1045]: info: Ignoring mount for partition: volumio_data Jul 06 20:36:02 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 06 20:36:02 volumio volumio[1045]: info: Loading plugin "volumio_command_line_client"... Jul 06 20:36:02 volumio volumio[1045]: info: Loading plugin "upnp"... Jul 06 20:36:02 volumio volumio[1045]: info: [1720272962909] Starting Upmpd Daemon Jul 06 20:36:02 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 06 20:36:02 volumio volumio[1045]: info: Loading plugin "my_music"... Jul 06 20:36:02 volumio volumio[1045]: info: Loading plugin "mpd"... Jul 06 20:36:03 volumio volumio[1045]: info: Loading plugin "upnp_browser"... Jul 06 20:36:03 volumio volumio[1045]: info: Loading plugin "alarm-clock"... Jul 06 20:36:03 volumio ntpd[1001]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jul 06 20:36:03 volumio volumio[1045]: info: Loading plugin "airplay_emulation"... Jul 06 20:36:03 volumio volumio[1045]: info: Starting Shairport Sync Jul 06 20:36:03 volumio volumio[1045]: info: Loading plugin "last_100"... Jul 06 20:36:03 volumio volumio[1045]: info: Loading plugin "webradio"... Jul 06 20:36:03 volumio volumio[1045]: info: Loading plugin "i2s_dacs"... Jul 06 20:36:03 volumio volumio[1045]: info: I2S DAC not set, start Auto-detection Jul 06 20:36:03 volumio volumio[1045]: info: Loading plugin "volumiodiscovery"... Jul 06 20:36:03 volumio volumio[1045]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 06 20:36:03 volumio volumio[1045]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 06 20:36:03 volumio volumio[1045]: *** WARNING *** For more information see Jul 06 20:36:03 volumio volumio[1045]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 06 20:36:03 volumio volumio[1045]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 06 20:36:03 volumio node[1045]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 06 20:36:03 volumio volumio[1045]: *** WARNING *** For more information see Jul 06 20:36:03 volumio node[1045]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 06 20:36:03 volumio node[1045]: *** WARNING *** For more information see Jul 06 20:36:03 volumio node[1045]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 06 20:36:03 volumio node[1045]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 06 20:36:03 volumio node[1045]: *** WARNING *** For more information see Jul 06 20:36:03 volumio volumio[1045]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 06 20:36:03 volumio volumio[1045]: info: Discovery: Started advertising with name: Volumio Jul 06 20:36:03 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 06 20:36:03 volumio volumio[1045]: info: Loading plugin "spop"... Jul 06 20:36:03 volumio systemd[1]: systemd-rfkill.service: Succeeded. Jul 06 20:36:03 volumio volumio[1045]: info: Loading plugin "youtube2"... Jul 06 20:36:04 volumio volumio-remote-updater[802]: [2024-07-06 20:36:04] [connect] Successful connection Jul 06 20:36:04 volumio volumio[1045]: info: Loading plugin "ytmusic"... Jul 06 20:36:04 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jul 06 20:36:04 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2. Jul 06 20:36:04 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:04 volumio dnsmasq[1283]: exiting on receipt of SIGTERM Jul 06 20:36:04 volumio systemd[1]: dnsmasq.service: Succeeded. Jul 06 20:36:04 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:04 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:04 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jul 06 20:36:04 volumio hostapd[1385]: Configuration file: /etc/hostapd/hostapd.conf Jul 06 20:36:04 volumio hostapd[1385]: rfkill: WLAN soft blocked Jul 06 20:36:04 volumio hostapd[1385]: wlan0: Could not connect to kernel driver Jul 06 20:36:04 volumio hostapd[1385]: Using interface wlan0 with hwaddr f8:a2:d6:ea:9e:4f and ssid "Volumio-E1AFE" Jul 06 20:36:04 volumio hostapd[1385]: Failed to set beacon parameters Jul 06 20:36:04 volumio hostapd[1385]: wlan0: Could not connect to kernel driver Jul 06 20:36:04 volumio hostapd[1385]: Interface initialization failed Jul 06 20:36:04 volumio hostapd[1385]: wlan0: interface state UNINITIALIZED->DISABLED Jul 06 20:36:04 volumio hostapd[1385]: wlan0: AP-DISABLED Jul 06 20:36:04 volumio hostapd[1385]: wlan0: Unable to setup interface. Jul 06 20:36:04 volumio hostapd[1385]: wlan0: interface state DISABLED->DISABLED Jul 06 20:36:04 volumio hostapd[1385]: wlan0: AP-DISABLED Jul 06 20:36:04 volumio hostapd[1385]: wlan0: CTRL-EVENT-TERMINATING Jul 06 20:36:04 volumio hostapd[1385]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jul 06 20:36:04 volumio hostapd[1385]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jul 06 20:36:04 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jul 06 20:36:04 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jul 06 20:36:04 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:04 volumio volumio[1045]: info: Loading plugin "outputs"... Jul 06 20:36:04 volumio volumio[1045]: info: Loading plugin "albumart"... Jul 06 20:36:04 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:04 volumio volumio[1045]: info: Plugin example_plugin is not enabled Jul 06 20:36:04 volumio volumio[1045]: info: Loading plugin "inputs"... Jul 06 20:36:04 volumio dnsmasq[1386]: dnsmasq: syntax check OK. Jul 06 20:36:04 volumio volumio[1045]: info: Loading plugin "updater_comm"... Jul 06 20:36:04 volumio dnsmasq[1399]: started, version 2.80 cachesize 150 Jul 06 20:36:04 volumio dnsmasq[1399]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jul 06 20:36:04 volumio dnsmasq-dhcp[1399]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jul 06 20:36:04 volumio dnsmasq[1399]: reading /etc/resolv.conf Jul 06 20:36:04 volumio dnsmasq[1399]: using nameserver 208.67.222.222#53 Jul 06 20:36:04 volumio dnsmasq[1399]: using nameserver 208.67.220.220#53 Jul 06 20:36:04 volumio dnsmasq[1399]: read /etc/hosts - 5 addresses Jul 06 20:36:04 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:04 volumio volumio[1045]: Forking 3 albumart workers Jul 06 20:36:04 volumio volumio[1045]: info: Plugin mpdemulation is not enabled Jul 06 20:36:04 volumio volumio[1045]: info: Loading plugin "rest_api"... Jul 06 20:36:04 volumio volumio[1045]: info: Loading plugin "websocket"... Jul 06 20:36:04 volumio volumio[1045]: info: Starting Socket.io Server version 2.3.0 Jul 06 20:36:04 volumio volumio[1045]: info: Plugin fusiondsp is not enabled Jul 06 20:36:04 volumio volumio[1045]: info: Loading i18n strings for locale vi Jul 06 20:36:04 volumio volumio[1045]: Updating browse sources language Jul 06 20:36:04 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 06 20:36:04 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 06 20:36:04 volumio volumio[1045]: info: CoreCommandRouter::initPlayerControls Jul 06 20:36:04 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 20:36:04 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 20:36:04 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 20:36:04 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 20:36:04 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 20:36:04 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 20:36:04 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 20:36:04 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 20:36:04 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 06 20:36:04 volumio volumio[1045]: Express server listening on port 3000 Jul 06 20:36:04 volumio volumio[1045]: [Metrics] WebUI: 4s 129.89ms Jul 06 20:36:04 volumio volumio[1045]: info: Setting Device type: x86 Jul 06 20:36:04 volumio volumio[1045]: info: CoreStateMachine::resetVolumioState Jul 06 20:36:04 volumio volumio[1045]: info: CoreStateMachine::getcurrentVolume Jul 06 20:36:04 volumio volumio[1045]: info: CoreCommandRouter::volumioRetrievevolume Jul 06 20:36:04 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 20:36:04 volumio volumio[1045]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jul 06 20:36:04 volumio volumio[1045]: info: Completed loading Core Plugins Jul 06 20:36:04 volumio volumio[1045]: info: Preparing to generate the ALSA configuration file Jul 06 20:36:04 volumio volumio[1045]: Starting albumart workers Jul 06 20:36:04 volumio volumio[1045]: Starting albumart workers Jul 06 20:36:04 volumio volumio[1045]: Starting albumart workers Jul 06 20:36:04 volumio volumio[1045]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jul 06 20:36:04 volumio volumio[1045]: wlan0 Interface doesn't support scanning : Network is down Jul 06 20:36:04 volumio volumio[1045]: info: Cannot use regular scanning, forcing with ap-force Jul 06 20:36:04 volumio sudo[1428]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jul 06 20:36:04 volumio sudo[1428]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 20:36:04 volumio sudo[1428]: pam_unix(sudo:session): session closed for user root Jul 06 20:36:04 volumio volumio[1045]: command failed: Network is down (-100) Jul 06 20:36:04 volumio volumio[1045]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jul 06 20:36:04 volumio volumio[1045]: command failed: Network is down (-100) Jul 06 20:36:04 volumio volumio[1045]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jul 06 20:36:04 volumio volumio-remote-updater[802]: [2024-07-06 20:36:04] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1720272964 101 Jul 06 20:36:04 volumio volumio[1045]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Jul 06 20:36:05 volumio volumio[1045]: info: Reloading queue from file Jul 06 20:36:05 volumio volumio[1045]: info: VolumeController:: Volume=100 Mute =false Jul 06 20:36:05 volumio volumio[1045]: info: CoreStateMachine::pushState Jul 06 20:36:05 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Jul 06 20:36:05 volumio volumio[1045]: info: CoreStateMachine::updateTrackBlock Jul 06 20:36:05 volumio volumio[1045]: info: CorePlayQueue::getTrackBlock Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::volumioRetrievevolume Jul 06 20:36:05 volumio volumio[1045]: info: CoreStateMachine::setRepeat null single undefined Jul 06 20:36:05 volumio volumio[1045]: info: CoreStateMachine::pushState Jul 06 20:36:05 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Jul 06 20:36:05 volumio volumio[1045]: info: CoreStateMachine::setRandom null Jul 06 20:36:05 volumio volumio[1045]: info: CoreStateMachine::pushState Jul 06 20:36:05 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Jul 06 20:36:05 volumio volumio[1045]: info: Asound.conf file unchanged, so no further update is needed Jul 06 20:36:05 volumio volumio[1045]: info: Output device has changed, restarting MPD Jul 06 20:36:05 volumio sudo[1461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 06 20:36:05 volumio sudo[1461]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 20:36:05 volumio sudo[1461]: pam_unix(sudo:session): session closed for user root Jul 06 20:36:05 volumio volumio[1045]: info: Output device has changed, restarting Shairport Sync Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 20:36:05 volumio sudo[1464]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 06 20:36:05 volumio sudo[1464]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 20:36:05 volumio systemd[1]: Listening on mpd.socket. Jul 06 20:36:05 volumio volumio[1045]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 06 20:36:05 volumio volumio[1045]: info: ___________ START PLUGINS ___________ Jul 06 20:36:05 volumio volumio[1045]: info: ControllerMpd::onStart: Initializing MPD Jul 06 20:36:05 volumio volumio[1045]: info: Creating MPD Configuration file Jul 06 20:36:05 volumio sudo[1471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 06 20:36:05 volumio sudo[1471]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 20:36:05 volumio systemd[1]: Starting Music Player Daemon... Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 06 20:36:05 volumio volumio[1045]: info: [1720272965115] CoreMusicLibrary::Adding element Máy chủ Media Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 20:36:05 volumio sudo[1471]: pam_unix(sudo:session): session closed for user root Jul 06 20:36:05 volumio sudo[1474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 06 20:36:05 volumio sudo[1474]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 20:36:05 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 06 20:36:05 volumio systemd[1]: mpd.service: Succeeded. Jul 06 20:36:05 volumio systemd[1]: Stopped Music Player Daemon. Jul 06 20:36:05 volumio systemd[1]: Starting Music Player Daemon... Jul 06 20:36:05 volumio volumio[1045]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 20:36:05 volumio volumio[1045]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 06 20:36:05 volumio volumio[1045]: info: [1720272965154] CoreMusicLibrary::Adding element Last_100 Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 06 20:36:05 volumio volumio[1045]: info: [1720272965156] CoreMusicLibrary::Adding element Webradio Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 06 20:36:05 volumio volumio[1045]: info: Initializing BBC Radios Jul 06 20:36:05 volumio sudo[1479]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 06 20:36:05 volumio sudo[1479]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 20:36:05 volumio sudo[1479]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jul 06 20:36:05 volumio sudo[1479]: pam_unix(sudo:session): session closed for user root Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 20:36:05 volumio volumio[1045]: info: Creating Spotify config file Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 06 20:36:05 volumio volumio[1045]: info: [1720272965193] CoreMusicLibrary::Adding element YouTube2 Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 06 20:36:05 volumio volumio[1045]: Cannot find translation for source YouTube2 Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 06 20:36:05 volumio volumio[1045]: info: [1720272965195] CoreMusicLibrary::Adding element YouTube Music Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 06 20:36:05 volumio volumio[1045]: Cannot find translation for source YouTube2 Jul 06 20:36:05 volumio volumio[1045]: Cannot find translation for source YouTube Music Jul 06 20:36:05 volumio volumio[1045]: info: Volumio Calling Home Jul 06 20:36:05 volumio volumio[1045]: Unhandled rejection Error: No sockets available, cannot start. Jul 06 20:36:05 volumio volumio[1045]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Jul 06 20:36:05 volumio volumio[1045]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Jul 06 20:36:05 volumio volumio[1045]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Jul 06 20:36:05 volumio volumio[1045]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Jul 06 20:36:05 volumio volumio[1045]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Jul 06 20:36:05 volumio volumio[1045]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Jul 06 20:36:05 volumio volumio[1045]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Jul 06 20:36:05 volumio volumio[1045]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Jul 06 20:36:05 volumio volumio[1045]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Jul 06 20:36:05 volumio volumio[1045]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Jul 06 20:36:05 volumio volumio[1045]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Jul 06 20:36:05 volumio volumio[1045]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jul 06 20:36:05 volumio volumio[1045]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jul 06 20:36:05 volumio volumio[1045]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Jul 06 20:36:05 volumio volumio[1045]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Jul 06 20:36:05 volumio volumio[1045]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 06 20:36:05 volumio volumio[1045]: info: MPD Permissions set Jul 06 20:36:05 volumio volumio[1045]: info: Spotify config file written Jul 06 20:36:05 volumio sudo[1500]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jul 06 20:36:05 volumio volumio[1045]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jul 06 20:36:05 volumio sudo[1500]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 20:36:05 volumio systemd[1]: Started go-librespot Daemon. Jul 06 20:36:05 volumio go-librespot[1506]: Librespot-go daemon starting... Jul 06 20:36:05 volumio sudo[1500]: pam_unix(sudo:session): session closed for user root Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 20:36:05 volumio volumio[1045]: info: VolumeController:: Volume=100 Mute =false Jul 06 20:36:05 volumio volumio[1045]: info: CoreStateMachine::pushState Jul 06 20:36:05 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Jul 06 20:36:05 volumio volumio[1045]: info: Starting Shairport Sync Jul 06 20:36:05 volumio volumio[1045]: info: Starting Shairport Sync Jul 06 20:36:05 volumio sudo[1522]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 06 20:36:05 volumio sudo[1522]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 20:36:05 volumio volumio[1045]: info: Starting Shairport Sync Jul 06 20:36:05 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 06 20:36:05 volumio sudo[1525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 06 20:36:05 volumio sudo[1525]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 20:36:05 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 06 20:36:05 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 06 20:36:05 volumio volumio[1045]: info: CoreCommandRouter::volumioGetState Jul 06 20:36:05 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Jul 06 20:36:05 volumio sudo[1528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 06 20:36:05 volumio sudo[1528]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 20:36:05 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 06 20:36:05 volumio sudo[1522]: pam_unix(sudo:session): session closed for user root Jul 06 20:36:05 volumio volumio[1045]: info: Shairport-Sync Started Jul 06 20:36:05 volumio go-librespot[1506]: time="2024-07-06T20:36:05+07:00" level=info msg="generated new device id: 6d41297322dc715ea0d32bfd5f0ea26d71a8b424" Jul 06 20:36:05 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 06 20:36:05 volumio volumio[1045]: Error adding Membership: Error: addMembership EINVAL Jul 06 20:36:05 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jul 06 20:36:05 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 06 20:36:05 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 06 20:36:05 volumio go-librespot[1506]: time="2024-07-06T20:36:05+07:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 06 20:36:05 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 06 20:36:05 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 06 20:36:05 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 06 20:36:05 volumio sudo[1525]: pam_unix(sudo:session): session closed for user root Jul 06 20:36:05 volumio sudo[1528]: pam_unix(sudo:session): session closed for user root Jul 06 20:36:05 volumio volumio[1045]: info: Shairport-Sync Started Jul 06 20:36:05 volumio volumio[1045]: info: Shairport-Sync Started Jul 06 20:36:05 volumio mpd[1487]: Jul 06 20:36 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 06 20:36:05 volumio systemd[1]: Started Music Player Daemon. Jul 06 20:36:05 volumio sudo[1464]: pam_unix(sudo:session): session closed for user root Jul 06 20:36:05 volumio sudo[1474]: pam_unix(sudo:session): session closed for user root Jul 06 20:36:05 volumio volumio[1045]: info: Completed starting Core Plugins Jul 06 20:36:05 volumio volumio[1045]: info: ------------------------------------------- Jul 06 20:36:05 volumio volumio[1045]: info: ----- MyVolumio plugins startup ---- Jul 06 20:36:05 volumio volumio[1045]: info: ------------------------------------------- Jul 06 20:36:05 volumio volumio[1045]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 06 20:36:05 volumio volumio[1045]: error: MPD error: The expression evaluated to a falsy value: Jul 06 20:36:05 volumio volumio[1045]: assert.ok(self.idling) Jul 06 20:36:05 volumio volumio[1045]: error: The expression evaluated to a falsy value: Jul 06 20:36:05 volumio volumio[1045]: assert.ok(self.idling) Jul 06 20:36:05 volumio volumio[1045]: error: updateQueue error: null Jul 06 20:36:05 volumio volumio[1045]: info: MPD running with PID1487 Jul 06 20:36:05 volumio volumio[1045]: ,establishing connection Jul 06 20:36:05 volumio volumio[1045]: error: updateQueue error: null Jul 06 20:36:05 volumio volumio[1045]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jul 06 20:36:06 volumio volumio[1045]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jul 06 20:36:06 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 06 20:36:06 volumio volumio[1045]: info: CoreCommandRouter::volumioGetVisibleSources Jul 06 20:36:06 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 06 20:36:06 volumio volumio[1045]: info: CoreCommandRouter::volumioGetState Jul 06 20:36:06 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Jul 06 20:36:06 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 06 20:36:06 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 06 20:36:06 volumio volumio[1045]: info: Received Get System Info Jul 06 20:36:06 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 06 20:36:06 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 06 20:36:06 volumio volumio[1045]: info: Discovery: Getting this device information Jul 06 20:36:06 volumio volumio[1045]: info: CoreCommandRouter::volumioGetState Jul 06 20:36:06 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Jul 06 20:36:06 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 06 20:36:06 volumio volumio[1045]: info: CoreCommandRouter::volumioGetState Jul 06 20:36:06 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Jul 06 20:36:06 volumio volumio[1045]: info: Listing playlists Jul 06 20:36:06 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 06 20:36:06 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 06 20:36:06 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jul 06 20:36:06 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 3. Jul 06 20:36:06 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:06 volumio dnsmasq[1399]: exiting on receipt of SIGTERM Jul 06 20:36:06 volumio systemd[1]: dnsmasq.service: Succeeded. Jul 06 20:36:06 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:06 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:06 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jul 06 20:36:06 volumio hostapd[1542]: Configuration file: /etc/hostapd/hostapd.conf Jul 06 20:36:06 volumio hostapd[1542]: rfkill: WLAN soft blocked Jul 06 20:36:06 volumio hostapd[1542]: wlan0: Could not connect to kernel driver Jul 06 20:36:06 volumio hostapd[1542]: Using interface wlan0 with hwaddr f8:a2:d6:ea:9e:4f and ssid "Volumio-E1AFE" Jul 06 20:36:06 volumio hostapd[1542]: Failed to set beacon parameters Jul 06 20:36:06 volumio hostapd[1542]: wlan0: Could not connect to kernel driver Jul 06 20:36:06 volumio hostapd[1542]: Interface initialization failed Jul 06 20:36:06 volumio hostapd[1542]: wlan0: interface state UNINITIALIZED->DISABLED Jul 06 20:36:06 volumio hostapd[1542]: wlan0: AP-DISABLED Jul 06 20:36:06 volumio hostapd[1542]: wlan0: Unable to setup interface. Jul 06 20:36:06 volumio hostapd[1542]: wlan0: interface state DISABLED->DISABLED Jul 06 20:36:06 volumio hostapd[1542]: wlan0: AP-DISABLED Jul 06 20:36:06 volumio hostapd[1542]: wlan0: CTRL-EVENT-TERMINATING Jul 06 20:36:06 volumio hostapd[1542]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jul 06 20:36:06 volumio hostapd[1542]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jul 06 20:36:06 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jul 06 20:36:06 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jul 06 20:36:06 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:06 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:06 volumio dnsmasq[1543]: dnsmasq: syntax check OK. Jul 06 20:36:06 volumio dnsmasq[1549]: started, version 2.80 cachesize 150 Jul 06 20:36:06 volumio dnsmasq[1549]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jul 06 20:36:06 volumio dnsmasq-dhcp[1549]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jul 06 20:36:06 volumio dnsmasq[1549]: reading /etc/resolv.conf Jul 06 20:36:06 volumio dnsmasq[1549]: using nameserver 208.67.222.222#53 Jul 06 20:36:06 volumio dnsmasq[1549]: using nameserver 208.67.220.220#53 Jul 06 20:36:06 volumio dnsmasq[1549]: read /etc/hosts - 5 addresses Jul 06 20:36:06 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:07 volumio volumio[1045]: info: CoreCommandRouter::volumioGetState Jul 06 20:36:07 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Jul 06 20:36:08 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 06 20:36:08 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 06 20:36:08 volumio volumio[1045]: info: Received Get System Info Jul 06 20:36:08 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 06 20:36:08 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 06 20:36:08 volumio volumio[1045]: info: Discovery: Getting this device information Jul 06 20:36:08 volumio volumio[1045]: info: CoreCommandRouter::volumioGetState Jul 06 20:36:08 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Jul 06 20:36:08 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 06 20:36:08 volumio volumio[1045]: info: go-librespot daemon successfully initialized Jul 06 20:36:08 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 06 20:36:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Jul 06 20:36:08 volumio systemd[1]: Stopped go-librespot Daemon. Jul 06 20:36:08 volumio systemd[1]: Started go-librespot Daemon. Jul 06 20:36:08 volumio go-librespot[1555]: Librespot-go daemon starting... Jul 06 20:36:08 volumio go-librespot[1555]: time="2024-07-06T20:36:08+07:00" level=info msg="generated new device id: 879c0bc276d113166981285fdca55cd2482f2615" Jul 06 20:36:08 volumio go-librespot[1555]: time="2024-07-06T20:36:08+07:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 06 20:36:08 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 06 20:36:08 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 06 20:36:09 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jul 06 20:36:09 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 4. Jul 06 20:36:09 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:09 volumio dnsmasq[1549]: exiting on receipt of SIGTERM Jul 06 20:36:09 volumio systemd[1]: dnsmasq.service: Succeeded. Jul 06 20:36:09 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:09 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:09 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jul 06 20:36:09 volumio hostapd[1743]: Configuration file: /etc/hostapd/hostapd.conf Jul 06 20:36:09 volumio hostapd[1743]: rfkill: WLAN soft blocked Jul 06 20:36:09 volumio hostapd[1743]: wlan0: Could not connect to kernel driver Jul 06 20:36:09 volumio hostapd[1743]: Using interface wlan0 with hwaddr f8:a2:d6:ea:9e:4f and ssid "Volumio-E1AFE" Jul 06 20:36:09 volumio hostapd[1743]: Failed to set beacon parameters Jul 06 20:36:09 volumio hostapd[1743]: wlan0: Could not connect to kernel driver Jul 06 20:36:09 volumio hostapd[1743]: Interface initialization failed Jul 06 20:36:09 volumio hostapd[1743]: wlan0: interface state UNINITIALIZED->DISABLED Jul 06 20:36:09 volumio hostapd[1743]: wlan0: AP-DISABLED Jul 06 20:36:09 volumio hostapd[1743]: wlan0: Unable to setup interface. Jul 06 20:36:09 volumio hostapd[1743]: wlan0: interface state DISABLED->DISABLED Jul 06 20:36:09 volumio hostapd[1743]: wlan0: AP-DISABLED Jul 06 20:36:09 volumio hostapd[1743]: wlan0: CTRL-EVENT-TERMINATING Jul 06 20:36:09 volumio hostapd[1743]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jul 06 20:36:09 volumio hostapd[1743]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jul 06 20:36:09 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jul 06 20:36:09 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jul 06 20:36:09 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:09 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:09 volumio dnsmasq[1744]: dnsmasq: syntax check OK. Jul 06 20:36:09 volumio dnsmasq[1751]: started, version 2.80 cachesize 150 Jul 06 20:36:09 volumio dnsmasq[1751]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jul 06 20:36:09 volumio dnsmasq-dhcp[1751]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jul 06 20:36:09 volumio dnsmasq[1751]: reading /etc/resolv.conf Jul 06 20:36:09 volumio dnsmasq[1751]: using nameserver 208.67.222.222#53 Jul 06 20:36:09 volumio dnsmasq[1751]: using nameserver 208.67.220.220#53 Jul 06 20:36:09 volumio dnsmasq[1751]: read /etc/hosts - 5 addresses Jul 06 20:36:09 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:10 volumio volumio[1045]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jul 06 20:36:11 volumio volumio[1045]: info: Initializing connection to go-librespot Websocket Jul 06 20:36:11 volumio volumio[1045]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 06 20:36:11 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jul 06 20:36:11 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 5. Jul 06 20:36:11 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:11 volumio dnsmasq[1751]: exiting on receipt of SIGTERM Jul 06 20:36:11 volumio systemd[1]: dnsmasq.service: Succeeded. Jul 06 20:36:11 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:11 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:11 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jul 06 20:36:11 volumio hostapd[1761]: Configuration file: /etc/hostapd/hostapd.conf Jul 06 20:36:11 volumio hostapd[1761]: rfkill: WLAN soft blocked Jul 06 20:36:11 volumio hostapd[1761]: wlan0: Could not connect to kernel driver Jul 06 20:36:11 volumio hostapd[1761]: Using interface wlan0 with hwaddr f8:a2:d6:ea:9e:4f and ssid "Volumio-E1AFE" Jul 06 20:36:11 volumio hostapd[1761]: Failed to set beacon parameters Jul 06 20:36:11 volumio hostapd[1761]: wlan0: Could not connect to kernel driver Jul 06 20:36:11 volumio hostapd[1761]: Interface initialization failed Jul 06 20:36:11 volumio hostapd[1761]: wlan0: interface state UNINITIALIZED->DISABLED Jul 06 20:36:11 volumio hostapd[1761]: wlan0: AP-DISABLED Jul 06 20:36:11 volumio hostapd[1761]: wlan0: Unable to setup interface. Jul 06 20:36:11 volumio hostapd[1761]: wlan0: interface state DISABLED->DISABLED Jul 06 20:36:11 volumio hostapd[1761]: wlan0: AP-DISABLED Jul 06 20:36:11 volumio hostapd[1761]: wlan0: CTRL-EVENT-TERMINATING Jul 06 20:36:11 volumio hostapd[1761]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jul 06 20:36:11 volumio hostapd[1761]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jul 06 20:36:11 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jul 06 20:36:11 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jul 06 20:36:11 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:11 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:11 volumio dnsmasq[1762]: dnsmasq: syntax check OK. Jul 06 20:36:11 volumio dnsmasq[1768]: started, version 2.80 cachesize 150 Jul 06 20:36:11 volumio dnsmasq[1768]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jul 06 20:36:11 volumio dnsmasq-dhcp[1768]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jul 06 20:36:11 volumio dnsmasq[1768]: reading /etc/resolv.conf Jul 06 20:36:11 volumio dnsmasq[1768]: using nameserver 208.67.222.222#53 Jul 06 20:36:11 volumio dnsmasq[1768]: using nameserver 208.67.220.220#53 Jul 06 20:36:11 volumio dnsmasq[1768]: read /etc/hosts - 5 addresses Jul 06 20:36:11 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:11 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 06 20:36:11 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Jul 06 20:36:11 volumio systemd[1]: Stopped go-librespot Daemon. Jul 06 20:36:12 volumio systemd[1]: Started go-librespot Daemon. Jul 06 20:36:12 volumio go-librespot[1774]: Librespot-go daemon starting... Jul 06 20:36:12 volumio go-librespot[1774]: time="2024-07-06T20:36:12+07:00" level=info msg="generated new device id: 9b7c20a877192280d23ec99913fd58c8642df0eb" Jul 06 20:36:12 volumio go-librespot[1774]: time="2024-07-06T20:36:12+07:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 06 20:36:12 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 06 20:36:12 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 06 20:36:12 volumio dhcpcd[890]: eth0: carrier acquired Jul 06 20:36:12 volumio sh[709]: eth0: carrier acquired Jul 06 20:36:12 volumio dhcpcd[752]: eth0: carrier acquired Jul 06 20:36:12 volumio kernel: r8169 0000:01:00.0 eth0: Link is Up - 1Gbps/Full - flow control rx/tx Jul 06 20:36:12 volumio sh[709]: eth0: IAID 64:af:4f:3d Jul 06 20:36:12 volumio dhcpcd[890]: eth0: IAID 64:af:4f:3d Jul 06 20:36:12 volumio dhcpcd[752]: eth0: IAID 64:af:4f:3d Jul 06 20:36:12 volumio ifplugd(eth0)[995]: Link beat detected. Jul 06 20:36:12 volumio dhcpcd[890]: eth0: soliciting a DHCP lease Jul 06 20:36:12 volumio sh[709]: eth0: soliciting a DHCP lease Jul 06 20:36:12 volumio dhcpcd[752]: eth0: soliciting a DHCP lease Jul 06 20:36:12 volumio ifplugd(eth0)[995]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jul 06 20:36:12 volumio ifplugd(eth0)[995]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Jul 06 20:36:12 volumio sudo[1789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 06 20:36:12 volumio sudo[1789]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 20:36:12 volumio sudo[1789]: pam_unix(sudo:session): session closed for user root Jul 06 20:36:12 volumio sudo[1792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 06 20:36:12 volumio sudo[1792]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 20:36:12 volumio sudo[1792]: pam_unix(sudo:session): session closed for user root Jul 06 20:36:12 volumio sudo[1795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 06 20:36:12 volumio sudo[1795]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 06 20:36:12 volumio sh[709]: eth0: soliciting an IPv6 router Jul 06 20:36:12 volumio dhcpcd[752]: eth0: soliciting an IPv6 router Jul 06 20:36:12 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jul 06 20:36:12 volumio sudo[1795]: pam_unix(sudo:session): session closed for user root Jul 06 20:36:12 volumio volumio[1045]: info: Upmpdcli Daemon Started Jul 06 20:36:13 volumio dhcpcd[890]: eth0: soliciting an IPv6 router Jul 06 20:36:13 volumio sh[709]: eth0: carrier lost Jul 06 20:36:13 volumio dhcpcd[752]: eth0: carrier lost Jul 06 20:36:13 volumio dhcpcd[890]: eth0: carrier lost Jul 06 20:36:13 volumio kernel: r8169 0000:01:00.0 eth0: Link is Down Jul 06 20:36:13 volumio dnsmasq[1768]: reading /etc/resolv.conf Jul 06 20:36:13 volumio dnsmasq[1768]: using nameserver 208.67.222.222#53 Jul 06 20:36:13 volumio dnsmasq[1768]: using nameserver 208.67.220.220#53 Jul 06 20:36:13 volumio dnsmasq[1768]: using nameserver 208.67.222.222#53 Jul 06 20:36:13 volumio dnsmasq[1768]: using nameserver 208.67.220.220#53 Jul 06 20:36:13 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jul 06 20:36:13 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 6. Jul 06 20:36:13 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:13 volumio dnsmasq[1768]: exiting on receipt of SIGTERM Jul 06 20:36:13 volumio systemd[1]: dnsmasq.service: Succeeded. Jul 06 20:36:13 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:13 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:13 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jul 06 20:36:13 volumio hostapd[1847]: Configuration file: /etc/hostapd/hostapd.conf Jul 06 20:36:13 volumio hostapd[1847]: rfkill: WLAN soft blocked Jul 06 20:36:13 volumio hostapd[1847]: wlan0: Could not connect to kernel driver Jul 06 20:36:13 volumio hostapd[1847]: Using interface wlan0 with hwaddr f8:a2:d6:ea:9e:4f and ssid "Volumio-E1AFE" Jul 06 20:36:13 volumio hostapd[1847]: Failed to set beacon parameters Jul 06 20:36:13 volumio hostapd[1847]: wlan0: Could not connect to kernel driver Jul 06 20:36:13 volumio hostapd[1847]: Interface initialization failed Jul 06 20:36:13 volumio hostapd[1847]: wlan0: interface state UNINITIALIZED->DISABLED Jul 06 20:36:13 volumio hostapd[1847]: wlan0: AP-DISABLED Jul 06 20:36:13 volumio hostapd[1847]: wlan0: Unable to setup interface. Jul 06 20:36:13 volumio hostapd[1847]: wlan0: interface state DISABLED->DISABLED Jul 06 20:36:13 volumio hostapd[1847]: wlan0: AP-DISABLED Jul 06 20:36:13 volumio hostapd[1847]: wlan0: CTRL-EVENT-TERMINATING Jul 06 20:36:13 volumio hostapd[1847]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jul 06 20:36:13 volumio hostapd[1847]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jul 06 20:36:13 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jul 06 20:36:13 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jul 06 20:36:13 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:13 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:13 volumio dnsmasq[1848]: dnsmasq: syntax check OK. Jul 06 20:36:13 volumio dnsmasq[1854]: started, version 2.80 cachesize 150 Jul 06 20:36:13 volumio dnsmasq[1854]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jul 06 20:36:13 volumio dnsmasq-dhcp[1854]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jul 06 20:36:13 volumio dnsmasq[1854]: reading /etc/resolv.conf Jul 06 20:36:13 volumio dnsmasq[1854]: using nameserver 208.67.222.222#53 Jul 06 20:36:13 volumio dnsmasq[1854]: using nameserver 208.67.220.220#53 Jul 06 20:36:13 volumio dnsmasq[1854]: using nameserver 208.67.222.222#53 Jul 06 20:36:13 volumio dnsmasq[1854]: using nameserver 208.67.220.220#53 Jul 06 20:36:13 volumio dnsmasq[1854]: read /etc/hosts - 5 addresses Jul 06 20:36:13 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:14 volumio volumio[1045]: info: Initializing connection to go-librespot Websocket Jul 06 20:36:14 volumio volumio[1045]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 06 20:36:15 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 06 20:36:15 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Jul 06 20:36:15 volumio systemd[1]: Stopped go-librespot Daemon. Jul 06 20:36:15 volumio volumio[1045]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.703&uuid=e1afea2e9bb1988b7de3723581b38a8d" http://updates.volumio.org/downloader-v1/track-device Jul 06 20:36:15 volumio volumio[1045]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 06 20:36:15 volumio volumio[1045]: Dload Upload Total Spent Left Speed Jul 06 20:36:15 volumio volumio[1045]: [132B blob data] Jul 06 20:36:15 volumio volumio[1045]: retrying in 5 seconds, trial 0 Jul 06 20:36:15 volumio volumio[1045]: info: Volumio Calling Home Jul 06 20:36:15 volumio systemd[1]: Started go-librespot Daemon. Jul 06 20:36:15 volumio go-librespot[1860]: Librespot-go daemon starting... Jul 06 20:36:15 volumio go-librespot[1860]: time="2024-07-06T20:36:15+07:00" level=info msg="generated new device id: 0eb914ebbc55b6e7dea99a8a5a64d309685cbf6f" Jul 06 20:36:15 volumio go-librespot[1860]: time="2024-07-06T20:36:15+07:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 06 20:36:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 06 20:36:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 06 20:36:16 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jul 06 20:36:16 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 7. Jul 06 20:36:16 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:16 volumio dnsmasq[1854]: exiting on receipt of SIGTERM Jul 06 20:36:16 volumio systemd[1]: dnsmasq.service: Succeeded. Jul 06 20:36:16 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:16 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:16 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jul 06 20:36:16 volumio hostapd[1874]: Configuration file: /etc/hostapd/hostapd.conf Jul 06 20:36:16 volumio hostapd[1874]: rfkill: WLAN soft blocked Jul 06 20:36:16 volumio hostapd[1874]: wlan0: Could not connect to kernel driver Jul 06 20:36:16 volumio hostapd[1874]: Using interface wlan0 with hwaddr f8:a2:d6:ea:9e:4f and ssid "Volumio-E1AFE" Jul 06 20:36:16 volumio hostapd[1874]: Failed to set beacon parameters Jul 06 20:36:16 volumio hostapd[1874]: wlan0: Could not connect to kernel driver Jul 06 20:36:16 volumio hostapd[1874]: Interface initialization failed Jul 06 20:36:16 volumio hostapd[1874]: wlan0: interface state UNINITIALIZED->DISABLED Jul 06 20:36:16 volumio hostapd[1874]: wlan0: AP-DISABLED Jul 06 20:36:16 volumio hostapd[1874]: wlan0: Unable to setup interface. Jul 06 20:36:16 volumio hostapd[1874]: wlan0: interface state DISABLED->DISABLED Jul 06 20:36:16 volumio hostapd[1874]: wlan0: AP-DISABLED Jul 06 20:36:16 volumio hostapd[1874]: wlan0: CTRL-EVENT-TERMINATING Jul 06 20:36:16 volumio hostapd[1874]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jul 06 20:36:16 volumio hostapd[1874]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jul 06 20:36:16 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jul 06 20:36:16 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jul 06 20:36:16 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:16 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 06 20:36:16 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:16 volumio dnsmasq[1875]: dnsmasq: syntax check OK. Jul 06 20:36:16 volumio dnsmasq[1881]: started, version 2.80 cachesize 150 Jul 06 20:36:16 volumio dnsmasq[1881]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jul 06 20:36:16 volumio dnsmasq-dhcp[1881]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jul 06 20:36:16 volumio dnsmasq[1881]: reading /etc/resolv.conf Jul 06 20:36:16 volumio dnsmasq[1881]: using nameserver 208.67.222.222#53 Jul 06 20:36:16 volumio dnsmasq[1881]: using nameserver 208.67.220.220#53 Jul 06 20:36:16 volumio dnsmasq[1881]: using nameserver 208.67.222.222#53 Jul 06 20:36:16 volumio dnsmasq[1881]: using nameserver 208.67.220.220#53 Jul 06 20:36:16 volumio dnsmasq[1881]: read /etc/hosts - 5 addresses Jul 06 20:36:16 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:17 volumio volumio[1045]: info: Initializing connection to go-librespot Websocket Jul 06 20:36:17 volumio volumio[1045]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 06 20:36:18 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jul 06 20:36:18 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 8. Jul 06 20:36:18 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 06 20:36:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Jul 06 20:36:18 volumio systemd[1]: Stopped go-librespot Daemon. Jul 06 20:36:18 volumio systemd[1]: Started go-librespot Daemon. Jul 06 20:36:18 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:18 volumio go-librespot[1887]: Librespot-go daemon starting... Jul 06 20:36:18 volumio go-librespot[1887]: time="2024-07-06T20:36:18+07:00" level=info msg="generated new device id: d90a7eceabffd96d165272a2ad4fdcb038083296" Jul 06 20:36:18 volumio go-librespot[1887]: time="2024-07-06T20:36:18+07:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 06 20:36:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 06 20:36:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 06 20:36:18 volumio dnsmasq[1881]: exiting on receipt of SIGTERM Jul 06 20:36:18 volumio systemd[1]: dnsmasq.service: Succeeded. Jul 06 20:36:18 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:18 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:18 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jul 06 20:36:18 volumio hostapd[1898]: Configuration file: /etc/hostapd/hostapd.conf Jul 06 20:36:18 volumio hostapd[1898]: rfkill: WLAN soft blocked Jul 06 20:36:18 volumio hostapd[1898]: wlan0: Could not connect to kernel driver Jul 06 20:36:18 volumio hostapd[1898]: Using interface wlan0 with hwaddr f8:a2:d6:ea:9e:4f and ssid "Volumio-E1AFE" Jul 06 20:36:18 volumio hostapd[1898]: Failed to set beacon parameters Jul 06 20:36:18 volumio hostapd[1898]: wlan0: Could not connect to kernel driver Jul 06 20:36:18 volumio hostapd[1898]: Interface initialization failed Jul 06 20:36:18 volumio hostapd[1898]: wlan0: interface state UNINITIALIZED->DISABLED Jul 06 20:36:18 volumio hostapd[1898]: wlan0: AP-DISABLED Jul 06 20:36:18 volumio hostapd[1898]: wlan0: Unable to setup interface. Jul 06 20:36:18 volumio hostapd[1898]: wlan0: interface state DISABLED->DISABLED Jul 06 20:36:18 volumio hostapd[1898]: wlan0: AP-DISABLED Jul 06 20:36:18 volumio hostapd[1898]: wlan0: CTRL-EVENT-TERMINATING Jul 06 20:36:18 volumio hostapd[1898]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jul 06 20:36:18 volumio hostapd[1898]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jul 06 20:36:18 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jul 06 20:36:18 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jul 06 20:36:18 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:18 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:18 volumio dnsmasq[1899]: dnsmasq: syntax check OK. Jul 06 20:36:18 volumio dnsmasq[1905]: started, version 2.80 cachesize 150 Jul 06 20:36:18 volumio dnsmasq[1905]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jul 06 20:36:18 volumio dnsmasq-dhcp[1905]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jul 06 20:36:18 volumio dnsmasq[1905]: reading /etc/resolv.conf Jul 06 20:36:18 volumio dnsmasq[1905]: using nameserver 208.67.222.222#53 Jul 06 20:36:18 volumio dnsmasq[1905]: using nameserver 208.67.220.220#53 Jul 06 20:36:18 volumio dnsmasq[1905]: using nameserver 208.67.222.222#53 Jul 06 20:36:18 volumio dnsmasq[1905]: using nameserver 208.67.220.220#53 Jul 06 20:36:18 volumio dnsmasq[1905]: read /etc/hosts - 5 addresses Jul 06 20:36:18 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:20 volumio volumio[1045]: info: Initializing connection to go-librespot Websocket Jul 06 20:36:20 volumio volumio[1045]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 06 20:36:20 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jul 06 20:36:20 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 9. Jul 06 20:36:20 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:20 volumio dnsmasq[1905]: exiting on receipt of SIGTERM Jul 06 20:36:20 volumio systemd[1]: dnsmasq.service: Succeeded. Jul 06 20:36:20 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:20 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:20 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jul 06 20:36:20 volumio hostapd[1915]: Configuration file: /etc/hostapd/hostapd.conf Jul 06 20:36:20 volumio hostapd[1915]: rfkill: WLAN soft blocked Jul 06 20:36:20 volumio hostapd[1915]: wlan0: Could not connect to kernel driver Jul 06 20:36:20 volumio hostapd[1915]: Using interface wlan0 with hwaddr f8:a2:d6:ea:9e:4f and ssid "Volumio-E1AFE" Jul 06 20:36:20 volumio hostapd[1915]: Failed to set beacon parameters Jul 06 20:36:20 volumio hostapd[1915]: wlan0: Could not connect to kernel driver Jul 06 20:36:20 volumio hostapd[1915]: Interface initialization failed Jul 06 20:36:20 volumio hostapd[1915]: wlan0: interface state UNINITIALIZED->DISABLED Jul 06 20:36:20 volumio hostapd[1915]: wlan0: AP-DISABLED Jul 06 20:36:20 volumio hostapd[1915]: wlan0: Unable to setup interface. Jul 06 20:36:20 volumio hostapd[1915]: wlan0: interface state DISABLED->DISABLED Jul 06 20:36:20 volumio hostapd[1915]: wlan0: AP-DISABLED Jul 06 20:36:20 volumio hostapd[1915]: wlan0: CTRL-EVENT-TERMINATING Jul 06 20:36:20 volumio hostapd[1915]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jul 06 20:36:20 volumio hostapd[1915]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jul 06 20:36:20 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jul 06 20:36:20 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jul 06 20:36:20 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:20 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:20 volumio dnsmasq[1916]: dnsmasq: syntax check OK. Jul 06 20:36:20 volumio dnsmasq[1922]: started, version 2.80 cachesize 150 Jul 06 20:36:20 volumio dnsmasq[1922]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jul 06 20:36:20 volumio dnsmasq-dhcp[1922]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jul 06 20:36:20 volumio dnsmasq[1922]: reading /etc/resolv.conf Jul 06 20:36:20 volumio dnsmasq[1922]: using nameserver 208.67.222.222#53 Jul 06 20:36:20 volumio dnsmasq[1922]: using nameserver 208.67.220.220#53 Jul 06 20:36:20 volumio dnsmasq[1922]: using nameserver 208.67.222.222#53 Jul 06 20:36:20 volumio dnsmasq[1922]: using nameserver 208.67.220.220#53 Jul 06 20:36:20 volumio dnsmasq[1922]: read /etc/hosts - 5 addresses Jul 06 20:36:20 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:21 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 06 20:36:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Jul 06 20:36:21 volumio systemd[1]: Stopped go-librespot Daemon. Jul 06 20:36:21 volumio systemd[1]: Started go-librespot Daemon. Jul 06 20:36:21 volumio go-librespot[1928]: Librespot-go daemon starting... Jul 06 20:36:21 volumio go-librespot[1928]: time="2024-07-06T20:36:21+07:00" level=info msg="generated new device id: d448d52a6add302b9bfa8deb77d2d0be6287f9d1" Jul 06 20:36:21 volumio go-librespot[1928]: time="2024-07-06T20:36:21+07:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 06 20:36:21 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 06 20:36:21 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 06 20:36:22 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jul 06 20:36:22 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 10. Jul 06 20:36:22 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:22 volumio dnsmasq[1922]: exiting on receipt of SIGTERM Jul 06 20:36:22 volumio systemd[1]: dnsmasq.service: Succeeded. Jul 06 20:36:22 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:22 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:22 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jul 06 20:36:22 volumio hostapd[1939]: Configuration file: /etc/hostapd/hostapd.conf Jul 06 20:36:22 volumio hostapd[1939]: rfkill: WLAN soft blocked Jul 06 20:36:22 volumio hostapd[1939]: wlan0: Could not connect to kernel driver Jul 06 20:36:22 volumio hostapd[1939]: Using interface wlan0 with hwaddr f8:a2:d6:ea:9e:4f and ssid "Volumio-E1AFE" Jul 06 20:36:22 volumio hostapd[1939]: Failed to set beacon parameters Jul 06 20:36:22 volumio hostapd[1939]: wlan0: Could not connect to kernel driver Jul 06 20:36:22 volumio hostapd[1939]: Interface initialization failed Jul 06 20:36:22 volumio hostapd[1939]: wlan0: interface state UNINITIALIZED->DISABLED Jul 06 20:36:22 volumio hostapd[1939]: wlan0: AP-DISABLED Jul 06 20:36:22 volumio hostapd[1939]: wlan0: Unable to setup interface. Jul 06 20:36:22 volumio hostapd[1939]: wlan0: interface state DISABLED->DISABLED Jul 06 20:36:22 volumio hostapd[1939]: wlan0: AP-DISABLED Jul 06 20:36:22 volumio hostapd[1939]: wlan0: CTRL-EVENT-TERMINATING Jul 06 20:36:22 volumio hostapd[1939]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jul 06 20:36:22 volumio hostapd[1939]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jul 06 20:36:22 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jul 06 20:36:22 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jul 06 20:36:22 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:22 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:22 volumio dnsmasq[1940]: dnsmasq: syntax check OK. Jul 06 20:36:22 volumio dnsmasq[1946]: started, version 2.80 cachesize 150 Jul 06 20:36:22 volumio dnsmasq[1946]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jul 06 20:36:22 volumio dnsmasq-dhcp[1946]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jul 06 20:36:22 volumio dnsmasq[1946]: reading /etc/resolv.conf Jul 06 20:36:22 volumio dnsmasq[1946]: using nameserver 208.67.222.222#53 Jul 06 20:36:22 volumio dnsmasq[1946]: using nameserver 208.67.220.220#53 Jul 06 20:36:22 volumio dnsmasq[1946]: using nameserver 208.67.222.222#53 Jul 06 20:36:22 volumio dnsmasq[1946]: using nameserver 208.67.220.220#53 Jul 06 20:36:22 volumio dnsmasq[1946]: read /etc/hosts - 5 addresses Jul 06 20:36:22 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:23 volumio volumio[1045]: info: Initializing connection to go-librespot Websocket Jul 06 20:36:23 volumio volumio[1045]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 06 20:36:24 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 06 20:36:24 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Jul 06 20:36:24 volumio systemd[1]: Stopped go-librespot Daemon. Jul 06 20:36:24 volumio systemd[1]: Started go-librespot Daemon. Jul 06 20:36:24 volumio go-librespot[1952]: Librespot-go daemon starting... Jul 06 20:36:24 volumio go-librespot[1952]: time="2024-07-06T20:36:24+07:00" level=info msg="generated new device id: 172cea8463f27e3a770a5e7f0176417b940ce520" Jul 06 20:36:24 volumio go-librespot[1952]: time="2024-07-06T20:36:24+07:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 06 20:36:24 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 06 20:36:24 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 06 20:36:25 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jul 06 20:36:25 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 11. Jul 06 20:36:25 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:25 volumio dnsmasq[1946]: exiting on receipt of SIGTERM Jul 06 20:36:25 volumio systemd[1]: dnsmasq.service: Succeeded. Jul 06 20:36:25 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:25 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:25 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jul 06 20:36:25 volumio hostapd[1963]: Configuration file: /etc/hostapd/hostapd.conf Jul 06 20:36:25 volumio hostapd[1963]: rfkill: WLAN soft blocked Jul 06 20:36:25 volumio hostapd[1963]: wlan0: Could not connect to kernel driver Jul 06 20:36:25 volumio hostapd[1963]: Using interface wlan0 with hwaddr f8:a2:d6:ea:9e:4f and ssid "Volumio-E1AFE" Jul 06 20:36:25 volumio hostapd[1963]: Failed to set beacon parameters Jul 06 20:36:25 volumio hostapd[1963]: wlan0: Could not connect to kernel driver Jul 06 20:36:25 volumio hostapd[1963]: Interface initialization failed Jul 06 20:36:25 volumio hostapd[1963]: wlan0: interface state UNINITIALIZED->DISABLED Jul 06 20:36:25 volumio hostapd[1963]: wlan0: AP-DISABLED Jul 06 20:36:25 volumio hostapd[1963]: wlan0: Unable to setup interface. Jul 06 20:36:25 volumio hostapd[1963]: wlan0: interface state DISABLED->DISABLED Jul 06 20:36:25 volumio hostapd[1963]: wlan0: AP-DISABLED Jul 06 20:36:25 volumio hostapd[1963]: wlan0: CTRL-EVENT-TERMINATING Jul 06 20:36:25 volumio hostapd[1963]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jul 06 20:36:25 volumio hostapd[1963]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jul 06 20:36:25 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jul 06 20:36:25 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jul 06 20:36:25 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:25 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:25 volumio dnsmasq[1964]: dnsmasq: syntax check OK. Jul 06 20:36:25 volumio dnsmasq[1970]: started, version 2.80 cachesize 150 Jul 06 20:36:25 volumio dnsmasq[1970]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jul 06 20:36:25 volumio dnsmasq-dhcp[1970]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jul 06 20:36:25 volumio dnsmasq[1970]: reading /etc/resolv.conf Jul 06 20:36:25 volumio dnsmasq[1970]: using nameserver 208.67.222.222#53 Jul 06 20:36:25 volumio dnsmasq[1970]: using nameserver 208.67.220.220#53 Jul 06 20:36:25 volumio dnsmasq[1970]: using nameserver 208.67.222.222#53 Jul 06 20:36:25 volumio dnsmasq[1970]: using nameserver 208.67.220.220#53 Jul 06 20:36:25 volumio dnsmasq[1970]: read /etc/hosts - 5 addresses Jul 06 20:36:25 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:25 volumio volumio[1045]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.703&uuid=e1afea2e9bb1988b7de3723581b38a8d" http://updates.volumio.org/downloader-v1/track-device Jul 06 20:36:25 volumio volumio[1045]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 06 20:36:25 volumio volumio[1045]: Dload Upload Total Spent Left Speed Jul 06 20:36:25 volumio volumio[1045]: [132B blob data] Jul 06 20:36:25 volumio volumio[1045]: retrying in 5 seconds, trial 1 Jul 06 20:36:25 volumio volumio[1045]: info: Volumio Calling Home Jul 06 20:36:25 volumio volumio[1045]: error: MyVolumio Plugin failed to start in a timely fashion Jul 06 20:36:25 volumio volumio[1045]: info: BOOT COMPLETED Jul 06 20:36:25 volumio volumio[1045]: [Metrics] CommandRouter: 24s 822.22ms Jul 06 20:36:25 volumio volumio[1045]: info: CoreCommandRouter::volumiosetStartupVolume Jul 06 20:36:25 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 06 20:36:25 volumio volumio[1045]: info: VolumeController:: Setting startup Volume 5 Jul 06 20:36:25 volumio volumio[1045]: info: VolumeController::SetAlsaVolume5 Jul 06 20:36:25 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 20:36:25 volumio volumio[1045]: info: CoreCommandRouter::Close All Modals sent Jul 06 20:36:25 volumio volumio[1045]: info: CoreCommandRouter::Close All Modals sent Jul 06 20:36:25 volumio volumio[1045]: info: CoreStateMachine::pushState Jul 06 20:36:25 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Jul 06 20:36:25 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 06 20:36:25 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Jul 06 20:36:26 volumio volumio[1045]: info: Initializing connection to go-librespot Websocket Jul 06 20:36:26 volumio volumio[1045]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 06 20:36:26 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 06 20:36:26 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 06 20:36:26 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 06 20:36:27 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jul 06 20:36:27 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 12. Jul 06 20:36:27 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:27 volumio dnsmasq[1970]: exiting on receipt of SIGTERM Jul 06 20:36:27 volumio systemd[1]: dnsmasq.service: Succeeded. Jul 06 20:36:27 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:27 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:27 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jul 06 20:36:27 volumio hostapd[1988]: Configuration file: /etc/hostapd/hostapd.conf Jul 06 20:36:27 volumio hostapd[1988]: rfkill: WLAN soft blocked Jul 06 20:36:27 volumio hostapd[1988]: wlan0: Could not connect to kernel driver Jul 06 20:36:27 volumio hostapd[1988]: Using interface wlan0 with hwaddr f8:a2:d6:ea:9e:4f and ssid "Volumio-E1AFE" Jul 06 20:36:27 volumio hostapd[1988]: Failed to set beacon parameters Jul 06 20:36:27 volumio hostapd[1988]: wlan0: Could not connect to kernel driver Jul 06 20:36:27 volumio hostapd[1988]: Interface initialization failed Jul 06 20:36:27 volumio hostapd[1988]: wlan0: interface state UNINITIALIZED->DISABLED Jul 06 20:36:27 volumio hostapd[1988]: wlan0: AP-DISABLED Jul 06 20:36:27 volumio hostapd[1988]: wlan0: Unable to setup interface. Jul 06 20:36:27 volumio hostapd[1988]: wlan0: interface state DISABLED->DISABLED Jul 06 20:36:27 volumio hostapd[1988]: wlan0: AP-DISABLED Jul 06 20:36:27 volumio hostapd[1988]: wlan0: CTRL-EVENT-TERMINATING Jul 06 20:36:27 volumio hostapd[1988]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jul 06 20:36:27 volumio hostapd[1988]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jul 06 20:36:27 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jul 06 20:36:27 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jul 06 20:36:27 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:27 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:27 volumio dnsmasq[1989]: dnsmasq: syntax check OK. Jul 06 20:36:27 volumio dnsmasq[1995]: started, version 2.80 cachesize 150 Jul 06 20:36:27 volumio dnsmasq[1995]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jul 06 20:36:27 volumio dnsmasq-dhcp[1995]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jul 06 20:36:27 volumio dnsmasq[1995]: reading /etc/resolv.conf Jul 06 20:36:27 volumio dnsmasq[1995]: using nameserver 208.67.222.222#53 Jul 06 20:36:27 volumio dnsmasq[1995]: using nameserver 208.67.220.220#53 Jul 06 20:36:27 volumio dnsmasq[1995]: using nameserver 208.67.222.222#53 Jul 06 20:36:27 volumio dnsmasq[1995]: using nameserver 208.67.220.220#53 Jul 06 20:36:27 volumio dnsmasq[1995]: read /etc/hosts - 5 addresses Jul 06 20:36:27 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:28 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 06 20:36:28 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Jul 06 20:36:28 volumio systemd[1]: Stopped go-librespot Daemon. Jul 06 20:36:28 volumio systemd[1]: Started go-librespot Daemon. Jul 06 20:36:28 volumio go-librespot[2001]: Librespot-go daemon starting... Jul 06 20:36:28 volumio go-librespot[2001]: time="2024-07-06T20:36:28+07:00" level=info msg="generated new device id: a6ff93ce8c50d2f4573e5ff8f4b715b67fa0e409" Jul 06 20:36:28 volumio go-librespot[2001]: time="2024-07-06T20:36:28+07:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 06 20:36:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 06 20:36:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 06 20:36:28 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jul 06 20:36:28 volumio sh[709]: timed out Jul 06 20:36:28 volumio dhcpcd[752]: timed out Jul 06 20:36:29 volumio sh[709]: dhcpcd exited Jul 06 20:36:29 volumio dhcpcd[752]: dhcpcd exited Jul 06 20:36:29 volumio sh[709]: ifup: failed to bring up eth0 Jul 06 20:36:29 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jul 06 20:36:29 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jul 06 20:36:29 volumio dhcpcd[2022]: sending commands to master dhcpcd process Jul 06 20:36:29 volumio dhcpcd[890]: control command: /sbin/dhcpcd eth0 Jul 06 20:36:29 volumio ifplugd(eth0)[995]: client: sending commands to master dhcpcd process Jul 06 20:36:29 volumio dnsmasq[1995]: reading /etc/resolv.conf Jul 06 20:36:29 volumio dnsmasq[1995]: using nameserver 208.67.222.222#53 Jul 06 20:36:29 volumio dnsmasq[1995]: using nameserver 208.67.220.220#53 Jul 06 20:36:29 volumio ifplugd(eth0)[995]: Program executed successfully. Jul 06 20:36:29 volumio ifplugd(eth0)[995]: Link beat lost. Jul 06 20:36:29 volumio volumio[1045]: info: Initializing connection to go-librespot Websocket Jul 06 20:36:29 volumio volumio[1045]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 06 20:36:29 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jul 06 20:36:29 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 13. Jul 06 20:36:29 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:29 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Jul 06 20:36:29 volumio dnsmasq[1995]: exiting on receipt of SIGTERM Jul 06 20:36:29 volumio systemd[1]: dnsmasq.service: Succeeded. Jul 06 20:36:29 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:29 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:29 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jul 06 20:36:29 volumio hostapd[2062]: Configuration file: /etc/hostapd/hostapd.conf Jul 06 20:36:29 volumio hostapd[2062]: rfkill: WLAN soft blocked Jul 06 20:36:29 volumio hostapd[2062]: wlan0: Could not connect to kernel driver Jul 06 20:36:29 volumio hostapd[2062]: Using interface wlan0 with hwaddr f8:a2:d6:ea:9e:4f and ssid "Volumio-E1AFE" Jul 06 20:36:29 volumio hostapd[2062]: Failed to set beacon parameters Jul 06 20:36:29 volumio hostapd[2062]: wlan0: Could not connect to kernel driver Jul 06 20:36:29 volumio hostapd[2062]: Interface initialization failed Jul 06 20:36:29 volumio hostapd[2062]: wlan0: interface state UNINITIALIZED->DISABLED Jul 06 20:36:29 volumio hostapd[2062]: wlan0: AP-DISABLED Jul 06 20:36:29 volumio hostapd[2062]: wlan0: Unable to setup interface. Jul 06 20:36:29 volumio hostapd[2062]: wlan0: interface state DISABLED->DISABLED Jul 06 20:36:29 volumio hostapd[2062]: wlan0: AP-DISABLED Jul 06 20:36:29 volumio hostapd[2062]: wlan0: CTRL-EVENT-TERMINATING Jul 06 20:36:29 volumio hostapd[2062]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jul 06 20:36:29 volumio hostapd[2062]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jul 06 20:36:29 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jul 06 20:36:29 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jul 06 20:36:29 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:29 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:29 volumio dnsmasq[2063]: dnsmasq: syntax check OK. Jul 06 20:36:29 volumio dnsmasq[2069]: started, version 2.80 cachesize 150 Jul 06 20:36:29 volumio dnsmasq[2069]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jul 06 20:36:29 volumio dnsmasq-dhcp[2069]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jul 06 20:36:29 volumio dnsmasq[2069]: reading /etc/resolv.conf Jul 06 20:36:29 volumio dnsmasq[2069]: using nameserver 208.67.222.222#53 Jul 06 20:36:29 volumio dnsmasq[2069]: using nameserver 208.67.220.220#53 Jul 06 20:36:29 volumio dnsmasq[2069]: read /etc/hosts - 5 addresses Jul 06 20:36:29 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:31 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 06 20:36:31 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Jul 06 20:36:31 volumio systemd[1]: Stopped go-librespot Daemon. Jul 06 20:36:31 volumio systemd[1]: Started go-librespot Daemon. Jul 06 20:36:31 volumio go-librespot[2075]: Librespot-go daemon starting... Jul 06 20:36:31 volumio go-librespot[2075]: time="2024-07-06T20:36:31+07:00" level=info msg="generated new device id: 0451e0aea2b280029e1215ec9a767d894575c54f" Jul 06 20:36:31 volumio go-librespot[2075]: time="2024-07-06T20:36:31+07:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 06 20:36:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 06 20:36:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 06 20:36:31 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jul 06 20:36:31 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 14. Jul 06 20:36:31 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:31 volumio dnsmasq[2069]: exiting on receipt of SIGTERM Jul 06 20:36:31 volumio systemd[1]: dnsmasq.service: Succeeded. Jul 06 20:36:31 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:31 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:31 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jul 06 20:36:31 volumio hostapd[2086]: Configuration file: /etc/hostapd/hostapd.conf Jul 06 20:36:31 volumio hostapd[2086]: rfkill: WLAN soft blocked Jul 06 20:36:31 volumio hostapd[2086]: wlan0: Could not connect to kernel driver Jul 06 20:36:31 volumio hostapd[2086]: Using interface wlan0 with hwaddr f8:a2:d6:ea:9e:4f and ssid "Volumio-E1AFE" Jul 06 20:36:31 volumio hostapd[2086]: Failed to set beacon parameters Jul 06 20:36:31 volumio hostapd[2086]: wlan0: Could not connect to kernel driver Jul 06 20:36:31 volumio hostapd[2086]: Interface initialization failed Jul 06 20:36:31 volumio hostapd[2086]: wlan0: interface state UNINITIALIZED->DISABLED Jul 06 20:36:31 volumio hostapd[2086]: wlan0: AP-DISABLED Jul 06 20:36:31 volumio hostapd[2086]: wlan0: Unable to setup interface. Jul 06 20:36:31 volumio hostapd[2086]: wlan0: interface state DISABLED->DISABLED Jul 06 20:36:31 volumio hostapd[2086]: wlan0: AP-DISABLED Jul 06 20:36:31 volumio hostapd[2086]: wlan0: CTRL-EVENT-TERMINATING Jul 06 20:36:31 volumio hostapd[2086]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jul 06 20:36:31 volumio hostapd[2086]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jul 06 20:36:31 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jul 06 20:36:31 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jul 06 20:36:31 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:31 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:31 volumio dnsmasq[2087]: dnsmasq: syntax check OK. Jul 06 20:36:31 volumio dnsmasq[2093]: started, version 2.80 cachesize 150 Jul 06 20:36:31 volumio dnsmasq[2093]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jul 06 20:36:31 volumio dnsmasq-dhcp[2093]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jul 06 20:36:31 volumio dnsmasq[2093]: reading /etc/resolv.conf Jul 06 20:36:31 volumio dnsmasq[2093]: using nameserver 208.67.222.222#53 Jul 06 20:36:31 volumio dnsmasq[2093]: using nameserver 208.67.220.220#53 Jul 06 20:36:31 volumio dnsmasq[2093]: read /etc/hosts - 5 addresses Jul 06 20:36:31 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:32 volumio volumio[1045]: info: Initializing connection to go-librespot Websocket Jul 06 20:36:32 volumio volumio[1045]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 06 20:36:33 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jul 06 20:36:33 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 15. Jul 06 20:36:33 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:33 volumio dnsmasq[2093]: exiting on receipt of SIGTERM Jul 06 20:36:33 volumio systemd[1]: dnsmasq.service: Succeeded. Jul 06 20:36:33 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:33 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:33 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jul 06 20:36:33 volumio hostapd[2103]: Configuration file: /etc/hostapd/hostapd.conf Jul 06 20:36:33 volumio hostapd[2103]: rfkill: WLAN soft blocked Jul 06 20:36:33 volumio hostapd[2103]: wlan0: Could not connect to kernel driver Jul 06 20:36:33 volumio hostapd[2103]: Using interface wlan0 with hwaddr f8:a2:d6:ea:9e:4f and ssid "Volumio-E1AFE" Jul 06 20:36:33 volumio hostapd[2103]: Failed to set beacon parameters Jul 06 20:36:33 volumio hostapd[2103]: wlan0: Could not connect to kernel driver Jul 06 20:36:33 volumio hostapd[2103]: Interface initialization failed Jul 06 20:36:33 volumio hostapd[2103]: wlan0: interface state UNINITIALIZED->DISABLED Jul 06 20:36:33 volumio hostapd[2103]: wlan0: AP-DISABLED Jul 06 20:36:33 volumio hostapd[2103]: wlan0: Unable to setup interface. Jul 06 20:36:33 volumio hostapd[2103]: wlan0: interface state DISABLED->DISABLED Jul 06 20:36:33 volumio hostapd[2103]: wlan0: AP-DISABLED Jul 06 20:36:33 volumio hostapd[2103]: wlan0: CTRL-EVENT-TERMINATING Jul 06 20:36:33 volumio hostapd[2103]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jul 06 20:36:33 volumio hostapd[2103]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jul 06 20:36:33 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jul 06 20:36:33 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jul 06 20:36:33 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:33 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:33 volumio dnsmasq[2104]: dnsmasq: syntax check OK. Jul 06 20:36:33 volumio dnsmasq[2110]: started, version 2.80 cachesize 150 Jul 06 20:36:33 volumio dnsmasq[2110]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jul 06 20:36:33 volumio dnsmasq-dhcp[2110]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jul 06 20:36:33 volumio dnsmasq[2110]: reading /etc/resolv.conf Jul 06 20:36:33 volumio dnsmasq[2110]: using nameserver 208.67.222.222#53 Jul 06 20:36:33 volumio dnsmasq[2110]: using nameserver 208.67.220.220#53 Jul 06 20:36:33 volumio dnsmasq[2110]: read /etc/hosts - 5 addresses Jul 06 20:36:33 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:34 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 06 20:36:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Jul 06 20:36:34 volumio systemd[1]: Stopped go-librespot Daemon. Jul 06 20:36:34 volumio systemd[1]: Started go-librespot Daemon. Jul 06 20:36:34 volumio go-librespot[2116]: Librespot-go daemon starting... Jul 06 20:36:34 volumio go-librespot[2116]: time="2024-07-06T20:36:34+07:00" level=info msg="generated new device id: a6eedcea4b66949d19acb455aa85fea909757294" Jul 06 20:36:34 volumio go-librespot[2116]: time="2024-07-06T20:36:34+07:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 06 20:36:34 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 06 20:36:34 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 06 20:36:35 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 06 20:36:35 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 06 20:36:35 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 06 20:36:35 volumio volumio[1045]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.703&uuid=e1afea2e9bb1988b7de3723581b38a8d" http://updates.volumio.org/downloader-v1/track-device Jul 06 20:36:35 volumio volumio[1045]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 06 20:36:35 volumio volumio[1045]: Dload Upload Total Spent Left Speed Jul 06 20:36:35 volumio volumio[1045]: [132B blob data] Jul 06 20:36:35 volumio volumio[1045]: retrying in 5 seconds, trial 2 Jul 06 20:36:35 volumio volumio[1045]: info: Volumio Calling Home Jul 06 20:36:35 volumio volumio[1045]: info: Initializing connection to go-librespot Websocket Jul 06 20:36:35 volumio volumio[1045]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 06 20:36:36 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jul 06 20:36:36 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 16. Jul 06 20:36:36 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:36 volumio dnsmasq[2110]: exiting on receipt of SIGTERM Jul 06 20:36:36 volumio systemd[1]: dnsmasq.service: Succeeded. Jul 06 20:36:36 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:36 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:36 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jul 06 20:36:36 volumio hostapd[2132]: Configuration file: /etc/hostapd/hostapd.conf Jul 06 20:36:36 volumio hostapd[2132]: rfkill: WLAN soft blocked Jul 06 20:36:36 volumio hostapd[2132]: wlan0: Could not connect to kernel driver Jul 06 20:36:36 volumio hostapd[2132]: Using interface wlan0 with hwaddr f8:a2:d6:ea:9e:4f and ssid "Volumio-E1AFE" Jul 06 20:36:36 volumio hostapd[2132]: Failed to set beacon parameters Jul 06 20:36:36 volumio hostapd[2132]: wlan0: Could not connect to kernel driver Jul 06 20:36:36 volumio hostapd[2132]: Interface initialization failed Jul 06 20:36:36 volumio hostapd[2132]: wlan0: interface state UNINITIALIZED->DISABLED Jul 06 20:36:36 volumio hostapd[2132]: wlan0: AP-DISABLED Jul 06 20:36:36 volumio hostapd[2132]: wlan0: Unable to setup interface. Jul 06 20:36:36 volumio hostapd[2132]: wlan0: interface state DISABLED->DISABLED Jul 06 20:36:36 volumio hostapd[2132]: wlan0: AP-DISABLED Jul 06 20:36:36 volumio hostapd[2132]: wlan0: CTRL-EVENT-TERMINATING Jul 06 20:36:36 volumio hostapd[2132]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jul 06 20:36:36 volumio hostapd[2132]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jul 06 20:36:36 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jul 06 20:36:36 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jul 06 20:36:36 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:36 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:36 volumio dnsmasq[2133]: dnsmasq: syntax check OK. Jul 06 20:36:36 volumio dnsmasq[2139]: started, version 2.80 cachesize 150 Jul 06 20:36:36 volumio dnsmasq[2139]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jul 06 20:36:36 volumio dnsmasq-dhcp[2139]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jul 06 20:36:36 volumio dnsmasq[2139]: reading /etc/resolv.conf Jul 06 20:36:36 volumio dnsmasq[2139]: using nameserver 208.67.222.222#53 Jul 06 20:36:36 volumio dnsmasq[2139]: using nameserver 208.67.220.220#53 Jul 06 20:36:36 volumio dnsmasq[2139]: read /etc/hosts - 5 addresses Jul 06 20:36:36 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:37 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 06 20:36:37 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Jul 06 20:36:37 volumio systemd[1]: Stopped go-librespot Daemon. Jul 06 20:36:37 volumio systemd[1]: Started go-librespot Daemon. Jul 06 20:36:37 volumio go-librespot[2145]: Librespot-go daemon starting... Jul 06 20:36:37 volumio go-librespot[2145]: time="2024-07-06T20:36:37+07:00" level=info msg="generated new device id: e456e4cc1b3ad0b89f0d942094fcfeb15beb5687" Jul 06 20:36:37 volumio go-librespot[2145]: time="2024-07-06T20:36:37+07:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 06 20:36:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 06 20:36:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 06 20:36:38 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jul 06 20:36:38 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 17. Jul 06 20:36:38 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:38 volumio dnsmasq[2139]: exiting on receipt of SIGTERM Jul 06 20:36:38 volumio systemd[1]: dnsmasq.service: Succeeded. Jul 06 20:36:38 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:38 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:38 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jul 06 20:36:38 volumio hostapd[2156]: Configuration file: /etc/hostapd/hostapd.conf Jul 06 20:36:38 volumio hostapd[2156]: rfkill: WLAN soft blocked Jul 06 20:36:38 volumio hostapd[2156]: wlan0: Could not connect to kernel driver Jul 06 20:36:38 volumio hostapd[2156]: Using interface wlan0 with hwaddr f8:a2:d6:ea:9e:4f and ssid "Volumio-E1AFE" Jul 06 20:36:38 volumio hostapd[2156]: Failed to set beacon parameters Jul 06 20:36:38 volumio hostapd[2156]: wlan0: Could not connect to kernel driver Jul 06 20:36:38 volumio hostapd[2156]: Interface initialization failed Jul 06 20:36:38 volumio hostapd[2156]: wlan0: interface state UNINITIALIZED->DISABLED Jul 06 20:36:38 volumio hostapd[2156]: wlan0: AP-DISABLED Jul 06 20:36:38 volumio hostapd[2156]: wlan0: Unable to setup interface. Jul 06 20:36:38 volumio hostapd[2156]: wlan0: interface state DISABLED->DISABLED Jul 06 20:36:38 volumio hostapd[2156]: wlan0: AP-DISABLED Jul 06 20:36:38 volumio hostapd[2156]: wlan0: CTRL-EVENT-TERMINATING Jul 06 20:36:38 volumio hostapd[2156]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jul 06 20:36:38 volumio hostapd[2156]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jul 06 20:36:38 volumio volumio[1045]: info: Initializing connection to go-librespot Websocket Jul 06 20:36:38 volumio volumio[1045]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 06 20:36:38 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jul 06 20:36:38 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jul 06 20:36:38 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:38 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:38 volumio dnsmasq[2157]: dnsmasq: syntax check OK. Jul 06 20:36:38 volumio dnsmasq[2163]: started, version 2.80 cachesize 150 Jul 06 20:36:38 volumio dnsmasq[2163]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jul 06 20:36:38 volumio dnsmasq-dhcp[2163]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jul 06 20:36:38 volumio dnsmasq[2163]: reading /etc/resolv.conf Jul 06 20:36:38 volumio dnsmasq[2163]: using nameserver 208.67.222.222#53 Jul 06 20:36:38 volumio dnsmasq[2163]: using nameserver 208.67.220.220#53 Jul 06 20:36:38 volumio dnsmasq[2163]: read /etc/hosts - 5 addresses Jul 06 20:36:38 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:39 volumio ifplugd(eth0)[995]: Executing '/etc/ifplugd/ifplugd.action eth0 down'. Jul 06 20:36:39 volumio dhcpcd[2177]: sending commands to master dhcpcd process Jul 06 20:36:39 volumio dhcpcd[890]: control command: /sbin/dhcpcd -k eth0 Jul 06 20:36:39 volumio dhcpcd[890]: eth0: removing interface Jul 06 20:36:39 volumio ifplugd(eth0)[995]: client: sending commands to master dhcpcd process Jul 06 20:36:39 volumio ifplugd(eth0)[995]: Program executed successfully. Jul 06 20:36:39 volumio kernel: Generic FE-GE Realtek PHY r8169-0-100:00: attached PHY driver (mii_bus:phy_addr=r8169-0-100:00, irq=MAC) Jul 06 20:36:39 volumio kernel: r8169 0000:01:00.0 eth0: Link is Down Jul 06 20:36:40 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jul 06 20:36:40 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 18. Jul 06 20:36:40 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:40 volumio dnsmasq[2163]: exiting on receipt of SIGTERM Jul 06 20:36:40 volumio systemd[1]: dnsmasq.service: Succeeded. Jul 06 20:36:40 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:40 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:40 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jul 06 20:36:40 volumio hostapd[2199]: Configuration file: /etc/hostapd/hostapd.conf Jul 06 20:36:40 volumio hostapd[2199]: rfkill: WLAN soft blocked Jul 06 20:36:40 volumio hostapd[2199]: wlan0: Could not connect to kernel driver Jul 06 20:36:40 volumio hostapd[2199]: Using interface wlan0 with hwaddr f8:a2:d6:ea:9e:4f and ssid "Volumio-E1AFE" Jul 06 20:36:40 volumio hostapd[2199]: Failed to set beacon parameters Jul 06 20:36:40 volumio hostapd[2199]: wlan0: Could not connect to kernel driver Jul 06 20:36:40 volumio hostapd[2199]: Interface initialization failed Jul 06 20:36:40 volumio hostapd[2199]: wlan0: interface state UNINITIALIZED->DISABLED Jul 06 20:36:40 volumio hostapd[2199]: wlan0: AP-DISABLED Jul 06 20:36:40 volumio hostapd[2199]: wlan0: Unable to setup interface. Jul 06 20:36:40 volumio hostapd[2199]: wlan0: interface state DISABLED->DISABLED Jul 06 20:36:40 volumio hostapd[2199]: wlan0: AP-DISABLED Jul 06 20:36:40 volumio hostapd[2199]: wlan0: CTRL-EVENT-TERMINATING Jul 06 20:36:40 volumio hostapd[2199]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jul 06 20:36:40 volumio hostapd[2199]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jul 06 20:36:40 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jul 06 20:36:40 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jul 06 20:36:40 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:40 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:40 volumio dnsmasq[2200]: dnsmasq: syntax check OK. Jul 06 20:36:40 volumio dnsmasq[2206]: started, version 2.80 cachesize 150 Jul 06 20:36:40 volumio dnsmasq[2206]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jul 06 20:36:40 volumio dnsmasq-dhcp[2206]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jul 06 20:36:40 volumio dnsmasq[2206]: reading /etc/resolv.conf Jul 06 20:36:40 volumio dnsmasq[2206]: using nameserver 208.67.222.222#53 Jul 06 20:36:40 volumio dnsmasq[2206]: using nameserver 208.67.220.220#53 Jul 06 20:36:40 volumio dnsmasq[2206]: read /etc/hosts - 5 addresses Jul 06 20:36:40 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:41 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 06 20:36:41 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Jul 06 20:36:41 volumio systemd[1]: Stopped go-librespot Daemon. Jul 06 20:36:41 volumio systemd[1]: Started go-librespot Daemon. Jul 06 20:36:41 volumio go-librespot[2212]: Librespot-go daemon starting... Jul 06 20:36:41 volumio go-librespot[2212]: time="2024-07-06T20:36:41+07:00" level=info msg="generated new device id: d373b01d3f4581c03a7d995a95ce1a33e3a98f5a" Jul 06 20:36:41 volumio go-librespot[2212]: time="2024-07-06T20:36:41+07:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 06 20:36:41 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 06 20:36:41 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 06 20:36:41 volumio volumio[1045]: info: Initializing connection to go-librespot Websocket Jul 06 20:36:41 volumio volumio[1045]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 06 20:36:42 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jul 06 20:36:42 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 19. Jul 06 20:36:42 volumio kernel: r8169 0000:01:00.0 eth0: Link is Up - 1Gbps/Full - flow control rx/tx Jul 06 20:36:42 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:42 volumio dnsmasq[2206]: exiting on receipt of SIGTERM Jul 06 20:36:42 volumio systemd[1]: dnsmasq.service: Succeeded. Jul 06 20:36:42 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:42 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:42 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jul 06 20:36:42 volumio hostapd[2223]: Configuration file: /etc/hostapd/hostapd.conf Jul 06 20:36:42 volumio hostapd[2223]: rfkill: WLAN soft blocked Jul 06 20:36:42 volumio hostapd[2223]: wlan0: Could not connect to kernel driver Jul 06 20:36:42 volumio hostapd[2223]: Using interface wlan0 with hwaddr f8:a2:d6:ea:9e:4f and ssid "Volumio-E1AFE" Jul 06 20:36:42 volumio hostapd[2223]: Failed to set beacon parameters Jul 06 20:36:42 volumio hostapd[2223]: wlan0: Could not connect to kernel driver Jul 06 20:36:42 volumio hostapd[2223]: Interface initialization failed Jul 06 20:36:42 volumio hostapd[2223]: wlan0: interface state UNINITIALIZED->DISABLED Jul 06 20:36:42 volumio hostapd[2223]: wlan0: AP-DISABLED Jul 06 20:36:42 volumio hostapd[2223]: wlan0: Unable to setup interface. Jul 06 20:36:42 volumio hostapd[2223]: wlan0: interface state DISABLED->DISABLED Jul 06 20:36:42 volumio hostapd[2223]: wlan0: AP-DISABLED Jul 06 20:36:42 volumio hostapd[2223]: wlan0: CTRL-EVENT-TERMINATING Jul 06 20:36:42 volumio hostapd[2223]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jul 06 20:36:42 volumio hostapd[2223]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jul 06 20:36:42 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jul 06 20:36:42 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jul 06 20:36:42 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:42 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:42 volumio dnsmasq[2224]: dnsmasq: syntax check OK. Jul 06 20:36:42 volumio dnsmasq[2230]: started, version 2.80 cachesize 150 Jul 06 20:36:42 volumio dnsmasq[2230]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jul 06 20:36:42 volumio dnsmasq-dhcp[2230]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jul 06 20:36:42 volumio dnsmasq[2230]: reading /etc/resolv.conf Jul 06 20:36:42 volumio dnsmasq[2230]: using nameserver 208.67.222.222#53 Jul 06 20:36:42 volumio dnsmasq[2230]: using nameserver 208.67.220.220#53 Jul 06 20:36:42 volumio dnsmasq[2230]: read /etc/hosts - 5 addresses Jul 06 20:36:42 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:43 volumio ifplugd(eth0)[995]: Link beat detected. Jul 06 20:36:43 volumio ifplugd(eth0)[995]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jul 06 20:36:43 volumio dhcpcd[2255]: sending commands to master dhcpcd process Jul 06 20:36:43 volumio dhcpcd[890]: control command: /sbin/dhcpcd eth0 Jul 06 20:36:43 volumio ifplugd(eth0)[995]: client: sending commands to master dhcpcd process Jul 06 20:36:43 volumio dhcpcd[890]: eth0: carrier acquired Jul 06 20:36:43 volumio dhcpcd[890]: eth0: IAID 64:af:4f:3d Jul 06 20:36:43 volumio dhcpcd[890]: eth0: soliciting an IPv6 router Jul 06 20:36:43 volumio ifplugd(eth0)[995]: Program executed successfully. Jul 06 20:36:43 volumio dhcpcd[890]: eth0: soliciting a DHCP lease Jul 06 20:36:44 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 06 20:36:44 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Jul 06 20:36:44 volumio systemd[1]: Stopped go-librespot Daemon. Jul 06 20:36:44 volumio systemd[1]: Started go-librespot Daemon. Jul 06 20:36:44 volumio go-librespot[2282]: Librespot-go daemon starting... Jul 06 20:36:44 volumio go-librespot[2282]: time="2024-07-06T20:36:44+07:00" level=info msg="generated new device id: d61fe7d38ac7aa361704f77b72247c8ee678d8a7" Jul 06 20:36:44 volumio go-librespot[2282]: time="2024-07-06T20:36:44+07:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 06 20:36:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 06 20:36:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 06 20:36:44 volumio volumio[1045]: info: Initializing connection to go-librespot Websocket Jul 06 20:36:44 volumio volumio[1045]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 06 20:36:45 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jul 06 20:36:45 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 20. Jul 06 20:36:45 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:45 volumio dnsmasq[2230]: exiting on receipt of SIGTERM Jul 06 20:36:45 volumio systemd[1]: dnsmasq.service: Succeeded. Jul 06 20:36:45 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:45 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:45 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jul 06 20:36:45 volumio hostapd[2293]: Configuration file: /etc/hostapd/hostapd.conf Jul 06 20:36:45 volumio hostapd[2293]: rfkill: WLAN soft blocked Jul 06 20:36:45 volumio hostapd[2293]: wlan0: Could not connect to kernel driver Jul 06 20:36:45 volumio hostapd[2293]: Using interface wlan0 with hwaddr f8:a2:d6:ea:9e:4f and ssid "Volumio-E1AFE" Jul 06 20:36:45 volumio hostapd[2293]: Failed to set beacon parameters Jul 06 20:36:45 volumio hostapd[2293]: wlan0: Could not connect to kernel driver Jul 06 20:36:45 volumio hostapd[2293]: Interface initialization failed Jul 06 20:36:45 volumio hostapd[2293]: wlan0: interface state UNINITIALIZED->DISABLED Jul 06 20:36:45 volumio hostapd[2293]: wlan0: AP-DISABLED Jul 06 20:36:45 volumio hostapd[2293]: wlan0: Unable to setup interface. Jul 06 20:36:45 volumio hostapd[2293]: wlan0: interface state DISABLED->DISABLED Jul 06 20:36:45 volumio hostapd[2293]: wlan0: AP-DISABLED Jul 06 20:36:45 volumio hostapd[2293]: wlan0: CTRL-EVENT-TERMINATING Jul 06 20:36:45 volumio hostapd[2293]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jul 06 20:36:45 volumio hostapd[2293]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jul 06 20:36:45 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jul 06 20:36:45 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jul 06 20:36:45 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:45 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:45 volumio dnsmasq[2294]: dnsmasq: syntax check OK. Jul 06 20:36:45 volumio dnsmasq[2300]: started, version 2.80 cachesize 150 Jul 06 20:36:45 volumio dnsmasq[2300]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jul 06 20:36:45 volumio dnsmasq-dhcp[2300]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jul 06 20:36:45 volumio dnsmasq[2300]: reading /etc/resolv.conf Jul 06 20:36:45 volumio dnsmasq[2300]: using nameserver 208.67.222.222#53 Jul 06 20:36:45 volumio dnsmasq[2300]: using nameserver 208.67.220.220#53 Jul 06 20:36:45 volumio dnsmasq[2300]: read /etc/hosts - 5 addresses Jul 06 20:36:45 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:47 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jul 06 20:36:47 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 21. Jul 06 20:36:47 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:47 volumio dnsmasq[2300]: exiting on receipt of SIGTERM Jul 06 20:36:47 volumio systemd[1]: dnsmasq.service: Succeeded. Jul 06 20:36:47 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:47 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:47 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jul 06 20:36:47 volumio hostapd[2310]: Configuration file: /etc/hostapd/hostapd.conf Jul 06 20:36:47 volumio hostapd[2310]: rfkill: WLAN soft blocked Jul 06 20:36:47 volumio hostapd[2310]: wlan0: Could not connect to kernel driver Jul 06 20:36:47 volumio hostapd[2310]: Using interface wlan0 with hwaddr f8:a2:d6:ea:9e:4f and ssid "Volumio-E1AFE" Jul 06 20:36:47 volumio hostapd[2310]: Failed to set beacon parameters Jul 06 20:36:47 volumio hostapd[2310]: wlan0: Could not connect to kernel driver Jul 06 20:36:47 volumio hostapd[2310]: Interface initialization failed Jul 06 20:36:47 volumio hostapd[2310]: wlan0: interface state UNINITIALIZED->DISABLED Jul 06 20:36:47 volumio hostapd[2310]: wlan0: AP-DISABLED Jul 06 20:36:47 volumio hostapd[2310]: wlan0: Unable to setup interface. Jul 06 20:36:47 volumio hostapd[2310]: wlan0: interface state DISABLED->DISABLED Jul 06 20:36:47 volumio hostapd[2310]: wlan0: AP-DISABLED Jul 06 20:36:47 volumio hostapd[2310]: wlan0: CTRL-EVENT-TERMINATING Jul 06 20:36:47 volumio hostapd[2310]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jul 06 20:36:47 volumio hostapd[2310]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jul 06 20:36:47 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jul 06 20:36:47 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jul 06 20:36:47 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:47 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 06 20:36:47 volumio volumio[1045]: info: Initializing connection to go-librespot Websocket Jul 06 20:36:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Jul 06 20:36:47 volumio systemd[1]: Stopped go-librespot Daemon. Jul 06 20:36:47 volumio volumio[1045]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 06 20:36:47 volumio systemd[1]: Started go-librespot Daemon. Jul 06 20:36:47 volumio go-librespot[2311]: Librespot-go daemon starting... Jul 06 20:36:47 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:47 volumio dnsmasq[2312]: dnsmasq: syntax check OK. Jul 06 20:36:47 volumio go-librespot[2311]: time="2024-07-06T20:36:47+07:00" level=info msg="generated new device id: afb72dd8d9f52d20a2eaa62c5f436de25f7548e5" Jul 06 20:36:47 volumio go-librespot[2311]: time="2024-07-06T20:36:47+07:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 06 20:36:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 06 20:36:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 06 20:36:47 volumio dnsmasq[2324]: started, version 2.80 cachesize 150 Jul 06 20:36:47 volumio dnsmasq[2324]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jul 06 20:36:47 volumio dnsmasq-dhcp[2324]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jul 06 20:36:47 volumio dnsmasq[2324]: reading /etc/resolv.conf Jul 06 20:36:47 volumio dnsmasq[2324]: using nameserver 208.67.222.222#53 Jul 06 20:36:47 volumio dnsmasq[2324]: using nameserver 208.67.220.220#53 Jul 06 20:36:47 volumio dnsmasq[2324]: read /etc/hosts - 5 addresses Jul 06 20:36:47 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:48 volumio dhcpcd[890]: eth0: probing for an IPv4LL address Jul 06 20:36:49 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jul 06 20:36:49 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 22. Jul 06 20:36:49 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:49 volumio dnsmasq[2324]: exiting on receipt of SIGTERM Jul 06 20:36:49 volumio systemd[1]: dnsmasq.service: Succeeded. Jul 06 20:36:49 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:49 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:49 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jul 06 20:36:49 volumio hostapd[2334]: Configuration file: /etc/hostapd/hostapd.conf Jul 06 20:36:49 volumio hostapd[2334]: rfkill: WLAN soft blocked Jul 06 20:36:49 volumio hostapd[2334]: wlan0: Could not connect to kernel driver Jul 06 20:36:49 volumio hostapd[2334]: Using interface wlan0 with hwaddr f8:a2:d6:ea:9e:4f and ssid "Volumio-E1AFE" Jul 06 20:36:49 volumio hostapd[2334]: Failed to set beacon parameters Jul 06 20:36:49 volumio hostapd[2334]: wlan0: Could not connect to kernel driver Jul 06 20:36:49 volumio hostapd[2334]: Interface initialization failed Jul 06 20:36:49 volumio hostapd[2334]: wlan0: interface state UNINITIALIZED->DISABLED Jul 06 20:36:49 volumio hostapd[2334]: wlan0: AP-DISABLED Jul 06 20:36:49 volumio hostapd[2334]: wlan0: Unable to setup interface. Jul 06 20:36:49 volumio hostapd[2334]: wlan0: interface state DISABLED->DISABLED Jul 06 20:36:49 volumio hostapd[2334]: wlan0: AP-DISABLED Jul 06 20:36:49 volumio hostapd[2334]: wlan0: CTRL-EVENT-TERMINATING Jul 06 20:36:49 volumio hostapd[2334]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jul 06 20:36:49 volumio hostapd[2334]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jul 06 20:36:49 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jul 06 20:36:49 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jul 06 20:36:49 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:49 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:49 volumio dnsmasq[2335]: dnsmasq: syntax check OK. Jul 06 20:36:49 volumio dnsmasq[2341]: started, version 2.80 cachesize 150 Jul 06 20:36:49 volumio dnsmasq[2341]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jul 06 20:36:49 volumio dnsmasq-dhcp[2341]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jul 06 20:36:49 volumio dnsmasq[2341]: reading /etc/resolv.conf Jul 06 20:36:49 volumio dnsmasq[2341]: using nameserver 208.67.222.222#53 Jul 06 20:36:49 volumio dnsmasq[2341]: using nameserver 208.67.220.220#53 Jul 06 20:36:49 volumio dnsmasq[2341]: read /etc/hosts - 5 addresses Jul 06 20:36:49 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:50 volumio volumio[1045]: info: Initializing connection to go-librespot Websocket Jul 06 20:36:50 volumio volumio[1045]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 06 20:36:50 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 06 20:36:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Jul 06 20:36:50 volumio systemd[1]: Stopped go-librespot Daemon. Jul 06 20:36:50 volumio systemd[1]: Started go-librespot Daemon. Jul 06 20:36:50 volumio go-librespot[2347]: Librespot-go daemon starting... Jul 06 20:36:50 volumio go-librespot[2347]: time="2024-07-06T20:36:50+07:00" level=info msg="generated new device id: 3987f4e36844663da98cfcb7017ef5b14ea214ef" Jul 06 20:36:50 volumio go-librespot[2347]: time="2024-07-06T20:36:50+07:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 06 20:36:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 06 20:36:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 06 20:36:50 volumio dhcpcd[890]: eth0: offered 192.168.1.6 from 192.168.1.1 Jul 06 20:36:50 volumio dhcpcd[890]: eth0: probing address 192.168.1.6/24 Jul 06 20:36:51 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jul 06 20:36:51 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 23. Jul 06 20:36:51 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:51 volumio dnsmasq[2341]: exiting on receipt of SIGTERM Jul 06 20:36:51 volumio systemd[1]: dnsmasq.service: Succeeded. Jul 06 20:36:51 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:51 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:51 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jul 06 20:36:51 volumio hostapd[2358]: Configuration file: /etc/hostapd/hostapd.conf Jul 06 20:36:51 volumio hostapd[2358]: rfkill: WLAN soft blocked Jul 06 20:36:51 volumio hostapd[2358]: wlan0: Could not connect to kernel driver Jul 06 20:36:51 volumio hostapd[2358]: Using interface wlan0 with hwaddr f8:a2:d6:ea:9e:4f and ssid "Volumio-E1AFE" Jul 06 20:36:51 volumio hostapd[2358]: Failed to set beacon parameters Jul 06 20:36:51 volumio hostapd[2358]: wlan0: Could not connect to kernel driver Jul 06 20:36:51 volumio hostapd[2358]: Interface initialization failed Jul 06 20:36:51 volumio hostapd[2358]: wlan0: interface state UNINITIALIZED->DISABLED Jul 06 20:36:51 volumio hostapd[2358]: wlan0: AP-DISABLED Jul 06 20:36:51 volumio hostapd[2358]: wlan0: Unable to setup interface. Jul 06 20:36:51 volumio hostapd[2358]: wlan0: interface state DISABLED->DISABLED Jul 06 20:36:51 volumio hostapd[2358]: wlan0: AP-DISABLED Jul 06 20:36:51 volumio hostapd[2358]: wlan0: CTRL-EVENT-TERMINATING Jul 06 20:36:51 volumio hostapd[2358]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jul 06 20:36:51 volumio hostapd[2358]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jul 06 20:36:51 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jul 06 20:36:51 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jul 06 20:36:51 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:51 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:51 volumio dnsmasq[2359]: dnsmasq: syntax check OK. Jul 06 20:36:51 volumio dnsmasq[2365]: started, version 2.80 cachesize 150 Jul 06 20:36:51 volumio dnsmasq[2365]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jul 06 20:36:51 volumio dnsmasq-dhcp[2365]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jul 06 20:36:51 volumio dnsmasq[2365]: reading /etc/resolv.conf Jul 06 20:36:51 volumio dnsmasq[2365]: using nameserver 208.67.222.222#53 Jul 06 20:36:51 volumio dnsmasq[2365]: using nameserver 208.67.220.220#53 Jul 06 20:36:51 volumio dnsmasq[2365]: read /etc/hosts - 5 addresses Jul 06 20:36:51 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:53 volumio volumio[1045]: info: Initializing connection to go-librespot Websocket Jul 06 20:36:53 volumio volumio[1045]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 06 20:36:53 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 06 20:36:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Jul 06 20:36:53 volumio systemd[1]: Stopped go-librespot Daemon. Jul 06 20:36:53 volumio systemd[1]: Started go-librespot Daemon. Jul 06 20:36:53 volumio go-librespot[2371]: Librespot-go daemon starting... Jul 06 20:36:53 volumio go-librespot[2371]: time="2024-07-06T20:36:53+07:00" level=info msg="generated new device id: 19a38d8568e624e578de55ca84c7bd9b1d1cfb3c" Jul 06 20:36:53 volumio go-librespot[2371]: time="2024-07-06T20:36:53+07:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 06 20:36:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 06 20:36:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 06 20:36:54 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jul 06 20:36:54 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 24. Jul 06 20:36:54 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:54 volumio dnsmasq[2365]: exiting on receipt of SIGTERM Jul 06 20:36:54 volumio systemd[1]: dnsmasq.service: Succeeded. Jul 06 20:36:54 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:54 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:54 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jul 06 20:36:54 volumio hostapd[2382]: Configuration file: /etc/hostapd/hostapd.conf Jul 06 20:36:54 volumio hostapd[2382]: rfkill: WLAN soft blocked Jul 06 20:36:54 volumio hostapd[2382]: wlan0: Could not connect to kernel driver Jul 06 20:36:54 volumio hostapd[2382]: Using interface wlan0 with hwaddr f8:a2:d6:ea:9e:4f and ssid "Volumio-E1AFE" Jul 06 20:36:54 volumio hostapd[2382]: Failed to set beacon parameters Jul 06 20:36:54 volumio hostapd[2382]: wlan0: Could not connect to kernel driver Jul 06 20:36:54 volumio hostapd[2382]: Interface initialization failed Jul 06 20:36:54 volumio hostapd[2382]: wlan0: interface state UNINITIALIZED->DISABLED Jul 06 20:36:54 volumio hostapd[2382]: wlan0: AP-DISABLED Jul 06 20:36:54 volumio hostapd[2382]: wlan0: Unable to setup interface. Jul 06 20:36:54 volumio hostapd[2382]: wlan0: interface state DISABLED->DISABLED Jul 06 20:36:54 volumio hostapd[2382]: wlan0: AP-DISABLED Jul 06 20:36:54 volumio hostapd[2382]: wlan0: CTRL-EVENT-TERMINATING Jul 06 20:36:54 volumio hostapd[2382]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jul 06 20:36:54 volumio hostapd[2382]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jul 06 20:36:54 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jul 06 20:36:54 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jul 06 20:36:54 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:54 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:54 volumio dnsmasq[2383]: dnsmasq: syntax check OK. Jul 06 20:36:54 volumio dnsmasq[2389]: started, version 2.80 cachesize 150 Jul 06 20:36:54 volumio dnsmasq[2389]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jul 06 20:36:54 volumio dnsmasq-dhcp[2389]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jul 06 20:36:54 volumio dnsmasq[2389]: reading /etc/resolv.conf Jul 06 20:36:54 volumio dnsmasq[2389]: using nameserver 208.67.222.222#53 Jul 06 20:36:54 volumio dnsmasq[2389]: using nameserver 208.67.220.220#53 Jul 06 20:36:54 volumio dnsmasq[2389]: read /etc/hosts - 5 addresses Jul 06 20:36:54 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:54 volumio dhcpcd[890]: eth0: using IPv4LL address 169.254.141.201 Jul 06 20:36:54 volumio dhcpcd[890]: eth0: adding route to 169.254.0.0/16 Jul 06 20:36:54 volumio dhcpcd[890]: eth0: adding default route Jul 06 20:36:54 volumio avahi-daemon[790]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.141.201. Jul 06 20:36:54 volumio avahi-daemon[790]: New relevant interface eth0.IPv4 for mDNS. Jul 06 20:36:54 volumio avahi-daemon[790]: Registering new address record for 169.254.141.201 on eth0.IPv4. Jul 06 20:36:54 volumio nmbd[966]: [2024/07/06 20:36:54.702757, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 06 20:36:54 volumio nmbd[966]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jul 06 20:36:54 volumio systemd[1]: Started Samba NMB Daemon. Jul 06 20:36:54 volumio systemd[1]: Starting Samba Winbind Daemon... Jul 06 20:36:54 volumio winbindd[2405]: [2024/07/06 20:36:54.807000, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jul 06 20:36:54 volumio winbindd[2405]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jul 06 20:36:54 volumio winbindd[2405]: [2024/07/06 20:36:54.808785, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 06 20:36:54 volumio winbindd[2405]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jul 06 20:36:54 volumio systemd[1]: Started Samba Winbind Daemon. Jul 06 20:36:54 volumio systemd[1]: Starting Samba SMB Daemon... Jul 06 20:36:54 volumio smbd[2410]: [2024/07/06 20:36:54.941695, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 06 20:36:54 volumio smbd[2410]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jul 06 20:36:54 volumio systemd[1]: Started Samba SMB Daemon. Jul 06 20:36:54 volumio systemd[1]: Reached target Multi-User System. Jul 06 20:36:54 volumio systemd[1]: Reached target Graphical Interface. Jul 06 20:36:54 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jul 06 20:36:54 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jul 06 20:36:54 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jul 06 20:36:54 volumio systemd[1]: Startup finished in 12.655s (firmware) + 1.037s (loader) + 21.940s (kernel) + 57.817s (userspace) = 1min 33.451s. Jul 06 20:36:55 volumio dhcpcd[890]: eth0: leased 192.168.1.6 for 3600 seconds Jul 06 20:36:55 volumio dhcpcd[890]: eth0: adding route to 192.168.1.0/24 Jul 06 20:36:55 volumio dhcpcd[890]: eth0: changing default route via 192.168.1.1 Jul 06 20:36:55 volumio avahi-daemon[790]: Registering new address record for 192.168.1.6 on eth0.IPv4. Jul 06 20:36:55 volumio dnsmasq[2389]: reading /etc/resolv.conf Jul 06 20:36:55 volumio dnsmasq[2389]: using nameserver 203.113.131.2#53 Jul 06 20:36:55 volumio dnsmasq[2389]: using nameserver 203.113.188.8#53 Jul 06 20:36:55 volumio dnsmasq[2389]: using nameserver 208.67.222.222#53 Jul 06 20:36:55 volumio dnsmasq[2389]: using nameserver 208.67.220.220#53 Jul 06 20:36:55 volumio ntpd[1001]: ntpd exiting on signal 15 (Terminated) Jul 06 20:36:55 volumio systemd[1]: Stopping Network Time Service... Jul 06 20:36:55 volumio systemd[1]: ntp.service: Succeeded. Jul 06 20:36:55 volumio systemd[1]: Stopped Network Time Service. Jul 06 20:36:55 volumio systemd[1]: Starting Network Time Service... Jul 06 20:36:55 volumio ntpd[2451]: ntpd 4.2.8p12@1.3728-o (1): Starting Jul 06 20:36:55 volumio ntpd[2451]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jul 06 20:36:55 volumio systemd[1]: Started Network Time Service. Jul 06 20:36:55 volumio avahi-daemon[790]: Withdrawing address record for 169.254.141.201 on eth0. Jul 06 20:36:55 volumio avahi-daemon[790]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.141.201. Jul 06 20:36:55 volumio avahi-daemon[790]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.6. Jul 06 20:36:55 volumio ntpd[2457]: proto: precision = 0.062 usec (-24) Jul 06 20:36:55 volumio dhcpcd[890]: eth0: deleting route to 169.254.0.0/16 Jul 06 20:36:55 volumio ntpd[2457]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jul 06 20:36:55 volumio ntpd[2457]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jul 06 20:36:55 volumio ntpd[2457]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 557 days ago Jul 06 20:36:55 volumio ntpd[2457]: Listen and drop on 0 v6wildcard [::]:123 Jul 06 20:36:55 volumio ntpd[2457]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jul 06 20:36:55 volumio ntpd[2457]: Listen normally on 2 lo 127.0.0.1:123 Jul 06 20:36:55 volumio ntpd[2457]: Listen normally on 3 eth0 192.168.1.6:123 Jul 06 20:36:55 volumio ntpd[2457]: Listening on routing socket on fd #20 for interface updates Jul 06 20:36:55 volumio ntpd[2457]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 06 20:36:55 volumio ntpd[2457]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 06 20:36:56 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jul 06 20:36:56 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 25. Jul 06 20:36:56 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:56 volumio dnsmasq[2389]: exiting on receipt of SIGTERM Jul 06 20:36:56 volumio systemd[1]: dnsmasq.service: Succeeded. Jul 06 20:36:56 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:56 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:56 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jul 06 20:36:56 volumio hostapd[2477]: Configuration file: /etc/hostapd/hostapd.conf Jul 06 20:36:56 volumio hostapd[2477]: rfkill: WLAN soft blocked Jul 06 20:36:56 volumio hostapd[2477]: wlan0: Could not connect to kernel driver Jul 06 20:36:56 volumio hostapd[2477]: Using interface wlan0 with hwaddr f8:a2:d6:ea:9e:4f and ssid "Volumio-E1AFE" Jul 06 20:36:56 volumio hostapd[2477]: Failed to set beacon parameters Jul 06 20:36:56 volumio hostapd[2477]: wlan0: Could not connect to kernel driver Jul 06 20:36:56 volumio hostapd[2477]: Interface initialization failed Jul 06 20:36:56 volumio hostapd[2477]: wlan0: interface state UNINITIALIZED->DISABLED Jul 06 20:36:56 volumio hostapd[2477]: wlan0: AP-DISABLED Jul 06 20:36:56 volumio hostapd[2477]: wlan0: Unable to setup interface. Jul 06 20:36:56 volumio hostapd[2477]: wlan0: interface state DISABLED->DISABLED Jul 06 20:36:56 volumio hostapd[2477]: wlan0: AP-DISABLED Jul 06 20:36:56 volumio hostapd[2477]: wlan0: CTRL-EVENT-TERMINATING Jul 06 20:36:56 volumio hostapd[2477]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jul 06 20:36:56 volumio hostapd[2477]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jul 06 20:36:56 volumio volumio[1045]: info: Initializing connection to go-librespot Websocket Jul 06 20:36:56 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jul 06 20:36:56 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jul 06 20:36:56 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:56 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:56 volumio dnsmasq[2478]: dnsmasq: syntax check OK. Jul 06 20:36:56 volumio dnsmasq[2484]: started, version 2.80 cachesize 150 Jul 06 20:36:56 volumio dnsmasq[2484]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jul 06 20:36:56 volumio dnsmasq-dhcp[2484]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jul 06 20:36:56 volumio dnsmasq[2484]: reading /etc/resolv.conf Jul 06 20:36:56 volumio dnsmasq[2484]: using nameserver 203.113.131.2#53 Jul 06 20:36:56 volumio dnsmasq[2484]: using nameserver 203.113.188.8#53 Jul 06 20:36:56 volumio dnsmasq[2484]: using nameserver 208.67.222.222#53 Jul 06 20:36:56 volumio dnsmasq[2484]: using nameserver 208.67.220.220#53 Jul 06 20:36:56 volumio dnsmasq[2484]: read /etc/hosts - 5 addresses Jul 06 20:36:56 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:56 volumio nmbd[966]: [2024/07/06 20:36:56.711272, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jul 06 20:36:56 volumio nmbd[966]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Jul 06 20:36:56 volumio nmbd[966]: [2024/07/06 20:36:56.711359, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Jul 06 20:36:56 volumio nmbd[966]: retransmit_or_expire_response_records: Failed to resend packet id 5581 to IP 169.254.255.255 on subnet 169.254.141.201 Jul 06 20:36:56 volumio nmbd[966]: [2024/07/06 20:36:56.711396, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jul 06 20:36:56 volumio nmbd[966]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Jul 06 20:36:56 volumio nmbd[966]: [2024/07/06 20:36:56.711425, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Jul 06 20:36:56 volumio nmbd[966]: retransmit_or_expire_response_records: Failed to resend packet id 5582 to IP 169.254.255.255 on subnet 169.254.141.201 Jul 06 20:36:56 volumio nmbd[966]: [2024/07/06 20:36:56.711452, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jul 06 20:36:56 volumio nmbd[966]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Jul 06 20:36:56 volumio nmbd[966]: [2024/07/06 20:36:56.711476, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Jul 06 20:36:56 volumio nmbd[966]: retransmit_or_expire_response_records: Failed to resend packet id 5583 to IP 169.254.255.255 on subnet 169.254.141.201 Jul 06 20:36:56 volumio nmbd[966]: [2024/07/06 20:36:56.711499, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jul 06 20:36:56 volumio nmbd[966]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Jul 06 20:36:56 volumio nmbd[966]: [2024/07/06 20:36:56.711523, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Jul 06 20:36:56 volumio nmbd[966]: retransmit_or_expire_response_records: Failed to resend packet id 5584 to IP 169.254.255.255 on subnet 169.254.141.201 Jul 06 20:36:56 volumio nmbd[966]: [2024/07/06 20:36:56.711549, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jul 06 20:36:56 volumio nmbd[966]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Jul 06 20:36:56 volumio nmbd[966]: [2024/07/06 20:36:56.711571, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Jul 06 20:36:56 volumio nmbd[966]: retransmit_or_expire_response_records: Failed to resend packet id 5585 to IP 169.254.255.255 on subnet 169.254.141.201 Jul 06 20:36:57 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jul 06 20:36:57 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jul 06 20:36:57 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 06 20:36:57 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Jul 06 20:36:57 volumio systemd[1]: Stopped go-librespot Daemon. Jul 06 20:36:57 volumio systemd[1]: Started go-librespot Daemon. Jul 06 20:36:57 volumio go-librespot[2490]: Librespot-go daemon starting... Jul 06 20:36:57 volumio go-librespot[2490]: time="2024-07-06T20:36:57+07:00" level=info msg="generated new device id: b7d61cea6fd9f15144ddd34b2d36f892096b773f" Jul 06 20:36:57 volumio go-librespot[2490]: time="2024-07-06T20:36:57+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jul 06 20:36:57 volumio go-librespot[2490]: time="2024-07-06T20:36:57+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jul 06 20:36:57 volumio go-librespot[2490]: time="2024-07-06T20:36:57+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jul 06 20:36:57 volumio go-librespot[2490]: time="2024-07-06T20:36:57+07:00" level=debug msg="zeroconf server listening on port 45963" Jul 06 20:36:57 volumio ntpd[2457]: Soliciting pool server 203.113.174.44 Jul 06 20:36:57 volumio ntpd[2457]: Soliciting pool server 103.130.217.41 Jul 06 20:36:57 volumio nmbd[966]: [2024/07/06 20:36:57.713306, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jul 06 20:36:57 volumio nmbd[966]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Jul 06 20:36:57 volumio nmbd[966]: [2024/07/06 20:36:57.713375, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Jul 06 20:36:57 volumio nmbd[966]: retransmit_or_expire_response_records: Failed to resend packet id 5581 to IP 169.254.255.255 on subnet 169.254.141.201 Jul 06 20:36:57 volumio nmbd[966]: [2024/07/06 20:36:57.713401, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jul 06 20:36:57 volumio nmbd[966]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Jul 06 20:36:57 volumio nmbd[966]: [2024/07/06 20:36:57.713426, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Jul 06 20:36:57 volumio nmbd[966]: retransmit_or_expire_response_records: Failed to resend packet id 5582 to IP 169.254.255.255 on subnet 169.254.141.201 Jul 06 20:36:57 volumio nmbd[966]: [2024/07/06 20:36:57.713457, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jul 06 20:36:57 volumio nmbd[966]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Jul 06 20:36:57 volumio nmbd[966]: [2024/07/06 20:36:57.713483, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Jul 06 20:36:57 volumio nmbd[966]: retransmit_or_expire_response_records: Failed to resend packet id 5583 to IP 169.254.255.255 on subnet 169.254.141.201 Jul 06 20:36:57 volumio nmbd[966]: [2024/07/06 20:36:57.713509, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jul 06 20:36:57 volumio nmbd[966]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Jul 06 20:36:57 volumio nmbd[966]: [2024/07/06 20:36:57.713534, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Jul 06 20:36:57 volumio nmbd[966]: retransmit_or_expire_response_records: Failed to resend packet id 5584 to IP 169.254.255.255 on subnet 169.254.141.201 Jul 06 20:36:57 volumio nmbd[966]: [2024/07/06 20:36:57.713561, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jul 06 20:36:57 volumio nmbd[966]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Jul 06 20:36:57 volumio nmbd[966]: [2024/07/06 20:36:57.713595, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Jul 06 20:36:57 volumio nmbd[966]: retransmit_or_expire_response_records: Failed to resend packet id 5585 to IP 169.254.255.255 on subnet 169.254.141.201 Jul 06 20:36:58 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jul 06 20:36:58 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 26. Jul 06 20:36:58 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:58 volumio dnsmasq[2484]: exiting on receipt of SIGTERM Jul 06 20:36:58 volumio systemd[1]: dnsmasq.service: Succeeded. Jul 06 20:36:58 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:58 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:58 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jul 06 20:36:58 volumio hostapd[2502]: Configuration file: /etc/hostapd/hostapd.conf Jul 06 20:36:58 volumio hostapd[2502]: rfkill: WLAN soft blocked Jul 06 20:36:58 volumio hostapd[2502]: wlan0: Could not connect to kernel driver Jul 06 20:36:58 volumio hostapd[2502]: Using interface wlan0 with hwaddr f8:a2:d6:ea:9e:4f and ssid "Volumio-E1AFE" Jul 06 20:36:58 volumio hostapd[2502]: Failed to set beacon parameters Jul 06 20:36:58 volumio hostapd[2502]: wlan0: Could not connect to kernel driver Jul 06 20:36:58 volumio hostapd[2502]: Interface initialization failed Jul 06 20:36:58 volumio hostapd[2502]: wlan0: interface state UNINITIALIZED->DISABLED Jul 06 20:36:58 volumio hostapd[2502]: wlan0: AP-DISABLED Jul 06 20:36:58 volumio hostapd[2502]: wlan0: Unable to setup interface. Jul 06 20:36:58 volumio hostapd[2502]: wlan0: interface state DISABLED->DISABLED Jul 06 20:36:58 volumio hostapd[2502]: wlan0: AP-DISABLED Jul 06 20:36:58 volumio hostapd[2502]: wlan0: CTRL-EVENT-TERMINATING Jul 06 20:36:58 volumio hostapd[2502]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jul 06 20:36:58 volumio hostapd[2502]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jul 06 20:36:58 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jul 06 20:36:58 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jul 06 20:36:58 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:36:58 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:36:58 volumio dnsmasq[2508]: dnsmasq: syntax check OK. Jul 06 20:36:58 volumio dnsmasq[2514]: started, version 2.80 cachesize 150 Jul 06 20:36:58 volumio dnsmasq[2514]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jul 06 20:36:58 volumio dnsmasq-dhcp[2514]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jul 06 20:36:58 volumio dnsmasq[2514]: reading /etc/resolv.conf Jul 06 20:36:58 volumio dnsmasq[2514]: using nameserver 203.113.131.2#53 Jul 06 20:36:58 volumio dnsmasq[2514]: using nameserver 203.113.188.8#53 Jul 06 20:36:58 volumio dnsmasq[2514]: using nameserver 208.67.222.222#53 Jul 06 20:36:58 volumio dnsmasq[2514]: using nameserver 208.67.220.220#53 Jul 06 20:36:58 volumio dnsmasq[2514]: read /etc/hosts - 5 addresses Jul 06 20:36:58 volumio nmbd[966]: [2024/07/06 20:36:58.714766, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jul 06 20:36:58 volumio nmbd[966]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Jul 06 20:36:58 volumio nmbd[966]: [2024/07/06 20:36:58.714831, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Jul 06 20:36:58 volumio nmbd[966]: retransmit_or_expire_response_records: Failed to resend packet id 5581 to IP 169.254.255.255 on subnet 169.254.141.201 Jul 06 20:36:58 volumio nmbd[966]: [2024/07/06 20:36:58.714873, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jul 06 20:36:58 volumio nmbd[966]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Jul 06 20:36:58 volumio nmbd[966]: [2024/07/06 20:36:58.714909, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Jul 06 20:36:58 volumio nmbd[966]: retransmit_or_expire_response_records: Failed to resend packet id 5582 to IP 169.254.255.255 on subnet 169.254.141.201 Jul 06 20:36:58 volumio nmbd[966]: [2024/07/06 20:36:58.714945, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jul 06 20:36:58 volumio nmbd[966]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Jul 06 20:36:58 volumio nmbd[966]: [2024/07/06 20:36:58.714979, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Jul 06 20:36:58 volumio nmbd[966]: retransmit_or_expire_response_records: Failed to resend packet id 5583 to IP 169.254.255.255 on subnet 169.254.141.201 Jul 06 20:36:58 volumio nmbd[966]: [2024/07/06 20:36:58.715012, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jul 06 20:36:58 volumio nmbd[966]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Jul 06 20:36:58 volumio nmbd[966]: [2024/07/06 20:36:58.715044, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Jul 06 20:36:58 volumio nmbd[966]: retransmit_or_expire_response_records: Failed to resend packet id 5584 to IP 169.254.255.255 on subnet 169.254.141.201 Jul 06 20:36:58 volumio nmbd[966]: [2024/07/06 20:36:58.715074, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jul 06 20:36:58 volumio nmbd[966]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Jul 06 20:36:58 volumio nmbd[966]: [2024/07/06 20:36:58.715156, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Jul 06 20:36:58 volumio nmbd[966]: retransmit_or_expire_response_records: Failed to resend packet id 5585 to IP 169.254.255.255 on subnet 169.254.141.201 Jul 06 20:36:58 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:36:58 volumio go-librespot[2490]: time="2024-07-06T20:36:58+07:00" level=debug msg="obtained new client token: AAAI56m3wwziZJmDrB06TJBioV/PWE3Ei90Iwy2F+u3QN12eCDufP9KotGSHRlwH6JUlgUWVXomb8VVvioUt1n0SbNJuzAT9iEmBO7CXjkoP4uY02lJdcVRACMebg3DTYDkCP77fES9J7S9fJB2oEzPmp7C9sJ6GW9SPPbuOs2AAJz3W+XXp0Ql7LHyQeN01MZnP/7FnDXF7MkFnIIxlVsL9+/xS5tL+R9t8RmSSpxbpZiZeKAZDFww96Z/OY69G" Jul 06 20:36:58 volumio go-librespot[2490]: time="2024-07-06T20:36:58+07:00" level=debug msg="completed keyexchange" Jul 06 20:36:59 volumio ntpd[2457]: Soliciting pool server 2402:f000:1:416:101:6:6:172 Jul 06 20:36:59 volumio go-librespot[2490]: time="2024-07-06T20:36:59+07:00" level=debug msg="completed challenge" Jul 06 20:36:59 volumio go-librespot[2490]: time="2024-07-06T20:36:59+07:00" level=debug msg="authenticated as raoofhppbxkqgyhe58jhcnyt2" Jul 06 20:36:59 volumio nmbd[966]: [2024/07/06 20:36:59.716945, 0] ../source3/libsmb/nmblib.c:917(send_udp) Jul 06 20:36:59 volumio nmbd[966]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable Jul 06 20:36:59 volumio go-librespot[2490]: time="2024-07-06T20:36:59+07:00" level=debug msg="authenticated as raoofhppbxkqgyhe58jhcnyt2" Jul 06 20:36:59 volumio go-librespot[2490]: time="2024-07-06T20:36:59+07:00" level=debug msg="new websocket client" Jul 06 20:36:59 volumio volumio[1045]: info: Connection to go-librespot Websocket established Jul 06 20:36:59 volumio go-librespot[2490]: time="2024-07-06T20:36:59+07:00" level=debug msg="dealer connection opened" Jul 06 20:36:59 volumio go-librespot[2490]: time="2024-07-06T20:36:59+07:00" level=info msg="accepted zeroconf user raoofhppbxkqgyhe58jhcnyt2 from iPhone" Jul 06 20:36:59 volumio go-librespot[2490]: time="2024-07-06T20:36:59+07:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jul 06 20:36:59 volumio go-librespot[2490]: time="2024-07-06T20:36:59+07:00" level=debug msg="received connection id: OWZlODlkNzUtZDgzNy00ODQ0LTk4MzQtN2ExZjAzNzZjYjI4K2RlYWxlcit0Y3A6Ly9nYWUyLWRlYWxlci1hLXA3djAuZ2FlMi5zcG90aWZ5Lm5ldDo1NzAwKzRFQzNCRkQ3Mjc0RTExNDFCOEQ2NUZCRDI0NTA4MzNBMzI3NzAyMzFDMDJCOTVCMkFBRTVCMkM1OTg5NUZEOEY=" Jul 06 20:36:59 volumio go-librespot[2490]: time="2024-07-06T20:36:59+07:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jul 06 20:37:00 volumio go-librespot[2490]: time="2024-07-06T20:37:00+07:00" level=debug msg="put connect state because NEW_DEVICE" Jul 06 20:37:00 volumio go-librespot[2490]: time="2024-07-06T20:37:00+07:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jul 06 20:37:00 volumio go-librespot[2490]: time="2024-07-06T20:37:00+07:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jul 06 20:37:00 volumio go-librespot[2490]: time="2024-07-06T20:37:00+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 513" Jul 06 20:37:00 volumio go-librespot[2490]: time="2024-07-06T20:37:00+07:00" level=debug msg="handling transfer player command from 047d1d27b9c35c34e68f8288d96bb69330c1685e" Jul 06 20:37:00 volumio volumio[1045]: info: Discovery: adding 9d40aec8-e39b-4943-a690-9be0ce2684ef Jul 06 20:37:00 volumio volumio[1045]: info: Discovery: Found device Volumio Jul 06 20:37:00 volumio volumio[1045]: info: CoreCommandRouter::volumioGetState Jul 06 20:37:00 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Jul 06 20:37:00 volumio go-librespot[2490]: time="2024-07-06T20:37:00+07:00" level=trace msg="fetched new page 0 with 24 items (list: 24)" Jul 06 20:37:00 volumio go-librespot[2490]: time="2024-07-06T20:37:00+07:00" level=debug msg="loading track spotify:track:45MN2CIT17yaBzSiMa5F1T (paused: false, position: 6870ms)" Jul 06 20:37:00 volumio go-librespot[2490]: time="2024-07-06T20:37:00+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 06 20:37:00 volumio go-librespot[2490]: time="2024-07-06T20:37:00+07:00" level=trace msg="emitting websocket event: will_play" Jul 06 20:37:00 volumio volumio[1045]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:45MN2CIT17yaBzSiMa5F1T","play_origin":"playlist/ondemand"}} Jul 06 20:37:00 volumio go-librespot[2490]: time="2024-07-06T20:37:00+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 241" Jul 06 20:37:00 volumio go-librespot[2490]: time="2024-07-06T20:37:00+07:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:45MN2CIT17yaBzSiMa5F1T" Jul 06 20:37:00 volumio go-librespot[2490]: time="2024-07-06T20:37:00+07:00" level=debug msg="requested aes key for file 4bb35e4dada66201cf3d96b5f155ea1cd097fb91, gid: 45MN2CIT17yaBzSiMa5F1T" Jul 06 20:37:00 volumio go-librespot[2490]: time="2024-07-06T20:37:00+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1633" Jul 06 20:37:00 volumio systemd[1]: hostapd.service: Service RestartSec=2s expired, scheduling restart. Jul 06 20:37:00 volumio systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 27. Jul 06 20:37:00 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:37:00 volumio dnsmasq[2514]: exiting on receipt of SIGTERM Jul 06 20:37:00 volumio systemd[1]: dnsmasq.service: Succeeded. Jul 06 20:37:00 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:37:00 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:37:00 volumio systemd[1]: Starting Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Jul 06 20:37:00 volumio hostapd[2568]: Configuration file: /etc/hostapd/hostapd.conf Jul 06 20:37:00 volumio hostapd[2568]: rfkill: WLAN soft blocked Jul 06 20:37:00 volumio hostapd[2568]: wlan0: Could not connect to kernel driver Jul 06 20:37:00 volumio hostapd[2568]: Using interface wlan0 with hwaddr f8:a2:d6:ea:9e:4f and ssid "Volumio-E1AFE" Jul 06 20:37:00 volumio hostapd[2568]: Failed to set beacon parameters Jul 06 20:37:00 volumio hostapd[2568]: wlan0: Could not connect to kernel driver Jul 06 20:37:00 volumio hostapd[2568]: Interface initialization failed Jul 06 20:37:00 volumio hostapd[2568]: wlan0: interface state UNINITIALIZED->DISABLED Jul 06 20:37:00 volumio hostapd[2568]: wlan0: AP-DISABLED Jul 06 20:37:00 volumio hostapd[2568]: wlan0: Unable to setup interface. Jul 06 20:37:00 volumio hostapd[2568]: wlan0: interface state DISABLED->DISABLED Jul 06 20:37:00 volumio hostapd[2568]: wlan0: AP-DISABLED Jul 06 20:37:00 volumio hostapd[2568]: wlan0: CTRL-EVENT-TERMINATING Jul 06 20:37:00 volumio hostapd[2568]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jul 06 20:37:00 volumio hostapd[2568]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jul 06 20:37:00 volumio systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Jul 06 20:37:00 volumio systemd[1]: hostapd.service: Failed with result 'exit-code'. Jul 06 20:37:00 volumio systemd[1]: Failed to start Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Jul 06 20:37:00 volumio systemd[1]: Starting dnsmasq - A lightweight DHCP and caching DNS server... Jul 06 20:37:00 volumio dnsmasq[2569]: dnsmasq: syntax check OK. Jul 06 20:37:00 volumio dnsmasq[2575]: started, version 2.80 cachesize 150 Jul 06 20:37:00 volumio dnsmasq[2575]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect inotify dumpfile Jul 06 20:37:00 volumio dnsmasq-dhcp[2575]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Jul 06 20:37:00 volumio dnsmasq[2575]: reading /etc/resolv.conf Jul 06 20:37:00 volumio dnsmasq[2575]: using nameserver 203.113.131.2#53 Jul 06 20:37:00 volumio dnsmasq[2575]: using nameserver 203.113.188.8#53 Jul 06 20:37:00 volumio dnsmasq[2575]: using nameserver 208.67.222.222#53 Jul 06 20:37:00 volumio dnsmasq[2575]: using nameserver 208.67.220.220#53 Jul 06 20:37:00 volumio dnsmasq[2575]: read /etc/hosts - 5 addresses Jul 06 20:37:00 volumio systemd[1]: Started dnsmasq - A lightweight DHCP and caching DNS server. Jul 06 20:37:01 volumio go-librespot[2490]: time="2024-07-06T20:37:01+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1391" Jul 06 20:37:01 volumio go-librespot[2490]: time="2024-07-06T20:37:01+07:00" level=debug msg="fetched first chunk of 26, total size is 13578884 bytes" Jul 06 20:37:01 volumio go-librespot[2490]: time="2024-07-06T20:37:01+07:00" level=trace msg="seek to 6870ms (diff: 156ms, samples: 302967, bytes: 274973)" Jul 06 20:37:01 volumio go-librespot[2490]: time="2024-07-06T20:37:01+07:00" level=info msg="loaded track \"Sao Chưa Thấy Hồi Âm\" (uri: spotify:track:45MN2CIT17yaBzSiMa5F1T, paused: false, position: 6870ms, duration: 366303ms)" Jul 06 20:37:01 volumio go-librespot[2490]: time="2024-07-06T20:37:01+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 06 20:37:01 volumio go-librespot[2490]: time="2024-07-06T20:37:01+07:00" level=debug msg="fetched chunk 1/25, size: 524288" Jul 06 20:37:01 volumio go-librespot[2490]: time="2024-07-06T20:37:01+07:00" level=trace msg="emitting websocket event: metadata" Jul 06 20:37:01 volumio go-librespot[2490]: time="2024-07-06T20:37:01+07:00" level=trace msg="emitting websocket event: active" Jul 06 20:37:01 volumio go-librespot[2490]: time="2024-07-06T20:37:01+07:00" level=debug msg="sending successful reply for delaer request" Jul 06 20:37:01 volumio go-librespot[2490]: time="2024-07-06T20:37:01+07:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jul 06 20:37:01 volumio go-librespot[2490]: time="2024-07-06T20:37:01+07:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jul 06 20:37:01 volumio volumio[1045]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:45MN2CIT17yaBzSiMa5F1T","name":"Sao Chưa Thấy Hồi Âm","artist_names":["Lệ Quyên"],"album_name":"Hẹn Hò (Khúc Tình Xưa 5)","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0281e4fdb58e7f28a11c3f0776","position":6870,"duration":366303,"release_date":"year:2019 month:3 day:1","track_number":3,"disc_number":1}} Jul 06 20:37:01 volumio go-librespot[2490]: time="2024-07-06T20:37:01+07:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jul 06 20:37:01 volumio volumio[1045]: SPOTIFY: received: {"type":"active","data":null} Jul 06 20:37:01 volumio volumio[1045]: info: Aligning Spotify Volume to Volumio Volume Jul 06 20:37:01 volumio volumio[1045]: info: CoreCommandRouter::volumioGetState Jul 06 20:37:01 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Jul 06 20:37:01 volumio volumio[1045]: info: Setting Spotify Volume from Volumio: 5 Jul 06 20:37:01 volumio go-librespot[2490]: time="2024-07-06T20:37:01+07:00" level=debug msg="fetched chunk 3/25, size: 524288" Jul 06 20:37:01 volumio go-librespot[2490]: time="2024-07-06T20:37:01+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 06 20:37:01 volumio go-librespot[2490]: time="2024-07-06T20:37:01+07:00" level=trace msg="emitting websocket event: playing" Jul 06 20:37:01 volumio volumio[1045]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:45MN2CIT17yaBzSiMa5F1T","play_origin":"playlist/ondemand"}} Jul 06 20:37:01 volumio volumio[1045]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 06 20:37:01 volumio volumio[1045]: TypeError: Cannot read property 'service' of undefined Jul 06 20:37:01 volumio volumio[1045]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:336:50) Jul 06 20:37:01 volumio volumio[1045]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:262:18) Jul 06 20:37:01 volumio volumio[1045]: at WebSocket.message (/data/plugins/music_service/spop/index.js:189:14) Jul 06 20:37:01 volumio volumio[1045]: at WebSocket.emit (events.js:400:28) Jul 06 20:37:01 volumio volumio[1045]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1209:20) Jul 06 20:37:01 volumio volumio[1045]: at Receiver.emit (events.js:400:28) Jul 06 20:37:01 volumio volumio[1045]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:594:14) Jul 06 20:37:01 volumio volumio[1045]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Jul 06 20:37:01 volumio volumio[1045]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Jul 06 20:37:01 volumio volumio[1045]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Jul 06 20:37:01 volumio volumio[1045]: at writeOrBuffer (internal/streams/writable.js:358:12) Jul 06 20:37:01 volumio volumio[1045]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Jul 06 20:37:01 volumio volumio[1045]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1303:35) Jul 06 20:37:01 volumio volumio[1045]: at Socket.emit (events.js:400:28) Jul 06 20:37:01 volumio volumio[1045]: at addChunk (internal/streams/readable.js:293:12) Jul 06 20:37:01 volumio volumio[1045]: at readableAddChunk (internal/streams/readable.js:267:9) Jul 06 20:37:01 volumio volumio[1045]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 06 20:37:01 volumio sudo[2592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-06 20:36 Jul 06 20:37:01 volumio sudo[2592]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 09:13:11 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ac782621640fcc70c07e91a81b6d958b"