From mboxrd@z Thu Jan 1 00:00:00 1970 Return-Path: Received: from mail-pf0-f200.google.com (mail-pf0-f200.google.com [209.85.192.200]) by kanga.kvack.org (Postfix) with ESMTP id A6F7E6B02DD for ; Thu, 3 Nov 2016 14:53:56 -0400 (EDT) Received: by mail-pf0-f200.google.com with SMTP id 17so14110101pfy.2 for ; Thu, 03 Nov 2016 11:53:56 -0700 (PDT) Received: from mail.linuxfoundation.org (mail.linuxfoundation.org. [140.211.169.12]) by mx.google.com with ESMTPS id qz8si9337933pab.25.2016.11.03.11.53.55 for (version=TLS1_2 cipher=ECDHE-RSA-AES128-GCM-SHA256 bits=128/128); Thu, 03 Nov 2016 11:53:55 -0700 (PDT) Date: Thu, 3 Nov 2016 11:53:53 -0700 From: Andrew Morton Subject: Re: [Bug 186671] New: OOM on system with just rsync running 32GB of ram 30GB of pagecache Message-Id: <20161103115353.de87ff35756a4ca8b21d2c57@linux-foundation.org> In-Reply-To: References: Mime-Version: 1.0 Content-Type: text/plain; charset=US-ASCII Content-Transfer-Encoding: 7bit Sender: owner-linux-mm@kvack.org List-ID: To: eliventer@gmail.com Cc: bugzilla-daemon@bugzilla.kernel.org, linux-mm@kvack.org, Michal Hocko (switched to email. Please respond via emailed reply-to-all, not via the bugzilla web interface). On Wed, 02 Nov 2016 13:02:39 +0000 bugzilla-daemon@bugzilla.kernel.org wrote: > https://bugzilla.kernel.org/show_bug.cgi?id=186671 > > Bug ID: 186671 > Summary: OOM on system with just rsync running 32GB of ram 30GB > of pagecache > Product: Memory Management > Version: 2.5 > Kernel Version: 4.9-rc3 > Hardware: x86-64 > OS: Linux > Tree: Mainline > Status: NEW > Severity: high > Priority: P1 > Component: Page Allocator > Assignee: akpm@linux-foundation.org > Reporter: eliventer@gmail.com > Regression: No > > Running rsync on a debian jessie system with 32GB of RAM and a big > 250TB btrfs filesystem. 30 GB of ram show up as cached, not much else > running on the system. Lots of page alloction stalls in dmesg before > hand, and several OOM's after this one as well until it finally killed > the rsync. So more traces available if desired. Started with the 4.7 > series kernels, thought it was going to be fixed in 4.9: OK, this looks bad. Please let's work it via email so do remember the reply-to-alls. > [93428.029768] irqbalance invoked oom-killer: > gfp_mask=0x24280ca(GFP_HIGHUSER_MOVABLE|__GFP_ZERO), nodemask=0-1, order=0, > oom_score_adj=0 > [93428.029824] irqbalance cpuset=/ mems_allowed=0-1 > [93428.029857] CPU: 11 PID: 2992 Comm: irqbalance Tainted: G W 4.9.0-rc3 > #1 > [93428.029945] 0000000000000000 ffffffff812946c9 ffffc90003d8bb10 > ffffc90003d8bb10 > [93428.029997] ffffffff81190dd5 0000000000000000 0000000000000000 > ffff88081db051c0 > [93428.030049] ffffc90003d8bb10 ffffffff81711866 0000000000000002 > 0000000000000213 > [93428.030101] Call Trace: > [93428.030127] [] ? dump_stack+0x46/0x5d > [93428.030157] [] ? dump_header.isra.20+0x75/0x1a6 > [93428.030189] [] ? oom_kill_process+0x219/0x3d0 > [93428.030218] [] ? out_of_memory+0xd9/0x570 > [93428.030246] [] ? __alloc_pages_slowpath+0xa4b/0xa80 > [93428.030276] [] ? __alloc_pages_nodemask+0x288/0x2c0 > [93428.030306] [] ? alloc_pages_vma+0xc1/0x240 > [93428.030337] [] ? handle_mm_fault+0xccb/0xe60 > [93428.030367] [] ? __do_page_fault+0x1c5/0x490 > [93428.030397] [] ? page_fault+0x22/0x30 > [93428.030425] [] ? copy_user_generic_string+0x2c/0x40 > [93428.030455] [] ? seq_read+0x305/0x370 > [93428.030483] [] ? proc_reg_read+0x3e/0x60 > [93428.030511] [] ? __vfs_read+0x1e/0x110 > [93428.030538] [] ? vfs_read+0x89/0x130 > [93428.030564] [] ? SyS_read+0x3d/0x90 > [93428.030591] [] ? entry_SYSCALL_64_fastpath+0x13/0x94 > [93428.030620] Mem-Info: > [93428.030647] active_anon:9283 inactive_anon:9905 isolated_anon:0 > [93428.030647] active_file:6752598 inactive_file:999166 isolated_file:288 > [93428.030647] unevictable:0 dirty:997857 writeback:1665 unstable:0 > [93428.030647] slab_reclaimable:203122 slab_unreclaimable:202102 > [93428.030647] mapped:7933 shmem:3170 pagetables:1752 bounce:0 > [93428.030647] free:39250 free_pcp:954 free_cma:0 > [93428.030800] Node 0 active_anon:24984kB inactive_anon:26704kB > active_file:14365920kB inactive_file:1341120kB unevictable:0kB > isolated(anon):0kB isolated(file):0kB mapped:15852kB dirty:1338044kB > writeback:3072kB shmem:0kB shmem_thp: 0kB shmem_pmdmapped: 0kB > anon_thp: 9484kB writeback_tmp:0kB unstable:0kB pages_scanned:23811175 > all_unreclaimable? yes > [93428.030933] Node 1 active_anon:12148kB inactive_anon:12916kB > active_file:12644472kB inactive_file:2655544kB unevictable:0kB > isolated(anon):0kB isolated(file):1152kB mapped:15880kB > dirty:2653384kB writeback:3588kB shmem:0kB shmem_thp: 0kB > shmem_pmdmapped: 0kB anon_thp: 3196kB writeback_tmp:0kB unstable:0kB > pages_scanned:23178917 all_unreclaimable? yes > [93428.031059] Node 0 Normal free:44968kB min:45192kB low:61736kB > high:78280kB active_anon:24984kB inactive_anon:26704kB > active_file:14365920kB inactive_file:1341120kB unevictable:0kB > writepending:1341116kB present:16777216kB managed:16546296kB > mlocked:0kB slab_reclaimable:413824kB slab_unreclaimable:253144kB > kernel_stack:3496kB pagetables:4104kB bounce:0kB free_pcp:1388kB > local_pcp:0kB free_cma:0kB > [93428.031211] lowmem_reserve[]: 0 0 0 0 > [93428.031245] Node 1 DMA free:15896kB min:40kB low:52kB high:64kB > active_anon:0kB inactive_anon:0kB active_file:0kB inactive_file:0kB > unevictable:0kB writepending:0kB present:15996kB managed:15896kB > mlocked:0kB slab_reclaimable:0kB slab_unreclaimable:0kB > kernel_stack:0kB pagetables:0kB bounce:0kB free_pcp:0kB local_pcp:0kB > free_cma:0kB > [93428.031373] lowmem_reserve[]: 0 3216 16045 16045 > [93428.031408] Node 1 DMA32 free:60288kB min:8996kB low:12288kB > high:15580kB active_anon:1360kB inactive_anon:1692kB > active_file:2735200kB inactive_file:427992kB unevictable:0kB > writepending:426716kB present:3378660kB managed:3304640kB mlocked:0kB > slab_reclaimable:55012kB slab_unreclaimable:17160kB kernel_stack:176kB > pagetables:132kB bounce:0kB free_pcp:120kB local_pcp:0kB free_cma:0kB > [93428.031544] lowmem_reserve[]: 0 0 12828 12828 > [93428.031579] Node 1 Normal free:35848kB min:35880kB low:49016kB > high:62152kB active_anon:10788kB inactive_anon:11224kB > active_file:9909272kB inactive_file:2227552kB unevictable:0kB > writepending:2230256kB present:13369344kB managed:13136800kB > mlocked:0kB slab_reclaimable:343652kB slab_unreclaimable:538104kB > kernel_stack:3112kB pagetables:2772kB bounce:0kB free_pcp:2308kB > local_pcp:148kB free_cma:0kB > [93428.031730] lowmem_reserve[]: 0 0 0 0 > [93428.031764] Node 0 Normal: 11132*4kB (UMH) 31*8kB (H) 12*16kB (H) > 0*32kB 0*64kB 0*128kB 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = > 44968kB > [93428.031853] Node 1 DMA: 0*4kB 1*8kB (U) 1*16kB (U) 0*32kB 2*64kB > (U) 1*128kB (U) 1*256kB (U) 0*512kB 1*1024kB (U) 1*2048kB (M) 3*4096kB > (M) = 15896kB > [93428.031956] Node 1 DMA32: 14990*4kB (UME) 41*8kB (UM) 0*16kB 0*32kB > 0*64kB 0*128kB 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 60288kB > [93428.032043] Node 1 Normal: 8958*4kB (M) 2*8kB (M) 0*16kB 0*32kB > 0*64kB 0*128kB 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 35848kB > [93428.032130] Node 0 hugepages_total=0 hugepages_free=0 > hugepages_surp=0 hugepages_size=1048576kB > [93428.032176] Node 0 hugepages_total=0 hugepages_free=0 > hugepages_surp=0 hugepages_size=2048kB > [93428.032222] Node 1 hugepages_total=0 hugepages_free=0 > hugepages_surp=0 hugepages_size=1048576kB > [93428.032267] Node 1 hugepages_total=0 hugepages_free=0 > hugepages_surp=0 hugepages_size=2048kB > [93428.032313] 7758107 total pagecache pages > [93428.032336] 2885 pages in swap cache > [93428.032360] Swap cache stats: add 609178, delete 606293, find 331548/559119 > [93428.032388] Free swap = 48055104kB > [93428.032411] Total swap = 48300028kB > [93428.032434] 8385304 pages RAM > [93428.032455] 0 pages HighMem/MovableOnly > [93428.032478] 134396 pages reserved > [93428.032500] 0 pages hwpoisoned > [93428.032522] [ pid ] uid tgid total_vm rss nr_ptes nr_pmds > swapents oom_score_adj name > [93428.032573] [ 1912] 0 1912 10572 1903 27 3 > 58 0 systemd-journal > [93428.032622] [ 1915] 0 1915 9953 482 22 4 > 304 -1000 systemd-udevd > [93428.032670] [ 2813] 0 2813 9270 432 24 3 > 114 0 rpcbind > [93428.032717] [ 2832] 102 2832 9320 438 23 3 > 150 0 rpc.statd > [93428.032765] [ 2848] 0 2848 5839 282 16 3 > 75 0 rpc.idmapd > [93428.032812] [ 2851] 104 2851 88525 1167 44 3 > 2225 0 apt-cacher-ng > [93428.032860] [ 2852] 0 2852 13796 754 32 3 > 168 -1000 sshd > [93428.032906] [ 2853] 0 2853 64668 751 28 3 > 153 0 rsyslogd > [93428.032954] [ 2854] 0 2854 6876 473 17 3 > 62 0 cron > [93428.033000] [ 2855] 0 2855 4756 389 15 3 > 45 0 atd > [93428.033046] [ 2856] 0 2856 7059 520 19 3 > 592 0 smartd > [93428.033093] [ 2860] 0 2860 7089 554 19 3 > 96 0 systemd-logind > [93428.033141] [ 2861] 106 2861 10531 549 26 3 > 102 -900 dbus-daemon > [93428.033194] [ 2990] 107 2990 7293 729 19 3 > 150 0 ntpd > [93428.033241] [ 2992] 0 2992 4853 417 16 3 > 31 0 irqbalance > [93428.033289] [ 3013] 0 3013 26571 387 43 3 > 258 0 sfcbd > [93428.033336] [ 3017] 0 3017 20392 270 40 3 > 235 0 sfcbd > [93428.033383] [ 3020] 0 3020 3180 229 9 3 > 39 0 mcelog > [93428.033429] [ 3050] 0 3050 22441 0 41 3 > 237 0 sfcbd > [93428.033476] [ 3051] 0 3051 57809 318 45 3 > 379 0 sfcbd > [93428.033523] [ 3371] 105 3371 18063 770 36 3 > 5046 0 snmpd > [93428.033569] [ 3473] 0 3473 39377 263 44 3 > 243 0 sfcbd > [93428.033616] [ 3479] 0 3479 58324 448 46 3 > 283 0 sfcbd > [93428.033663] [ 3561] 0 3561 262687 975 65 4 > 3828 0 dsm_sa_datamgrd > [93428.033711] [ 3565] 101 3565 13312 606 29 3 > 184 0 exim4 > [93428.033758] [ 3580] 0 3580 61531 1209 115 3 > 467 0 winbindd > [93428.033805] [ 3581] 0 3581 61531 1226 118 3 > 433 0 winbindd > [93428.033852] [ 3647] 0 3647 48584 826 37 4 > 260 0 dsm_sa_eventmgr > [93428.033900] [ 3670] 0 3670 99593 919 47 3 > 1346 0 dsm_sa_snmpd > [93428.033948] [ 3713] 0 3713 7923 307 16 3 > 116 0 dsm_om_connsvcd > [93428.033996] [ 3714] 0 3714 961001 15661 261 8 > 33671 0 dsm_om_connsvcd > [93428.036621] [ 3719] 0 3719 178651 0 57 4 > 3787 0 dsm_sa_datamgrd > [93428.036669] [ 3825] 0 3825 3604 403 12 3 > 38 0 agetty > [93428.036716] [ 3977] 0 3977 26472 831 54 3 > 252 0 sshd > [93428.036762] [ 3979] 1000 3979 8941 665 23 3 > 182 0 systemd > [93428.036809] [ 3980] 1000 3980 15684 0 34 3 > 542 0 (sd-pam) > [93428.036857] [ 3982] 1000 3982 26472 637 52 3 > 239 0 sshd > [93428.036903] [ 3983] 1000 3983 6041 701 16 3 > 686 0 bash > [93428.036950] [ 3998] 1000 3998 16853 517 37 3 > 127 0 su > [93428.036996] [ 3999] 0 3999 5483 820 15 3 > 65 0 bash > [93428.037043] [ 4534] 0 4534 3311 584 11 3 > 58 0 run_mirror.sh > [93428.037091] [14179] 0 14179 1450 49 8 3 > 23 0 flock > [93428.037137] [14180] 0 14180 9289 1293 23 3 > 3217 0 rsync > [93428.037188] [14181] 0 14181 7616 584 20 3 > 821 0 rsync > [93428.037237] [14182] 0 14182 9171 598 23 3 > 2352 0 rsync > [93428.037287] [15616] 0 15616 2050 535 9 3 > 0 0 less > [93428.037332] Out of memory: Kill process 3714 (dsm_om_connsvcd) > score 2 or sacrifice child > [93428.037455] Killed process 3714 (dsm_om_connsvcd) > total-vm:3844004kB, anon-rss:49616kB, file-rss:13028kB, shmem-rss:0kB > [93428.068402] oom_reaper: reaped process 3714 (dsm_om_connsvcd), now > anon-rss:0kB, file-rss:20kB, shmem-rss:0kB > > -- > You are receiving this mail because: > You are the assignee for the bug. -- To unsubscribe, send a message with 'unsubscribe linux-mm' in the body to majordomo@kvack.org. For more info on Linux MM, see: http://www.linux-mm.org/ . Don't email: email@kvack.org