#12037 NORM 12.1.0: XO-1.5 Page allocation failure after 5000 suspend/resume cycles

[Zarro Boogs per Child]

#12037: XO-1.5 Page allocation failure after 5000 suspend/resume cycles
--------------------+-------------------------------------------------------
 Reporter:  dsd     |                 Owner:  kernel       
     Type:  defect  |                Status:  new          
 Priority:  normal  |             Milestone:  12.1.0       
Component:  kernel  |               Version:  not specified
 Keywords:          |           Next_action:  diagnose     
 Verified:  0       |   Deployment_affected:               
Blockedby:          |              Blocking:               
--------------------+-------------------------------------------------------
 On kernel 3.3.8_xo1.5-20120628.1739.olpc.137c3f8 Sam has caught a page
 allocation failure after approx 5400 suspend/resume cycles.

 {{{
 [35056.357690] Restarting tasks ... done.
 Slept 5424 cycles
 [35056.810256] mmc1: new SDIO card at address 0001
 [35056.820560] kworker/u:8: page allocation failure: order:5, mode:0xd0
 [35056.826979] Pid: 3098, comm: kworker/u:8 Tainted: G        W
 3.3.8_xo1.5-20120628.1739.olpc.137c3f8 #1
 [35056.884942] Call Trace:
 [35056.887470]  [<b047b608>] warn_alloc_failed+0xc6/0xd8
 [35056.897522]  [<b047d3fc>] __alloc_pages_nodemask+0x486/0x51e
 [35056.921952]  [<b073b7bb>] cache_alloc_refill+0x227/0x3df
 [35056.940187]  [<b049e19f>] kmem_cache_alloc_trace+0x4e/0xd9
 [35056.945756]  [<f1195711>] if_sdio_probe+0xfb/0x346 [libertas_sdio]
 [35056.954637]  [<b0637b3e>] sdio_bus_probe+0x7a/0xbc
 [35056.959926]  [<b05d9f73>] driver_probe_device+0x86/0x119
 [35056.965921]  [<b05da08f>] __device_attach+0x21/0x31
 [35056.971012]  [<b05d8c5a>] bus_for_each_drv+0x3f/0x70
 [35056.976054]  [<b05d9eaf>] device_attach+0x60/0x80
 [35056.980903]  [<b05da06e>] ? __driver_attach+0x68/0x68
 [35056.986053]  [<b05d981b>] bus_probe_device+0x27/0x7a
 [35056.991238]  [<b05d84df>] device_add+0x356/0x4d6
 [35056.996299]  [<b0551497>] ? kobject_set_name_vargs+0x3d/0x4a
 [35057.003123]  [<b0637c37>] sdio_add_func+0x35/0x4a
 [35057.008276]  [<b0637408>] mmc_attach_sdio+0x2aa/0x326
 [35057.013718]  [<b0631ae7>] mmc_rescan+0x188/0x1e1
 [35057.018503]  [<b042d06d>] process_one_work+0x192/0x31c
 [35057.024020]  [<b063195f>] ? mmc_detect_card_removed+0x63/0x63
 [35057.029885]  [<b042d462>] worker_thread+0x138/0x208
 [35057.035102]  [<b042d32a>] ? rescuer_thread+0x10d/0x10d
 [35057.040663]  [<b0430087>] kthread+0x6b/0x70
 [35057.045029]  [<b043001c>] ? __init_kthread_worker+0x2d/0x2d
 [35057.050771]  [<b0744036>] kernel_thread_helper+0x6/0x10
 [35057.056153] Mem-Info:
 [35057.058484] DMA per-cpu:
 [35057.061068] CPU    0: hi:    0, btch:   1 usd:   0
 [35057.066276] Normal per-cpu:
 [35057.069128] CPU    0: hi:  186, btch:  31 usd:  35
 [35057.073987] active_anon:40489 inactive_anon:653 isolated_anon:0
 [35057.073992]  active_file:69 inactive_file:222 isolated_file:0
 [35057.073997]  unevictable:0 dirty:0 writeback:0 unstable:0
 [35057.074002]  free:20890 slab_reclaimable:1432 slab_unreclaimable:175181
 [35057.074007]  mapped:243 shmem:24520 pagetables:248 bounce:0
 [35057.104129] DMA free:3884kB min:64kB low:80kB high:96kB
 active_anon:344kB inactive_anon:4kB active_file:0kB inactive_file:0kB
 unevictable:0kB isolated(anon):0kB isolated(file):0kB present:15804kB
 mlocked:0kB dirty:0kB writeback:0kB mapped:0kB shmem:268kB
 slab_reclaimable:52kB slab_unreclaimable:6164kB kernel_stack:0kB
 pagetables:0kB unstable:0kB bounce:0kB writeback_tmp:0kB pages_scanned:0
 all_unreclaimable? yes
 [35057.141392] lowmem_reserve[]: 0 929 929
 [35057.146059] Normal free:79428kB min:3864kB low:4828kB high:5796kB
 active_anon:161612kB inactive_anon:2608kB active_file:276kB
 inactive_file:1168kB unevictable:0kB isolated(anon):0kB isolated(file):0kB
 present:951944kB mlocked:0kB dirty:0kB writeback:0kB mapped:996kB
 shmem:97812kB slab_reclaimable:5676kB slab_unreclaimable:694560kB
 kernel_stack:800kB pagetables:992kB unstable:0kB bounce:0kB
 writeback_tmp:0kB pages_scanned:237 all_unreclaimable? no
 [35057.185975] lowmem_reserve[]: 0 0 0
 [35057.189962] DMA: 25*4kB 15*8kB 7*16kB 5*32kB 9*64kB 4*128kB 3*256kB
 3*512kB 0*1024kB 0*2048kB 0*4096kB = 3884kB
 [35057.201735] Normal: 3337*4kB 1989*8kB 1350*16kB 509*32kB 186*64kB
 1*128kB 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 79180kB
 [35057.214928] 24970 total pagecache pages
 [35057.218892] 0 pages in swap cache
 [35057.222301] Swap cache stats: add 0, delete 0, find 0/0
 [35057.228138] Free swap  = 0kB
 [35057.231388] Total swap = 0kB
 [35057.253765] 243940 pages RAM
 [35057.256744] 3603 pages reserved
 [35057.260557] 1133 pages shared
 [35057.263585] 50718 pages non-shared
 [35057.267294] libertas_sdio: probe of mmc1:0001:1 failed with error -12
 }}}

 Probably a memory leak, suspect in libertas/if_sdio

-- 
Ticket URL: <http://dev.laptop.org/ticket/12037>
One Laptop Per Child <http://laptop.org/>
OLPC bug tracking system