[OpenWrt-Devel] trouble with netifd

e9hack e9hack at gmail.com
Mon May 21 01:39:55 EDT 2018


Hi,

the issue looks like a memory problem of kernel 4.14. If I reboot the router or restart the network, netifd doesn't
crash again. Independently of this, memory consumption does increase very fast. I let run an endless loop to call free
every 15min. The output from the beginning:
Sun May 20 07:43:42 CEST 2018
             total       used       free     shared    buffers     cached
Mem:         28040      25996       2044         60       3112       5100
-/+ buffers/cache:      17784      10256
Swap:            0          0          0

The same after 10 hours:
Sun May 20 17:35:37 CEST 2018
             total       used       free     shared    buffers     cached
Mem:         28040      26732       1308         60        852       1504
-/+ buffers/cache:      24376       3664
Swap:            0          0          0

During the next loop, the router does restart. If I try the same with kernel 4.9, at the beginning:
Sun May 20 19:36:53 CEST 2018
             total       used       free     shared    buffers     cached
Mem:         28336      25988       2348        128       2952       6412
-/+ buffers/cache:      16624      11712
Swap:            0          0          0

After 12hours:
Mon May 21 07:16:57 CEST 2018
             total       used       free     shared    buffers     cached
Mem:         28336      25928       2408        128       2952       6420
-/+ buffers/cache:      16556      11780
Swap:            0          0          0

With kernel 4.14, the memory consumption increase from 17784k to 24376k, with kernel 4.9 it is stable at 16556k.

What can be the reason for this increasing of used memory?

Currently, the main tree isn't usable for carambola box with target ramips.

Regards,
Hartmut

Am 13.05.2018 um 13:22 schrieb e9hack:
> Hi,
> 
> I've trouble with netifd on a carambola box (ralink rt305x target) from 8devices. After a sysupgrade, netifd crashes all
> the time at the first boot. Lan/wan access over cable isn't possible. Wireless access is possible. After a reboot,
> netifd doesn't crash again.
> 
> ...
> Sat May 12 23:54:13 2018 daemon.err block: /dev/mtdblock5 is already mounted on /rom
> Sat May 12 23:54:13 2018 daemon.err block: /dev/mtdblock6 is already mounted on /overlay
> Sat May 12 23:54:18 2018 authpriv.info dropbear[1179]: Not backgrounding
> Sat May 12 23:54:23 2018 kern.warn kernel: [   70.576598] netifd: page allocation failure: order:0,
> mode:0x1080120(GFP_ATOMIC|__GFP_COLD), nodemask=(null)
> Sat May 12 23:54:23 2018 kern.warn kernel: [   70.586606] CPU: 0 PID: 1086 Comm: netifd Not tainted 4.14.37 #0
> Sat May 12 23:54:23 2018 kern.warn kernel: [   70.592668] Stack : 00000006 80443640 00000000 00000000 00000000 00000000
> 804474e2 00000034
> Sat May 12 23:54:23 2018 kern.warn kernel: [   70.601134]         803f0000 803ed8a7 803b55ac 0000043e 80443640 00000001
> 80a2bb20 803f0000
> Sat May 12 23:54:23 2018 kern.warn kernel: [   70.609601]         00000000 00000000 80440000 000000cb 00000000 6465746e
> 312e3420 37332e34
> Sat May 12 23:54:23 2018 kern.warn kernel: [   70.618067]         646f6e20 0000003c 00000020 73616d65 803f0000 80000000
> 00000000 00000000
> Sat May 12 23:54:23 2018 kern.warn kernel: [   70.626532]         00000000 000003e8 77f39000 00000000 00000003 801bce3c
> 08052098 80440000
> Sat May 12 23:54:23 2018 kern.warn kernel: [   70.634998]         ...
> Sat May 12 23:54:23 2018 kern.warn kernel: [   70.637474] Call Trace:
> Sat May 12 23:54:23 2018 kern.warn kernel: [   70.639997] [<8000e504>] show_stack+0x8c/0x130
> Sat May 12 23:54:23 2018 kern.warn kernel: [   70.644511] [<80090944>] warn_alloc+0xbc/0x178
> Sat May 12 23:54:23 2018 kern.warn kernel: [   70.649010] [<80091228>] __alloc_pages_nodemask+0x79c/0x9e4
> Sat May 12 23:54:23 2018 kern.warn kernel: [   70.654648] [<800915e8>] page_frag_alloc+0x6c/0x160
> Sat May 12 23:54:23 2018 kern.warn kernel: [   70.659619] [<802172c8>] netdev_alloc_frag+0x30/0x54
> Sat May 12 23:54:23 2018 kern.warn kernel: [   70.664656] [<801fd6d4>] fe_open+0x3e0/0x548
> Sat May 12 23:54:23 2018 kern.warn kernel: [   70.668985] [<8022d1e0>] __dev_open+0x128/0x140
> Sat May 12 23:54:23 2018 kern.warn kernel: [   70.673571] [<8022d4c8>] __dev_change_flags+0xf4/0x1c0
> Sat May 12 23:54:23 2018 kern.warn kernel: [   70.678768] [<8022d5bc>] dev_change_flags+0x28/0x70
> Sat May 12 23:54:23 2018 kern.warn kernel: [   70.683731] [<80249c78>] dev_ifsioc+0x1fc/0x384
> Sat May 12 23:54:23 2018 kern.warn kernel: [   70.688319] [<8024a188>] dev_ioctl+0x30c/0x714
> Sat May 12 23:54:23 2018 kern.warn kernel: [   70.692843] [<800d63f0>] vfs_ioctl+0x28/0x40
> Sat May 12 23:54:23 2018 kern.warn kernel: [   70.697169] [<800d65d0>] do_vfs_ioctl+0xcc/0x73c
> Sat May 12 23:54:23 2018 kern.warn kernel: [   70.701844] [<800d6c90>] SyS_ioctl+0x50/0x90
> Sat May 12 23:54:23 2018 kern.warn kernel: [   70.706179] [<800133ac>] syscall_common+0x34/0x58
> Sat May 12 23:54:23 2018 kern.warn kernel: [   70.710937] Mem-Info:
> Sat May 12 23:54:23 2018 kern.warn kernel: [   70.713274] active_anon:261 inactive_anon:2 isolated_anon:0
> Sat May 12 23:54:23 2018 kern.warn kernel: [   70.713274]  active_file:1734 inactive_file:1418 isolated_file:0
> Sat May 12 23:54:23 2018 kern.warn kernel: [   70.713274]  unevictable:0 dirty:0 writeback:0 unstable:0
> Sat May 12 23:54:23 2018 kern.warn kernel: [   70.713274]  slab_reclaimable:445 slab_unreclaimable:1352
> Sat May 12 23:54:23 2018 kern.warn kernel: [   70.713274]  mapped:580 shmem:5 pagetables:39 bounce:0
> Sat May 12 23:54:23 2018 kern.warn kernel: [   70.713274]  free:96 free_pcp:0 free_cma:0
> Sat May 12 23:54:23 2018 kern.warn kernel: [   70.745152] Node 0 active_anon:1044kB inactive_anon:8kB active_file:6936kB
> inactive_file:5672kB unevictable:0kB isolated(anon):0kB isolated(file):0kB mapped:2320kB dirty:0kB writeback:0kB
> shmem:20kB writeback_tmp:0kB unstable:0kB all_unreclaimable? no
> Sat May 12 23:54:23 2018 kern.warn kernel: [   70.767633] Normal free:384kB min:1024kB low:1280kB high:1536kB
> active_anon:1044kB inactive_anon:8kB active_file:6936kB inactive_file:5672kB unevictable:0kB writepending:0kB
> present:32768kB managed:28044kB mlocked:0kB kernel_stack:256kB pagetables:156kB bounce:0kB free_pcp:0kB local_pcp:0kB
> free_cma:0kB
> Sat May 12 23:54:23 2018 kern.warn kernel: [   70.794706] lowmem_reserve[]: 0 0
> Sat May 12 23:54:23 2018 kern.warn kernel: [   70.798063] Normal: 10*4kB (M) 23*8kB (UM) 10*16kB (UM) 0*32kB 0*64kB
> 0*128kB 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 384kB
> Sat May 12 23:54:23 2018 kern.warn kernel: [   70.809818] 3157 total pagecache pages
> Sat May 12 23:54:23 2018 kern.warn kernel: [   70.813601] 8192 pages RAM
> Sat May 12 23:54:23 2018 kern.warn kernel: [   70.816332] 0 pages HighMem/MovableOnly
> Sat May 12 23:54:23 2018 kern.warn kernel: [   70.820202] 1181 pages reserved
> Sat May 12 23:54:23 2018 kern.info kernel: [   71.017567] br-lan: port 1(eth0.1) entered blocking state
> Sat May 12 23:54:23 2018 kern.info kernel: [   71.023206] br-lan: port 1(eth0.1) entered disabled state
> Sat May 12 23:54:23 2018 kern.info kernel: [   71.029582] device eth0.1 entered promiscuous mode
> Sat May 12 23:54:24 2018 daemon.notice netifd: Interface 'lan' is enabled
> Sat May 12 23:54:24 2018 daemon.notice netifd: Interface 'lan' is setting up now
> Sat May 12 23:54:24 2018 kern.info kernel: [   71.069251] IPv6: ADDRCONF(NETDEV_UP): br-lan: link is not ready
> Sat May 12 23:54:24 2018 daemon.notice netifd: Interface 'lan' is now up
> Sat May 12 23:54:24 2018 daemon.notice netifd: Interface 'loopback' is enabled
> Sat May 12 23:54:24 2018 daemon.notice netifd: Interface 'loopback' is setting up now
> Sat May 12 23:54:24 2018 daemon.notice netifd: Interface 'loopback' is now up
> Sat May 12 23:54:24 2018 daemon.notice netifd: Interface 'wan' is enabled
> Sat May 12 23:54:24 2018 daemon.notice netifd: Interface 'wan' is setting up now
> Sat May 12 23:54:24 2018 daemon.notice netifd: Network device 'lo' link is up
> Sat May 12 23:54:24 2018 daemon.notice netifd: Interface 'loopback' has link connectivity
> Sat May 12 23:54:25 2018 daemon.notice netifd: wan (1289): udhcpc: started, v1.28.3
> Sat May 12 23:54:28 2018 daemon.warn 6relayd[1302]: Failed to relay to ff02::2% (Permission denied)
> Sat May 12 23:54:28 2018 daemon.notice netifd: wan (1289): udhcpc: sending discover
> Sat May 12 23:54:28 2018 daemon.notice netifd: wan (1289): udhcpc: sendto: Network is down
> Sat May 12 23:54:28 2018 daemon.notice netifd: wan (1289): udhcpc: read error: Network is down, reopening socket
> ...
> 
> Regards,
> Hartmut
> 


_______________________________________________
openwrt-devel mailing list
openwrt-devel at lists.openwrt.org
http://lists.infradead.org/mailman/listinfo/openwrt-devel



More information about the openwrt-devel mailing list