Sep 09 22:36:00 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2374. Sep 09 22:36:00 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:00 volumio-goco dnsmasq[39210]: exiting on receipt of SIGTERM Sep 09 22:36:00 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:36:00 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:00 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:00 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:36:00 volumio-goco hostapd[39216]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:36:00 volumio-goco hostapd[39216]: nl80211: Could not configure driver mode Sep 09 22:36:00 volumio-goco hostapd[39216]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:36:00 volumio-goco hostapd[39216]: nl80211 driver initialization failed. Sep 09 22:36:00 volumio-goco hostapd[39216]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:36:00 volumio-goco hostapd[39216]: wlan0: AP-DISABLED Sep 09 22:36:00 volumio-goco hostapd[39216]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:36:00 volumio-goco hostapd[39216]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:36:00 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:36:00 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:36:00 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:00 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:01 volumio-goco dnsmasq[39221]: started, version 2.90 cachesize 150 Sep 09 22:36:01 volumio-goco dnsmasq[39221]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:36:01 volumio-goco dnsmasq-dhcp[39221]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:36:01 volumio-goco dnsmasq[39221]: reading /etc/resolv.conf Sep 09 22:36:01 volumio-goco dnsmasq[39221]: using nameserver 192.168.3.1#53 Sep 09 22:36:01 volumio-goco dnsmasq[39221]: using nameserver 91.102.231.241#53 Sep 09 22:36:01 volumio-goco dnsmasq[39221]: using nameserver 91.102.231.242#53 Sep 09 22:36:01 volumio-goco dnsmasq[39221]: using nameserver 208.67.222.222#53 Sep 09 22:36:01 volumio-goco dnsmasq[39221]: using nameserver 208.67.220.220#53 Sep 09 22:36:01 volumio-goco dnsmasq[39221]: read /etc/hosts - 4 names Sep 09 22:36:01 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:03 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2375. Sep 09 22:36:03 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:03 volumio-goco dnsmasq[39221]: exiting on receipt of SIGTERM Sep 09 22:36:03 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:36:03 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:03 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:03 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:36:03 volumio-goco hostapd[39228]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:36:03 volumio-goco hostapd[39228]: nl80211: Could not configure driver mode Sep 09 22:36:03 volumio-goco hostapd[39228]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:36:03 volumio-goco hostapd[39228]: nl80211 driver initialization failed. Sep 09 22:36:03 volumio-goco hostapd[39228]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:36:03 volumio-goco hostapd[39228]: wlan0: AP-DISABLED Sep 09 22:36:03 volumio-goco hostapd[39228]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:36:03 volumio-goco hostapd[39228]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:36:03 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:36:03 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:36:03 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:03 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:03 volumio-goco dnsmasq[39233]: started, version 2.90 cachesize 150 Sep 09 22:36:03 volumio-goco dnsmasq[39233]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:36:03 volumio-goco dnsmasq-dhcp[39233]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:36:03 volumio-goco dnsmasq[39233]: reading /etc/resolv.conf Sep 09 22:36:03 volumio-goco dnsmasq[39233]: using nameserver 192.168.3.1#53 Sep 09 22:36:03 volumio-goco dnsmasq[39233]: using nameserver 91.102.231.241#53 Sep 09 22:36:03 volumio-goco dnsmasq[39233]: using nameserver 91.102.231.242#53 Sep 09 22:36:03 volumio-goco dnsmasq[39233]: using nameserver 208.67.222.222#53 Sep 09 22:36:03 volumio-goco dnsmasq[39233]: using nameserver 208.67.220.220#53 Sep 09 22:36:03 volumio-goco dnsmasq[39233]: read /etc/hosts - 4 names Sep 09 22:36:03 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:05 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2376. Sep 09 22:36:05 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:05 volumio-goco dnsmasq[39233]: exiting on receipt of SIGTERM Sep 09 22:36:05 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:36:05 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:05 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:05 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:36:05 volumio-goco hostapd[39253]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:36:05 volumio-goco hostapd[39253]: nl80211: Could not configure driver mode Sep 09 22:36:05 volumio-goco hostapd[39253]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:36:05 volumio-goco hostapd[39253]: nl80211 driver initialization failed. Sep 09 22:36:05 volumio-goco hostapd[39253]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:36:05 volumio-goco hostapd[39253]: wlan0: AP-DISABLED Sep 09 22:36:05 volumio-goco hostapd[39253]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:36:05 volumio-goco hostapd[39253]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:36:05 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:36:05 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:36:05 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:05 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:05 volumio-goco dnsmasq[39258]: started, version 2.90 cachesize 150 Sep 09 22:36:05 volumio-goco dnsmasq[39258]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:36:05 volumio-goco dnsmasq-dhcp[39258]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:36:05 volumio-goco dnsmasq[39258]: reading /etc/resolv.conf Sep 09 22:36:05 volumio-goco dnsmasq[39258]: using nameserver 192.168.3.1#53 Sep 09 22:36:05 volumio-goco dnsmasq[39258]: using nameserver 91.102.231.241#53 Sep 09 22:36:05 volumio-goco dnsmasq[39258]: using nameserver 91.102.231.242#53 Sep 09 22:36:05 volumio-goco dnsmasq[39258]: using nameserver 208.67.222.222#53 Sep 09 22:36:05 volumio-goco dnsmasq[39258]: using nameserver 208.67.220.220#53 Sep 09 22:36:05 volumio-goco dnsmasq[39258]: read /etc/hosts - 4 names Sep 09 22:36:05 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:07 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2377. Sep 09 22:36:07 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:07 volumio-goco dnsmasq[39258]: exiting on receipt of SIGTERM Sep 09 22:36:07 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:36:07 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:07 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:07 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:36:07 volumio-goco hostapd[39265]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:36:07 volumio-goco hostapd[39265]: nl80211: Could not configure driver mode Sep 09 22:36:07 volumio-goco hostapd[39265]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:36:07 volumio-goco hostapd[39265]: nl80211 driver initialization failed. Sep 09 22:36:07 volumio-goco hostapd[39265]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:36:07 volumio-goco hostapd[39265]: wlan0: AP-DISABLED Sep 09 22:36:07 volumio-goco hostapd[39265]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:36:07 volumio-goco hostapd[39265]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:36:07 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:36:07 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:36:07 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:07 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:07 volumio-goco dnsmasq[39270]: started, version 2.90 cachesize 150 Sep 09 22:36:07 volumio-goco dnsmasq[39270]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:36:07 volumio-goco dnsmasq-dhcp[39270]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:36:07 volumio-goco dnsmasq[39270]: reading /etc/resolv.conf Sep 09 22:36:07 volumio-goco dnsmasq[39270]: using nameserver 192.168.3.1#53 Sep 09 22:36:07 volumio-goco dnsmasq[39270]: using nameserver 91.102.231.241#53 Sep 09 22:36:07 volumio-goco dnsmasq[39270]: using nameserver 91.102.231.242#53 Sep 09 22:36:07 volumio-goco dnsmasq[39270]: using nameserver 208.67.222.222#53 Sep 09 22:36:07 volumio-goco dnsmasq[39270]: using nameserver 208.67.220.220#53 Sep 09 22:36:07 volumio-goco dnsmasq[39270]: read /etc/hosts - 4 names Sep 09 22:36:07 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:09 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2378. Sep 09 22:36:09 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:09 volumio-goco dnsmasq[39270]: exiting on receipt of SIGTERM Sep 09 22:36:09 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:36:09 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:09 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:09 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:36:09 volumio-goco hostapd[39276]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:36:09 volumio-goco hostapd[39276]: nl80211: Could not configure driver mode Sep 09 22:36:09 volumio-goco hostapd[39276]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:36:09 volumio-goco hostapd[39276]: nl80211 driver initialization failed. Sep 09 22:36:09 volumio-goco hostapd[39276]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:36:09 volumio-goco hostapd[39276]: wlan0: AP-DISABLED Sep 09 22:36:09 volumio-goco hostapd[39276]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:36:09 volumio-goco hostapd[39276]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:36:09 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:36:09 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:36:09 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:09 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:10 volumio-goco dnsmasq[39281]: started, version 2.90 cachesize 150 Sep 09 22:36:10 volumio-goco dnsmasq[39281]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:36:10 volumio-goco dnsmasq-dhcp[39281]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:36:10 volumio-goco dnsmasq[39281]: reading /etc/resolv.conf Sep 09 22:36:10 volumio-goco dnsmasq[39281]: using nameserver 192.168.3.1#53 Sep 09 22:36:10 volumio-goco dnsmasq[39281]: using nameserver 91.102.231.241#53 Sep 09 22:36:10 volumio-goco dnsmasq[39281]: using nameserver 91.102.231.242#53 Sep 09 22:36:10 volumio-goco dnsmasq[39281]: using nameserver 208.67.222.222#53 Sep 09 22:36:10 volumio-goco dnsmasq[39281]: using nameserver 208.67.220.220#53 Sep 09 22:36:10 volumio-goco dnsmasq[39281]: read /etc/hosts - 4 names Sep 09 22:36:10 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:12 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2379. Sep 09 22:36:12 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:12 volumio-goco dnsmasq[39281]: exiting on receipt of SIGTERM Sep 09 22:36:12 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:36:12 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:12 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:12 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:36:12 volumio-goco hostapd[39288]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:36:12 volumio-goco hostapd[39288]: nl80211: Could not configure driver mode Sep 09 22:36:12 volumio-goco hostapd[39288]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:36:12 volumio-goco hostapd[39288]: nl80211 driver initialization failed. Sep 09 22:36:12 volumio-goco hostapd[39288]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:36:12 volumio-goco hostapd[39288]: wlan0: AP-DISABLED Sep 09 22:36:12 volumio-goco hostapd[39288]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:36:12 volumio-goco hostapd[39288]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:36:12 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:36:12 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:36:12 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:12 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:12 volumio-goco dnsmasq[39293]: started, version 2.90 cachesize 150 Sep 09 22:36:12 volumio-goco dnsmasq[39293]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:36:12 volumio-goco dnsmasq-dhcp[39293]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:36:12 volumio-goco dnsmasq[39293]: reading /etc/resolv.conf Sep 09 22:36:12 volumio-goco dnsmasq[39293]: using nameserver 192.168.3.1#53 Sep 09 22:36:12 volumio-goco dnsmasq[39293]: using nameserver 91.102.231.241#53 Sep 09 22:36:12 volumio-goco dnsmasq[39293]: using nameserver 91.102.231.242#53 Sep 09 22:36:12 volumio-goco dnsmasq[39293]: using nameserver 208.67.222.222#53 Sep 09 22:36:12 volumio-goco dnsmasq[39293]: using nameserver 208.67.220.220#53 Sep 09 22:36:12 volumio-goco dnsmasq[39293]: read /etc/hosts - 4 names Sep 09 22:36:12 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:12 volumio-goco systemd[1]: systemd-timedated.service: Deactivated successfully. Sep 09 22:36:14 volumio-goco volumio[1001]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Sep 09 22:36:14 volumio-goco volumio[1001]: info: Preload queue cleared Sep 09 22:36:14 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2380. Sep 09 22:36:14 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:14 volumio-goco dnsmasq[39293]: exiting on receipt of SIGTERM Sep 09 22:36:14 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:36:14 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:14 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:14 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:36:14 volumio-goco hostapd[39315]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:36:14 volumio-goco hostapd[39315]: nl80211: Could not configure driver mode Sep 09 22:36:14 volumio-goco hostapd[39315]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:36:14 volumio-goco hostapd[39315]: nl80211 driver initialization failed. Sep 09 22:36:14 volumio-goco hostapd[39315]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:36:14 volumio-goco hostapd[39315]: wlan0: AP-DISABLED Sep 09 22:36:14 volumio-goco hostapd[39315]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:36:14 volumio-goco hostapd[39315]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:36:14 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:36:14 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:36:14 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:14 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:14 volumio-goco dnsmasq[39320]: started, version 2.90 cachesize 150 Sep 09 22:36:14 volumio-goco dnsmasq[39320]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:36:14 volumio-goco dnsmasq-dhcp[39320]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:36:14 volumio-goco dnsmasq[39320]: reading /etc/resolv.conf Sep 09 22:36:14 volumio-goco dnsmasq[39320]: using nameserver 192.168.3.1#53 Sep 09 22:36:14 volumio-goco dnsmasq[39320]: using nameserver 91.102.231.241#53 Sep 09 22:36:14 volumio-goco dnsmasq[39320]: using nameserver 91.102.231.242#53 Sep 09 22:36:14 volumio-goco dnsmasq[39320]: using nameserver 208.67.222.222#53 Sep 09 22:36:14 volumio-goco dnsmasq[39320]: using nameserver 208.67.220.220#53 Sep 09 22:36:14 volumio-goco dnsmasq[39320]: read /etc/hosts - 4 names Sep 09 22:36:14 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:16 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2381. Sep 09 22:36:16 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:16 volumio-goco dnsmasq[39320]: exiting on receipt of SIGTERM Sep 09 22:36:16 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:36:16 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:16 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:16 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:36:16 volumio-goco hostapd[39326]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:36:16 volumio-goco hostapd[39326]: nl80211: Could not configure driver mode Sep 09 22:36:16 volumio-goco hostapd[39326]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:36:16 volumio-goco hostapd[39326]: nl80211 driver initialization failed. Sep 09 22:36:16 volumio-goco hostapd[39326]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:36:16 volumio-goco hostapd[39326]: wlan0: AP-DISABLED Sep 09 22:36:16 volumio-goco hostapd[39326]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:36:16 volumio-goco hostapd[39326]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:36:16 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:36:16 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:36:16 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:16 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:16 volumio-goco dnsmasq[39331]: started, version 2.90 cachesize 150 Sep 09 22:36:16 volumio-goco dnsmasq[39331]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:36:16 volumio-goco dnsmasq-dhcp[39331]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:36:16 volumio-goco dnsmasq[39331]: reading /etc/resolv.conf Sep 09 22:36:16 volumio-goco dnsmasq[39331]: using nameserver 192.168.3.1#53 Sep 09 22:36:16 volumio-goco dnsmasq[39331]: using nameserver 91.102.231.241#53 Sep 09 22:36:16 volumio-goco dnsmasq[39331]: using nameserver 91.102.231.242#53 Sep 09 22:36:16 volumio-goco dnsmasq[39331]: using nameserver 208.67.222.222#53 Sep 09 22:36:16 volumio-goco dnsmasq[39331]: using nameserver 208.67.220.220#53 Sep 09 22:36:16 volumio-goco dnsmasq[39331]: read /etc/hosts - 4 names Sep 09 22:36:16 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:16 volumio-goco volumio[1001]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Sep 09 22:36:16 volumio-goco volumio[1001]: info: TuneIn handleBrowseUri: tunein/local Sep 09 22:36:17 volumio-goco volumio[1001]: info: Preload queue cleared Sep 09 22:36:18 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2382. Sep 09 22:36:18 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:18 volumio-goco dnsmasq[39331]: exiting on receipt of SIGTERM Sep 09 22:36:18 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:36:18 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:18 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:18 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:36:18 volumio-goco hostapd[39337]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:36:18 volumio-goco hostapd[39337]: nl80211: Could not configure driver mode Sep 09 22:36:18 volumio-goco hostapd[39337]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:36:18 volumio-goco hostapd[39337]: nl80211 driver initialization failed. Sep 09 22:36:18 volumio-goco hostapd[39337]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:36:18 volumio-goco hostapd[39337]: wlan0: AP-DISABLED Sep 09 22:36:18 volumio-goco hostapd[39337]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:36:18 volumio-goco hostapd[39337]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:36:18 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:36:18 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:36:18 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:18 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:19 volumio-goco dnsmasq[39342]: started, version 2.90 cachesize 150 Sep 09 22:36:19 volumio-goco dnsmasq[39342]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:36:19 volumio-goco dnsmasq-dhcp[39342]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:36:19 volumio-goco dnsmasq[39342]: reading /etc/resolv.conf Sep 09 22:36:19 volumio-goco dnsmasq[39342]: using nameserver 192.168.3.1#53 Sep 09 22:36:19 volumio-goco dnsmasq[39342]: using nameserver 91.102.231.241#53 Sep 09 22:36:19 volumio-goco dnsmasq[39342]: using nameserver 91.102.231.242#53 Sep 09 22:36:19 volumio-goco dnsmasq[39342]: using nameserver 208.67.222.222#53 Sep 09 22:36:19 volumio-goco dnsmasq[39342]: using nameserver 208.67.220.220#53 Sep 09 22:36:19 volumio-goco dnsmasq[39342]: read /etc/hosts - 4 names Sep 09 22:36:19 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:21 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2383. Sep 09 22:36:21 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:21 volumio-goco dnsmasq[39342]: exiting on receipt of SIGTERM Sep 09 22:36:21 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:36:21 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:21 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:21 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:36:21 volumio-goco hostapd[39349]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:36:21 volumio-goco hostapd[39349]: nl80211: Could not configure driver mode Sep 09 22:36:21 volumio-goco hostapd[39349]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:36:21 volumio-goco hostapd[39349]: nl80211 driver initialization failed. Sep 09 22:36:21 volumio-goco hostapd[39349]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:36:21 volumio-goco hostapd[39349]: wlan0: AP-DISABLED Sep 09 22:36:21 volumio-goco hostapd[39349]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:36:21 volumio-goco hostapd[39349]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:36:21 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:36:21 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:36:21 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:21 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:21 volumio-goco dnsmasq[39354]: started, version 2.90 cachesize 150 Sep 09 22:36:21 volumio-goco dnsmasq[39354]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:36:21 volumio-goco dnsmasq-dhcp[39354]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:36:21 volumio-goco dnsmasq[39354]: reading /etc/resolv.conf Sep 09 22:36:21 volumio-goco dnsmasq[39354]: using nameserver 192.168.3.1#53 Sep 09 22:36:21 volumio-goco dnsmasq[39354]: using nameserver 91.102.231.241#53 Sep 09 22:36:21 volumio-goco dnsmasq[39354]: using nameserver 91.102.231.242#53 Sep 09 22:36:21 volumio-goco dnsmasq[39354]: using nameserver 208.67.222.222#53 Sep 09 22:36:21 volumio-goco dnsmasq[39354]: using nameserver 208.67.220.220#53 Sep 09 22:36:21 volumio-goco dnsmasq[39354]: read /etc/hosts - 4 names Sep 09 22:36:21 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:23 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2384. Sep 09 22:36:23 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:23 volumio-goco dnsmasq[39354]: exiting on receipt of SIGTERM Sep 09 22:36:23 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:36:23 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:23 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:23 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:36:23 volumio-goco hostapd[39360]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:36:23 volumio-goco hostapd[39360]: nl80211: Could not configure driver mode Sep 09 22:36:23 volumio-goco hostapd[39360]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:36:23 volumio-goco hostapd[39360]: nl80211 driver initialization failed. Sep 09 22:36:23 volumio-goco hostapd[39360]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:36:23 volumio-goco hostapd[39360]: wlan0: AP-DISABLED Sep 09 22:36:23 volumio-goco hostapd[39360]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:36:23 volumio-goco hostapd[39360]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:36:23 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:36:23 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:36:23 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:23 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:23 volumio-goco dnsmasq[39366]: started, version 2.90 cachesize 150 Sep 09 22:36:23 volumio-goco dnsmasq[39366]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:36:23 volumio-goco dnsmasq-dhcp[39366]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:36:23 volumio-goco dnsmasq[39366]: reading /etc/resolv.conf Sep 09 22:36:23 volumio-goco dnsmasq[39366]: using nameserver 192.168.3.1#53 Sep 09 22:36:23 volumio-goco dnsmasq[39366]: using nameserver 91.102.231.241#53 Sep 09 22:36:23 volumio-goco dnsmasq[39366]: using nameserver 91.102.231.242#53 Sep 09 22:36:23 volumio-goco dnsmasq[39366]: using nameserver 208.67.222.222#53 Sep 09 22:36:23 volumio-goco dnsmasq[39366]: using nameserver 208.67.220.220#53 Sep 09 22:36:23 volumio-goco dnsmasq[39366]: read /etc/hosts - 4 names Sep 09 22:36:23 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:23 volumio-goco volumio[1001]: info: Preload queue cleared Sep 09 22:36:23 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 09 22:36:23 volumio-goco volumio[1001]: info: CoreStateMachine::ClearQueue Sep 09 22:36:23 volumio-goco volumio[1001]: info: CoreStateMachine::stop Sep 09 22:36:23 volumio-goco volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 22:36:23 volumio-goco volumio[1001]: info: CoreStateMachine::stPlaybackTimer Sep 09 22:36:23 volumio-goco volumio[1001]: info: CoreStateMachine::updateTrackBlock Sep 09 22:36:23 volumio-goco volumio[1001]: info: CorePlayQueue::getTrackBlock Sep 09 22:36:23 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:36:23 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 3 Sep 09 22:36:23 volumio-goco volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 09 22:36:23 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:36:23 volumio-goco volumio[1001]: info: CoreStateMachine::serviceStop Sep 09 22:36:23 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 3 Sep 09 22:36:23 volumio-goco volumio[1001]: info: CoreCommandRouter::serviceStop Sep 09 22:36:23 volumio-goco volumio[1001]: info: ControllerMpd::stop Sep 09 22:36:23 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand stop Sep 09 22:36:23 volumio-goco volumio[1001]: info: CorePlayQueue::clearPlayQueue Sep 09 22:36:23 volumio-goco volumio[1001]: info: CorePlayQueue::saveQueue Sep 09 22:36:23 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushQueue Sep 09 22:36:23 volumio-goco volumio[1001]: info: CoreStateMachine::addQueueItems Sep 09 22:36:23 volumio-goco volumio[1001]: info: CorePlayQueue::addQueueItems Sep 09 22:36:23 volumio-goco volumio[1001]: info: Preload queue cleared Sep 09 22:36:23 volumio-goco volumio[1001]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s243340 Sep 09 22:36:23 volumio-goco volumio[1001]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 09 22:36:23 volumio-goco volumio[1001]: info: FusionDsp - Volumio is not playing Sep 09 22:36:23 volumio-goco volumio[1001]: info: FusionDsp - Clipped samples monitor stopped Sep 09 22:36:23 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:36:23 volumio-goco volumio[1001]: info: Sep 09 22:36:23 volumio-goco volumio[1001]: ---------------------------- MPD announces state update: player Sep 09 22:36:23 volumio-goco volumio[1001]: info: sendMpdCommand stop took 128 milliseconds Sep 09 22:36:23 volumio-goco volumio[1001]: info: ControllerMpd::getState Sep 09 22:36:23 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Sep 09 22:36:23 volumio-goco volumio[1001]: info: Sep 09 22:36:23 volumio-goco volumio[1001]: ---------------------------- MPD announces state update: player Sep 09 22:36:23 volumio-goco volumio[1001]: info: ControllerMpd::getState Sep 09 22:36:23 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Sep 09 22:36:23 volumio-goco volumio[1001]: info: sendMpdCommand status took 9 milliseconds Sep 09 22:36:23 volumio-goco volumio[1001]: info: sendMpdCommand status took 7 milliseconds Sep 09 22:36:23 volumio-goco volumio[1001]: verbose: ControllerMpd::parseState Sep 09 22:36:23 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 22:36:23 volumio-goco volumio[1001]: verbose: ControllerMpd::parseState Sep 09 22:36:23 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 22:36:23 volumio-goco volumio[1001]: info: sendMpdCommand playlistinfo took 11 milliseconds Sep 09 22:36:23 volumio-goco volumio[1001]: info: sendMpdCommand playlistinfo took 13 milliseconds Sep 09 22:36:23 volumio-goco volumio[1001]: verbose: ControllerMpd::parseTrackInfo Sep 09 22:36:23 volumio-goco volumio[1001]: verbose: ControllerMpd::parseTrackInfo Sep 09 22:36:23 volumio-goco volumio[1001]: info: ControllerMpd::pushState Sep 09 22:36:23 volumio-goco volumio[1001]: info: CoreCommandRouter::servicePushState Sep 09 22:36:23 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:36:23 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 3 Sep 09 22:36:23 volumio-goco volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 09 22:36:23 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:36:23 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 3 Sep 09 22:36:23 volumio-goco volumio[1001]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Girly","artist":"Juli Fabian & Zoohacker","album":"Girly [EP]","uri":"INTERNAL/Music/Elektro Swing/1. Girly.mp3","trackType":"mp3"} Sep 09 22:36:23 volumio-goco volumio[1001]: verbose: CURRENT POSITION 3 Sep 09 22:36:23 volumio-goco volumio[1001]: info: CoreStateMachine::syncState stateService stop Sep 09 22:36:23 volumio-goco volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Sep 09 22:36:23 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:36:23 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 3 Sep 09 22:36:23 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:36:23 volumio-goco volumio[1001]: info: No code Sep 09 22:36:23 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:36:23 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 3 Sep 09 22:36:23 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:36:23 volumio-goco volumio[1001]: info: ControllerMpd::pushState Sep 09 22:36:23 volumio-goco volumio[1001]: info: CoreCommandRouter::servicePushState Sep 09 22:36:23 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:36:23 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 3 Sep 09 22:36:23 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:36:23 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 3 Sep 09 22:36:23 volumio-goco volumio[1001]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Girly","artist":"Juli Fabian & Zoohacker","album":"Girly [EP]","uri":"INTERNAL/Music/Elektro Swing/1. Girly.mp3","trackType":"mp3"} Sep 09 22:36:23 volumio-goco volumio[1001]: verbose: CURRENT POSITION 3 Sep 09 22:36:23 volumio-goco volumio[1001]: info: CoreStateMachine::syncState stateService stop Sep 09 22:36:23 volumio-goco volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Sep 09 22:36:23 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:36:23 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 3 Sep 09 22:36:23 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:36:23 volumio-goco volumio[1001]: info: No code Sep 09 22:36:23 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:36:23 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 3 Sep 09 22:36:23 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:36:23 volumio-goco volumio[1001]: info: ------------------------------ 81ms Sep 09 22:36:23 volumio-goco volumio[1001]: info: ------------------------------ 85ms Sep 09 22:36:23 volumio-goco volumio[1001]: info: FusionDsp - Volumio is not playing Sep 09 22:36:23 volumio-goco volumio[1001]: info: FusionDsp - Clipped samples monitor stopped Sep 09 22:36:23 volumio-goco volumio[1001]: info: FusionDsp - Volumio is not playing Sep 09 22:36:23 volumio-goco volumio[1001]: info: FusionDsp - Clipped samples monitor stopped Sep 09 22:36:23 volumio-goco volumio[1001]: info: FusionDsp - Volumio is not playing Sep 09 22:36:23 volumio-goco volumio[1001]: info: FusionDsp - Clipped samples monitor stopped Sep 09 22:36:23 volumio-goco volumio[1001]: info: FusionDsp - Volumio is not playing Sep 09 22:36:23 volumio-goco volumio[1001]: info: FusionDsp - Clipped samples monitor stopped Sep 09 22:36:23 volumio-goco volumio[1001]: info: FusionDsp - Volumio is not playing Sep 09 22:36:23 volumio-goco volumio[1001]: info: FusionDsp - Clipped samples monitor stopped Sep 09 22:36:23 volumio-goco volumio[1001]: info: FusionDsp - Volumio is not playing Sep 09 22:36:23 volumio-goco volumio[1001]: info: FusionDsp - Clipped samples monitor stopped Sep 09 22:36:23 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:36:23 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:36:23 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:36:23 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:36:23 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:36:23 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:36:24 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushQueue Sep 09 22:36:24 volumio-goco volumio[1001]: info: CorePlayQueue::saveQueue Sep 09 22:36:24 volumio-goco volumio[1001]: info: CoreStateMachine::updateTrackBlock Sep 09 22:36:24 volumio-goco volumio[1001]: info: CorePlayQueue::getTrackBlock Sep 09 22:36:24 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPlay Sep 09 22:36:24 volumio-goco volumio[1001]: info: CoreStateMachine::play index 0 Sep 09 22:36:24 volumio-goco volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 22:36:24 volumio-goco volumio[1001]: info: CoreStateMachine::stop Sep 09 22:36:24 volumio-goco volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 22:36:24 volumio-goco volumio[1001]: info: CoreStateMachine::play index undefined Sep 09 22:36:24 volumio-goco volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 22:36:24 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:36:24 volumio-goco volumio[1001]: info: CoreStateMachine::startPlaybackTimer Sep 09 22:36:24 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:36:24 volumio-goco volumio[1001]: info: [1757450184259] ControllerWebradio::clearAddPlayTrack Sep 09 22:36:24 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand stop Sep 09 22:36:24 volumio-goco volumio[1001]: info: sendMpdCommand stop took 14 milliseconds Sep 09 22:36:24 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand clear Sep 09 22:36:24 volumio-goco volumio[1001]: info: Sep 09 22:36:24 volumio-goco volumio[1001]: ---------------------------- MPD announces system playlist update Sep 09 22:36:24 volumio-goco volumio[1001]: info: Ignoring MPD Status Update Sep 09 22:36:24 volumio-goco volumio[1001]: info: sendMpdCommand clear took 8 milliseconds Sep 09 22:36:24 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand load "http://hosting.42netmedia.com:8110" Sep 09 22:36:24 volumio-goco volumio[1001]: info: Sep 09 22:36:24 volumio-goco volumio[1001]: ---------------------------- MPD announces system playlist update Sep 09 22:36:24 volumio-goco volumio[1001]: info: Ignoring MPD Status Update Sep 09 22:36:25 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2385. Sep 09 22:36:25 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:25 volumio-goco dnsmasq[39366]: exiting on receipt of SIGTERM Sep 09 22:36:25 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:36:25 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:25 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:25 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:36:25 volumio-goco hostapd[39390]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:36:25 volumio-goco hostapd[39390]: nl80211: Could not configure driver mode Sep 09 22:36:25 volumio-goco hostapd[39390]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:36:25 volumio-goco hostapd[39390]: nl80211 driver initialization failed. Sep 09 22:36:25 volumio-goco hostapd[39390]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:36:25 volumio-goco hostapd[39390]: wlan0: AP-DISABLED Sep 09 22:36:25 volumio-goco hostapd[39390]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:36:25 volumio-goco hostapd[39390]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:36:25 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:36:25 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:36:25 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:25 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:25 volumio-goco dnsmasq[39395]: started, version 2.90 cachesize 150 Sep 09 22:36:25 volumio-goco dnsmasq[39395]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:36:25 volumio-goco dnsmasq-dhcp[39395]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:36:25 volumio-goco dnsmasq[39395]: reading /etc/resolv.conf Sep 09 22:36:25 volumio-goco dnsmasq[39395]: using nameserver 192.168.3.1#53 Sep 09 22:36:25 volumio-goco dnsmasq[39395]: using nameserver 91.102.231.241#53 Sep 09 22:36:25 volumio-goco dnsmasq[39395]: using nameserver 91.102.231.242#53 Sep 09 22:36:25 volumio-goco dnsmasq[39395]: using nameserver 208.67.222.222#53 Sep 09 22:36:25 volumio-goco dnsmasq[39395]: using nameserver 208.67.220.220#53 Sep 09 22:36:25 volumio-goco dnsmasq[39395]: read /etc/hosts - 4 names Sep 09 22:36:25 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:27 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2386. Sep 09 22:36:27 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:27 volumio-goco dnsmasq[39395]: exiting on receipt of SIGTERM Sep 09 22:36:27 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:36:27 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:27 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:27 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:36:27 volumio-goco hostapd[39402]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:36:27 volumio-goco hostapd[39402]: nl80211: Could not configure driver mode Sep 09 22:36:27 volumio-goco hostapd[39402]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:36:27 volumio-goco hostapd[39402]: nl80211 driver initialization failed. Sep 09 22:36:27 volumio-goco hostapd[39402]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:36:27 volumio-goco hostapd[39402]: wlan0: AP-DISABLED Sep 09 22:36:27 volumio-goco hostapd[39402]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:36:27 volumio-goco hostapd[39402]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:36:27 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:36:27 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:36:27 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:27 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:28 volumio-goco dnsmasq[39407]: started, version 2.90 cachesize 150 Sep 09 22:36:28 volumio-goco dnsmasq[39407]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:36:28 volumio-goco dnsmasq-dhcp[39407]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:36:28 volumio-goco dnsmasq[39407]: reading /etc/resolv.conf Sep 09 22:36:28 volumio-goco dnsmasq[39407]: using nameserver 192.168.3.1#53 Sep 09 22:36:28 volumio-goco dnsmasq[39407]: using nameserver 91.102.231.241#53 Sep 09 22:36:28 volumio-goco dnsmasq[39407]: using nameserver 91.102.231.242#53 Sep 09 22:36:28 volumio-goco dnsmasq[39407]: using nameserver 208.67.222.222#53 Sep 09 22:36:28 volumio-goco dnsmasq[39407]: using nameserver 208.67.220.220#53 Sep 09 22:36:28 volumio-goco dnsmasq[39407]: read /etc/hosts - 4 names Sep 09 22:36:28 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:29 volumio-goco volumio[1001]: info: Preload queue cleared Sep 09 22:36:29 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 09 22:36:29 volumio-goco volumio[1001]: info: CoreStateMachine::ClearQueue Sep 09 22:36:29 volumio-goco volumio[1001]: info: CoreStateMachine::stop Sep 09 22:36:29 volumio-goco volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 22:36:29 volumio-goco volumio[1001]: info: CorePlayQueue::clearPlayQueue Sep 09 22:36:29 volumio-goco volumio[1001]: info: CorePlayQueue::saveQueue Sep 09 22:36:29 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushQueue Sep 09 22:36:29 volumio-goco volumio[1001]: info: CoreStateMachine::addQueueItems Sep 09 22:36:29 volumio-goco volumio[1001]: info: CorePlayQueue::addQueueItems Sep 09 22:36:29 volumio-goco volumio[1001]: info: Preload queue cleared Sep 09 22:36:29 volumio-goco volumio[1001]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s266748 Sep 09 22:36:29 volumio-goco volumio[1001]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 09 22:36:30 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushQueue Sep 09 22:36:30 volumio-goco volumio[1001]: info: CorePlayQueue::saveQueue Sep 09 22:36:30 volumio-goco volumio[1001]: info: CoreStateMachine::updateTrackBlock Sep 09 22:36:30 volumio-goco volumio[1001]: info: CorePlayQueue::getTrackBlock Sep 09 22:36:30 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPlay Sep 09 22:36:30 volumio-goco volumio[1001]: info: CoreStateMachine::play index 0 Sep 09 22:36:30 volumio-goco volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 22:36:30 volumio-goco volumio[1001]: info: CoreStateMachine::stop Sep 09 22:36:30 volumio-goco volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 22:36:30 volumio-goco volumio[1001]: info: CoreStateMachine::play index undefined Sep 09 22:36:30 volumio-goco volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 22:36:30 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:36:30 volumio-goco volumio[1001]: info: CoreStateMachine::startPlaybackTimer Sep 09 22:36:30 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:36:30 volumio-goco volumio[1001]: info: [1757450190094] ControllerWebradio::clearAddPlayTrack Sep 09 22:36:30 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand stop Sep 09 22:36:30 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2387. Sep 09 22:36:30 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:30 volumio-goco dnsmasq[39407]: exiting on receipt of SIGTERM Sep 09 22:36:30 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:36:30 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:30 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:30 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:36:30 volumio-goco hostapd[39414]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:36:30 volumio-goco hostapd[39414]: nl80211: Could not configure driver mode Sep 09 22:36:30 volumio-goco hostapd[39414]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:36:30 volumio-goco hostapd[39414]: nl80211 driver initialization failed. Sep 09 22:36:30 volumio-goco hostapd[39414]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:36:30 volumio-goco hostapd[39414]: wlan0: AP-DISABLED Sep 09 22:36:30 volumio-goco hostapd[39414]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:36:30 volumio-goco hostapd[39414]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:36:30 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:36:30 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:36:30 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:30 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:30 volumio-goco dnsmasq[39419]: started, version 2.90 cachesize 150 Sep 09 22:36:30 volumio-goco dnsmasq[39419]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:36:30 volumio-goco dnsmasq-dhcp[39419]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:36:30 volumio-goco dnsmasq[39419]: reading /etc/resolv.conf Sep 09 22:36:30 volumio-goco dnsmasq[39419]: using nameserver 192.168.3.1#53 Sep 09 22:36:30 volumio-goco dnsmasq[39419]: using nameserver 91.102.231.241#53 Sep 09 22:36:30 volumio-goco dnsmasq[39419]: using nameserver 91.102.231.242#53 Sep 09 22:36:30 volumio-goco dnsmasq[39419]: using nameserver 208.67.222.222#53 Sep 09 22:36:30 volumio-goco dnsmasq[39419]: using nameserver 208.67.220.220#53 Sep 09 22:36:30 volumio-goco dnsmasq[39419]: read /etc/hosts - 4 names Sep 09 22:36:30 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:32 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2388. Sep 09 22:36:32 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:32 volumio-goco dnsmasq[39419]: exiting on receipt of SIGTERM Sep 09 22:36:32 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:36:32 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:32 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:32 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:36:32 volumio-goco hostapd[39425]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:36:32 volumio-goco hostapd[39425]: nl80211: Could not configure driver mode Sep 09 22:36:32 volumio-goco hostapd[39425]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:36:32 volumio-goco hostapd[39425]: nl80211 driver initialization failed. Sep 09 22:36:32 volumio-goco hostapd[39425]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:36:32 volumio-goco hostapd[39425]: wlan0: AP-DISABLED Sep 09 22:36:32 volumio-goco hostapd[39425]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:36:32 volumio-goco hostapd[39425]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:36:32 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:36:32 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:36:32 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:32 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:32 volumio-goco dnsmasq[39430]: started, version 2.90 cachesize 150 Sep 09 22:36:32 volumio-goco dnsmasq[39430]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:36:32 volumio-goco dnsmasq-dhcp[39430]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:36:32 volumio-goco dnsmasq[39430]: reading /etc/resolv.conf Sep 09 22:36:32 volumio-goco dnsmasq[39430]: using nameserver 192.168.3.1#53 Sep 09 22:36:32 volumio-goco dnsmasq[39430]: using nameserver 91.102.231.241#53 Sep 09 22:36:32 volumio-goco dnsmasq[39430]: using nameserver 91.102.231.242#53 Sep 09 22:36:32 volumio-goco dnsmasq[39430]: using nameserver 208.67.222.222#53 Sep 09 22:36:32 volumio-goco dnsmasq[39430]: using nameserver 208.67.220.220#53 Sep 09 22:36:32 volumio-goco dnsmasq[39430]: read /etc/hosts - 4 names Sep 09 22:36:32 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:34 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2389. Sep 09 22:36:34 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:34 volumio-goco dnsmasq[39430]: exiting on receipt of SIGTERM Sep 09 22:36:34 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:36:34 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:34 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:34 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:36:34 volumio-goco hostapd[39450]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:36:34 volumio-goco hostapd[39450]: nl80211: Could not configure driver mode Sep 09 22:36:34 volumio-goco hostapd[39450]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:36:34 volumio-goco hostapd[39450]: nl80211 driver initialization failed. Sep 09 22:36:34 volumio-goco hostapd[39450]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:36:34 volumio-goco hostapd[39450]: wlan0: AP-DISABLED Sep 09 22:36:34 volumio-goco hostapd[39450]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:36:34 volumio-goco hostapd[39450]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:36:34 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:36:34 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:36:34 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:34 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:34 volumio-goco dnsmasq[39455]: started, version 2.90 cachesize 150 Sep 09 22:36:34 volumio-goco dnsmasq[39455]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:36:34 volumio-goco dnsmasq-dhcp[39455]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:36:34 volumio-goco dnsmasq[39455]: reading /etc/resolv.conf Sep 09 22:36:34 volumio-goco dnsmasq[39455]: using nameserver 192.168.3.1#53 Sep 09 22:36:34 volumio-goco dnsmasq[39455]: using nameserver 91.102.231.241#53 Sep 09 22:36:34 volumio-goco dnsmasq[39455]: using nameserver 91.102.231.242#53 Sep 09 22:36:34 volumio-goco dnsmasq[39455]: using nameserver 208.67.222.222#53 Sep 09 22:36:34 volumio-goco dnsmasq[39455]: using nameserver 208.67.220.220#53 Sep 09 22:36:34 volumio-goco dnsmasq[39455]: read /etc/hosts - 4 names Sep 09 22:36:34 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:34 volumio-goco volumio[1001]: error: updateQueue error: null Sep 09 22:36:34 volumio-goco volumio[1001]: error: updateQueue error: null Sep 09 22:36:34 volumio-goco volumio[1001]: info: ------------------------------ 10678ms Sep 09 22:36:34 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand add "http://hosting.42netmedia.com:8110" Sep 09 22:36:34 volumio-goco volumio[1001]: info: ------------------------------ 10668ms Sep 09 22:36:34 volumio-goco volumio[1001]: info: sendMpdCommand stop took 4863 milliseconds Sep 09 22:36:34 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand clear Sep 09 22:36:34 volumio-goco volumio[1001]: info: Sep 09 22:36:34 volumio-goco volumio[1001]: ---------------------------- MPD announces system playlist update Sep 09 22:36:34 volumio-goco volumio[1001]: info: Ignoring MPD Status Update Sep 09 22:36:34 volumio-goco volumio[1001]: info: Sep 09 22:36:34 volumio-goco volumio[1001]: ---------------------------- MPD announces system playlist update Sep 09 22:36:34 volumio-goco volumio[1001]: info: Ignoring MPD Status Update Sep 09 22:36:34 volumio-goco volumio[1001]: info: sendMpdCommand add "http://hosting.42netmedia.com:8110" took 4 milliseconds Sep 09 22:36:34 volumio-goco volumio[1001]: info: sendMpdCommand clear took 2 milliseconds Sep 09 22:36:34 volumio-goco volumio[1001]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 09 22:36:34 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand play Sep 09 22:36:34 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand load "http://webradio.sksyu.net:8000/nolimithq.ogg" Sep 09 22:36:34 volumio-goco volumio[1001]: info: Sep 09 22:36:34 volumio-goco volumio[1001]: ---------------------------- MPD announces system playlist update Sep 09 22:36:34 volumio-goco volumio[1001]: info: Ignoring MPD Status Update Sep 09 22:36:35 volumio-goco volumio[1001]: error: updateQueue error: null Sep 09 22:36:35 volumio-goco volumio[1001]: error: updateQueue error: null Sep 09 22:36:35 volumio-goco volumio[1001]: error: updateQueue error: null Sep 09 22:36:35 volumio-goco volumio[1001]: info: ------------------------------ 92ms Sep 09 22:36:35 volumio-goco volumio[1001]: info: ------------------------------ 91ms Sep 09 22:36:35 volumio-goco volumio[1001]: info: sendMpdCommand play took 91 milliseconds Sep 09 22:36:35 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand add "http://webradio.sksyu.net:8000/nolimithq.ogg" Sep 09 22:36:35 volumio-goco volumio[1001]: info: ------------------------------ 91ms Sep 09 22:36:35 volumio-goco volumio[1001]: info: Sep 09 22:36:35 volumio-goco volumio[1001]: ---------------------------- MPD announces system playlist update Sep 09 22:36:35 volumio-goco volumio[1001]: info: Ignoring MPD Status Update Sep 09 22:36:35 volumio-goco volumio[1001]: info: Sep 09 22:36:35 volumio-goco volumio[1001]: ---------------------------- MPD announces system playlist update Sep 09 22:36:35 volumio-goco volumio[1001]: info: Ignoring MPD Status Update Sep 09 22:36:35 volumio-goco volumio[1001]: info: sendMpdCommand add "http://webradio.sksyu.net:8000/nolimithq.ogg" took 3 milliseconds Sep 09 22:36:35 volumio-goco volumio[1001]: info: ------------------------------ 2ms Sep 09 22:36:35 volumio-goco volumio[1001]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 09 22:36:35 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand play Sep 09 22:36:35 volumio-goco volumio[1001]: info: Sep 09 22:36:35 volumio-goco volumio[1001]: ---------------------------- MPD announces system playlist update Sep 09 22:36:35 volumio-goco volumio[1001]: info: Ignoring MPD Status Update Sep 09 22:36:35 volumio-goco volumio[1001]: info: ------------------------------ 8ms Sep 09 22:36:35 volumio-goco volumio[1001]: info: sendMpdCommand play took 10 milliseconds Sep 09 22:36:35 volumio-goco volumio[1001]: info: ------------------------------ 6ms Sep 09 22:36:35 volumio-goco volumio[1001]: info: Sep 09 22:36:35 volumio-goco volumio[1001]: ---------------------------- MPD announces state update: player Sep 09 22:36:35 volumio-goco volumio[1001]: info: ControllerMpd::getState Sep 09 22:36:35 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Sep 09 22:36:35 volumio-goco volumio[1001]: info: Sep 09 22:36:35 volumio-goco volumio[1001]: ---------------------------- MPD announces state update: player Sep 09 22:36:35 volumio-goco volumio[1001]: info: ControllerMpd::getState Sep 09 22:36:35 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Sep 09 22:36:35 volumio-goco volumio[1001]: info: Sep 09 22:36:35 volumio-goco volumio[1001]: ---------------------------- MPD announces state update: player Sep 09 22:36:35 volumio-goco volumio[1001]: info: ControllerMpd::getState Sep 09 22:36:35 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Sep 09 22:36:35 volumio-goco volumio[1001]: info: Sep 09 22:36:35 volumio-goco volumio[1001]: ---------------------------- MPD announces state update: player Sep 09 22:36:35 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 09 22:36:35 volumio-goco volumio[1001]: info: sendMpdCommand status took 7 milliseconds Sep 09 22:36:35 volumio-goco volumio[1001]: info: ControllerMpd::getState Sep 09 22:36:35 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Sep 09 22:36:35 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 09 22:36:35 volumio-goco volumio[1001]: info: sendMpdCommand status took 12 milliseconds Sep 09 22:36:35 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 09 22:36:35 volumio-goco volumio[1001]: info: sendMpdCommand status took 12 milliseconds Sep 09 22:36:35 volumio-goco volumio[1001]: verbose: ControllerMpd::parseState Sep 09 22:36:35 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 22:36:35 volumio-goco volumio[1001]: verbose: ControllerMpd::parseState Sep 09 22:36:35 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 22:36:35 volumio-goco volumio[1001]: verbose: ControllerMpd::parseState Sep 09 22:36:35 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 22:36:35 volumio-goco volumio[1001]: info: sendMpdCommand clearerror took 17 milliseconds Sep 09 22:36:35 volumio-goco volumio[1001]: info: sendMpdCommand status took 18 milliseconds Sep 09 22:36:35 volumio-goco volumio[1001]: info: sendMpdCommand clearerror took 13 milliseconds Sep 09 22:36:35 volumio-goco volumio[1001]: info: sendMpdCommand clearerror took 12 milliseconds Sep 09 22:36:35 volumio-goco volumio[1001]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 09 22:36:35 volumio-goco volumio[1001]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 09 22:36:35 volumio-goco volumio[1001]: verbose: ControllerMpd::parseState Sep 09 22:36:35 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 22:36:35 volumio-goco volumio[1001]: verbose: ControllerMpd::parseTrackInfo Sep 09 22:36:35 volumio-goco volumio[1001]: verbose: ControllerMpd::parseTrackInfo Sep 09 22:36:35 volumio-goco volumio[1001]: info: ControllerMpd::pushState Sep 09 22:36:35 volumio-goco volumio[1001]: info: CoreCommandRouter::servicePushState Sep 09 22:36:35 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:36:35 volumio-goco volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 09 22:36:35 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:36:35 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:36:35 volumio-goco volumio[1001]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"nolimithq.ogg","artist":null,"album":null,"uri":"http://webradio.sksyu.net:8000/nolimithq.ogg","trackType":"ogg"} Sep 09 22:36:35 volumio-goco volumio[1001]: verbose: CURRENT POSITION 0 Sep 09 22:36:35 volumio-goco volumio[1001]: info: CoreStateMachine::syncState stateService stop Sep 09 22:36:35 volumio-goco volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Sep 09 22:36:35 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:36:35 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:36:35 volumio-goco volumio[1001]: info: No code Sep 09 22:36:35 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:36:35 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:36:35 volumio-goco volumio[1001]: info: ControllerMpd::pushState Sep 09 22:36:35 volumio-goco volumio[1001]: info: CoreCommandRouter::servicePushState Sep 09 22:36:35 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:36:35 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:36:35 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:36:35 volumio-goco volumio[1001]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"nolimithq.ogg","artist":null,"album":null,"uri":"http://webradio.sksyu.net:8000/nolimithq.ogg","trackType":"ogg"} Sep 09 22:36:35 volumio-goco volumio[1001]: verbose: CURRENT POSITION 0 Sep 09 22:36:35 volumio-goco volumio[1001]: info: CoreStateMachine::syncState stateService stop Sep 09 22:36:35 volumio-goco volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Sep 09 22:36:35 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:36:35 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:36:35 volumio-goco volumio[1001]: info: No code Sep 09 22:36:35 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:36:35 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:36:35 volumio-goco volumio[1001]: info: ------------------------------ 74ms Sep 09 22:36:35 volumio-goco volumio[1001]: info: ------------------------------ 74ms Sep 09 22:36:35 volumio-goco volumio[1001]: info: FusionDsp - Volumio is not playing Sep 09 22:36:35 volumio-goco volumio[1001]: info: FusionDsp - Clipped samples monitor stopped Sep 09 22:36:35 volumio-goco volumio[1001]: info: FusionDsp - Volumio is not playing Sep 09 22:36:35 volumio-goco volumio[1001]: info: FusionDsp - Clipped samples monitor stopped Sep 09 22:36:35 volumio-goco volumio[1001]: info: FusionDsp - Volumio is not playing Sep 09 22:36:35 volumio-goco volumio[1001]: info: FusionDsp - Clipped samples monitor stopped Sep 09 22:36:35 volumio-goco volumio[1001]: info: FusionDsp - Volumio is not playing Sep 09 22:36:35 volumio-goco volumio[1001]: info: FusionDsp - Clipped samples monitor stopped Sep 09 22:36:35 volumio-goco volumio[1001]: info: FusionDsp - Volumio is not playing Sep 09 22:36:35 volumio-goco volumio[1001]: info: FusionDsp - Clipped samples monitor stopped Sep 09 22:36:35 volumio-goco volumio[1001]: info: FusionDsp - Volumio is not playing Sep 09 22:36:35 volumio-goco volumio[1001]: info: FusionDsp - Clipped samples monitor stopped Sep 09 22:36:35 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:36:35 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:36:35 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:36:35 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:36:35 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:36:35 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:36:35 volumio-goco volumio[1001]: info: sendMpdCommand playlistinfo took 118 milliseconds Sep 09 22:36:35 volumio-goco volumio[1001]: info: sendMpdCommand playlistinfo took 114 milliseconds Sep 09 22:36:35 volumio-goco volumio[1001]: verbose: ControllerMpd::parseTrackInfo Sep 09 22:36:35 volumio-goco volumio[1001]: verbose: ControllerMpd::parseTrackInfo Sep 09 22:36:35 volumio-goco volumio[1001]: info: ControllerMpd::pushState Sep 09 22:36:35 volumio-goco volumio[1001]: info: CoreCommandRouter::servicePushState Sep 09 22:36:35 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:36:35 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:36:35 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:36:35 volumio-goco volumio[1001]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"nolimithq.ogg","artist":null,"album":null,"uri":"http://webradio.sksyu.net:8000/nolimithq.ogg","trackType":"ogg"} Sep 09 22:36:35 volumio-goco volumio[1001]: verbose: CURRENT POSITION 0 Sep 09 22:36:35 volumio-goco volumio[1001]: info: CoreStateMachine::syncState stateService stop Sep 09 22:36:35 volumio-goco volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Sep 09 22:36:35 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:36:35 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:36:35 volumio-goco volumio[1001]: info: No code Sep 09 22:36:35 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:36:35 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:36:35 volumio-goco volumio[1001]: info: ControllerMpd::pushState Sep 09 22:36:35 volumio-goco volumio[1001]: info: CoreCommandRouter::servicePushState Sep 09 22:36:35 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:36:35 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:36:35 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:36:35 volumio-goco volumio[1001]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"nolimithq.ogg","artist":null,"album":null,"uri":"http://webradio.sksyu.net:8000/nolimithq.ogg","trackType":"ogg"} Sep 09 22:36:35 volumio-goco volumio[1001]: verbose: CURRENT POSITION 0 Sep 09 22:36:35 volumio-goco volumio[1001]: info: CoreStateMachine::syncState stateService stop Sep 09 22:36:35 volumio-goco volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Sep 09 22:36:35 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:36:35 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:36:35 volumio-goco volumio[1001]: info: No code Sep 09 22:36:35 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:36:35 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:36:35 volumio-goco volumio[1001]: info: ------------------------------ 214ms Sep 09 22:36:35 volumio-goco volumio[1001]: info: ------------------------------ 215ms Sep 09 22:36:35 volumio-goco volumio[1001]: info: FusionDsp - Volumio is not playing Sep 09 22:36:35 volumio-goco volumio[1001]: info: FusionDsp - Clipped samples monitor stopped Sep 09 22:36:35 volumio-goco volumio[1001]: info: FusionDsp - Volumio is not playing Sep 09 22:36:35 volumio-goco volumio[1001]: info: FusionDsp - Clipped samples monitor stopped Sep 09 22:36:35 volumio-goco volumio[1001]: info: FusionDsp - Volumio is not playing Sep 09 22:36:35 volumio-goco volumio[1001]: info: FusionDsp - Clipped samples monitor stopped Sep 09 22:36:35 volumio-goco volumio[1001]: info: FusionDsp - Volumio is not playing Sep 09 22:36:35 volumio-goco volumio[1001]: info: FusionDsp - Clipped samples monitor stopped Sep 09 22:36:35 volumio-goco volumio[1001]: info: FusionDsp - Volumio is not playing Sep 09 22:36:35 volumio-goco volumio[1001]: info: FusionDsp - Clipped samples monitor stopped Sep 09 22:36:35 volumio-goco volumio[1001]: info: FusionDsp - Volumio is not playing Sep 09 22:36:35 volumio-goco volumio[1001]: info: FusionDsp - Clipped samples monitor stopped Sep 09 22:36:35 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:36:35 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:36:35 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:36:35 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:36:35 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:36:35 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:36:36 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2390. Sep 09 22:36:36 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:36 volumio-goco dnsmasq[39455]: exiting on receipt of SIGTERM Sep 09 22:36:36 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:36:36 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:36 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:36 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:36:36 volumio-goco hostapd[39462]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:36:36 volumio-goco hostapd[39462]: nl80211: Could not configure driver mode Sep 09 22:36:36 volumio-goco hostapd[39462]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:36:36 volumio-goco hostapd[39462]: nl80211 driver initialization failed. Sep 09 22:36:36 volumio-goco hostapd[39462]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:36:36 volumio-goco hostapd[39462]: wlan0: AP-DISABLED Sep 09 22:36:36 volumio-goco hostapd[39462]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:36:36 volumio-goco hostapd[39462]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:36:36 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:36:36 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:36:36 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:36 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:37 volumio-goco dnsmasq[39467]: started, version 2.90 cachesize 150 Sep 09 22:36:37 volumio-goco dnsmasq[39467]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:36:37 volumio-goco dnsmasq-dhcp[39467]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:36:37 volumio-goco dnsmasq[39467]: reading /etc/resolv.conf Sep 09 22:36:37 volumio-goco dnsmasq[39467]: using nameserver 192.168.3.1#53 Sep 09 22:36:37 volumio-goco dnsmasq[39467]: using nameserver 91.102.231.241#53 Sep 09 22:36:37 volumio-goco dnsmasq[39467]: using nameserver 91.102.231.242#53 Sep 09 22:36:37 volumio-goco dnsmasq[39467]: using nameserver 208.67.222.222#53 Sep 09 22:36:37 volumio-goco dnsmasq[39467]: using nameserver 208.67.220.220#53 Sep 09 22:36:37 volumio-goco dnsmasq[39467]: read /etc/hosts - 4 names Sep 09 22:36:37 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:37 volumio-goco systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Sep 09 22:36:37 volumio-goco systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Sep 09 22:36:37 volumio-goco systemd[1]: welcome.service: Deactivated successfully. Sep 09 22:36:37 volumio-goco systemd[1]: Stopped welcome.service - Show a welcome message on console. Sep 09 22:36:37 volumio-goco systemd[1]: Stopping welcome.service - Show a welcome message on console... Sep 09 22:36:37 volumio-goco systemd[1]: Starting welcome.service - Show a welcome message on console... Sep 09 22:36:37 volumio-goco welcome[39482]: Resolved ip:[1] 192.168.3.104 Sep 09 22:36:37 volumio-goco systemd[1]: Finished welcome.service - Show a welcome message on console. Sep 09 22:36:37 volumio-goco systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Sep 09 22:36:39 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2391. Sep 09 22:36:39 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:39 volumio-goco dnsmasq[39467]: exiting on receipt of SIGTERM Sep 09 22:36:39 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:36:39 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:39 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:39 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:36:39 volumio-goco hostapd[39497]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:36:39 volumio-goco hostapd[39497]: nl80211: Could not configure driver mode Sep 09 22:36:39 volumio-goco hostapd[39497]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:36:39 volumio-goco hostapd[39497]: nl80211 driver initialization failed. Sep 09 22:36:39 volumio-goco hostapd[39497]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:36:39 volumio-goco hostapd[39497]: wlan0: AP-DISABLED Sep 09 22:36:39 volumio-goco hostapd[39497]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:36:39 volumio-goco hostapd[39497]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:36:39 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:36:39 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:36:39 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:39 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:39 volumio-goco dnsmasq[39502]: started, version 2.90 cachesize 150 Sep 09 22:36:39 volumio-goco dnsmasq[39502]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:36:39 volumio-goco dnsmasq-dhcp[39502]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:36:39 volumio-goco dnsmasq[39502]: reading /etc/resolv.conf Sep 09 22:36:39 volumio-goco dnsmasq[39502]: using nameserver 192.168.3.1#53 Sep 09 22:36:39 volumio-goco dnsmasq[39502]: using nameserver 91.102.231.241#53 Sep 09 22:36:39 volumio-goco dnsmasq[39502]: using nameserver 91.102.231.242#53 Sep 09 22:36:39 volumio-goco dnsmasq[39502]: using nameserver 208.67.222.222#53 Sep 09 22:36:39 volumio-goco dnsmasq[39502]: using nameserver 208.67.220.220#53 Sep 09 22:36:39 volumio-goco dnsmasq[39502]: read /etc/hosts - 4 names Sep 09 22:36:39 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:41 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2392. Sep 09 22:36:41 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:41 volumio-goco dnsmasq[39502]: exiting on receipt of SIGTERM Sep 09 22:36:41 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:36:41 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:41 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:41 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:36:41 volumio-goco hostapd[39508]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:36:41 volumio-goco hostapd[39508]: nl80211: Could not configure driver mode Sep 09 22:36:41 volumio-goco hostapd[39508]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:36:41 volumio-goco hostapd[39508]: nl80211 driver initialization failed. Sep 09 22:36:41 volumio-goco hostapd[39508]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:36:41 volumio-goco hostapd[39508]: wlan0: AP-DISABLED Sep 09 22:36:41 volumio-goco hostapd[39508]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:36:41 volumio-goco hostapd[39508]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:36:41 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:36:41 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:36:41 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:41 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:41 volumio-goco dnsmasq[39513]: started, version 2.90 cachesize 150 Sep 09 22:36:41 volumio-goco dnsmasq[39513]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:36:41 volumio-goco dnsmasq-dhcp[39513]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:36:41 volumio-goco dnsmasq[39513]: reading /etc/resolv.conf Sep 09 22:36:41 volumio-goco dnsmasq[39513]: using nameserver 192.168.3.1#53 Sep 09 22:36:41 volumio-goco dnsmasq[39513]: using nameserver 91.102.231.241#53 Sep 09 22:36:41 volumio-goco dnsmasq[39513]: using nameserver 91.102.231.242#53 Sep 09 22:36:41 volumio-goco dnsmasq[39513]: using nameserver 208.67.222.222#53 Sep 09 22:36:41 volumio-goco dnsmasq[39513]: using nameserver 208.67.220.220#53 Sep 09 22:36:41 volumio-goco dnsmasq[39513]: read /etc/hosts - 4 names Sep 09 22:36:41 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:43 volumio-goco systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Sep 09 22:36:43 volumio-goco dbus-daemon[591]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.218' (uid=0 pid=39519 comm="timedatectl show --property=NTPSynchronized --valu") Sep 09 22:36:43 volumio-goco systemd[1]: Starting systemd-timedated.service - Time & Date Service... Sep 09 22:36:43 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2393. Sep 09 22:36:43 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:43 volumio-goco dnsmasq[39513]: exiting on receipt of SIGTERM Sep 09 22:36:43 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:36:43 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:43 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:43 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:36:43 volumio-goco hostapd[39523]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:36:43 volumio-goco hostapd[39523]: nl80211: Could not configure driver mode Sep 09 22:36:43 volumio-goco hostapd[39523]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:36:43 volumio-goco hostapd[39523]: nl80211 driver initialization failed. Sep 09 22:36:43 volumio-goco hostapd[39523]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:36:43 volumio-goco hostapd[39523]: wlan0: AP-DISABLED Sep 09 22:36:43 volumio-goco hostapd[39523]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:36:43 volumio-goco hostapd[39523]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:36:43 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:36:43 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:36:43 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:43 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:43 volumio-goco dnsmasq[39532]: started, version 2.90 cachesize 150 Sep 09 22:36:43 volumio-goco dnsmasq[39532]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:36:43 volumio-goco dnsmasq-dhcp[39532]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:36:43 volumio-goco dnsmasq[39532]: reading /etc/resolv.conf Sep 09 22:36:43 volumio-goco dnsmasq[39532]: using nameserver 192.168.3.1#53 Sep 09 22:36:43 volumio-goco dnsmasq[39532]: using nameserver 91.102.231.241#53 Sep 09 22:36:43 volumio-goco dnsmasq[39532]: using nameserver 91.102.231.242#53 Sep 09 22:36:43 volumio-goco dnsmasq[39532]: using nameserver 208.67.222.222#53 Sep 09 22:36:43 volumio-goco dnsmasq[39532]: using nameserver 208.67.220.220#53 Sep 09 22:36:43 volumio-goco dnsmasq[39532]: read /etc/hosts - 4 names Sep 09 22:36:43 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:43 volumio-goco dbus-daemon[591]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 09 22:36:43 volumio-goco systemd[1]: Started systemd-timedated.service - Time & Date Service. Sep 09 22:36:43 volumio-goco setdatetime-helper.sh[39518]: Time is already synchronized. Sep 09 22:36:43 volumio-goco systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Sep 09 22:36:43 volumio-goco systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Sep 09 22:36:44 volumio-goco volumio[1001]: info: Preload queue cleared Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreStateMachine::ClearQueue Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreStateMachine::stop Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 22:36:44 volumio-goco volumio[1001]: info: CorePlayQueue::clearPlayQueue Sep 09 22:36:44 volumio-goco volumio[1001]: info: CorePlayQueue::saveQueue Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushQueue Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreStateMachine::addQueueItems Sep 09 22:36:44 volumio-goco volumio[1001]: info: CorePlayQueue::addQueueItems Sep 09 22:36:44 volumio-goco volumio[1001]: info: Preload queue cleared Sep 09 22:36:44 volumio-goco volumio[1001]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s66047 Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushQueue Sep 09 22:36:44 volumio-goco volumio[1001]: info: CorePlayQueue::saveQueue Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreStateMachine::updateTrackBlock Sep 09 22:36:44 volumio-goco volumio[1001]: info: CorePlayQueue::getTrackBlock Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPlay Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreStateMachine::play index 0 Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreStateMachine::stop Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreStateMachine::play index undefined Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 22:36:44 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreStateMachine::startPlaybackTimer Sep 09 22:36:44 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:36:44 volumio-goco volumio[1001]: info: [1757450204516] ControllerWebradio::clearAddPlayTrack Sep 09 22:36:44 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand stop Sep 09 22:36:44 volumio-goco volumio[1001]: info: sendMpdCommand stop took 2 milliseconds Sep 09 22:36:44 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand clear Sep 09 22:36:44 volumio-goco volumio[1001]: info: Sep 09 22:36:44 volumio-goco volumio[1001]: ---------------------------- MPD announces system playlist update Sep 09 22:36:44 volumio-goco volumio[1001]: info: Ignoring MPD Status Update Sep 09 22:36:44 volumio-goco volumio[1001]: info: sendMpdCommand clear took 2 milliseconds Sep 09 22:36:44 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand load "http://mr-stream.mediaconnect.hu/4738/mr2.mp3" Sep 09 22:36:44 volumio-goco volumio[1001]: info: Sep 09 22:36:44 volumio-goco volumio[1001]: ---------------------------- MPD announces system playlist update Sep 09 22:36:44 volumio-goco volumio[1001]: info: Ignoring MPD Status Update Sep 09 22:36:44 volumio-goco volumio[1001]: error: updateQueue error: null Sep 09 22:36:44 volumio-goco volumio[1001]: info: ------------------------------ 4ms Sep 09 22:36:44 volumio-goco volumio[1001]: error: updateQueue error: null Sep 09 22:36:44 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand add "http://mr-stream.mediaconnect.hu/4738/mr2.mp3" Sep 09 22:36:44 volumio-goco volumio[1001]: info: ------------------------------ 142ms Sep 09 22:36:44 volumio-goco volumio[1001]: info: Sep 09 22:36:44 volumio-goco volumio[1001]: ---------------------------- MPD announces system playlist update Sep 09 22:36:44 volumio-goco volumio[1001]: info: Ignoring MPD Status Update Sep 09 22:36:44 volumio-goco volumio[1001]: info: sendMpdCommand add "http://mr-stream.mediaconnect.hu/4738/mr2.mp3" took 2 milliseconds Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 09 22:36:44 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand play Sep 09 22:36:44 volumio-goco volumio[1001]: info: Sep 09 22:36:44 volumio-goco volumio[1001]: ---------------------------- MPD announces system playlist update Sep 09 22:36:44 volumio-goco volumio[1001]: info: Ignoring MPD Status Update Sep 09 22:36:44 volumio-goco volumio[1001]: info: ------------------------------ 4ms Sep 09 22:36:44 volumio-goco volumio[1001]: info: sendMpdCommand play took 4 milliseconds Sep 09 22:36:44 volumio-goco volumio[1001]: info: ------------------------------ 3ms Sep 09 22:36:44 volumio-goco volumio[1001]: info: Sep 09 22:36:44 volumio-goco volumio[1001]: ---------------------------- MPD announces state update: player Sep 09 22:36:44 volumio-goco volumio[1001]: info: ControllerMpd::getState Sep 09 22:36:44 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Sep 09 22:36:44 volumio-goco volumio[1001]: info: Sep 09 22:36:44 volumio-goco volumio[1001]: ---------------------------- MPD announces state update: player Sep 09 22:36:44 volumio-goco volumio[1001]: info: ControllerMpd::getState Sep 09 22:36:44 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Sep 09 22:36:44 volumio-goco volumio[1001]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Sep 09 22:36:44 volumio-goco volumio[1001]: info: FusionDsp - ---- read samplerate from file: 44100 Sep 09 22:36:44 volumio-goco volumio[1001]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 09 22:36:44 volumio-goco volumio[1001]: info: Sep 09 22:36:44 volumio-goco volumio[1001]: ---------------------------- MPD announces state update: player Sep 09 22:36:44 volumio-goco volumio[1001]: info: ControllerMpd::getState Sep 09 22:36:44 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Sep 09 22:36:44 volumio-goco volumio[1001]: info: Sep 09 22:36:44 volumio-goco volumio[1001]: ---------------------------- MPD announces state update: player Sep 09 22:36:44 volumio-goco volumio[1001]: info: sendMpdCommand status took 23 milliseconds Sep 09 22:36:44 volumio-goco volumio[1001]: info: ControllerMpd::getState Sep 09 22:36:44 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Sep 09 22:36:44 volumio-goco volumio[1001]: info: sendMpdCommand status took 23 milliseconds Sep 09 22:36:44 volumio-goco volumio[1001]: verbose: ControllerMpd::parseState Sep 09 22:36:44 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 22:36:44 volumio-goco volumio[1001]: verbose: ControllerMpd::parseState Sep 09 22:36:44 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 22:36:44 volumio-goco volumio[1001]: info: sendMpdCommand status took 9 milliseconds Sep 09 22:36:44 volumio-goco volumio[1001]: info: sendMpdCommand status took 7 milliseconds Sep 09 22:36:44 volumio-goco volumio[1001]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 09 22:36:44 volumio-goco volumio[1001]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 09 22:36:44 volumio-goco volumio[1001]: verbose: ControllerMpd::parseState Sep 09 22:36:44 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 22:36:44 volumio-goco volumio[1001]: verbose: ControllerMpd::parseState Sep 09 22:36:44 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 22:36:44 volumio-goco volumio[1001]: verbose: ControllerMpd::parseTrackInfo Sep 09 22:36:44 volumio-goco volumio[1001]: verbose: ControllerMpd::parseTrackInfo Sep 09 22:36:44 volumio-goco volumio[1001]: info: ControllerMpd::pushState Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreCommandRouter::servicePushState Sep 09 22:36:44 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:36:44 volumio-goco volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mr2.mp3","artist":null,"album":null,"uri":"http://mr-stream.mediaconnect.hu/4738/mr2.mp3","trackType":"mp3"} Sep 09 22:36:44 volumio-goco volumio[1001]: verbose: CURRENT POSITION 0 Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreStateMachine::syncState stateService play Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Sep 09 22:36:44 volumio-goco volumio[1001]: info: ControllerMpd::pushState Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreCommandRouter::servicePushState Sep 09 22:36:44 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:36:44 volumio-goco volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mr2.mp3","artist":null,"album":null,"uri":"http://mr-stream.mediaconnect.hu/4738/mr2.mp3","trackType":"mp3"} Sep 09 22:36:44 volumio-goco volumio[1001]: verbose: CURRENT POSITION 0 Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreStateMachine::syncState stateService play Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreStateMachine::syncState currentStatus play Sep 09 22:36:44 volumio-goco volumio[1001]: info: Received an update from plugin. extracting info from payload Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:36:44 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:36:44 volumio-goco volumio[1001]: info: ------------------------------ 53ms Sep 09 22:36:44 volumio-goco volumio[1001]: info: ------------------------------ 58ms Sep 09 22:36:44 volumio-goco volumio[1001]: info: sendMpdCommand playlistinfo took 31 milliseconds Sep 09 22:36:44 volumio-goco volumio[1001]: info: sendMpdCommand playlistinfo took 30 milliseconds Sep 09 22:36:44 volumio-goco volumio[1001]: verbose: ControllerMpd::parseTrackInfo Sep 09 22:36:44 volumio-goco volumio[1001]: verbose: ControllerMpd::parseTrackInfo Sep 09 22:36:44 volumio-goco volumio[1001]: info: ControllerMpd::pushState Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreCommandRouter::servicePushState Sep 09 22:36:44 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:36:44 volumio-goco volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1277,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"mr2.mp3","artist":null,"album":null,"uri":"http://mr-stream.mediaconnect.hu/4738/mr2.mp3","trackType":"mp3"} Sep 09 22:36:44 volumio-goco volumio[1001]: verbose: CURRENT POSITION 0 Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreStateMachine::syncState stateService play Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreStateMachine::syncState currentStatus play Sep 09 22:36:44 volumio-goco volumio[1001]: info: Received an update from plugin. extracting info from payload Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:36:44 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:36:44 volumio-goco volumio[1001]: info: ControllerMpd::pushState Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreCommandRouter::servicePushState Sep 09 22:36:44 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:36:44 volumio-goco volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1277,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"mr2.mp3","artist":null,"album":null,"uri":"http://mr-stream.mediaconnect.hu/4738/mr2.mp3","trackType":"mp3"} Sep 09 22:36:44 volumio-goco volumio[1001]: verbose: CURRENT POSITION 0 Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreStateMachine::syncState stateService play Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreStateMachine::syncState currentStatus play Sep 09 22:36:44 volumio-goco volumio[1001]: info: Received an update from plugin. extracting info from payload Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:36:44 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:36:44 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:36:44 volumio-goco volumio[1001]: info: ------------------------------ 79ms Sep 09 22:36:44 volumio-goco volumio[1001]: info: ------------------------------ 78ms Sep 09 22:36:44 volumio-goco volumio[1001]: info: FusionDsp - Volumio is playing Sep 09 22:36:44 volumio-goco volumio[1001]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Sep 09 22:36:44 volumio-goco volumio[1001]: info: FusionDsp - Volumio is playing Sep 09 22:36:44 volumio-goco volumio[1001]: info: FusionDsp - Volumio is playing Sep 09 22:36:44 volumio-goco volumio[1001]: info: FusionDsp - Volumio is playing Sep 09 22:36:44 volumio-goco volumio[1001]: info: FusionDsp - Volumio is playing Sep 09 22:36:44 volumio-goco volumio[1001]: info: FusionDsp - Volumio is playing Sep 09 22:36:44 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:36:44 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:36:44 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:36:44 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:36:44 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:36:44 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:36:44 volumio-goco volumio[1001]: info: FusionDsp - Clipping Monitor started Sep 09 22:36:44 volumio-goco volumio[1001]: info: FusionDsp - Clipping Monitor started Sep 09 22:36:44 volumio-goco volumio[1001]: info: FusionDsp - Clipping Monitor started Sep 09 22:36:44 volumio-goco volumio[1001]: info: FusionDsp - Clipping Monitor started Sep 09 22:36:44 volumio-goco volumio[1001]: info: FusionDsp - Clipping Monitor started Sep 09 22:36:44 volumio-goco volumio[1001]: info: FusionDsp - Clipping Monitor started Sep 09 22:36:45 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2394. Sep 09 22:36:45 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:45 volumio-goco dnsmasq[39532]: exiting on receipt of SIGTERM Sep 09 22:36:45 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:36:45 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:45 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:45 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:36:45 volumio-goco hostapd[39563]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:36:45 volumio-goco hostapd[39563]: nl80211: Could not configure driver mode Sep 09 22:36:45 volumio-goco hostapd[39563]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:36:45 volumio-goco hostapd[39563]: nl80211 driver initialization failed. Sep 09 22:36:45 volumio-goco hostapd[39563]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:36:45 volumio-goco hostapd[39563]: wlan0: AP-DISABLED Sep 09 22:36:45 volumio-goco hostapd[39563]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:36:45 volumio-goco hostapd[39563]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:36:45 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:36:45 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:36:45 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:45 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:45 volumio-goco dnsmasq[39568]: started, version 2.90 cachesize 150 Sep 09 22:36:45 volumio-goco dnsmasq[39568]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:36:45 volumio-goco dnsmasq-dhcp[39568]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:36:45 volumio-goco dnsmasq[39568]: reading /etc/resolv.conf Sep 09 22:36:45 volumio-goco dnsmasq[39568]: using nameserver 192.168.3.1#53 Sep 09 22:36:45 volumio-goco dnsmasq[39568]: using nameserver 91.102.231.241#53 Sep 09 22:36:45 volumio-goco dnsmasq[39568]: using nameserver 91.102.231.242#53 Sep 09 22:36:45 volumio-goco dnsmasq[39568]: using nameserver 208.67.222.222#53 Sep 09 22:36:45 volumio-goco dnsmasq[39568]: using nameserver 208.67.220.220#53 Sep 09 22:36:45 volumio-goco dnsmasq[39568]: read /etc/hosts - 4 names Sep 09 22:36:45 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:47 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2395. Sep 09 22:36:47 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:47 volumio-goco dnsmasq[39568]: exiting on receipt of SIGTERM Sep 09 22:36:47 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:36:47 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:47 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:47 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:36:47 volumio-goco hostapd[39575]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:36:47 volumio-goco hostapd[39575]: nl80211: Could not configure driver mode Sep 09 22:36:47 volumio-goco hostapd[39575]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:36:47 volumio-goco hostapd[39575]: nl80211 driver initialization failed. Sep 09 22:36:47 volumio-goco hostapd[39575]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:36:47 volumio-goco hostapd[39575]: wlan0: AP-DISABLED Sep 09 22:36:47 volumio-goco hostapd[39575]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:36:47 volumio-goco hostapd[39575]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:36:47 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:36:47 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:36:47 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:47 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:48 volumio-goco dnsmasq[39580]: started, version 2.90 cachesize 150 Sep 09 22:36:48 volumio-goco dnsmasq[39580]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:36:48 volumio-goco dnsmasq-dhcp[39580]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:36:48 volumio-goco dnsmasq[39580]: reading /etc/resolv.conf Sep 09 22:36:48 volumio-goco dnsmasq[39580]: using nameserver 192.168.3.1#53 Sep 09 22:36:48 volumio-goco dnsmasq[39580]: using nameserver 91.102.231.241#53 Sep 09 22:36:48 volumio-goco dnsmasq[39580]: using nameserver 91.102.231.242#53 Sep 09 22:36:48 volumio-goco dnsmasq[39580]: using nameserver 208.67.222.222#53 Sep 09 22:36:48 volumio-goco dnsmasq[39580]: using nameserver 208.67.220.220#53 Sep 09 22:36:48 volumio-goco dnsmasq[39580]: read /etc/hosts - 4 names Sep 09 22:36:48 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:50 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2396. Sep 09 22:36:50 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:50 volumio-goco dnsmasq[39580]: exiting on receipt of SIGTERM Sep 09 22:36:50 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:36:50 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:50 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:50 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:36:50 volumio-goco hostapd[39586]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:36:50 volumio-goco hostapd[39586]: nl80211: Could not configure driver mode Sep 09 22:36:50 volumio-goco hostapd[39586]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:36:50 volumio-goco hostapd[39586]: nl80211 driver initialization failed. Sep 09 22:36:50 volumio-goco hostapd[39586]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:36:50 volumio-goco hostapd[39586]: wlan0: AP-DISABLED Sep 09 22:36:50 volumio-goco hostapd[39586]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:36:50 volumio-goco hostapd[39586]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:36:50 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:36:50 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:36:50 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:50 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:50 volumio-goco dnsmasq[39592]: started, version 2.90 cachesize 150 Sep 09 22:36:50 volumio-goco dnsmasq[39592]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:36:50 volumio-goco dnsmasq-dhcp[39592]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:36:50 volumio-goco dnsmasq[39592]: reading /etc/resolv.conf Sep 09 22:36:50 volumio-goco dnsmasq[39592]: using nameserver 192.168.3.1#53 Sep 09 22:36:50 volumio-goco dnsmasq[39592]: using nameserver 91.102.231.241#53 Sep 09 22:36:50 volumio-goco dnsmasq[39592]: using nameserver 91.102.231.242#53 Sep 09 22:36:50 volumio-goco dnsmasq[39592]: using nameserver 208.67.222.222#53 Sep 09 22:36:50 volumio-goco dnsmasq[39592]: using nameserver 208.67.220.220#53 Sep 09 22:36:50 volumio-goco dnsmasq[39592]: read /etc/hosts - 4 names Sep 09 22:36:50 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:52 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2397. Sep 09 22:36:52 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:52 volumio-goco dnsmasq[39592]: exiting on receipt of SIGTERM Sep 09 22:36:52 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:36:52 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:52 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:52 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:36:52 volumio-goco hostapd[39598]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:36:52 volumio-goco hostapd[39598]: nl80211: Could not configure driver mode Sep 09 22:36:52 volumio-goco hostapd[39598]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:36:52 volumio-goco hostapd[39598]: nl80211 driver initialization failed. Sep 09 22:36:52 volumio-goco hostapd[39598]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:36:52 volumio-goco hostapd[39598]: wlan0: AP-DISABLED Sep 09 22:36:52 volumio-goco hostapd[39598]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:36:52 volumio-goco hostapd[39598]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:36:52 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:36:52 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:36:52 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:52 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:52 volumio-goco dnsmasq[39603]: started, version 2.90 cachesize 150 Sep 09 22:36:52 volumio-goco dnsmasq[39603]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:36:52 volumio-goco dnsmasq-dhcp[39603]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:36:52 volumio-goco dnsmasq[39603]: reading /etc/resolv.conf Sep 09 22:36:52 volumio-goco dnsmasq[39603]: using nameserver 192.168.3.1#53 Sep 09 22:36:52 volumio-goco dnsmasq[39603]: using nameserver 91.102.231.241#53 Sep 09 22:36:52 volumio-goco dnsmasq[39603]: using nameserver 91.102.231.242#53 Sep 09 22:36:52 volumio-goco dnsmasq[39603]: using nameserver 208.67.222.222#53 Sep 09 22:36:52 volumio-goco dnsmasq[39603]: using nameserver 208.67.220.220#53 Sep 09 22:36:52 volumio-goco dnsmasq[39603]: read /etc/hosts - 4 names Sep 09 22:36:52 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:54 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2398. Sep 09 22:36:54 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:54 volumio-goco dnsmasq[39603]: exiting on receipt of SIGTERM Sep 09 22:36:54 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:36:54 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:54 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:54 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:36:54 volumio-goco hostapd[39623]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:36:54 volumio-goco hostapd[39623]: nl80211: Could not configure driver mode Sep 09 22:36:54 volumio-goco hostapd[39623]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:36:54 volumio-goco hostapd[39623]: nl80211 driver initialization failed. Sep 09 22:36:54 volumio-goco hostapd[39623]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:36:54 volumio-goco hostapd[39623]: wlan0: AP-DISABLED Sep 09 22:36:54 volumio-goco hostapd[39623]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:36:54 volumio-goco hostapd[39623]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:36:54 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:36:54 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:36:54 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:54 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:54 volumio-goco dnsmasq[39628]: started, version 2.90 cachesize 150 Sep 09 22:36:54 volumio-goco dnsmasq[39628]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:36:54 volumio-goco dnsmasq-dhcp[39628]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:36:54 volumio-goco dnsmasq[39628]: reading /etc/resolv.conf Sep 09 22:36:54 volumio-goco dnsmasq[39628]: using nameserver 192.168.3.1#53 Sep 09 22:36:54 volumio-goco dnsmasq[39628]: using nameserver 91.102.231.241#53 Sep 09 22:36:54 volumio-goco dnsmasq[39628]: using nameserver 91.102.231.242#53 Sep 09 22:36:54 volumio-goco dnsmasq[39628]: using nameserver 208.67.222.222#53 Sep 09 22:36:54 volumio-goco dnsmasq[39628]: using nameserver 208.67.220.220#53 Sep 09 22:36:54 volumio-goco dnsmasq[39628]: read /etc/hosts - 4 names Sep 09 22:36:54 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:56 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2399. Sep 09 22:36:56 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:56 volumio-goco dnsmasq[39628]: exiting on receipt of SIGTERM Sep 09 22:36:56 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:36:56 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:56 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:56 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:36:56 volumio-goco hostapd[39634]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:36:56 volumio-goco hostapd[39634]: nl80211: Could not configure driver mode Sep 09 22:36:56 volumio-goco hostapd[39634]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:36:56 volumio-goco hostapd[39634]: nl80211 driver initialization failed. Sep 09 22:36:56 volumio-goco hostapd[39634]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:36:56 volumio-goco hostapd[39634]: wlan0: AP-DISABLED Sep 09 22:36:56 volumio-goco hostapd[39634]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:36:56 volumio-goco hostapd[39634]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:36:56 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:36:56 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:36:56 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:56 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:57 volumio-goco dnsmasq[39639]: started, version 2.90 cachesize 150 Sep 09 22:36:57 volumio-goco dnsmasq[39639]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:36:57 volumio-goco dnsmasq-dhcp[39639]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:36:57 volumio-goco dnsmasq[39639]: reading /etc/resolv.conf Sep 09 22:36:57 volumio-goco dnsmasq[39639]: using nameserver 192.168.3.1#53 Sep 09 22:36:57 volumio-goco dnsmasq[39639]: using nameserver 91.102.231.241#53 Sep 09 22:36:57 volumio-goco dnsmasq[39639]: using nameserver 91.102.231.242#53 Sep 09 22:36:57 volumio-goco dnsmasq[39639]: using nameserver 208.67.222.222#53 Sep 09 22:36:57 volumio-goco dnsmasq[39639]: using nameserver 208.67.220.220#53 Sep 09 22:36:57 volumio-goco dnsmasq[39639]: read /etc/hosts - 4 names Sep 09 22:36:57 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:59 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2400. Sep 09 22:36:59 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:59 volumio-goco dnsmasq[39639]: exiting on receipt of SIGTERM Sep 09 22:36:59 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:36:59 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:36:59 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:59 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:36:59 volumio-goco hostapd[39645]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:36:59 volumio-goco hostapd[39645]: nl80211: Could not configure driver mode Sep 09 22:36:59 volumio-goco hostapd[39645]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:36:59 volumio-goco hostapd[39645]: nl80211 driver initialization failed. Sep 09 22:36:59 volumio-goco hostapd[39645]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:36:59 volumio-goco hostapd[39645]: wlan0: AP-DISABLED Sep 09 22:36:59 volumio-goco hostapd[39645]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:36:59 volumio-goco hostapd[39645]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:36:59 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:36:59 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:36:59 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:36:59 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:36:59 volumio-goco dnsmasq[39651]: started, version 2.90 cachesize 150 Sep 09 22:36:59 volumio-goco dnsmasq[39651]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:36:59 volumio-goco dnsmasq-dhcp[39651]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:36:59 volumio-goco dnsmasq[39651]: reading /etc/resolv.conf Sep 09 22:36:59 volumio-goco dnsmasq[39651]: using nameserver 192.168.3.1#53 Sep 09 22:36:59 volumio-goco dnsmasq[39651]: using nameserver 91.102.231.241#53 Sep 09 22:36:59 volumio-goco dnsmasq[39651]: using nameserver 91.102.231.242#53 Sep 09 22:36:59 volumio-goco dnsmasq[39651]: using nameserver 208.67.222.222#53 Sep 09 22:36:59 volumio-goco dnsmasq[39651]: using nameserver 208.67.220.220#53 Sep 09 22:36:59 volumio-goco dnsmasq[39651]: read /etc/hosts - 4 names Sep 09 22:36:59 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:01 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2401. Sep 09 22:37:01 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:01 volumio-goco dnsmasq[39651]: exiting on receipt of SIGTERM Sep 09 22:37:01 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:37:01 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:01 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:01 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:37:01 volumio-goco hostapd[39657]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:37:01 volumio-goco hostapd[39657]: nl80211: Could not configure driver mode Sep 09 22:37:01 volumio-goco hostapd[39657]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:37:01 volumio-goco hostapd[39657]: nl80211 driver initialization failed. Sep 09 22:37:01 volumio-goco hostapd[39657]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:37:01 volumio-goco hostapd[39657]: wlan0: AP-DISABLED Sep 09 22:37:01 volumio-goco hostapd[39657]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:37:01 volumio-goco hostapd[39657]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:37:01 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:37:01 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:37:01 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:01 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:01 volumio-goco dnsmasq[39662]: started, version 2.90 cachesize 150 Sep 09 22:37:01 volumio-goco dnsmasq[39662]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:37:01 volumio-goco dnsmasq-dhcp[39662]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:37:01 volumio-goco dnsmasq[39662]: reading /etc/resolv.conf Sep 09 22:37:01 volumio-goco dnsmasq[39662]: using nameserver 192.168.3.1#53 Sep 09 22:37:01 volumio-goco dnsmasq[39662]: using nameserver 91.102.231.241#53 Sep 09 22:37:01 volumio-goco dnsmasq[39662]: using nameserver 91.102.231.242#53 Sep 09 22:37:01 volumio-goco dnsmasq[39662]: using nameserver 208.67.222.222#53 Sep 09 22:37:01 volumio-goco dnsmasq[39662]: using nameserver 208.67.220.220#53 Sep 09 22:37:01 volumio-goco dnsmasq[39662]: read /etc/hosts - 4 names Sep 09 22:37:01 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:03 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2402. Sep 09 22:37:03 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:03 volumio-goco dnsmasq[39662]: exiting on receipt of SIGTERM Sep 09 22:37:03 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:37:03 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:03 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:03 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:37:03 volumio-goco hostapd[39668]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:37:03 volumio-goco hostapd[39668]: nl80211: Could not configure driver mode Sep 09 22:37:03 volumio-goco hostapd[39668]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:37:03 volumio-goco hostapd[39668]: nl80211 driver initialization failed. Sep 09 22:37:03 volumio-goco hostapd[39668]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:37:03 volumio-goco hostapd[39668]: wlan0: AP-DISABLED Sep 09 22:37:03 volumio-goco hostapd[39668]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:37:03 volumio-goco hostapd[39668]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:37:03 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:37:03 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:37:03 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:03 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:03 volumio-goco dnsmasq[39673]: started, version 2.90 cachesize 150 Sep 09 22:37:03 volumio-goco dnsmasq[39673]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:37:03 volumio-goco dnsmasq-dhcp[39673]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:37:03 volumio-goco dnsmasq[39673]: reading /etc/resolv.conf Sep 09 22:37:03 volumio-goco dnsmasq[39673]: using nameserver 192.168.3.1#53 Sep 09 22:37:03 volumio-goco dnsmasq[39673]: using nameserver 91.102.231.241#53 Sep 09 22:37:03 volumio-goco dnsmasq[39673]: using nameserver 91.102.231.242#53 Sep 09 22:37:03 volumio-goco dnsmasq[39673]: using nameserver 208.67.222.222#53 Sep 09 22:37:03 volumio-goco dnsmasq[39673]: using nameserver 208.67.220.220#53 Sep 09 22:37:03 volumio-goco dnsmasq[39673]: read /etc/hosts - 4 names Sep 09 22:37:03 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:05 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2403. Sep 09 22:37:05 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:05 volumio-goco dnsmasq[39673]: exiting on receipt of SIGTERM Sep 09 22:37:05 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:37:05 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:05 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:05 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:37:05 volumio-goco hostapd[39693]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:37:05 volumio-goco hostapd[39693]: nl80211: Could not configure driver mode Sep 09 22:37:05 volumio-goco hostapd[39693]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:37:05 volumio-goco hostapd[39693]: nl80211 driver initialization failed. Sep 09 22:37:05 volumio-goco hostapd[39693]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:37:05 volumio-goco hostapd[39693]: wlan0: AP-DISABLED Sep 09 22:37:05 volumio-goco hostapd[39693]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:37:05 volumio-goco hostapd[39693]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:37:05 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:37:05 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:37:05 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:05 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:06 volumio-goco dnsmasq[39698]: started, version 2.90 cachesize 150 Sep 09 22:37:06 volumio-goco dnsmasq[39698]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:37:06 volumio-goco dnsmasq-dhcp[39698]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:37:06 volumio-goco dnsmasq[39698]: reading /etc/resolv.conf Sep 09 22:37:06 volumio-goco dnsmasq[39698]: using nameserver 192.168.3.1#53 Sep 09 22:37:06 volumio-goco dnsmasq[39698]: using nameserver 91.102.231.241#53 Sep 09 22:37:06 volumio-goco dnsmasq[39698]: using nameserver 91.102.231.242#53 Sep 09 22:37:06 volumio-goco dnsmasq[39698]: using nameserver 208.67.222.222#53 Sep 09 22:37:06 volumio-goco dnsmasq[39698]: using nameserver 208.67.220.220#53 Sep 09 22:37:06 volumio-goco dnsmasq[39698]: read /etc/hosts - 4 names Sep 09 22:37:06 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:08 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2404. Sep 09 22:37:08 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:08 volumio-goco dnsmasq[39698]: exiting on receipt of SIGTERM Sep 09 22:37:08 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:37:08 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:08 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:08 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:37:08 volumio-goco hostapd[39705]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:37:08 volumio-goco hostapd[39705]: nl80211: Could not configure driver mode Sep 09 22:37:08 volumio-goco hostapd[39705]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:37:08 volumio-goco hostapd[39705]: nl80211 driver initialization failed. Sep 09 22:37:08 volumio-goco hostapd[39705]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:37:08 volumio-goco hostapd[39705]: wlan0: AP-DISABLED Sep 09 22:37:08 volumio-goco hostapd[39705]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:37:08 volumio-goco hostapd[39705]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:37:08 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:37:08 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:37:08 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:08 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:08 volumio-goco dnsmasq[39711]: started, version 2.90 cachesize 150 Sep 09 22:37:08 volumio-goco dnsmasq[39711]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:37:08 volumio-goco dnsmasq-dhcp[39711]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:37:08 volumio-goco dnsmasq[39711]: reading /etc/resolv.conf Sep 09 22:37:08 volumio-goco dnsmasq[39711]: using nameserver 192.168.3.1#53 Sep 09 22:37:08 volumio-goco dnsmasq[39711]: using nameserver 91.102.231.241#53 Sep 09 22:37:08 volumio-goco dnsmasq[39711]: using nameserver 91.102.231.242#53 Sep 09 22:37:08 volumio-goco dnsmasq[39711]: using nameserver 208.67.222.222#53 Sep 09 22:37:08 volumio-goco dnsmasq[39711]: using nameserver 208.67.220.220#53 Sep 09 22:37:08 volumio-goco dnsmasq[39711]: read /etc/hosts - 4 names Sep 09 22:37:08 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:10 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2405. Sep 09 22:37:10 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:10 volumio-goco dnsmasq[39711]: exiting on receipt of SIGTERM Sep 09 22:37:10 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:37:10 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:10 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:10 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:37:10 volumio-goco hostapd[39717]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:37:10 volumio-goco hostapd[39717]: nl80211: Could not configure driver mode Sep 09 22:37:10 volumio-goco hostapd[39717]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:37:10 volumio-goco hostapd[39717]: nl80211 driver initialization failed. Sep 09 22:37:10 volumio-goco hostapd[39717]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:37:10 volumio-goco hostapd[39717]: wlan0: AP-DISABLED Sep 09 22:37:10 volumio-goco hostapd[39717]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:37:10 volumio-goco hostapd[39717]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:37:10 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:37:10 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:37:10 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:10 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:10 volumio-goco dnsmasq[39722]: started, version 2.90 cachesize 150 Sep 09 22:37:10 volumio-goco dnsmasq[39722]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:37:10 volumio-goco dnsmasq-dhcp[39722]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:37:10 volumio-goco dnsmasq[39722]: reading /etc/resolv.conf Sep 09 22:37:10 volumio-goco dnsmasq[39722]: using nameserver 192.168.3.1#53 Sep 09 22:37:10 volumio-goco dnsmasq[39722]: using nameserver 91.102.231.241#53 Sep 09 22:37:10 volumio-goco dnsmasq[39722]: using nameserver 91.102.231.242#53 Sep 09 22:37:10 volumio-goco dnsmasq[39722]: using nameserver 208.67.222.222#53 Sep 09 22:37:10 volumio-goco dnsmasq[39722]: using nameserver 208.67.220.220#53 Sep 09 22:37:10 volumio-goco dnsmasq[39722]: read /etc/hosts - 4 names Sep 09 22:37:10 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:12 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2406. Sep 09 22:37:12 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:12 volumio-goco dnsmasq[39722]: exiting on receipt of SIGTERM Sep 09 22:37:12 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:37:12 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:12 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:12 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:37:12 volumio-goco hostapd[39728]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:37:12 volumio-goco hostapd[39728]: nl80211: Could not configure driver mode Sep 09 22:37:12 volumio-goco hostapd[39728]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:37:12 volumio-goco hostapd[39728]: nl80211 driver initialization failed. Sep 09 22:37:12 volumio-goco hostapd[39728]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:37:12 volumio-goco hostapd[39728]: wlan0: AP-DISABLED Sep 09 22:37:12 volumio-goco hostapd[39728]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:37:12 volumio-goco hostapd[39728]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:37:12 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:37:12 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:37:12 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:12 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:12 volumio-goco dnsmasq[39733]: started, version 2.90 cachesize 150 Sep 09 22:37:12 volumio-goco dnsmasq[39733]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:37:12 volumio-goco dnsmasq-dhcp[39733]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:37:12 volumio-goco dnsmasq[39733]: reading /etc/resolv.conf Sep 09 22:37:12 volumio-goco dnsmasq[39733]: using nameserver 192.168.3.1#53 Sep 09 22:37:12 volumio-goco dnsmasq[39733]: using nameserver 91.102.231.241#53 Sep 09 22:37:12 volumio-goco dnsmasq[39733]: using nameserver 91.102.231.242#53 Sep 09 22:37:12 volumio-goco dnsmasq[39733]: using nameserver 208.67.222.222#53 Sep 09 22:37:12 volumio-goco dnsmasq[39733]: using nameserver 208.67.220.220#53 Sep 09 22:37:12 volumio-goco dnsmasq[39733]: read /etc/hosts - 4 names Sep 09 22:37:12 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:13 volumio-goco volumio[1001]: info: Preload queue cleared Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreStateMachine::ClearQueue Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreStateMachine::stop Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreStateMachine::stPlaybackTimer Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreStateMachine::updateTrackBlock Sep 09 22:37:13 volumio-goco volumio[1001]: info: CorePlayQueue::getTrackBlock Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:37:13 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreStateMachine::serviceStop Sep 09 22:37:13 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreCommandRouter::serviceStop Sep 09 22:37:13 volumio-goco volumio[1001]: info: [1757450233573] ControllerWebradio::stop Sep 09 22:37:13 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand stop Sep 09 22:37:13 volumio-goco volumio[1001]: info: CorePlayQueue::clearPlayQueue Sep 09 22:37:13 volumio-goco volumio[1001]: info: CorePlayQueue::saveQueue Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushQueue Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreStateMachine::addQueueItems Sep 09 22:37:13 volumio-goco volumio[1001]: info: CorePlayQueue::addQueueItems Sep 09 22:37:13 volumio-goco volumio[1001]: info: Preload queue cleared Sep 09 22:37:13 volumio-goco volumio[1001]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s175687 Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 09 22:37:13 volumio-goco volumio[1001]: info: FusionDsp - Volumio is not playing Sep 09 22:37:13 volumio-goco volumio[1001]: info: FusionDsp - Clipped samples monitor stopped Sep 09 22:37:13 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:37:13 volumio-goco volumio[1001]: info: Sep 09 22:37:13 volumio-goco volumio[1001]: ---------------------------- MPD announces state update: player Sep 09 22:37:13 volumio-goco volumio[1001]: info: sendMpdCommand stop took 61 milliseconds Sep 09 22:37:13 volumio-goco volumio[1001]: info: ControllerMpd::getState Sep 09 22:37:13 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Sep 09 22:37:13 volumio-goco volumio[1001]: info: Sep 09 22:37:13 volumio-goco volumio[1001]: ---------------------------- MPD announces state update: player Sep 09 22:37:13 volumio-goco volumio[1001]: info: ControllerMpd::getState Sep 09 22:37:13 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Sep 09 22:37:13 volumio-goco volumio[1001]: info: sendMpdCommand status took 7 milliseconds Sep 09 22:37:13 volumio-goco volumio[1001]: info: sendMpdCommand status took 7 milliseconds Sep 09 22:37:13 volumio-goco volumio[1001]: verbose: ControllerMpd::parseState Sep 09 22:37:13 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 22:37:13 volumio-goco volumio[1001]: verbose: ControllerMpd::parseState Sep 09 22:37:13 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 22:37:13 volumio-goco volumio[1001]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 09 22:37:13 volumio-goco volumio[1001]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 09 22:37:13 volumio-goco volumio[1001]: verbose: ControllerMpd::parseTrackInfo Sep 09 22:37:13 volumio-goco volumio[1001]: verbose: ControllerMpd::parseTrackInfo Sep 09 22:37:13 volumio-goco volumio[1001]: info: ControllerMpd::pushState Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreCommandRouter::servicePushState Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:37:13 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:37:13 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:37:13 volumio-goco volumio[1001]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mr2.mp3","artist":null,"album":null,"uri":"http://mr-stream.mediaconnect.hu/4738/mr2.mp3","trackType":"mp3"} Sep 09 22:37:13 volumio-goco volumio[1001]: verbose: CURRENT POSITION 0 Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreStateMachine::syncState stateService stop Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:37:13 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:37:13 volumio-goco volumio[1001]: info: No code Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:37:13 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:37:13 volumio-goco volumio[1001]: info: ControllerMpd::pushState Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreCommandRouter::servicePushState Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:37:13 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:37:13 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:37:13 volumio-goco volumio[1001]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mr2.mp3","artist":null,"album":null,"uri":"http://mr-stream.mediaconnect.hu/4738/mr2.mp3","trackType":"mp3"} Sep 09 22:37:13 volumio-goco volumio[1001]: verbose: CURRENT POSITION 0 Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreStateMachine::syncState stateService stop Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:37:13 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:37:13 volumio-goco volumio[1001]: info: No code Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:37:13 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:37:13 volumio-goco volumio[1001]: info: ------------------------------ 71ms Sep 09 22:37:13 volumio-goco volumio[1001]: info: ------------------------------ 70ms Sep 09 22:37:13 volumio-goco volumio[1001]: info: FusionDsp - Volumio is not playing Sep 09 22:37:13 volumio-goco volumio[1001]: info: FusionDsp - Clipped samples monitor stopped Sep 09 22:37:13 volumio-goco volumio[1001]: info: FusionDsp - Volumio is not playing Sep 09 22:37:13 volumio-goco volumio[1001]: info: FusionDsp - Clipped samples monitor stopped Sep 09 22:37:13 volumio-goco volumio[1001]: info: FusionDsp - Volumio is not playing Sep 09 22:37:13 volumio-goco volumio[1001]: info: FusionDsp - Clipped samples monitor stopped Sep 09 22:37:13 volumio-goco volumio[1001]: info: FusionDsp - Volumio is not playing Sep 09 22:37:13 volumio-goco volumio[1001]: info: FusionDsp - Clipped samples monitor stopped Sep 09 22:37:13 volumio-goco volumio[1001]: info: FusionDsp - Volumio is not playing Sep 09 22:37:13 volumio-goco volumio[1001]: info: FusionDsp - Clipped samples monitor stopped Sep 09 22:37:13 volumio-goco volumio[1001]: info: FusionDsp - Volumio is not playing Sep 09 22:37:13 volumio-goco volumio[1001]: info: FusionDsp - Clipped samples monitor stopped Sep 09 22:37:13 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:37:13 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:37:13 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:37:13 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:37:13 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:37:13 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:37:13 volumio-goco systemd[1]: systemd-timedated.service: Deactivated successfully. Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushQueue Sep 09 22:37:13 volumio-goco volumio[1001]: info: CorePlayQueue::saveQueue Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreStateMachine::updateTrackBlock Sep 09 22:37:13 volumio-goco volumio[1001]: info: CorePlayQueue::getTrackBlock Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPlay Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreStateMachine::play index 0 Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreStateMachine::stop Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreStateMachine::play index undefined Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 22:37:13 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:37:13 volumio-goco volumio[1001]: info: CoreStateMachine::startPlaybackTimer Sep 09 22:37:13 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:37:13 volumio-goco volumio[1001]: info: [1757450233985] ControllerWebradio::clearAddPlayTrack Sep 09 22:37:13 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand stop Sep 09 22:37:13 volumio-goco volumio[1001]: info: sendMpdCommand stop took 3 milliseconds Sep 09 22:37:13 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand clear Sep 09 22:37:13 volumio-goco volumio[1001]: info: Sep 09 22:37:13 volumio-goco volumio[1001]: ---------------------------- MPD announces system playlist update Sep 09 22:37:13 volumio-goco volumio[1001]: info: Ignoring MPD Status Update Sep 09 22:37:13 volumio-goco volumio[1001]: info: sendMpdCommand clear took 1 milliseconds Sep 09 22:37:13 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand load "http://max96-naxinacional.streaming.rs:8530/;" Sep 09 22:37:13 volumio-goco volumio[1001]: info: Sep 09 22:37:13 volumio-goco volumio[1001]: ---------------------------- MPD announces system playlist update Sep 09 22:37:13 volumio-goco volumio[1001]: info: Ignoring MPD Status Update Sep 09 22:37:13 volumio-goco volumio[1001]: error: updateQueue error: null Sep 09 22:37:13 volumio-goco volumio[1001]: info: ------------------------------ 4ms Sep 09 22:37:14 volumio-goco volumio[1001]: error: updateQueue error: null Sep 09 22:37:14 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand add "http://max96-naxinacional.streaming.rs:8530/;" Sep 09 22:37:14 volumio-goco volumio[1001]: info: ------------------------------ 101ms Sep 09 22:37:14 volumio-goco volumio[1001]: info: Sep 09 22:37:14 volumio-goco volumio[1001]: ---------------------------- MPD announces system playlist update Sep 09 22:37:14 volumio-goco volumio[1001]: info: Ignoring MPD Status Update Sep 09 22:37:14 volumio-goco volumio[1001]: info: sendMpdCommand add "http://max96-naxinacional.streaming.rs:8530/;" took 2 milliseconds Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 09 22:37:14 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand play Sep 09 22:37:14 volumio-goco volumio[1001]: info: Sep 09 22:37:14 volumio-goco volumio[1001]: ---------------------------- MPD announces system playlist update Sep 09 22:37:14 volumio-goco volumio[1001]: info: Ignoring MPD Status Update Sep 09 22:37:14 volumio-goco volumio[1001]: info: ------------------------------ 5ms Sep 09 22:37:14 volumio-goco volumio[1001]: info: sendMpdCommand play took 4 milliseconds Sep 09 22:37:14 volumio-goco volumio[1001]: info: ------------------------------ 4ms Sep 09 22:37:14 volumio-goco volumio[1001]: info: Sep 09 22:37:14 volumio-goco volumio[1001]: ---------------------------- MPD announces state update: player Sep 09 22:37:14 volumio-goco volumio[1001]: info: ControllerMpd::getState Sep 09 22:37:14 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Sep 09 22:37:14 volumio-goco volumio[1001]: info: Sep 09 22:37:14 volumio-goco volumio[1001]: ---------------------------- MPD announces state update: player Sep 09 22:37:14 volumio-goco volumio[1001]: info: ControllerMpd::getState Sep 09 22:37:14 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Sep 09 22:37:14 volumio-goco volumio[1001]: info: FusionDsp - ---- read samplerate, raw: Sep 09 22:37:14 volumio-goco volumio[1001]: error: FusionDsp - invalid sample rate Sep 09 22:37:14 volumio-goco volumio[1001]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Sep 09 22:37:14 volumio-goco volumio[1001]: info: FusionDsp - ---- read samplerate from file: 44100 Sep 09 22:37:14 volumio-goco volumio[1001]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 09 22:37:14 volumio-goco volumio[1001]: info: Sep 09 22:37:14 volumio-goco volumio[1001]: ---------------------------- MPD announces state update: player Sep 09 22:37:14 volumio-goco volumio[1001]: info: sendMpdCommand status took 28 milliseconds Sep 09 22:37:14 volumio-goco volumio[1001]: info: ControllerMpd::getState Sep 09 22:37:14 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Sep 09 22:37:14 volumio-goco volumio[1001]: info: sendMpdCommand status took 26 milliseconds Sep 09 22:37:14 volumio-goco volumio[1001]: verbose: ControllerMpd::parseState Sep 09 22:37:14 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 22:37:14 volumio-goco volumio[1001]: verbose: ControllerMpd::parseState Sep 09 22:37:14 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 22:37:14 volumio-goco volumio[1001]: info: Sep 09 22:37:14 volumio-goco volumio[1001]: ---------------------------- MPD announces state update: player Sep 09 22:37:14 volumio-goco volumio[1001]: info: ControllerMpd::getState Sep 09 22:37:14 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Sep 09 22:37:14 volumio-goco volumio[1001]: info: Sep 09 22:37:14 volumio-goco volumio[1001]: ---------------------------- MPD announces system playlist update Sep 09 22:37:14 volumio-goco volumio[1001]: info: Ignoring MPD Status Update Sep 09 22:37:14 volumio-goco volumio[1001]: info: sendMpdCommand status took 13 milliseconds Sep 09 22:37:14 volumio-goco volumio[1001]: info: sendMpdCommand playlistinfo took 14 milliseconds Sep 09 22:37:14 volumio-goco volumio[1001]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 09 22:37:14 volumio-goco volumio[1001]: info: sendMpdCommand status took 8 milliseconds Sep 09 22:37:14 volumio-goco volumio[1001]: verbose: ControllerMpd::parseState Sep 09 22:37:14 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 22:37:14 volumio-goco volumio[1001]: verbose: ControllerMpd::parseTrackInfo Sep 09 22:37:14 volumio-goco volumio[1001]: verbose: ControllerMpd::parseTrackInfo Sep 09 22:37:14 volumio-goco volumio[1001]: verbose: ControllerMpd::parseState Sep 09 22:37:14 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 22:37:14 volumio-goco volumio[1001]: info: ControllerMpd::pushState Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreCommandRouter::servicePushState Sep 09 22:37:14 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:37:14 volumio-goco volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":";","artist":null,"album":null,"uri":"http://max96-naxinacional.streaming.rs:8530/;","trackType":"rs:8530/;"} Sep 09 22:37:14 volumio-goco volumio[1001]: verbose: CURRENT POSITION 0 Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreStateMachine::syncState stateService play Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Sep 09 22:37:14 volumio-goco volumio[1001]: info: ControllerMpd::pushState Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreCommandRouter::servicePushState Sep 09 22:37:14 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:37:14 volumio-goco volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":";","artist":null,"album":null,"uri":"http://max96-naxinacional.streaming.rs:8530/;","trackType":"rs:8530/;"} Sep 09 22:37:14 volumio-goco volumio[1001]: verbose: CURRENT POSITION 0 Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreStateMachine::syncState stateService play Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreStateMachine::syncState currentStatus play Sep 09 22:37:14 volumio-goco volumio[1001]: info: Received an update from plugin. extracting info from payload Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:37:14 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:37:14 volumio-goco volumio[1001]: info: ------------------------------ 79ms Sep 09 22:37:14 volumio-goco volumio[1001]: info: ------------------------------ 84ms Sep 09 22:37:14 volumio-goco volumio[1001]: info: Sep 09 22:37:14 volumio-goco volumio[1001]: ---------------------------- MPD announces system playlist update Sep 09 22:37:14 volumio-goco volumio[1001]: info: Ignoring MPD Status Update Sep 09 22:37:14 volumio-goco volumio[1001]: info: Sep 09 22:37:14 volumio-goco volumio[1001]: ---------------------------- MPD announces state update: player Sep 09 22:37:14 volumio-goco volumio[1001]: info: ControllerMpd::getState Sep 09 22:37:14 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Sep 09 22:37:14 volumio-goco volumio[1001]: info: ------------------------------ 53ms Sep 09 22:37:14 volumio-goco volumio[1001]: info: sendMpdCommand playlistinfo took 43 milliseconds Sep 09 22:37:14 volumio-goco volumio[1001]: info: sendMpdCommand playlistinfo took 42 milliseconds Sep 09 22:37:14 volumio-goco volumio[1001]: verbose: ControllerMpd::parseTrackInfo Sep 09 22:37:14 volumio-goco volumio[1001]: verbose: ControllerMpd::parseTrackInfo Sep 09 22:37:14 volumio-goco volumio[1001]: info: ControllerMpd::pushState Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreCommandRouter::servicePushState Sep 09 22:37:14 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:37:14 volumio-goco volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":";","artist":"Naxi MAX","album":null,"uri":"http://max96-naxinacional.streaming.rs:8530/;","trackType":"rs:8530/;"} Sep 09 22:37:14 volumio-goco volumio[1001]: verbose: CURRENT POSITION 0 Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreStateMachine::syncState stateService play Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreStateMachine::syncState currentStatus play Sep 09 22:37:14 volumio-goco volumio[1001]: info: Received an update from plugin. extracting info from payload Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:37:14 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:37:14 volumio-goco volumio[1001]: info: ControllerMpd::pushState Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreCommandRouter::servicePushState Sep 09 22:37:14 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:37:14 volumio-goco volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":";","artist":"Naxi MAX","album":null,"uri":"http://max96-naxinacional.streaming.rs:8530/;","trackType":"rs:8530/;"} Sep 09 22:37:14 volumio-goco volumio[1001]: verbose: CURRENT POSITION 0 Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreStateMachine::syncState stateService play Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreStateMachine::syncState currentStatus play Sep 09 22:37:14 volumio-goco volumio[1001]: info: Received an update from plugin. extracting info from payload Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:37:14 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:37:14 volumio-goco volumio[1001]: info: ------------------------------ 121ms Sep 09 22:37:14 volumio-goco volumio[1001]: info: ------------------------------ 111ms Sep 09 22:37:14 volumio-goco volumio[1001]: info: FusionDsp - Volumio is playing Sep 09 22:37:14 volumio-goco volumio[1001]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Sep 09 22:37:14 volumio-goco volumio[1001]: info: FusionDsp - Volumio is playing Sep 09 22:37:14 volumio-goco volumio[1001]: info: FusionDsp - Volumio is playing Sep 09 22:37:14 volumio-goco volumio[1001]: info: FusionDsp - Volumio is playing Sep 09 22:37:14 volumio-goco volumio[1001]: info: FusionDsp - Volumio is playing Sep 09 22:37:14 volumio-goco volumio[1001]: info: FusionDsp - Volumio is playing Sep 09 22:37:14 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:37:14 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:37:14 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:37:14 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:37:14 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:37:14 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:37:14 volumio-goco volumio[1001]: info: ------------------------------ 140ms Sep 09 22:37:14 volumio-goco volumio[1001]: info: sendMpdCommand status took 135 milliseconds Sep 09 22:37:14 volumio-goco volumio[1001]: verbose: ControllerMpd::parseState Sep 09 22:37:14 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 22:37:14 volumio-goco volumio[1001]: info: Sep 09 22:37:14 volumio-goco volumio[1001]: ---------------------------- MPD announces state update: player Sep 09 22:37:14 volumio-goco volumio[1001]: info: ControllerMpd::getState Sep 09 22:37:14 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Sep 09 22:37:14 volumio-goco volumio[1001]: info: sendMpdCommand playlistinfo took 18 milliseconds Sep 09 22:37:14 volumio-goco volumio[1001]: info: sendMpdCommand status took 12 milliseconds Sep 09 22:37:14 volumio-goco volumio[1001]: verbose: ControllerMpd::parseTrackInfo Sep 09 22:37:14 volumio-goco volumio[1001]: verbose: ControllerMpd::parseState Sep 09 22:37:14 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 22:37:14 volumio-goco volumio[1001]: info: ControllerMpd::pushState Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreCommandRouter::servicePushState Sep 09 22:37:14 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:37:14 volumio-goco volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":";","artist":"Naxi MAX","album":null,"uri":"http://max96-naxinacional.streaming.rs:8530/;","trackType":"rs:8530/;"} Sep 09 22:37:14 volumio-goco volumio[1001]: verbose: CURRENT POSITION 0 Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreStateMachine::syncState stateService play Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreStateMachine::syncState currentStatus play Sep 09 22:37:14 volumio-goco volumio[1001]: info: Received an update from plugin. extracting info from payload Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:37:14 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:37:14 volumio-goco volumio[1001]: info: ------------------------------ 175ms Sep 09 22:37:14 volumio-goco volumio[1001]: info: sendMpdCommand playlistinfo took 19 milliseconds Sep 09 22:37:14 volumio-goco volumio[1001]: verbose: ControllerMpd::parseTrackInfo Sep 09 22:37:14 volumio-goco volumio[1001]: info: ControllerMpd::pushState Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreCommandRouter::servicePushState Sep 09 22:37:14 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:37:14 volumio-goco volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":";","artist":"Naxi MAX","album":null,"uri":"http://max96-naxinacional.streaming.rs:8530/;","trackType":"rs:8530/;"} Sep 09 22:37:14 volumio-goco volumio[1001]: verbose: CURRENT POSITION 0 Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreStateMachine::syncState stateService play Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreStateMachine::syncState currentStatus play Sep 09 22:37:14 volumio-goco volumio[1001]: info: Received an update from plugin. extracting info from payload Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:37:14 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:37:14 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:37:14 volumio-goco volumio[1001]: info: ------------------------------ 52ms Sep 09 22:37:14 volumio-goco volumio[1001]: error: FusionDsp - Monitor WebSocket error: [object Object] Sep 09 22:37:14 volumio-goco volumio[1001]: error: FusionDsp - Monitor WebSocket error: [object Object] Sep 09 22:37:14 volumio-goco volumio[1001]: error: FusionDsp - Monitor WebSocket error: [object Object] Sep 09 22:37:14 volumio-goco volumio[1001]: error: FusionDsp - Monitor WebSocket error: [object Object] Sep 09 22:37:14 volumio-goco volumio[1001]: error: FusionDsp - Monitor WebSocket error: [object Object] Sep 09 22:37:14 volumio-goco volumio[1001]: error: FusionDsp - Monitor WebSocket error: [object Object] Sep 09 22:37:14 volumio-goco volumio[1001]: info: FusionDsp - Volumio is playing Sep 09 22:37:14 volumio-goco volumio[1001]: info: FusionDsp - Volumio is playing Sep 09 22:37:14 volumio-goco volumio[1001]: info: FusionDsp - Volumio is playing Sep 09 22:37:14 volumio-goco volumio[1001]: info: FusionDsp - Volumio is playing Sep 09 22:37:14 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:37:14 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:37:14 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:37:14 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:37:14 volumio-goco volumio[1001]: error: FusionDsp - Monitor WebSocket error: [object Object] Sep 09 22:37:14 volumio-goco volumio[1001]: error: FusionDsp - Monitor WebSocket error: [object Object] Sep 09 22:37:14 volumio-goco volumio[1001]: error: FusionDsp - Monitor WebSocket error: [object Object] Sep 09 22:37:14 volumio-goco volumio[1001]: error: FusionDsp - Monitor WebSocket error: [object Object] Sep 09 22:37:14 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2407. Sep 09 22:37:14 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:14 volumio-goco dnsmasq[39733]: exiting on receipt of SIGTERM Sep 09 22:37:14 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:37:14 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:14 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:14 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:37:14 volumio-goco hostapd[39771]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:37:14 volumio-goco hostapd[39771]: nl80211: Could not configure driver mode Sep 09 22:37:14 volumio-goco hostapd[39771]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:37:14 volumio-goco hostapd[39771]: nl80211 driver initialization failed. Sep 09 22:37:14 volumio-goco hostapd[39771]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:37:14 volumio-goco hostapd[39771]: wlan0: AP-DISABLED Sep 09 22:37:14 volumio-goco hostapd[39771]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:37:14 volumio-goco hostapd[39771]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:37:14 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:37:15 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:37:15 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:15 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:15 volumio-goco dnsmasq[39776]: started, version 2.90 cachesize 150 Sep 09 22:37:15 volumio-goco dnsmasq[39776]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:37:15 volumio-goco dnsmasq-dhcp[39776]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:37:15 volumio-goco dnsmasq[39776]: reading /etc/resolv.conf Sep 09 22:37:15 volumio-goco dnsmasq[39776]: using nameserver 192.168.3.1#53 Sep 09 22:37:15 volumio-goco dnsmasq[39776]: using nameserver 91.102.231.241#53 Sep 09 22:37:15 volumio-goco dnsmasq[39776]: using nameserver 91.102.231.242#53 Sep 09 22:37:15 volumio-goco dnsmasq[39776]: using nameserver 208.67.222.222#53 Sep 09 22:37:15 volumio-goco dnsmasq[39776]: using nameserver 208.67.220.220#53 Sep 09 22:37:15 volumio-goco dnsmasq[39776]: read /etc/hosts - 4 names Sep 09 22:37:15 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:16 volumio-goco volumio[1001]: info: FusionDsp - Clipping Monitor started Sep 09 22:37:16 volumio-goco volumio[1001]: info: FusionDsp - Clipping Monitor started Sep 09 22:37:16 volumio-goco volumio[1001]: info: FusionDsp - Clipping Monitor started Sep 09 22:37:16 volumio-goco volumio[1001]: info: FusionDsp - Clipping Monitor started Sep 09 22:37:16 volumio-goco volumio[1001]: info: FusionDsp - Clipping Monitor started Sep 09 22:37:16 volumio-goco volumio[1001]: info: FusionDsp - Clipping Monitor started Sep 09 22:37:17 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2408. Sep 09 22:37:17 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:17 volumio-goco dnsmasq[39776]: exiting on receipt of SIGTERM Sep 09 22:37:17 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:37:17 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:17 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:17 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:37:17 volumio-goco hostapd[39788]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:37:17 volumio-goco hostapd[39788]: nl80211: Could not configure driver mode Sep 09 22:37:17 volumio-goco hostapd[39788]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:37:17 volumio-goco hostapd[39788]: nl80211 driver initialization failed. Sep 09 22:37:17 volumio-goco hostapd[39788]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:37:17 volumio-goco hostapd[39788]: wlan0: AP-DISABLED Sep 09 22:37:17 volumio-goco hostapd[39788]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:37:17 volumio-goco hostapd[39788]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:37:17 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:37:17 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:37:17 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:17 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:17 volumio-goco dnsmasq[39793]: started, version 2.90 cachesize 150 Sep 09 22:37:17 volumio-goco dnsmasq[39793]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:37:17 volumio-goco dnsmasq-dhcp[39793]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:37:17 volumio-goco dnsmasq[39793]: reading /etc/resolv.conf Sep 09 22:37:17 volumio-goco dnsmasq[39793]: using nameserver 192.168.3.1#53 Sep 09 22:37:17 volumio-goco dnsmasq[39793]: using nameserver 91.102.231.241#53 Sep 09 22:37:17 volumio-goco dnsmasq[39793]: using nameserver 91.102.231.242#53 Sep 09 22:37:17 volumio-goco dnsmasq[39793]: using nameserver 208.67.222.222#53 Sep 09 22:37:17 volumio-goco dnsmasq[39793]: using nameserver 208.67.220.220#53 Sep 09 22:37:17 volumio-goco dnsmasq[39793]: read /etc/hosts - 4 names Sep 09 22:37:17 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:18 volumio-goco volumio[1001]: info: Preload queue cleared Sep 09 22:37:18 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 09 22:37:18 volumio-goco volumio[1001]: info: CoreStateMachine::ClearQueue Sep 09 22:37:18 volumio-goco volumio[1001]: info: CoreStateMachine::stop Sep 09 22:37:18 volumio-goco volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 22:37:18 volumio-goco volumio[1001]: info: CoreStateMachine::stPlaybackTimer Sep 09 22:37:18 volumio-goco volumio[1001]: info: CoreStateMachine::updateTrackBlock Sep 09 22:37:18 volumio-goco volumio[1001]: info: CorePlayQueue::getTrackBlock Sep 09 22:37:18 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:37:18 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:37:18 volumio-goco volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 09 22:37:18 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:37:18 volumio-goco volumio[1001]: info: CoreStateMachine::serviceStop Sep 09 22:37:18 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:37:18 volumio-goco volumio[1001]: info: CoreCommandRouter::serviceStop Sep 09 22:37:18 volumio-goco volumio[1001]: info: [1757450238958] ControllerWebradio::stop Sep 09 22:37:18 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand stop Sep 09 22:37:18 volumio-goco volumio[1001]: info: CorePlayQueue::clearPlayQueue Sep 09 22:37:18 volumio-goco volumio[1001]: info: CorePlayQueue::saveQueue Sep 09 22:37:18 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushQueue Sep 09 22:37:18 volumio-goco volumio[1001]: info: CoreStateMachine::addQueueItems Sep 09 22:37:18 volumio-goco volumio[1001]: info: CorePlayQueue::addQueueItems Sep 09 22:37:18 volumio-goco volumio[1001]: info: Preload queue cleared Sep 09 22:37:18 volumio-goco volumio[1001]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s90194 Sep 09 22:37:18 volumio-goco volumio[1001]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 09 22:37:18 volumio-goco volumio[1001]: info: FusionDsp - Volumio is not playing Sep 09 22:37:18 volumio-goco volumio[1001]: info: FusionDsp - Clipped samples monitor stopped Sep 09 22:37:19 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:37:19 volumio-goco volumio[1001]: info: Sep 09 22:37:19 volumio-goco volumio[1001]: ---------------------------- MPD announces state update: player Sep 09 22:37:19 volumio-goco volumio[1001]: info: sendMpdCommand stop took 44 milliseconds Sep 09 22:37:19 volumio-goco volumio[1001]: info: ControllerMpd::getState Sep 09 22:37:19 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Sep 09 22:37:19 volumio-goco volumio[1001]: info: Sep 09 22:37:19 volumio-goco volumio[1001]: ---------------------------- MPD announces state update: player Sep 09 22:37:19 volumio-goco volumio[1001]: info: ControllerMpd::getState Sep 09 22:37:19 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Sep 09 22:37:19 volumio-goco volumio[1001]: info: sendMpdCommand status took 11 milliseconds Sep 09 22:37:19 volumio-goco volumio[1001]: info: sendMpdCommand status took 19 milliseconds Sep 09 22:37:19 volumio-goco volumio[1001]: verbose: ControllerMpd::parseState Sep 09 22:37:19 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 22:37:19 volumio-goco volumio[1001]: verbose: ControllerMpd::parseState Sep 09 22:37:19 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 22:37:19 volumio-goco volumio[1001]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 09 22:37:19 volumio-goco volumio[1001]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 09 22:37:19 volumio-goco volumio[1001]: verbose: ControllerMpd::parseTrackInfo Sep 09 22:37:19 volumio-goco volumio[1001]: verbose: ControllerMpd::parseTrackInfo Sep 09 22:37:19 volumio-goco volumio[1001]: info: ControllerMpd::pushState Sep 09 22:37:19 volumio-goco volumio[1001]: info: CoreCommandRouter::servicePushState Sep 09 22:37:19 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:37:19 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:37:19 volumio-goco volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 09 22:37:19 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:37:19 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:37:19 volumio-goco volumio[1001]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":";","artist":"Naxi MAX","album":null,"uri":"http://max96-naxinacional.streaming.rs:8530/;","trackType":"rs:8530/;"} Sep 09 22:37:19 volumio-goco volumio[1001]: verbose: CURRENT POSITION 0 Sep 09 22:37:19 volumio-goco volumio[1001]: info: CoreStateMachine::syncState stateService stop Sep 09 22:37:19 volumio-goco volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Sep 09 22:37:19 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:37:19 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:37:19 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:37:19 volumio-goco volumio[1001]: info: No code Sep 09 22:37:19 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:37:19 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:37:19 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:37:19 volumio-goco volumio[1001]: info: ControllerMpd::pushState Sep 09 22:37:19 volumio-goco volumio[1001]: info: CoreCommandRouter::servicePushState Sep 09 22:37:19 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:37:19 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:37:19 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:37:19 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:37:19 volumio-goco volumio[1001]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":";","artist":"Naxi MAX","album":null,"uri":"http://max96-naxinacional.streaming.rs:8530/;","trackType":"rs:8530/;"} Sep 09 22:37:19 volumio-goco volumio[1001]: verbose: CURRENT POSITION 0 Sep 09 22:37:19 volumio-goco volumio[1001]: info: CoreStateMachine::syncState stateService stop Sep 09 22:37:19 volumio-goco volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Sep 09 22:37:19 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:37:19 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:37:19 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:37:19 volumio-goco volumio[1001]: info: No code Sep 09 22:37:19 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:37:19 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:37:19 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:37:19 volumio-goco volumio[1001]: info: ------------------------------ 118ms Sep 09 22:37:19 volumio-goco volumio[1001]: info: ------------------------------ 120ms Sep 09 22:37:19 volumio-goco volumio[1001]: info: FusionDsp - Volumio is not playing Sep 09 22:37:19 volumio-goco volumio[1001]: info: FusionDsp - Clipped samples monitor stopped Sep 09 22:37:19 volumio-goco volumio[1001]: info: FusionDsp - Volumio is not playing Sep 09 22:37:19 volumio-goco volumio[1001]: info: FusionDsp - Clipped samples monitor stopped Sep 09 22:37:19 volumio-goco volumio[1001]: info: FusionDsp - Volumio is not playing Sep 09 22:37:19 volumio-goco volumio[1001]: info: FusionDsp - Clipped samples monitor stopped Sep 09 22:37:19 volumio-goco volumio[1001]: info: FusionDsp - Volumio is not playing Sep 09 22:37:19 volumio-goco volumio[1001]: info: FusionDsp - Clipped samples monitor stopped Sep 09 22:37:19 volumio-goco volumio[1001]: info: FusionDsp - Volumio is not playing Sep 09 22:37:19 volumio-goco volumio[1001]: info: FusionDsp - Clipped samples monitor stopped Sep 09 22:37:19 volumio-goco volumio[1001]: info: FusionDsp - Volumio is not playing Sep 09 22:37:19 volumio-goco volumio[1001]: info: FusionDsp - Clipped samples monitor stopped Sep 09 22:37:19 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:37:19 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:37:19 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:37:19 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:37:19 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:37:19 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:37:19 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushQueue Sep 09 22:37:19 volumio-goco volumio[1001]: info: CorePlayQueue::saveQueue Sep 09 22:37:19 volumio-goco volumio[1001]: info: CoreStateMachine::updateTrackBlock Sep 09 22:37:19 volumio-goco volumio[1001]: info: CorePlayQueue::getTrackBlock Sep 09 22:37:19 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPlay Sep 09 22:37:19 volumio-goco volumio[1001]: info: CoreStateMachine::play index 0 Sep 09 22:37:19 volumio-goco volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 22:37:19 volumio-goco volumio[1001]: info: CoreStateMachine::stop Sep 09 22:37:19 volumio-goco volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 22:37:19 volumio-goco volumio[1001]: info: CoreStateMachine::play index undefined Sep 09 22:37:19 volumio-goco volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 09 22:37:19 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:37:19 volumio-goco volumio[1001]: info: CoreStateMachine::startPlaybackTimer Sep 09 22:37:19 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:37:19 volumio-goco volumio[1001]: info: [1757450239214] ControllerWebradio::clearAddPlayTrack Sep 09 22:37:19 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand stop Sep 09 22:37:19 volumio-goco volumio[1001]: info: sendMpdCommand stop took 6 milliseconds Sep 09 22:37:19 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand clear Sep 09 22:37:19 volumio-goco volumio[1001]: info: Sep 09 22:37:19 volumio-goco volumio[1001]: ---------------------------- MPD announces system playlist update Sep 09 22:37:19 volumio-goco volumio[1001]: info: Ignoring MPD Status Update Sep 09 22:37:19 volumio-goco volumio[1001]: info: sendMpdCommand clear took 1 milliseconds Sep 09 22:37:19 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand load "http://breddstream.unrealhosting.hu:4110/" Sep 09 22:37:19 volumio-goco volumio[1001]: info: Sep 09 22:37:19 volumio-goco volumio[1001]: ---------------------------- MPD announces system playlist update Sep 09 22:37:19 volumio-goco volumio[1001]: info: Ignoring MPD Status Update Sep 09 22:37:19 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2409. Sep 09 22:37:19 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:19 volumio-goco dnsmasq[39793]: exiting on receipt of SIGTERM Sep 09 22:37:19 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:37:19 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:19 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:19 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:37:19 volumio-goco hostapd[39802]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:37:19 volumio-goco hostapd[39802]: nl80211: Could not configure driver mode Sep 09 22:37:19 volumio-goco hostapd[39802]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:37:19 volumio-goco hostapd[39802]: nl80211 driver initialization failed. Sep 09 22:37:19 volumio-goco hostapd[39802]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:37:19 volumio-goco hostapd[39802]: wlan0: AP-DISABLED Sep 09 22:37:19 volumio-goco hostapd[39802]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:37:19 volumio-goco hostapd[39802]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:37:19 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:37:19 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:37:19 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:19 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:19 volumio-goco dnsmasq[39807]: started, version 2.90 cachesize 150 Sep 09 22:37:19 volumio-goco dnsmasq[39807]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:37:19 volumio-goco dnsmasq-dhcp[39807]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:37:19 volumio-goco dnsmasq[39807]: reading /etc/resolv.conf Sep 09 22:37:19 volumio-goco dnsmasq[39807]: using nameserver 192.168.3.1#53 Sep 09 22:37:19 volumio-goco dnsmasq[39807]: using nameserver 91.102.231.241#53 Sep 09 22:37:19 volumio-goco dnsmasq[39807]: using nameserver 91.102.231.242#53 Sep 09 22:37:19 volumio-goco dnsmasq[39807]: using nameserver 208.67.222.222#53 Sep 09 22:37:19 volumio-goco dnsmasq[39807]: using nameserver 208.67.220.220#53 Sep 09 22:37:19 volumio-goco dnsmasq[39807]: read /etc/hosts - 4 names Sep 09 22:37:19 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:19 volumio-goco volumio[1001]: error: updateQueue error: null Sep 09 22:37:19 volumio-goco volumio[1001]: error: updateQueue error: null Sep 09 22:37:19 volumio-goco volumio[1001]: info: ------------------------------ 429ms Sep 09 22:37:19 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand add "http://breddstream.unrealhosting.hu:4110/" Sep 09 22:37:19 volumio-goco volumio[1001]: info: ------------------------------ 430ms Sep 09 22:37:19 volumio-goco volumio[1001]: info: Sep 09 22:37:19 volumio-goco volumio[1001]: ---------------------------- MPD announces system playlist update Sep 09 22:37:19 volumio-goco volumio[1001]: info: Ignoring MPD Status Update Sep 09 22:37:19 volumio-goco volumio[1001]: info: sendMpdCommand add "http://breddstream.unrealhosting.hu:4110/" took 4 milliseconds Sep 09 22:37:19 volumio-goco volumio[1001]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 09 22:37:19 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand play Sep 09 22:37:19 volumio-goco volumio[1001]: info: Sep 09 22:37:19 volumio-goco volumio[1001]: ---------------------------- MPD announces system playlist update Sep 09 22:37:19 volumio-goco volumio[1001]: info: Ignoring MPD Status Update Sep 09 22:37:19 volumio-goco volumio[1001]: info: ------------------------------ 4ms Sep 09 22:37:19 volumio-goco volumio[1001]: info: sendMpdCommand play took 4 milliseconds Sep 09 22:37:19 volumio-goco volumio[1001]: info: ------------------------------ 3ms Sep 09 22:37:19 volumio-goco volumio[1001]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Sep 09 22:37:19 volumio-goco volumio[1001]: info: FusionDsp - ---- read samplerate from file: 44100 Sep 09 22:37:19 volumio-goco volumio[1001]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 09 22:37:19 volumio-goco volumio[1001]: info: Sep 09 22:37:19 volumio-goco volumio[1001]: ---------------------------- MPD announces state update: player Sep 09 22:37:20 volumio-goco volumio[1001]: info: ControllerMpd::getState Sep 09 22:37:20 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Sep 09 22:37:20 volumio-goco volumio[1001]: info: Sep 09 22:37:20 volumio-goco volumio[1001]: ---------------------------- MPD announces state update: player Sep 09 22:37:20 volumio-goco volumio[1001]: info: ControllerMpd::getState Sep 09 22:37:20 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Sep 09 22:37:20 volumio-goco volumio[1001]: info: Sep 09 22:37:20 volumio-goco volumio[1001]: ---------------------------- MPD announces system playlist update Sep 09 22:37:20 volumio-goco volumio[1001]: info: Ignoring MPD Status Update Sep 09 22:37:20 volumio-goco volumio[1001]: info: Sep 09 22:37:20 volumio-goco volumio[1001]: ---------------------------- MPD announces state update: player Sep 09 22:37:20 volumio-goco volumio[1001]: info: ControllerMpd::getState Sep 09 22:37:20 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Sep 09 22:37:20 volumio-goco volumio[1001]: info: Sep 09 22:37:20 volumio-goco volumio[1001]: ---------------------------- MPD announces system playlist update Sep 09 22:37:20 volumio-goco volumio[1001]: info: Ignoring MPD Status Update Sep 09 22:37:20 volumio-goco volumio[1001]: info: Sep 09 22:37:20 volumio-goco volumio[1001]: ---------------------------- MPD announces state update: player Sep 09 22:37:20 volumio-goco volumio[1001]: info: sendMpdCommand status took 28 milliseconds Sep 09 22:37:20 volumio-goco volumio[1001]: info: ControllerMpd::getState Sep 09 22:37:20 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Sep 09 22:37:20 volumio-goco volumio[1001]: info: sendMpdCommand status took 36 milliseconds Sep 09 22:37:20 volumio-goco volumio[1001]: info: ------------------------------ 37ms Sep 09 22:37:20 volumio-goco volumio[1001]: info: sendMpdCommand status took 34 milliseconds Sep 09 22:37:20 volumio-goco volumio[1001]: verbose: ControllerMpd::parseState Sep 09 22:37:20 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 22:37:20 volumio-goco volumio[1001]: verbose: ControllerMpd::parseState Sep 09 22:37:20 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 22:37:20 volumio-goco volumio[1001]: verbose: ControllerMpd::parseState Sep 09 22:37:20 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 22:37:20 volumio-goco volumio[1001]: info: ------------------------------ 42ms Sep 09 22:37:20 volumio-goco volumio[1001]: info: sendMpdCommand status took 38 milliseconds Sep 09 22:37:20 volumio-goco volumio[1001]: info: sendMpdCommand playlistinfo took 28 milliseconds Sep 09 22:37:20 volumio-goco volumio[1001]: info: sendMpdCommand playlistinfo took 15 milliseconds Sep 09 22:37:20 volumio-goco volumio[1001]: info: sendMpdCommand playlistinfo took 15 milliseconds Sep 09 22:37:20 volumio-goco volumio[1001]: verbose: ControllerMpd::parseState Sep 09 22:37:20 volumio-goco volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 09 22:37:20 volumio-goco volumio[1001]: verbose: ControllerMpd::parseTrackInfo Sep 09 22:37:20 volumio-goco volumio[1001]: verbose: ControllerMpd::parseTrackInfo Sep 09 22:37:20 volumio-goco volumio[1001]: verbose: ControllerMpd::parseTrackInfo Sep 09 22:37:20 volumio-goco volumio[1001]: info: ControllerMpd::pushState Sep 09 22:37:20 volumio-goco volumio[1001]: info: CoreCommandRouter::servicePushState Sep 09 22:37:20 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:37:20 volumio-goco volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"","artist":"No Name","album":null,"uri":"http://breddstream.unrealhosting.hu:4110/","trackType":"hu:4110/"} Sep 09 22:37:20 volumio-goco volumio[1001]: verbose: CURRENT POSITION 0 Sep 09 22:37:20 volumio-goco volumio[1001]: info: CoreStateMachine::syncState stateService play Sep 09 22:37:20 volumio-goco volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Sep 09 22:37:20 volumio-goco volumio[1001]: info: ControllerMpd::pushState Sep 09 22:37:20 volumio-goco volumio[1001]: info: CoreCommandRouter::servicePushState Sep 09 22:37:20 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:37:20 volumio-goco volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":969,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"","artist":"No Name","album":null,"uri":"http://breddstream.unrealhosting.hu:4110/","trackType":"hu:4110/"} Sep 09 22:37:20 volumio-goco volumio[1001]: verbose: CURRENT POSITION 0 Sep 09 22:37:20 volumio-goco volumio[1001]: info: CoreStateMachine::syncState stateService play Sep 09 22:37:20 volumio-goco volumio[1001]: info: CoreStateMachine::syncState currentStatus play Sep 09 22:37:20 volumio-goco volumio[1001]: info: Received an update from plugin. extracting info from payload Sep 09 22:37:20 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:37:20 volumio-goco volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 09 22:37:20 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:37:20 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:37:20 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:37:20 volumio-goco volumio[1001]: info: ControllerMpd::pushState Sep 09 22:37:20 volumio-goco volumio[1001]: info: CoreCommandRouter::servicePushState Sep 09 22:37:20 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:37:20 volumio-goco volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"","artist":"No Name","album":null,"uri":"http://breddstream.unrealhosting.hu:4110/","trackType":"hu:4110/"} Sep 09 22:37:20 volumio-goco volumio[1001]: verbose: CURRENT POSITION 0 Sep 09 22:37:20 volumio-goco volumio[1001]: info: CoreStateMachine::syncState stateService play Sep 09 22:37:20 volumio-goco volumio[1001]: info: CoreStateMachine::syncState currentStatus play Sep 09 22:37:20 volumio-goco volumio[1001]: info: Received an update from plugin. extracting info from payload Sep 09 22:37:20 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:37:20 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:37:20 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:37:20 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:37:20 volumio-goco volumio[1001]: info: ------------------------------ 97ms Sep 09 22:37:20 volumio-goco volumio[1001]: info: ------------------------------ 112ms Sep 09 22:37:20 volumio-goco volumio[1001]: info: ------------------------------ 112ms Sep 09 22:37:20 volumio-goco volumio[1001]: info: sendMpdCommand playlistinfo took 53 milliseconds Sep 09 22:37:20 volumio-goco volumio[1001]: verbose: ControllerMpd::parseTrackInfo Sep 09 22:37:20 volumio-goco volumio[1001]: info: ControllerMpd::pushState Sep 09 22:37:20 volumio-goco volumio[1001]: info: CoreCommandRouter::servicePushState Sep 09 22:37:20 volumio-goco volumio[1001]: info: CorePlayQueue::getTrack 0 Sep 09 22:37:20 volumio-goco volumio[1001]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1608,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"","artist":"No Name","album":null,"uri":"http://breddstream.unrealhosting.hu:4110/","trackType":"hu:4110/"} Sep 09 22:37:20 volumio-goco volumio[1001]: verbose: CURRENT POSITION 0 Sep 09 22:37:20 volumio-goco volumio[1001]: info: CoreStateMachine::syncState stateService play Sep 09 22:37:20 volumio-goco volumio[1001]: info: CoreStateMachine::syncState currentStatus play Sep 09 22:37:20 volumio-goco volumio[1001]: info: Received an update from plugin. extracting info from payload Sep 09 22:37:20 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:37:20 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:37:20 volumio-goco volumio[1001]: info: CoreStateMachine::pushState Sep 09 22:37:20 volumio-goco volumio[1001]: info: CoreCommandRouter::volumioPushState Sep 09 22:37:20 volumio-goco volumio[1001]: info: ------------------------------ 138ms Sep 09 22:37:20 volumio-goco volumio[1001]: info: FusionDsp - Volumio is playing Sep 09 22:37:20 volumio-goco volumio[1001]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Sep 09 22:37:20 volumio-goco volumio[1001]: info: FusionDsp - Volumio is playing Sep 09 22:37:20 volumio-goco volumio[1001]: info: FusionDsp - Volumio is playing Sep 09 22:37:20 volumio-goco volumio[1001]: info: FusionDsp - Volumio is playing Sep 09 22:37:20 volumio-goco volumio[1001]: info: FusionDsp - Volumio is playing Sep 09 22:37:20 volumio-goco volumio[1001]: info: FusionDsp - Volumio is playing Sep 09 22:37:20 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:37:20 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:37:20 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:37:20 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:37:20 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:37:20 volumio-goco volumio[1001]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:37:20 volumio-goco volumio[1001]: info: FusionDsp - Clipping Monitor started Sep 09 22:37:20 volumio-goco volumio[1001]: info: FusionDsp - Clipping Monitor started Sep 09 22:37:20 volumio-goco volumio[1001]: info: FusionDsp - Clipping Monitor started Sep 09 22:37:20 volumio-goco volumio[1001]: info: FusionDsp - Clipping Monitor started Sep 09 22:37:20 volumio-goco volumio[1001]: info: FusionDsp - Clipping Monitor started Sep 09 22:37:20 volumio-goco volumio[1001]: info: FusionDsp - Clipping Monitor started Sep 09 22:37:21 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2410. Sep 09 22:37:21 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:21 volumio-goco dnsmasq[39807]: exiting on receipt of SIGTERM Sep 09 22:37:21 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:37:21 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:21 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:21 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:37:21 volumio-goco hostapd[39829]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:37:21 volumio-goco hostapd[39829]: nl80211: Could not configure driver mode Sep 09 22:37:21 volumio-goco hostapd[39829]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:37:21 volumio-goco hostapd[39829]: nl80211 driver initialization failed. Sep 09 22:37:21 volumio-goco hostapd[39829]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:37:21 volumio-goco hostapd[39829]: wlan0: AP-DISABLED Sep 09 22:37:21 volumio-goco hostapd[39829]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:37:21 volumio-goco hostapd[39829]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:37:21 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:37:21 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:37:21 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:21 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:21 volumio-goco dnsmasq[39834]: started, version 2.90 cachesize 150 Sep 09 22:37:21 volumio-goco dnsmasq[39834]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:37:21 volumio-goco dnsmasq-dhcp[39834]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:37:21 volumio-goco dnsmasq[39834]: reading /etc/resolv.conf Sep 09 22:37:21 volumio-goco dnsmasq[39834]: using nameserver 192.168.3.1#53 Sep 09 22:37:21 volumio-goco dnsmasq[39834]: using nameserver 91.102.231.241#53 Sep 09 22:37:21 volumio-goco dnsmasq[39834]: using nameserver 91.102.231.242#53 Sep 09 22:37:21 volumio-goco dnsmasq[39834]: using nameserver 208.67.222.222#53 Sep 09 22:37:21 volumio-goco dnsmasq[39834]: using nameserver 208.67.220.220#53 Sep 09 22:37:21 volumio-goco dnsmasq[39834]: read /etc/hosts - 4 names Sep 09 22:37:21 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:23 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2411. Sep 09 22:37:23 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:23 volumio-goco dnsmasq[39834]: exiting on receipt of SIGTERM Sep 09 22:37:23 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:37:23 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:23 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:23 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:37:23 volumio-goco hostapd[39840]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:37:23 volumio-goco hostapd[39840]: nl80211: Could not configure driver mode Sep 09 22:37:23 volumio-goco hostapd[39840]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:37:23 volumio-goco hostapd[39840]: nl80211 driver initialization failed. Sep 09 22:37:23 volumio-goco hostapd[39840]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:37:23 volumio-goco hostapd[39840]: wlan0: AP-DISABLED Sep 09 22:37:23 volumio-goco hostapd[39840]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:37:23 volumio-goco hostapd[39840]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:37:23 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:37:23 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:37:23 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:24 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:24 volumio-goco dnsmasq[39859]: started, version 2.90 cachesize 150 Sep 09 22:37:24 volumio-goco dnsmasq[39859]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:37:24 volumio-goco dnsmasq-dhcp[39859]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:37:24 volumio-goco dnsmasq[39859]: reading /etc/resolv.conf Sep 09 22:37:24 volumio-goco dnsmasq[39859]: using nameserver 192.168.3.1#53 Sep 09 22:37:24 volumio-goco dnsmasq[39859]: using nameserver 91.102.231.241#53 Sep 09 22:37:24 volumio-goco dnsmasq[39859]: using nameserver 91.102.231.242#53 Sep 09 22:37:24 volumio-goco dnsmasq[39859]: using nameserver 208.67.222.222#53 Sep 09 22:37:24 volumio-goco dnsmasq[39859]: using nameserver 208.67.220.220#53 Sep 09 22:37:24 volumio-goco dnsmasq[39859]: read /etc/hosts - 4 names Sep 09 22:37:24 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:26 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2412. Sep 09 22:37:26 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:26 volumio-goco dnsmasq[39859]: exiting on receipt of SIGTERM Sep 09 22:37:26 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:37:26 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:26 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:26 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:37:26 volumio-goco hostapd[39865]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:37:26 volumio-goco hostapd[39865]: nl80211: Could not configure driver mode Sep 09 22:37:26 volumio-goco hostapd[39865]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:37:26 volumio-goco hostapd[39865]: nl80211 driver initialization failed. Sep 09 22:37:26 volumio-goco hostapd[39865]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:37:26 volumio-goco hostapd[39865]: wlan0: AP-DISABLED Sep 09 22:37:26 volumio-goco hostapd[39865]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:37:26 volumio-goco hostapd[39865]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:37:26 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:37:26 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:37:26 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:26 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:26 volumio-goco dnsmasq[39870]: started, version 2.90 cachesize 150 Sep 09 22:37:26 volumio-goco dnsmasq[39870]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:37:26 volumio-goco dnsmasq-dhcp[39870]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:37:26 volumio-goco dnsmasq[39870]: reading /etc/resolv.conf Sep 09 22:37:26 volumio-goco dnsmasq[39870]: using nameserver 192.168.3.1#53 Sep 09 22:37:26 volumio-goco dnsmasq[39870]: using nameserver 91.102.231.241#53 Sep 09 22:37:26 volumio-goco dnsmasq[39870]: using nameserver 91.102.231.242#53 Sep 09 22:37:26 volumio-goco dnsmasq[39870]: using nameserver 208.67.222.222#53 Sep 09 22:37:26 volumio-goco dnsmasq[39870]: using nameserver 208.67.220.220#53 Sep 09 22:37:26 volumio-goco dnsmasq[39870]: read /etc/hosts - 4 names Sep 09 22:37:26 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:28 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2413. Sep 09 22:37:28 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:28 volumio-goco dnsmasq[39870]: exiting on receipt of SIGTERM Sep 09 22:37:28 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:37:28 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:28 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:28 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:37:28 volumio-goco hostapd[39878]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:37:28 volumio-goco hostapd[39878]: nl80211: Could not configure driver mode Sep 09 22:37:28 volumio-goco hostapd[39878]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:37:28 volumio-goco hostapd[39878]: nl80211 driver initialization failed. Sep 09 22:37:28 volumio-goco hostapd[39878]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:37:28 volumio-goco hostapd[39878]: wlan0: AP-DISABLED Sep 09 22:37:28 volumio-goco hostapd[39878]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:37:28 volumio-goco hostapd[39878]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:37:28 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:37:28 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:37:28 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:28 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:28 volumio-goco dnsmasq[39883]: started, version 2.90 cachesize 150 Sep 09 22:37:28 volumio-goco dnsmasq[39883]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:37:28 volumio-goco dnsmasq-dhcp[39883]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:37:28 volumio-goco dnsmasq[39883]: reading /etc/resolv.conf Sep 09 22:37:28 volumio-goco dnsmasq[39883]: using nameserver 192.168.3.1#53 Sep 09 22:37:28 volumio-goco dnsmasq[39883]: using nameserver 91.102.231.241#53 Sep 09 22:37:28 volumio-goco dnsmasq[39883]: using nameserver 91.102.231.242#53 Sep 09 22:37:28 volumio-goco dnsmasq[39883]: using nameserver 208.67.222.222#53 Sep 09 22:37:28 volumio-goco dnsmasq[39883]: using nameserver 208.67.220.220#53 Sep 09 22:37:28 volumio-goco dnsmasq[39883]: read /etc/hosts - 4 names Sep 09 22:37:28 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:30 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2414. Sep 09 22:37:30 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:30 volumio-goco dnsmasq[39883]: exiting on receipt of SIGTERM Sep 09 22:37:30 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:37:30 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:30 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:30 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:37:30 volumio-goco hostapd[39889]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:37:30 volumio-goco hostapd[39889]: nl80211: Could not configure driver mode Sep 09 22:37:30 volumio-goco hostapd[39889]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:37:30 volumio-goco hostapd[39889]: nl80211 driver initialization failed. Sep 09 22:37:30 volumio-goco hostapd[39889]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:37:30 volumio-goco hostapd[39889]: wlan0: AP-DISABLED Sep 09 22:37:30 volumio-goco hostapd[39889]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:37:30 volumio-goco hostapd[39889]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:37:30 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:37:30 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:37:30 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:30 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:30 volumio-goco dnsmasq[39894]: started, version 2.90 cachesize 150 Sep 09 22:37:30 volumio-goco dnsmasq[39894]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:37:30 volumio-goco dnsmasq-dhcp[39894]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:37:30 volumio-goco dnsmasq[39894]: reading /etc/resolv.conf Sep 09 22:37:30 volumio-goco dnsmasq[39894]: using nameserver 192.168.3.1#53 Sep 09 22:37:30 volumio-goco dnsmasq[39894]: using nameserver 91.102.231.241#53 Sep 09 22:37:30 volumio-goco dnsmasq[39894]: using nameserver 91.102.231.242#53 Sep 09 22:37:30 volumio-goco dnsmasq[39894]: using nameserver 208.67.222.222#53 Sep 09 22:37:30 volumio-goco dnsmasq[39894]: using nameserver 208.67.220.220#53 Sep 09 22:37:30 volumio-goco dnsmasq[39894]: read /etc/hosts - 4 names Sep 09 22:37:30 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:32 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2415. Sep 09 22:37:32 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:32 volumio-goco dnsmasq[39894]: exiting on receipt of SIGTERM Sep 09 22:37:32 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:37:32 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:32 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:32 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:37:32 volumio-goco hostapd[39900]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:37:32 volumio-goco hostapd[39900]: nl80211: Could not configure driver mode Sep 09 22:37:32 volumio-goco hostapd[39900]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:37:32 volumio-goco hostapd[39900]: nl80211 driver initialization failed. Sep 09 22:37:32 volumio-goco hostapd[39900]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:37:32 volumio-goco hostapd[39900]: wlan0: AP-DISABLED Sep 09 22:37:32 volumio-goco hostapd[39900]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:37:32 volumio-goco hostapd[39900]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:37:32 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:37:32 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:37:32 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:32 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:33 volumio-goco dnsmasq[39905]: started, version 2.90 cachesize 150 Sep 09 22:37:33 volumio-goco dnsmasq[39905]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:37:33 volumio-goco dnsmasq-dhcp[39905]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:37:33 volumio-goco dnsmasq[39905]: reading /etc/resolv.conf Sep 09 22:37:33 volumio-goco dnsmasq[39905]: using nameserver 192.168.3.1#53 Sep 09 22:37:33 volumio-goco dnsmasq[39905]: using nameserver 91.102.231.241#53 Sep 09 22:37:33 volumio-goco dnsmasq[39905]: using nameserver 91.102.231.242#53 Sep 09 22:37:33 volumio-goco dnsmasq[39905]: using nameserver 208.67.222.222#53 Sep 09 22:37:33 volumio-goco dnsmasq[39905]: using nameserver 208.67.220.220#53 Sep 09 22:37:33 volumio-goco dnsmasq[39905]: read /etc/hosts - 4 names Sep 09 22:37:33 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:35 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2416. Sep 09 22:37:35 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:35 volumio-goco dnsmasq[39905]: exiting on receipt of SIGTERM Sep 09 22:37:35 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:37:35 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:35 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:35 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:37:35 volumio-goco hostapd[39925]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:37:35 volumio-goco hostapd[39925]: nl80211: Could not configure driver mode Sep 09 22:37:35 volumio-goco hostapd[39925]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:37:35 volumio-goco hostapd[39925]: nl80211 driver initialization failed. Sep 09 22:37:35 volumio-goco hostapd[39925]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:37:35 volumio-goco hostapd[39925]: wlan0: AP-DISABLED Sep 09 22:37:35 volumio-goco hostapd[39925]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:37:35 volumio-goco hostapd[39925]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:37:35 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:37:35 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:37:35 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:35 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:35 volumio-goco dnsmasq[39930]: started, version 2.90 cachesize 150 Sep 09 22:37:35 volumio-goco dnsmasq[39930]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:37:35 volumio-goco dnsmasq-dhcp[39930]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:37:35 volumio-goco dnsmasq[39930]: reading /etc/resolv.conf Sep 09 22:37:35 volumio-goco dnsmasq[39930]: using nameserver 192.168.3.1#53 Sep 09 22:37:35 volumio-goco dnsmasq[39930]: using nameserver 91.102.231.241#53 Sep 09 22:37:35 volumio-goco dnsmasq[39930]: using nameserver 91.102.231.242#53 Sep 09 22:37:35 volumio-goco dnsmasq[39930]: using nameserver 208.67.222.222#53 Sep 09 22:37:35 volumio-goco dnsmasq[39930]: using nameserver 208.67.220.220#53 Sep 09 22:37:35 volumio-goco dnsmasq[39930]: read /etc/hosts - 4 names Sep 09 22:37:35 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:37 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2417. Sep 09 22:37:37 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:37 volumio-goco dnsmasq[39930]: exiting on receipt of SIGTERM Sep 09 22:37:37 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:37:37 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:37 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:37 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:37:37 volumio-goco hostapd[39937]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:37:37 volumio-goco hostapd[39937]: nl80211: Could not configure driver mode Sep 09 22:37:37 volumio-goco hostapd[39937]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:37:37 volumio-goco hostapd[39937]: nl80211 driver initialization failed. Sep 09 22:37:37 volumio-goco hostapd[39937]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:37:37 volumio-goco hostapd[39937]: wlan0: AP-DISABLED Sep 09 22:37:37 volumio-goco hostapd[39937]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:37:37 volumio-goco hostapd[39937]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:37:37 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:37:37 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:37:37 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:37 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:37 volumio-goco dnsmasq[39942]: started, version 2.90 cachesize 150 Sep 09 22:37:37 volumio-goco dnsmasq[39942]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:37:37 volumio-goco dnsmasq-dhcp[39942]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:37:37 volumio-goco dnsmasq[39942]: reading /etc/resolv.conf Sep 09 22:37:37 volumio-goco dnsmasq[39942]: using nameserver 192.168.3.1#53 Sep 09 22:37:37 volumio-goco dnsmasq[39942]: using nameserver 91.102.231.241#53 Sep 09 22:37:37 volumio-goco dnsmasq[39942]: using nameserver 91.102.231.242#53 Sep 09 22:37:37 volumio-goco dnsmasq[39942]: using nameserver 208.67.222.222#53 Sep 09 22:37:37 volumio-goco dnsmasq[39942]: using nameserver 208.67.220.220#53 Sep 09 22:37:37 volumio-goco dnsmasq[39942]: read /etc/hosts - 4 names Sep 09 22:37:37 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:39 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2418. Sep 09 22:37:39 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:39 volumio-goco dnsmasq[39942]: exiting on receipt of SIGTERM Sep 09 22:37:39 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:37:39 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:39 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:39 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:37:39 volumio-goco hostapd[39948]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:37:39 volumio-goco hostapd[39948]: nl80211: Could not configure driver mode Sep 09 22:37:39 volumio-goco hostapd[39948]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:37:39 volumio-goco hostapd[39948]: nl80211 driver initialization failed. Sep 09 22:37:39 volumio-goco hostapd[39948]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:37:39 volumio-goco hostapd[39948]: wlan0: AP-DISABLED Sep 09 22:37:39 volumio-goco hostapd[39948]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:37:39 volumio-goco hostapd[39948]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:37:39 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:37:39 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:37:39 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:39 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:39 volumio-goco dnsmasq[39953]: started, version 2.90 cachesize 150 Sep 09 22:37:39 volumio-goco dnsmasq[39953]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:37:39 volumio-goco dnsmasq-dhcp[39953]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:37:39 volumio-goco dnsmasq[39953]: reading /etc/resolv.conf Sep 09 22:37:39 volumio-goco dnsmasq[39953]: using nameserver 192.168.3.1#53 Sep 09 22:37:39 volumio-goco dnsmasq[39953]: using nameserver 91.102.231.241#53 Sep 09 22:37:39 volumio-goco dnsmasq[39953]: using nameserver 91.102.231.242#53 Sep 09 22:37:39 volumio-goco dnsmasq[39953]: using nameserver 208.67.222.222#53 Sep 09 22:37:39 volumio-goco dnsmasq[39953]: using nameserver 208.67.220.220#53 Sep 09 22:37:39 volumio-goco dnsmasq[39953]: read /etc/hosts - 4 names Sep 09 22:37:39 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:41 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2419. Sep 09 22:37:41 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:41 volumio-goco dnsmasq[39953]: exiting on receipt of SIGTERM Sep 09 22:37:41 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:37:41 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:41 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:41 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:37:41 volumio-goco hostapd[39959]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:37:41 volumio-goco hostapd[39959]: nl80211: Could not configure driver mode Sep 09 22:37:41 volumio-goco hostapd[39959]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:37:41 volumio-goco hostapd[39959]: nl80211 driver initialization failed. Sep 09 22:37:41 volumio-goco hostapd[39959]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:37:41 volumio-goco hostapd[39959]: wlan0: AP-DISABLED Sep 09 22:37:41 volumio-goco hostapd[39959]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:37:41 volumio-goco hostapd[39959]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:37:41 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:37:41 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:37:41 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:41 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:42 volumio-goco dnsmasq[39964]: started, version 2.90 cachesize 150 Sep 09 22:37:42 volumio-goco dnsmasq[39964]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:37:42 volumio-goco dnsmasq-dhcp[39964]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:37:42 volumio-goco dnsmasq[39964]: reading /etc/resolv.conf Sep 09 22:37:42 volumio-goco dnsmasq[39964]: using nameserver 192.168.3.1#53 Sep 09 22:37:42 volumio-goco dnsmasq[39964]: using nameserver 91.102.231.241#53 Sep 09 22:37:42 volumio-goco dnsmasq[39964]: using nameserver 91.102.231.242#53 Sep 09 22:37:42 volumio-goco dnsmasq[39964]: using nameserver 208.67.222.222#53 Sep 09 22:37:42 volumio-goco dnsmasq[39964]: using nameserver 208.67.220.220#53 Sep 09 22:37:42 volumio-goco dnsmasq[39964]: read /etc/hosts - 4 names Sep 09 22:37:42 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:42 volumio-goco volumio[1001]: Searching plugin music_service/webradio Sep 09 22:37:42 volumio-goco volumio[1001]: info: CoreCommandRouter::executeOnPlugin: webradio , search Sep 09 22:37:43 volumio-goco volumio[1001]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 09 22:37:43 volumio-goco volumio[1001]: TypeError: Cannot read properties of undefined (reading 'length') Sep 09 22:37:43 volumio-goco volumio[1001]: at /volumio/node_modules/libxmljs/dist/lib/parse.js:186:126 Sep 09 22:37:43 volumio-goco volumio[1001]: at Object.parseXml (/volumio/node_modules/libxmljs/dist/lib/parse.js:185:49) Sep 09 22:37:43 volumio-goco volumio[1001]: at /volumio/app/plugins/music_service/webradio/index.js:949:31 Sep 09 22:37:43 volumio-goco volumio[1001]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Sep 09 22:37:43 volumio-goco volumio[1001]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 09 22:37:44 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2420. Sep 09 22:37:44 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:44 volumio-goco systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Sep 09 22:37:44 volumio-goco dnsmasq[39964]: exiting on receipt of SIGTERM Sep 09 22:37:44 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:37:44 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:44 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:44 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:37:44 volumio-goco hostapd[39999]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:37:44 volumio-goco dbus-daemon[591]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.220' (uid=0 pid=39997 comm="timedatectl show --property=NTPSynchronized --valu") Sep 09 22:37:44 volumio-goco hostapd[39999]: nl80211: Could not configure driver mode Sep 09 22:37:44 volumio-goco hostapd[39999]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:37:44 volumio-goco hostapd[39999]: nl80211 driver initialization failed. Sep 09 22:37:44 volumio-goco hostapd[39999]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:37:44 volumio-goco hostapd[39999]: wlan0: AP-DISABLED Sep 09 22:37:44 volumio-goco hostapd[39999]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:37:44 volumio-goco hostapd[39999]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:37:44 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:37:44 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:37:44 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:44 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:44 volumio-goco systemd[1]: Starting systemd-timedated.service - Time & Date Service... Sep 09 22:37:44 volumio-goco dnsmasq[40006]: started, version 2.90 cachesize 150 Sep 09 22:37:44 volumio-goco dnsmasq[40006]: compile time options: IPv6 GNU-getopt DBus no-UBus i18n IDN2 DHCP DHCPv6 no-Lua TFTP conntrack ipset nftset auth cryptohash DNSSEC loop-detect inotify dumpfile Sep 09 22:37:44 volumio-goco dnsmasq-dhcp[40006]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:37:44 volumio-goco dnsmasq[40006]: reading /etc/resolv.conf Sep 09 22:37:44 volumio-goco dnsmasq[40006]: using nameserver 192.168.3.1#53 Sep 09 22:37:44 volumio-goco dnsmasq[40006]: using nameserver 91.102.231.241#53 Sep 09 22:37:44 volumio-goco dnsmasq[40006]: using nameserver 91.102.231.242#53 Sep 09 22:37:44 volumio-goco dnsmasq[40006]: using nameserver 208.67.222.222#53 Sep 09 22:37:44 volumio-goco dnsmasq[40006]: using nameserver 208.67.220.220#53 Sep 09 22:37:44 volumio-goco dnsmasq[40006]: read /etc/hosts - 4 names Sep 09 22:37:44 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:44 volumio-goco dbus-daemon[591]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 09 22:37:44 volumio-goco systemd[1]: Started systemd-timedated.service - Time & Date Service. Sep 09 22:37:44 volumio-goco setdatetime-helper.sh[39996]: Time is already synchronized. Sep 09 22:37:44 volumio-goco systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Sep 09 22:37:44 volumio-goco systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Sep 09 22:37:44 volumio-goco sudo[40011]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-09-09 22:36' Sep 09 22:37:44 volumio-goco sudo[40011]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="4a70cb031e64a8e498efc3e29470650871ff7d54" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat May 31 04:47:19 UTC 2025" VOLUMIO_VERSION="4.005" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="f0f6a90610a0a39b2758a5b3958219e6"