Heavy I/O causes page allocation failiure in ath9k module

Bug #385048 reported by Erik Meitner
18
This bug affects 4 people
Affects Status Importance Assigned to Milestone
linux-backports-modules-2.6.28 (Ubuntu)
Confirmed
Undecided
Unassigned

Bug Description

To reproduce:
1. Bring up the wireless interface.
2. Run any command that does heavy I/O, eg. "dd if=/dev/sda of=/dev/null"
3. Wait a few seconds

Always reproduceable.

The wireless continues to work after the problem occurs.

See the attached log.

System is a Lenovo Thinkpad T60 8744-5bu.

03:00.0 Network controller: Atheros Communications Inc. AR5008 Wireless Network Adapter (168c:0024) (rev 01)
 Subsystem: Atheros Communications Inc. Device 0033
 Flags: bus master, fast devsel, latency 0, IRQ 17
 Memory at edf00000 (64-bit, non-prefetchable) [size=64K]
 Capabilities: [40] Power Management version 2
 Capabilities: [50] Message Signalled Interrupts: Mask- 64bit- Queue=0/0 Enable-
 Capabilities: [60] Express Legacy Endpoint, MSI 00
 Capabilities: [90] MSI-X: Enable- Mask- TabSize=1
 Capabilities: [100] Advanced Error Reporting <?>
 Capabilities: [140] Virtual Channel <?>
 Kernel driver in use: ath9k
 Kernel modules: ath9k

ProblemType: Bug
Architecture: i386
DistroRelease: Ubuntu 9.04
Package: linux-backports-modules-2.6.28-12-generic 2.6.28-12.13
ProcEnviron:
 SHELL=/bin/bash
 PATH=(custom, user)
 LANG=en_US.UTF-8
SourcePackage: linux-backports-modules-2.6.28
Uname: Linux 2.6.28-12-generic i686

Revision history for this message
Erik Meitner (e.meitner) wrote :
Revision history for this message
Erik Meitner (e.meitner) wrote :

Easily reproducable on a fresh boot.

Another way to reproduce:
1. rmmod ath9k
2. "dd if=/dev/sda of=/dev/null"
Ran with no problem
3. modprobe ath9k
4. immediately got:
Jun 9 08:07:21 cheren kernel: [ 1534.014840] modprobe: page allocation failure. order:5, mode:0x80d0
Jun 9 08:07:21 cheren kernel: [ 1534.014847] Pid: 7371, comm: modprobe Not tainted 2.6.28-12-generic #43-Ubuntu
Jun 9 08:07:21 cheren kernel: [ 1534.014852] Call Trace:
Jun 9 08:07:21 cheren kernel: [ 1534.014864] [<c04fca36>] ? printk+0x18/0x1a
Jun 9 08:07:21 cheren kernel: [ 1534.014872] [<c0194287>] __alloc_pages_internal+0x387/0x490
Jun 9 08:07:21 cheren kernel: [ 1534.014881] [<c01087d3>] dma_generic_alloc_coherent+0x73/0xe0
...snip...

Revision history for this message
Erik Meitner (e.meitner) wrote :
Download full text (11.7 KiB)

This is still reproducible with these updates installed:
linux-image-2.6.28-13-generic
linux-backports-modules-2.6.28-13-generic

Simply do: cat /dev/sda > /dev/null
And get:
Jun 18 21:38:09 cheren kernel: [146818.176740] swapper: page allocation failure. order:1, mode:0x4020
Jun 18 21:38:09 cheren kernel: [146818.176748] Pid: 0, comm: swapper Tainted: G W 2.6.28-13-generic #44-Ubuntu
Jun 18 21:38:09 cheren kernel: [146818.176752] Call Trace:
Jun 18 21:38:09 cheren kernel: [146818.176765] [<c04fca36>] ? printk+0x18/0x1a
Jun 18 21:38:09 cheren kernel: [146818.176773] [<c0194287>] __alloc_pages_internal+0x387/0x490
Jun 18 21:38:09 cheren kernel: [146818.176780] [<c01943e1>] __get_free_pages+0x21/0x40
Jun 18 21:38:09 cheren kernel: [146818.176787] [<c01b8c2e>] __kmalloc_track_caller+0xee/0xf0
Jun 18 21:38:09 cheren kernel: [146818.176806] [<faaf1dde>] ? ath_rxbuf_alloc+0x2e/0x80 [ath9k]
Jun 18 21:38:09 cheren kernel: [146818.176814] [<c0427ecd>] __alloc_skb+0x4d/0x110
Jun 18 21:38:09 cheren kernel: [146818.176830] [<faaf1dde>] ath_rxbuf_alloc+0x2e/0x80 [ath9k]
Jun 18 21:38:09 cheren kernel: [146818.176846] [<faaf2215>] ath_rx_tasklet+0x345/0x6c0 [ath9k]
Jun 18 21:38:09 cheren kernel: [146818.176854] [<c0119a73>] ? lapic_next_event+0x13/0x20
Jun 18 21:38:09 cheren kernel: [146818.176870] [<faaef8e6>] ath9k_tasklet+0x76/0xb0 [ath9k]
Jun 18 21:38:09 cheren kernel: [146818.176878] [<c013ed18>] tasklet_action+0x78/0x100
Jun 18 21:38:09 cheren kernel: [146818.176884] [<c013f197>] __do_softirq+0x97/0x170
Jun 18 21:38:09 cheren kernel: [146818.176891] [<c011c7df>] ? ack_apic_level+0x6f/0x290
Jun 18 21:38:09 cheren kernel: [146818.176897] [<c013f2cd>] do_softirq+0x5d/0x60
Jun 18 21:38:09 cheren kernel: [146818.176903] [<c013f445>] irq_exit+0x55/0x90
Jun 18 21:38:09 cheren kernel: [146818.176910] [<c0106853>] do_IRQ+0x83/0xa0
Jun 18 21:38:09 cheren kernel: [146818.176915] [<c01051f3>] common_interrupt+0x23/0x30
Jun 18 21:38:09 cheren kernel: [146818.176923] [<c0319467>] ? acpi_idle_enter_bm+0x269/0x2b8
Jun 18 21:38:09 cheren kernel: [146818.176930] [<c040b44f>] cpuidle_idle_call+0x6f/0xd0
Jun 18 21:38:09 cheren kernel: [146818.176936] [<c010285d>] cpu_idle+0x6d/0xd0
Jun 18 21:38:09 cheren kernel: [146818.176943] [<c04ed07e>] rest_init+0x4e/0x60
Jun 18 21:38:09 cheren kernel: [146818.176947] Mem-Info:
Jun 18 21:38:09 cheren kernel: [146818.176950] DMA per-cpu:
Jun 18 21:38:09 cheren kernel: [146818.176954] CPU 0: hi: 0, btch: 1 usd: 0
Jun 18 21:38:09 cheren kernel: [146818.176958] CPU 1: hi: 0, btch: 1 usd: 0
Jun 18 21:38:09 cheren kernel: [146818.176962] Normal per-cpu:
Jun 18 21:38:09 cheren kernel: [146818.176965] CPU 0: hi: 186, btch: 31 usd: 152
Jun 18 21:38:09 cheren kernel: [146818.176970] CPU 1: hi: 186, btch: 31 usd: 20
Jun 18 21:38:09 cheren kernel: [146818.176973] HighMem per-cpu:
Jun 18 21:38:09 cheren kernel: [146818.176976] CPU 0: hi: 42, btch: 7 usd: 1
Jun 18 21:38:09 cheren kernel: [146818.176980] CPU 1: hi: 42, btch: 7 usd: 7
Jun 18 21:38:09 cheren kernel: [146818.176987] Active_anon:78037 active_file:23834 inactive_anon:80474
Jun 18 21:38:09 cher...

Revision history for this message
Erik Meitner (e.meitner) wrote :
Download full text (6.1 KiB)

I removed all linux-backports-modules-* packages and the problem is still reproducible. Again, doing "cat /dev/sda > /dev/null " causes this only when the ath9k module is loaded.

Jul 1 07:43:59 cheren kernel: [ 418.094914] __ratelimit: 6 callbacks suppressed
Jul 1 07:43:59 cheren kernel: [ 418.094920] swapper: page allocation failure. order:1, mode:0x4020
Jul 1 07:43:59 cheren kernel: [ 418.094927] Pid: 0, comm: swapper Not tainted 2.6.28-13-generic #44-Ubuntu
Jul 1 07:43:59 cheren kernel: [ 418.094931] Call Trace:
Jul 1 07:43:59 cheren kernel: [ 418.094943] [<c04fca36>] ? printk+0x18/0x1a
Jul 1 07:43:59 cheren kernel: [ 418.094951] [<c0194287>] __alloc_pages_internal+0x387/0x490
Jul 1 07:43:59 cheren kernel: [ 418.094959] [<c01b7ebc>] ? __slab_alloc+0xcc/0x260
Jul 1 07:43:59 cheren kernel: [ 418.094965] [<c01943e1>] __get_free_pages+0x21/0x40
Jul 1 07:43:59 cheren kernel: [ 418.094971] [<c01b8c2e>] __kmalloc_track_caller+0xee/0xf0
Jul 1 07:43:59 cheren kernel: [ 418.094979] [<c0427eac>] ? __alloc_skb+0x2c/0x110
Jul 1 07:43:59 cheren kernel: [ 418.094986] [<c042818c>] ? dev_alloc_skb+0x1c/0x30
Jul 1 07:43:59 cheren kernel: [ 418.094992] [<c0427ecd>] __alloc_skb+0x4d/0x110
Jul 1 07:43:59 cheren kernel: [ 418.094999] [<c042818c>] dev_alloc_skb+0x1c/0x30
Jul 1 07:43:59 cheren kernel: [ 418.095031] [<f8019bc0>] ath_rxbuf_alloc+0x20/0x80 [ath9k]
Jul 1 07:43:59 cheren kernel: [ 418.095053] [<f8019c4c>] ath_rx_indicate+0x2c/0xd0 [ath9k]
Jul 1 07:43:59 cheren kernel: [ 418.095074] [<f801a01d>] ath_rx_tasklet+0x32d/0x520 [ath9k]
Jul 1 07:43:59 cheren kernel: [ 418.095082] [<c0119a73>] ? lapic_next_event+0x13/0x20
Jul 1 07:43:59 cheren kernel: [ 418.095104] [<f8022dee>] ath9k_tasklet+0x6e/0xa0 [ath9k]
Jul 1 07:43:59 cheren kernel: [ 418.095112] [<c013ed18>] tasklet_action+0x78/0x100
Jul 1 07:43:59 cheren kernel: [ 418.095118] [<c013f197>] __do_softirq+0x97/0x170
Jul 1 07:43:59 cheren kernel: [ 418.095125] [<c011c7df>] ? ack_apic_level+0x6f/0x290
Jul 1 07:43:59 cheren kernel: [ 418.095132] [<c013f2cd>] do_softirq+0x5d/0x60
Jul 1 07:43:59 cheren kernel: [ 418.095138] [<c013f445>] irq_exit+0x55/0x90
Jul 1 07:43:59 cheren kernel: [ 418.095144] [<c0106853>] do_IRQ+0x83/0xa0
Jul 1 07:43:59 cheren kernel: [ 418.095150] [<c01051f3>] common_interrupt+0x23/0x30
Jul 1 07:43:59 cheren kernel: [ 418.095157] [<c0319467>] ? acpi_idle_enter_bm+0x269/0x2b8
Jul 1 07:43:59 cheren kernel: [ 418.095165] [<c040b44f>] cpuidle_idle_call+0x6f/0xd0
Jul 1 07:43:59 cheren kernel: [ 418.095170] [<c010285d>] cpu_idle+0x6d/0xd0
Jul 1 07:43:59 cheren kernel: [ 418.095177] [<c04ed07e>] rest_init+0x4e/0x60
Jul 1 07:43:59 cheren kernel: [ 418.095181] Mem-Info:
Jul 1 07:43:59 cheren kernel: [ 418.095184] DMA per-cpu:
Jul 1 07:43:59 cheren kernel: [ 418.095188] CPU 0: hi: 0, btch: 1 usd: 0
Jul 1 07:43:59 cheren kernel: [ 418.095192] CPU 1: hi: 0, btch: 1 usd: 0
Jul 1 07:43:59 cheren kernel: [ 418.095195] Normal per-cpu:
Jul 1 07:43:59 cheren kernel: [ 418.095199] CPU 0: hi: 186, btch: 31 usd: 22
Jul 1 07:43:59 cheren kernel: [ 418.095203] CPU 1: hi: 186, btch: 3...

Read more...

Revision history for this message
Launchpad Janitor (janitor) wrote :

Status changed to 'Confirmed' because the bug affects multiple users.

Changed in linux-backports-modules-2.6.28 (Ubuntu):
status: New → Confirmed
To post a comment you must log in.
This report contains Public information  
Everyone can see this information.

Other bug subscribers

Remote bug watches

Bug watches keep track of this bug in other bug trackers.