kern/170513: ath logs: ath_tx_aggr_comp_aggr: AR5416 bug:

Kim Culhan w8hdkim at gmail.com
Thu Aug 9 17:30:02 UTC 2012 

>Number:         170513
>Category:       kern
>Synopsis:       ath logs:  ath_tx_aggr_comp_aggr: AR5416 bug:
>Confidential:   no
>Severity:       non-critical
>Priority:       low
>Responsible:    freebsd-bugs
>State:          open
>Quarter:        
>Keywords:       
>Date-Required:
>Class:          update
>Submitter-Id:   current-users
>Arrival-Date:   Thu Aug 09 17:30:01 UTC 2012
>Closed-Date:
>Last-Modified:
>Originator:     Kim Culhan
>Release:        r239130M
>Organization:
>Environment:
FreeBSD foo at bar 10.0-CURRENT FreeBSD 10.0-CURRENT #7 r239130M: Thu Aug  9 08:18:10 EDT 2012
>Description:
The following appeared in /var/log/messages after a fresh boot and within
5 minutes of the start of transferring file data:

Aug  9 11:07:08 foo kernel: ath0: ath_tx_aggr_comp_aggr: AR5416 bug: hasba=0; txok=1, isaggr=1, seq_st=4004
Aug  9 11:07:08 foo kernel: Q1[  0] (DS.V:0xffffff808ff1b940 DS.P:0x691b940) L:0691b9a0 D:07e5cc5e F:0004
Aug  9 11:07:08 foo kernel: Seq: 64880 swtry: 1 ADDBAW?: 1 DOBAW?: 1
Aug  9 11:07:08 foo kernel: 407f0612 0001102c 24348000 898c8d8e 82df8290 8af983c6
Aug  9 11:07:08 foo kernel: 08000000 0097bdef 00000000 3f000000 3f000000 3f000000 00000000 00000000
Aug  9 11:07:08 foo kernel: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
Aug  9 11:07:08 foo kernel: (DS.V:0xffffff808ff1b9a0 DS.P:0x691b9a0) L:0691ba00 D:6cf9cc80 F:0004
Aug  9 11:07:08 foo kernel: Seq: 64880 swtry: 1 ADDBAW?: 1 DOBAW?: 1
Aug  9 11:07:08 foo kernel: 00000000 00001028 00000000 00000000 00000000 00000000
Aug  9 11:07:08 foo kernel: 08280000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
Aug  9 11:07:08 foo kernel: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
Aug  9 11:07:08 foo kernel: (DS.V:0xffffff808ff1ba00 DS.P:0x691ba00) L:06918c00 D:6c99c9f0 F:0004
Aug  9 11:07:08 foo kernel: Seq: 64880 swtry: 1 ADDBAW?: 1 DOBAW?: 1
Aug  9 11:07:08 foo kernel: 00000000 000005b4 24348000 898c8d8e 00000000 00000000
Aug  9 11:07:08 foo kernel: 08280000 00000000 00000000 00000000 00000000 00000000 0080302a 00000001
Aug  9 11:07:08 foo kernel: 29c9e43d ffffffff fff7ffff 30802c2c 80808080 80808080 80808080 00001f49
Aug  9 11:07:08 foo kernel: [end]
Aug  9 11:07:08 foo kernel: ath0: ath_tx_aggr_comp_aggr: num frames seen=1; bf nframes=13
Aug  9 11:07:16 foo kernel: ath0: ath_tx_aggr_comp_aggr: AR5416 bug: hasba=0; txok=1, isaggr=1, seq_st=1229
Aug  9 11:07:16 foo kernel: Q1[  0] (DS.V:0xffffff808ff69000 DS.P:0x6969000) L:06969060 D:07e6025e F:0004
Aug  9 11:07:16 foo kernel: Seq: 20496 swtry: 1 ADDBAW?: 1 DOBAW?: 1
Aug  9 11:07:16 foo kernel: 407f0612 0001102c 24348000 898d8e8f 8241820b 89948285
Aug  9 11:07:16 foo kernel: 08000000 0097bdef 00000000 3f000000 3f000000 3f000000 00000000 00000000
Aug  9 11:07:16 foo kernel: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
Aug  9 11:07:16 foo kernel: (DS.V:0xffffff808ff69060 DS.P:0x6969060) L:069690c0 D:07e11a80 F:0004
Aug  9 11:07:16 foo kernel: Seq: 20496 swtry: 1 ADDBAW?: 1 DOBAW?: 1
Aug  9 11:07:16 foo kernel: 00000000 00001028 00000000 00000000 00000000 00000000
Aug  9 11:07:16 foo kernel: 08280000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
Aug  9 11:07:16 foo kernel: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
Aug  9 11:07:16 foo kernel: (DS.V:0xffffff808ff690c0 DS.P:0x69690c0) L:069600c0 D:6c8ca000 F:0004
Aug  9 11:07:16 foo kernel: Seq: 20496 swtry: 1 ADDBAW?: 1 DOBAW?: 1
Aug  9 11:07:16 foo kernel: 00000000 000005b4 24348000 898d8e8f 00000000 00000000
Aug  9 11:07:16 foo kernel: 08280000 00000000 00000000 00000000 00000000 00000000 0080312a 00000001
Aug  9 11:07:16 foo kernel: 2a4998af ffffffff 87cfffff 31802e2c 80808080 80808080 80808080 0000099b
Aug  9 11:07:16 foo kernel: [end]
Aug  9 11:07:16 foo kernel: ath0: ath_tx_aggr_comp_aggr: num frames seen=1; bf nframes=12
Aug  9 11:07:20 foo kernel: ath0: ath_tx_aggr_comp_aggr: AR5416 bug: hasba=0; txok=1, isaggr=1, seq_st=460
Aug  9 11:07:20 foo kernel: Q1[  0] (DS.V:0xffffff808ff0ea40 DS.P:0x690ea40) L:0690eaa0 D:6cf9b15e F:0004
Aug  9 11:07:20 foo kernel: Seq: 8032 swtry: 1 ADDBAW?: 1 DOBAW?: 1
Aug  9 11:07:20 foo kernel: 407f0612 0001102c 24348000 898c8d8e 821981e3 87e882bf
Aug  9 11:07:20 foo kernel: 08000000 0097bdef 00000000 3f000000 3f000000 3f000000 00000000 00000000
Aug  9 11:07:20 foo kernel: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
Aug  9 11:07:20 foo kernel: (DS.V:0xffffff808ff0eaa0 DS.P:0x690eaa0) L:0690eb00 D:6cf9b480 F:0004
Aug  9 11:07:20 foo kernel: Seq: 8032 swtry: 1 ADDBAW?: 1 DOBAW?: 1
Aug  9 11:07:20 foo kernel: 00000000 00001028 00000000 00000000 00000000 00000000
Aug  9 11:07:20 foo kernel: 08280000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
Aug  9 11:07:20 foo kernel: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
Aug  9 11:07:20 foo kernel: (DS.V:0xffffff808ff0eb00 DS.P:0x690eb00) L:0690eb60 D:6cf39b68 F:0004
Aug  9 11:07:20 foo kernel: Seq: 8032 swtry: 1 ADDBAW?: 1 DOBAW?: 1
Aug  9 11:07:20 foo kernel: 00000000 00001498 00000000 00000000 00000000 00000000
Aug  9 11:07:20 foo kernel: 08280000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
Aug  9 11:07:20 foo kernel: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
Aug  9 11:07:20 foo kernel: (DS.V:0xffffff808ff0eb60 DS.P:0x690eb60) L:069280c0 D:6cf8e000 F:0004
Aug  9 11:07:20 foo kernel: Seq: 8032 swtry: 1 ADDBAW?: 1 DOBAW?: 1
Aug  9 11:07:20 foo kernel: 00000000 0000011c 24348000 898c8d8e 00000000 00000000
Aug  9 11:07:20 foo kernel: 08280000 00000000 00000000 00000000 00000000 00000000 0080312a 00000001
Aug  9 11:07:20 foo kernel: 2a889534 ffffffff fffffbff 31802d2c 80808080 80808080 80808080 00000399
Aug  9 11:07:20 foo kernel: [end]
Aug  9 11:07:20 foo kernel: ath0: ath_tx_aggr_comp_aggr: num frames seen=1; bf nframes=10

Several transfers of the same file in each direction failed to produce any
further logging of this information.

The machine was rebooted and a transfer was done from the
client machine --> ath test machine

No data was logged.

A transfer was started from
ath test machine --> client

Within a few seconds the following data was logged:

Aug  9 13:07:48 foo kernel: ath0: ath_tx_aggr_comp_aggr: AR5416 bug: hasba=0; txok=1, isaggr=1, seq_st=3847
Aug  9 13:07:48 foo kernel: Q1[  0] (DS.V:0xffffff808ff32a40 DS.P:0x6932a40) L:06932aa0 D:07ec975e F:0004
Aug  9 13:07:48 foo kernel: Seq: 62192 swtry: 1 ADDBAW?: 1 DOBAW?: 1
Aug  9 13:07:49 foo kernel: 407f0612 0001102c 24348000 898c8d8e 8094808a 81d180b8
Aug  9 13:07:49 foo kernel: 08000000 0097bdef 00000000 3f000000 3f000000 3f000000 00000000 00000000
Aug  9 13:07:49 foo kernel: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
Aug  9 13:07:49 foo kernel: (DS.V:0xffffff808ff32aa0 DS.P:0x6932aa0) L:06932b00 D:719cf880 F:0004
Aug  9 13:07:49 foo kernel: Seq: 62192 swtry: 1 ADDBAW?: 1 DOBAW?: 1
Aug  9 13:07:49 foo kernel: 00000000 00001028 00000000 00000000 00000000 00000000
Aug  9 13:07:49 foo kernel: 08280000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
Aug  9 13:07:49 foo kernel: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
Aug  9 13:07:49 foo kernel: (DS.V:0xffffff808ff32b00 DS.P:0x6932b00) L:06948840 D:71ce1000 F:0004
Aug  9 13:07:49 foo kernel: Seq: 62192 swtry: 1 ADDBAW?: 1 DOBAW?: 1
Aug  9 13:07:49 foo kernel: 00000000 000005b4 24348000 898c8d8e 00000000 00000000
Aug  9 13:07:49 foo kernel: 08280000 00000000 00000000 00000000 00000000 00000000 00803128 00000101
Aug  9 13:07:49 foo kernel: 0e153391 ffffffff fdfffeff 3280302b 80808080 80808080 80808080 00001e0f
Aug  9 13:07:49 foo kernel: [end]
Aug  9 13:07:49 foo kernel: ath0: ath_tx_aggr_comp_aggr: num frames seen=1; bf nframes=2
Aug  9 13:07:57 foo kernel: ath0: ath_tx_aggr_comp_aggr: AR5416 bug: hasba=0; txok=1, isaggr=1, seq_st=2684
Aug  9 13:07:57 foo kernel: Q1[  0] (DS.V:0xffffff808ff2c0c0 DS.P:0x692c0c0) L:0692c120 D:07ab245e F:0004
Aug  9 13:07:57 foo kernel: Seq: 43648 swtry: 1 ADDBAW?: 1 DOBAW?: 1
Aug  9 13:07:57 foo kernel: 407f0612 0001102c 24348000 888b8c8d 823281b1 8c528336
Aug  9 13:07:57 foo kernel: 08000000 0097bdef 00000000 3f000000 3f000000 3f000000 00000000 00000000
Aug  9 13:07:57 foo kernel: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
Aug  9 13:07:57 foo kernel: (DS.V:0xffffff808ff2c120 DS.P:0x692c120) L:0692c180 D:719d4c80 F:0004
Aug  9 13:07:57 foo kernel: Seq: 43648 swtry: 1 ADDBAW?: 1 DOBAW?: 1
Aug  9 13:07:57 foo kernel: 00000000 00001028 00000000 00000000 00000000 00000000
Aug  9 13:07:57 foo kernel: 08280000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
Aug  9 13:07:57 foo kernel: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
Aug  9 13:07:57 foo kernel: (DS.V:0xffffff808ff2c180 DS.P:0x692c180) L:069180c0 D:719316d0 F:0004
Aug  9 13:07:57 foo kernel: Seq: 43648 swtry: 1 ADDBAW?: 1 DOBAW?: 1
Aug  9 13:07:57 foo kernel: 00000000 000005b4 24348000 888b8c8d 00000000 00000000
Aug  9 13:07:57 foo kernel: 08280000 00000000 00000000 00000000 00000000 00000000 00803128 00000001
Aug  9 13:07:57 foo kernel: 0e9acbcc ffffffff ffffefff 31802f2a 80808080 80808080 80808080 000014f9
Aug  9 13:07:57 foo kernel: [end]
Aug  9 13:07:57 foo kernel: ath0: ath_tx_aggr_comp_aggr: num frames seen=1; bf nframes=8
Aug  9 13:08:05 foo kernel: ath0: ath_tx_aggr_comp_aggr: AR5416 bug: hasba=0; txok=1, isaggr=1, seq_st=144
Aug  9 13:08:05 foo kernel: Q1[  0] (DS.V:0xffffff808ff1bd00 DS.P:0x691bd00) L:0691bd60 D:07ab815e F:0004
Aug  9 13:08:05 foo kernel: Seq: 3136 swtry: 1 ADDBAW?: 1 DOBAW?: 1
Aug  9 13:08:05 foo kernel: 407f0612 0001102c 24348000 888b8c8d 826181d5 8d76837e
Aug  9 13:08:05 foo kernel: 08000000 0097bdef 00000000 3f000000 3f000000 3f000000 00000000 00000000
Aug  9 13:08:05 foo kernel: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
Aug  9 13:08:05 foo kernel: (DS.V:0xffffff808ff1bd60 DS.P:0x691bd60) L:0691bdc0 D:07eca180 F:0004
Aug  9 13:08:05 foo kernel: Seq: 3136 swtry: 1 ADDBAW?: 1 DOBAW?: 1
Aug  9 13:08:05 foo kernel: 00000000 00001028 00000000 00000000 00000000 00000000
Aug  9 13:08:05 foo kernel: 08280000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
Aug  9 13:08:05 foo kernel: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
Aug  9 13:08:05 foo kernel: (DS.V:0xffffff808ff1bdc0 DS.P:0x691bdc0) L:0692fd00 D:71ad0000 F:0004
Aug  9 13:08:05 foo kernel: Seq: 3136 swtry: 1 ADDBAW?: 1 DOBAW?: 1
Aug  9 13:08:05 foo kernel: 00000000 000005b4 24348000 888b8c8d 00000000 00000000
Aug  9 13:08:05 foo kernel: 08280000 00000000 00000000 00000000 00000000 00000000 00803025 00000001
Aug  9 13:08:05 foo kernel: 0f164c49 ffffffff ffefffff 30802f29 80808080 80808080 80808080 00000121
Aug  9 13:08:05 foo kernel: [end]
Aug  9 13:08:05 foo kernel: ath0: ath_tx_aggr_comp_aggr: num frames seen=1; bf nframes=8
Aug  9 13:08:09 foo kernel: ath0: ath_tx_aggr_comp_aggr: AR5416 bug: hasba=0; txok=1, isaggr=1, seq_st=980
Aug  9 13:08:09 foo kernel: Q1[  0] (DS.V:0xffffff808fefc840 DS.P:0x68fc840) L:068fc8a0 D:07ab635e F:0004
Aug  9 13:08:09 foo kernel: Seq: 16512 swtry: 1 ADDBAW?: 1 DOBAW?: 1
Aug  9 13:08:09 foo kernel: 407f0612 0001102c 24348000 898c8d8e 821981e3 87e882bf
Aug  9 13:08:09 foo kernel: 08000000 0097bdef 00000000 3f000000 3f000000 3f000000 00000000 00000000
Aug  9 13:08:09 foo kernel: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
Aug  9 13:08:09 foo kernel: (DS.V:0xffffff808fefc8a0 DS.P:0x68fc8a0) L:068fc900 D:71cf0a80 F:0004
Aug  9 13:08:09 foo kernel: Seq: 16512 swtry: 1 ADDBAW?: 1 DOBAW?: 1
Aug  9 13:08:09 foo kernel: 00000000 00001028 00000000 00000000 00000000 00000000
Aug  9 13:08:09 foo kernel: 08280000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
Aug  9 13:08:09 foo kernel: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
Aug  9 13:08:09 foo kernel: (DS.V:0xffffff808fefc900 DS.P:0x68fc900) L:068fc960 D:71ad0b68 F:0004
Aug  9 13:08:09 foo kernel: Seq: 16512 swtry: 1 ADDBAW?: 1 DOBAW?: 1
Aug  9 13:08:09 foo kernel: 00000000 00001498 00000000 00000000 00000000 00000000
Aug  9 13:08:09 foo kernel: 08280000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
Aug  9 13:08:09 foo kernel: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000
Aug  9 13:08:09 foo kernel: (DS.V:0xffffff808fefc960 DS.P:0x68fc960) L:06908c00 D:71cdf000 F:0004
Aug  9 13:08:09 foo kernel: Seq: 16512 swtry: 1 ADDBAW?: 1 DOBAW?: 1
Aug  9 13:08:09 foo kernel: 00000000 0000011c 24348000 898c8d8e 00000000 00000000
Aug  9 13:08:09 foo kernel: 08280000 00000000 00000000 00000000 00000000 00000000 00803028 00000001
Aug  9 13:08:09 foo kernel: 0f4932eb ffffffff ffefffff 30802e2a 80808080 80808080 80808080 000007a9
Aug  9 13:08:09 foo kernel: [end]
Aug  9 13:08:09 foo kernel: ath0: ath_tx_aggr_comp_aggr: num frames seen=1; bf nframes=10

~5 minutes later the same file was transferred in the same direction
producing this logging output:

Aug  9 13:13:37 foo kernel: ath0: ath_tx_aggr_comp_aggr: AR5416 bug: hasba=0; txok=1, isaggr=1, seq_st=50    
Aug  9 13:13:37 foo kernel: Q1[  0] (DS.V:0xffffff808ff3ee00 DS.P:0x693ee00) L:0693ee60 D:07ab285e F:0004    
Aug  9 13:13:37 foo kernel: Seq: 1776 swtry: 1 ADDBAW?: 1 DOBAW?: 1    
Aug  9 13:13:37 foo kernel: 407f0612 0001102c 24348000 898c8d8e 80a68098 821680ce    
Aug  9 13:13:37 foo kernel: 08000000 0097bdef 00000000 3f000000 3f000000 3f000000 00000000 00000000    
Aug  9 13:13:37 foo kernel: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000    
Aug  9 13:13:37 foo kernel: (DS.V:0xffffff808ff3ee60 DS.P:0x693ee60) L:0693eec0 D:71cf0080 F:0004    
Aug  9 13:13:37 foo kernel: Seq: 1776 swtry: 1 ADDBAW?: 1 DOBAW?: 1    
Aug  9 13:13:37 foo kernel: 00000000 00001028 00000000 00000000 00000000 00000000    
Aug  9 13:13:37 foo kernel: 08280000 00000000 00000000 00000000 00000000 00000000 00000000 00000000    
Aug  9 13:13:37 foo kernel: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000    
Aug  9 13:13:37 foo kernel: (DS.V:0xffffff808ff3eec0 DS.P:0x693eec0) L:0692e2c0 D:71939354 F:0004    
Aug  9 13:13:37 foo kernel: Seq: 1776 swtry: 1 ADDBAW?: 1 DOBAW?: 1    
Aug  9 13:13:37 foo kernel: 00000000 000005b4 24348000 898c8d8e 00000000 00000000    
Aug  9 13:13:37 foo kernel: 08280000 00000000 00000000 00000000 00000000 00000000 00803128 00000001    
Aug  9 13:13:37 foo kernel: 22decd06 ffffffff dfffffff 3180302a 80808080 80808080 80808080 00000065    
Aug  9 13:13:37 foo kernel: [end]    
Aug  9 13:13:37 foo kernel: ath0: ath_tx_aggr_comp_aggr: num frames seen=1; bf nframes=3

3 more transfers of the same type were done with no additional info logged.

>How-To-Repeat:

Transfer a 300MB file using the sftp protocol over a wifi link running
the ath wireless nic driver.

>Fix:


>Release-Note:
>Audit-Trail:
>Unformatted:

More information about the freebsd-bugs mailing list