From owner-freebsd-wireless@FreeBSD.ORG Fri Feb 28 23:07:19 2014 Return-Path: Delivered-To: freebsd-wireless@freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2001:1900:2254:206a::19:1]) (using TLSv1 with cipher ADH-AES256-SHA (256/256 bits)) (No client certificate requested) by hub.freebsd.org (Postfix) with ESMTPS id CB2BEFE7; Fri, 28 Feb 2014 23:07:19 +0000 (UTC) Received: from mx1.pertho.net (87-194-102-137.bethere.co.uk [87.194.102.137]) (using TLSv1.2 with cipher AECDH-AES256-SHA (256/256 bits)) (No client certificate requested) by mx1.freebsd.org (Postfix) with ESMTPS id 780A612EF; Fri, 28 Feb 2014 23:07:16 +0000 (UTC) Received: from syn.pertho.net (syn [10.0.29.1]) (using TLSv1.2 with cipher ECDHE-RSA-AES256-GCM-SHA384 (256/256 bits)) (No client certificate requested) (Authenticated sender: tom) by syn.pertho.net (Postfix) with ESMTPSA id 5501D1D449D; Fri, 28 Feb 2014 23:07:07 +0000 (GMT) Date: Fri, 28 Feb 2014 23:07:05 +0000 From: Tom Murphy To: Adrian Chadd Subject: Re: iwn(4) in -HEAD supporting Centrino Wireless-N 135 Message-ID: <20140228230705.GA28290@syn.pertho.net> References: <20140226123709.GA31099@syn.pertho.net> <530EE7F4.3080903@shurik.kiev.ua> <530EEEA5.4090306@shurik.kiev.ua> MIME-Version: 1.0 Content-Type: multipart/mixed; boundary="C7zPtVaVf+AK4Oqc" Content-Disposition: inline In-Reply-To: Cc: freebsd-current , "freebsd-wireless@freebsd.org" X-BeenThere: freebsd-wireless@freebsd.org X-Mailman-Version: 2.1.17 Precedence: list List-Id: "Discussions of 802.11 stack, tools device driver development." List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Fri, 28 Feb 2014 23:07:20 -0000 --C7zPtVaVf+AK4Oqc Content-Type: text/plain; charset=us-ascii Content-Disposition: inline I've attached my iwn debug messages to this email starting with the point I tried to associate to the Wifi. Thanks again for looking at this! Kind regards, Tom On Thu, Feb 27, 2014 at 12:13:51PM -0800, Adrian Chadd wrote: > On 26 February 2014 23:52, Alexandr wrote: > > Tom, could you: > > > > 1. compile kernel WITH_IWNDEBUG > > 2. sysctl dev.iwn.0.debug=0x1 > > 3. wlandebug -i wlan0 auth+assoc > > 4. Associate with AP in 11n mode > > 5. Send us appropriate /var/log/messages > > > > Then I try to compare it with my log. > > Please do. I've been trying to track down the source of this "ht just > doesn't work!" but it works fine with all of the Intel NICs I have > here. > > Can someone see if they can find a mtaching NIC online (amazon,ebay?) > Owning one that I can whack in a laptop is likely going ot help things > a lot. > > Thanks, > > > -a --C7zPtVaVf+AK4Oqc Content-Type: text/plain; charset=us-ascii Content-Disposition: attachment; filename="iwn_debug_messages.txt" Feb 28 22:55:20 kernel: wlan0: Ethernet address: 0c:d2:92:0e:aa:e2 Feb 28 22:55:20 wpa_supplicant[2424]: Successfully initialized wpa_supplicant Feb 28 22:55:20 kernel: iwn_notif_intr: scanning channel 1 status 1 Feb 28 22:55:20 kernel: iwn_notif_intr: scanning channel 6 status 1 Feb 28 22:55:20 kernel: iwn_notif_intr: scanning channel 11 status 1 Feb 28 22:55:20 kernel: iwn_notif_intr: scanning channel 7 status 1 Feb 28 22:55:20 wpa_supplicant[2423]: Successfully initialized wpa_supplicant Feb 28 22:55:20 wpa_supplicant[2426]: ioctl[SIOCS80211, op=103, val=0, arg_len=128]: Device not configured Feb 28 22:55:20 wpa_supplicant[2426]: wlan0: Failed to initiate AP scan Feb 28 22:55:20 kernel: iwn_notif_intr: scanning channel 1 status 1 Feb 28 22:55:20 kernel: iwn_notif_intr: scanning channel 6 status 1 Feb 28 22:55:20 kernel: iwn_notif_intr: scanning channel 11 status 1 Feb 28 22:55:20 kernel: iwn_notif_intr: scanning channel 7 status 1 Feb 28 22:55:20 kernel: iwn_notif_intr: scanning channel 13 status 1 Feb 28 22:55:20 kernel: iwn_notif_intr: scanning channel 2 status 1 Feb 28 22:55:20 kernel: iwn_notif_intr: scanning channel 3 status 1 Feb 28 22:55:20 kernel: iwn_notif_intr: scanning channel 4 status 1 Feb 28 22:55:20 kernel: iwn_notif_intr: scanning channel 5 status 1 Feb 28 22:55:20 kernel: iwn_notif_intr: scanning channel 8 status 1 Feb 28 22:55:20 kernel: iwn_notif_intr: scanning channel 9 status 1 Feb 28 22:55:20 kernel: iwn_notif_intr: scanning channel 10 status 1 Feb 28 22:55:20 kernel: iwn_notif_intr: scanning channel 12 status 1 Feb 28 22:55:20 wpa_supplicant[2426]: wlan0: Trying to associate with a0:f3:c1:35:a3:6c (SSID='pertho' freq=2462 MHz) Feb 28 22:55:20 wpa_supplicant[2425]: wlan0: Trying to associate with a0:f3:c1:35:a3:6c (SSID='pertho' freq=2462 MHz) Feb 28 22:55:21 kernel: iwn_tx_data_raw: qid 3 idx 0 len 6 nsegs 1 Feb 28 22:55:21 kernel: iwn_tx_data_raw: qid 3 idx 1 len 6 nsegs 1 Feb 28 22:55:21 kernel: iwn5000_tx_done: qid 3 idx 0 retries 0 nkill 0 rate 420a duration 778 status 201 Feb 28 22:55:21 kernel: iwn5000_tx_done: qid 3 idx 1 retries 0 nkill 0 rate 420a duration 778 status 201 Feb 28 22:55:21 kernel: iwn_tx_data_raw: qid 3 idx 2 len 87 nsegs 1 Feb 28 22:55:21 kernel: iwn5000_tx_done: qid 3 idx 2 retries 0 nkill 0 rate 420a duration 1426 status 201 Feb 28 22:55:21 kernel: iwn_set_link_quality: 1stream antenna=0x01, 2stream antenna=0x03, ntxstreams=1 Feb 28 22:55:21 kernel: iwn_set_link_quality: i=0, txrate=7, rate=0x87 Feb 28 22:55:21 kernel: iwn_set_link_quality: i=1, txrate=6, rate=0x86 Feb 28 22:55:21 kernel: iwn_set_link_quality: i=2, txrate=5, rate=0x85 Feb 28 22:55:21 kernel: iwn_set_link_quality: i=3, txrate=4, rate=0x84 Feb 28 22:55:21 kernel: iwn_set_link_quality: i=4, txrate=3, rate=0x83 Feb 28 22:55:21 kernel: iwn_set_link_quality: i=5, txrate=2, rate=0x82 Feb 28 22:55:21 kernel: iwn_set_link_quality: i=6, txrate=1, rate=0x81 Feb 28 22:55:21 kernel: iwn_set_link_quality: i=7, txrate=0, rate=0x80 Feb 28 22:55:21 kernel: iwn_set_link_quality: i=8, txrate=0, rate=0x80 Feb 28 22:55:21 kernel: iwn_set_link_quality: i=9, txrate=0, rate=0x80 Feb 28 22:55:21 kernel: iwn_set_link_quality: i=10, txrate=0, rate=0x80 Feb 28 22:55:21 kernel: iwn_set_link_quality: i=11, txrate=0, rate=0x80 Feb 28 22:55:21 kernel: iwn_set_link_quality: i=12, txrate=0, rate=0x80 Feb 28 22:55:21 kernel: iwn_set_link_quality: i=13, txrate=0, rate=0x80 Feb 28 22:55:21 kernel: iwn_set_link_quality: i=14, txrate=0, rate=0x80 Feb 28 22:55:21 kernel: iwn_set_link_quality: i=15, txrate=0, rate=0x80 Feb 28 22:55:21 kernel: wlan0: link state changed to UP Feb 28 22:55:21 wpa_supplicant[2425]: wlan0: Associated with a0:f3:c1:35:a3:6c Feb 28 22:55:21 wpa_supplicant[2426]: wlan0: Associated with a0:f3:c1:35:a3:6c Feb 28 22:55:21 dhclient[2746]: send_packet: No buffer space available Feb 28 22:55:22 kernel: iwn_tx_data: qid 3 idx 3 len 129 nsegs 2 rate 0002 plcp 0x0000420a Feb 28 22:55:22 kernel: iwn_tx_data: qid 3 idx 4 len 129 nsegs 2 rate 0002 plcp 0x0000420a Feb 28 22:55:22 kernel: iwn5000_tx_done: qid 3 idx 3 retries 16 nkill 0 rate 80006902 duration 2815 status 83 Feb 28 22:55:22 kernel: iwn5000_tx_done: qid 3 idx 4 retries 16 nkill 0 rate 80006902 duration 2815 status 83 Feb 28 22:55:23 kernel: iwn_tx_data: qid 3 idx 5 len 129 nsegs 2 rate 0002 plcp 0x0000420a Feb 28 22:55:23 kernel: iwn_tx_data: qid 3 idx 6 len 129 nsegs 2 rate 0002 plcp 0x0000420a Feb 28 22:55:23 kernel: iwn5000_tx_done: qid 3 idx 5 retries 16 nkill 0 rate 80006902 duration 2815 status 83 Feb 28 22:55:23 kernel: iwn5000_tx_done: qid 3 idx 6 retries 16 nkill 0 rate 80006902 duration 2815 status 83 Feb 28 22:55:24 kernel: iwn_tx_data: qid 3 idx 7 len 129 nsegs 2 rate 0002 plcp 0x0000420a Feb 28 22:55:24 kernel: iwn_tx_data: qid 3 idx 8 len 129 nsegs 2 rate 0002 plcp 0x0000420a Feb 28 22:55:24 kernel: iwn5000_tx_done: qid 3 idx 7 retries 16 nkill 0 rate 80006902 duration 2815 status 83 Feb 28 22:55:24 kernel: iwn5000_tx_done: qid 3 idx 8 retries 16 nkill 0 rate 80006902 duration 2815 status 83 Feb 28 22:55:27 dhclient[2746]: send_packet: No buffer space available Feb 28 22:55:32 wpa_supplicant[2425]: wlan0: Authentication with a0:f3:c1:35:a3:6c timed out. Feb 28 22:55:32 kernel: iwn_tx_data_raw: qid 3 idx 9 len 2 nsegs 1 Feb 28 22:55:32 wpa_supplicant[2425]: wlan0: CTRL-EVENT-DISCONNECTED bssid=a0:f3:c1:35:a3:6c reason=3 locally_generated=1 Feb 28 22:55:32 wpa_supplicant[2425]: wlan0: WPA: 4-Way Handshake failed - pre-shared key may be incorrect Feb 28 22:55:32 wpa_supplicant[2425]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="pertho" auth_failures=1 duration=10 Feb 28 22:55:32 kernel: wlan0: link state changed to DOWN Feb 28 22:55:32 wpa_supplicant[2426]: wlan0: Authentication with a0:f3:c1:35:a3:6c timed out. Feb 28 22:55:32 wpa_supplicant[2426]: wlan0: CTRL-EVENT-DISCONNECTED bssid=a0:f3:c1:35:a3:6c reason=3 locally_generated=1 Feb 28 22:55:32 wpa_supplicant[2426]: wlan0: WPA: 4-Way Handshake failed - pre-shared key may be incorrect Feb 28 22:55:32 wpa_supplicant[2426]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="pertho" auth_failures=1 duration=10 Feb 28 22:55:32 wpa_supplicant[2426]: ioctl[SIOCS80211, op=20, val=0, arg_len=7]: Can't assign requested address Feb 28 22:55:32 kernel: iwn5000_tx_done: qid 3 idx 9 retries 7 nkill 0 rate 420a duration 3024 status 83 Feb 28 22:55:33 wpa_supplicant[2426]: ioctl[SIOCS80211, op=103, val=0, arg_len=128]: Operation now in progress Feb 28 22:55:33 wpa_supplicant[2426]: wlan0: Failed to initiate AP scan Feb 28 22:55:33 kernel: iwn_notif_intr: scanning channel 1 status 1 Feb 28 22:55:33 kernel: iwn_notif_intr: scanning channel 6 status 1 Feb 28 22:55:33 kernel: iwn_notif_intr: scanning channel 11 status 1 Feb 28 22:55:33 kernel: iwn_notif_intr: scanning channel 7 status 1 Feb 28 22:55:33 kernel: iwn_notif_intr: scanning channel 13 status 1 Feb 28 22:55:35 kernel: iwn_notif_intr: scanning channel 2 status 1 Feb 28 22:55:35 kernel: iwn_notif_intr: scanning channel 3 status 1 Feb 28 22:55:35 kernel: iwn_notif_intr: scanning channel 4 status 1 Feb 28 22:55:35 kernel: iwn_notif_intr: scanning channel 5 status 1 Feb 28 22:55:35 kernel: iwn_notif_intr: scanning channel 8 status 1 Feb 28 22:55:35 kernel: iwn_notif_intr: scanning channel 9 status 1 Feb 28 22:55:35 kernel: iwn_notif_intr: scanning channel 10 status 1 Feb 28 22:55:35 kernel: iwn_notif_intr: scanning channel 12 status 1 Feb 28 22:55:38 wpa_supplicant[2426]: ioctl[SIOCS80211, op=103, val=0, arg_len=128]: Operation now in progress Feb 28 22:55:38 wpa_supplicant[2426]: wlan0: Failed to initiate AP scan Feb 28 22:55:38 kernel: iwn_notif_intr: scanning channel 1 status 1 Feb 28 22:55:38 kernel: iwn_notif_intr: scanning channel 6 status 1 Feb 28 22:55:38 kernel: iwn_notif_intr: scanning channel 11 status 1 Feb 28 22:55:38 kernel: iwn_notif_intr: scanning channel 7 status 1 Feb 28 22:55:38 dhclient[2746]: send_packet: Invalid argument Feb 28 22:55:39 kernel: iwn_notif_intr: scanning channel 13 status 1 Feb 28 22:55:39 kernel: iwn_notif_intr: scanning channel 2 status 1 Feb 28 22:55:39 kernel: iwn_notif_intr: scanning channel 3 status 1 Feb 28 22:55:39 kernel: iwn_notif_intr: scanning channel 4 status 1 Feb 28 22:55:39 kernel: iwn_notif_intr: scanning channel 5 status 1 Feb 28 22:55:39 kernel: iwn_notif_intr: scanning channel 8 status 1 Feb 28 22:55:39 kernel: iwn_notif_intr: scanning channel 9 status 1 Feb 28 22:55:39 kernel: iwn_notif_intr: scanning channel 10 status 1 Feb 28 22:55:39 kernel: iwn_notif_intr: scanning channel 12 status 1 Feb 28 22:55:43 dhclient[2746]: send_packet: Network is down Feb 28 22:55:44 wpa_supplicant[2426]: ioctl[SIOCS80211, op=103, val=0, arg_len=128]: Operation now in progress Feb 28 22:55:44 wpa_supplicant[2426]: wlan0: Failed to initiate AP scan Feb 28 22:55:44 kernel: iwn_notif_intr: scanning channel 1 status 1 Feb 28 22:55:44 kernel: iwn_notif_intr: scanning channel 6 status 1 Feb 28 22:55:44 kernel: iwn_notif_intr: scanning channel 11 status 1 Feb 28 22:55:44 kernel: iwn_notif_intr: scanning channel 7 status 1 Feb 28 22:55:44 kernel: iwn_notif_intr: scanning channel 13 status 1 Feb 28 22:55:44 kernel: iwn_notif_intr: scanning channel 2 status 1 Feb 28 22:55:44 kernel: iwn_notif_intr: scanning channel 3 status 1 Feb 28 22:55:44 kernel: iwn_notif_intr: scanning channel 4 status 1 Feb 28 22:55:44 kernel: iwn_notif_intr: scanning channel 5 status 1 Feb 28 22:55:44 kernel: iwn_notif_intr: scanning channel 8 status 1 Feb 28 22:55:44 kernel: iwn_notif_intr: scanning channel 9 status 1 Feb 28 22:55:44 kernel: iwn_notif_intr: scanning channel 10 status 1 Feb 28 22:55:45 wpa_supplicant[2426]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="pertho" Feb 28 22:55:45 kernel: iwn_notif_intr: scanning channel 12 status 1 Feb 28 22:55:45 wpa_supplicant[2426]: wlan0: Trying to associate with a0:f3:c1:35:a3:6c (SSID='pertho' freq=2462 MHz) Feb 28 22:55:45 wpa_supplicant[2425]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="pertho" Feb 28 22:55:45 wpa_supplicant[2425]: wlan0: Trying to associate with a0:f3:c1:35:a3:6c (SSID='pertho' freq=2462 MHz) Feb 28 22:55:45 kernel: iwn_tx_data_raw: qid 3 idx 10 len 6 nsegs 1 Feb 28 22:55:45 kernel: iwn_tx_data_raw: qid 3 idx 11 len 6 nsegs 1 Feb 28 22:55:45 kernel: iwn5000_tx_done: qid 3 idx 10 retries 0 nkill 0 rate 420a duration 778 status 201 Feb 28 22:55:45 kernel: iwn5000_tx_done: qid 3 idx 11 retries 0 nkill 0 rate 420a duration 778 status 201 Feb 28 22:55:45 kernel: iwn_tx_data_raw: qid 3 idx 12 len 87 nsegs 1 Feb 28 22:55:45 wpa_supplicant[2426]: wlan0: Associated with a0:f3:c1:35:a3:6c Feb 28 22:55:45 kernel: iwn5000_tx_done: qid 3 idx 12 retries 0 nkill 0 rate 420a duration 1426 status 201 Feb 28 22:55:45 kernel: iwn_set_link_quality: 1stream antenna=0x01, 2stream antenna=0x03, ntxstreams=1 Feb 28 22:55:45 kernel: iwn_set_link_quality: i=0, txrate=7, rate=0x87 Feb 28 22:55:45 kernel: iwn_set_link_quality: i=1, txrate=6, rate=0x86 Feb 28 22:55:45 kernel: iwn_set_link_quality: i=2, txrate=5, rate=0x85 Feb 28 22:55:45 kernel: iwn_set_link_quality: i=3, txrate=4, rate=0x84 Feb 28 22:55:45 kernel: iwn_set_link_quality: i=4, txrate=3, rate=0x83 Feb 28 22:55:45 kernel: iwn_set_link_quality: i=5, txrate=2, rate=0x82 Feb 28 22:55:45 kernel: iwn_set_link_quality: i=6, txrate=1, rate=0x81 Feb 28 22:55:45 kernel: iwn_set_link_quality: i=7, txrate=0, rate=0x80 Feb 28 22:55:45 kernel: iwn_set_link_quality: i=8, txrate=0, rate=0x80 Feb 28 22:55:45 kernel: iwn_set_link_quality: i=9, txrate=0, rate=0x80 Feb 28 22:55:45 kernel: iwn_set_link_quality: i=10, txrate=0, rate=0x80 Feb 28 22:55:45 kernel: iwn_set_link_quality: i=11, txrate=0, rate=0x80 Feb 28 22:55:45 kernel: iwn_set_link_quality: i=12, txrate=0, rate=0x80 Feb 28 22:55:45 kernel: iwn_set_link_quality: i=13, txrate=0, rate=0x80 Feb 28 22:55:45 kernel: iwn_set_link_quality: i=14, txrate=0, rate=0x80 Feb 28 22:55:45 kernel: iwn_set_link_quality: i=15, txrate=0, rate=0x80 Feb 28 22:55:45 kernel: received statistics without RSSI Feb 28 22:55:45 kernel: wlan0: link state changed to UP Feb 28 22:55:45 wpa_supplicant[2425]: wlan0: Associated with a0:f3:c1:35:a3:6c Feb 28 22:55:45 dhclient[2746]: send_packet: No buffer space available Feb 28 22:55:45 dhclient[2746]: send_packet: No buffer space available Feb 28 22:55:46 kernel: iwn_tx_data: qid 3 idx 13 len 129 nsegs 2 rate 0002 plcp 0x0000420a Feb 28 22:55:46 kernel: iwn_tx_data: qid 3 idx 14 len 129 nsegs 2 rate 0002 plcp 0x0000420a Feb 28 22:55:46 kernel: iwn5000_tx_done: qid 3 idx 13 retries 16 nkill 0 rate 80006902 duration 2815 status 83 Feb 28 22:55:46 kernel: iwn5000_tx_done: qid 3 idx 14 retries 16 nkill 0 rate 80006902 duration 2815 status 83 Feb 28 22:55:47 kernel: iwn_tx_data: qid 3 idx 15 len 129 nsegs 2 rate 0002 plcp 0x0000420a Feb 28 22:55:47 kernel: iwn_tx_data: qid 3 idx 16 len 129 nsegs 2 rate 0002 plcp 0x0000420a Feb 28 22:55:47 kernel: iwn5000_tx_done: qid 3 idx 15 retries 16 nkill 0 rate 80006902 duration 2815 status 83 Feb 28 22:55:47 kernel: iwn5000_tx_done: qid 3 idx 16 retries 16 nkill 0 rate 80006902 duration 2815 status 83 Feb 28 22:55:48 kernel: iwn_tx_data: qid 3 idx 17 len 129 nsegs 2 rate 0002 plcp 0x0000420a Feb 28 22:55:48 kernel: iwn_tx_data: qid 3 idx 18 len 129 nsegs 2 rate 0002 plcp 0x0000420a Feb 28 22:55:48 kernel: iwn5000_tx_done: qid 3 idx 17 retries 16 nkill 0 rate 80006902 duration 2815 status 83 Feb 28 22:55:48 kernel: iwn5000_tx_done: qid 3 idx 18 retries 16 nkill 0 rate 80006902 duration 2815 status 83 Feb 28 22:55:49 kernel: iwn_check_rx_recovery: PLCP error threshold raw (134) comparison (13400) over limit (10250); retune! Feb 28 22:55:49 dhclient[2746]: send_packet: No buffer space available Feb 28 22:55:53 dhclient[2746]: send_packet: No buffer space available Feb 28 22:55:55 kernel: iwn_check_rx_recovery: PLCP error threshold raw (61) comparison (6100) over limit (5450); retune! Feb 28 22:55:56 wpa_supplicant[2426]: wlan0: Authentication with a0:f3:c1:35:a3:6c timed out. Feb 28 22:55:56 kernel: iwn_tx_data_raw: qid 3 idx 19 len 2 nsegs 1 Feb 28 22:55:56 wpa_supplicant[2426]: wlan0: CTRL-EVENT-DISCONNECTED bssid=a0:f3:c1:35:a3:6c reason=3 locally_generated=1 Feb 28 22:55:56 wpa_supplicant[2426]: wlan0: WPA: 4-Way Handshake failed - pre-shared key may be incorrect Feb 28 22:55:56 wpa_supplicant[2426]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="pertho" auth_failures=2 duration=20 Feb 28 22:55:56 kernel: wlan0: link state changed to DOWN Feb 28 22:55:56 wpa_supplicant[2425]: wlan0: Authentication with a0:f3:c1:35:a3:6c timed out. Feb 28 22:55:56 wpa_supplicant[2425]: wlan0: CTRL-EVENT-DISCONNECTED bssid=a0:f3:c1:35:a3:6c reason=3 locally_generated=1 Feb 28 22:55:56 wpa_supplicant[2425]: wlan0: WPA: 4-Way Handshake failed - pre-shared key may be incorrect Feb 28 22:55:56 wpa_supplicant[2425]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="pertho" auth_failures=2 duration=20 Feb 28 22:55:56 wpa_supplicant[2425]: ioctl[SIOCS80211, op=20, val=0, arg_len=7]: Can't assign requested address Feb 28 22:55:56 kernel: iwn5000_tx_done: qid 3 idx 19 retries 6 nkill 0 rate 420a duration 3338 status 201 Feb 28 22:55:57 wpa_supplicant[2425]: ioctl[SIOCS80211, op=103, val=0, arg_len=128]: Operation now in progress Feb 28 22:55:57 wpa_supplicant[2425]: wlan0: Failed to initiate AP scan Feb 28 22:55:57 kernel: iwn_notif_intr: scanning channel 1 status 1 Feb 28 22:55:57 kernel: iwn_notif_intr: scanning channel 6 status 1 Feb 28 22:55:57 kernel: iwn_notif_intr: scanning channel 11 status 1 Feb 28 22:55:57 kernel: iwn_notif_intr: scanning channel 7 status 1 Feb 28 22:55:57 kernel: iwn_notif_intr: scanning channel 13 status 1 Feb 28 22:55:57 kernel: iwn_notif_intr: scanning channel 2 status 1 Feb 28 22:55:57 kernel: iwn_notif_intr: scanning channel 3 status 1 Feb 28 22:55:57 kernel: iwn_notif_intr: scanning channel 4 status 1 Feb 28 22:55:57 kernel: iwn_notif_intr: scanning channel 5 status 1 Feb 28 22:55:57 kernel: iwn_notif_intr: scanning channel 8 status 1 Feb 28 22:55:57 kernel: iwn_notif_intr: scanning channel 9 status 1 Feb 28 22:55:57 kernel: iwn_notif_intr: scanning channel 10 status 1 Feb 28 22:55:57 kernel: iwn_notif_intr: scanning channel 12 status 1 Feb 28 22:56:00 dhclient[2746]: send_packet: Invalid argument Feb 28 22:56:02 wpa_supplicant[2426]: ioctl[SIOCS80211, op=103, val=0, arg_len=128]: Operation now in progress Feb 28 22:56:02 wpa_supplicant[2426]: wlan0: Failed to initiate AP scan Feb 28 22:56:02 kernel: iwn_notif_intr: scanning channel 1 status 1 Feb 28 22:56:02 kernel: iwn_notif_intr: scanning channel 6 status 1 Feb 28 22:56:02 kernel: iwn_notif_intr: scanning channel 11 status 1 Feb 28 22:56:03 kernel: iwn_notif_intr: scanning channel 7 status 1 Feb 28 22:56:03 kernel: iwn_notif_intr: scanning channel 13 status 1 Feb 28 22:56:03 kernel: iwn_notif_intr: scanning channel 2 status 1 Feb 28 22:56:03 kernel: iwn_notif_intr: scanning channel 3 status 1 Feb 28 22:56:03 kernel: iwn_notif_intr: scanning channel 4 status 1 Feb 28 22:56:03 kernel: iwn_notif_intr: scanning channel 5 status 1 Feb 28 22:56:03 kernel: iwn_notif_intr: scanning channel 8 status 1 Feb 28 22:56:03 kernel: iwn_notif_intr: scanning channel 9 status 1 Feb 28 22:56:03 kernel: iwn_notif_intr: scanning channel 10 status 1 Feb 28 22:56:03 kernel: iwn_notif_intr: scanning channel 12 status 1 Feb 28 22:56:08 wpa_supplicant[2426]: ioctl[SIOCS80211, op=103, val=0, arg_len=128]: Operation now in progress Feb 28 22:56:08 wpa_supplicant[2426]: wlan0: Failed to initiate AP scan Feb 28 22:56:08 kernel: iwn_notif_intr: scanning channel 1 status 1 Feb 28 22:56:08 kernel: iwn_notif_intr: scanning channel 6 status 1 Feb 28 22:56:08 kernel: iwn_notif_intr: scanning channel 11 status 1 Feb 28 22:56:08 kernel: iwn_notif_intr: scanning channel 7 status 1 Feb 28 22:56:08 kernel: iwn_notif_intr: scanning channel 13 status 1 Feb 28 22:56:08 kernel: iwn_notif_intr: scanning channel 2 status 1 Feb 28 22:56:08 kernel: iwn_notif_intr: scanning channel 3 status 1 Feb 28 22:56:08 kernel: iwn_notif_intr: scanning channel 4 status 1 Feb 28 22:56:08 kernel: iwn_notif_intr: scanning channel 5 status 1 Feb 28 22:56:09 kernel: iwn_notif_intr: scanning channel 8 status 1 Feb 28 22:56:09 kernel: iwn_notif_intr: scanning channel 9 status 1 Feb 28 22:56:09 kernel: iwn_notif_intr: scanning channel 10 status 1 Feb 28 22:56:09 kernel: iwn_notif_intr: scanning channel 12 status 1 Feb 28 22:56:10 dhclient[2746]: send_packet: Network is down Feb 28 22:56:10 login: ROOT LOGIN (root) ON ttyv1 Feb 28 22:56:14 wpa_supplicant[2426]: ioctl[SIOCS80211, op=103, val=0, arg_len=128]: Operation now in progress Feb 28 22:56:14 kernel: iwn_notif_intr: scanning channel 1 status 1 Feb 28 22:56:14 wpa_supplicant[2426]: wlan0: Failed to initiate AP scan Feb 28 22:56:14 kernel: iwn_notif_intr: scanning channel 6 status 1 Feb 28 22:56:14 kernel: iwn_notif_intr: scanning channel 11 status 1 Feb 28 22:56:14 kernel: iwn_notif_intr: scanning channel 7 status 1 Feb 28 22:56:14 kernel: iwn_notif_intr: scanning channel 13 status 1 Feb 28 22:56:14 kernel: iwn_notif_intr: scanning channel 2 status 1 Feb 28 22:56:14 kernel: iwn_notif_intr: scanning channel 3 status 1 Feb 28 22:56:14 kernel: iwn_notif_intr: scanning channel 4 status 1 Feb 28 22:56:14 kernel: iwn_notif_intr: scanning channel 5 status 1 Feb 28 22:56:14 kernel: iwn_notif_intr: scanning channel 8 status 1 Feb 28 22:56:14 kernel: iwn_notif_intr: scanning channel 9 status 1 Feb 28 22:56:14 kernel: iwn_notif_intr: scanning channel 10 status 1 Feb 28 22:56:14 kernel: iwn_notif_intr: scanning channel 12 status 1 Feb 28 22:56:17 dhclient[2746]: send_packet: Network is down Feb 28 22:56:19 wpa_supplicant[2425]: ioctl[SIOCS80211, op=103, val=0, arg_len=128]: Operation now in progress Feb 28 22:56:19 wpa_supplicant[2425]: wlan0: Failed to initiate AP scan Feb 28 22:56:19 kernel: iwn_notif_intr: scanning channel 1 status 1 Feb 28 22:56:19 kernel: iwn_notif_intr: scanning channel 6 status 1 Feb 28 22:56:19 kernel: iwn_notif_intr: scanning channel 11 status 1 Feb 28 22:56:19 kernel: iwn_notif_intr: scanning channel 7 status 1 Feb 28 22:56:19 kernel: iwn_notif_intr: scanning channel 13 status 1 Feb 28 22:56:20 kernel: iwn_notif_intr: scanning channel 2 status 1 Feb 28 22:56:20 kernel: iwn_notif_intr: scanning channel 3 status 1 Feb 28 22:56:20 kernel: iwn_notif_intr: scanning channel 4 status 1 Feb 28 22:56:20 kernel: iwn_notif_intr: scanning channel 5 status 1 Feb 28 22:56:20 kernel: iwn_notif_intr: scanning channel 8 status 1 Feb 28 22:56:20 wpa_supplicant[2426]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="pertho" Feb 28 22:56:20 kernel: iwn_notif_intr: scanning channel 9 status 1 Feb 28 22:56:20 kernel: iwn_notif_intr: scanning channel 10 status 1 Feb 28 22:56:20 kernel: iwn_notif_intr: scanning channel 12 status 1 Feb 28 22:56:20 wpa_supplicant[2426]: wlan0: Trying to associate with a0:f3:c1:35:a3:6c (SSID='pertho' freq=2462 MHz) Feb 28 22:56:20 kernel: iwn_tx_data_raw: qid 3 idx 20 len 6 nsegs 1 Feb 28 22:56:20 kernel: iwn_tx_data_raw: qid 3 idx 21 len 6 nsegs 1 Feb 28 22:56:20 wpa_supplicant[2425]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="pertho" Feb 28 22:56:20 wpa_supplicant[2425]: wlan0: Trying to associate with a0:f3:c1:35:a3:6c (SSID='pertho' freq=2462 MHz) Feb 28 22:56:20 kernel: iwn5000_tx_done: qid 3 idx 20 retries 0 nkill 0 rate 420a duration 778 status 201 Feb 28 22:56:20 kernel: iwn5000_tx_done: qid 3 idx 21 retries 0 nkill 0 rate 420a duration 778 status 201 Feb 28 22:56:20 kernel: iwn_tx_data_raw: qid 3 idx 22 len 87 nsegs 1 Feb 28 22:56:20 kernel: iwn5000_tx_done: qid 3 idx 22 retries 1 nkill 0 rate 420a duration 2538 status 201 Feb 28 22:56:20 kernel: iwn_set_link_quality: 1stream antenna=0x01, 2stream antenna=0x03, ntxstreams=1 Feb 28 22:56:20 kernel: iwn_set_link_quality: i=0, txrate=7, rate=0x87 Feb 28 22:56:20 kernel: iwn_set_link_quality: i=1, txrate=6, rate=0x86 Feb 28 22:56:20 kernel: iwn_set_link_quality: i=2, txrate=5, rate=0x85 Feb 28 22:56:20 kernel: iwn_set_link_quality: i=3, txrate=4, rate=0x84 Feb 28 22:56:20 kernel: iwn_set_link_quality: i=4, txrate=3, rate=0x83 Feb 28 22:56:20 kernel: iwn_set_link_quality: i=5, txrate=2, rate=0x82 Feb 28 22:56:20 kernel: iwn_set_link_quality: i=6, txrate=1, rate=0x81 Feb 28 22:56:20 kernel: iwn_set_link_quality: i=7, txrate=0, rate=0x80 Feb 28 22:56:20 kernel: iwn_set_link_quality: i=8, txrate=0, rate=0x80 Feb 28 22:56:20 kernel: iwn_set_link_quality: i=9, txrate=0, rate=0x80 Feb 28 22:56:20 kernel: iwn_set_link_quality: i=10, txrate=0, rate=0x80 Feb 28 22:56:20 kernel: iwn_set_link_quality: i=11, txrate=0, rate=0x80 Feb 28 22:56:20 kernel: iwn_set_link_quality: i=12, txrate=0, rate=0x80 Feb 28 22:56:20 kernel: iwn_set_link_quality: i=13, txrate=0, rate=0x80 Feb 28 22:56:20 kernel: iwn_set_link_quality: i=14, txrate=0, rate=0x80 Feb 28 22:56:20 kernel: iwn_set_link_quality: i=15, txrate=0, rate=0x80 Feb 28 22:56:20 kernel: wlan0: link state changed to UP Feb 28 22:56:20 kernel: received statistics without RSSI Feb 28 22:56:20 wpa_supplicant[2425]: wlan0: Associated with a0:f3:c1:35:a3:6c Feb 28 22:56:20 dhclient[2746]: send_packet: No buffer space available Feb 28 22:56:20 wpa_supplicant[2426]: wlan0: Associated with a0:f3:c1:35:a3:6c Feb 28 22:56:21 kernel: iwn_tx_data: qid 3 idx 23 len 129 nsegs 2 rate 0002 plcp 0x0000420a Feb 28 22:56:21 kernel: iwn_tx_data: qid 3 idx 24 len 129 nsegs 2 rate 0002 plcp 0x0000420a Feb 28 22:56:21 kernel: iwn5000_tx_done: qid 3 idx 23 retries 16 nkill 0 rate 80006902 duration 2815 status 83 Feb 28 22:56:21 kernel: iwn5000_tx_done: qid 3 idx 24 retries 16 nkill 0 rate 80006902 duration 2815 status 83 Feb 28 22:56:22 kernel: iwn_tx_data: qid 3 idx 25 len 129 nsegs 2 rate 0002 plcp 0x0000420a Feb 28 22:56:22 kernel: iwn_tx_data: qid 3 idx 26 len 129 nsegs 2 rate 0002 plcp 0x0000420a Feb 28 22:56:22 dhclient[2746]: send_packet: No buffer space available Feb 28 22:56:22 kernel: iwn5000_tx_done: qid 3 idx 25 retries 16 nkill 0 rate 80006902 duration 2815 status 83 Feb 28 22:56:22 kernel: iwn5000_tx_done: qid 3 idx 26 retries 16 nkill 0 rate 80006902 duration 2815 status 83 Feb 28 22:56:23 kernel: iwn_tx_data: qid 3 idx 27 len 129 nsegs 2 rate 0002 plcp 0x0000420a Feb 28 22:56:23 kernel: iwn_tx_data: qid 3 idx 28 len 129 nsegs 2 rate 0002 plcp 0x0000420a Feb 28 22:56:23 kernel: iwn5000_tx_done: qid 3 idx 27 retries 16 nkill 0 rate 80006902 duration 2815 status 83 Feb 28 22:56:23 kernel: iwn5000_tx_done: qid 3 idx 28 retries 16 nkill 0 rate 80006902 duration 2815 status 83 Feb 28 22:56:27 kernel: iwn_check_rx_recovery: PLCP error threshold raw (117) comparison (11700) over limit (4950); retune! Feb 28 22:56:27 dhclient[2746]: send_packet: No buffer space available Feb 28 22:56:31 wpa_supplicant[2426]: wlan0: Authentication with a0:f3:c1:35:a3:6c timed out. Feb 28 22:56:31 kernel: iwn_tx_data_raw: qid 3 idx 29 len 2 nsegs 1 Feb 28 22:56:31 kernel: wlan0: link state changed to DOWN Feb 28 22:56:31 wpa_supplicant[2425]: wlan0: Authentication with a0:f3:c1:35:a3:6c timed out. Feb 28 22:56:31 wpa_supplicant[2426]: wlan0: CTRL-EVENT-DISCONNECTED bssid=a0:f3:c1:35:a3:6c reason=3 locally_generated=1 Feb 28 22:56:31 wpa_supplicant[2426]: wlan0: WPA: 4-Way Handshake failed - pre-shared key may be incorrect Feb 28 22:56:31 wpa_supplicant[2426]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="pertho" auth_failures=3 duration=20 Feb 28 22:56:31 wpa_supplicant[2426]: ioctl[SIOCS80211, op=20, val=0, arg_len=7]: Can't assign requested address Feb 28 22:56:31 wpa_supplicant[2425]: wlan0: CTRL-EVENT-DISCONNECTED bssid=a0:f3:c1:35:a3:6c reason=3 locally_generated=1 Feb 28 22:56:31 wpa_supplicant[2425]: wlan0: WPA: 4-Way Handshake failed - pre-shared key may be incorrect Feb 28 22:56:31 wpa_supplicant[2425]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="pertho" auth_failures=3 duration=20 Feb 28 22:56:31 wpa_supplicant[2425]: ioctl[SIOCS80211, op=20, val=0, arg_len=7]: Can't assign requested address Feb 28 22:56:31 kernel: iwn5000_tx_done: qid 3 idx 29 retries 3 nkill 0 rate 420a duration 2042 status 201 Feb 28 22:56:31 wpa_supplicant[2425]: ioctl[SIOCS80211, op=26, val=0, arg_len=0]: Operation not supported Feb 28 22:56:31 wpa_supplicant[2426]: ioctl[SIOCS80211, op=26, val=0, arg_len=0]: Operation not supported Feb 28 22:56:31 wpa_supplicant[2426]: ioctl[SIOCS80211, op=26, val=0, arg_len=0]: Operation not supported Feb 28 22:56:31 dhclient[2743]: Interface wlan0 is down, dhclient exiting Feb 28 22:56:31 wpa_supplicant[2426]: wlan0: CTRL-EVENT-TERMINATING Feb 28 22:56:31 wpa_supplicant[2425]: ioctl[SIOCS80211, op=26, val=0, arg_len=0]: Operation not supported Feb 28 22:56:31 wpa_supplicant[2425]: wlan0: CTRL-EVENT-TERMINATING Feb 28 22:56:31 dhclient[2746]: connection closed Feb 28 22:56:31 dhclient[2746]: exiting. Feb 28 22:56:31 root: /etc/rc.d/dhclient: WARNING: failed to start dhclient Feb 28 22:56:43 root: /etc/rc.d/dhclient: WARNING: failed to start dhclient Feb 28 22:56:51 root: /etc/rc.d/netif: WARNING: wlan0 does not exist. Skipped. Feb 28 22:56:51 dhclient[2815]: wlan0: not found Feb 28 22:56:51 dhclient[2815]: exiting. Feb 28 22:56:51 root: /etc/rc.d/dhclient: WARNING: failed to start dhclient --C7zPtVaVf+AK4Oqc--