#11968 NORM Not Tri: Page allocation failures upgrading XO-1.75 from 11.3.1

Fri Jun 22 15:41:58 EDT 2012

#11968: Page allocation failures upgrading XO-1.75 from 11.3.1
-----------------------+----------------------------------------------------
 Reporter:  greenfeld  |                 Owner:               
     Type:  defect     |                Status:  new          
 Priority:  normal     |             Milestone:  Not Triaged  
Component:  kernel     |               Version:  not specified
 Keywords:             |           Next_action:  diagnose     
 Verified:  0          |   Deployment_affected:               
Blockedby:             |              Blocking:               
-----------------------+----------------------------------------------------
 I saw page allocation failures upgrading a 512 MB XO-1.75 from 11.3.1 to
 12.1.0 os15 while olpc-update was running on 11.3.1.

 These occurred when an asix USB ethernet adapter was used with such
 frequency and were logged against so many things that the XO was unable to
 upgrade without a reboot using wifi after the asix adapter was removed.

 After a reboot the XO was upgraded successfully to 12.1.0 via wifi & the
 libertas card, which is much slower (only 100-200 KB/sec reliable
 bandwidth in our area).

 Psmem.py did not show unusual memory usage by any userspace process.  I
 have not retried the upgrade with the asix adapter since.

 {{{
 Jun 21 18:08:26 xo-74-34-46 NetworkManager[735]: <info> Activation (eth1)
 Stage 5 of 5 (IP Configure Commit) complete.
 Jun 21 18:08:27 xo-74-34-46 avahi-daemon[753]: Registering new address
 record for 2002:ad0c:4b0c:1:21c:49ff:fe01:6c0 on eth1.*.
 Jun 21 18:08:27 xo-74-34-46 avahi-daemon[753]: Withdrawing address record
 for fe80::21c:49ff:fe01:6c0 on eth1.
 Jun 21 18:15:51 xo-74-34-46 kernel: imable:4504kB kernel_stack:1312kB
 pagetables:1516kB unstable:0kB bounce:0kB writeback_tmp:0kB
 pages_scanned:0 all
 _unreclaimable? no
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.276938] lowmem_reserve[]: 0 0
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.286502] Normal: 3170*4kB
 2782*8kB 0*16kB 0*32kB 0*64kB 0*128kB 0*256kB 0*512kB 0*1024kB 0*2048kB
 0*4096kB
 0*8192kB 0*16384kB 0*32768kB = 34936kB
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.306098] 78186 total pagecache
 pages
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.315583] 0 pages in swap cache
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.324851] Swap cache stats: add
 0, delete 0, find 0/0
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.334032] Free swap  = 0kB
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.342970] Total swap = 0kB
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.368843] 131072 pages of RAM
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.377385] 9078 free pages
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.385717] 2541 reserved pages
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.393908] 4085 slab pages
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.402014] 51335 pages shared
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.409978] 0 pages swap cached
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.417740] asix 1-1.4:1.0: eth1:
 kevent 2 may have been dropped
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.425512] rsync: page allocation
 failure: order:0, mode:0x20
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.433168] [<c00369ac>]
 (unwind_backtrace+0x0/0x120) from [<c0091534>]
 (warn_alloc_failed+0xe0/0x104)
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.448274] [<c0091534>]
 (warn_alloc_failed+0xe0/0x104) from [<c00940bc>]
 (__alloc_pages_nodemask+0x528/0x580)
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.463394] [<c00940bc>]
 (__alloc_pages_nodemask+0x528/0x580) from [<c00b891c>]
 (cache_alloc_refill+0x2d8/0x5a
 4)
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.478567] [<c00b891c>]
 (cache_alloc_refill+0x2d8/0x5a4) from [<c00b8c70>] (__kmalloc+0x88/0xd8)
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.486395] [<c00b8c70>]
 (__kmalloc+0x88/0xd8) from [<c02b6bd0>] (__alloc_skb+0x48/0xd8)
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.494201] [<c02b6bd0>]
 (__alloc_skb+0x48/0xd8) from [<bf0b4090>] (rx_submit+0x24/0x328 [usbnet])
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.509708] [<bf0b4090>]
 (rx_submit+0x24/0x328 [usbnet]) from [<c0211318>]
 (usb_hcd_giveback_urb+0xa0/0xec)
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.525774] [<c0211318>]
 (usb_hcd_giveback_urb+0xa0/0xec) from [<c0222328>]
 (ehci_urb_done+0xc4/0xe0)
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.542501] [<c0222328>]
 (ehci_urb_done+0xc4/0xe0) from [<c0224980>] (qh_completions+0xb4/0x458)
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.551198] [<c0224980>]
 (qh_completions+0xb4/0x458) from [<c02258e0>] (ehci_work+0xb8/0x970)
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.559970] [<c02258e0>]
 (ehci_work+0xb8/0x970) from [<c0228d5c>] (ehci_irq+0x308/0x370)
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.568771] [<c0228d5c>]
 (ehci_irq+0x308/0x370) from [<c0210898>] (usb_hcd_irq+0x34/0x88)
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.577558] [<c0210898>]
 (usb_hcd_irq+0x34/0x88) from [<c008888c>]
 (handle_irq_event_percpu+0x30/0x174)
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.594774] [<c008888c>]
 (handle_irq_event_percpu+0x30/0x174) from [<c0088a28>]
 (handle_irq_event+0x58/0x80)
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.611999] [<c0088a28>]
 (handle_irq_event+0x58/0x80) from [<c008a8a0>]
 (handle_level_irq+0xc4/0x114)
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.629315] [<c008a8a0>]
 (handle_level_irq+0xc4/0x114) from [<c00883dc>]
 (generic_handle_irq+0x20/0x30)
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.646841] [<c00883dc>]
 (generic_handle_irq+0x20/0x30) from [<c002b060>] (asm_do_IRQ+0x60/0x84)
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.655912] [<c002b060>]
 (asm_do_IRQ+0x60/0x84) from [<c0030e18>] (__irq_usr+0x58/0xa0)
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.665076] Exception
 stack(0xd41bffb0 to 0xd41bfff8)
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.674246] ffa0:
 00077e68 00000011 00000000 00087cf3
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.683629] ffc0: 00081ae0 00000000
 00000001 00087cef 00000000 000815b0 000824b0 00083c5b
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.693140] ffe0: 00008257 beff90e0
 0004ba60 00049dac 60000010 ffffffff
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.702688] Mem-info:
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.712191] Normal per-cpu:
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.721710] CPU    0: hi:  186,
 btch:  31 usd:  30
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.731358] active_anon:15717
 inactive_anon:2161 isolated_anon:0
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.731367]  active_file:16307
 inactive_file:59507 isolated_file:0
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.731376]  unevictable:8
 dirty:5794 writeback:0 unstable:0
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.731385]  free:8734
 slab_reclaimable:2959 slab_unreclaimable:1126
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.731394]  mapped:7644 shmem:2372
 pagetables:379 bounce:0
 Jun 21 18:15:51 xo-74-34-46 kernel: [ 1162.780471] Normal free:34936kB
 min:2884kB low:3604kB high:4324kB active_anon:62868kB inactive_anon:8644kB
 active_file:65228kB inactive_file:238028kB unevictable:32kB
 isolated(anon):0kB isolated(file):0kB present:520192kB mlocked:32kB
 dirty:23176kB writeback:0kB mapped:30576kB shmem:9488kB
 slab_reclaimable:11836kB slab_unreclaimable:4504kB kernel_stack:1312kB
 pagetables:1516kB unstable:0kB bounce:0kB writeback_tmp:0kB
 pages_scanned:0 all_unreclaimable? no
 }}}

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