[Bug 404575] Re: iwlagn creates cpu hog

Sat Oct 24 16:28:32 UTC 2009

With the backport-modules, I feel it's harder to trigger but when it
hits, it seems to be worse:

[200790.561493] Free swap  = 971420kB
[200790.561493] Total swap = 1959920kB
[200790.561493] 513712 pages RAM
[200790.561493] 9742 pages reserved
[200790.561493] 57599 pages shared
[200790.561493] 476022 pages non-shared
[200790.582266] kcryptd_io: page allocation failure. order:2, mode:0x4020
[200790.582275] Pid: 858, comm: kcryptd_io Tainted: G        WC 2.6.31-14-generic #48-Ubuntu
[200790.582281] Call Trace:
[200790.582285]  <IRQ>  [<ffffffff810e083c>] __alloc_pages_slowpath+0x4cc/0x4e0
[200790.582309]  [<ffffffff810e099e>] __alloc_pages_nodemask+0x14e/0x150
[200790.582320]  [<ffffffff81111dfa>] kmalloc_large_node+0x5a/0xb0
[200790.582328]  [<ffffffff81115fa5>] __kmalloc_node_track_caller+0x135/0x180
[200790.582360]  [<ffffffffa0510967>] ? iwl_rx_allocate+0x197/0x2f0 [iwlcore]
[200790.582372]  [<ffffffff8142e41b>] __alloc_skb+0x7b/0x180
[200790.582393]  [<ffffffffa0510967>] iwl_rx_allocate+0x197/0x2f0 [iwlcore]
[200790.582416]  [<ffffffffa0511dd6>] iwl_rx_replenish_now+0x16/0x30 [iwlcore]
[200790.582433]  [<ffffffffa0462eda>] iwl_rx_handle+0x24a/0x2f0 [iwlagn]
[200790.582449]  [<ffffffffa0463308>] iwl_irq_tasklet_legacy+0x1d8/0x4a0 [iwlagn]
[200790.582460]  [<ffffffff81063ee0>] tasklet_action+0xd0/0xe0
[200790.582469]  [<ffffffff8106549d>] __do_softirq+0xbd/0x200
[200790.582478]  [<ffffffff810131ec>] call_softirq+0x1c/0x30
[200790.582485]  [<ffffffff81014bc5>] do_softirq+0x55/0x90
[200790.582493]  [<ffffffff81065205>] irq_exit+0x85/0x90
[200790.582500]  [<ffffffff81014100>] do_IRQ+0x70/0xe0
[200790.582509]  [<ffffffff81012a13>] ret_from_intr+0x0/0x11
[200790.582514]  <EOI>  [<ffffffff81261f3c>] ? get_request+0x1fc/0x370
[200790.582529]  [<ffffffff812620d5>] ? get_request_wait+0x25/0x1a0
[200790.582538]  [<ffffffff81256fdb>] ? elv_merge+0x1cb/0x200
[200790.582545]  [<ffffffff812622e3>] ? __make_request+0x93/0x440
[200790.582556]  [<ffffffff81260e03>] ? generic_make_request+0x1a3/0x4d0
[200790.582564]  [<ffffffff8105225b>] ? dequeue_entity+0x1b/0x240
[200790.582574]  [<ffffffff8114a006>] ? bio_alloc_bioset+0x56/0xf0
[200790.582602]  [<ffffffffa0046938>] ? kcryptd_io_read+0xd8/0x110 [dm_crypt]
[200790.582615]  [<ffffffffa0046970>] ? kcryptd_io+0x0/0x30 [dm_crypt]
[200790.582628]  [<ffffffffa0046998>] ? kcryptd_io+0x28/0x30 [dm_crypt]
[200790.582638]  [<ffffffff810737a5>] ? run_workqueue+0x95/0x170
[200790.582647]  [<ffffffff81073924>] ? worker_thread+0xa4/0x120
[200790.582656]  [<ffffffff81078b30>] ? autoremove_wake_function+0x0/0x40
[200790.582665]  [<ffffffff81073880>] ? worker_thread+0x0/0x120
[200790.582672]  [<ffffffff81078746>] ? kthread+0xa6/0xb0
[200790.582680]  [<ffffffff810130ea>] ? child_rip+0xa/0x20
[200790.582689]  [<ffffffff810786a0>] ? kthread+0x0/0xb0
[200790.582696]  [<ffffffff810130e0>] ? child_rip+0x0/0x20
[200790.582701] Mem-Info:
[200790.582704] Node 0 DMA per-cpu:
[200790.582712] CPU    0: hi:    0, btch:   1 usd:   0
[200790.582718] CPU    1: hi:    0, btch:   1 usd:   0
[200790.582722] Node 0 DMA32 per-cpu:
[200790.582729] CPU    0: hi:  186, btch:  31 usd:  54
[200790.582734] CPU    1: hi:  186, btch:  31 usd:  62
[200790.582745] Active_anon:324819 active_file:9383 inactive_anon:109931
[200790.582748]  inactive_file:9487 unevictable:5 dirty:44 writeback:231 unstable:0
[200790.582751]  free:4021 slab:20380 mapped:10761 pagetables:7641 bounce:0
[200790.582756] Node 0 DMA free:7876kB min:40kB low:48kB high:60kB active_anon:656kB inactive_anon:3612kB active_file:1532kB inactive_file:1640kB unevictable:0kB present:15332kB pages_scanned:0 all_unreclaimable? no
[200790.582771] lowmem_reserve[]: 0 1963 1963 1963
[200790.582782] Node 0 DMA32 free:8208kB min:5648kB low:7060kB high:8472kB active_anon:1298620kB inactive_anon:436112kB active_file:36000kB inactive_file:36308kB unevictable:20kB present:2010592kB pages_scanned:0 all_unreclaimable? no
[200790.582797] lowmem_reserve[]: 0 0 0 0
[200790.582807] Node 0 DMA: 13*4kB 10*8kB 14*16kB 5*32kB 9*64kB 5*128kB 4*256kB 2*512kB 2*1024kB 1*2048kB 0*4096kB = 7876kB
[200790.582834] Node 0 DMA32: 1844*4kB 8*8kB 6*16kB 2*32kB 0*64kB 1*128kB 0*256kB 1*512kB 0*1024kB 0*2048kB 0*4096kB = 8240kB
[200790.582859] 80549 total pagecache pages
[200790.582864] 49716 pages in swap cache
[200790.582869] Swap cache stats: add 4197976, delete 4148260, find 1352663/1908435
[200790.582875] Free swap  = 971420kB
[200790.582879] Total swap = 1959920kB
[200790.592031] 513712 pages RAM
[200790.592031] 9742 pages reserved
[200790.592031] 57600 pages shared
[200790.592031] 476017 pages non-shared
[200792.350057] iwlagn 0000:03:00.0: Microcode SW error detected.  Restarting 0x2000000.
[200872.154497] __ratelimit: 305 callbacks suppressed
[200872.154506] events/0: page allocation failure. order:4, mode:0x40d0
[200872.154514] Pid: 9, comm: events/0 Tainted: G        WC 2.6.31-14-generic #48-Ubuntu
[200872.154520] Call Trace:
[200872.154538]  [<ffffffff810e083c>] __alloc_pages_slowpath+0x4cc/0x4e0
[200872.154548]  [<ffffffff810e099e>] __alloc_pages_nodemask+0x14e/0x150
[200872.154559]  [<ffffffff8110cea2>] alloc_pages_current+0x82/0xd0
[200872.154567]  [<ffffffff810df989>] __get_free_pages+0x9/0x50
[200872.154597]  [<ffffffffa05146fe>] iwl_tx_queue_init+0x16e/0x440 [iwlcore]
[200872.154619]  [<ffffffffa05152b7>] iwl_txq_ctx_reset+0x2c7/0x520 [iwlcore]
[200872.154642]  [<ffffffffa050b1a3>] iwl_hw_nic_init+0xf3/0x160 [iwlcore]
[200872.154661]  [<ffffffffa046438a>] __iwl_up+0x9a/0x390 [iwlagn]
[200872.154677]  [<ffffffffa0464fc0>] iwl_mac_start+0x4b0/0x930 [iwlagn]
[200872.154687]  [<ffffffff810554ad>] ? enqueue_task_fair+0x3d/0x80
[200872.154720]  [<ffffffffa011fce7>] ? heci_bh_write_handler+0x57/0x8b0 [heci]
[200872.154729]  [<ffffffff81052119>] ? update_curr+0x189/0x190
[200872.154737]  [<ffffffff8105225b>] ? dequeue_entity+0x1b/0x240
[200872.154771]  [<ffffffffa04e42c1>] ieee80211_reconfig+0x3d1/0x3f0 [mac80211]
[200872.154795]  [<ffffffffa04cb480>] ? ieee80211_restart_work+0x0/0x30 [mac80211]
[200872.154819]  [<ffffffffa04cb49d>] ieee80211_restart_work+0x1d/0x30 [mac80211]
[200872.154830]  [<ffffffff810737a5>] run_workqueue+0x95/0x170
[200872.154839]  [<ffffffff81073924>] worker_thread+0xa4/0x120
[200872.154848]  [<ffffffff81078b30>] ? autoremove_wake_function+0x0/0x40
[200872.154858]  [<ffffffff81073880>] ? worker_thread+0x0/0x120
[200872.154865]  [<ffffffff81078746>] kthread+0xa6/0xb0
[200872.154875]  [<ffffffff810130ea>] child_rip+0xa/0x20
[200872.154884]  [<ffffffff810786a0>] ? kthread+0x0/0xb0
[200872.154891]  [<ffffffff810130e0>] ? child_rip+0x0/0x20
[200872.154895] Mem-Info:
[200872.154900] Node 0 DMA per-cpu:
[200872.154907] CPU    0: hi:    0, btch:   1 usd:   0
[200872.154913] CPU    1: hi:    0, btch:   1 usd:   0
[200872.154917] Node 0 DMA32 per-cpu:
[200872.154924] CPU    0: hi:  186, btch:  31 usd:   3
[200872.154930] CPU    1: hi:  186, btch:  31 usd:   0
[200872.154940] Active_anon:321590 active_file:10199 inactive_anon:110852
[200872.154943]  inactive_file:9691 unevictable:5 dirty:54 writeback:243 unstable:0
[200872.154946]  free:5412 slab:20008 mapped:10711 pagetables:7646 bounce:0
[200872.154952] Node 0 DMA free:7892kB min:40kB low:48kB high:60kB active_anon:656kB inactive_anon:3612kB active_file:1560kB inactive_file:1612kB unevictable:0kB present:15332kB pages_scanned:0 all_unreclaimable? no
[200872.154966] lowmem_reserve[]: 0 1963 1963 1963
[200872.154977] Node 0 DMA32 free:13756kB min:5648kB low:7060kB high:8472kB active_anon:1285704kB inactive_anon:439796kB active_file:39236kB inactive_file:37152kB unevictable:20kB present:2010592kB pages_scanned:0 all_unreclaimable? no
[200872.154993] lowmem_reserve[]: 0 0 0 0
[200872.155002] Node 0 DMA: 13*4kB 10*8kB 15*16kB 5*32kB 9*64kB 5*128kB 4*256kB 2*512kB 2*1024kB 1*2048kB 0*4096kB = 7892kB
[200872.155030] Node 0 DMA32: 1587*4kB 418*8kB 214*16kB 0*32kB 0*64kB 1*128kB 0*256kB 1*512kB 0*1024kB 0*2048kB 0*4096kB = 13756kB
[200872.155057] 81775 total pagecache pages
[200872.155061] 50095 pages in swap cache
[200872.155067] Swap cache stats: add 4223013, delete 4172918, find 1354505/1912924
[200872.155072] Free swap  = 951492kB
[200872.155076] Total swap = 1959920kB
[200872.168740] 513712 pages RAM
[200872.168745] 9742 pages reserved
[200872.168749] 57821 pages shared
[200872.168752] 474530 pages non-shared
[200872.168760] iwlagn 0000:03:00.0: kmalloc for auxiliary BD structures failed
[200872.168853] iwlagn 0000:03:00.0: Tx 5 queue init failed
[200872.169158] iwlagn 0000:03:00.0: Unable to init nic
[200872.169188] iwlagn 0000:03:00.0: No space for Tx
[200872.169195] iwlagn 0000:03:00.0: Error sending SENSITIVITY_CMD: enqueue_hcmd failed: -28
[200872.169200] iwlagn 0000:03:00.0: SENSITIVITY_CMD failed
[200872.200088] mac80211-phy0: failed to set key (0, 00:24:92:f6:eb:a0) to hardware (-22)
[202220.770096] mac80211-phy0: failed to remove key (0, 00:24:92:f6:eb:a0) from hardware (-22)
[202220.770169] wlan0: deauthenticating from 00:24:92:f6:eb:a0 by local choice (reason=3)
[202220.850147] iwlagn 0000:03:00.0: No space for Tx
[202220.850158] iwlagn 0000:03:00.0: Error sending REPLY_ADD_STA: enqueue_hcmd failed: -28
[202220.850167] mac80211-phy0: failed to remove key (2, ff:ff:ff:ff:ff:ff) from hardware (-28)

-- 
iwlagn creates cpu hog
https://bugs.launchpad.net/bugs/404575
You received this bug notification because you are a member of Kernel
Bugs, which is subscribed to Linux.