Page Faults in DMESG? (simple-adblock, dnsmasq, 17.01.0, WNDR3800)


#1

I woke up this morning to no internet. After trying to reboot the router and modem, which failed, I eventually logged in and pulled up my kernel log. If you look toward the bottom, it appears some sort of failure has occured. I have no idea what to do about it. Ideas?

WNDR3800, LEDE 17.01.0

[ 0.000000] Linux version 4.4.50 (buildbot@builds-02.infra.lede-project.org) (gcc version 5.4.0 (LEDE GCC 5.4.0 r3101-bce140e) ) #0 Mon Feb 20 17:13:44 2017
[ 0.000000] MyLoader: sysp=aaaa5554, boardp=aaaa5554, parts=aaaa5554
[ 0.000000] bootconsole [early0] enabled
[ 0.000000] CPU0 revision is: 00019374 (MIPS 24Kc)
[ 0.000000] SoC: Atheros AR7161 rev 2
[ 0.000000] Determined physical RAM map:
[ 0.000000] memory: 08000000 @ 00000000 (usable)
[ 0.000000] Initrd not found or empty - disabling initrd
[ 0.000000] No valid device tree found, continuing without
[ 0.000000] Zone ranges:
[ 0.000000] Normal [mem 0x0000000000000000-0x0000000007ffffff]
[ 0.000000] Movable zone start for each node
[ 0.000000] Early memory node ranges
[ 0.000000] node 0: [mem 0x0000000000000000-0x0000000007ffffff]
[ 0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x0000000007ffffff]
[ 0.000000] On node 0 totalpages: 32768
[ 0.000000] free_area_init_node: node 0, pgdat 803d04d0, node_mem_map 81000000
[ 0.000000] Normal zone: 256 pages used for memmap
[ 0.000000] Normal zone: 0 pages reserved
[ 0.000000] Normal zone: 32768 pages, LIFO batch:7
[ 0.000000] Primary instruction cache 64kB, VIPT, 4-way, linesize 32 bytes.
[ 0.000000] Primary data cache 32kB, 4-way, VIPT, cache aliases, linesize 32 bytes
[ 0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
[ 0.000000] pcpu-alloc: [0] 0
[ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 32512
[ 0.000000] Kernel command line: board=WNDR3700 mtdparts=spi0.0:320k(u-boot)ro,128k(u-boot-env)ro,15872k(firmware),64k(art)ro console=ttyS0,115200 rootfstype=squashfs,jffs2 noinitrd
[ 0.000000] PID hash table entries: 512 (order: -1, 2048 bytes)
[ 0.000000] Dentry cache hash table entries: 16384 (order: 4, 65536 bytes)
[ 0.000000] Inode-cache hash table entries: 8192 (order: 3, 32768 bytes)
[ 0.000000] Writing ErrCtl register=00000000
[ 0.000000] Readback ErrCtl register=00000000
[ 0.000000] Memory: 125328K/131072K available (3071K kernel code, 165K rwdata, 412K rodata, 312K init, 205K bss, 5744K reserved, 0K cma-reserved)
[ 0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[ 0.000000] NR_IRQS:51
[ 0.000000] Clocks: CPU:680.000MHz, DDR:340.000MHz, AHB:170.000MHz, Ref:40.000MHz
[ 0.000000] clocksource: MIPS: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 5621354254 ns
[ 0.000008] sched_clock: 32 bits at 340MHz, resolution 2ns, wraps every 6316128254ns
[ 0.007787] Calibrating delay loop... 452.19 BogoMIPS (lpj=2260992)
[ 0.070505] pid_max: default: 32768 minimum: 301
[ 0.075223] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.081830] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.090759] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[ 0.101583] NET: Registered protocol family 16
[ 0.107044] MIPS: machine is NETGEAR WNDR3700/WNDR3800/WNDRMAC
[ 2.753242] registering PCI controller with io_map_base unset
[ 2.766226] PCI host bridge to bus 0000:00
[ 2.770379] pci_bus 0000:00: root bus resource [mem 0x10000000-0x16ffffff]
[ 2.777241] pci_bus 0000:00: root bus resource [io 0x0000]
[ 2.782809] pci_bus 0000:00: root bus resource [??? 0x00000000 flags 0x0]
[ 2.789581] pci_bus 0000:00: No busn resource found for root bus, will use [bus 00-ff]
[ 2.797543] pci 0000:00:11.0: [168c:ff1d] type 00 class 0x020000
[ 2.797564] pci 0000:00:11.0: fixup device configuration
[ 2.804079] pci 0000:00:11.0: reg 0x10: [mem 0x00000000-0x0000ffff]
[ 2.804133] pci 0000:00:11.0: PME# supported from D0 D3hot
[ 2.804316] pci 0000:00:12.0: [168c:ff1d] type 00 class 0x020000
[ 2.804335] pci 0000:00:12.0: fixup device configuration
[ 2.810872] pci 0000:00:12.0: reg 0x10: [mem 0x00000000-0x0000ffff]
[ 2.810921] pci 0000:00:12.0: PME# supported from D0 D3hot
[ 2.811109] pci_bus 0000:00: busn_res: [bus 00-ff] end is updated to 00
[ 2.811141] pci 0000:00:11.0: BAR 0: assigned [mem 0x10000000-0x1000ffff]
[ 2.817917] pci 0000:00:12.0: BAR 0: assigned [mem 0x10010000-0x1001ffff]
[ 2.824728] pci 0000:00:11.0: using irq 40 for pin 1
[ 2.829685] pci 0000:00:12.0: using irq 41 for pin 1
[ 2.835284] clocksource: Switched to clocksource MIPS
[ 2.841154] NET: Registered protocol family 2
[ 2.846346] TCP established hash table entries: 1024 (order: 0, 4096 bytes)
[ 2.853316] TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
[ 2.859711] TCP: Hash tables configured (established 1024 bind 1024)
[ 2.866157] UDP hash table entries: 256 (order: 0, 4096 bytes)
[ 2.871987] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[ 2.878441] NET: Registered protocol family 1
[ 2.882823] PCI: CLS 0 bytes, default 32
[ 2.886307] futex hash table entries: 256 (order: -1, 3072 bytes)
[ 2.892439] Crashlog allocated RAM at address 0x3f00000
[ 2.908922] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[ 2.914747] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
[ 2.926638] io scheduler noop registered
[ 2.930558] io scheduler deadline registered (default)
[ 2.935917] Serial: 8250/16550 driver, 16 ports, IRQ sharing enabled
[ 2.944545] console [ttyS0] disabled
[ 2.968173] serial8250.0: ttyS0 at MMIO 0x18020000 (irq = 11, base_baud = 10625000) is a 16550A
[ 2.976875] console [ttyS0] enabled
[ 2.983823] bootconsole [early0] disabled
[ 2.995478] m25p80 spi0.0: found mx25l12805d, expected m25p80
[ 3.001238] m25p80 spi0.0: mx25l12805d (16384 Kbytes)
[ 3.006326] 4 cmdlinepart partitions found on MTD device spi0.0
[ 3.012226] Creating 4 MTD partitions on "spi0.0":
[ 3.017029] 0x000000000000-0x000000050000 : "u-boot"
[ 3.023692] 0x000000050000-0x000000070000 : "u-boot-env"
[ 3.030359] 0x000000070000-0x000000ff0000 : "firmware"
[ 3.048327] 2 netgear-fw partitions found on MTD device firmware
[ 3.054333] 0x000000070000-0x0000001a6440 : "kernel"
[ 3.060477] 0x0000001a6440-0x000000ff0000 : "rootfs"
[ 3.066813] mtd: device 4 (rootfs) set to be root filesystem
[ 3.072495] 1 squashfs-split partitions found on MTD device rootfs
[ 3.078713] 0x0000003e0000-0x000000ff0000 : "rootfs_data"
[ 3.085514] 0x000000ff0000-0x000001000000 : "art"
[ 3.092397] Realtek RTL8366S ethernet switch driver version 0.2.2
[ 3.098576] rtl8366s rtl8366s: using GPIO pins 5 (SDA) and 7 (SCK)
[ 3.104827] rtl8366s rtl8366s: RTL8366 ver. 1 chip found
[ 3.151264] libphy: rtl8366s: probed
[ 3.476960] eth0: Atheros AG71xx at 0xb9000000, irq 4, mode:RGMII
[ 3.797088] ag71xx ag71xx.1: connected to PHY at rtl8366s:04 [uid=001cc960, driver=Generic PHY]
[ 3.806373] eth1: Atheros AG71xx at 0xba000000, irq 5, mode:RGMII
[ 3.814134] NET: Registered protocol family 10
[ 3.821863] NET: Registered protocol family 17
[ 3.826412] bridge: automatic filtering via arp/ip/ip6tables has been deprecated. Update your scripts to load br_netfilter if you need this.
[ 3.839091] 8021q: 802.1Q VLAN Support v1.8
[ 3.848948] VFS: Mounted root (squashfs filesystem) readonly on device 31:4.
[ 3.856989] Freeing unused kernel memory: 312K (803f2000 - 80440000)
[ 4.907240] init: Console is alive
[ 4.910825] init: - watchdog -
[ 5.806371] usbcore: registered new interface driver usbfs
[ 5.811957] usbcore: registered new interface driver hub
[ 5.817377] usbcore: registered new device driver usb
[ 5.826495] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[ 5.834191] ehci-platform: EHCI generic platform driver
[ 5.839554] ehci-platform ehci-platform: EHCI Host Controller
[ 5.845340] ehci-platform ehci-platform: new USB bus registered, assigned bus number 1
[ 5.853341] ehci-platform ehci-platform: irq 3, io mem 0x1b000000
[ 5.875318] ehci-platform ehci-platform: USB 2.0 started, EHCI 1.00
[ 5.882363] hub 1-0:1.0: USB hub found
[ 5.886425] hub 1-0:1.0: 2 ports detected
[ 5.892639] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[ 5.899910] ohci-platform: OHCI generic platform driver
[ 5.905227] ohci-platform ohci-platform: Generic Platform OHCI controller
[ 5.912057] ohci-platform ohci-platform: new USB bus registered, assigned bus number 2
[ 5.920054] ohci-platform ohci-platform: irq 14, io mem 0x1c000000
[ 5.990114] hub 2-0:1.0: USB hub found
[ 5.994123] hub 2-0:1.0: 2 ports detected
[ 5.999708] init: - preinit -
[ 6.305365] usb 1-1: new high-speed USB device number 2 using ehci-platform
[ 6.518225] random: procd: uninitialized urandom read (4 bytes read, 10 bits of entropy available)
[ 6.527619] hub 1-1:1.0: USB hub found
[ 6.536472] hub 1-1:1.0: 4 ports detected
[ 6.564116] ar71xx: pll_reg 0xb8050010: 0x11110000
[ 6.564145] eth0: link up (1000Mbps/Full duplex)
[ 6.825300] usb 1-1.1: new high-speed USB device number 3 using ehci-platform
[ 7.205300] usb 1-1.2: new full-speed USB device number 4 using ehci-platform
[ 7.435303] usb 1-1.3: new low-speed USB device number 5 using ehci-platform
[ 9.833667] jffs2: notice: (417) jffs2_build_xattr_subsystem: complete building xattr subsystem, 0 of xdatum (0 unchecked, 0 orphan) and 0 of xref (0 dead, 0 orphan) found.
[ 9.861778] mount_root: loading kmods from internal overlay
[ 10.638974] SCSI subsystem initialized
[ 10.675008] usb-storage 1-1.1:1.0: USB Mass Storage device detected
[ 10.682159] scsi host0: usb-storage 1-1.1:1.0
[ 10.687098] usbcore: registered new interface driver usb-storage
[ 10.958667] block: attempting to load /tmp/jffs_cfg/upper/etc/config/fstab
[ 10.972146] block: extroot: not configured
[ 10.977087] mount_root: switching to jffs2 overlay
[ 10.989092] urandom-seed: Seeding with /etc/urandom.seed
[ 11.105721] eth0: link down
[ 11.124717] procd: - early -
[ 11.127763] procd: - watchdog -
[ 11.748004] procd: - ubus -
[ 11.848904] scsi 0:0:0:0: Direct-Access Lexar Echo 1100 PQ: 0 ANSI: 0 CCS
[ 11.859305] sd 0:0:0:0: [sda] 31326208 512-byte logical blocks: (16.0 GB/14.9 GiB)
[ 11.867395] random: ubusd: uninitialized urandom read (4 bytes read, 24 bits of entropy available)
[ 11.876663] sd 0:0:0:0: [sda] Write Protect is off
[ 11.881460] sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00
[ 11.941157] sd 0:0:0:0: [sda] No Caching mode page found
[ 11.946506] sd 0:0:0:0: [sda] Assuming drive cache: write through
[ 11.953332] random: ubusd: uninitialized urandom read (4 bytes read, 24 bits of entropy available)
[ 11.963047] random: ubusd: uninitialized urandom read (4 bytes read, 24 bits of entropy available)
[ 11.972147] random: ubusd: uninitialized urandom read (4 bytes read, 24 bits of entropy available)
[ 11.981916] random: ubusd: uninitialized urandom read (4 bytes read, 24 bits of entropy available)
[ 11.991663] random: ubusd: uninitialized urandom read (4 bytes read, 24 bits of entropy available)
[ 12.001212] random: ubusd: uninitialized urandom read (4 bytes read, 25 bits of entropy available)
[ 12.010457] random: ubusd: uninitialized urandom read (4 bytes read, 25 bits of entropy available)
[ 12.020382] procd: - init -
[ 12.023628] sda: sda1 sda2
[ 12.038984] sd 0:0:0:0: [sda] Attached SCSI removable disk
[ 12.568609] ip6_tables: (C) 2000-2006 Netfilter Core Team
[ 12.594250] nf_conntrack version 0.5.0 (1963 buckets, 7852 max)
[ 12.800132] hidraw: raw HID events driver (C) Jiri Kosina
[ 12.866007] u32 classifier
[ 12.868719] input device check on
[ 12.872372] Actions configured
[ 12.886454] Mirror/redirect action on
[ 12.918446] Loading modules backported from Linux version wt-2016-10-03-1-g6fcb1a6
[ 12.926064] Backport generated by backports.git backports-20160324-9-g0e38f5c
[ 12.937374] ip_tables: (C) 2000-2006 Netfilter Core Team
[ 13.261405] hid-generic 0003:051D:0002.0001: hiddev0,hidraw0: USB HID v1.10 Device [APC Back-UPS ES 650 FW:825.B1.D USB FW:B1] on usb-ehci-platform-1.3/input0
[ 13.275705] usbcore: registered new interface driver usbhid
[ 13.281268] usbhid: USB HID core driver
[ 13.290007] usblp 1-1.2:1.0: usblp0: USB Bidirectional printer dev 4 if 0 alt 1 proto 2 vid 0x03F0 pid 0x1617
[ 13.300066] usbcore: registered new interface driver usblp
[ 13.387224] xt_time: kernel timezone is -0000
[ 13.447450] PPP generic driver version 2.4.2
[ 13.453870] NET: Registered protocol family 24
[ 13.494261] PCI: Enabling device 0000:00:11.0 (0000 -> 0002)
[ 13.504841] ath: phy0: Ignoring endianness difference in EEPROM magic bytes.
[ 13.513445] ath: EEPROM regdomain: 0x0
[ 13.513455] ath: EEPROM indicates default country code should be used
[ 13.513463] ath: doing EEPROM country->regdmn map search
[ 13.513478] ath: country maps to regdmn code: 0x3a
[ 13.513488] ath: Country alpha2 being used: US
[ 13.513496] ath: Regpair used: 0x3a
[ 13.526795] ieee80211 phy0: Selected rate control algorithm 'minstrel_ht'
[ 13.530728] ieee80211 phy0: Atheros AR9280 Rev:2 mem=0xb0000000, irq=40
[ 13.636800] PCI: Enabling device 0000:00:12.0 (0000 -> 0002)
[ 13.647370] ath: phy1: Ignoring endianness difference in EEPROM magic bytes.
[ 13.655951] ath: EEPROM regdomain: 0x0
[ 13.655961] ath: EEPROM indicates default country code should be used
[ 13.655969] ath: doing EEPROM country->regdmn map search
[ 13.655985] ath: country maps to regdmn code: 0x3a
[ 13.655994] ath: Country alpha2 being used: US
[ 13.656002] ath: Regpair used: 0x3a
[ 13.671529] ieee80211 phy1: Selected rate control algorithm 'minstrel_ht'
[ 13.675574] ieee80211 phy1: Atheros AR9280 Rev:2 mem=0xb0010000, irq=41
[ 14.780370] random: jshn: uninitialized urandom read (4 bytes read, 52 bits of entropy available)
[ 20.932378] ar71xx: pll_reg 0xb8050010: 0x11110000
[ 20.937461] eth0: link up (1000Mbps/Full duplex)
[ 20.992313] device eth0 entered promiscuous mode
[ 21.004317] IPv6: ADDRCONF(NETDEV_UP): br-lan: link is not ready
[ 21.053548] IPv6: ADDRCONF(NETDEV_UP): eth1: link is not ready
[ 21.928707] br-lan: port 1(eth0) entered forwarding state
[ 21.934140] br-lan: port 1(eth0) entered forwarding state
[ 21.962763] IPv6: ADDRCONF(NETDEV_CHANGE): br-lan: link becomes ready
[ 23.929285] br-lan: port 1(eth0) entered forwarding state
[ 23.942328] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[ 24.018726] device wlan0 entered promiscuous mode
[ 24.069492] IPv6: ADDRCONF(NETDEV_UP): wlan1: link is not ready
[ 24.180504] device wlan1 entered promiscuous mode
[ 24.185358] br-lan: port 3(wlan1) entered forwarding state
[ 24.190856] br-lan: port 3(wlan1) entered forwarding state
[ 24.576524] ar71xx: pll_reg 0xb8050014: 0x11110000
[ 24.581318] eth1: link up (1000Mbps/Full duplex)
[ 24.599579] IPv6: ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready
[ 25.049468] br-lan: port 3(wlan1) entered disabled state
[ 27.192466] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[ 27.199046] br-lan: port 2(wlan0) entered forwarding state
[ 27.204574] br-lan: port 2(wlan0) entered forwarding state
[ 27.281138] IPv6: ADDRCONF(NETDEV_UP): wlan0-1: link is not ready
[ 29.099623] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0-1: link becomes ready
[ 29.195348] br-lan: port 2(wlan0) entered forwarding state
[ 34.194504] random: nonblocking pool is initialized
[ 73.170617]
[ 73.170617] do_page_fault(): sending SIGSEGV to dnsmasq for invalid read access from 00000000
[ 73.179191] epc = 770e71cc in libc.so[77074000+92000]
[ 73.184268] ra = 0040c9d5 in dnsmasq[400000+20000]
[ 73.189211]
[ 79.548941]
[ 79.548941] do_page_fault(): sending SIGSEGV to dnsmasq for invalid read access from 00000000
[ 79.557501] epc = 773291cc in libc.so[772b6000+92000]
[ 79.562594] ra = 0040c9d5 in dnsmasq[400000+20000]
[ 79.567505]
[ 85.512930] IPv6: ADDRCONF(NETDEV_CHANGE): wlan1: link becomes ready
[ 85.519488] br-lan: port 3(wlan1) entered forwarding state
[ 85.525040] br-lan: port 3(wlan1) entered forwarding state
[ 86.291598]
[ 86.291598] do_page_fault(): sending SIGSEGV to dnsmasq for invalid read access from 00000000
[ 86.300175] epc = 771cb1cc in libc.so[77158000+92000]
[ 86.305260] ra = 0040c9d5 in dnsmasq[400000+20000]
[ 86.310156]
[ 87.515298] br-lan: port 3(wlan1) entered forwarding state
[ 92.587286]
[ 92.587286] do_page_fault(): sending SIGSEGV to dnsmasq for invalid read access from 00000000
[ 92.595895] epc = 77f691cc in libc.so[77ef6000+92000]
[ 92.600991] ra = 0040c9d5 in dnsmasq[400000+20000]
[ 92.605905]
[ 98.928480]
[ 98.928480] do_page_fault(): sending SIGSEGV to dnsmasq for invalid read access from 00000000
[ 98.937030] epc = 77c891cc in libc.so[77c16000+92000]
[ 98.942143] ra = 0040c9d5 in dnsmasq[400000+20000]
[ 98.947053]
[ 105.123593]
[ 105.123593] do_page_fault(): sending SIGSEGV to dnsmasq for invalid read access from 00000000
[ 105.132138] epc = 77d4d1cc in libc.so[77cda000+92000]
[ 105.137243] ra = 0040c9d5 in dnsmasq[400000+20000]
[ 105.142139]


#2

WNDR3800 is s stable old device, so that looks a bit strange.
Just reboot the device, and hope that this time things keep running.

Note that the logged faults have happened right after the boot and then at every 6 seconds. So you should see rather quickly if the same happens again.

You might also try a newer 17.01 build. E.g. my community build for WNDR3700/3800 has a month newer version. Build for WNDR3700v1/v2 / WNDR3800


#3

Simple Adblock was causing it. I turned it off and now it's working fine. The DNS errors tipped me off...

Thanks Hnyman. I have extra hardware, so maybe I'll load up your latest and check it out...


#4

Interesting. "simple adblock" causing dnsmasq page faults? Might interest also @stangri


#5

Thanks @hnyman, @motocrossmann. Could it be caused by a very large blocked domains list so dnsmasq was running out of RAM?


#6

I'll try and test it later today and report back.


#7

It doesn't appear to be out of memory, but here are the numbers. Let me know if there are other tests I should run, or logs to look at. As soon and I turn adblock on, the page faults appear. When I turn it off and restart, they are gone.


#8

For what it's worth, here is the system log when I enable simple-adblock. This isn't an urgent issue for me, as my internet works fine when I turn adblock off.

Mon Apr 3 10:19:31 2017 user.notice simple-adblock [3921]: : service is not enabled.
Mon Apr 3 10:19:31 2017 daemon.info odhcpd[1096]: Initial RA router lifetime 1, 2 address(es) available on br-lan
Mon Apr 3 10:19:31 2017 daemon.info odhcpd[1096]: Updating RA router lifetime to 3441 on br-lan
Mon Apr 3 10:19:31 2017 daemon.notice odhcpd[1096]: Got DHCPv6 request
Mon Apr 3 10:19:31 2017 daemon.warn odhcpd[1096]: DHCPV6 SOLICIT IA_NA from 000100010000013f209148bf6846 on br-lan: ok fd0a:188e:11a0::d28/128
Mon Apr 3 10:19:31 2017 daemon.notice odhcpd[1096]: Got DHCPv6 request
Mon Apr 3 10:19:31 2017 daemon.warn odhcpd[1096]: DHCPV6 REQUEST IA_NA from 000100010000013f209148bf6846 on br-lan: ok fd0a:188e:11a0::d28/128
Mon Apr 3 10:19:31 2017 user.notice simple-adblock [3953]: Forcing local DNS server: 192.168.1.2/2601:647:4901:34d0::1.
Mon Apr 3 10:19:32 2017 daemon.info odhcpd[1096]: Initial RA router lifetime 1, 2 address(es) available on br-lan
Mon Apr 3 10:19:32 2017 daemon.info odhcpd[1096]: Updating RA router lifetime to 3439 on br-lan
Mon Apr 3 10:19:33 2017 daemon.notice odhcpd[1096]: Got DHCPv6 request
Mon Apr 3 10:19:33 2017 daemon.warn odhcpd[1096]: DHCPV6 SOLICIT IA_NA from 000100010000013f209148bf6846 on br-lan: ok fd0a:188e:11a0::d28/128
Mon Apr 3 10:19:33 2017 daemon.notice odhcpd[1096]: Got DHCPv6 request
Mon Apr 3 10:19:33 2017 daemon.warn odhcpd[1096]: DHCPV6 REQUEST IA_NA from 000100010000013f209148bf6846 on br-lan: ok fd0a:188e:11a0::d28/128
Mon Apr 3 10:19:34 2017 user.notice simple-adblock [3953]: [DL] Blocked Hosts: www.mvps.org [✓]
Mon Apr 3 10:19:34 2017 daemon.info odhcpd[1096]: Initial RA router lifetime 1, 2 address(es) available on br-lan
Mon Apr 3 10:19:34 2017 daemon.info odhcpd[1096]: Updating RA router lifetime to 3437 on br-lan
Mon Apr 3 10:19:35 2017 user.notice simple-adblock [3953]: [DL] Blocked Hosts: pgl.yoyo.org [✓]
Mon Apr 3 10:19:35 2017 daemon.notice odhcpd[1096]: Got DHCPv6 request
Mon Apr 3 10:19:35 2017 daemon.warn odhcpd[1096]: DHCPV6 SOLICIT IA_NA from 000100010000013f209148bf6846 on br-lan: ok fd0a:188e:11a0::d28/128
Mon Apr 3 10:19:35 2017 daemon.notice odhcpd[1096]: Got DHCPv6 request
Mon Apr 3 10:19:35 2017 daemon.warn odhcpd[1096]: DHCPV6 REQUEST IA_NA from 000100010000013f209148bf6846 on br-lan: ok fd0a:188e:11a0::d28/128
Mon Apr 3 10:19:35 2017 user.notice simple-adblock [3953]: [DL] Blocked Hosts: www.malwaredomainlist.com [✓]
Mon Apr 3 10:19:36 2017 daemon.info odhcpd[1096]: Initial RA router lifetime 1, 2 address(es) available on br-lan
Mon Apr 3 10:19:36 2017 daemon.info odhcpd[1096]: Updating RA router lifetime to 3436 on br-lan
Mon Apr 3 10:19:36 2017 daemon.notice odhcpd[1096]: Got DHCPv6 request
Mon Apr 3 10:19:36 2017 daemon.warn odhcpd[1096]: DHCPV6 SOLICIT IA_NA from 000100010000013f209148bf6846 on br-lan: ok fd0a:188e:11a0::d28/128
Mon Apr 3 10:19:36 2017 daemon.notice odhcpd[1096]: Got DHCPv6 request
Mon Apr 3 10:19:36 2017 daemon.warn odhcpd[1096]: DHCPV6 REQUEST IA_NA from 000100010000013f209148bf6846 on br-lan: ok fd0a:188e:11a0::d28/128
Mon Apr 3 10:19:36 2017 user.notice simple-adblock [3953]: [DL] Blocked Hosts: adaway.org [✓]
Mon Apr 3 10:19:37 2017 daemon.info odhcpd[1096]: Initial RA router lifetime 1, 2 address(es) available on br-lan
Mon Apr 3 10:19:37 2017 daemon.info odhcpd[1096]: Updating RA router lifetime to 3435 on br-lan
Mon Apr 3 10:19:37 2017 user.notice simple-adblock [3953]: [DL] Blocked Hosts: someonewhocares.org [✓]
Mon Apr 3 10:19:38 2017 daemon.notice odhcpd[1096]: Got DHCPv6 request
Mon Apr 3 10:19:38 2017 daemon.warn odhcpd[1096]: DHCPV6 SOLICIT IA_NA from 000100010000013f209148bf6846 on br-lan: ok fd0a:188e:11a0::d28/128
Mon Apr 3 10:19:38 2017 daemon.notice odhcpd[1096]: Got DHCPv6 request
Mon Apr 3 10:19:38 2017 daemon.warn odhcpd[1096]: DHCPV6 REQUEST IA_NA from 000100010000013f209148bf6846 on br-lan: ok fd0a:188e:11a0::d28/128
Mon Apr 3 10:19:39 2017 daemon.info odhcpd[1096]: Initial RA router lifetime 1, 2 address(es) available on br-lan
Mon Apr 3 10:19:39 2017 daemon.info odhcpd[1096]: Updating RA router lifetime to 3433 on br-lan
Mon Apr 3 10:19:39 2017 user.notice simple-adblock [3953]: [DL] Blocked Hosts: zeustracker.abuse.ch [✓]
Mon Apr 3 10:19:40 2017 user.notice simple-adblock [3953]: [DL] Blocked Domains: mirror1.malwaredomains.com [✓]
Mon Apr 3 10:19:40 2017 daemon.notice odhcpd[1096]: Got DHCPv6 request
Mon Apr 3 10:19:40 2017 daemon.warn odhcpd[1096]: DHCPV6 SOLICIT IA_NA from 000100010000013f209148bf6846 on br-lan: ok fd0a:188e:11a0::d28/128
Mon Apr 3 10:19:40 2017 daemon.notice odhcpd[1096]: Got DHCPv6 request
Mon Apr 3 10:19:40 2017 daemon.warn odhcpd[1096]: DHCPV6 REQUEST IA_NA from 000100010000013f209148bf6846 on br-lan: ok fd0a:188e:11a0::d28/128
Mon Apr 3 10:19:41 2017 user.notice simple-adblock [3953]: [DL] Blocked Domains: s3.amazonaws.com [✓]
Mon Apr 3 10:19:41 2017 daemon.info odhcpd[1096]: Initial RA router lifetime 1, 2 address(es) available on br-lan
Mon Apr 3 10:19:41 2017 daemon.info odhcpd[1096]: Updating RA router lifetime to 3430 on br-lan
Mon Apr 3 10:19:42 2017 user.notice simple-adblock [3953]: [DL] Blocked Domains: s3.amazonaws.com [✓]
Mon Apr 3 10:19:42 2017 daemon.notice odhcpd[1096]: Got DHCPv6 request
Mon Apr 3 10:19:42 2017 daemon.warn odhcpd[1096]: DHCPV6 SOLICIT IA_NA from 000100010000013f209148bf6846 on br-lan: ok fd0a:188e:11a0::d28/128
Mon Apr 3 10:19:42 2017 daemon.notice odhcpd[1096]: Got DHCPv6 request
Mon Apr 3 10:19:42 2017 daemon.warn odhcpd[1096]: DHCPV6 REQUEST IA_NA from 000100010000013f209148bf6846 on br-lan: ok fd0a:188e:11a0::d28/128
Mon Apr 3 10:19:43 2017 user.notice simple-adblock [3953]: [DL] Blocked Domains: s3.amazonaws.com [✓]
Mon Apr 3 10:19:43 2017 daemon.info odhcpd[1096]: Initial RA router lifetime 1, 2 address(es) available on br-lan
Mon Apr 3 10:19:43 2017 daemon.info odhcpd[1096]: Updating RA router lifetime to 3428 on br-lan
Mon Apr 3 10:19:44 2017 user.notice simple-adblock [3953]: [DL] Blocked Domains: ransomwaretracker.abuse.ch [✓]
Mon Apr 3 10:19:45 2017 daemon.info odhcpd[1096]: Initial RA router lifetime 1, 2 address(es) available on br-lan
Mon Apr 3 10:19:45 2017 daemon.info odhcpd[1096]: Updating RA router lifetime to 3427 on br-lan
Mon Apr 3 10:19:45 2017 daemon.notice odhcpd[1096]: Got DHCPv6 request
Mon Apr 3 10:19:45 2017 daemon.warn odhcpd[1096]: DHCPV6 SOLICIT IA_NA from 000100010000013f209148bf6846 on br-lan: ok fd0a:188e:11a0::d28/128
Mon Apr 3 10:19:45 2017 daemon.notice odhcpd[1096]: Got DHCPv6 request
Mon Apr 3 10:19:45 2017 daemon.warn odhcpd[1096]: DHCPV6 REQUEST IA_NA from 000100010000013f209148bf6846 on br-lan: ok fd0a:188e:11a0::d28/128
Mon Apr 3 10:19:45 2017 user.notice simple-adblock [3953]: [DL] Blocked Domains: gitlab.com [✓]
Mon Apr 3 10:19:46 2017 daemon.info odhcpd[1096]: Initial RA router lifetime 1, 2 address(es) available on br-lan
Mon Apr 3 10:19:46 2017 daemon.info odhcpd[1096]: Updating RA router lifetime to 3426 on br-lan
Mon Apr 3 10:19:47 2017 daemon.notice odhcpd[1096]: Got DHCPv6 request
Mon Apr 3 10:19:47 2017 daemon.warn odhcpd[1096]: DHCPV6 SOLICIT IA_NA from 000100010000013f209148bf6846 on br-lan: ok fd0a:188e:11a0::d28/128
Mon Apr 3 10:19:47 2017 daemon.notice odhcpd[1096]: Got DHCPv6 request
Mon Apr 3 10:19:47 2017 daemon.warn odhcpd[1096]: DHCPV6 REQUEST IA_NA from 000100010000013f209148bf6846 on br-lan: ok fd0a:188e:11a0::d28/128
Mon Apr 3 10:19:47 2017 user.notice simple-adblock [3953]: [DL] Blocked Domains: dshield.org [✓]
Mon Apr 3 10:19:47 2017 user.notice simple-adblock [3953]: Waiting for background processes [✓]
Mon Apr 3 10:19:48 2017 daemon.info odhcpd[1096]: Initial RA router lifetime 1, 2 address(es) available on br-lan
Mon Apr 3 10:19:48 2017 daemon.info odhcpd[1096]: Updating RA router lifetime to 3424 on br-lan
Mon Apr 3 10:19:48 2017 daemon.notice odhcpd[1096]: Got DHCPv6 request
Mon Apr 3 10:19:48 2017 daemon.warn odhcpd[1096]: DHCPV6 SOLICIT IA_NA from 000100010000013f209148bf6846 on br-lan: ok fd0a:188e:11a0::d28/128
Mon Apr 3 10:19:48 2017 daemon.notice odhcpd[1096]: Got DHCPv6 request
Mon Apr 3 10:19:48 2017 daemon.warn odhcpd[1096]: DHCPV6 REQUEST IA_NA from 000100010000013f209148bf6846 on br-lan: ok fd0a:188e:11a0::d28/128


#9

There should be more entries in the log. It would also improve readability if you do logread -e simple.


#10

root@LEDE:~# logread -e simple
Mon Apr 3 13:28:58 2017 user.notice simple-adblock [1910]: Forcing local DNS server: 192.168.1.2/2601:647:4901:34d0::1.
Mon Apr 3 13:29:01 2017 user.notice simple-adblock [1910]: [DL] Blocked Hosts: www.mvps.org [✓]
Mon Apr 3 13:29:02 2017 user.notice simple-adblock [1910]: [DL] Blocked Hosts: pgl.yoyo.org [✓]
Mon Apr 3 13:29:02 2017 user.notice simple-adblock [1910]: [DL] Blocked Hosts: www.malwaredomainlist.com [✓]
Mon Apr 3 13:30:07 2017 user.notice simple-adblock [1910]: [DL] Blocked Hosts: adaway.org [✓]
Mon Apr 3 13:30:08 2017 user.notice simple-adblock [1910]: [DL] Blocked Hosts: someonewhocares.org [✓]
Mon Apr 3 13:30:09 2017 user.notice simple-adblock [1910]: [DL] Blocked Hosts: zeustracker.abuse.ch [✓]
Mon Apr 3 13:30:10 2017 user.notice simple-adblock [1910]: [DL] Blocked Domains: mirror1.malwaredomains.com [✓]
Mon Apr 3 13:30:11 2017 user.notice simple-adblock [1910]: [DL] Blocked Domains: s3.amazonaws.com [✓]
Mon Apr 3 13:30:12 2017 user.notice simple-adblock [1910]: [DL] Blocked Domains: s3.amazonaws.com [✓]
Mon Apr 3 13:30:13 2017 user.notice simple-adblock [1910]: [DL] Blocked Domains: s3.amazonaws.com [✓]
Mon Apr 3 13:30:15 2017 user.notice simple-adblock [1910]: [DL] Blocked Domains: ransomwaretracker.abuse.ch [✓]
Mon Apr 3 13:30:16 2017 user.notice simple-adblock [1910]: [DL] Blocked Domains: gitlab.com [✓]
Mon Apr 3 13:30:17 2017 user.notice simple-adblock [1910]: [DL] Blocked Domains: dshield.org [✓]
Mon Apr 3 13:30:18 2017 user.notice simple-adblock [1910]: Waiting for background processes [✓]
Mon Apr 3 13:30:21 2017 user.notice simple-adblock [1910]: Sorting merged file [✓]
Mon Apr 3 13:30:23 2017 user.notice simple-adblock [1910]: Whitelisting domains [✓]
Mon Apr 3 13:30:25 2017 user.notice simple-adblock [1910]: Formatting merged file [✓]
Mon Apr 3 13:30:25 2017 user.notice simple-adblock [1910]: Removing temporary files [✓]
Mon Apr 3 13:30:32 2017 user.notice simple-adblock [1910]: Reloading dnsmasq [✓]
Mon Apr 3 13:30:32 2017 user.notice simple-adblock [1910]: service enabled: blocking 53066 domains.


#11

Thanks! And the dnsmasq still segfaults?


#12

At the time of that log, I did check dmesg and it was showing the page faults. What I don't know is whether or not adblock was always causing those errors or if it just started happening. I never looked at the kernel log until internet went down, which is when I started this thread.


#13

I've implemented some changes in filtering and management of the dnsmasq-file in 1.5.7-1, please upgrade to this version (if you have my repo added to your router it's just opkg update; opkg upgrade simple-adblock) and let me know if that helps.


#14

Hmm, I'm failing to get the repo added. I followed your package repo instructions, but got this:

Collected errors:


#15

Are uclient-fetch and libustream-mbedtls installed?


#16

Yup. I originally installed simple-adblock using an old procedure using wget, I think. I can't really find those old instructions as your readme appears to be updated.


#17

Can you post opkg list-installed?
@hnyman -- may the opkg_download fail because it either can't or won't use wget/libopenssl combo?


#18

root@LEDE:~# opkg list-installed
apcupsd - 3.14.14-1
base-files - 172-r3205-59508e3
block-mount - 2016-12-04-84b530a7-1
busybox - 1.25.1-2
collectd - 5.5.3-1
collectd-mod-cpu - 5.5.3-1
collectd-mod-interface - 5.5.3-1
collectd-mod-iwinfo - 5.5.3-1
collectd-mod-load - 5.5.3-1
collectd-mod-memory - 5.5.3-1
collectd-mod-network - 5.5.3-1
collectd-mod-rrdtool - 5.5.3-1
coreutils - 8.23-2
ddns-scripts - 2.7.6-13
ddns-scripts_no-ip_com - 2.7.6-13
dnsmasq - 2.76-6
dosfstools - 4.0-2
dropbear - 2016.74-2
e2fsprogs - 1.43.3-2
etherwake - 1.09-4
firewall - 2017-01-13-37cb4cb4-1
fstools - 2016-12-04-84b530a7-1
fwtool - 1
hostapd-common - 2016-12-19-ad02e79d-2
ip-tiny - 4.4.0-8
ip6tables - 1.4.21-2
ip6tables-mod-nat - 1.4.21-2
iptables - 1.4.21-2
iptables-mod-conntrack-extra - 1.4.21-2
iptables-mod-ipopt - 1.4.21-2
iw - 4.9-1
iwinfo - 2016-09-21-fd9e17be-1
jshn - 2016-11-29-77a62937-1
jsonfilter - 2016-07-02-dea067ad-1
kernel - 4.4.50-1-fb8488eb27aea27776f3c885f63f61b2
kmod-ath - 4.4.50+2016-10-08-1
kmod-ath9k - 4.4.50+2016-10-08-1
kmod-ath9k-common - 4.4.50+2016-10-08-1
kmod-cfg80211 - 4.4.50+2016-10-08-1
kmod-crypto-crc32c - 4.4.50-1
kmod-crypto-hash - 4.4.50-1
kmod-fs-ext4 - 4.4.50-1
kmod-gpio-button-hotplug - 4.4.50-2
kmod-hid - 4.4.50-1
kmod-hid-generic - 4.4.50-1
kmod-ifb - 4.4.50-1
kmod-input-core - 4.4.50-1
kmod-input-evdev - 4.4.50-1
kmod-ip6tables - 4.4.50-1
kmod-ipt-conntrack - 4.4.50-1
kmod-ipt-conntrack-extra - 4.4.50-1
kmod-ipt-core - 4.4.50-1
kmod-ipt-ipopt - 4.4.50-1
kmod-ipt-nat - 4.4.50-1
kmod-ipt-nat6 - 4.4.50-1
kmod-leds-wndr3700-usb - 4.4.50-1
kmod-lib-crc-ccitt - 4.4.50-1
kmod-lib-crc16 - 4.4.50-1
kmod-mac80211 - 4.4.50+2016-10-08-1
kmod-nf-conntrack - 4.4.50-1
kmod-nf-conntrack6 - 4.4.50-1
kmod-nf-ipt - 4.4.50-1
kmod-nf-ipt6 - 4.4.50-1
kmod-nf-nat - 4.4.50-1
kmod-nf-nat6 - 4.4.50-1
kmod-nls-base - 4.4.50-1
kmod-ppp - 4.4.50-1
kmod-pppoe - 4.4.50-1
kmod-pppox - 4.4.50-1
kmod-sched-cake - 4.4.50+2017-01-28-9789742c-1
kmod-sched-core - 4.4.50-1
kmod-scsi-core - 4.4.50-1
kmod-slhc - 4.4.50-1
kmod-usb-core - 4.4.50-1
kmod-usb-hid - 4.4.50-1
kmod-usb-ledtrig-usbport - 4.4.50-1
kmod-usb-ohci - 4.4.50-1
kmod-usb-printer - 4.4.50-1
kmod-usb-storage - 4.4.50-1
kmod-usb2 - 4.4.50-1
lede-keyring - 2017-01-20-a50b7529-1
libblobmsg-json - 2016-11-29-77a62937-1
libc - 1.1.16-1
libext2fs - 1.43.3-2
libgcc - 5.4.0-1
libip4tc - 1.4.21-2
libip6tc - 1.4.21-2
libiwinfo - 2016-09-21-fd9e17be-1
libiwinfo-lua - 2016-09-21-fd9e17be-1
libjson-c - 0.12.1-1
libjson-script - 2016-11-29-77a62937-1
libltdl - 2.4-2
liblua - 5.1.5-1
libmbedtls - 2.4.0-2
libnl-tiny - 0.1-5
libopenssl - 1.0.2k-1
libpcre - 8.40-1
libpthread - 1.1.16-1
librrd1 - 1.0.50-2
librt - 1.1.16-1
libubox - 2016-11-29-77a62937-1
libubus - 2017-01-22-6f4e11e1-1
libubus-lua - 2017-01-22-6f4e11e1-1
libuci - 2016-07-04-e1bf4356-1
libuci-lua - 2016-07-04-e1bf4356-1
libuclient - 2016-12-09-52d955fd-1
libusb-1.0 - 1.0.21-1
libusb-compat - 0.1.4-2
libustream-mbedtls - 2016-07-02-ec80adaa-2
libuuid - 2.28-1
libxtables - 1.4.21-2
logd - 2017-01-15-5649c028-1
lua - 5.1.5-1
luci - git-17.051.53299-a100738-1
luci-app-ddns - 2.4.8-1
luci-app-firewall - git-17.051.53299-a100738-1
luci-app-p910nd - git-17.051.53299-a100738-1
luci-app-samba - git-17.051.53299-a100738-1
luci-app-simple-adblock - git-17.064.52681-ff36d1b9e-1
luci-app-sqm - 1.1.3-1
luci-app-statistics - git-17.051.53299-a100738-1
luci-base - git-17.051.53299-a100738-1
luci-lib-ip - git-17.051.53299-a100738-1
luci-lib-jsonc - git-17.051.53299-a100738-1
luci-lib-nixio - git-17.051.53299-a100738-1
luci-mod-admin-full - git-17.051.53299-a100738-1
luci-proto-ipv6 - git-17.051.53299-a100738-1
luci-proto-ppp - git-17.051.53299-a100738-1
luci-theme-bootstrap - git-17.051.53299-a100738-1
mtd - 21
netifd - 2017-01-25-650758b1-1
odhcp6c - 2017-01-30-c13b6a05-1
odhcpd - 2017-02-09-8df4253b-1
opkg - 2011-04-08-9c97d5ec-17
p910nd - 0.97-5
ppp - 2.4.7-10
ppp-mod-pppoe - 2.4.7-10
procd - 2017-02-08-cdc3dab3-1
rpcd - 2016-12-03-0577cfc1-1
rrdtool1 - 1.0.50-2
samba36-server - 3.6.25-5
simple-adblock - 1.5.6-2
sqm-scripts - 1.1.3-1
swconfig - 11
tc - 4.4.0-8
uboot-envtools - 2015.10-1
ubox - 2017-01-15-5649c028-1
ubus - 2017-01-22-6f4e11e1-1
ubusd - 2017-01-22-6f4e11e1-1
uci - 2016-07-04-e1bf4356-1
uclient-fetch - 2016-12-09-52d955fd-1
uhttpd - 2016-10-25-1628fa4b-1
uhttpd-mod-ubus - 2016-10-25-1628fa4b-1
usbutils - 007-6
usign - 2015-07-04-ef641914-1
wget - 1.18-2
wpad-mini - 2016-12-19-ad02e79d-2
zlib - 1.2.11-1


#19

Based on the installed package list, there is full wget, and both libopenssl and libmbedtls, so everything should be ok.

wget error 5 is "SSL verification failure."
https://www.gnu.org/software/wget/manual/html_node/Exit-Status.html

When I try manually in a router without ca-certificates downloading with wget https://raw.githubusercontent.com/stangri/openwrt-repo/master/Packages.gz , I receive certificate check error: "Unable to locally verify the issuer's authority."

That is likely what is happening there. @stangri's repo at Github is https, and the cert verification fails due to missing certificates.

The same happens if I change a normal LEDE repo's address in /etc/opkg/distfeeds.conf to be https. opkg does not normally use https based repos, so opkg does not issue the "--no-check-certificate" parameter for wget. So, opkg expects "fully working https downloading functionality" if https links are used.

Two simple solution alternatives:

  • install ca-certificates package, so that SSL certificate verification succeeds.
  • manually download the simple-adblock related packages to /tmp and opkg install them. You can use wget --no-check-certificate https://github.com/stangri/openwrt-repo/blob/master/simple-adblock_1.5.7-1_all.ipk for downloading from Github

#20

This worked! I no longer get the error when trying to run 'opkg update'. Thanks @hnyman!

@stangri, I'll test out 1.5.7-1 and report back later.