-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Tue 2025-03-11 15:39:58 CST. -- Mar 11 15:38:23 volumio volumio[1239]: info: CorePlayQueue::getTrack 1 Mar 11 15:38:23 volumio volumio[1239]: info: CorePlayQueue::getTrack 2 Mar 11 15:38:23 volumio volumio[1239]: info: Prefetching next song Mar 11 15:38:23 volumio volumio[1239]: info: Doing Prefetch in UPNP Mar 11 15:38:23 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.5:9790/minimserver/*/volume1/music/MQA/*5bMQA*5dKevin*20Kern*20*e7*b2*be*e9*80*89/*5bMQA*5d*20Kevin*20Kern*20-*20Enchanted*20Piano*c2*b7MQA/03*20-*20Imagination*27s*20Key.flac" Mar 11 15:38:23 volumio volumio[1239]: info: Mar 11 15:38:23 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Mar 11 15:38:23 volumio volumio[1239]: info: Ignoring MPD Status Update Mar 11 15:38:23 volumio volumio[1239]: info: sendMpdCommand add "http://192.168.0.5:9790/minimserver/*/volume1/music/MQA/*5bMQA*5dKevin*20Kern*20*e7*b2*be*e9*80*89/*5bMQA*5d*20Kevin*20Kern*20-*20Enchanted*20Piano*c2*b7MQA/03*20-*20Imagination*27s*20Key.flac" took 3 milliseconds Mar 11 15:38:23 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand consume 1 Mar 11 15:38:23 volumio volumio[1239]: info: Mar 11 15:38:23 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Mar 11 15:38:23 volumio volumio[1239]: info: Ignoring MPD Status Update Mar 11 15:38:23 volumio volumio[1239]: info: Mar 11 15:38:23 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Mar 11 15:38:23 volumio volumio[1239]: info: Ignoring MPD Status Update Mar 11 15:38:23 volumio volumio[1239]: info: ------------------------------ 7ms Mar 11 15:38:23 volumio volumio[1239]: info: sendMpdCommand consume 1 took 6 milliseconds Mar 11 15:38:23 volumio volumio[1239]: info: ------------------------------ 4ms Mar 11 15:38:23 volumio volumio[1239]: info: ------------------------------ 3ms Mar 11 15:38:28 volumio volumio[1239]: info: CoreStateMachine::startPlaybackTimer Mar 11 15:38:28 volumio volumio[1239]: info: CorePlayQueue::getTrack 2 Mar 11 15:38:28 volumio volumio[1239]: info: CoreStateMachine::pushState Mar 11 15:38:28 volumio volumio[1239]: info: CorePlayQueue::getTrack 2 Mar 11 15:38:28 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 11 15:38:28 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Mar 11 15:38:28 volumio volumio[1239]: info: CoreCommandRouter::volumioGetQueue Mar 11 15:38:28 volumio volumio[1239]: info: CoreStateMachine::getQueue Mar 11 15:38:28 volumio volumio[1239]: info: CorePlayQueue::getQueue Mar 11 15:38:29 volumio volumio[1239]: info: Mar 11 15:38:29 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Mar 11 15:38:29 volumio volumio[1239]: info: Ignoring MPD Status Update Mar 11 15:38:29 volumio volumio[1239]: info: Mar 11 15:38:29 volumio volumio[1239]: ---------------------------- MPD announces state update: player Mar 11 15:38:29 volumio volumio[1239]: info: ControllerMpd::getState Mar 11 15:38:29 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Mar 11 15:38:29 volumio volumio[1239]: info: Mar 11 15:38:29 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Mar 11 15:38:29 volumio volumio[1239]: info: Ignoring MPD Status Update Mar 11 15:38:29 volumio volumio[1239]: info: Mar 11 15:38:29 volumio volumio[1239]: ---------------------------- MPD announces state update: player Mar 11 15:38:29 volumio volumio[1239]: info: ControllerMpd::getState Mar 11 15:38:29 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Mar 11 15:38:29 volumio volumio[1239]: info: Mar 11 15:38:29 volumio volumio[1239]: ---------------------------- MPD announces system playlist update Mar 11 15:38:29 volumio volumio[1239]: info: Ignoring MPD Status Update Mar 11 15:38:29 volumio volumio[1239]: info: Mar 11 15:38:29 volumio volumio[1239]: ---------------------------- MPD announces state update: player Mar 11 15:38:29 volumio volumio[1239]: info: ControllerMpd::getState Mar 11 15:38:29 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand status Mar 11 15:38:29 volumio volumio[1239]: info: ------------------------------ 9ms Mar 11 15:38:29 volumio volumio[1239]: info: sendMpdCommand status took 8 milliseconds Mar 11 15:38:29 volumio volumio[1239]: info: ------------------------------ 7ms Mar 11 15:38:29 volumio volumio[1239]: info: sendMpdCommand status took 7 milliseconds Mar 11 15:38:29 volumio volumio[1239]: info: ------------------------------ 5ms Mar 11 15:38:29 volumio volumio[1239]: info: sendMpdCommand status took 4 milliseconds Mar 11 15:38:29 volumio volumio[1239]: verbose: ControllerMpd::parseState Mar 11 15:38:29 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 11 15:38:29 volumio volumio[1239]: verbose: ControllerMpd::parseState Mar 11 15:38:29 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 11 15:38:29 volumio volumio[1239]: verbose: ControllerMpd::parseState Mar 11 15:38:29 volumio volumio[1239]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 11 15:38:29 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 11 15:38:29 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Mar 11 15:38:29 volumio volumio[1239]: info: ControllerMpd::pushState Mar 11 15:38:29 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Mar 11 15:38:29 volumio volumio[1239]: info: CorePlayQueue::getTrack 2 Mar 11 15:38:29 volumio volumio[1239]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":202,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"526 Kbps","isStreaming":false,"title":"Imagination's Key","artist":"Kevin Kern","album":"Enchanted Piano","uri":"http://192.168.0.5:9790/minimserver/*/volume1/music/MQA/*5bMQA*5dKevin*20Kern*20*e7*b2*be*e9*80*89/*5bMQA*5d*20Kevin*20Kern*20-*20Enchanted*20Piano*c2*b7MQA/03*20-*20Imagination*27s*20Key.flac","trackType":"flac"} Mar 11 15:38:29 volumio volumio[1239]: verbose: CURRENT POSITION 2 Mar 11 15:38:29 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Mar 11 15:38:29 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Mar 11 15:38:29 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Mar 11 15:38:29 volumio volumio[1239]: info: CoreStateMachine::pushState Mar 11 15:38:29 volumio volumio[1239]: info: CorePlayQueue::getTrack 2 Mar 11 15:38:29 volumio volumio[1239]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 11 15:38:29 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Mar 11 15:38:29 volumio volumio[1239]: info: CoreStateMachine::pushState Mar 11 15:38:29 volumio volumio[1239]: info: CorePlayQueue::getTrack 2 Mar 11 15:38:29 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Mar 11 15:38:29 volumio volumio[1239]: info: ------------------------------ 38ms Mar 11 15:38:29 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 29 milliseconds Mar 11 15:38:29 volumio volumio[1239]: info: sendMpdCommand playlistinfo took 29 milliseconds Mar 11 15:38:29 volumio volumio[1239]: verbose: ControllerMpd::parseTrackInfo Mar 11 15:38:29 volumio volumio[1239]: info: ControllerMpd::pushState Mar 11 15:38:29 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Mar 11 15:38:29 volumio volumio[1239]: info: CorePlayQueue::getTrack 2 Mar 11 15:38:29 volumio volumio[1239]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":202,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"526 Kbps","isStreaming":false,"title":"Imagination's Key","artist":"Kevin Kern","album":"Enchanted Piano","uri":"http://192.168.0.5:9790/minimserver/*/volume1/music/MQA/*5bMQA*5dKevin*20Kern*20*e7*b2*be*e9*80*89/*5bMQA*5d*20Kevin*20Kern*20-*20Enchanted*20Piano*c2*b7MQA/03*20-*20Imagination*27s*20Key.flac","trackType":"flac"} Mar 11 15:38:29 volumio volumio[1239]: verbose: CURRENT POSITION 2 Mar 11 15:38:29 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Mar 11 15:38:29 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Mar 11 15:38:29 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Mar 11 15:38:29 volumio volumio[1239]: info: CoreStateMachine::pushState Mar 11 15:38:29 volumio volumio[1239]: info: CorePlayQueue::getTrack 2 Mar 11 15:38:29 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Mar 11 15:38:29 volumio volumio[1239]: info: CoreStateMachine::pushState Mar 11 15:38:29 volumio volumio[1239]: info: CorePlayQueue::getTrack 2 Mar 11 15:38:29 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Mar 11 15:38:29 volumio volumio[1239]: info: ControllerMpd::pushState Mar 11 15:38:29 volumio volumio[1239]: info: CoreCommandRouter::servicePushState Mar 11 15:38:29 volumio volumio[1239]: info: CorePlayQueue::getTrack 2 Mar 11 15:38:29 volumio volumio[1239]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":202,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"526 Kbps","isStreaming":false,"title":"Imagination's Key","artist":"Kevin Kern","album":"Enchanted Piano","uri":"http://192.168.0.5:9790/minimserver/*/volume1/music/MQA/*5bMQA*5dKevin*20Kern*20*e7*b2*be*e9*80*89/*5bMQA*5d*20Kevin*20Kern*20-*20Enchanted*20Piano*c2*b7MQA/03*20-*20Imagination*27s*20Key.flac","trackType":"flac"} Mar 11 15:38:29 volumio volumio[1239]: verbose: CURRENT POSITION 2 Mar 11 15:38:29 volumio volumio[1239]: info: CoreStateMachine::syncState stateService play Mar 11 15:38:29 volumio volumio[1239]: info: CoreStateMachine::syncState currentStatus play Mar 11 15:38:29 volumio volumio[1239]: info: Received an update from plugin. extracting info from payload Mar 11 15:38:29 volumio volumio[1239]: info: CoreStateMachine::pushState Mar 11 15:38:29 volumio volumio[1239]: info: CorePlayQueue::getTrack 2 Mar 11 15:38:29 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Mar 11 15:38:29 volumio volumio[1239]: info: CoreStateMachine::pushState Mar 11 15:38:29 volumio volumio[1239]: info: CorePlayQueue::getTrack 2 Mar 11 15:38:29 volumio volumio[1239]: info: CoreCommandRouter::volumioPushState Mar 11 15:38:29 volumio volumio[1239]: info: ------------------------------ 68ms Mar 11 15:38:29 volumio volumio[1239]: info: ------------------------------ 67ms Mar 11 15:38:29 volumio volumio[1239]: info: CoreCommandRouter::volumioGetQueue Mar 11 15:38:29 volumio volumio[1239]: info: CoreStateMachine::getQueue Mar 11 15:38:29 volumio volumio[1239]: info: CorePlayQueue::getQueue Mar 11 15:38:29 volumio volumio[1239]: info: CoreCommandRouter::volumioGetQueue Mar 11 15:38:29 volumio volumio[1239]: info: CoreStateMachine::getQueue Mar 11 15:38:29 volumio volumio[1239]: info: CorePlayQueue::getQueue Mar 11 15:38:29 volumio volumio[1239]: info: CoreCommandRouter::volumioGetQueue Mar 11 15:38:29 volumio volumio[1239]: info: CoreStateMachine::getQueue Mar 11 15:38:29 volumio volumio[1239]: info: CorePlayQueue::getQueue Mar 11 15:38:29 volumio volumio[1239]: info: CoreCommandRouter::volumioGetQueue Mar 11 15:38:29 volumio volumio[1239]: info: CoreStateMachine::getQueue Mar 11 15:38:29 volumio volumio[1239]: info: CorePlayQueue::getQueue Mar 11 15:38:29 volumio volumio[1239]: info: CoreCommandRouter::volumioGetQueue Mar 11 15:38:29 volumio volumio[1239]: info: CoreStateMachine::getQueue Mar 11 15:38:29 volumio volumio[1239]: info: CorePlayQueue::getQueue Mar 11 15:38:29 volumio volumio[1239]: info: CoreCommandRouter::volumioGetQueue Mar 11 15:38:29 volumio volumio[1239]: info: CoreStateMachine::getQueue Mar 11 15:38:29 volumio volumio[1239]: info: CorePlayQueue::getQueue Mar 11 15:38:56 volumio wpa_supplicant[892]: wlan0: CTRL-EVENT-DISCONNECTED bssid=c0:56:27:b7:b8:09 reason=7 Mar 11 15:38:56 volumio dhcpcd[1007]: wlan0: carrier lost Mar 11 15:38:56 volumio wpa_supplicant[892]: wlan0: Trying to associate with SSID 'Linksys_5GHZ' Mar 11 15:38:56 volumio wpa_supplicant[892]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Mar 11 15:38:56 volumio wpa_supplicant[892]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Mar 11 15:38:56 volumio wpa_supplicant[892]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=CN Mar 11 15:38:56 volumio avahi-daemon[625]: Withdrawing address record for 192.168.0.25 on wlan0. Mar 11 15:38:56 volumio avahi-daemon[625]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.25. Mar 11 15:38:56 volumio dhcpcd[1007]: wlan0: deleting route to 192.168.0.0/24 Mar 11 15:38:56 volumio dhcpcd[1007]: wlan0: deleting default route via 192.168.0.1 Mar 11 15:38:56 volumio avahi-daemon[625]: Interface wlan0.IPv4 no longer relevant for mDNS. Mar 11 15:38:56 volumio volumio[1239]: info: Discovery: A device disappeared from network Mar 11 15:38:56 volumio volumio[1239]: info: Discovery: Device volumio disappeared from network Mar 11 15:38:56 volumio volumio[1239]: info: Volumio Network Manager: Network status updated: 1 Mar 11 15:38:59 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Mar 11 15:38:59 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Mar 11 15:38:59 volumio ntpd[807]: Deleting interface #4 wlan0, 192.168.0.25#123, interface stats: received=0, sent=0, dropped=0, active_time=1290 secs Mar 11 15:38:59 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Mar 11 15:38:59 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Mar 11 15:38:59 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd034 fail, reason -52 Mar 11 15:38:59 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd038 fail, reason -52 Mar 11 15:38:59 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd03c fail, reason -52 Mar 11 15:38:59 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd040 fail, reason -52 Mar 11 15:39:00 volumio wpa_supplicant[892]: wlan0: Trying to associate with SSID 'Linksys_2.4GHZ' Mar 11 15:39:00 volumio wpa_supplicant[892]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Mar 11 15:39:02 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Mar 11 15:39:03 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Mar 11 15:39:03 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Mar 11 15:39:03 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Mar 11 15:39:03 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd034 fail, reason -52 Mar 11 15:39:03 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd038 fail, reason -52 Mar 11 15:39:03 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd03c fail, reason -52 Mar 11 15:39:03 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd040 fail, reason -52 Mar 11 15:39:03 volumio wpa_supplicant[892]: wlan0: Trying to associate with SSID 'Linksys_2.4GHZ' Mar 11 15:39:04 volumio wpa_supplicant[892]: wlan0: Associated with c0:56:27:b7:b8:08 Mar 11 15:39:04 volumio wpa_supplicant[892]: wlan0: CTRL-EVENT-CONNECTED - Connection to c0:56:27:b7:b8:08 completed [id=3 id_str=] Mar 11 15:39:04 volumio dhcpcd[1007]: wlan0: carrier acquired Mar 11 15:39:04 volumio wpa_supplicant[892]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Mar 11 15:39:04 volumio dhcpcd[1007]: wlan0: connected to Access Point `Linksys_2.4GHZ' Mar 11 15:39:04 volumio dhcpcd[1007]: wlan0: IAID dd:93:5f:be Mar 11 15:39:04 volumio dhcpcd[1007]: wlan0: soliciting an IPv6 router Mar 11 15:39:04 volumio dhcpcd[1007]: wlan0: soliciting a DHCP lease Mar 11 15:39:09 volumio dhcpcd[1007]: wlan0: probing for an IPv4LL address Mar 11 15:39:12 volumio wpa_supplicant[892]: wlan0: CTRL-EVENT-DISCONNECTED bssid=c0:56:27:b7:b8:08 reason=15 Mar 11 15:39:12 volumio dhcpcd[1007]: wlan0: carrier lost Mar 11 15:39:12 volumio wpa_supplicant[892]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Mar 11 15:39:12 volumio wpa_supplicant[892]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=CN Mar 11 15:39:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Mar 11 15:39:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Mar 11 15:39:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Mar 11 15:39:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Mar 11 15:39:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd034 fail, reason -52 Mar 11 15:39:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd038 fail, reason -52 Mar 11 15:39:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd03c fail, reason -52 Mar 11 15:39:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd040 fail, reason -52 Mar 11 15:39:15 volumio wpa_supplicant[892]: wlan0: Trying to associate with SSID 'Linksys_5GHZ' Mar 11 15:39:15 volumio wpa_supplicant[892]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Mar 11 15:39:18 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Mar 11 15:39:18 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Mar 11 15:39:18 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Mar 11 15:39:18 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Mar 11 15:39:18 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd034 fail, reason -52 Mar 11 15:39:18 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd038 fail, reason -52 Mar 11 15:39:18 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd03c fail, reason -52 Mar 11 15:39:18 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd040 fail, reason -52 Mar 11 15:39:19 volumio wpa_supplicant[892]: wlan0: Trying to associate with SSID 'Linksys_5GHZ' Mar 11 15:39:20 volumio wpa_supplicant[892]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Mar 11 15:39:23 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Mar 11 15:39:23 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Mar 11 15:39:23 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Mar 11 15:39:24 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Mar 11 15:39:24 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd034 fail, reason -52 Mar 11 15:39:24 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd038 fail, reason -52 Mar 11 15:39:24 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd03c fail, reason -52 Mar 11 15:39:24 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd040 fail, reason -52 Mar 11 15:39:24 volumio wpa_supplicant[892]: wlan0: Trying to associate with SSID 'Linksys_2.4GHZ' Mar 11 15:39:24 volumio wpa_supplicant[892]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Mar 11 15:39:27 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Mar 11 15:39:27 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Mar 11 15:39:27 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Mar 11 15:39:28 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Mar 11 15:39:28 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd034 fail, reason -52 Mar 11 15:39:28 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd038 fail, reason -52 Mar 11 15:39:28 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd03c fail, reason -52 Mar 11 15:39:28 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd040 fail, reason -52 Mar 11 15:39:28 volumio wpa_supplicant[892]: wlan0: Trying to associate with SSID 'Linksys_5GHZ' Mar 11 15:39:29 volumio wpa_supplicant[892]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Mar 11 15:39:32 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Mar 11 15:39:32 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Mar 11 15:39:32 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Mar 11 15:39:32 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Mar 11 15:39:32 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd034 fail, reason -52 Mar 11 15:39:32 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd038 fail, reason -52 Mar 11 15:39:32 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd03c fail, reason -52 Mar 11 15:39:32 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd040 fail, reason -52 Mar 11 15:39:33 volumio wpa_supplicant[892]: wlan0: Trying to associate with SSID 'Linksys_5GHZ' Mar 11 15:39:35 volumio wpa_supplicant[892]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Mar 11 15:39:35 volumio wpa_supplicant[892]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=1 ssid="Linksys_5GHZ" auth_failures=1 duration=10 reason=CONN_FAILED Mar 11 15:39:42 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Mar 11 15:39:42 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Mar 11 15:39:42 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Mar 11 15:39:43 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Mar 11 15:39:43 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd034 fail, reason -52 Mar 11 15:39:43 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd038 fail, reason -52 Mar 11 15:39:43 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd03c fail, reason -52 Mar 11 15:39:43 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd040 fail, reason -52 Mar 11 15:39:43 volumio wpa_supplicant[892]: wlan0: Trying to associate with SSID 'Linksys_2.4GHZ' Mar 11 15:39:44 volumio wpa_supplicant[892]: wlan0: Associated with c0:56:27:b7:b8:08 Mar 11 15:39:44 volumio wpa_supplicant[892]: wlan0: CTRL-EVENT-CONNECTED - Connection to c0:56:27:b7:b8:08 completed [id=3 id_str=] Mar 11 15:39:44 volumio dhcpcd[1007]: wlan0: carrier acquired Mar 11 15:39:44 volumio wpa_supplicant[892]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Mar 11 15:39:44 volumio dhcpcd[1007]: wlan0: IAID dd:93:5f:be Mar 11 15:39:45 volumio dhcpcd[1007]: wlan0: soliciting a DHCP lease Mar 11 15:39:45 volumio dhcpcd[1007]: wlan0: soliciting an IPv6 router Mar 11 15:39:50 volumio dhcpcd[1007]: wlan0: probing for an IPv4LL address Mar 11 15:39:52 volumio wpa_supplicant[892]: wlan0: CTRL-EVENT-DISCONNECTED bssid=c0:56:27:b7:b8:08 reason=15 Mar 11 15:39:52 volumio dhcpcd[1007]: wlan0: carrier lost Mar 11 15:39:52 volumio wpa_supplicant[892]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Mar 11 15:39:52 volumio wpa_supplicant[892]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=CN Mar 11 15:39:55 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Mar 11 15:39:55 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Mar 11 15:39:55 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Mar 11 15:39:55 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Mar 11 15:39:55 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd034 fail, reason -52 Mar 11 15:39:55 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd038 fail, reason -52 Mar 11 15:39:55 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd03c fail, reason -52 Mar 11 15:39:55 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd040 fail, reason -52 Mar 11 15:39:56 volumio wpa_supplicant[892]: wlan0: CTRL-EVENT-SSID-REENABLED id=1 ssid="Linksys_5GHZ" Mar 11 15:39:56 volumio wpa_supplicant[892]: wlan0: Trying to associate with SSID 'Linksys_5GHZ' Mar 11 15:39:56 volumio wpa_supplicant[892]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Mar 11 15:39:57 volumio volumio[1239]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 11 15:39:57 volumio volumio[1239]: Error: read ETIMEDOUT Mar 11 15:39:57 volumio volumio[1239]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:209:20) { Mar 11 15:39:57 volumio volumio[1239]: errno: -110, Mar 11 15:39:57 volumio volumio[1239]: code: 'ETIMEDOUT', Mar 11 15:39:57 volumio volumio[1239]: syscall: 'read' Mar 11 15:39:57 volumio volumio[1239]: } Mar 11 15:39:57 volumio volumio[1239]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 11 15:39:58 volumio sudo[7197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-11 15:38 Mar 11 15:39:58 volumio sudo[7197]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"