When ureadahead tries to profile on a system with 512MB of memory. The kernel gets an OOM error. Below is the kernel stack dump.
2 3 4 5 6 7 8 9 10 11 12 13 14 15 16 17 18 19 20 21 22 23 24 25 26 27 28 29 30 31 32 33 34 35 36 37 38 39 40 41 42 43 44 45 46 47 48 49 50 51 52 53 54 55 56 57 58 59 60 61 62 63 64 65 66 67 68 69 70 71 72 73 74 75 76 77 78 79 80 81 82 83 84 85 86 87 88 89 90 91 92 93 94 95 96 97 [ 2.912175] Freeing unused kernel memory: 336k freed [ 2.912713] Write protecting the kernel text: 4536k [ 2.913052] Write protecting the kernel read-only data: 2004k [ 4.335798] ureadahead invoked oom-killer: gfp_mask=0xd0, order=0, oomkilladj=0 [ 4.335820] Pid: 64, comm: ureadahead Not tainted 2.6.31-15-generic #50fastboot9-Ubuntu [ 4.335832] Call Trace: [ 4.335859] [<c1077c61>] T.393+0x61/0x1be [ 4.335879] [<c1077ded>] __out_of_memory+0x2f/0x113 [ 4.335896] [<c1077f2f>] out_of_memory+0x5e/0x86 [ 4.335914] [<c107a20f>] __alloc_pages_nodemask+0x36a/0x42f [ 4.335933] [<c107a309>] __get_free_pages+0xf/0x21 [ 4.335951] [<c106227b>] ring_buffer_resize+0x139/0x238 [ 4.335970] [<c1062c03>] tracing_resize_ring_buffer+0x2f/0x70 [ 4.335987] [<c1065ba2>] tracing_entries_write+0xb2/0x136 [ 4.336005] [<c10651d3>] ? trace_nowake_buffer_unlock_commit+0x3f/0x46 [ 4.336051] [<c10982a6>] ? ftrace_raw_event_do_sys_open+0x85/0x8d [ 4.336069] [<c109a599>] vfs_write+0x8a/0x112 [ 4.336086] [<c1065af0>] ? tracing_entries_write+0x0/0x136 [ 4.336103] [<c109a6b8>] sys_write+0x3b/0x60 [ 4.336121] [<c1002af4>] sysenter_do_call+0x12/0x26 [ 4.336132] Mem-Info: [ 4.336140] DMA per-cpu: [ 4.336150] CPU 0: hi: 0, btch: 1 usd: 0 [ 4.336161] CPU 1: hi: 0, btch: 1 usd: 0 [ 4.336170] Normal per-cpu: [ 4.336181] CPU 0: hi: 186, btch: 31 usd: 156 [ 4.336192] CPU 1: hi: 186, btch: 31 usd: 161 [ 4.336212] Active_anon:326 active_file:13 inactive_anon:352 [ 4.336218] inactive_file:4 unevictable:0 dirty:6 writeback:0 unstable:0 [ 4.336224] free:1176 slab:3013 mapped:0 pagetables:3 bounce:0 [ 4.336245] DMA free:2000kB min:88kB low:108kB high:132kB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB present:15836kB pages_scanned:0 all_unreclaimable? yes [ 4.336270] lowmem_reserve[]: 0 482 482 482 [ 4.336312] Normal free:2704kB min:2760kB low:3448kB high:4140kB active_anon:1304kB inactive_anon:1408kB active_file:52kB inactive_file:16kB unevictable:0kB present:493624kB pages_scanned:0 all_unreclaimable? no [ 4.336338] lowmem_reserve[]: 0 0 0 0 [ 4.336362] DMA: 0*4kB 0*8kB 1*16kB 0*32kB 1*64kB 1*128kB 1*256kB 1*512kB 1*1024kB 0*2048kB 0*4096kB = 2000kB [ 4.336414] Normal: 0*4kB 0*8kB 1*16kB 0*32kB 0*64kB 1*128kB 0*256kB 1*512kB 0*1024kB 1*2048kB 0*4096kB = 2704kB [ 4.336464] 644 total pagecache pages [ 4.336473] 0 pages in swap cache [ 4.336482] Swap cache stats: add 0, delete 0, find 0/0 [ 4.336491] Free swap = 0kB [ 4.336499] Total swap = 0kB [ 4.344121] 128720 pages RAM [ 4.344132] 0 pages HighMem [ 4.344140] 3428 pages reserved [ 4.344148] 630 pages shared [ 4.344155] 122976 pages non-shared [ 4.344169] Out of memory: kill process 64 (ureadahead) score 33 or a child [ 4.344199] Killed process 64 (ureadahead) [ 4.344353] ureadahead: page allocation failure. order:0, mode:0xd0 [ 4.344372] Pid: 64, comm: ureadahead Not tainted 2.6.31-15-generic #50fastboot9-Ubuntu [ 4.344382] Call Trace: [ 4.344407] [<c146ac77>] ? printk+0xf/0x11 [ 4.344429] [<c107a292>] __alloc_pages_nodemask+0x3ed/0x42f [ 4.344449] [<c107a309>] __get_free_pages+0xf/0x21 [ 4.344468] [<c106227b>] ring_buffer_resize+0x139/0x238 [ 4.344486] [<c1062c03>] tracing_resize_ring_buffer+0x2f/0x70 [ 4.344503] [<c1065ba2>] tracing_entries_write+0xb2/0x136 [ 4.344521] [<c10651d3>] ? trace_nowake_buffer_unlock_commit+0x3f/0x46 [ 4.344541] [<c10982a6>] ? ftrace_raw_event_do_sys_open+0x85/0x8d [ 4.344559] [<c109a599>] vfs_write+0x8a/0x112 [ 4.344575] [<c1065af0>] ? tracing_entries_write+0x0/0x136 [ 4.344592] [<c109a6b8>] sys_write+0x3b/0x60 [ 4.344610] [<c1002af4>] sysenter_do_call+0x12/0x26 [ 4.344621] Mem-Info: [ 4.344629] DMA per-cpu: [ 4.344638] CPU 0: hi: 0, btch: 1 usd: 0 [ 4.344649] CPU 1: hi: 0, btch: 1 usd: 0 [ 4.344658] Normal per-cpu: [ 4.344669] CPU 0: hi: 186, btch: 31 usd: 156 [ 4.344689] CPU 1: hi: 186, btch: 31 usd: 161 [ 4.344700] Active_anon:326 active_file:13 inactive_anon:352 [ 4.344703] inactive_file:4 unevictable:0 dirty:6 writeback:0 unstable:0 [ 4.344706] free:1176 slab:3013 mapped:0 pagetables:3 bounce:0 [ 4.344717] DMA free:2000kB min:88kB low:108kB high:132kB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB present:15836kB pages_scanned:0 all_unreclaimable? yes [ 4.344725] lowmem_reserve[]: 0 482 482 482 [ 4.344740] Normal free:2704kB min:2760kB low:3448kB high:4140kB active_anon:1304kB inactive_anon:1408kB active_file:52kB inactive_file:16kB unevictable:0kB present:493624kB pages_scanned:0 all_unreclaimable? no [ 4.344748] lowmem_reserve[]: 0 0 0 0 [ 4.344758] DMA: 0*4kB 0*8kB 1*16kB 0*32kB 1*64kB 1*128kB 1*256kB 1*512kB 1*1024kB 0*2048kB 0*4096kB = 2000kB [ 4.344783] Normal: 0*4kB 0*8kB 1*16kB 0*32kB 0*64kB 1*128kB 0*256kB 1*512kB 0*1024kB 1*2048kB 0*4096kB = 2704kB [ 4.344808] 644 total pagecache pages [ 4.344813] 0 pages in swap cache [ 4.344818] Swap cache stats: add 0, delete 0, find 0/0 [ 4.344822] Free swap = 0kB [ 4.344826] Total swap = 0kB [ 4.348583] 128720 pages RAM [ 4.348588] 0 pages HighMem [ 4.348592] 3428 pages reserved [ 4.348596] 630 pages shared [ 4.348600] 122976 pages non-shared [ 4.982732] wl: module license 'MIXED/Proprietary' taints kernel. [ 4.982744] Disabling lock debugging due to kernel taint [ 4.990532] wl 0000:03:00.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17 [ 4.990555] wl 0000:03:00.0: setting latency timer to 64 [ 5.427550] ACPI: Battery Slot [BAT1] (battery present) [ 5.617895] compal-laptop: Identified laptop model 'Dell Mini 10v'. [ 5.618487] compal-laptop: driver 0.2.6 successfully loaded. [ 5.629208] lib80211_crypt: registered algorithm 'TKIP'
When ureadahead tries to profile on a system with 512MB of memory. The kernel gets an OOM error.
Below is the kernel stack dump.
2 memory+ 0x2f/0x113 memory+ 0x5e/0x86 pages_nodemask+ 0x36a/0x42f pages+0xf/ 0x21 resize+ 0x139/0x238 resize_ ring_buffer+ 0x2f/0x70 entries_ write+0xb2/ 0x136 buffer_ unlock_ commit+ 0x3f/0x46 raw_event_ do_sys_ open+0x85/ 0x8d 0x8a/0x112 entries_ write+0x0/ 0x136 do_call+ 0x12/0x26 anon:1408kB active_file:52kB inactive_file:16kB unevictable:0kB present:493624kB pages_scanned:0 all_unreclaimable? no pages_nodemask+ 0x3ed/0x42f pages+0xf/ 0x21 resize+ 0x139/0x238 resize_ ring_buffer+ 0x2f/0x70 entries_ write+0xb2/ 0x136 buffer_ unlock_ commit+ 0x3f/0x46 raw_event_ do_sys_ open+0x85/ 0x8d 0x8a/0x112 entries_ write+0x0/ 0x136 do_call+ 0x12/0x26 anon:1408kB active_file:52kB inactive_file:16kB unevictable:0kB present:493624kB pages_scanned:0 all_unreclaimable? no
3
4
5
6
7
8
9
10
11
12
13
14
15
16
17
18
19
20
21
22
23
24
25
26
27
28
29
30
31
32
33
34
35
36
37
38
39
40
41
42
43
44
45
46
47
48
49
50
51
52
53
54
55
56
57
58
59
60
61
62
63
64
65
66
67
68
69
70
71
72
73
74
75
76
77
78
79
80
81
82
83
84
85
86
87
88
89
90
91
92
93
94
95
96
97
[ 2.912175] Freeing unused kernel memory: 336k freed
[ 2.912713] Write protecting the kernel text: 4536k
[ 2.913052] Write protecting the kernel read-only data: 2004k
[ 4.335798] ureadahead invoked oom-killer: gfp_mask=0xd0, order=0, oomkilladj=0
[ 4.335820] Pid: 64, comm: ureadahead Not tainted 2.6.31-15-generic #50fastboot9-Ubuntu
[ 4.335832] Call Trace:
[ 4.335859] [<c1077c61>] T.393+0x61/0x1be
[ 4.335879] [<c1077ded>] __out_of_
[ 4.335896] [<c1077f2f>] out_of_
[ 4.335914] [<c107a20f>] __alloc_
[ 4.335933] [<c107a309>] __get_free_
[ 4.335951] [<c106227b>] ring_buffer_
[ 4.335970] [<c1062c03>] tracing_
[ 4.335987] [<c1065ba2>] tracing_
[ 4.336005] [<c10651d3>] ? trace_nowake_
[ 4.336051] [<c10982a6>] ? ftrace_
[ 4.336069] [<c109a599>] vfs_write+
[ 4.336086] [<c1065af0>] ? tracing_
[ 4.336103] [<c109a6b8>] sys_write+0x3b/0x60
[ 4.336121] [<c1002af4>] sysenter_
[ 4.336132] Mem-Info:
[ 4.336140] DMA per-cpu:
[ 4.336150] CPU 0: hi: 0, btch: 1 usd: 0
[ 4.336161] CPU 1: hi: 0, btch: 1 usd: 0
[ 4.336170] Normal per-cpu:
[ 4.336181] CPU 0: hi: 186, btch: 31 usd: 156
[ 4.336192] CPU 1: hi: 186, btch: 31 usd: 161
[ 4.336212] Active_anon:326 active_file:13 inactive_anon:352
[ 4.336218] inactive_file:4 unevictable:0 dirty:6 writeback:0 unstable:0
[ 4.336224] free:1176 slab:3013 mapped:0 pagetables:3 bounce:0
[ 4.336245] DMA free:2000kB min:88kB low:108kB high:132kB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB present:15836kB pages_scanned:0 all_unreclaimable? yes
[ 4.336270] lowmem_reserve[]: 0 482 482 482
[ 4.336312] Normal free:2704kB min:2760kB low:3448kB high:4140kB active_anon:1304kB inactive_
[ 4.336338] lowmem_reserve[]: 0 0 0 0
[ 4.336362] DMA: 0*4kB 0*8kB 1*16kB 0*32kB 1*64kB 1*128kB 1*256kB 1*512kB 1*1024kB 0*2048kB 0*4096kB = 2000kB
[ 4.336414] Normal: 0*4kB 0*8kB 1*16kB 0*32kB 0*64kB 1*128kB 0*256kB 1*512kB 0*1024kB 1*2048kB 0*4096kB = 2704kB
[ 4.336464] 644 total pagecache pages
[ 4.336473] 0 pages in swap cache
[ 4.336482] Swap cache stats: add 0, delete 0, find 0/0
[ 4.336491] Free swap = 0kB
[ 4.336499] Total swap = 0kB
[ 4.344121] 128720 pages RAM
[ 4.344132] 0 pages HighMem
[ 4.344140] 3428 pages reserved
[ 4.344148] 630 pages shared
[ 4.344155] 122976 pages non-shared
[ 4.344169] Out of memory: kill process 64 (ureadahead) score 33 or a child
[ 4.344199] Killed process 64 (ureadahead)
[ 4.344353] ureadahead: page allocation failure. order:0, mode:0xd0
[ 4.344372] Pid: 64, comm: ureadahead Not tainted 2.6.31-15-generic #50fastboot9-Ubuntu
[ 4.344382] Call Trace:
[ 4.344407] [<c146ac77>] ? printk+0xf/0x11
[ 4.344429] [<c107a292>] __alloc_
[ 4.344449] [<c107a309>] __get_free_
[ 4.344468] [<c106227b>] ring_buffer_
[ 4.344486] [<c1062c03>] tracing_
[ 4.344503] [<c1065ba2>] tracing_
[ 4.344521] [<c10651d3>] ? trace_nowake_
[ 4.344541] [<c10982a6>] ? ftrace_
[ 4.344559] [<c109a599>] vfs_write+
[ 4.344575] [<c1065af0>] ? tracing_
[ 4.344592] [<c109a6b8>] sys_write+0x3b/0x60
[ 4.344610] [<c1002af4>] sysenter_
[ 4.344621] Mem-Info:
[ 4.344629] DMA per-cpu:
[ 4.344638] CPU 0: hi: 0, btch: 1 usd: 0
[ 4.344649] CPU 1: hi: 0, btch: 1 usd: 0
[ 4.344658] Normal per-cpu:
[ 4.344669] CPU 0: hi: 186, btch: 31 usd: 156
[ 4.344689] CPU 1: hi: 186, btch: 31 usd: 161
[ 4.344700] Active_anon:326 active_file:13 inactive_anon:352
[ 4.344703] inactive_file:4 unevictable:0 dirty:6 writeback:0 unstable:0
[ 4.344706] free:1176 slab:3013 mapped:0 pagetables:3 bounce:0
[ 4.344717] DMA free:2000kB min:88kB low:108kB high:132kB active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB unevictable:0kB present:15836kB pages_scanned:0 all_unreclaimable? yes
[ 4.344725] lowmem_reserve[]: 0 482 482 482
[ 4.344740] Normal free:2704kB min:2760kB low:3448kB high:4140kB active_anon:1304kB inactive_
[ 4.344748] lowmem_reserve[]: 0 0 0 0
[ 4.344758] DMA: 0*4kB 0*8kB 1*16kB 0*32kB 1*64kB 1*128kB 1*256kB 1*512kB 1*1024kB 0*2048kB 0*4096kB = 2000kB
[ 4.344783] Normal: 0*4kB 0*8kB 1*16kB 0*32kB 0*64kB 1*128kB 0*256kB 1*512kB 0*1024kB 1*2048kB 0*4096kB = 2704kB
[ 4.344808] 644 total pagecache pages
[ 4.344813] 0 pages in swap cache
[ 4.344818] Swap cache stats: add 0, delete 0, find 0/0
[ 4.344822] Free swap = 0kB
[ 4.344826] Total swap = 0kB
[ 4.348583] 128720 pages RAM
[ 4.348588] 0 pages HighMem
[ 4.348592] 3428 pages reserved
[ 4.348596] 630 pages shared
[ 4.348600] 122976 pages non-shared
[ 4.982732] wl: module license 'MIXED/Proprietary' taints kernel.
[ 4.982744] Disabling lock debugging due to kernel taint
[ 4.990532] wl 0000:03:00.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17
[ 4.990555] wl 0000:03:00.0: setting latency timer to 64
[ 5.427550] ACPI: Battery Slot [BAT1] (battery present)
[ 5.617895] compal-laptop: Identified laptop model 'Dell Mini 10v'.
[ 5.618487] compal-laptop: driver 0.2.6 successfully loaded.
[ 5.629208] lib80211_crypt: registered algorithm 'TKIP'