[LEDE-DEV] trouble with netifd

Sun May 13 04:22:20 PDT 2018

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