#12009 NORM Not Tri: Page allocation failure restarting MMC

Wed Jul 11 22:45:14 EDT 2012

#12009: Page allocation failure restarting MMC
-----------------------+----------------------------------------------------
 Reporter:  greenfeld  |                 Owner:                                   
     Type:  defect     |                Status:  new                              
 Priority:  normal     |             Milestone:  Not Triaged                      
Component:  kernel     |               Version:  Development build as of this date
 Keywords:             |           Next_action:  diagnose                         
 Verified:  0          |   Deployment_affected:                                   
Blockedby:             |              Blocking:                                   
-----------------------+----------------------------------------------------
 I saw a page allocation failure resuming mmc support on a XO-1.75 in
 12.1.0 os18.

 The XO was running runin in DEVL mode with gdb attached and monitoring
 runin-gtk (which previously had died; the MMC failure was not spotted
 until later).

 After this page allocation error happened future resume cycles returned
 -110 trying to initialize the Ethernet card.

 Something triggered /tmp/runin-fail but wlan.log and fail.log were empty.
 An acceleration test python exception was also seen in the logs.

 {{{
 <3>[27407.164266] mmp2_pm_finish: Done
 <6>[27407.473029] usb 1-1: reset high speed USB device number 2 using
 pxau2o-ehc
 <7>[27408.223496] Bus Clock: USB PLL 480MHz
 <7>[27408.223507] GC Controller Clock: USB PLL 480MHz
 <4>[27408.223920] [galcore]: gpu_resume, 1042
 <6>[27408.479791] olpc-dcon: The CPU has control
 <4>[27408.483878] Restarting tasks ... done.
 <6>[27408.812414] mmc1: new SDIO card at address 0001
 <4>[27408.826881] kworker/u:6: page allocation failure: order:5, mode:0xd0
 <4>[27408.842563] [<c0036ce8>] (unwind_backtrace+0x0/0x11c) from
 [<c009fa14>] (warn_alloc_failed+0xec/0x110)
 <4>[27408.863633] [<c009fa14>] (warn_alloc_failed+0xec/0x110) from
 [<c00a197c>] (__alloc_pages_nodemask+0x510/0x59c)
 <4>[27408.883123] [<c00a197c>] (__alloc_pages_nodemask+0x510/0x59c) from
 [<c039b404>] (cache_alloc_refill+0x2bc/0x56c)
 <4>[27408.906976] [<c039b404>] (cache_alloc_refill+0x2bc/0x56c) from
 [<c00c6058] (kmem_cache_alloc+0x50/0x8c)
 <4>[27408.927040] [<c00c6058>] (kmem_cache_alloc+0x50/0x8c) from
 [<bf056700>] (f_sdio_probe+0x110/0x7b4 [libertas_sdio])
 <4>[27408.950065] [<bf056700>] (if_sdio_probe+0x110/0x7b4 [libertas_sdio])
 from[<c027241c>] (sdio_bus_probe+0x8c/0xf8)
 <4>[27408.973220] [<c027241c>] (sdio_bus_probe+0x8c/0xf8) from
 [<c01e5fcc>] (drver_probe_device+0x134/0x274)
 <4>[27408.993607] [<c01e5fcc>] (driver_probe_device+0x134/0x274) from
 [<c01e4d5>] (bus_for_each_drv+0x48/0x8c)
 <4>[27409.013560] [<c01e4d54>] (bus_for_each_drv+0x48/0x8c) from
 [<c01e5d84>] (evice_attach+0x7c/0xa0)
 <4>[27409.033077] [<c01e5d84>] (device_attach+0x7c/0xa0) from [<c01e5600>]
 (busprobe_device+0x24/0x40)
 <4>[27409.053091] [<c01e5600>] (bus_probe_device+0x24/0x40) from
 [<c01e3ae0>] (evice_add+0x3b4/0x578)
 <4>[27409.072858] [<c01e3ae0>] (device_add+0x3b4/0x578) from [<c0272618>]
 (sdioadd_func+0x34/0x4c)
 <4>[27409.091786] [<c0272618>] (sdio_add_func+0x34/0x4c) from [<c0271c08>]
 (mmcattach_sdio+0x28c/0x340)
 <4>[27409.111613] [<c0271c08>] (mmc_attach_sdio+0x28c/0x340) from
 [<c026c67c>] mmc_rescan+0x2a0/0x310)
 <4>[27409.130105] [<c026c67c>] (mmc_rescan+0x2a0/0x310) from [<c005e314>]
 (procss_one_work+0x1f4/0x35c)
 <4>[27409.149788] [<c005e314>] (process_one_work+0x1f4/0x35c) from
 [<c005e67c>](worker_thread+0x1d4/0x314)
 <4>[27409.168824] [<c005e67c>] (worker_thread+0x1d4/0x314) from
 [<c0063038>] (khread+0x7c/0x88)
 <4>[27409.187380] [<c0063038>] (kthread+0x7c/0x88) from [<c0032158>]
 (kernel_thead_exit+0x0/0x8)
 <4>[27409.203003] Mem-info:
 <4>[27409.205265] Normal per-cpu:
 <4>[27409.208038] CPU    0: hi:  186, btch:  31 usd: 103
 <4>[27409.212810] active_anon:17130 inactive_anon:1148 isolated_anon:0
 <4>[27409.212818]  active_file:1450 inactive_file:10396 isolated_file:0
 <4>[27409.212827]  unevictable:2560 dirty:359 writeback:0 unstable:0
 <4>[27409.212835]  free:7521 slab_reclaimable:906 slab_unreclaimable:55957
 <4>[27409.212843]  mapped:6808 shmem:1799 pagetables:291 bounce:0
 <4>[27409.284324] Normal free:33224kB min:2884kB low:3604kB high:4324kB
 active_non:64724kB inactive_anon:4604kB active_file:5812kB
 inactive_file:42052kB unevitable:10240kB isolated(anon):0kB
 isolated(file):0kB present:520192kB mlocked:1040kB dirty:1712kB
 writeback:0kB mapped:27140kB shmem:7212kB slab_reclaimable:368kB
 slab_unreclaimable:223828kB kernel_stack:616kB pagetables:1112kB
 unstable:0B bounce:0kB writeback_tmp:0kB pages_scanned:0
 all_unreclaimable? no
 <4>[27409.373177] lowmem_reserve[]: 0 0
 <4>[27409.376493] Normal: 592*4kB 499*8kB 256*16kB 250*32kB 109*64kB
 1*128kB 1*56kB 1*512kB 1*1024kB 0*2048kB 0*4096kB 1*8192kB 0*16384kB
 0*32768kB = 35544kB
 <4>[27409.398548] 13878 total pagecache pages
 <4>[27409.402356] 0 pages in swap cache
 <4>[27409.423234] Swap cache stats: add 0, delete 0, find 0/0
 <4>[27409.428450] Free swap  = 0kB
 <4>[27409.431303] Total swap = 0kB
 <4>[27409.471048] 131072 pages of RAM
 <4>[27409.479608] 9011 free pages
 <4>[27409.482408] 2562 reserved pages
 <4>[27409.492796] 56861 slab pages
 <4>[27409.501237] 16252 pages shared
 <4>[27409.504476] 0 pages swap cached
 <4>[27409.511606] libertas_sdio: probe of mmc1:0001:1 failed with error
 -12
 }}}

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