Sasha Goldshtein | dda4769 | 2016-02-08 03:10:13 -0800 | [diff] [blame] | 1 | Demonstrations of memleak. |
| 2 | |
| 3 | |
| 4 | memleak traces and matches memory allocation and deallocation requests, and |
| 5 | collects call stacks for each allocation. memleak can then print a summary |
| 6 | of which call stacks performed allocations that weren't subsequently freed. |
| 7 | For example: |
| 8 | |
| 9 | # ./memleak.py -p $(pidof allocs) |
| 10 | Attaching to malloc and free in pid 5193, Ctrl+C to quit. |
Sasha Goldshtein | 828edb5 | 2016-02-09 11:18:32 -0800 | [diff] [blame] | 11 | [11:16:33] Top 2 stacks with outstanding allocations: |
Sasha Goldshtein | dda4769 | 2016-02-08 03:10:13 -0800 | [diff] [blame] | 12 | 80 bytes in 5 allocations from stack |
| 13 | main+0x6d [/home/vagrant/allocs] (400862) |
| 14 | __libc_start_main+0xf0 [/usr/lib64/libc-2.21.so] (7fd460ac2790) |
| 15 | |
Sasha Goldshtein | 828edb5 | 2016-02-09 11:18:32 -0800 | [diff] [blame] | 16 | [11:16:34] Top 2 stacks with outstanding allocations: |
Sasha Goldshtein | dda4769 | 2016-02-08 03:10:13 -0800 | [diff] [blame] | 17 | 160 bytes in 10 allocations from stack |
| 18 | main+0x6d [/home/vagrant/allocs] (400862) |
| 19 | __libc_start_main+0xf0 [/usr/lib64/libc-2.21.so] (7fd460ac2790) |
| 20 | |
| 21 | |
Sasha Goldshtein | b9b9ad3 | 2016-02-08 03:41:43 -0800 | [diff] [blame] | 22 | Each entry printed is a set of allocations that originate from the same call |
| 23 | stack, and that weren't freed yet. The number of bytes and number of allocs |
| 24 | are followed by the call stack, top to bottom, of the allocation site. |
| 25 | |
Sasha Goldshtein | 33522d7 | 2016-02-08 03:39:44 -0800 | [diff] [blame] | 26 | As time goes on, it becomes apparent that the main function in the allocs |
| 27 | process is leaking memory, 16 bytes at a time. Fortunately, you don't have to |
| 28 | inspect each allocation individually -- you get a nice summary of which stack |
| 29 | is responsible for a large leak. |
| 30 | |
| 31 | Occasionally, you do want the individual allocation details. Perhaps the same |
| 32 | stack is allocating various sizes and you want to confirm which sizes are |
| 33 | prevalent. Use the -a switch: |
| 34 | |
| 35 | # ./memleak.py -p $(pidof allocs) -a |
| 36 | Attaching to malloc and free in pid 5193, Ctrl+C to quit. |
Sasha Goldshtein | 828edb5 | 2016-02-09 11:18:32 -0800 | [diff] [blame] | 37 | [11:16:33] Top 2 stacks with outstanding allocations: |
Sasha Goldshtein | 33522d7 | 2016-02-08 03:39:44 -0800 | [diff] [blame] | 38 | addr = 948cd0 size = 16 |
| 39 | addr = 948d10 size = 16 |
| 40 | addr = 948d30 size = 16 |
| 41 | addr = 948cf0 size = 16 |
| 42 | 64 bytes in 4 allocations from stack |
| 43 | main+0x6d [/home/vagrant/allocs] (400862) |
| 44 | __libc_start_main+0xf0 [/usr/lib64/libc-2.21.so] (7fd460ac2790) |
| 45 | |
Sasha Goldshtein | 828edb5 | 2016-02-09 11:18:32 -0800 | [diff] [blame] | 46 | [11:16:34] Top 2 stacks with outstanding allocations: |
Sasha Goldshtein | 33522d7 | 2016-02-08 03:39:44 -0800 | [diff] [blame] | 47 | addr = 948d50 size = 16 |
| 48 | addr = 948cd0 size = 16 |
| 49 | addr = 948d10 size = 16 |
| 50 | addr = 948d30 size = 16 |
| 51 | addr = 948cf0 size = 16 |
| 52 | addr = 948dd0 size = 16 |
| 53 | addr = 948d90 size = 16 |
| 54 | addr = 948db0 size = 16 |
| 55 | addr = 948d70 size = 16 |
| 56 | addr = 948df0 size = 16 |
| 57 | 160 bytes in 10 allocations from stack |
| 58 | main+0x6d [/home/vagrant/allocs] (400862) |
| 59 | __libc_start_main+0xf0 [/usr/lib64/libc-2.21.so] (7fd460ac2790) |
| 60 | |
| 61 | |
Sasha Goldshtein | dda4769 | 2016-02-08 03:10:13 -0800 | [diff] [blame] | 62 | When using the -p switch, memleak traces the allocations of a particular |
| 63 | process. Without this switch, kernel allocations (kmalloc) are traced instead. |
| 64 | For example: |
| 65 | |
| 66 | # ./memleak.py |
| 67 | Attaching to kmalloc and kfree, Ctrl+C to quit. |
| 68 | ... |
| 69 | 248 bytes in 4 allocations from stack |
| 70 | bpf_prog_load [kernel] (ffffffff8118c471) |
| 71 | sys_bpf [kernel] (ffffffff8118c8b5) |
| 72 | |
| 73 | 328 bytes in 1 allocations from stack |
| 74 | perf_mmap [kernel] (ffffffff811990fd) |
| 75 | mmap_region [kernel] (ffffffff811df5d4) |
| 76 | do_mmap [kernel] (ffffffff811dfb83) |
| 77 | vm_mmap_pgoff [kernel] (ffffffff811c494f) |
| 78 | sys_mmap_pgoff [kernel] (ffffffff811ddf02) |
| 79 | sys_mmap [kernel] (ffffffff8101b0ab) |
| 80 | |
| 81 | 464 bytes in 1 allocations from stack |
| 82 | traceprobe_command [kernel] (ffffffff81187cf2) |
| 83 | traceprobe_probes_write [kernel] (ffffffff81187d86) |
| 84 | probes_write [kernel] (ffffffff81181580) |
| 85 | __vfs_write [kernel] (ffffffff812237b7) |
| 86 | vfs_write [kernel] (ffffffff81223ec6) |
| 87 | sys_write [kernel] (ffffffff81224b85) |
| 88 | entry_SYSCALL_64_fastpath [kernel] (ffffffff8178182e) |
| 89 | |
| 90 | 8192 bytes in 1 allocations from stack |
| 91 | alloc_and_copy_ftrace_hash.constprop.59 [kernel] (ffffffff8115d17e) |
| 92 | ftrace_set_hash [kernel] (ffffffff8115e767) |
| 93 | ftrace_set_filter_ip [kernel] (ffffffff8115e9a8) |
| 94 | arm_kprobe [kernel] (ffffffff81148600) |
| 95 | enable_kprobe [kernel] (ffffffff811486f6) |
| 96 | kprobe_register [kernel] (ffffffff81182399) |
| 97 | perf_trace_init [kernel] (ffffffff8117c4e0) |
| 98 | perf_tp_event_init [kernel] (ffffffff81192479) |
| 99 | |
| 100 | |
Sasha Goldshtein | 33522d7 | 2016-02-08 03:39:44 -0800 | [diff] [blame] | 101 | Here you can see that arming the kprobe to which our eBPF program is attached |
| 102 | consumed 8KB of memory. Loading the BPF program also consumed a couple hundred |
| 103 | bytes (in bpf_prog_load). |
| 104 | |
Sasha Goldshtein | dda4769 | 2016-02-08 03:10:13 -0800 | [diff] [blame] | 105 | memleak stores each allocated block along with its size, timestamp, and the |
| 106 | stack that allocated it. When the block is deleted, this information is freed |
| 107 | to reduce the memory overhead. |
| 108 | |
| 109 | To avoid false positives, allocations younger than a certain age (500ms by |
| 110 | default) are not printed. To change this threshold, use the -o switch. |
| 111 | |
| 112 | By default, memleak prints its output every 5 seconds. To change this |
Sasha Goldshtein | 75ba13f | 2016-02-09 06:03:46 -0800 | [diff] [blame] | 113 | interval, pass the interval as a positional parameter to memleak. You can |
| 114 | also control the number of times the output will be printed before exiting. |
| 115 | For example: |
| 116 | |
| 117 | # ./memleak.py 1 10 |
| 118 | |
| 119 | ... will print the outstanding allocation statistics every second, for ten |
| 120 | times, and then exit. |
Sasha Goldshtein | dda4769 | 2016-02-08 03:10:13 -0800 | [diff] [blame] | 121 | |
Sasha Goldshtein | d2241f4 | 2016-02-09 06:23:10 -0800 | [diff] [blame] | 122 | memleak may introduce considerable overhead if your application or kernel is |
| 123 | allocating and freeing memory at a very high rate. In that case, you can |
| 124 | control the overhead by sampling every N-th allocation. For example, to sample |
| 125 | roughly 10% of the allocations and print the outstanding allocations every 5 |
| 126 | seconds, 3 times before quitting: |
| 127 | |
| 128 | # ./memleak.py -p $(pidof allocs) -s 10 5 3 |
| 129 | Attaching to malloc and free in pid 2614, Ctrl+C to quit. |
Sasha Goldshtein | 828edb5 | 2016-02-09 11:18:32 -0800 | [diff] [blame] | 130 | [11:16:33] Top 2 stacks with outstanding allocations: |
Sasha Goldshtein | d2241f4 | 2016-02-09 06:23:10 -0800 | [diff] [blame] | 131 | 16 bytes in 1 allocations from stack |
| 132 | main+0x6d [/home/vagrant/allocs] (400862) |
| 133 | __libc_start_main+0xf0 [/usr/lib64/libc-2.21.so] (7fdc11ce8790) |
| 134 | |
Sasha Goldshtein | 828edb5 | 2016-02-09 11:18:32 -0800 | [diff] [blame] | 135 | [11:16:38] Top 2 stacks with outstanding allocations: |
Sasha Goldshtein | d2241f4 | 2016-02-09 06:23:10 -0800 | [diff] [blame] | 136 | 16 bytes in 1 allocations from stack |
| 137 | main+0x6d [/home/vagrant/allocs] (400862) |
| 138 | __libc_start_main+0xf0 [/usr/lib64/libc-2.21.so] (7fdc11ce8790) |
| 139 | |
Sasha Goldshtein | 828edb5 | 2016-02-09 11:18:32 -0800 | [diff] [blame] | 140 | [11:16:43] Top 2 stacks with outstanding allocations: |
Sasha Goldshtein | d2241f4 | 2016-02-09 06:23:10 -0800 | [diff] [blame] | 141 | 32 bytes in 2 allocations from stack |
| 142 | main+0x6d [/home/vagrant/allocs] (400862) |
| 143 | __libc_start_main+0xf0 [/usr/lib64/libc-2.21.so] (7fdc11ce8790) |
| 144 | |
| 145 | Note that even though the application leaks 16 bytes of memory every second, |
| 146 | the report (printed every 5 seconds) doesn't "see" all the allocations because |
| 147 | of the sampling rate applied. |
| 148 | |
Sasha Goldshtein | dda4769 | 2016-02-08 03:10:13 -0800 | [diff] [blame] | 149 | |
| 150 | USAGE message: |
| 151 | |
| 152 | # ./memleak.py -h |
Sasha Goldshtein | 75ba13f | 2016-02-09 06:03:46 -0800 | [diff] [blame] | 153 | usage: memleak.py [-h] [-p PID] [-t] [-a] [-o OLDER] [-c COMMAND] |
Sasha Goldshtein | c8148c8 | 2016-02-09 11:15:41 -0800 | [diff] [blame] | 154 | [-s SAMPLE_RATE] [-d STACK_DEPTH] [-T TOP] |
Sasha Goldshtein | 75ba13f | 2016-02-09 06:03:46 -0800 | [diff] [blame] | 155 | [interval] [count] |
Sasha Goldshtein | dda4769 | 2016-02-08 03:10:13 -0800 | [diff] [blame] | 156 | |
| 157 | Trace outstanding memory allocations that weren't freed. |
| 158 | Supports both user-mode allocations made with malloc/free and kernel-mode |
| 159 | allocations made with kmalloc/kfree. |
| 160 | |
Sasha Goldshtein | 75ba13f | 2016-02-09 06:03:46 -0800 | [diff] [blame] | 161 | interval interval in seconds to print outstanding allocations |
| 162 | count number of times to print the report before exiting |
| 163 | |
Sasha Goldshtein | dda4769 | 2016-02-08 03:10:13 -0800 | [diff] [blame] | 164 | optional arguments: |
| 165 | -h, --help show this help message and exit |
| 166 | -p PID, --pid PID the PID to trace; if not specified, trace kernel |
| 167 | allocs |
| 168 | -t, --trace print trace messages for each alloc/free call |
Sasha Goldshtein | dda4769 | 2016-02-08 03:10:13 -0800 | [diff] [blame] | 169 | -a, --show-allocs show allocation addresses and sizes as well as call |
| 170 | stacks |
| 171 | -o OLDER, --older OLDER |
| 172 | prune allocations younger than this age in |
| 173 | milliseconds |
| 174 | -c COMMAND, --command COMMAND |
| 175 | execute and trace the specified command |
Sasha Goldshtein | 521ab4f | 2016-02-08 05:48:31 -0800 | [diff] [blame] | 176 | -s SAMPLE_RATE, --sample-rate SAMPLE_RATE |
| 177 | sample every N-th allocation to decrease the overhead |
Sasha Goldshtein | d2241f4 | 2016-02-09 06:23:10 -0800 | [diff] [blame] | 178 | -d STACK_DEPTH, --stack_depth STACK_DEPTH |
| 179 | maximum stack depth to capture |
Sasha Goldshtein | c8148c8 | 2016-02-09 11:15:41 -0800 | [diff] [blame] | 180 | -T TOP, --top TOP display only this many top allocating stacks (by size) |
Sasha Goldshtein | 5045964 | 2016-02-10 08:35:20 -0800 | [diff] [blame] | 181 | -z MIN_SIZE, --min-size MIN_SIZE |
| 182 | capture only allocations larger than this size |
| 183 | -Z MAX_SIZE, --max-size MAX_SIZE |
| 184 | capture only allocations smaller than this size |
Sasha Goldshtein | dda4769 | 2016-02-08 03:10:13 -0800 | [diff] [blame] | 185 | |
| 186 | EXAMPLES: |
| 187 | |
| 188 | ./memleak.py -p $(pidof allocs) |
| 189 | Trace allocations and display a summary of "leaked" (outstanding) |
| 190 | allocations every 5 seconds |
| 191 | ./memleak.py -p $(pidof allocs) -t |
| 192 | Trace allocations and display each individual call to malloc/free |
Sasha Goldshtein | 75ba13f | 2016-02-09 06:03:46 -0800 | [diff] [blame] | 193 | ./memleak.py -ap $(pidof allocs) 10 |
| 194 | Trace allocations and display allocated addresses, sizes, and stacks |
| 195 | every 10 seconds for outstanding allocations |
| 196 | ./memleak.py -c "./allocs" |
| 197 | Run the specified command and trace its allocations |
| 198 | ./memleak.py |
| 199 | Trace allocations in kernel mode and display a summary of outstanding |
| 200 | allocations every 5 seconds |
| 201 | ./memleak.py -o 60000 |
| 202 | Trace allocations in kernel mode and display a summary of outstanding |
| 203 | allocations that are at least one minute (60 seconds) old |
Sasha Goldshtein | 521ab4f | 2016-02-08 05:48:31 -0800 | [diff] [blame] | 204 | ./memleak.py -s 5 |
| 205 | Trace roughly every 5th allocation, to reduce overhead |