Skip site navigation (1)Skip section navigation (2)
Date:      Fri, 28 Feb 2014 23:07:05 +0000
From:      Tom Murphy <freebsd@pertho.net>
To:        Adrian Chadd <adrian@freebsd.org>
Cc:        Alexandr <shuriku@shurik.kiev.ua>, freebsd-current <freebsd-current@freebsd.org>, "freebsd-wireless@freebsd.org" <freebsd-wireless@freebsd.org>
Subject:   Re: iwn(4) in -HEAD supporting Centrino Wireless-N 135
Message-ID:  <20140228230705.GA28290@syn.pertho.net>
In-Reply-To: <CAJ-VmonApR_rXWQJm=M7mHkNe=PsTChKebMHrzy19Cg-aTkexA@mail.gmail.com>
References:  <20140226123709.GA31099@syn.pertho.net> <CAJ-Vmom=b5tPTh1qWNG=zV5qM=Wzf1BRe5%2B2-ndrR648NSFmkw@mail.gmail.com> <530EE7F4.3080903@shurik.kiev.ua> <CAJ-VmomsEdS5nm8rX6245rAEhVxWeOc4aO9AYrmEBHuUKHG=Tw@mail.gmail.com> <530EEEA5.4090306@shurik.kiev.ua> <CAJ-VmonApR_rXWQJm=M7mHkNe=PsTChKebMHrzy19Cg-aTkexA@mail.gmail.com>

next in thread | previous in thread | raw e-mail | index | archive | help

--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 <shuriku@shurik.kiev.ua> 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--



Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?20140228230705.GA28290>