Comment 154 for bug 414560

Revision history for this message
Tyrael (marco-crociani) wrote : Re: ath9k disassociates/reassociates a lot

I still have problem with backports.
04:00.0 Network controller: Atheros Communications Inc. AR928X Wireless Network Adapter (PCI-Express) (rev 01)

[ 425.750135] cfg80211: Calling CRDA to update world regulatory domain
[ 425.829054] cfg80211: World regulatory domain updated:
[ 425.829064] (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
[ 425.829072] (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[ 425.829079] (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
[ 425.829085] (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
[ 425.829092] (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[ 425.829098] (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[ 425.854324] ACPI: PCI Interrupt Link [LN3A] enabled at IRQ 19
[ 425.854337] alloc irq_desc for 19 on node 0
[ 425.854342] alloc kstat_irqs on node 0
[ 425.854360] ath9k 0000:04:00.0: PCI INT A -> Link[LN3A] -> GSI 19 (level, low) -> IRQ 19
[ 425.854379] ath9k 0000:04:00.0: setting latency timer to 64
[ 426.281351] ath: EEPROM regdomain: 0x60
[ 426.281359] ath: EEPROM indicates we should expect a direct regpair map
[ 426.281367] ath: Country alpha2 being used: 00
[ 426.281372] ath: Regpair used: 0x60
[ 426.308647] phy0: Selected rate control algorithm 'ath9k_rate_control'
[ 426.310561] Registered led device: ath9k-phy0::radio
[ 426.310619] Registered led device: ath9k-phy0::assoc
[ 426.310663] Registered led device: ath9k-phy0::tx
[ 426.310707] Registered led device: ath9k-phy0::rx
[ 426.310754] phy0: Atheros AR9280 MAC/BB Rev:2 AR5133 RF Rev:d0: mem=0xffffc90003180000, irq=19
[ 426.403348] ADDRCONF(NETDEV_UP): wlan0: link is not ready
[ 433.831105] wlan0: deauthenticating from 00:22:6b:62:72:0a by local choice (reason=3)
[ 433.831271] wlan0: direct probe to AP 00:22:6b:62:72:0a (try 1)
[ 433.838957] wlan0: direct probe responded
[ 433.838971] wlan0: authenticate with AP 00:22:6b:62:72:0a (try 1)
[ 433.841197] wlan0: authenticated
[ 433.841246] wlan0: associate with AP 00:22:6b:62:72:0a (try 1)
[ 433.845758] wlan0: RX AssocResp from 00:22:6b:62:72:0a (capab=0x411 status=0 aid=4)
[ 433.845766] wlan0: associated
[ 433.856893] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[21989.542769] No probe response from AP 00:22:6b:62:72:0a after 500ms, disconnecting.
[21991.027345] wlan0: direct probe to AP 00:22:6b:62:72:0a (try 1)
[21991.032625] wlan0: direct probe responded
[21991.032640] wlan0: authenticate with AP 00:22:6b:62:72:0a (try 1)
[21991.035500] wlan0: authenticated
[21991.035586] wlan0: associate with AP 00:22:6b:62:72:0a (try 1)
[21991.041377] wlan0: RX ReassocResp from 00:22:6b:62:72:0a (capab=0x411 status=0 aid=3)
[21991.041389] wlan0: associated
[34338.441416] wlan0: deauthenticating from 00:22:6b:62:72:0a by local choice (reason=3)
[34338.442541] wlan0: direct probe to AP 00:22:6b:62:72:0a (try 1)
[34338.447802] wlan0: direct probe responded
[34338.447813] wlan0: authenticate with AP 00:22:6b:62:72:0a (try 1)
[34338.450039] wlan0: authenticated
[34338.478052] wlan0: deauthenticating from 00:22:6b:62:72:0a by local choice (reason=3)
[34338.704670] wlan0: deauthenticating from 00:22:6b:62:72:0a by local choice (reason=3)
[34338.922693] wlan0: direct probe to AP 00:22:6b:62:72:0a (try 1)
[34338.927927] wlan0: direct probe responded
[34338.927936] wlan0: authenticate with AP 00:22:6b:62:72:0a (try 1)
[34338.930753] wlan0: authenticated
[34338.930833] wlan0: associate with AP 00:22:6b:62:72:0a (try 1)
[34338.935095] wlan0: RX AssocResp from 00:22:6b:62:72:0a (capab=0x411 status=0 aid=3)
[34338.935108] wlan0: associated
[39195.435983] ath9k: DMA failed to stop in 10 ms AR_CR=0x00000024 AR_DIAG_SW=0x40000020
[40410.540047] No probe response from AP 00:22:6b:62:72:0a after 500ms, disconnecting.
[40443.310140] wlan0: direct probe to AP 00:22:6b:62:72:0a (try 1)
[40443.314387] wlan0: direct probe responded
[40443.314398] wlan0: authenticate with AP 00:22:6b:62:72:0a (try 1)
[40443.317155] wlan0: authenticated
[40443.317240] wlan0: associate with AP 00:22:6b:62:72:0a (try 1)
[40443.321922] wlan0: RX ReassocResp from 00:22:6b:62:72:0a (capab=0x411 status=0 aid=1)
[40443.321955] wlan0: associated
[40444.677144] wlan0: deauthenticated from 00:22:6b:62:72:0a (Reason: 14)
[40446.131071] wlan0: direct probe to AP 00:22:6b:62:72:0a (try 1)
[40446.136361] wlan0: direct probe responded
[40446.136374] wlan0: authenticate with AP 00:22:6b:62:72:0a (try 1)
[40446.138621] wlan0: authenticated
[40446.138669] wlan0: associate with AP 00:22:6b:62:72:0a (try 1)
[40446.144675] wlan0: RX ReassocResp from 00:22:6b:62:72:0a (capab=0x411 status=12 aid=0)
[40446.144685] wlan0: AP denied association (code=12)
[42723.390886] dropbox invoked oom-killer: gfp_mask=0x201da, order=0, oomkilladj=0
[42723.390901] dropbox cpuset=/ mems_allowed=0
[42723.390911] Pid: 2373, comm: dropbox Tainted: P 2.6.31-15-generic #50-Ubuntu
[42723.390919] Call Trace:
[42723.390943] [<ffffffff810a27a8>] ? cpuset_print_task_mems_allowed+0x98/0xa0
[42723.390957] [<ffffffff810dd7de>] oom_kill_process+0xce/0x290
[42723.390969] [<ffffffff810ddd4a>] ? select_bad_process+0xea/0x120
[42723.390982] [<ffffffff810dddd0>] __out_of_memory+0x50/0xb0
[42723.390993] [<ffffffff810ddf56>] out_of_memory+0x126/0x1a0
[42723.391007] [<ffffffff81529a09>] ? _spin_lock+0x9/0x10
[42723.391020] [<ffffffff810e0818>] __alloc_pages_slowpath+0x498/0x4e0
[42723.391033] [<ffffffff810e09ae>] __alloc_pages_nodemask+0x14e/0x150
[42723.391047] [<ffffffff8110ceb2>] alloc_pages_current+0x82/0xd0
[42723.391058] [<ffffffff810da93f>] __page_cache_alloc+0x5f/0x70
[42723.391070] [<ffffffff810e4611>] __do_page_cache_readahead+0xc1/0x160
[42723.391082] [<ffffffff810e46cc>] ra_submit+0x1c/0x20
[42723.391164] [<ffffffff810da43b>] do_sync_mmap_readahead+0x9b/0xd0
[42723.391178] [<ffffffff810dc5a4>] filemap_fault+0x314/0x3c0
[42723.391190] [<ffffffff810f424f>] __do_fault+0x4f/0x4e0
[42723.391202] [<ffffffff810f89e7>] handle_mm_fault+0x1a7/0x3c0
[42723.391215] [<ffffffff81036419>] ? default_spin_lock_flags+0x9/0x10
[42723.391227] [<ffffffff8152c5aa>] do_page_fault+0x16a/0x370
[42723.391240] [<ffffffff81529f25>] page_fault+0x25/0x30
[42723.391250] Mem-Info:
[42723.391256] Node 0 DMA per-cpu:
[42723.391265] CPU 0: hi: 0, btch: 1 usd: 0
[42723.391273] CPU 1: hi: 0, btch: 1 usd: 0
[42723.391281] CPU 2: hi: 0, btch: 1 usd: 0
[42723.391288] CPU 3: hi: 0, btch: 1 usd: 0
[42723.391294] Node 0 DMA32 per-cpu:
[42723.391303] CPU 0: hi: 186, btch: 31 usd: 130
[42723.391310] CPU 1: hi: 186, btch: 31 usd: 30
[42723.391318] CPU 2: hi: 186, btch: 31 usd: 181
[42723.391325] CPU 3: hi: 186, btch: 31 usd: 0
[42723.391339] Active_anon:579217 active_file:142 inactive_anon:145229
[42723.391343] inactive_file:192 unevictable:0 dirty:0 writeback:0 unstable:0
[42723.391348] free:4828 slab:10815 mapped:1402 pagetables:7263 bounce:0
[42723.391356] Node 0 DMA free:12076kB min:32kB low:40kB high:48kB active_anon:1720kB inactive_anon:1852kB active_file:24kB inactive_file:160kB unevictable:0kB present:15300kB pages_scanned:0 all_unreclaimable? no
[42723.391376] lowmem_reserve[]: 0 3013 3013 3013
[42723.391389] Node 0 DMA32 free:7236kB min:7008kB low:8760kB high:10512kB active_anon:2315148kB inactive_anon:579064kB active_file:544kB inactive_file:608kB unevictable:0kB present:3086180kB pages_scanned:160 all_unreclaimable? no
[42723.391408] lowmem_reserve[]: 0 0 0 0
[42723.391420] Node 0 DMA: 3*4kB 2*8kB 3*16kB 5*32kB 3*64kB 1*128kB 3*256kB 1*512kB 2*1024kB 2*2048kB 1*4096kB = 12076kB
[42723.391451] Node 0 DMA32: 368*4kB 2*8kB 3*16kB 2*32kB 4*64kB 1*128kB 1*256kB 0*512kB 1*1024kB 0*2048kB 1*4096kB = 7360kB
[42723.391483] 3577 total pagecache pages
[42723.391489] 2562 pages in swap cache
[42723.391496] Swap cache stats: add 281317, delete 278755, find 2922/6126
[42723.391502] Free swap = 0kB
[42723.391507] Total swap = 999992kB
[42723.417870] 786320 pages RAM
[42723.417878] 13390 pages reserved
[42723.417884] 2022 pages shared
[42723.417890] 765729 pages non-shared
[42723.417900] Out of memory: kill process 2399 (bash) score 689557 or a child
[42723.418044] Killed process 2444 (amule)