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) Sep 09 22:37:44 volumio-goco sudo[40011]: pam_unix(sudo:session): session closed for user root Sep 09 22:37:45 volumio-goco systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 09 22:37:45 volumio-goco go-librespot[6519]: time="2025-09-09T22:37:45+02:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Sep 09 22:37:45 volumio-goco volumio-remote-updater[608]: [2025-09-09 20:37:45] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 09 22:37:45 volumio-goco volumio-remote-updater[608]: [2025-09-09 20:37:45] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 09 22:37:46 volumio-goco systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 09 22:37:46 volumio-goco systemd[1]: volumio.service: Consumed 2min 55.857s CPU time. Sep 09 22:37:46 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2421. Sep 09 22:37:46 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:46 volumio-goco systemd[1]: Started dynamicswap.service - dynamicswap service. Sep 09 22:37:46 volumio-goco dnsmasq[40006]: exiting on receipt of SIGTERM Sep 09 22:37:46 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:37:46 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:46 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:46 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:37:46 volumio-goco systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Sep 09 22:37:46 volumio-goco systemd[1]: Stopped volumio.service - Volumio Backend Module. Sep 09 22:37:46 volumio-goco systemd[1]: volumio.service: Consumed 2min 55.857s CPU time. Sep 09 22:37:46 volumio-goco systemd[1]: Started volumio.service - Volumio Backend Module. Sep 09 22:37:46 volumio-goco systemd[1]: dynamicswap.service: Deactivated successfully. Sep 09 22:37:46 volumio-goco hostapd[40033]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:37:46 volumio-goco hostapd[40033]: nl80211: Could not configure driver mode Sep 09 22:37:46 volumio-goco hostapd[40033]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:37:46 volumio-goco hostapd[40033]: nl80211 driver initialization failed. Sep 09 22:37:46 volumio-goco hostapd[40033]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:37:46 volumio-goco hostapd[40033]: wlan0: AP-DISABLED Sep 09 22:37:46 volumio-goco hostapd[40033]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:37:46 volumio-goco hostapd[40033]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:37:46 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:37:46 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:37:46 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:46 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:46 volumio-goco dnsmasq[40053]: started, version 2.90 cachesize 150 Sep 09 22:37:46 volumio-goco dnsmasq[40053]: 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:46 volumio-goco dnsmasq-dhcp[40053]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:37:46 volumio-goco dnsmasq[40053]: reading /etc/resolv.conf Sep 09 22:37:46 volumio-goco dnsmasq[40053]: using nameserver 192.168.3.1#53 Sep 09 22:37:46 volumio-goco dnsmasq[40053]: using nameserver 91.102.231.241#53 Sep 09 22:37:46 volumio-goco dnsmasq[40053]: using nameserver 91.102.231.242#53 Sep 09 22:37:46 volumio-goco dnsmasq[40053]: using nameserver 208.67.222.222#53 Sep 09 22:37:46 volumio-goco dnsmasq[40053]: using nameserver 208.67.220.220#53 Sep 09 22:37:46 volumio-goco dnsmasq[40053]: read /etc/hosts - 4 names Sep 09 22:37:46 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:48 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2422. Sep 09 22:37:48 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:48 volumio-goco dnsmasq[40053]: exiting on receipt of SIGTERM Sep 09 22:37:48 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:37:48 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:48 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:48 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:37:48 volumio-goco hostapd[40060]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:37:48 volumio-goco hostapd[40060]: nl80211: Could not configure driver mode Sep 09 22:37:48 volumio-goco hostapd[40060]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:37:48 volumio-goco hostapd[40060]: nl80211 driver initialization failed. Sep 09 22:37:48 volumio-goco hostapd[40060]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:37:48 volumio-goco hostapd[40060]: wlan0: AP-DISABLED Sep 09 22:37:48 volumio-goco hostapd[40060]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:37:48 volumio-goco hostapd[40060]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:37:48 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:37:48 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:37:48 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:48 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:48 volumio-goco dnsmasq[40065]: started, version 2.90 cachesize 150 Sep 09 22:37:48 volumio-goco dnsmasq[40065]: 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:48 volumio-goco dnsmasq-dhcp[40065]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:37:48 volumio-goco dnsmasq[40065]: reading /etc/resolv.conf Sep 09 22:37:48 volumio-goco dnsmasq[40065]: using nameserver 192.168.3.1#53 Sep 09 22:37:48 volumio-goco dnsmasq[40065]: using nameserver 91.102.231.241#53 Sep 09 22:37:48 volumio-goco dnsmasq[40065]: using nameserver 91.102.231.242#53 Sep 09 22:37:48 volumio-goco dnsmasq[40065]: using nameserver 208.67.222.222#53 Sep 09 22:37:48 volumio-goco dnsmasq[40065]: using nameserver 208.67.220.220#53 Sep 09 22:37:48 volumio-goco dnsmasq[40065]: read /etc/hosts - 4 names Sep 09 22:37:48 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:49 volumio-goco volumio[40037]: info: ------------------------------------------- Sep 09 22:37:49 volumio-goco volumio[40037]: info: ----- Volumio3 ---- Sep 09 22:37:49 volumio-goco volumio[40037]: info: ------------------------------------------- Sep 09 22:37:49 volumio-goco volumio[40037]: info: ----- System startup ---- Sep 09 22:37:49 volumio-goco volumio[40037]: info: ------------------------------------------- Sep 09 22:37:50 volumio-goco volumio[40037]: info: MYVOLUMIO Environment detected Sep 09 22:37:50 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2423. Sep 09 22:37:50 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:50 volumio-goco dnsmasq[40065]: exiting on receipt of SIGTERM Sep 09 22:37:50 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:37:50 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:50 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:50 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:37:50 volumio-goco hostapd[40080]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:37:50 volumio-goco hostapd[40080]: nl80211: Could not configure driver mode Sep 09 22:37:50 volumio-goco hostapd[40080]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:37:50 volumio-goco hostapd[40080]: nl80211 driver initialization failed. Sep 09 22:37:50 volumio-goco hostapd[40080]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:37:50 volumio-goco hostapd[40080]: wlan0: AP-DISABLED Sep 09 22:37:50 volumio-goco hostapd[40080]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:37:50 volumio-goco hostapd[40080]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:37:50 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:37:51 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:37:51 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:51 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:51 volumio-goco volumio[40037]: info: Plugin folders cleanup Sep 09 22:37:51 volumio-goco volumio-remote-updater[608]: [2025-09-09 20:37:51] [connect] Successful connection Sep 09 22:37:51 volumio-goco volumio[40037]: info: Scanning into folder /volumio/app/plugins/ Sep 09 22:37:51 volumio-goco volumio[40037]: info: Scanning category audio_interface Sep 09 22:37:51 volumio-goco volumio[40037]: info: Scanning category miscellanea Sep 09 22:37:51 volumio-goco volumio[40037]: info: Scanning category music_service Sep 09 22:37:51 volumio-goco volumio[40037]: info: Scanning category plugins.json Sep 09 22:37:51 volumio-goco volumio[40037]: info: Scanning category system_controller Sep 09 22:37:51 volumio-goco volumio[40037]: info: Scanning category user_interface Sep 09 22:37:51 volumio-goco volumio[40037]: info: Scanning into folder /data/plugins/ Sep 09 22:37:51 volumio-goco volumio[40037]: info: Scanning category audio_interface Sep 09 22:37:51 volumio-goco volumio[40037]: info: Scanning category music_service Sep 09 22:37:51 volumio-goco volumio[40037]: info: Plugin folders cleanup completed Sep 09 22:37:51 volumio-goco volumio[40037]: info: ------------------------------------------- Sep 09 22:37:51 volumio-goco dnsmasq[40085]: started, version 2.90 cachesize 150 Sep 09 22:37:51 volumio-goco dnsmasq[40085]: 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:51 volumio-goco dnsmasq-dhcp[40085]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:37:51 volumio-goco dnsmasq[40085]: reading /etc/resolv.conf Sep 09 22:37:51 volumio-goco dnsmasq[40085]: using nameserver 192.168.3.1#53 Sep 09 22:37:51 volumio-goco dnsmasq[40085]: using nameserver 91.102.231.241#53 Sep 09 22:37:51 volumio-goco dnsmasq[40085]: using nameserver 91.102.231.242#53 Sep 09 22:37:51 volumio-goco dnsmasq[40085]: using nameserver 208.67.222.222#53 Sep 09 22:37:51 volumio-goco dnsmasq[40085]: using nameserver 208.67.220.220#53 Sep 09 22:37:51 volumio-goco volumio[40037]: info: ----- Core plugins startup ---- Sep 09 22:37:51 volumio-goco volumio[40037]: info: ------------------------------------------- Sep 09 22:37:51 volumio-goco dnsmasq[40085]: read /etc/hosts - 4 names Sep 09 22:37:51 volumio-goco volumio[40037]: info: Loading plugins from folder /volumio/app/plugins/ Sep 09 22:37:51 volumio-goco volumio[40037]: info: Adding plugin upnp to MyMusic Plugins Sep 09 22:37:51 volumio-goco volumio[40037]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 09 22:37:51 volumio-goco volumio[40037]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 09 22:37:51 volumio-goco volumio[40037]: info: Loading plugins from folder /data/plugins/ Sep 09 22:37:51 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:51 volumio-goco volumio[40037]: info: Loading plugin "system"... Sep 09 22:37:51 volumio-goco volumio[40037]: info: Loading plugin "appearance"... Sep 09 22:37:52 volumio-goco volumio[40037]: info: Loading plugin "network"... Sep 09 22:37:52 volumio-goco volumio[40037]: info: Refreshing Cached IP Addresses Sep 09 22:37:52 volumio-goco sudo[40094]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 09 22:37:52 volumio-goco sudo[40094]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 09 22:37:52 volumio-goco volumio[40037]: info: Loading plugin "services"... Sep 09 22:37:52 volumio-goco sudo[40096]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 09 22:37:52 volumio-goco volumio[40037]: info: Loading plugin "alsa_controller"... Sep 09 22:37:52 volumio-goco sudo[40096]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 09 22:37:52 volumio-goco sudo[40094]: pam_unix(sudo:session): session closed for user root Sep 09 22:37:52 volumio-goco sudo[40096]: pam_unix(sudo:session): session closed for user root Sep 09 22:37:52 volumio-goco sudo[40103]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 09 22:37:52 volumio-goco sudo[40103]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 09 22:37:53 volumio-goco sudo[40103]: pam_unix(sudo:session): session closed for user root Sep 09 22:37:53 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 09 22:37:53 volumio-goco volumio[40037]: info: Loading plugin "wizard"... Sep 09 22:37:53 volumio-goco volumio[40037]: info: Loading plugin "networkfs"... Sep 09 22:37:53 volumio-goco volumio[40037]: info: Starting Udev Watcher for removable devices Sep 09 22:37:53 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2424. Sep 09 22:37:53 volumio-goco volumio[40037]: info: Ignoring mount for partition: boot Sep 09 22:37:53 volumio-goco volumio[40037]: info: Ignoring mount for partition: volumio Sep 09 22:37:53 volumio-goco volumio[40037]: info: Ignoring mount for partition: volumio_data Sep 09 22:37:53 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 09 22:37:53 volumio-goco volumio[40037]: info: Loading plugin "volumio_command_line_client"... Sep 09 22:37:53 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:53 volumio-goco dnsmasq[40085]: exiting on receipt of SIGTERM Sep 09 22:37:53 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:37:53 volumio-goco volumio[40037]: info: Loading plugin "upnp"... Sep 09 22:37:53 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:53 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:53 volumio-goco volumio[40037]: info: [1757450273226] Starting Upmpd Daemon Sep 09 22:37:53 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:37:53 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 09 22:37:53 volumio-goco volumio[40037]: info: Loading plugin "my_music"... Sep 09 22:37:53 volumio-goco volumio[40037]: info: Loading plugin "mpd"... Sep 09 22:37:53 volumio-goco hostapd[40128]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:37:53 volumio-goco hostapd[40128]: nl80211: Could not configure driver mode Sep 09 22:37:53 volumio-goco hostapd[40128]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:37:53 volumio-goco hostapd[40128]: nl80211 driver initialization failed. Sep 09 22:37:53 volumio-goco hostapd[40128]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:37:53 volumio-goco hostapd[40128]: wlan0: AP-DISABLED Sep 09 22:37:53 volumio-goco hostapd[40128]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:37:53 volumio-goco hostapd[40128]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:37:53 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:37:53 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:37:53 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:53 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:53 volumio-goco dnsmasq[40133]: started, version 2.90 cachesize 150 Sep 09 22:37:53 volumio-goco dnsmasq[40133]: 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:53 volumio-goco dnsmasq-dhcp[40133]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:37:53 volumio-goco dnsmasq[40133]: reading /etc/resolv.conf Sep 09 22:37:53 volumio-goco dnsmasq[40133]: using nameserver 192.168.3.1#53 Sep 09 22:37:53 volumio-goco dnsmasq[40133]: using nameserver 91.102.231.241#53 Sep 09 22:37:53 volumio-goco dnsmasq[40133]: using nameserver 91.102.231.242#53 Sep 09 22:37:53 volumio-goco dnsmasq[40133]: using nameserver 208.67.222.222#53 Sep 09 22:37:53 volumio-goco dnsmasq[40133]: using nameserver 208.67.220.220#53 Sep 09 22:37:53 volumio-goco dnsmasq[40133]: read /etc/hosts - 4 names Sep 09 22:37:53 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:53 volumio-goco volumio[40037]: info: Loading plugin "upnp_browser"... Sep 09 22:37:55 volumio-goco volumio[40037]: info: Loading plugin "alarm-clock"... Sep 09 22:37:55 volumio-goco volumio[40037]: info: Loading plugin "airplay_emulation"... Sep 09 22:37:55 volumio-goco volumio[40037]: info: Starting Shairport Sync Sep 09 22:37:55 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2425. Sep 09 22:37:55 volumio-goco volumio[40037]: info: Loading plugin "last_100"... Sep 09 22:37:55 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:55 volumio-goco dnsmasq[40133]: exiting on receipt of SIGTERM Sep 09 22:37:55 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:37:55 volumio-goco volumio[40037]: info: Loading plugin "webradio"... Sep 09 22:37:55 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:55 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:55 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:37:55 volumio-goco hostapd[40139]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:37:55 volumio-goco hostapd[40139]: nl80211: Could not configure driver mode Sep 09 22:37:55 volumio-goco hostapd[40139]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:37:55 volumio-goco hostapd[40139]: nl80211 driver initialization failed. Sep 09 22:37:55 volumio-goco hostapd[40139]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:37:55 volumio-goco hostapd[40139]: wlan0: AP-DISABLED Sep 09 22:37:55 volumio-goco hostapd[40139]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:37:55 volumio-goco hostapd[40139]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:37:55 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:37:55 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:37:55 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:55 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:55 volumio-goco dnsmasq[40145]: started, version 2.90 cachesize 150 Sep 09 22:37:55 volumio-goco dnsmasq[40145]: 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:55 volumio-goco dnsmasq-dhcp[40145]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:37:55 volumio-goco dnsmasq[40145]: reading /etc/resolv.conf Sep 09 22:37:55 volumio-goco dnsmasq[40145]: using nameserver 192.168.3.1#53 Sep 09 22:37:55 volumio-goco dnsmasq[40145]: using nameserver 91.102.231.241#53 Sep 09 22:37:55 volumio-goco dnsmasq[40145]: using nameserver 91.102.231.242#53 Sep 09 22:37:55 volumio-goco dnsmasq[40145]: using nameserver 208.67.222.222#53 Sep 09 22:37:55 volumio-goco dnsmasq[40145]: using nameserver 208.67.220.220#53 Sep 09 22:37:55 volumio-goco dnsmasq[40145]: read /etc/hosts - 4 names Sep 09 22:37:55 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:55 volumio-goco volumio[40037]: info: Loading plugin "i2s_dacs"... Sep 09 22:37:55 volumio-goco volumio[40037]: info: I2S DAC not set, start Auto-detection Sep 09 22:37:55 volumio-goco volumio[40037]: info: Loading plugin "volumiodiscovery"... Sep 09 22:37:55 volumio-goco volumio[40037]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 09 22:37:55 volumio-goco node[40037]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 09 22:37:55 volumio-goco volumio[40037]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 09 22:37:55 volumio-goco volumio[40037]: *** WARNING *** For more information see Sep 09 22:37:55 volumio-goco volumio[40037]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 09 22:37:55 volumio-goco volumio[40037]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 09 22:37:55 volumio-goco volumio[40037]: *** WARNING *** For more information see Sep 09 22:37:55 volumio-goco node[40037]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 09 22:37:55 volumio-goco node[40037]: *** WARNING *** For more information see Sep 09 22:37:55 volumio-goco node[40037]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 09 22:37:55 volumio-goco node[40037]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 09 22:37:55 volumio-goco node[40037]: *** WARNING *** For more information see Sep 09 22:37:55 volumio-goco volumio[40037]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 09 22:37:55 volumio-goco volumio[40037]: info: Discovery: Started advertising with name: Volumio-Goco Sep 09 22:37:55 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 09 22:37:55 volumio-goco volumio[40037]: info: Loading plugin "soundcloud"... Sep 09 22:37:56 volumio-goco volumio[40037]: info: Loading plugin "spop"... Sep 09 22:37:57 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2426. Sep 09 22:37:57 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:57 volumio-goco dnsmasq[40145]: exiting on receipt of SIGTERM Sep 09 22:37:57 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:37:57 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:57 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:57 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:37:57 volumio-goco hostapd[40153]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:37:57 volumio-goco hostapd[40153]: nl80211: Could not configure driver mode Sep 09 22:37:57 volumio-goco hostapd[40153]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:37:57 volumio-goco hostapd[40153]: nl80211 driver initialization failed. Sep 09 22:37:57 volumio-goco hostapd[40153]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:37:57 volumio-goco hostapd[40153]: wlan0: AP-DISABLED Sep 09 22:37:57 volumio-goco hostapd[40153]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:37:57 volumio-goco hostapd[40153]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:37:57 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:37:57 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:37:57 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:57 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:57 volumio-goco dnsmasq[40158]: started, version 2.90 cachesize 150 Sep 09 22:37:57 volumio-goco dnsmasq[40158]: 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:57 volumio-goco dnsmasq-dhcp[40158]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:37:57 volumio-goco dnsmasq[40158]: reading /etc/resolv.conf Sep 09 22:37:57 volumio-goco dnsmasq[40158]: using nameserver 192.168.3.1#53 Sep 09 22:37:57 volumio-goco dnsmasq[40158]: using nameserver 91.102.231.241#53 Sep 09 22:37:57 volumio-goco dnsmasq[40158]: using nameserver 91.102.231.242#53 Sep 09 22:37:57 volumio-goco dnsmasq[40158]: using nameserver 208.67.222.222#53 Sep 09 22:37:57 volumio-goco dnsmasq[40158]: using nameserver 208.67.220.220#53 Sep 09 22:37:57 volumio-goco dnsmasq[40158]: read /etc/hosts - 4 names Sep 09 22:37:57 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:58 volumio-goco volumio[40037]: info: Loading plugin "youtube2"... Sep 09 22:37:59 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2427. Sep 09 22:37:59 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:37:59 volumio-goco dnsmasq[40158]: exiting on receipt of SIGTERM Sep 09 22:37:59 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:37:59 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:37:59 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:37:59 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:37:59 volumio-goco hostapd[40164]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:37:59 volumio-goco hostapd[40164]: nl80211: Could not configure driver mode Sep 09 22:37:59 volumio-goco hostapd[40164]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:37:59 volumio-goco hostapd[40164]: nl80211 driver initialization failed. Sep 09 22:37:59 volumio-goco hostapd[40164]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:37:59 volumio-goco hostapd[40164]: wlan0: AP-DISABLED Sep 09 22:37:59 volumio-goco hostapd[40164]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:37:59 volumio-goco hostapd[40164]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:38:00 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:38:00 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:38:00 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:38:00 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:38:00 volumio-goco dnsmasq[40169]: started, version 2.90 cachesize 150 Sep 09 22:38:00 volumio-goco dnsmasq[40169]: 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:38:00 volumio-goco dnsmasq-dhcp[40169]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:38:00 volumio-goco dnsmasq[40169]: reading /etc/resolv.conf Sep 09 22:38:00 volumio-goco dnsmasq[40169]: using nameserver 192.168.3.1#53 Sep 09 22:38:00 volumio-goco dnsmasq[40169]: using nameserver 91.102.231.241#53 Sep 09 22:38:00 volumio-goco dnsmasq[40169]: using nameserver 91.102.231.242#53 Sep 09 22:38:00 volumio-goco dnsmasq[40169]: using nameserver 208.67.222.222#53 Sep 09 22:38:00 volumio-goco dnsmasq[40169]: using nameserver 208.67.220.220#53 Sep 09 22:38:00 volumio-goco dnsmasq[40169]: read /etc/hosts - 4 names Sep 09 22:38:00 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:38:02 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2428. Sep 09 22:38:02 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:38:02 volumio-goco dnsmasq[40169]: exiting on receipt of SIGTERM Sep 09 22:38:02 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:38:02 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:38:02 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:38:02 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:38:02 volumio-goco hostapd[40175]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:38:02 volumio-goco hostapd[40175]: nl80211: Could not configure driver mode Sep 09 22:38:02 volumio-goco hostapd[40175]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:38:02 volumio-goco hostapd[40175]: nl80211 driver initialization failed. Sep 09 22:38:02 volumio-goco hostapd[40175]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:38:02 volumio-goco hostapd[40175]: wlan0: AP-DISABLED Sep 09 22:38:02 volumio-goco hostapd[40175]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:38:02 volumio-goco hostapd[40175]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:38:02 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:38:02 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:38:02 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:38:02 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:38:02 volumio-goco dnsmasq[40180]: started, version 2.90 cachesize 150 Sep 09 22:38:02 volumio-goco dnsmasq[40180]: 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:38:02 volumio-goco dnsmasq-dhcp[40180]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:38:02 volumio-goco dnsmasq[40180]: reading /etc/resolv.conf Sep 09 22:38:02 volumio-goco dnsmasq[40180]: using nameserver 192.168.3.1#53 Sep 09 22:38:02 volumio-goco dnsmasq[40180]: using nameserver 91.102.231.241#53 Sep 09 22:38:02 volumio-goco dnsmasq[40180]: using nameserver 91.102.231.242#53 Sep 09 22:38:02 volumio-goco dnsmasq[40180]: using nameserver 208.67.222.222#53 Sep 09 22:38:02 volumio-goco dnsmasq[40180]: using nameserver 208.67.220.220#53 Sep 09 22:38:02 volumio-goco dnsmasq[40180]: read /etc/hosts - 4 names Sep 09 22:38:02 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:38:03 volumio-goco volumio-remote-updater[608]: [2025-09-09 20:38:03] [connect] Successful connection Sep 09 22:38:03 volumio-goco volumio[40037]: info: Loading plugin "outputs"... Sep 09 22:38:03 volumio-goco volumio[40037]: info: Loading plugin "albumart"... Sep 09 22:38:03 volumio-goco volumio[40037]: info: Plugin example_plugin is not enabled Sep 09 22:38:03 volumio-goco volumio[40037]: info: Loading plugin "inputs"... Sep 09 22:38:03 volumio-goco volumio[40037]: info: Loading plugin "updater_comm"... Sep 09 22:38:04 volumio-goco volumio[40199]: Forking 1 albumart workers Sep 09 22:38:04 volumio-goco volumio[40037]: info: Plugin mpdemulation is not enabled Sep 09 22:38:04 volumio-goco volumio[40037]: info: Loading plugin "rest_api"... Sep 09 22:38:04 volumio-goco volumio[40037]: info: Loading plugin "websocket"... Sep 09 22:38:04 volumio-goco volumio[40037]: info: Starting Socket.io Server version 1.7.4 Sep 09 22:38:04 volumio-goco volumio[40037]: info: Loading plugin "fusiondsp"... Sep 09 22:38:04 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2429. Sep 09 22:38:04 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:38:04 volumio-goco dnsmasq[40180]: exiting on receipt of SIGTERM Sep 09 22:38:04 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:38:04 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:38:04 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:38:04 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:38:04 volumio-goco hostapd[40220]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:38:04 volumio-goco hostapd[40220]: nl80211: Could not configure driver mode Sep 09 22:38:04 volumio-goco hostapd[40220]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:38:04 volumio-goco hostapd[40220]: nl80211 driver initialization failed. Sep 09 22:38:04 volumio-goco hostapd[40220]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:38:04 volumio-goco hostapd[40220]: wlan0: AP-DISABLED Sep 09 22:38:04 volumio-goco hostapd[40220]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:38:04 volumio-goco hostapd[40220]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:38:04 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:38:04 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:38:04 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:38:04 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:38:04 volumio-goco dnsmasq[40226]: started, version 2.90 cachesize 150 Sep 09 22:38:04 volumio-goco dnsmasq[40226]: 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:38:04 volumio-goco dnsmasq-dhcp[40226]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:38:04 volumio-goco dnsmasq[40226]: reading /etc/resolv.conf Sep 09 22:38:04 volumio-goco dnsmasq[40226]: using nameserver 192.168.3.1#53 Sep 09 22:38:04 volumio-goco dnsmasq[40226]: using nameserver 91.102.231.241#53 Sep 09 22:38:04 volumio-goco dnsmasq[40226]: using nameserver 91.102.231.242#53 Sep 09 22:38:04 volumio-goco dnsmasq[40226]: using nameserver 208.67.222.222#53 Sep 09 22:38:04 volumio-goco dnsmasq[40226]: using nameserver 208.67.220.220#53 Sep 09 22:38:04 volumio-goco dnsmasq[40226]: read /etc/hosts - 4 names Sep 09 22:38:04 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:38:05 volumio-goco volumio[40037]: info: Applying required configuration parameters for plugin fusiondsp Sep 09 22:38:05 volumio-goco volumio[40209]: Starting albumart workers Sep 09 22:38:05 volumio-goco volumio[40037]: info: Loading i18n strings for locale en Sep 09 22:38:05 volumio-goco volumio[40037]: Updating browse sources language Sep 09 22:38:05 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 09 22:38:05 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 09 22:38:05 volumio-goco volumio[40037]: info: CoreCommandRouter::initPlayerControls Sep 09 22:38:05 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:05 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:05 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:05 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:05 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:05 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:05 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:05 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:05 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 09 22:38:05 volumio-goco volumio[40037]: Express server listening on port 3000 Sep 09 22:38:05 volumio-goco volumio[40037]: [Metrics] WebUI: 17s 317.95ms Sep 09 22:38:05 volumio-goco volumio[40037]: info: Setting Device type: x86 Sep 09 22:38:05 volumio-goco volumio[40037]: info: CoreStateMachine::resetVolumioState Sep 09 22:38:05 volumio-goco volumio[40037]: info: CoreStateMachine::getcurrentVolume Sep 09 22:38:05 volumio-goco volumio[40037]: info: CoreCommandRouter::volumioRetrievevolume Sep 09 22:38:05 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 22:38:06 volumio-goco sudo[40238]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 09 22:38:06 volumio-goco sudo[40238]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 09 22:38:06 volumio-goco volumio[40037]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Sep 09 22:38:06 volumio-goco sudo[40240]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 09 22:38:06 volumio-goco volumio[40037]: info: Completed loading Core Plugins Sep 09 22:38:06 volumio-goco sudo[40238]: pam_unix(sudo:session): session closed for user root Sep 09 22:38:06 volumio-goco volumio[40037]: info: Preparing to generate the ALSA configuration file Sep 09 22:38:06 volumio-goco sudo[40240]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 09 22:38:06 volumio-goco sudo[40240]: pam_unix(sudo:session): session closed for user root Sep 09 22:38:06 volumio-goco volumio[40037]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 09 22:38:06 volumio-goco volumio[40037]: info: Reading ALSA contributions from plugins. Sep 09 22:38:06 volumio-goco volumio[40037]: info: Volumio Network Manager: Network status updated: 1 Sep 09 22:38:06 volumio-goco volumio[40037]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Sep 09 22:38:06 volumio-goco volumio[40037]: wlan0 Interface doesn't support scanning : Network is down Sep 09 22:38:06 volumio-goco volumio[40037]: info: Cannot use regular scanning, forcing with ap-force Sep 09 22:38:06 volumio-goco sudo[40246]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Sep 09 22:38:06 volumio-goco sudo[40246]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 09 22:38:06 volumio-goco sudo[40246]: pam_unix(sudo:session): session closed for user root Sep 09 22:38:06 volumio-goco volumio[40037]: command failed: Network is down (-100) Sep 09 22:38:06 volumio-goco volumio[40037]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Sep 09 22:38:06 volumio-goco volumio[40037]: command failed: Network is down (-100) Sep 09 22:38:06 volumio-goco volumio[40037]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Sep 09 22:38:06 volumio-goco volumio[40037]: verbose: New Socket.io Connection to 192.168.3.104 from 192.168.3.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Sep 09 22:38:06 volumio-goco volumio[40037]: info: VolumeController:: Volume=74 Mute =false Sep 09 22:38:06 volumio-goco volumio[40037]: info: CoreStateMachine::pushState Sep 09 22:38:06 volumio-goco volumio[40037]: info: CorePlayQueue::getTrack 0 Sep 09 22:38:06 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 09 22:38:06 volumio-goco volumio[40037]: info: CoreCommandRouter::volumioPushState Sep 09 22:38:06 volumio-goco volumio[40037]: info: CoreStateMachine::updateTrackBlock Sep 09 22:38:06 volumio-goco volumio[40037]: info: CorePlayQueue::getTrackBlock Sep 09 22:38:06 volumio-goco volumio[40037]: info: CoreCommandRouter::volumioRetrievevolume Sep 09 22:38:06 volumio-goco volumio[40037]: verbose: New Socket.io Connection to 192.168.3.104 from 192.168.3.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Sep 09 22:38:06 volumio-goco volumio[40037]: info: Reloading queue from file Sep 09 22:38:06 volumio-goco volumio[40037]: verbose: New Socket.io Connection to 192.168.3.104 from 192.168.3.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Sep 09 22:38:06 volumio-goco volumio[40037]: verbose: New Socket.io Connection to 192.168.3.104 from 192.168.3.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Sep 09 22:38:06 volumio-goco volumio[40037]: info: CoreStateMachine::setRepeat null single undefined Sep 09 22:38:06 volumio-goco volumio[40037]: info: CoreStateMachine::pushState Sep 09 22:38:06 volumio-goco volumio[40037]: info: CorePlayQueue::getTrack 0 Sep 09 22:38:06 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 09 22:38:06 volumio-goco volumio[40037]: info: CoreCommandRouter::volumioPushState Sep 09 22:38:06 volumio-goco volumio[40037]: info: CoreStateMachine::setRandom null Sep 09 22:38:06 volumio-goco volumio[40037]: info: CoreStateMachine::pushState Sep 09 22:38:06 volumio-goco volumio[40037]: info: CorePlayQueue::getTrack 0 Sep 09 22:38:06 volumio-goco volumio[40037]: info: CoreCommandRouter::volumioPushState Sep 09 22:38:06 volumio-goco volumio[40037]: verbose: New Socket.io Connection to 192.168.3.104 from 192.168.3.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Sep 09 22:38:06 volumio-goco volumio[40037]: verbose: New Socket.io Connection to 192.168.3.104 from 192.168.3.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Sep 09 22:38:06 volumio-goco volumio[40037]: verbose: New Socket.io Connection to 192.168.3.104 from 192.168.3.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Sep 09 22:38:06 volumio-goco volumio[40037]: verbose: New Socket.io Connection to 192.168.3.104 from 192.168.3.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Sep 09 22:38:06 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2430. Sep 09 22:38:06 volumio-goco volumio[40037]: verbose: New Socket.io Connection to 192.168.3.104 from 192.168.3.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Sep 09 22:38:06 volumio-goco volumio[40037]: verbose: New Socket.io Connection to 192.168.3.104 from 192.168.3.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Sep 09 22:38:06 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:38:06 volumio-goco volumio[40037]: verbose: New Socket.io Connection to 192.168.3.104 from 192.168.3.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Sep 09 22:38:06 volumio-goco dnsmasq[40226]: exiting on receipt of SIGTERM Sep 09 22:38:06 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:38:06 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:38:06 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:38:06 volumio-goco volumio[40037]: verbose: New Socket.io Connection to 192.168.3.104 from 192.168.3.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Sep 09 22:38:06 volumio-goco volumio[40037]: info: VolumeController:: Volume=74 Mute =false Sep 09 22:38:06 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:38:06 volumio-goco volumio[40037]: info: CoreStateMachine::pushState Sep 09 22:38:06 volumio-goco volumio[40037]: info: CorePlayQueue::getTrack 0 Sep 09 22:38:06 volumio-goco volumio[40037]: info: CoreCommandRouter::volumioPushState Sep 09 22:38:06 volumio-goco hostapd[40260]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:38:06 volumio-goco hostapd[40260]: nl80211: Could not configure driver mode Sep 09 22:38:06 volumio-goco hostapd[40260]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:38:06 volumio-goco hostapd[40260]: nl80211 driver initialization failed. Sep 09 22:38:06 volumio-goco hostapd[40260]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:38:06 volumio-goco hostapd[40260]: wlan0: AP-DISABLED Sep 09 22:38:06 volumio-goco hostapd[40260]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:38:06 volumio-goco hostapd[40260]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:38:06 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:38:06 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:38:06 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:38:06 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:38:06 volumio-goco volumio[40037]: verbose: New Socket.io Connection to 192.168.3.104 from 192.168.3.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Sep 09 22:38:06 volumio-goco volumio[40037]: verbose: New Socket.io Connection to 192.168.3.104 from 192.168.3.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Sep 09 22:38:06 volumio-goco sudo[40262]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 09 22:38:06 volumio-goco sudo[40262]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 09 22:38:06 volumio-goco volumio[40037]: verbose: New Socket.io Connection to 192.168.3.104 from 192.168.3.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 15 Sep 09 22:38:06 volumio-goco volumio[40037]: verbose: New Socket.io Connection to 192.168.3.104 from 192.168.3.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 16 Sep 09 22:38:06 volumio-goco dnsmasq[40268]: started, version 2.90 cachesize 150 Sep 09 22:38:06 volumio-goco dnsmasq[40268]: 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:38:06 volumio-goco dnsmasq-dhcp[40268]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:38:06 volumio-goco dnsmasq[40268]: reading /etc/resolv.conf Sep 09 22:38:06 volumio-goco dnsmasq[40268]: using nameserver 192.168.3.1#53 Sep 09 22:38:06 volumio-goco dnsmasq[40268]: using nameserver 91.102.231.241#53 Sep 09 22:38:06 volumio-goco dnsmasq[40268]: using nameserver 91.102.231.242#53 Sep 09 22:38:06 volumio-goco dnsmasq[40268]: using nameserver 208.67.222.222#53 Sep 09 22:38:06 volumio-goco dnsmasq[40268]: using nameserver 208.67.220.220#53 Sep 09 22:38:06 volumio-goco dnsmasq[40268]: read /etc/hosts - 4 names Sep 09 22:38:06 volumio-goco volumio[40037]: verbose: New Socket.io Connection to 192.168.3.104 from 192.168.3.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 17 Sep 09 22:38:06 volumio-goco volumio[40037]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 18 Sep 09 22:38:06 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:38:06 volumio-goco sudo[40262]: pam_unix(sudo:session): session closed for user root Sep 09 22:38:06 volumio-goco volumio[40037]: verbose: New Socket.io Connection to 192.168.3.104 from 192.168.3.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 19 Sep 09 22:38:07 volumio-goco volumio[40037]: verbose: New Socket.io Connection to 192.168.3.104 from 192.168.3.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 20 Sep 09 22:38:07 volumio-goco volumio[40037]: info: Upmpdcli Daemon Started Sep 09 22:38:07 volumio-goco volumio[40037]: info: Asound.conf file unchanged, so no further update is needed Sep 09 22:38:07 volumio-goco volumio[40037]: info: Output device has changed, restarting MPD Sep 09 22:38:07 volumio-goco volumio[40037]: info: Output device has changed, restarting Shairport Sync Sep 09 22:38:07 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:07 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 22:38:07 volumio-goco sudo[40273]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 09 22:38:07 volumio-goco sudo[40273]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 09 22:38:07 volumio-goco sudo[40275]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 09 22:38:07 volumio-goco sudo[40273]: pam_unix(sudo:session): session closed for user root Sep 09 22:38:07 volumio-goco sudo[40275]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 09 22:38:07 volumio-goco volumio[40037]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 09 22:38:07 volumio-goco volumio[40037]: info: ___________ START PLUGINS ___________ Sep 09 22:38:07 volumio-goco volumio[40037]: info: ControllerMpd::onStart: Initializing MPD Sep 09 22:38:07 volumio-goco volumio[40037]: info: Creating MPD Configuration file Sep 09 22:38:07 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 09 22:38:07 volumio-goco volumio[40037]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 09 22:38:07 volumio-goco volumio[40037]: info: [1757450287259] CoreMusicLibrary::Adding element Media Servers Sep 09 22:38:07 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 09 22:38:07 volumio-goco sudo[40283]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 09 22:38:07 volumio-goco sudo[40283]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 09 22:38:07 volumio-goco sudo[40285]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 09 22:38:07 volumio-goco systemd[1]: Stopping mpd.service - Music Player Daemon... Sep 09 22:38:07 volumio-goco sudo[40285]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 09 22:38:07 volumio-goco sudo[40283]: pam_unix(sudo:session): session closed for user root Sep 09 22:38:07 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:07 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 22:38:07 volumio-goco volumio[40037]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 09 22:38:07 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:07 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 22:38:07 volumio-goco volumio[40037]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 09 22:38:07 volumio-goco volumio[40037]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 09 22:38:07 volumio-goco volumio[40037]: info: [1757450287497] CoreMusicLibrary::Adding element Last_100 Sep 09 22:38:07 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 09 22:38:07 volumio-goco volumio[40037]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 09 22:38:07 volumio-goco volumio[40037]: info: [1757450287505] CoreMusicLibrary::Adding element Webradio Sep 09 22:38:07 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 09 22:38:07 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 09 22:38:07 volumio-goco volumio[40037]: info: Initializing BBC Radios Sep 09 22:38:07 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 09 22:38:07 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 22:38:07 volumio-goco volumio[40037]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 09 22:38:07 volumio-goco volumio[40037]: info: [1757450287665] CoreMusicLibrary::Adding element SoundCloud Sep 09 22:38:07 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 09 22:38:07 volumio-goco volumio[40037]: Cannot find translation for source SoundCloud Sep 09 22:38:07 volumio-goco volumio[40037]: info: Creating Spotify config file Sep 09 22:38:07 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:07 volumio-goco volumio[40037]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 09 22:38:07 volumio-goco volumio[40037]: info: [1757450287804] CoreMusicLibrary::Adding element YouTube2 Sep 09 22:38:07 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 09 22:38:07 volumio-goco volumio[40037]: Cannot find translation for source SoundCloud Sep 09 22:38:07 volumio-goco volumio[40037]: Cannot find translation for source YouTube2 Sep 09 22:38:07 volumio-goco volumio[40037]: info: Loading i18n strings for locale en Sep 09 22:38:07 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 09 22:38:07 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:07 volumio-goco volumio[40037]: info: FusionDsp - mixtype--------------------- Hardware Sep 09 22:38:07 volumio-goco volumio[40037]: info: Volumio Calling Home Sep 09 22:38:08 volumio-goco volumio[40037]: info: Preparing to generate the ALSA configuration file Sep 09 22:38:08 volumio-goco volumio[40037]: info: Discovery: adding fc424e36-b5bc-4e66-b049-6ea7ce0c51c4 Sep 09 22:38:08 volumio-goco volumio[40037]: info: Discovery: Found device Volumio-Goco Sep 09 22:38:08 volumio-goco volumio[40037]: info: CoreCommandRouter::volumioGetState Sep 09 22:38:08 volumio-goco volumio[40037]: info: CorePlayQueue::getTrack 0 Sep 09 22:38:08 volumio-goco volumio[40037]: info: Discovery: this is already registered, fc424e36-b5bc-4e66-b049-6ea7ce0c51c4 Sep 09 22:38:08 volumio-goco volumio[40037]: info: Discovery: Found device Volumio-Goco Sep 09 22:38:08 volumio-goco volumio[40037]: info: CoreCommandRouter::volumioGetState Sep 09 22:38:08 volumio-goco volumio[40037]: info: CorePlayQueue::getTrack 0 Sep 09 22:38:08 volumio-goco volumio[40037]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 09 22:38:08 volumio-goco volumio[40037]: info: Reading ALSA contributions from plugins. Sep 09 22:38:08 volumio-goco volumio[40037]: verbose: New Socket.io Connection to 192.168.3.104 from 192.168.3.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 21 Sep 09 22:38:08 volumio-goco volumio[40037]: verbose: New Socket.io Connection to 192.168.3.104 from 192.168.3.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 22 Sep 09 22:38:08 volumio-goco volumio[40037]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 23 Sep 09 22:38:08 volumio-goco volumio[40037]: info: MPD Permissions set Sep 09 22:38:08 volumio-goco volumio[40037]: info: MPD Permissions set Sep 09 22:38:08 volumio-goco volumio[40037]: error: Upnp client error: Error: This socket has been ended by the other party Sep 09 22:38:08 volumio-goco volumio[40037]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 24 Sep 09 22:38:08 volumio-goco volumio[40037]: info: Volumio called home Sep 09 22:38:08 volumio-goco volumio[40037]: info: Spotify config file written Sep 09 22:38:08 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:08 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:08 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:08 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:08 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:08 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:08 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:08 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:08 volumio-goco sudo[40316]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Sep 09 22:38:08 volumio-goco sudo[40316]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 09 22:38:08 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 22:38:08 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 09 22:38:08 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2431. Sep 09 22:38:08 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:38:08 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:08 volumio-goco systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Sep 09 22:38:08 volumio-goco systemd[1]: go-librespot-daemon.service: Deactivated successfully. Sep 09 22:38:08 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:08 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:08 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:08 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:08 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:08 volumio-goco systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Sep 09 22:38:08 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:08 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:08 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:09 volumio-goco systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Sep 09 22:38:09 volumio-goco dnsmasq[40268]: exiting on receipt of SIGTERM Sep 09 22:38:09 volumio-goco sudo[40316]: pam_unix(sudo:session): session closed for user root Sep 09 22:38:09 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 22:38:09 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 09 22:38:09 volumio-goco go-librespot[40327]: go-librespot daemon starting... Sep 09 22:38:09 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:09 volumio-goco volumio[40037]: info: No need to fix Spotify hosts Sep 09 22:38:09 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:38:09 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:38:09 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:38:09 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:38:09 volumio-goco volumio[40037]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 25 Sep 09 22:38:09 volumio-goco volumio[40037]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 26 Sep 09 22:38:09 volumio-goco hostapd[40337]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:38:09 volumio-goco hostapd[40337]: nl80211: Could not configure driver mode Sep 09 22:38:09 volumio-goco hostapd[40337]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:38:09 volumio-goco hostapd[40337]: nl80211 driver initialization failed. Sep 09 22:38:09 volumio-goco hostapd[40337]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:38:09 volumio-goco hostapd[40337]: wlan0: AP-DISABLED Sep 09 22:38:09 volumio-goco hostapd[40337]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:38:09 volumio-goco hostapd[40337]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:38:09 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:38:09 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:38:09 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:38:09 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:38:09 volumio-goco volumio[40037]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 27 Sep 09 22:38:09 volumio-goco volumio[40037]: info: Starting Shairport Sync Sep 09 22:38:09 volumio-goco volumio[40037]: info: Starting Shairport Sync Sep 09 22:38:09 volumio-goco volumio[40037]: info: Starting Shairport Sync Sep 09 22:38:09 volumio-goco sudo[40347]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 09 22:38:09 volumio-goco sudo[40349]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 09 22:38:09 volumio-goco sudo[40345]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 09 22:38:09 volumio-goco sudo[40345]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 09 22:38:09 volumio-goco sudo[40347]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 09 22:38:09 volumio-goco sudo[40349]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 09 22:38:09 volumio-goco volumio[40037]: info: Asound.conf file unchanged, so no further update is needed Sep 09 22:38:09 volumio-goco volumio[40037]: info: Output device has changed, restarting MPD Sep 09 22:38:09 volumio-goco systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Sep 09 22:38:09 volumio-goco systemd[1]: shairport-sync.service: Deactivated successfully. Sep 09 22:38:09 volumio-goco systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 09 22:38:09 volumio-goco systemd[1]: shairport-sync.service: Consumed 32.016s CPU time. Sep 09 22:38:09 volumio-goco systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 09 22:38:09 volumio-goco volumio[40037]: info: Output device has changed, restarting Shairport Sync Sep 09 22:38:09 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:09 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 22:38:09 volumio-goco sudo[40345]: pam_unix(sudo:session): session closed for user root Sep 09 22:38:09 volumio-goco sudo[40347]: pam_unix(sudo:session): session closed for user root Sep 09 22:38:09 volumio-goco sudo[40349]: pam_unix(sudo:session): session closed for user root Sep 09 22:38:10 volumio-goco sudo[40355]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 09 22:38:10 volumio-goco sudo[40358]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 09 22:38:10 volumio-goco sudo[40355]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 09 22:38:10 volumio-goco sudo[40358]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 09 22:38:10 volumio-goco sudo[40355]: pam_unix(sudo:session): session closed for user root Sep 09 22:38:10 volumio-goco dnsmasq[40367]: started, version 2.90 cachesize 150 Sep 09 22:38:10 volumio-goco dnsmasq[40367]: 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:38:10 volumio-goco dnsmasq-dhcp[40367]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:38:10 volumio-goco dnsmasq[40367]: reading /etc/resolv.conf Sep 09 22:38:10 volumio-goco dnsmasq[40367]: using nameserver 192.168.3.1#53 Sep 09 22:38:10 volumio-goco dnsmasq[40367]: using nameserver 91.102.231.241#53 Sep 09 22:38:10 volumio-goco dnsmasq[40367]: using nameserver 91.102.231.242#53 Sep 09 22:38:10 volumio-goco dnsmasq[40367]: using nameserver 208.67.222.222#53 Sep 09 22:38:10 volumio-goco dnsmasq[40367]: using nameserver 208.67.220.220#53 Sep 09 22:38:10 volumio-goco dnsmasq[40367]: read /etc/hosts - 4 names Sep 09 22:38:10 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:38:10 volumio-goco volumio[40037]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 09 22:38:10 volumio-goco go-librespot[40336]: time="2025-09-09T22:38:10+02:00" level=info msg="running go-librespot 0.3.2" Sep 09 22:38:10 volumio-goco go-librespot[40336]: time="2025-09-09T22:38:10+02:00" level=debug msg="app state loaded" Sep 09 22:38:10 volumio-goco go-librespot[40336]: time="2025-09-09T22:38:10+02:00" level=debug msg="stored credentials not found" Sep 09 22:38:10 volumio-goco volumio[40037]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 09 22:38:10 volumio-goco volumio[40037]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Sep 09 22:38:10 volumio-goco volumio[40037]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Sep 09 22:38:10 volumio-goco volumio[40037]: info: camilladsp service started and running in background, instance 1 Sep 09 22:38:10 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:10 volumio-goco go-librespot[40336]: time="2025-09-09T22:38:10+02:00" level=info msg="api server listening on 127.0.0.1:9879" Sep 09 22:38:10 volumio-goco volumio[40037]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 96000 <-AAAAAAAAAAAAA Sep 09 22:38:10 volumio-goco volumio[40037]: info: FusionDsp loaded Sep 09 22:38:10 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 09 22:38:10 volumio-goco volumio[40037]: error: FusionDsp - Socket connection not established Sep 09 22:38:10 volumio-goco volumio[40037]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 09 22:38:10 volumio-goco volumio[40037]: info: Adding Signal Path Element [object Object] Sep 09 22:38:10 volumio-goco volumio[40037]: info: Adding fusiondspeq DSP Signal Path Element Sep 09 22:38:10 volumio-goco volumio[40037]: info: FusionDsp - ---- installed callbackRead Sep 09 22:38:10 volumio-goco volumio[40037]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 09 22:38:10 volumio-goco systemd[1]: mpd.service: Deactivated successfully. Sep 09 22:38:10 volumio-goco systemd[1]: Stopped mpd.service - Music Player Daemon. Sep 09 22:38:10 volumio-goco systemd[1]: mpd.service: Consumed 33.774s CPU time. Sep 09 22:38:10 volumio-goco systemd[1]: mpd.socket: Deactivated successfully. Sep 09 22:38:10 volumio-goco systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Sep 09 22:38:10 volumio-goco systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Sep 09 22:38:10 volumio-goco volumio[40037]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 28 Sep 09 22:38:10 volumio-goco sudo[40383]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 09 22:38:10 volumio-goco sudo[40383]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 09 22:38:10 volumio-goco systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Sep 09 22:38:10 volumio-goco systemd[1]: Starting mpd.service - Music Player Daemon... Sep 09 22:38:10 volumio-goco volumio[40037]: info: MPD Permissions set Sep 09 22:38:10 volumio-goco volumio[40037]: info: Shairport-Sync Started Sep 09 22:38:10 volumio-goco volumio[40037]: Error adding Membership: Error: addMembership EINVAL Sep 09 22:38:10 volumio-goco volumio[40037]: info: Shairport-Sync Started Sep 09 22:38:10 volumio-goco volumio[40037]: info: Shairport-Sync Started Sep 09 22:38:10 volumio-goco sudo[40383]: pam_unix(sudo:session): session closed for user root Sep 09 22:38:11 volumio-goco volumio[40037]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Sep 09 22:38:11 volumio-goco sudo[40387]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 09 22:38:11 volumio-goco sudo[40387]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Sep 09 22:38:11 volumio-goco volumio[40037]: error: FusionDsp - Reload WebSocket error: [object Object] Sep 09 22:38:11 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:11 volumio-goco go-librespot[40336]: time="2025-09-09T22:38:11+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Sep 09 22:38:11 volumio-goco go-librespot[40336]: time="2025-09-09T22:38:11+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Sep 09 22:38:11 volumio-goco go-librespot[40336]: time="2025-09-09T22:38:11+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Sep 09 22:38:11 volumio-goco sudo[40387]: pam_unix(sudo:session): session closed for user root Sep 09 22:38:11 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:11 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:11 volumio-goco go-librespot[40336]: time="2025-09-09T22:38:11+02:00" level=info msg="zeroconf server listening on port 46361" Sep 09 22:38:11 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:11 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:11 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:11 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:11 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:11 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 22:38:11 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 09 22:38:11 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 09 22:38:11 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2432. Sep 09 22:38:11 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:38:11 volumio-goco dnsmasq[40367]: exiting on receipt of SIGTERM Sep 09 22:38:11 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:38:11 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:38:11 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:38:11 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:38:11 volumio-goco volumio[40037]: verbose: New Socket.io Connection to 192.168.3.104 from 192.168.3.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 29 Sep 09 22:38:11 volumio-goco volumio[40037]: info: Starting Shairport Sync Sep 09 22:38:11 volumio-goco hostapd[40415]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:38:11 volumio-goco hostapd[40415]: nl80211: Could not configure driver mode Sep 09 22:38:11 volumio-goco hostapd[40415]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:38:11 volumio-goco hostapd[40415]: nl80211 driver initialization failed. Sep 09 22:38:11 volumio-goco hostapd[40415]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:38:11 volumio-goco hostapd[40415]: wlan0: AP-DISABLED Sep 09 22:38:11 volumio-goco hostapd[40415]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:38:11 volumio-goco hostapd[40415]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:38:11 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:38:11 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:38:11 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:38:11 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:38:12 volumio-goco sudo[40418]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 09 22:38:12 volumio-goco sudo[40418]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 09 22:38:12 volumio-goco dnsmasq[40424]: started, version 2.90 cachesize 150 Sep 09 22:38:12 volumio-goco dnsmasq[40424]: 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:38:12 volumio-goco dnsmasq-dhcp[40424]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:38:12 volumio-goco dnsmasq[40424]: reading /etc/resolv.conf Sep 09 22:38:12 volumio-goco dnsmasq[40424]: using nameserver 192.168.3.1#53 Sep 09 22:38:12 volumio-goco dnsmasq[40424]: using nameserver 91.102.231.241#53 Sep 09 22:38:12 volumio-goco dnsmasq[40424]: using nameserver 91.102.231.242#53 Sep 09 22:38:12 volumio-goco dnsmasq[40424]: using nameserver 208.67.222.222#53 Sep 09 22:38:12 volumio-goco dnsmasq[40424]: using nameserver 208.67.220.220#53 Sep 09 22:38:12 volumio-goco dnsmasq[40424]: read /etc/hosts - 4 names Sep 09 22:38:12 volumio-goco volumio[40037]: info: CoreCommandRouter::volumioGetState Sep 09 22:38:12 volumio-goco volumio[40037]: info: CorePlayQueue::getTrack 0 Sep 09 22:38:12 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:38:12 volumio-goco systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Sep 09 22:38:12 volumio-goco systemd[1]: shairport-sync.service: Deactivated successfully. Sep 09 22:38:12 volumio-goco systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 09 22:38:12 volumio-goco systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Sep 09 22:38:12 volumio-goco sudo[40418]: pam_unix(sudo:session): session closed for user root Sep 09 22:38:12 volumio-goco volumio[40037]: info: Shairport-Sync Started Sep 09 22:38:12 volumio-goco volumio[40037]: info: go-librespot daemon successfully initialized Sep 09 22:38:12 volumio-goco volumio[40037]: verbose: New Socket.io Connection to 192.168.3.104 from 192.168.3.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 30 Sep 09 22:38:13 volumio-goco volumio[40037]: verbose: New Socket.io Connection to 192.168.3.104 from 192.168.3.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 31 Sep 09 22:38:13 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 09 22:38:13 volumio-goco volumio[40037]: verbose: New Socket.io Connection to 192.168.3.104 from 192.168.3.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 32 Sep 09 22:38:14 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2433. Sep 09 22:38:14 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:38:14 volumio-goco dnsmasq[40424]: exiting on receipt of SIGTERM Sep 09 22:38:14 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:38:14 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:38:14 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:38:14 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:38:14 volumio-goco hostapd[40459]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:38:14 volumio-goco hostapd[40459]: nl80211: Could not configure driver mode Sep 09 22:38:14 volumio-goco hostapd[40459]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:38:14 volumio-goco hostapd[40459]: nl80211 driver initialization failed. Sep 09 22:38:14 volumio-goco hostapd[40459]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:38:14 volumio-goco hostapd[40459]: wlan0: AP-DISABLED Sep 09 22:38:14 volumio-goco hostapd[40459]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:38:14 volumio-goco hostapd[40459]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:38:14 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:38:14 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:38:14 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:38:14 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:38:14 volumio-goco volumio[40037]: verbose: New Socket.io Connection to 192.168.3.104 from 192.168.3.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 33 Sep 09 22:38:14 volumio-goco dnsmasq[40464]: started, version 2.90 cachesize 150 Sep 09 22:38:14 volumio-goco dnsmasq[40464]: 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:38:14 volumio-goco dnsmasq-dhcp[40464]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:38:14 volumio-goco dnsmasq[40464]: reading /etc/resolv.conf Sep 09 22:38:14 volumio-goco dnsmasq[40464]: using nameserver 192.168.3.1#53 Sep 09 22:38:14 volumio-goco dnsmasq[40464]: using nameserver 91.102.231.241#53 Sep 09 22:38:14 volumio-goco dnsmasq[40464]: using nameserver 91.102.231.242#53 Sep 09 22:38:14 volumio-goco dnsmasq[40464]: using nameserver 208.67.222.222#53 Sep 09 22:38:14 volumio-goco dnsmasq[40464]: using nameserver 208.67.220.220#53 Sep 09 22:38:14 volumio-goco dnsmasq[40464]: read /etc/hosts - 4 names Sep 09 22:38:14 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:38:14 volumio-goco systemd[1]: systemd-timedated.service: Deactivated successfully. Sep 09 22:38:15 volumio-goco volumio[40037]: verbose: New Socket.io Connection to 192.168.3.104 from 192.168.3.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 34 Sep 09 22:38:15 volumio-goco volumio[40037]: info: CoreCommandRouter::volumioGetVisibleSources Sep 09 22:38:15 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 09 22:38:15 volumio-goco volumio[40037]: info: CoreCommandRouter::volumioGetState Sep 09 22:38:15 volumio-goco volumio[40037]: info: CorePlayQueue::getTrack 0 Sep 09 22:38:15 volumio-goco volumio[40037]: info: CoreCommandRouter::volumioGetState Sep 09 22:38:15 volumio-goco volumio[40037]: info: CorePlayQueue::getTrack 0 Sep 09 22:38:15 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 09 22:38:15 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 09 22:38:15 volumio-goco volumio[40037]: info: Received Get System Info Sep 09 22:38:15 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 09 22:38:15 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 09 22:38:15 volumio-goco volumio[40037]: info: Discovery: Getting this device information Sep 09 22:38:15 volumio-goco volumio[40037]: info: CoreCommandRouter::volumioGetState Sep 09 22:38:15 volumio-goco volumio[40037]: info: CorePlayQueue::getTrack 0 Sep 09 22:38:15 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 09 22:38:15 volumio-goco volumio[40037]: info: CoreCommandRouter::volumioGetState Sep 09 22:38:15 volumio-goco volumio[40037]: info: CorePlayQueue::getTrack 0 Sep 09 22:38:15 volumio-goco volumio[40037]: info: Listing playlists Sep 09 22:38:15 volumio-goco volumio[40037]: info: Initializing connection to go-librespot Websocket Sep 09 22:38:15 volumio-goco go-librespot[40336]: time="2025-09-09T22:38:15+02:00" level=debug msg="new websocket client" Sep 09 22:38:15 volumio-goco volumio[40037]: info: Connection to go-librespot Websocket established Sep 09 22:38:15 volumio-goco volumio[40037]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 09 22:38:16 volumio-goco volumio[40037]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 09 22:38:16 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2434. Sep 09 22:38:16 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:38:16 volumio-goco dnsmasq[40464]: exiting on receipt of SIGTERM Sep 09 22:38:16 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:38:16 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:38:16 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:38:16 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:38:17 volumio-goco hostapd[40473]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:38:17 volumio-goco hostapd[40473]: nl80211: Could not configure driver mode Sep 09 22:38:17 volumio-goco hostapd[40473]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:38:17 volumio-goco hostapd[40473]: nl80211 driver initialization failed. Sep 09 22:38:17 volumio-goco hostapd[40473]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:38:17 volumio-goco hostapd[40473]: wlan0: AP-DISABLED Sep 09 22:38:17 volumio-goco hostapd[40473]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:38:17 volumio-goco hostapd[40473]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:38:17 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:38:17 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:38:17 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:38:17 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:38:17 volumio-goco dnsmasq[40478]: started, version 2.90 cachesize 150 Sep 09 22:38:17 volumio-goco dnsmasq[40478]: 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:38:17 volumio-goco dnsmasq-dhcp[40478]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:38:17 volumio-goco dnsmasq[40478]: reading /etc/resolv.conf Sep 09 22:38:17 volumio-goco dnsmasq[40478]: using nameserver 192.168.3.1#53 Sep 09 22:38:17 volumio-goco dnsmasq[40478]: using nameserver 91.102.231.241#53 Sep 09 22:38:17 volumio-goco dnsmasq[40478]: using nameserver 91.102.231.242#53 Sep 09 22:38:17 volumio-goco dnsmasq[40478]: using nameserver 208.67.222.222#53 Sep 09 22:38:17 volumio-goco dnsmasq[40478]: using nameserver 208.67.220.220#53 Sep 09 22:38:17 volumio-goco dnsmasq[40478]: read /etc/hosts - 4 names Sep 09 22:38:17 volumio-goco volumio[40037]: verbose: New Socket.io Connection to 192.168.3.104 from 192.168.3.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 35 Sep 09 22:38:17 volumio-goco mpd[40405]: 2025-09-09T22:38:17 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 09 22:38:17 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:38:17 volumio-goco systemd[1]: Started mpd.service - Music Player Daemon. Sep 09 22:38:17 volumio-goco sudo[40358]: pam_unix(sudo:session): session closed for user root Sep 09 22:38:17 volumio-goco sudo[40285]: pam_unix(sudo:session): session closed for user root Sep 09 22:38:17 volumio-goco sudo[40275]: pam_unix(sudo:session): session closed for user root Sep 09 22:38:17 volumio-goco volumio[40037]: info: Completed starting Core Plugins Sep 09 22:38:17 volumio-goco volumio[40037]: info: ------------------------------------------- Sep 09 22:38:17 volumio-goco volumio[40037]: info: ----- MyVolumio plugins startup ---- Sep 09 22:38:17 volumio-goco volumio[40037]: info: ------------------------------------------- Sep 09 22:38:17 volumio-goco volumio[40037]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 09 22:38:17 volumio-goco volumio[40037]: info: MPD running with PID40405 Sep 09 22:38:17 volumio-goco volumio[40037]: ,establishing connection Sep 09 22:38:17 volumio-goco volumio[40037]: error: MPD error: The expression evaluated to a falsy value: Sep 09 22:38:17 volumio-goco volumio[40037]: assert.ok(self.idling) Sep 09 22:38:17 volumio-goco volumio[40037]: error: The expression evaluated to a falsy value: Sep 09 22:38:17 volumio-goco volumio[40037]: assert.ok(self.idling) Sep 09 22:38:17 volumio-goco volumio[40037]: error: MPD error: The expression evaluated to a falsy value: Sep 09 22:38:17 volumio-goco volumio[40037]: assert.ok(self.idling) Sep 09 22:38:17 volumio-goco volumio[40037]: error: The expression evaluated to a falsy value: Sep 09 22:38:17 volumio-goco volumio[40037]: assert.ok(self.idling) Sep 09 22:38:17 volumio-goco volumio[40037]: error: MPD error: The expression evaluated to a falsy value: Sep 09 22:38:17 volumio-goco volumio[40037]: assert.ok(self.idling) Sep 09 22:38:17 volumio-goco volumio[40037]: error: The expression evaluated to a falsy value: Sep 09 22:38:17 volumio-goco volumio[40037]: assert.ok(self.idling) Sep 09 22:38:17 volumio-goco volumio[40037]: error: updateQueue error: null Sep 09 22:38:18 volumio-goco volumio[40037]: verbose: New Socket.io Connection to 192.168.3.104 from 192.168.3.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 36 Sep 09 22:38:18 volumio-goco volumio[40037]: verbose: New Socket.io Connection to 192.168.3.104 from 192.168.3.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 37 Sep 09 22:38:18 volumio-goco volumio[40037]: verbose: New Socket.io Connection to 192.168.3.104 from 192.168.3.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 38 Sep 09 22:38:18 volumio-goco volumio[40037]: verbose: New Socket.io Connection to 192.168.3.104 from 192.168.3.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/140.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 39 Sep 09 22:38:18 volumio-goco volumio-remote-updater[608]: [2025-09-09 20:38:18] [connect] Successful connection Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::volumioGetState Sep 09 22:38:18 volumio-goco volumio[40037]: info: CorePlayQueue::getTrack 0 Sep 09 22:38:18 volumio-goco volumio-remote-updater[608]: [2025-09-09 20:38:18] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1757450298 101 Sep 09 22:38:18 volumio-goco volumio[40037]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 40 Sep 09 22:38:18 volumio-goco volumio[40037]: info: Received Get System Info Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 09 22:38:18 volumio-goco volumio[40037]: info: Discovery: Getting this device information Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::volumioGetState Sep 09 22:38:18 volumio-goco volumio[40037]: info: CorePlayQueue::getTrack 0 Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::volumioGetState Sep 09 22:38:18 volumio-goco volumio[40037]: info: CorePlayQueue::getTrack 0 Sep 09 22:38:18 volumio-goco volumio[40037]: info: Received Get System Info Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 09 22:38:18 volumio-goco volumio[40037]: info: Discovery: Getting this device information Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::volumioGetState Sep 09 22:38:18 volumio-goco volumio[40037]: info: CorePlayQueue::getTrack 0 Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::volumioGetState Sep 09 22:38:18 volumio-goco volumio[40037]: info: CorePlayQueue::getTrack 0 Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::volumioGetVisibleSources Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::volumioGetState Sep 09 22:38:18 volumio-goco volumio[40037]: info: CorePlayQueue::getTrack 0 Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::volumioGetState Sep 09 22:38:18 volumio-goco volumio[40037]: info: CorePlayQueue::getTrack 0 Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 09 22:38:18 volumio-goco volumio[40037]: info: Received Get System Info Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 09 22:38:18 volumio-goco volumio[40037]: info: Discovery: Getting this device information Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::volumioGetState Sep 09 22:38:18 volumio-goco volumio[40037]: info: CorePlayQueue::getTrack 0 Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::volumioGetState Sep 09 22:38:18 volumio-goco volumio[40037]: info: CorePlayQueue::getTrack 0 Sep 09 22:38:18 volumio-goco volumio[40037]: info: Listing playlists Sep 09 22:38:18 volumio-goco volumio[40037]: info: Received Get System Info Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 09 22:38:18 volumio-goco volumio[40037]: info: Discovery: Getting this device information Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::volumioGetState Sep 09 22:38:18 volumio-goco volumio[40037]: info: CorePlayQueue::getTrack 0 Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::volumioGetState Sep 09 22:38:18 volumio-goco volumio[40037]: info: CorePlayQueue::getTrack 0 Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::volumioGetQueue Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreStateMachine::getQueue Sep 09 22:38:18 volumio-goco volumio[40037]: info: CorePlayQueue::getQueue Sep 09 22:38:18 volumio-goco volumio[40037]: info: Getting Spotify volume Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 09 22:38:18 volumio-goco volumio[40037]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 09 22:38:18 volumio-goco volumio[40037]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 41 Sep 09 22:38:19 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2435. Sep 09 22:38:19 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:38:19 volumio-goco dnsmasq[40478]: exiting on receipt of SIGTERM Sep 09 22:38:19 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:38:19 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:38:19 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:38:19 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:38:19 volumio-goco hostapd[40488]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:38:19 volumio-goco hostapd[40488]: nl80211: Could not configure driver mode Sep 09 22:38:19 volumio-goco hostapd[40488]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:38:19 volumio-goco hostapd[40488]: nl80211 driver initialization failed. Sep 09 22:38:19 volumio-goco hostapd[40488]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:38:19 volumio-goco hostapd[40488]: wlan0: AP-DISABLED Sep 09 22:38:19 volumio-goco hostapd[40488]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:38:19 volumio-goco hostapd[40488]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:38:19 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:38:19 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:38:19 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:38:19 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:38:19 volumio-goco dnsmasq[40493]: started, version 2.90 cachesize 150 Sep 09 22:38:19 volumio-goco dnsmasq[40493]: 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:38:19 volumio-goco dnsmasq-dhcp[40493]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:38:19 volumio-goco dnsmasq[40493]: reading /etc/resolv.conf Sep 09 22:38:19 volumio-goco dnsmasq[40493]: using nameserver 192.168.3.1#53 Sep 09 22:38:19 volumio-goco dnsmasq[40493]: using nameserver 91.102.231.241#53 Sep 09 22:38:19 volumio-goco dnsmasq[40493]: using nameserver 91.102.231.242#53 Sep 09 22:38:19 volumio-goco dnsmasq[40493]: using nameserver 208.67.222.222#53 Sep 09 22:38:19 volumio-goco dnsmasq[40493]: using nameserver 208.67.220.220#53 Sep 09 22:38:19 volumio-goco dnsmasq[40493]: read /etc/hosts - 4 names Sep 09 22:38:19 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:38:20 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 09 22:38:20 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 09 22:38:20 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 09 22:38:20 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 09 22:38:21 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2436. Sep 09 22:38:21 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:38:21 volumio-goco dnsmasq[40493]: exiting on receipt of SIGTERM Sep 09 22:38:21 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:38:21 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:38:21 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:38:21 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:38:21 volumio-goco hostapd[40499]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:38:21 volumio-goco hostapd[40499]: nl80211: Could not configure driver mode Sep 09 22:38:21 volumio-goco hostapd[40499]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:38:21 volumio-goco hostapd[40499]: nl80211 driver initialization failed. Sep 09 22:38:21 volumio-goco hostapd[40499]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:38:21 volumio-goco hostapd[40499]: wlan0: AP-DISABLED Sep 09 22:38:21 volumio-goco hostapd[40499]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:38:21 volumio-goco hostapd[40499]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:38:21 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:38:21 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:38:21 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:38:21 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 09 22:38:21 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:38:21 volumio-goco dnsmasq[40505]: started, version 2.90 cachesize 150 Sep 09 22:38:21 volumio-goco dnsmasq[40505]: 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:38:21 volumio-goco dnsmasq-dhcp[40505]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:38:21 volumio-goco dnsmasq[40505]: reading /etc/resolv.conf Sep 09 22:38:21 volumio-goco dnsmasq[40505]: using nameserver 192.168.3.1#53 Sep 09 22:38:21 volumio-goco dnsmasq[40505]: using nameserver 91.102.231.241#53 Sep 09 22:38:21 volumio-goco dnsmasq[40505]: using nameserver 91.102.231.242#53 Sep 09 22:38:21 volumio-goco dnsmasq[40505]: using nameserver 208.67.222.222#53 Sep 09 22:38:21 volumio-goco dnsmasq[40505]: using nameserver 208.67.220.220#53 Sep 09 22:38:21 volumio-goco dnsmasq[40505]: read /etc/hosts - 4 names Sep 09 22:38:21 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:38:21 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 09 22:38:21 volumio-goco volumio[40037]: info: CoreCommandRouter::volumioGetState Sep 09 22:38:21 volumio-goco volumio[40037]: info: CorePlayQueue::getTrack 0 Sep 09 22:38:21 volumio-goco volumio[40037]: SPOTIFY: RECEIVED VOLUMIO VOLUME 74 Sep 09 22:38:21 volumio-goco volumio[40037]: SPOTIFY: SPOTIFY VOLUME undefined Sep 09 22:38:21 volumio-goco volumio[40037]: SPOTIFY: VOLUMIO VOLUME 74 Sep 09 22:38:21 volumio-goco volumio[40037]: info: Aligning Spotify Volume to Volumio Volume Sep 09 22:38:21 volumio-goco volumio[40037]: info: CoreCommandRouter::volumioGetState Sep 09 22:38:21 volumio-goco volumio[40037]: info: CorePlayQueue::getTrack 0 Sep 09 22:38:21 volumio-goco volumio[40037]: info: Setting Spotify Volume from Volumio: 74 Sep 09 22:38:22 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 09 22:38:22 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 09 22:38:22 volumio-goco volumio[40037]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 09 22:38:23 volumio-goco volumio[40037]: Cannot compose Albumart path Sep 09 22:38:23 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 09 22:38:23 volumio-goco volumio[40037]: SPOTIFY: SETTING SPOTIFY VOLUME 74 Sep 09 22:38:23 volumio-goco volumio[40037]: info: Sending Spotify command with payload to local API: /player/volume Sep 09 22:38:23 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2437. Sep 09 22:38:23 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 09 22:38:23 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:38:23 volumio-goco dnsmasq[40505]: exiting on receipt of SIGTERM Sep 09 22:38:23 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:38:23 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:38:23 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:38:23 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:38:23 volumio-goco hostapd[40525]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:38:23 volumio-goco hostapd[40525]: nl80211: Could not configure driver mode Sep 09 22:38:23 volumio-goco hostapd[40525]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:38:23 volumio-goco hostapd[40525]: nl80211 driver initialization failed. Sep 09 22:38:23 volumio-goco hostapd[40525]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:38:23 volumio-goco hostapd[40525]: wlan0: AP-DISABLED Sep 09 22:38:23 volumio-goco hostapd[40525]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:38:23 volumio-goco hostapd[40525]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:38:23 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:38:23 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:38:23 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:38:23 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:38:23 volumio-goco dnsmasq[40531]: started, version 2.90 cachesize 150 Sep 09 22:38:23 volumio-goco dnsmasq[40531]: 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:38:23 volumio-goco dnsmasq-dhcp[40531]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:38:23 volumio-goco dnsmasq[40531]: reading /etc/resolv.conf Sep 09 22:38:23 volumio-goco dnsmasq[40531]: using nameserver 192.168.3.1#53 Sep 09 22:38:23 volumio-goco dnsmasq[40531]: using nameserver 91.102.231.241#53 Sep 09 22:38:23 volumio-goco dnsmasq[40531]: using nameserver 91.102.231.242#53 Sep 09 22:38:23 volumio-goco dnsmasq[40531]: using nameserver 208.67.222.222#53 Sep 09 22:38:23 volumio-goco dnsmasq[40531]: using nameserver 208.67.220.220#53 Sep 09 22:38:23 volumio-goco dnsmasq[40531]: read /etc/hosts - 4 names Sep 09 22:38:23 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:38:24 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 09 22:38:24 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 09 22:38:25 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 09 22:38:25 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 09 22:38:25 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2438. Sep 09 22:38:25 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:38:25 volumio-goco dnsmasq[40531]: exiting on receipt of SIGTERM Sep 09 22:38:25 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:38:25 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:38:25 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:38:25 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:38:25 volumio-goco hostapd[40537]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:38:25 volumio-goco hostapd[40537]: nl80211: Could not configure driver mode Sep 09 22:38:25 volumio-goco hostapd[40537]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:38:25 volumio-goco hostapd[40537]: nl80211 driver initialization failed. Sep 09 22:38:25 volumio-goco hostapd[40537]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:38:25 volumio-goco hostapd[40537]: wlan0: AP-DISABLED Sep 09 22:38:25 volumio-goco hostapd[40537]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:38:25 volumio-goco hostapd[40537]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:38:25 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:38:25 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:38:25 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:38:25 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:38:26 volumio-goco dnsmasq[40542]: started, version 2.90 cachesize 150 Sep 09 22:38:26 volumio-goco dnsmasq[40542]: 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:38:26 volumio-goco dnsmasq-dhcp[40542]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:38:26 volumio-goco dnsmasq[40542]: reading /etc/resolv.conf Sep 09 22:38:26 volumio-goco dnsmasq[40542]: using nameserver 192.168.3.1#53 Sep 09 22:38:26 volumio-goco dnsmasq[40542]: using nameserver 91.102.231.241#53 Sep 09 22:38:26 volumio-goco dnsmasq[40542]: using nameserver 91.102.231.242#53 Sep 09 22:38:26 volumio-goco dnsmasq[40542]: using nameserver 208.67.222.222#53 Sep 09 22:38:26 volumio-goco dnsmasq[40542]: using nameserver 208.67.220.220#53 Sep 09 22:38:26 volumio-goco dnsmasq[40542]: read /etc/hosts - 4 names Sep 09 22:38:26 volumio-goco volumio[40037]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 09 22:38:26 volumio-goco volumio[40037]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 09 22:38:26 volumio-goco volumio[40037]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 09 22:38:26 volumio-goco volumio[40037]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 09 22:38:26 volumio-goco volumio[40037]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 09 22:38:26 volumio-goco volumio[40037]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 09 22:38:26 volumio-goco volumio[40037]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 09 22:38:26 volumio-goco volumio[40037]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 09 22:38:26 volumio-goco volumio[40037]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 09 22:38:26 volumio-goco volumio[40037]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 09 22:38:26 volumio-goco volumio[40037]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Sep 09 22:38:26 volumio-goco volumio[40037]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 09 22:38:26 volumio-goco volumio[40037]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 09 22:38:26 volumio-goco volumio[40037]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 09 22:38:26 volumio-goco volumio[40037]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 09 22:38:26 volumio-goco volumio[40037]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 09 22:38:26 volumio-goco volumio[40037]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 09 22:38:26 volumio-goco volumio[40037]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 09 22:38:26 volumio-goco volumio[40037]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 09 22:38:26 volumio-goco volumio[40037]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 09 22:38:26 volumio-goco volumio[40037]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 09 22:38:26 volumio-goco volumio[40037]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Sep 09 22:38:26 volumio-goco volumio[40037]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 09 22:38:26 volumio-goco volumio[40037]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 09 22:38:26 volumio-goco volumio[40037]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 09 22:38:26 volumio-goco volumio[40037]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 09 22:38:26 volumio-goco volumio[40037]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 09 22:38:26 volumio-goco volumio[40037]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 09 22:38:26 volumio-goco volumio[40037]: info: Adding plugin bluetooth to MyMusic Plugins Sep 09 22:38:26 volumio-goco volumio[40037]: info: Adding plugin multiroom to MyMusic Plugins Sep 09 22:38:26 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:38:26 volumio-goco volumio[40037]: info: Adding plugin metavolumio to MyMusic Plugins Sep 09 22:38:26 volumio-goco volumio[40037]: info: Adding plugin cd_controller to MyMusic Plugins Sep 09 22:38:26 volumio-goco volumio[40037]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 09 22:38:26 volumio-goco volumio[40037]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 09 22:38:26 volumio-goco volumio[40037]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 09 22:38:27 volumio-goco volumio[40037]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 09 22:38:27 volumio-goco volumio[40037]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 09 22:38:27 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 22:38:27 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 22:38:27 volumio-goco volumio[40037]: info: Starting MyVolumio Remote Streaming Endpoints Sep 09 22:38:27 volumio-goco volumio[40037]: info: MyVolumio login type: Token Sep 09 22:38:27 volumio-goco volumio[40037]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 09 22:38:27 volumio-goco volumio[40037]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 09 22:38:27 volumio-goco volumio[40037]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 09 22:38:27 volumio-goco volumio[40037]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 09 22:38:27 volumio-goco volumio[40037]: info: Streaming services startup Sep 09 22:38:27 volumio-goco volumio[40037]: info: Starting Streaming Daemon Sep 09 22:38:28 volumio-goco volumio[40037]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 09 22:38:28 volumio-goco sudo[40549]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 09 22:38:28 volumio-goco sudo[40549]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 09 22:38:28 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2439. Sep 09 22:38:28 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:38:28 volumio-goco sudo[40549]: pam_unix(sudo:session): session closed for user root Sep 09 22:38:28 volumio-goco dnsmasq[40542]: exiting on receipt of SIGTERM Sep 09 22:38:28 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 09 22:38:28 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 09 22:38:28 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 09 22:38:28 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 09 22:38:28 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:38:28 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:38:28 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:38:28 volumio-goco volumio[40037]: error: Cannot start Volumio Streaming Daemon Sep 09 22:38:28 volumio-goco volumio[40037]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 09 22:38:28 volumio-goco volumio[40037]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 09 22:38:28 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:38:28 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 09 22:38:28 volumio-goco hostapd[40556]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:38:28 volumio-goco hostapd[40556]: nl80211: Could not configure driver mode Sep 09 22:38:28 volumio-goco hostapd[40556]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:38:28 volumio-goco hostapd[40556]: nl80211 driver initialization failed. Sep 09 22:38:28 volumio-goco hostapd[40556]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:38:28 volumio-goco hostapd[40556]: wlan0: AP-DISABLED Sep 09 22:38:28 volumio-goco hostapd[40556]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:38:28 volumio-goco hostapd[40556]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:38:28 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:38:28 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:38:28 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:38:28 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:38:28 volumio-goco dnsmasq[40561]: started, version 2.90 cachesize 150 Sep 09 22:38:28 volumio-goco dnsmasq[40561]: 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:38:28 volumio-goco dnsmasq-dhcp[40561]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:38:28 volumio-goco dnsmasq[40561]: reading /etc/resolv.conf Sep 09 22:38:28 volumio-goco dnsmasq[40561]: using nameserver 192.168.3.1#53 Sep 09 22:38:28 volumio-goco dnsmasq[40561]: using nameserver 91.102.231.241#53 Sep 09 22:38:28 volumio-goco dnsmasq[40561]: using nameserver 91.102.231.242#53 Sep 09 22:38:28 volumio-goco dnsmasq[40561]: using nameserver 208.67.222.222#53 Sep 09 22:38:28 volumio-goco dnsmasq[40561]: using nameserver 208.67.220.220#53 Sep 09 22:38:28 volumio-goco dnsmasq[40561]: read /etc/hosts - 4 names Sep 09 22:38:28 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:38:28 volumio-goco volumio[40037]: error: MyVolumio Custom Token format not valid, refreshing it Sep 09 22:38:30 volumio-goco volumio[40037]: info: MyVolumio login type: Token Sep 09 22:38:30 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2440. Sep 09 22:38:30 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:38:30 volumio-goco dnsmasq[40561]: exiting on receipt of SIGTERM Sep 09 22:38:30 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:38:30 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:38:30 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:38:30 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:38:30 volumio-goco hostapd[40567]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:38:30 volumio-goco hostapd[40567]: nl80211: Could not configure driver mode Sep 09 22:38:30 volumio-goco hostapd[40567]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:38:30 volumio-goco hostapd[40567]: nl80211 driver initialization failed. Sep 09 22:38:30 volumio-goco hostapd[40567]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:38:30 volumio-goco hostapd[40567]: wlan0: AP-DISABLED Sep 09 22:38:30 volumio-goco hostapd[40567]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:38:30 volumio-goco hostapd[40567]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:38:30 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:38:30 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:38:30 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:38:30 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:38:30 volumio-goco dnsmasq[40572]: started, version 2.90 cachesize 150 Sep 09 22:38:30 volumio-goco dnsmasq[40572]: 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:38:30 volumio-goco dnsmasq-dhcp[40572]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:38:30 volumio-goco dnsmasq[40572]: reading /etc/resolv.conf Sep 09 22:38:30 volumio-goco dnsmasq[40572]: using nameserver 192.168.3.1#53 Sep 09 22:38:30 volumio-goco dnsmasq[40572]: using nameserver 91.102.231.241#53 Sep 09 22:38:30 volumio-goco dnsmasq[40572]: using nameserver 91.102.231.242#53 Sep 09 22:38:30 volumio-goco dnsmasq[40572]: using nameserver 208.67.222.222#53 Sep 09 22:38:30 volumio-goco dnsmasq[40572]: using nameserver 208.67.220.220#53 Sep 09 22:38:30 volumio-goco dnsmasq[40572]: read /etc/hosts - 4 names Sep 09 22:38:30 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:38:30 volumio-goco volumio[40037]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 09 22:38:31 volumio-goco volumio[40037]: info: MyVolumio token set successfully Sep 09 22:38:31 volumio-goco volumio[40037]: info: MYVOLUMIO: Adding device Sep 09 22:38:31 volumio-goco volumio[40037]: info: MYVOLUMIO: Evaluating Server Sep 09 22:38:31 volumio-goco volumio[40037]: info: MyVolumio status changed Sep 09 22:38:31 volumio-goco volumio[40037]: info: Streaming services startup Sep 09 22:38:31 volumio-goco volumio[40037]: info: Starting Streaming Daemon Sep 09 22:38:31 volumio-goco volumio[40037]: info: Removing browser output: myVolumio user plan is not superstar Sep 09 22:38:31 volumio-goco volumio[40037]: info: Removing audio output: Sep 09 22:38:31 volumio-goco volumio[40037]: info: Stoppping Tunnel 1 Sep 09 22:38:31 volumio-goco sudo[40594]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 09 22:38:31 volumio-goco sudo[40594]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 09 22:38:31 volumio-goco sudo[40596]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Sep 09 22:38:31 volumio-goco sudo[40596]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Sep 09 22:38:31 volumio-goco sudo[40594]: pam_unix(sudo:session): session closed for user root Sep 09 22:38:31 volumio-goco volumio[40037]: error: Cannot start Volumio Streaming Daemon Sep 09 22:38:31 volumio-goco volumio[40037]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 09 22:38:31 volumio-goco volumio[40037]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 09 22:38:31 volumio-goco systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 09 22:38:31 volumio-goco systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 09 22:38:31 volumio-goco systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 09 22:38:31 volumio-goco systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 09 22:38:31 volumio-goco systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 09 22:38:31 volumio-goco systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 09 22:38:31 volumio-goco systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 09 22:38:31 volumio-goco systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Sep 09 22:38:31 volumio-goco sudo[40596]: pam_unix(sudo:session): session closed for user root Sep 09 22:38:31 volumio-goco volumio[40037]: info: Remote SSH Stopped Sep 09 22:38:31 volumio-goco volumio[40037]: info: Setting Geolocation for MyVolumio to eu10 Sep 09 22:38:31 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 22:38:31 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 22:38:31 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 22:38:32 volumio-goco systemd[1]: hostapd.service: Scheduled restart job, restart counter is at 2441. Sep 09 22:38:32 volumio-goco systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:38:32 volumio-goco dnsmasq[40572]: exiting on receipt of SIGTERM Sep 09 22:38:32 volumio-goco systemd[1]: dnsmasq.service: Deactivated successfully. Sep 09 22:38:32 volumio-goco systemd[1]: Stopped dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:38:32 volumio-goco systemd[1]: Stopped hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:38:32 volumio-goco systemd[1]: Starting hostapd.service - Access point and authentication server for Wi-Fi and Ethernet... Sep 09 22:38:32 volumio-goco hostapd[40601]: Could not set interface wlan0 flags (UP): Operation not possible due to RF-kill Sep 09 22:38:32 volumio-goco hostapd[40601]: nl80211: Could not configure driver mode Sep 09 22:38:32 volumio-goco hostapd[40601]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Sep 09 22:38:32 volumio-goco hostapd[40601]: nl80211 driver initialization failed. Sep 09 22:38:32 volumio-goco hostapd[40601]: wlan0: interface state UNINITIALIZED->DISABLED Sep 09 22:38:32 volumio-goco hostapd[40601]: wlan0: AP-DISABLED Sep 09 22:38:32 volumio-goco hostapd[40601]: wlan0: CTRL-EVENT-TERMINATING Sep 09 22:38:32 volumio-goco hostapd[40601]: hostapd_free_hapd_data: Interface wlan0 wasn't started Sep 09 22:38:32 volumio-goco systemd[1]: hostapd.service: Control process exited, code=exited, status=1/FAILURE Sep 09 22:38:32 volumio-goco systemd[1]: hostapd.service: Failed with result 'exit-code'. Sep 09 22:38:32 volumio-goco systemd[1]: Failed to start hostapd.service - Access point and authentication server for Wi-Fi and Ethernet. Sep 09 22:38:32 volumio-goco systemd[1]: Starting dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Sep 09 22:38:32 volumio-goco volumio[40037]: info: Updating MyVolumio device info Sep 09 22:38:32 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 22:38:32 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 22:38:32 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 09 22:38:32 volumio-goco dnsmasq[40606]: started, version 2.90 cachesize 150 Sep 09 22:38:32 volumio-goco dnsmasq[40606]: 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:38:32 volumio-goco dnsmasq-dhcp[40606]: DHCP, IP range 192.168.211.100 -- 192.168.211.200, lease time 1d Sep 09 22:38:32 volumio-goco dnsmasq[40606]: reading /etc/resolv.conf Sep 09 22:38:32 volumio-goco dnsmasq[40606]: using nameserver 192.168.3.1#53 Sep 09 22:38:32 volumio-goco dnsmasq[40606]: using nameserver 91.102.231.241#53 Sep 09 22:38:32 volumio-goco dnsmasq[40606]: using nameserver 91.102.231.242#53 Sep 09 22:38:32 volumio-goco dnsmasq[40606]: using nameserver 208.67.222.222#53 Sep 09 22:38:32 volumio-goco dnsmasq[40606]: using nameserver 208.67.220.220#53 Sep 09 22:38:32 volumio-goco dnsmasq[40606]: read /etc/hosts - 4 names Sep 09 22:38:32 volumio-goco systemd[1]: Started dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server. Sep 09 22:38:32 volumio-goco volumio[40037]: Searching plugin music_service/webradio Sep 09 22:38:32 volumio-goco volumio[40037]: info: CoreCommandRouter::executeOnPlugin: webradio , search Sep 09 22:38:33 volumio-goco volumio[40037]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 09 22:38:33 volumio-goco volumio[40037]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 09 22:38:33 volumio-goco volumio[40037]: Use XMLElement.setAttribute instead Sep 09 22:38:33 volumio-goco volumio[40037]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 09 22:38:33 volumio-goco volumio[40037]: TypeError: children[i].attr(...).value is not a function Sep 09 22:38:33 volumio-goco volumio[40037]: at /volumio/app/plugins/music_service/webradio/index.js:956:46 Sep 09 22:38:33 volumio-goco volumio[40037]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Sep 09 22:38:33 volumio-goco volumio[40037]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 09 22:38:33 volumio-goco sudo[40638]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-09-09 22:37' Sep 09 22:38:33 volumio-goco sudo[40638]: 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"