iwl3945: order 5 allocation during ifconfig up; vm problem?

From: Pavel Machek
Date: Sun Sep 09 2012 - 17:32:23 EST


Hi!

On 3.6.0-rc2+, I tried to turn on the wireless, but got

root@amd:~# ifconfig wlan0 10.0.0.6 up
SIOCSIFFLAGS: Cannot allocate memory
SIOCSIFFLAGS: Cannot allocate memory
root@amd:~#

It looks like it uses "a bit too big" allocations to allocate
firmware...? Order five allocation....

Hmm... then I did "echo 3 > /proc/sys/vm/drop_caches" and now the
network works. Is it VM problem that it failed to allocate memory when
it was freeable?

Pavel

iwl3945 0000:03:00.0: failed to allocate pci memory
iwl3945 0000:03:00.0: Could not read microcode: -12
firmware 0000:03:00.0: firmware_loading_store: vmap() failed
iwl3945 0000:03:00.0: iwlwifi-3945-2.ucode firmware file req failed:
-2
iwl3945 0000:03:00.0: Loaded firmware iwlwifi-3945-1.ucode, which is
deprecated. Please use API v2 instead.
iwl3945 0000:03:00.0: loaded firmware version 15.32.2.9
ifconfig: page allocation failure: order:5, mode:0x80d0
Pid: 21116, comm: ifconfig Tainted: G W 3.6.0-rc2+ #228
Call Trace:
[<c029ebf6>] warn_alloc_failed+0xb6/0x100
[<c02a0321>] __alloc_pages_nodemask+0x4c1/0x6e0
[<c020699c>] dma_generic_alloc_coherent+0x8c/0xc0
[<c05c4d59>] il3945_mac_start+0x1269/0x1280
[<c0206910>] ? dma_generic_free_coherent+0x30/0x30
[<c0800f64>] ? packet_notifier+0xc4/0x1a0
[<c088094c>] ieee80211_do_open+0x28c/0x840
[<c087f399>] ? ieee80211_check_concurrent_iface+0x19/0x190
[<c024c7ea>] ? raw_notifier_call_chain+0x1a/0x20
[<c0880f3b>] ieee80211_open+0x3b/0x80
[<c0769966>] __dev_open+0x96/0xf0
[<c08c73a5>] ? _raw_spin_unlock_bh+0x25/0x30
[<c076633d>] __dev_change_flags+0x7d/0x150
[<c076988e>] dev_change_flags+0x1e/0x60
[<c07d819d>] devinet_ioctl+0x69d/0x770
[<c076b026>] ? dev_ioctl+0x336/0x740
[<c07d959a>] inet_ioctl+0x9a/0xc0
[<c0756a33>] sock_ioctl+0x63/0x240
[<c07569d0>] ? sock_fasync+0x80/0x80
[<c02d91a3>] do_vfs_ioctl+0x83/0x570
[<c0223a50>] ? mm_fault_error+0x170/0x170
[<c024ba86>] ? up_read+0x16/0x30
[<c0223bcc>] ? do_page_fault+0x17c/0x3b0
[<c02d96c9>] sys_ioctl+0x39/0x70
[<c08c7c10>] sysenter_do_call+0x12/0x31
Mem-Info:
DMA per-cpu:
CPU 0: hi: 0, btch: 1 usd: 0
CPU 1: hi: 0, btch: 1 usd: 0
Normal per-cpu:
CPU 0: hi: 186, btch: 31 usd: 30
CPU 1: hi: 186, btch: 31 usd: 0
HighMem per-cpu:
CPU 0: hi: 186, btch: 31 usd: 0
CPU 1: hi: 186, btch: 31 usd: 0
active_anon:250783 inactive_anon:118977 isolated_anon:0
active_file:14686 inactive_file:13826 isolated_file:0
unevictable:0 dirty:3050 writeback:0 unstable:0
free:30113 slab_reclaimable:43820 slab_unreclaimable:34426
mapped:11898 shmem:19084 pagetables:3043 bounce:0
DMA free:1580kB min:64kB low:80kB high:96kB active_anon:0kB
inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB
isolated(anon):0kB isolated(file):0kB present:15788kB mlocked:0kB
dirty:0kB writeback:0kB mapped:0kB shmem:0kB slab_reclaimable:0kB
slab_unreclaimable:0kB kernel_stack:0kB pagetables:0kB unstable:0kB
bounce:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? yes
lowmem_reserve[]: 0 864 2005 2005
Normal free:115348kB min:3728kB low:4660kB high:5592kB
active_anon:193616kB inactive_anon:192540kB active_file:19528kB
inactive_file:12916kB unevictable:0kB isolated(anon):0kB
isolated(file):0kB present:885072kB mlocked:0kB dirty:5388kB
writeback:0kB mapped:9212kB shmem:29100kB slab_reclaimable:175280kB
slab_unreclaimable:137704kB kernel_stack:6032kB pagetables:12172kB
unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0
all_unreclaimable? no
lowmem_reserve[]: 0 0 9125 9125
HighMem free:3524kB min:512kB low:1740kB high:2972kB
active_anon:809516kB inactive_anon:283368kB active_file:39216kB
inactive_file:42388kB unevictable:0kB isolated(anon):0kB
isolated(file):0kB present:1168080kB mlocked:0kB dirty:6812kB
writeback:0kB mapped:38380kB shmem:47236kB slab_reclaimable:0kB
slab_unreclaimable:0kB kernel_stack:0kB pagetables:0kB unstable:0kB
bounce:0kB writeback_tmp:0kB pages_scanned:0 all_unreclaimable? no
lowmem_reserve[]: 0 0 0 0
DMA: 1*4kB 1*8kB 0*16kB 1*32kB 2*64kB 1*128kB 1*256kB 0*512kB 1*1024kB
0*2048kB 0*4096kB = 1580kB
Normal: 4729*4kB 8354*8kB 1683*16kB 74*32kB 4*64kB 0*128kB 0*256kB
0*512kB 0*1024kB 0*2048kB 0*4096kB = 115300kB
HighMem: 537*4kB 118*8kB 27*16kB 0*32kB 0*64kB 0*128kB 0*256kB 0*512kB
0*1024kB 0*2048kB 0*4096kB = 3524kB
62305 total pagecache pages
14672 pages in swap cache
Swap cache stats: add 96156, delete 81484, find 3042279/3044117
Free swap = 480024kB
Total swap = 779148kB
521920 pages RAM
294610 pages HighMem
9083 pages reserved
244243 pages shared
450983 pages non-shared
iwl3945 0000:03:00.0: failed to allocate pci memory
iwl3945 0000:03:00.0: Could not read microcode: -12

--
(english) http://www.livejournal.com/~pavelmachek
(cesky, pictures) http://atrey.karlin.mff.cuni.cz/~pavel/picture/horses/blog.html
--
To unsubscribe from this list: send the line "unsubscribe linux-kernel" in
the body of a message to majordomo@xxxxxxxxxxxxxxx
More majordomo info at http://vger.kernel.org/majordomo-info.html
Please read the FAQ at http://www.tux.org/lkml/