From owner-freebsd-current@FreeBSD.ORG Wed Jun 29 13:36:36 2005 Return-Path: X-Original-To: freebsd-current@FreeBSD.org Delivered-To: freebsd-current@FreeBSD.org Received: from mx1.FreeBSD.org (mx1.freebsd.org [216.136.204.125]) by hub.freebsd.org (Postfix) with ESMTP id 74A8716A41C for ; Wed, 29 Jun 2005 13:36:36 +0000 (GMT) (envelope-from avatar@mmlab.cse.yzu.edu.tw) Received: from www.mmlab.cse.yzu.edu.tw (www.mmlab.cse.yzu.edu.tw [140.138.150.166]) by mx1.FreeBSD.org (Postfix) with ESMTP id AE9F443D4C for ; Wed, 29 Jun 2005 13:36:35 +0000 (GMT) (envelope-from avatar@mmlab.cse.yzu.edu.tw) Received: by www.mmlab.cse.yzu.edu.tw (qmail, from userid 1000) id 5A8A3507910; Wed, 29 Jun 2005 21:36:34 +0800 (CST) Received: from localhost (localhost [127.0.0.1]) by www.mmlab.cse.yzu.edu.tw (qmail) with ESMTP id 546885070B1; Wed, 29 Jun 2005 21:36:34 +0800 (CST) Date: Wed, 29 Jun 2005 21:36:34 +0800 (CST) From: Tai-hwa Liang To: Eric Masson In-Reply-To: <86psu67naj.fsf@srvbsdnanssv.interne.kisoft-services.com> Message-ID: <0506292053383.79911@www.mmlab.cse.yzu.edu.tw> References: <86psu67naj.fsf@srvbsdnanssv.interne.kisoft-services.com> MIME-Version: 1.0 Content-Type: TEXT/PLAIN; charset=US-ASCII; format=flowed Cc: Mailing List FreeBSD Current Subject: Re: ath0, link goes down then up periodically X-BeenThere: freebsd-current@freebsd.org X-Mailman-Version: 2.1.5 Precedence: list List-Id: Discussions about the use of FreeBSD-current List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Wed, 29 Jun 2005 13:36:36 -0000 On Tue, 28 Jun 2005, Eric Masson wrote: > Hi, > > I'm using a Planet WL3560 (atheros chipset) with -current as a DLink > DWL2000AP client : > > Sometimes, ath0 link state goes down then up : > http://emss.free.fr/informatique/FreeBSD/messages > > 80211debug has been set with the following options : > /usr/local/bin/80211debug +debug +assoc +xrate +power +scan +wme > > Any idea ? I have observed similar periodic UP/DOWN whilst associating with certain APs(in my case, DLink DWL-7100AP). On the other hand, this doesn't look like ath specific to me since I can reproduce this with the same AP + if_wi. According to the timestamp, the UP/DOWN interval is *exactly* 1 minute. I suspect this AP has some sort of "auto-kick-out" function builtin; that is, if there is no frame from the station within a defined period, AP will try to re-authenticate with this station(reason 2 - Previous authentication no longer valid). In this testing case, I didn't send any data frame after ath0 associated with the AP. If I send something periodically(such like a background ping), the AP seems happy and no longer kicks me out every single minute. Notes that this re-authentication madness doesn't manifest itself on Windows XP since XP periodically scans all available channels even it has associated with a BSS. Jun 29 20:59:46: ath0: received auth from 00:11:22:33:44:55 rssi 61 Jun 29 20:59:46: [00:11:22:33:44:55] recv auth frame with algorithm 0 seq 2 Jun 29 20:59:46: [00:11:22:33:44:55] send assoc_req on channel 9 Jun 29 20:59:46: ath0: received assoc_resp from 00:11:22:33:44:55 rssi 61 Jun 29 20:59:46: [00:11:22:33:44:55] assoc success: long preamble, short slot time Jun 29 20:59:46: ath0: associated with 00:11:22:33:44:55 ssid "MySSID" channel 9 start 36Mb Jun 29 20:59:46: ath0: link state changed to UP Jun 29 21:00:46: ath0: received deauth from 00:11:22:33:44:55 rssi 60 Jun 29 21:00:46: [00:11:22:33:44:55] recv deauthenticate (reason 2) Jun 29 21:00:46: [00:11:22:33:44:55] send auth on channel 9 Jun 29 21:00:46: ath0: link state changed to DOWN Jun 29 21:00:46: ath0: received auth from 00:11:22:33:44:55 rssi 59 Jun 29 21:00:46: [00:11:22:33:44:55] recv auth frame with algorithm 0 seq 2 Jun 29 21:00:46: [00:11:22:33:44:55] send assoc_req on channel 9 Jun 29 21:00:46: [00:11:22:33:44:55] discard duplicate frame, seqno <3939,3939> fragno <0,0> tid 0 Jun 29 21:00:46: ath0: received assoc_resp from 00:11:22:33:44:55 rssi 60 Jun 29 21:00:46: [00:11:22:33:44:55] assoc success: long preamble, short slot time Jun 29 21:00:46: ath0: associated with 00:11:22:33:44:55 ssid "MySSID" channel 9 start 36Mb Jun 29 21:00:46: ath0: link state changed to UP Jun 29 21:01:46: ath0: received deauth from 00:11:22:33:44:55 rssi 60 Jun 29 21:01:46: [00:11:22:33:44:55] recv deauthenticate (reason 2) Jun 29 21:01:46: [00:11:22:33:44:55] send auth on channel 9 Jun 29 21:01:46: ath0: link state changed to DOWN Jun 29 21:01:46: ath0: received auth from 00:11:22:33:44:55 rssi 59 Jun 29 21:01:46: [00:11:22:33:44:55] recv auth frame with algorithm 0 seq 2 Jun 29 21:01:46: [00:11:22:33:44:55] send assoc_req on channel 9 Jun 29 21:01:46: ath0: received assoc_resp from 00:11:22:33:44:55 rssi 61 Jun 29 21:01:46: [00:11:22:33:44:55] assoc success: long preamble, short slot time Jun 29 21:01:46: ath0: associated with 00:11:22:33:44:55 ssid "MySSID" channel 9 start 36Mb Jun 29 21:01:46: ath0: link state changed to UP Jun 29 21:02:46: ath0: received deauth from 00:11:22:33:44:55 rssi 60 Jun 29 21:02:46: [00:11:22:33:44:55] recv deauthenticate (reason 2) Jun 29 21:02:46: [00:11:22:33:44:55] send auth on channel 9 Jun 29 21:02:46: ath0: link state changed to DOWN Jun 29 21:02:46: ath0: received auth from 00:11:22:33:44:55 rssi 61 Jun 29 21:02:46: [00:11:22:33:44:55] recv auth frame with algorithm 0 seq 2 Jun 29 21:02:46: [00:11:22:33:44:55] send assoc_req on channel 9 Jun 29 21:02:46: ath0: received assoc_resp from 00:11:22:33:44:55 rssi 61 Jun 29 21:02:46: [00:11:22:33:44:55] assoc success: long preamble, short slot time Jun 29 21:02:46: ath0: associated with 00:11:22:33:44:55 ssid "MySSID" channel 9 start 36Mb Jun 29 21:02:46: ath0: link state changed to UP Jun 29 21:03:46: ath0: received deauth from 00:11:22:33:44:55 rssi 61 Jun 29 21:03:46: [00:11:22:33:44:55] recv deauthenticate (reason 2) Jun 29 21:03:46: [00:11:22:33:44:55] send auth on channel 9 Jun 29 21:03:46: ath0: link state changed to DOWN Jun 29 21:03:46: ath0: received auth from 00:11:22:33:44:55 rssi 60 Jun 29 21:03:46: [00:11:22:33:44:55] recv auth frame with algorithm 0 seq 2 Jun 29 21:03:46: [00:11:22:33:44:55] send assoc_req on channel 9 Jun 29 21:03:46: ath0: received assoc_resp from 00:11:22:33:44:55 rssi 61 Jun 29 21:03:46: [00:11:22:33:44:55] assoc success: long preamble, short slot time Jun 29 21:03:46: ath0: associated with 00:11:22:33:44:55 ssid "MySSID" channel 9 start 36Mb Jun 29 21:03:46: ath0: link state changed to UP Jun 29 21:04:46: ath0: received deauth from 00:11:22:33:44:55 rssi 61 Jun 29 21:04:46: [00:11:22:33:44:55] recv deauthenticate (reason 2) Jun 29 21:04:46: [00:11:22:33:44:55] send auth on channel 9 Jun 29 21:04:46: ath0: link state changed to DOWN Jun 29 21:04:46: ath0: received auth from 00:11:22:33:44:55 rssi 60 Jun 29 21:04:46: [00:11:22:33:44:55] recv auth frame with algorithm 0 seq 2 Jun 29 21:04:46: [00:11:22:33:44:55] send assoc_req on channel 9 Jun 29 21:04:46: ath0: received assoc_resp from 00:11:22:33:44:55 rssi 60 Jun 29 21:04:46: [00:11:22:33:44:55] assoc success: long preamble, short slot time Jun 29 21:04:46: ath0: associated with 00:11:22:33:44:55 ssid "MySSID" channel 9 start 36Mb Jun 29 21:04:46: ath0: link state changed to UP Jun 29 21:05:46: ath0: received deauth from 00:11:22:33:44:55 rssi 60 Jun 29 21:05:46: [00:11:22:33:44:55] recv deauthenticate (reason 2) Jun 29 21:05:46: [00:11:22:33:44:55] send auth on channel 9 Jun 29 21:05:46: ath0: link state changed to DOWN Jun 29 21:05:46: ath0: received auth from 00:11:22:33:44:55 rssi 60 Jun 29 21:05:46: [00:11:22:33:44:55] recv auth frame with algorithm 0 seq 2 Jun 29 21:05:46: [00:11:22:33:44:55] send assoc_req on channel 9 Jun 29 21:05:46: ath0: received assoc_resp from 00:11:22:33:44:55 rssi 59 Jun 29 21:05:46: [00:11:22:33:44:55] assoc success: long preamble, short slot time Jun 29 21:05:46: ath0: associated with 00:11:22:33:44:55 ssid "MySSID" channel 9 start 36Mb Jun 29 21:05:46: ath0: link state changed to UP Jun 29 21:06:46: ath0: received deauth from 00:11:22:33:44:55 rssi 60 Jun 29 21:06:46: [00:11:22:33:44:55] recv deauthenticate (reason 2) Jun 29 21:06:46: [00:11:22:33:44:55] send auth on channel 9 Jun 29 21:06:46: ath0: link state changed to DOWN Jun 29 21:06:46: ath0: received auth from 00:11:22:33:44:55 rssi 60 Jun 29 21:06:46: [00:11:22:33:44:55] recv auth frame with algorithm 0 seq 2 Jun 29 21:06:46: [00:11:22:33:44:55] send assoc_req on channel 9 Jun 29 21:06:46: [00:11:22:33:44:55] discard duplicate frame, seqno <3915,3915> fragno <0,0> tid 0 Jun 29 21:06:46: ath0: received assoc_resp from 00:11:22:33:44:55 rssi 59 Jun 29 21:06:46: [00:11:22:33:44:55] assoc success: long preamble, short slot time Jun 29 21:06:46: ath0: associated with 00:11:22:33:44:55 ssid "MySSID" channel 9 start 36Mb Jun 29 21:06:46: ath0: link state changed to UP Jun 29 21:07:46: ath0: received deauth from 00:11:22:33:44:55 rssi 59 Jun 29 21:07:46: [00:11:22:33:44:55] recv deauthenticate (reason 2) Jun 29 21:07:46: [00:11:22:33:44:55] send auth on channel 9 Jun 29 21:07:46: [00:11:22:33:44:55] discard duplicate frame, seqno <503,503> fragno <0,0> tid 0 Jun 29 21:07:46: ath0: received auth from 00:11:22:33:44:55 rssi 59 Jun 29 21:07:46: [00:11:22:33:44:55] recv auth frame with algorithm 0 seq 2 Jun 29 21:07:46: [00:11:22:33:44:55] send assoc_req on channel 9 Jun 29 21:07:46: ath0: link state changed to DOWN Jun 29 21:07:46: ath0: received assoc_resp from 00:11:22:33:44:55 rssi 59 Jun 29 21:07:46: [00:11:22:33:44:55] assoc success: long preamble, short slot time Jun 29 21:07:46: ath0: associated with 00:11:22:33:44:55 ssid "MySSID" channel 9 start 36Mb Jun 29 21:07:46: ath0: link state changed to UP Jun 29 21:08:46: ath0: received deauth from 00:11:22:33:44:55 rssi 59 Jun 29 21:08:46: [00:11:22:33:44:55] recv deauthenticate (reason 2) Jun 29 21:08:46: [00:11:22:33:44:55] send auth on channel 9 Jun 29 21:08:46: ath0: link state changed to DOWN Jun 29 21:08:46: ath0: received auth from 00:11:22:33:44:55 rssi 60 Jun 29 21:08:46: [00:11:22:33:44:55] recv auth frame with algorithm 0 seq 2 Jun 29 21:08:46: [00:11:22:33:44:55] send assoc_req on channel 9 Jun 29 21:08:46: ath0: received assoc_resp from 00:11:22:33:44:55 rssi 61 Jun 29 21:08:46: [00:11:22:33:44:55] assoc success: long preamble, short slot time Jun 29 21:08:46: ath0: associated with 00:11:22:33:44:55 ssid "MySSID" channel 9 start 36Mb Jun 29 21:08:46: ath0: link state changed to UP Jun 29 21:09:45: ath0: received deauth from 00:11:22:33:44:55 rssi 61 Jun 29 21:09:45: [00:11:22:33:44:55] recv deauthenticate (reason 2) Jun 29 21:09:45: [00:11:22:33:44:55] send auth on channel 9 Jun 29 21:09:45: ath0: link state changed to DOWN Jun 29 21:09:45: ath0: received auth from 00:11:22:33:44:55 rssi 60 Jun 29 21:09:45: [00:11:22:33:44:55] recv auth frame with algorithm 0 seq 2 Jun 29 21:09:45: [00:11:22:33:44:55] send assoc_req on channel 9 Jun 29 21:09:46: ath0: received assoc_resp from 00:11:22:33:44:55 rssi 60 Jun 29 21:09:46: [00:11:22:33:44:55] assoc success: long preamble, short slot time Jun 29 21:09:46: ath0: associated with 00:11:22:33:44:55 ssid "MySSID" channel 9 start 36Mb Jun 29 21:09:46: ath0: link state changed to UP