WARN critical system time drift detected

Hallo,

ich kämpfe am Knoten 1681 mit ständigen Verbindungsabbrüchen. Im System-Log steht dazu:

Tue Jun 4 20:42:40 2019 daemon.err bmx[4216]: WARN critical system time drift detected: ++ca 0 s, 493934 us! Correcting reference!
Tue Jun 4 20:42:40 2019 user.notice DHCP: cmd:old mac:58:40:4e:1a:ac:47 ip:100.64.218.2 hostname:iPhone
Tue Jun 4 20:42:40 2019 user.notice DHCP: cmd:old mac:a8:66:7f:09:44:78 ip:100.64.107.166 hostname:THX
Tue Jun 4 20:42:40 2019 daemon.err bmx[4216]: INFO using new default tunnel to GW 10.200.200.18 (gw_flags: 63, packet_count: 98, gw_product: 0)
Tue Jun 4 20:42:40 2019 user.notice BMXD-SCRIPT[9117]: nameserver 10.200.200.18
Tue Jun 4 20:42:40 2019 user.notice ddmesh: network-hotplug net: [bat0] add type:
Tue Jun 4 20:42:41 2019 user.notice BMXD-SCRIPT[9117]: ssid: Freifunk Hotspot [1681]
Tue Jun 4 20:42:42 2019 daemon.err bmx[4216]: WARN critical system time drift detected: ++ca 0 s, 549233 us! Correcting reference!
Tue Jun 4 20:42:42 2019 user.notice DHCP: cmd:old mac:58:40:4e:1a:ac:47 ip:100.64.218.2 hostname:iPhone
Tue Jun 4 20:42:42 2019 user.notice DHCP: cmd:old mac:58:40:4e:1a:ac:47 ip:100.64.218.2 hostname:iPhone
Tue Jun 4 20:42:42 2019 user.notice DHCP: cmd:old mac:a8:66:7f:09:44:78 ip:100.64.107.166 hostname:THX
Tue Jun 4 20:43:15 2019 user.notice BMXD-SCRIPT[10084]: remove GATEWAY (del)
Tue Jun 4 20:43:15 2019 user.notice BMXD-SCRIPT[10084]: ssid: FF no-inet [1681]
Tue Jun 4 20:43:16 2019 daemon.err bmx[4216]: INFO del_dev_tun(): closing gwc_cleanup tunnel bat0 ip 10.200.6.152
Tue Jun 4 20:43:16 2019 daemon.err bmx[4216]: WARN critical system time drift detected: ++ca 0 s, 413016 us! Correcting reference!

Auch Knoten 1815 zeigt diese Bild:

Tue Jun 4 20:39:40 2019 daemon.err bmx[4083]: WARN critical system time drift detected: ++ca 0 s, 472269 us! Correcting reference!
Tue Jun 4 20:39:40 2019 daemon.err bmx[4083]: INFO using new default tunnel to GW 10.200.6.146 (gw_flags: 63, packet_count: 99, gw_product: 0)
Tue Jun 4 20:39:40 2019 user.notice BMXD-SCRIPT[15289]: nameserver 10.200.6.146
Tue Jun 4 20:39:40 2019 user.notice ddmesh: network-hotplug net: [bat0] add type:
Tue Jun 4 20:39:40 2019 user.notice BMXD-SCRIPT[15289]: ssid: Freifunk Hotspot [1815]
Tue Jun 4 20:39:41 2019 daemon.err bmx[4083]: WARN critical system time drift detected: ++ca 0 s, 549585 us! Correcting reference!
Tue Jun 4 20:40:26 2019 user.notice BMXD-SCRIPT[16238]: remove GATEWAY (del)
Tue Jun 4 20:40:27 2019 user.notice BMXD-SCRIPT[16238]: ssid: FF no-inet [1815]
Tue Jun 4 20:40:27 2019 daemon.err bmx[4083]: INFO del_dev_tun(): closing gwc_cleanup tunnel bat0 ip 10.200.7.31
Tue Jun 4 20:40:27 2019 daemon.err bmx[4083]: WARN critical system time drift detected: ++ca 0 s, 424537 us! Correcting reference!

Ich weiß, in der Beschreibung zur Firmware für die CPE 210 und den TL-WR841ND v10 steht „testing…“ und bei dem WDR4300 v1 oder der Archer C7 v2 suche ich diese Fehler vergebens. Aber vielleicht ist ja doch was zu machen? Gerade für die CPE ist es mir wichtig, da ich noch 5 liegen habe.

Viele Grüße,
Thomas

Die Warnung spielt keine Rolle. die Zeiten zwischen der system-uhr von linux und der, die bmxd selber zählt driften immer weg, und bmxd passt diese dann automatisch wieder an. Liegt daran, dass die router keine wirkliche eigene uhr besitzen.

die Verbindungsabbrüche, deuten darauf hin, dass die internet verbindung selbst, die der router hat, wegfällt und später wiede aufgebaut wird. Oder die internetverbindung leitet die udp packete von fastd nicht mehr durch, so dass bmxd die gateway verbindung verliert.

ES kommt jetzt auf deinen Internet anschluss an. ist dieser ein T-Online/Vodafon anschluss? oder bezieht der router sein internet via richtfunk von einem anderen router?
Laufen andere geräte an diesem anschluss stabil?

Die firmware versionen sind soweit stabil, auch wenn bei der CPE und dem 841er noch „testing“ steht.

Die statistic beider knoten sieht aber gut aus, auch die verbindungen via fastd

Byte Stephan

VDSL 100 der Telekom mit Fritz!Box 7530, DSL Daten sehen gut aus:

Mon Jun 10 10:43:35 2019 kern.notice kernel: [ 0.000000] Linux version 4.4.124 (stephan@IdeaCentre-B750) (gcc version 5.4.0 (LEDE GCC 5.4.0 r2993+873-b9a408c2b4) ) #0 Sun Apr 1 13:21:14 2018
Mon Jun 10 10:43:35 2019 kern.debug kernel: [ 0.000000] MyLoader: sysp=0000ffff, boardp=0004ffff, parts=0000ffff
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.000000] bootconsole [early0] enabled
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.000000] CPU0 revision is: 0001974c (MIPS 74Kc)
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.000000] SoC: Atheros AR9344 rev 2
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.000000] Determined physical RAM map:
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.000000] memory: 04000000 @ 00000000 (usable)
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.000000] Initrd not found or empty - disabling initrd
Mon Jun 10 10:43:35 2019 kern.warn kernel: [ 0.000000] No valid device tree found, continuing without
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.000000] Zone ranges:
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.000000] Normal [mem 0x0000000000000000-0x0000000003ffffff]
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.000000] Movable zone start for each node
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.000000] Early memory node ranges
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.000000] node 0: [mem 0x0000000000000000-0x0000000003ffffff]
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x0000000003ffffff]
Mon Jun 10 10:43:35 2019 kern.debug kernel: [ 0.000000] On node 0 totalpages: 16384
Mon Jun 10 10:43:35 2019 kern.debug kernel: [ 0.000000] free_area_init_node: node 0, pgdat 80327730, node_mem_map 81000000
Mon Jun 10 10:43:35 2019 kern.debug kernel: [ 0.000000] Normal zone: 128 pages used for memmap
Mon Jun 10 10:43:35 2019 kern.debug kernel: [ 0.000000] Normal zone: 0 pages reserved
Mon Jun 10 10:43:35 2019 kern.debug kernel: [ 0.000000] Normal zone: 16384 pages, LIFO batch:3
Mon Jun 10 10:43:35 2019 kern.warn kernel: [ 0.000000] Primary instruction cache 64kB, VIPT, 4-way, linesize 32 bytes.
Mon Jun 10 10:43:35 2019 kern.warn kernel: [ 0.000000] Primary data cache 32kB, 4-way, VIPT, cache aliases, linesize 32 bytes
Mon Jun 10 10:43:35 2019 kern.debug kernel: [ 0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
Mon Jun 10 10:43:35 2019 kern.debug kernel: [ 0.000000] pcpu-alloc: [0] 0
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 16256
Mon Jun 10 10:43:35 2019 kern.notice kernel: [ 0.000000] Kernel command line: board=CPE210 mtdparts=spi0.0:128k(u-boot)ro,64k(pation-table)ro,64k(product-info)ro,1536k(kernel),6144k(rootfs),192k(config)ro,64k(ART)ro,7680k@0x40000(firmware) console=ttyS0,115200 rootfstype=squashfs,jffs2 noinitrd
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.000000] PID hash table entries: 256 (order: -2, 1024 bytes)
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.000000] Dentry cache hash table entries: 8192 (order: 3, 32768 bytes)
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.000000] Inode-cache hash table entries: 4096 (order: 2, 16384 bytes)
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.000000] Writing ErrCtl register=00000000
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.000000] Readback ErrCtl register=00000000
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.000000] Memory: 61088K/65536K available (2526K kernel code, 151K rwdata, 284K rodata, 296K init, 189K bss, 4448K reserved, 0K cma-reserved)
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.000000] NR_IRQS:51
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.000000] Clocks: CPU:560.000MHz, DDR:450.000MHz, AHB:225.000MHz, Ref:40.000MHz
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.000000] clocksource: MIPS: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6825930166 ns
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.000010] sched_clock: 32 bits at 280MHz, resolution 3ns, wraps every 7669584382ns
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.009055] Calibrating delay loop... 278.93 BogoMIPS (lpj=1394688)
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.082154] pid_max: default: 32768 minimum: 301
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.087654] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.095351] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.106274] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.117740] futex hash table entries: 256 (order: -1, 3072 bytes)
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.125499] NET: Registered protocol family 16
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.132154] MIPS: machine is TP-LINK CPE210/220
Mon Jun 10 10:43:35 2019 kern.warn kernel: [ 0.352548] Can't analyze schedule() prologue at 80066a6c
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.368145] clocksource: Switched to clocksource MIPS
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.375308] NET: Registered protocol family 2
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.381298] TCP established hash table entries: 1024 (order: 0, 4096 bytes)
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.389462] TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.396852] TCP: Hash tables configured (established 1024 bind 1024)
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.404362] UDP hash table entries: 256 (order: 0, 4096 bytes)
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.411199] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.418784] NET: Registered protocol family 1
Mon Jun 10 10:43:35 2019 kern.debug kernel: [ 0.423874] PCI: CLS 0 bytes, default 32
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.444126] squashfs: version 4.0 (2009/01/31) Phillip Lougher
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.450960] jffs2: version 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red Hat, Inc.
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.465050] io scheduler noop registered
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.469661] io scheduler deadline registered (default)
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.475808] Serial: 8250/16550 driver, 1 ports, IRQ sharing disabled
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.483707] console [ttyS0] disabled
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.507943] serial8250.0: ttyS0 at MMIO 0x18020000 (irq = 11, base_baud = 2500000) is a 16550A
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.517972] console [ttyS0] enabled
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.525400] bootconsole [early0] disabled
Mon Jun 10 10:43:35 2019 kern.warn kernel: [ 0.536928] m25p80 spi0.0: found gd25q64, expected m25p80
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.542494] m25p80 spi0.0: gd25q64 (8192 Kbytes)
Mon Jun 10 10:43:35 2019 kern.notice kernel: [ 0.547218] 8 cmdlinepart partitions found on MTD device spi0.0
Mon Jun 10 10:43:35 2019 kern.notice kernel: [ 0.553241] Creating 8 MTD partitions on "spi0.0":
Mon Jun 10 10:43:35 2019 kern.notice kernel: [ 0.558109] 0x000000000000-0x000000020000 : "u-boot"
Mon Jun 10 10:43:35 2019 kern.notice kernel: [ 0.565658] 0x000000020000-0x000000030000 : "pation-table"
Mon Jun 10 10:43:35 2019 kern.notice kernel: [ 0.573129] 0x000000030000-0x000000040000 : "product-info"
Mon Jun 10 10:43:35 2019 kern.notice kernel: [ 0.580587] 0x000000040000-0x0000001c0000 : "kernel"
Mon Jun 10 10:43:35 2019 kern.notice kernel: [ 0.587439] 0x0000001c0000-0x0000007c0000 : "rootfs"
Mon Jun 10 10:43:35 2019 kern.notice kernel: [ 0.594340] mtd: device 4 (rootfs) set to be root filesystem
Mon Jun 10 10:43:35 2019 kern.notice kernel: [ 0.600191] 1 squashfs-split partitions found on MTD device rootfs
Mon Jun 10 10:43:35 2019 kern.notice kernel: [ 0.606469] 0x000000400000-0x0000007c0000 : "rootfs_data"
Mon Jun 10 10:43:35 2019 kern.notice kernel: [ 0.613826] 0x0000007c0000-0x0000007f0000 : "config"
Mon Jun 10 10:43:35 2019 kern.notice kernel: [ 0.620777] 0x0000007f0000-0x000000800000 : "ART"
Mon Jun 10 10:43:35 2019 kern.notice kernel: [ 0.627369] 0x000000040000-0x0000007c0000 : "firmware"
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 0.652072] libphy: ag71xx_mdio: probed
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 1.239913] ag71xx-mdio.1: Found an AR934X built-in switch
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 1.282370] eth0: Atheros AG71xx at 0xba000000, irq 5, mode:GMII
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 1.290123] NET: Registered protocol family 17
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 1.294724] bridge: automatic filtering via arp/ip/ip6tables has been deprecated. Update your scripts to load br_netfilter if you need this.
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 1.307678] 8021q: 802.1Q VLAN Support v1.8
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 1.319302] VFS: Mounted root (squashfs filesystem) readonly on device 31:4.
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 1.328610] Freeing unused kernel memory: 296K
Mon Jun 10 10:43:35 2019 user.info kernel: [ 2.348905] init: Console is alive
Mon Jun 10 10:43:35 2019 user.info kernel: [ 2.352598] init: - watchdog -
Mon Jun 10 10:43:35 2019 user.info kernel: [ 3.263651] kmodloader: loading kernel modules from /etc/modules-boot.d/*
Mon Jun 10 10:43:35 2019 user.info kernel: [ 3.345298] kmodloader: done loading kernel modules from /etc/modules-boot.d/*
Mon Jun 10 10:43:35 2019 user.info kernel: [ 3.363744] init: - preinit -
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 5.439877] eth0: link up (1000Mbps/Full duplex)
Mon Jun 10 10:43:35 2019 kern.notice kernel: [ 7.173906] jffs2: notice: (341) jffs2_build_xattr_subsystem: complete building xattr subsystem, 0 of xdatum (0 unchecked, 0 orphan) and 0 of xref (0 dead, 0 orphan) found.
Mon Jun 10 10:43:35 2019 user.info kernel: [ 7.191133] mount_root: switching to jffs2 overlay
Mon Jun 10 10:43:35 2019 user.warn kernel: [ 7.204137] urandom-seed: Seeding with /etc/urandom.seed
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 7.385925] eth0: link down
Mon Jun 10 10:43:35 2019 user.info kernel: [ 7.400413] procd: - early -
Mon Jun 10 10:43:35 2019 user.info kernel: [ 7.403450] procd: - watchdog -
Mon Jun 10 10:43:35 2019 user.info kernel: [ 8.029313] procd: - watchdog -
Mon Jun 10 10:43:35 2019 user.info kernel: [ 8.032804] procd: - ubus -
Mon Jun 10 10:43:35 2019 kern.notice kernel: [ 8.088554] random: ubusd: uninitialized urandom read (4 bytes read, 14 bits of entropy available)
Mon Jun 10 10:43:35 2019 kern.notice kernel: [ 8.098285] random: ubusd: uninitialized urandom read (4 bytes read, 14 bits of entropy available)
Mon Jun 10 10:43:35 2019 kern.notice kernel: [ 8.107437] random: ubusd: uninitialized urandom read (4 bytes read, 14 bits of entropy available)
Mon Jun 10 10:43:35 2019 kern.notice kernel: [ 8.120296] random: ubusd: uninitialized urandom read (4 bytes read, 14 bits of entropy available)
Mon Jun 10 10:43:35 2019 kern.notice kernel: [ 8.129521] random: ubusd: uninitialized urandom read (4 bytes read, 14 bits of entropy available)
Mon Jun 10 10:43:35 2019 kern.notice kernel: [ 8.139479] random: ubusd: uninitialized urandom read (4 bytes read, 14 bits of entropy available)
Mon Jun 10 10:43:35 2019 kern.notice kernel: [ 8.148864] random: ubusd: uninitialized urandom read (4 bytes read, 14 bits of entropy available)
Mon Jun 10 10:43:35 2019 user.info kernel: [ 8.158525] procd: - init -
Mon Jun 10 10:43:35 2019 user.info kernel: [ 8.500360] kmodloader: loading kernel modules from /etc/modules.d/*
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 8.509769] tun: Universal TUN/TAP device driver, 1.6
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 8.514904] tun: (C) 1999-2004 Max Krasnyansky
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 8.535990] u32 classifier
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 8.538810] input device check on
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 8.542524] Actions configured
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 8.548696] Mirror/redirect action on
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 8.666579] Loading modules backported from Linux version wt-2017-01-31-0-ge882dff19e7f
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 8.674770] Backport generated by backports.git backports-20160324-13-g24da7d3c
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 8.684237] ip_tables: (C) 2000-2006 Netfilter Core Team
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 8.698201] nf_conntrack version 0.5.0 (959 buckets, 3836 max)
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 8.736302] xt_time: kernel timezone is -0000
Mon Jun 10 10:43:35 2019 kern.debug kernel: [ 8.934908] ath: EEPROM regdomain: 0x0
Mon Jun 10 10:43:35 2019 kern.debug kernel: [ 8.934931] ath: EEPROM indicates default country code should be used
Mon Jun 10 10:43:35 2019 kern.debug kernel: [ 8.934941] ath: doing EEPROM country->regdmn map search
Mon Jun 10 10:43:35 2019 kern.debug kernel: [ 8.934963] ath: country maps to regdmn code: 0x3a
Mon Jun 10 10:43:35 2019 kern.debug kernel: [ 8.934975] ath: Country alpha2 being used: US
Mon Jun 10 10:43:35 2019 kern.debug kernel: [ 8.934985] ath: Regpair used: 0x3a
Mon Jun 10 10:43:35 2019 kern.debug kernel: [ 8.948387] ieee80211 phy0: Selected rate control algorithm 'minstrel_ht'
Mon Jun 10 10:43:35 2019 kern.info kernel: [ 8.954527] ieee80211 phy0: Atheros AR9340 Rev:2 mem=0xb8100000, irq=47
Mon Jun 10 10:43:35 2019 user.info kernel: [ 9.068769] kmodloader: done loading kernel modules from /etc/modules.d/*
Mon Jun 10 10:43:35 2019 kern.notice kernel: [ 10.825414] random: jshn: uninitialized urandom read (4 bytes read, 18 bits of entropy available)
Mon Jun 10 10:43:35 2019 kern.notice kernel: [ 10.916647] random: ubusd: uninitialized urandom read (4 bytes read, 18 bits of entropy available)
Mon Jun 10 10:43:35 2019 kern.notice kernel: [ 10.926112] random: ubus: uninitialized urandom read (4 bytes read, 18 bits of entropy available)
Mon Jun 10 10:43:36 2019 user.notice : Added device handler type: 8021ad
Mon Jun 10 10:43:36 2019 user.notice : Added device handler type: 8021q
Mon Jun 10 10:43:36 2019 user.notice : Added device handler type: macvlan
Mon Jun 10 10:43:36 2019 user.notice : Added device handler type: bridge
Mon Jun 10 10:43:36 2019 user.notice : Added device handler type: Network device
Mon Jun 10 10:43:36 2019 user.notice : Added device handler type: tunnel
Mon Jun 10 10:43:37 2019 cron.err crond[728]: root: No such file or directory
Mon Jun 10 10:43:38 2019 authpriv.info dropbear[747]: Not backgrounding
Mon Jun 10 10:43:38 2019 daemon.notice procd: /etc/rc.d/S96led: setting up led LAN0
Mon Jun 10 10:43:39 2019 daemon.notice procd: /etc/rc.d/S96led: setting up led LAN1
Mon Jun 10 10:43:40 2019 daemon.notice netifd: Interface 'lan' is enabled
Mon Jun 10 10:43:40 2019 daemon.notice netifd: Interface 'lan' is setting up now
Mon Jun 10 10:43:40 2019 daemon.notice netifd: Interface 'lan' is now up
Mon Jun 10 10:43:40 2019 kern.info kernel: [ 16.500546] device eth0.1 entered promiscuous mode
Mon Jun 10 10:43:40 2019 kern.info kernel: [ 16.505421] device eth0 entered promiscuous mode
Mon Jun 10 10:43:40 2019 daemon.notice netifd: Interface 'mesh_lan' is enabled
Mon Jun 10 10:43:40 2019 daemon.notice netifd: Interface 'mesh_lan' is setting up now
Mon Jun 10 10:43:40 2019 daemon.notice netifd: Interface 'mesh_lan' is now up
Mon Jun 10 10:43:40 2019 user.notice ddmesh: network-hotplug net: [br-lan] add type:bridge
Mon Jun 10 10:43:40 2019 daemon.notice netifd: Interface 'mesh_wan' is enabled
Mon Jun 10 10:43:40 2019 daemon.notice netifd: Interface 'mesh_wan' is setting up now
Mon Jun 10 10:43:40 2019 daemon.notice netifd: Interface 'mesh_wan' is now up
Mon Jun 10 10:43:40 2019 daemon.notice netifd: Interface 'wan' is enabled
Mon Jun 10 10:43:40 2019 daemon.notice netifd: Interface 'wan6' is enabled
Mon Jun 10 10:43:40 2019 kern.info kernel: [ 16.655263] device eth0.2 entered promiscuous mode
Mon Jun 10 10:43:40 2019 daemon.notice netifd: Interface 'loopback' is enabled
Mon Jun 10 10:43:40 2019 daemon.notice netifd: Interface 'loopback' is setting up now
Mon Jun 10 10:43:40 2019 daemon.notice netifd: Interface 'loopback' is now up
Mon Jun 10 10:43:40 2019 daemon.notice netifd: Network device 'lo' link is up
Mon Jun 10 10:43:40 2019 daemon.notice netifd: Interface 'loopback' has link connectivity
Mon Jun 10 10:43:40 2019 user.notice ddmesh: network-hotplug net: [eth0.1] add type:vlan
Mon Jun 10 10:43:40 2019 user.notice ddmesh: network-hotplug iface: network:[lan] interface:br-lan ifup
Mon Jun 10 10:43:40 2019 user.notice ddmesh: network-hotplug net: [br-mesh_lan] add type:bridge
Mon Jun 10 10:43:40 2019 user.notice ddmesh: network-hotplug net: [br-mesh_wan] add type:bridge
Mon Jun 10 10:43:41 2019 user.notice ddmesh: network-hotplug net: [br-wan] add type:bridge
Mon Jun 10 10:43:41 2019 user.notice ddmesh: network-hotplug net: [eth0.2] add type:vlan
Mon Jun 10 10:43:41 2019 daemon.notice netifd: Network device 'eth0' link is up
Mon Jun 10 10:43:41 2019 kern.info kernel: [ 18.091807] eth0: link up (1000Mbps/Full duplex)
Mon Jun 10 10:43:41 2019 kern.info kernel: [ 18.097641] br-lan: port 1(eth0.1) entered listening state
Mon Jun 10 10:43:41 2019 kern.info kernel: [ 18.103329] br-lan: port 1(eth0.1) entered listening state
Mon Jun 10 10:43:41 2019 kern.info kernel: [ 18.109011] br-wan: port 1(eth0.2) entered listening state
Mon Jun 10 10:43:41 2019 kern.info kernel: [ 18.114594] br-wan: port 1(eth0.2) entered listening state
Mon Jun 10 10:43:41 2019 daemon.notice netifd: VLAN 'eth0.1' link is up
Mon Jun 10 10:43:41 2019 daemon.notice netifd: VLAN 'eth0.2' link is up
Mon Jun 10 10:43:41 2019 daemon.notice netifd: Interface 'wan6' has link connectivity
Mon Jun 10 10:43:41 2019 daemon.notice netifd: Interface 'wan6' is setting up now
Mon Jun 10 10:43:41 2019 daemon.notice netifd: Interface 'wan6' is now up
Mon Jun 10 10:43:41 2019 daemon.notice procd: /etc/rc.d/S99freifunk: platform: ar71xx
Mon Jun 10 10:43:41 2019 daemon.notice procd: /etc/rc.d/S99freifunk: board: cpe210
Mon Jun 10 10:43:41 2019 daemon.notice procd: /etc/rc.d/S99freifunk: status-led:
Mon Jun 10 10:43:41 2019 daemon.notice procd: /etc/rc.d/S99freifunk: wifi-led: tp-link:green:link2
Mon Jun 10 10:43:41 2019 user.notice ddmesh: boot inital boot setting
Mon Jun 10 10:43:41 2019 daemon.notice procd: /etc/rc.d/S99freifunk: ddmesh: boot inital boot setting
Mon Jun 10 10:43:42 2019 daemon.notice procd: /etc/rc.d/S99freifunk: platform: ar71xx
Mon Jun 10 10:43:42 2019 daemon.notice procd: /etc/rc.d/S99freifunk: board: cpe210
Mon Jun 10 10:43:42 2019 daemon.notice procd: /etc/rc.d/S99freifunk: status-led:
Mon Jun 10 10:43:42 2019 daemon.notice procd: /etc/rc.d/S99freifunk: wifi-led: tp-link:green:link2
Mon Jun 10 10:43:42 2019 user.notice ddmesh-boot: boot step 3
Mon Jun 10 10:43:42 2019 daemon.notice procd: /etc/rc.d/S99freifunk: ddmesh-boot: boot step 3
Mon Jun 10 10:43:43 2019 daemon.notice procd: /etc/rc.d/S99freifunk: rm: can't remove '/var/etc/config/wireless': No such file or directory
Mon Jun 10 10:43:43 2019 daemon.notice procd: /etc/rc.d/S99freifunk: uci: Entry not found
Mon Jun 10 10:43:43 2019 daemon.notice procd: /etc/rc.d/S99freifunk: uci: Entry not found
Mon Jun 10 10:43:43 2019 daemon.notice procd: /etc/rc.d/S99freifunk: ok
Mon Jun 10 10:43:43 2019 daemon.notice procd: /etc/rc.d/S99freifunk: uci: Entry not found
Mon Jun 10 10:43:43 2019 daemon.notice procd: /etc/rc.d/S99freifunk: cfg043579
Mon Jun 10 10:43:43 2019 kern.info kernel: [ 20.098232] br-lan: port 1(eth0.1) entered learning state
Mon Jun 10 10:43:43 2019 kern.info kernel: [ 20.108231] br-wan: port 1(eth0.2) entered learning state
Mon Jun 10 10:43:43 2019 daemon.notice procd: /etc/rc.d/S99freifunk: cfg053579
Mon Jun 10 10:43:43 2019 daemon.notice procd: /etc/rc.d/S99freifunk: uci: Entry not found
Mon Jun 10 10:43:44 2019 user.notice ddmesh-boot: start wifi
Mon Jun 10 10:43:44 2019 daemon.notice procd: /etc/rc.d/S99freifunk: ddmesh-boot: start wifi
Mon Jun 10 10:43:44 2019 user.notice ddmesh-boot: check for WIFI
Mon Jun 10 10:43:45 2019 daemon.notice procd: /etc/rc.d/S99freifunk: ddmesh-boot: check for WIFI
Mon Jun 10 10:43:45 2019 user.notice ddmesh-boot: Wait for WIFI up: 0/30 (only_adhoc=, wifi:, wifi2:)
Mon Jun 10 10:43:45 2019 daemon.notice procd: /etc/rc.d/S99freifunk: ddmesh-boot: Wait for WIFI up: 0/30 (only_adhoc=, wifi:, wifi2:)
Mon Jun 10 10:43:45 2019 daemon.notice netifd: Interface 'wifi2' is enabled
Mon Jun 10 10:43:45 2019 daemon.notice netifd: Interface 'wifi2' is setting up now
Mon Jun 10 10:43:45 2019 daemon.notice netifd: Interface 'wifi2' is now up
Mon Jun 10 10:43:45 2019 user.notice ddmesh: network-hotplug net: [br-wifi2] add type:bridge
Mon Jun 10 10:43:45 2019 user.notice ddmesh: network-hotplug net: [wlan0] remove type:wlan
Mon Jun 10 10:43:45 2019 kern.debug kernel: [ 22.066462] ath: EEPROM regdomain: 0x8044
Mon Jun 10 10:43:45 2019 kern.debug kernel: [ 22.066486] ath: EEPROM indicates we should expect a country code
Mon Jun 10 10:43:45 2019 kern.debug kernel: [ 22.066497] ath: doing EEPROM country->regdmn map search
Mon Jun 10 10:43:45 2019 kern.debug kernel: [ 22.066509] ath: country maps to regdmn code: 0x55
Mon Jun 10 10:43:45 2019 kern.debug kernel: [ 22.066522] ath: Country alpha2 being used: BO
Mon Jun 10 10:43:45 2019 kern.debug kernel: [ 22.066532] ath: Regpair used: 0x55
Mon Jun 10 10:43:45 2019 kern.debug kernel: [ 22.066546] ath: regdomain 0x8044 dynamically updated by user
Mon Jun 10 10:43:45 2019 kern.info kernel: [ 22.098199] br-lan: topology change detected, propagating
Mon Jun 10 10:43:45 2019 kern.info kernel: [ 22.103703] br-lan: port 1(eth0.1) entered forwarding state
Mon Jun 10 10:43:45 2019 kern.info kernel: [ 22.109427] br-wan: topology change detected, propagating
Mon Jun 10 10:43:45 2019 kern.info kernel: [ 22.114912] br-wan: port 1(eth0.2) entered forwarding state
Mon Jun 10 10:43:45 2019 daemon.notice netifd: bridge 'br-lan' link is up
Mon Jun 10 10:43:45 2019 daemon.notice netifd: Interface 'lan' has link connectivity
Mon Jun 10 10:43:45 2019 daemon.notice netifd: bridge 'br-wan' link is up
Mon Jun 10 10:43:45 2019 daemon.notice netifd: Interface 'wan' has link connectivity
Mon Jun 10 10:43:45 2019 daemon.notice netifd: Interface 'wan' is setting up now
Mon Jun 10 10:43:45 2019 daemon.notice netifd: wan (1446): udhcpc: started, v1.25.1
Mon Jun 10 10:43:45 2019 daemon.notice netifd: wan (1446): udhcpc: sending discover
Mon Jun 10 10:43:45 2019 daemon.notice netifd: wan (1446): udhcpc: sending select for 172.17.17.26
Mon Jun 10 10:43:45 2019 daemon.notice netifd: wan (1446): udhcpc: lease of 172.17.17.26 obtained, lease time 864000
Mon Jun 10 10:43:46 2019 daemon.notice netifd: Interface 'wan' is now up
Mon Jun 10 10:43:46 2019 user.notice ddmesh-boot: Wait for WIFI up: 1/30 (only_adhoc=, wifi:, wifi2:)
Mon Jun 10 10:43:46 2019 daemon.notice procd: /etc/rc.d/S99freifunk: ddmesh-boot: Wait for WIFI up: 1/30 (only_adhoc=, wifi:, wifi2:)
Mon Jun 10 10:43:46 2019 daemon.info logread[623]: Logread connected to 172.17.17.56:514
Mon Jun 10 10:43:47 2019 user.notice ddmesh: network-hotplug net: [wlan0] add type:wlan
Mon Jun 10 10:43:47 2019 user.notice ddmesh-boot: Wait for WIFI up: 2/30 (only_adhoc=, wifi:, wifi2:)
Mon Jun 10 10:43:47 2019 daemon.notice procd: /etc/rc.d/S99freifunk: ddmesh-boot: Wait for WIFI up: 2/30 (only_adhoc=, wifi:, wifi2:)
Mon Jun 10 10:43:47 2019 user.notice ddmesh: network-hotplug iface: network:[mesh_lan] interface:br-mesh_lan ifup
Mon Jun 10 10:43:47 2019 user.notice ddmesh: network-hotplug net: [wlan0-1] add type:wlan
Mon Jun 10 10:43:48 2019 daemon.err hostapd: Configuration file: /var/run/hostapd-phy0.conf
Mon Jun 10 10:43:48 2019 user.notice ddmesh-boot: Wait for WIFI up: 3/30 (only_adhoc=, wifi:, wifi2:)
Mon Jun 10 10:43:48 2019 daemon.notice procd: /etc/rc.d/S99freifunk: ddmesh-boot: Wait for WIFI up: 3/30 (only_adhoc=, wifi:, wifi2:)
Mon Jun 10 10:43:48 2019 kern.info kernel: [ 24.902280] device wlan0-1 entered promiscuous mode
Mon Jun 10 10:43:48 2019 daemon.notice hostapd: wlan0-1: interface state UNINITIALIZED->COUNTRY_UPDATE
Mon Jun 10 10:43:48 2019 daemon.err hostapd: Using interface wlan0-1 with hwaddr 86:16:f9:48:fb:1c and ssid "Freifunk Hotspot [1681]"
Mon Jun 10 10:43:48 2019 kern.info kernel: [ 24.976635] br-wifi2: port 1(wlan0-1) entered listening state
Mon Jun 10 10:43:48 2019 kern.info kernel: [ 24.982579] br-wifi2: port 1(wlan0-1) entered listening state
Mon Jun 10 10:43:48 2019 daemon.notice hostapd: wlan0-1: interface state COUNTRY_UPDATE->ENABLED
Mon Jun 10 10:43:48 2019 daemon.notice hostapd: wlan0-1: AP-ENABLED
Mon Jun 10 10:43:48 2019 kern.info kernel: [ 25.292405] wlan0: Created IBSS using preconfigured BSSID 64:64:6d:65:73:68
Mon Jun 10 10:43:48 2019 kern.info kernel: [ 25.299545] wlan0: Creating new IBSS network, BSSID 64:64:6d:65:73:68
Mon Jun 10 10:43:49 2019 user.notice ddmesh: network-hotplug iface: network:[mesh_wan] interface:br-mesh_wan ifup
Mon Jun 10 10:43:49 2019 user.notice ddmesh-boot: Wait for WIFI up: 4/30 (only_adhoc=, wifi:, wifi2:)
Mon Jun 10 10:43:49 2019 daemon.notice procd: /etc/rc.d/S99freifunk: ddmesh-boot: Wait for WIFI up: 4/30 (only_adhoc=, wifi:, wifi2:)
Mon Jun 10 10:43:50 2019 user.notice ddmesh: network-hotplug iface: network:[loopback] interface:lo ifup
Mon Jun 10 10:43:50 2019 kern.info kernel: [ 26.978212] br-wifi2: port 1(wlan0-1) entered learning state
Mon Jun 10 10:43:50 2019 user.notice ddmesh-boot: Wait for WIFI up: 5/30 (only_adhoc=, wifi:, wifi2:)
Mon Jun 10 10:43:50 2019 daemon.notice procd: /etc/rc.d/S99freifunk: ddmesh-boot: Wait for WIFI up: 5/30 (only_adhoc=, wifi:, wifi2:)
Mon Jun 10 10:43:50 2019 user.notice ddmesh: network-hotplug iface: network:[wan6] interface:eth0.2 ifup
Mon Jun 10 10:43:51 2019 user.notice ddmesh: network-hotplug iface: network:[wifi2] interface:br-wifi2 ifup
Mon Jun 10 10:43:51 2019 user.notice ddmesh-boot: Wait for WIFI up: 6/30 (only_adhoc=, wifi:, wifi2:)
Mon Jun 10 10:43:51 2019 daemon.notice procd: /etc/rc.d/S99freifunk: ddmesh-boot: Wait for WIFI up: 6/30 (only_adhoc=, wifi:, wifi2:)
Mon Jun 10 10:43:51 2019 user.notice ddmesh: network-hotplug iface: network:[wan] interface:br-wan ifup
Mon Jun 10 10:43:52 2019 user.warn ddns-scripts[2001]: dyndns: Service section disabled! - TERMINATE
Mon Jun 10 10:43:52 2019 user.warn ddns-scripts[2001]: dyndns: PID '2001' exit WITH ERROR '1' at 2019-06-10 10:43
Mon Jun 10 10:43:52 2019 kern.info kernel: [ 28.978188] br-wifi2: topology change detected, propagating
Mon Jun 10 10:43:52 2019 kern.info kernel: [ 28.983871] br-wifi2: port 1(wlan0-1) entered forwarding state
Mon Jun 10 10:43:52 2019 daemon.notice netifd: bridge 'br-wifi2' link is up
Mon Jun 10 10:43:52 2019 daemon.notice netifd: Interface 'wifi2' has link connectivity
Mon Jun 10 10:43:52 2019 user.notice ddmesh-boot: Wait for WIFI up: 7/30 (only_adhoc=, wifi:, wifi2:)
Mon Jun 10 10:43:52 2019 daemon.notice procd: /etc/rc.d/S99freifunk: ddmesh-boot: Wait for WIFI up: 7/30 (only_adhoc=, wifi:, wifi2:)
Mon Jun 10 10:43:53 2019 user.notice ddmesh-boot: Wait for WIFI up: 8/30 (only_adhoc=, wifi:, wifi2:)
Mon Jun 10 10:43:53 2019 daemon.notice procd: /etc/rc.d/S99freifunk: ddmesh-boot: Wait for WIFI up: 8/30 (only_adhoc=, wifi:, wifi2:)
Mon Jun 10 10:43:54 2019 user.notice ddmesh-boot: Wait for WIFI up: 9/30 (only_adhoc=, wifi:, wifi2:)
Mon Jun 10 10:43:54 2019 daemon.notice procd: /etc/rc.d/S99freifunk: ddmesh-boot: Wait for WIFI up: 9/30 (only_adhoc=, wifi:, wifi2:)
Mon Jun 10 10:43:55 2019 user.notice ddmesh-boot: Wait for WIFI up: 10/30 (only_adhoc=, wifi:, wifi2:)
Mon Jun 10 10:43:55 2019 daemon.notice procd: /etc/rc.d/S99freifunk: ddmesh-boot: Wait for WIFI up: 10/30 (only_adhoc=, wifi:, wifi2:)
Mon Jun 10 10:43:56 2019 user.notice ddmesh-boot: Wait for WIFI up: 11/30 (only_adhoc=, wifi:, wifi2:)
Mon Jun 10 10:43:56 2019 daemon.notice procd: /etc/rc.d/S99freifunk: ddmesh-boot: Wait for WIFI up: 11/30 (only_adhoc=, wifi:, wifi2:)
Mon Jun 10 10:43:57 2019 user.notice ddmesh-boot: Wait for WIFI up: 12/30 (only_adhoc=, wifi:, wifi2:)
Mon Jun 10 10:43:57 2019 daemon.notice procd: /etc/rc.d/S99freifunk: ddmesh-boot: Wait for WIFI up: 12/30 (only_adhoc=, wifi:, wifi2:)
Mon Jun 10 10:43:58 2019 user.notice ddmesh-boot: Wait for WIFI up: 13/30 (only_adhoc=, wifi:, wifi2:)
Mon Jun 10 10:43:58 2019 daemon.notice procd: /etc/rc.d/S99freifunk: ddmesh-boot: Wait for WIFI up: 13/30 (only_adhoc=, wifi:, wifi2:)
Mon Jun 10 10:43:59 2019 daemon.notice hostapd: wlan0-1: interface state ENABLED->DISABLED
Mon Jun 10 10:43:59 2019 daemon.notice hostapd: wlan0-1: AP-DISABLED
Mon Jun 10 10:43:59 2019 daemon.notice hostapd: wlan0-1: CTRL-EVENT-TERMINATING
Mon Jun 10 10:43:59 2019 daemon.notice hostapd: nl80211: deinit ifname=wlan0-1 disabled_11b_rates=0
Mon Jun 10 10:43:59 2019 kern.info kernel: [ 35.666798] device wlan0-1 left promiscuous mode
Mon Jun 10 10:43:59 2019 kern.info kernel: [ 35.671676] br-wifi2: port 1(wlan0-1) entered disabled state
Mon Jun 10 10:43:59 2019 daemon.notice netifd: bridge 'br-wifi2' link is down
Mon Jun 10 10:43:59 2019 daemon.notice netifd: Interface 'wifi2' has link connectivity loss
Mon Jun 10 10:43:59 2019 user.notice ddmesh: network-hotplug net: [wlan0] remove type:wlan
Mon Jun 10 10:43:59 2019 user.notice ddmesh: network-hotplug net: [wlan0-1] remove type:wlan
Mon Jun 10 10:44:00 2019 user.notice ddmesh: network-hotplug net: [wlan0] add type:wlan
Mon Jun 10 10:44:00 2019 user.notice ddmesh-boot: Wait for WIFI up: 14/30 (only_adhoc=, wifi:, wifi2:)
Mon Jun 10 10:44:00 2019 daemon.notice procd: /etc/rc.d/S99freifunk: ddmesh-boot: Wait for WIFI up: 14/30 (only_adhoc=, wifi:, wifi2:)
Mon Jun 10 10:44:00 2019 daemon.err hostapd: Configuration file: /var/run/hostapd-phy0.conf
Mon Jun 10 10:44:00 2019 kern.info kernel: [ 36.643250] device wlan0-1 entered promiscuous mode
Mon Jun 10 10:44:00 2019 kern.info kernel: [ 36.648425] br-wifi2: port 1(wlan0-1) entered listening state
Mon Jun 10 10:44:00 2019 kern.info kernel: [ 36.654283] br-wifi2: port 1(wlan0-1) entered listening state
Mon Jun 10 10:44:00 2019 user.notice ddmesh: network-hotplug net: [wlan0-1] add type:wlan
Mon Jun 10 10:44:00 2019 daemon.notice hostapd: wlan0-1: interface state UNINITIALIZED->COUNTRY_UPDATE
Mon Jun 10 10:44:00 2019 kern.info kernel: [ 36.678285] br-wifi2: port 1(wlan0-1) entered disabled state
Mon Jun 10 10:44:00 2019 daemon.err hostapd: Using interface wlan0-1 with hwaddr 86:16:f9:48:fb:1c and ssid "Freifunk Hotspot [1681]"
Mon Jun 10 10:44:00 2019 kern.info kernel: [ 36.716472] br-wifi2: port 1(wlan0-1) entered listening state
Mon Jun 10 10:44:00 2019 kern.info kernel: [ 36.722424] br-wifi2: port 1(wlan0-1) entered listening state
Mon Jun 10 10:44:00 2019 daemon.notice hostapd: wlan0-1: interface state COUNTRY_UPDATE->ENABLED
Mon Jun 10 10:44:00 2019 daemon.notice hostapd: wlan0-1: AP-ENABLED
Mon Jun 10 10:44:00 2019 kern.info kernel: [ 36.863639] wlan0: Created IBSS using preconfigured BSSID 64:64:6d:65:73:68
Mon Jun 10 10:44:00 2019 kern.info kernel: [ 36.870781] wlan0: Creating new IBSS network, BSSID 64:64:6d:65:73:68
Mon Jun 10 10:44:00 2019 daemon.notice netifd: Network device 'wlan0' link is up
Mon Jun 10 10:44:00 2019 daemon.notice netifd: Interface 'wifi' is enabled
Mon Jun 10 10:44:00 2019 daemon.notice netifd: Interface 'wifi' is setting up now
Mon Jun 10 10:44:00 2019 daemon.notice netifd: Interface 'wifi' is now up
Mon Jun 10 10:44:00 2019 daemon.notice netifd: Interface 'wifi' has link connectivity
Mon Jun 10 10:44:00 2019 daemon.notice netifd: Network device 'wlan0-1' link is up
Mon Jun 10 10:44:00 2019 user.notice ddmesh: network-hotplug iface: network:[wifi] interface:wlan0 ifup
Mon Jun 10 10:44:01 2019 user.notice ddmesh-boot: Wait for WIFI up: 15/30 (only_adhoc=, wifi:1, wifi2:1)
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: ddmesh-boot: Wait for WIFI up: 15/30 (only_adhoc=, wifi:1, wifi2:1)
Mon Jun 10 10:44:01 2019 user.notice ddmesh-boot: WIFI is up - continue
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: ddmesh-boot: WIFI is up - continue
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: platform: ar71xx
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: board: cpe210
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: status-led:
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: wifi-led: tp-link:green:link2
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: Warning: Unable to locate ipset utility, disabling ipset support
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: Warning: Section @zone[0] (lan) has no device, network, subnet or extra options
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: Warning: Section @zone[1] (wan) has no device, network, subnet or extra options
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: Warning: Section @zone[3] (bat) has no device, network, subnet or extra options
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: Warning: Section @zone[4] (mesh) has no device, network, subnet or extra options
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: Warning: Section @zone[5] (vpn) has no device, network, subnet or extra options
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Flushing IPv4 filter table
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Flushing IPv4 nat table
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Flushing IPv4 mangle table
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Flushing conntrack table ...
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Populating IPv4 filter table
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Zone 'lan'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Zone 'wan'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Zone 'wifi2'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Zone 'bat'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Zone 'mesh'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Zone 'vpn'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'forward_lan_lan'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'forward_lan_wan_192_168'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'forward_lan_wan_172_16'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'forward_lan_wan_10'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'forward_lan_wan'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'forward_lan_mesh'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'forward_lan_bat_192_168'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'forward_lan_bat_172_16'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'forward_lan_bat_10'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'forward_lan_bat'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'forward_lan_vpn_192_168'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'forward_lan_vpn_172_16'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'forward_lan_vpn_10'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'forward_lan_vpn'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'forward_mesh_mesh'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'forward_wifi2_wan_192_168'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'forward_wifi2_wan_172_16'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'forward_wifi2_wan_10'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'forward_wifi2_wan'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'forward_wifi2_mesh'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'forward_wifi2_bat_192_168'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'forward_wifi2_bat_172_16'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'forward_wifi2_bat_10'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'forward_wifi2_bat'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'forward_wifi2_vpn_192_168'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'forward_wifi2_vpn_172_16'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'forward_wifi2_vpn_10'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'forward_wifi2_vpn'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'forward_bat_wan_192_168'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'forward_bat_wan_172_16'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'forward_bat_wan_10'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'forward_bat_wan'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'forward_bat_vpn_192_168'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'forward_bat_vpn_172_16'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'forward_bat_vpn_10'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'forward_bat_vpn'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'Allow-lan-ssh'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'Allow-bmxd-mesh'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'Allow-dhcp-answer'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'Allow-dhcp-lan-requests'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'Allow-dhcp-wifi2-requests'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'Allow-dns-lan-requests'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'Allow-dns-wifi2-requests'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'Allow-dns-mesh-requests'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'Allow-HTTP-lan'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'Allow-HTTP-mesh'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'Allow-HTTP-wifi2'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'Allow-HTTPS-lan'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'Allow-HTTPS-mesh'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'Allow-HTTPS-wifi2'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'Allow-icmp-lan'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'Allow-icmp-mesh'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'Allow-icmp-wifi2'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'Allow-iperf3-mesh-tcp'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'Allow-iperf3-mesh-udp'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'Allow-iperf3-tcp'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'Allow-iperf3-udp'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'Allow-wan-ssh'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'Allow-mesh-ssh'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'Allow-wifi2-ssh'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'Allow-wan-http'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'Allow-wan-https'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Rule 'Allow-wan-icmp'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Populating IPv4 nat table
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Zone 'lan'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Zone 'wan'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Zone 'wifi2'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Zone 'bat'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Zone 'mesh'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Zone 'vpn'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Populating IPv4 mangle table
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Zone 'lan'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Zone 'wan'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Zone 'wifi2'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Zone 'bat'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Zone 'mesh'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Zone 'vpn'
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Set tcp_ecn to off
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Set tcp_syncookies to on
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Set tcp_window_scaling to on
Mon Jun 10 10:44:01 2019 daemon.notice procd: /etc/rc.d/S99freifunk: * Running script '/etc/firewall.user'
Mon Jun 10 10:44:02 2019 kern.info kernel: [ 38.718926] br-wifi2: port 1(wlan0-1) entered learning state
Mon Jun 10 10:44:04 2019 kern.info kernel: [ 40.718210] br-wifi2: topology change detected, propagating
Mon Jun 10 10:44:04 2019 kern.info kernel: [ 40.723896] br-wifi2: port 1(wlan0-1) entered forwarding state
Mon Jun 10 10:44:04 2019 daemon.notice netifd: bridge 'br-wifi2' link is up
Mon Jun 10 10:44:04 2019 daemon.notice netifd: Interface 'wifi2' has link connectivity
Mon Jun 10 10:44:05 2019 kern.notice kernel: [ 42.168224] random: nonblocking pool is initialized
Mon Jun 10 10:44:18 2019 user.notice ddmesh: boot network
Mon Jun 10 10:44:18 2019 daemon.notice procd: /etc/rc.d/S99freifunk: ddmesh: boot network
Mon Jun 10 10:44:19 2019 daemon.notice procd: /etc/rc.d/S99freifunk: ip: RTNETLINK answers: No such process
Mon Jun 10 10:44:19 2019 user.notice ddmesh: boot splash firewall
Mon Jun 10 10:44:19 2019 daemon.info logread[623]: failed to send log data to 172.17.17.56:514 via udp
Mon Jun 10 10:44:19 2019 daemon.notice procd: /etc/rc.d/S99freifunk: ddmesh: boot splash firewall
Mon Jun 10 10:44:19 2019 user.notice ddmesh: boot dnsmasq
Mon Jun 10 10:44:19 2019 daemon.notice procd: /etc/rc.d/S99freifunk: ddmesh: boot dnsmasq
Mon Jun 10 10:44:20 2019 user.notice ddmesh: boot start service bmxd
Mon Jun 10 10:44:20 2019 daemon.notice procd: /etc/rc.d/S99freifunk: ddmesh: boot start service bmxd
Mon Jun 10 10:44:20 2019 user.notice ddmesh: network-hotplug net: [bmx_prime] add type:bridge
Mon Jun 10 10:44:20 2019 daemon.notice procd: /etc/rc.d/S99freifunk: Starting bmxd: opt: --throw-rules 0 --prio-rules 0 --gateway_tunnel_network 10.200.0.0/16 --one-way-tunnel 1 --purge_timeout 20 -r 3 --gateway_hysteresis 20 -p 10.200.200.2 dev=bmx_prime /linklayer 0 dev=tbb_fastd /linklayer 1 dev=br-mesh_lan /linklayer 1 dev=br-mesh_wan /linklayer 1 dev=wlan0 /linklayer 2
Mon Jun 10 10:44:21 2019 user.notice ddmesh: boot start service backbone
Mon Jun 10 10:44:21 2019 daemon.err bmx[4110]: WARN netmask of loopback interface is 16 but SHOULD BE 32
Mon Jun 10 10:44:21 2019 daemon.notice procd: /etc/rc.d/S99freifunk: ddmesh: boot start service backbone
Mon Jun 10 10:44:21 2019 daemon.err bmx[4110]: INFO changing /proc/sys/net/ipv4/conf/bmx_prime/send_redirects from 1 to 0
Mon Jun 10 10:44:21 2019 daemon.err bmx[4110]: INFO enabled loopback dev bmx_prime 10.200.6.152/16 brc 10.255.255.255
Mon Jun 10 10:44:21 2019 daemon.err bmx[4110]: INFO skipping invalid IF tbb_fastd
Mon Jun 10 10:44:21 2019 daemon.err bmx[4110]: WARN not using interface tbb_fastd (retrying later): interface not ready
Mon Jun 10 10:44:21 2019 daemon.err bmx[4110]: INFO changing /proc/sys/net/ipv4/conf/br-mesh_lan/send_redirects from 1 to 0
Mon Jun 10 10:44:21 2019 daemon.err bmx[4110]: INFO enabled ethernet dev br-mesh_lan 10.201.6.152/16 brc 10.255.255.255 (use br-mesh_lan /l=2 to optimize for wireless)
Mon Jun 10 10:44:21 2019 daemon.err bmx[4110]: INFO changing /proc/sys/net/ipv4/conf/br-mesh_wan/send_redirects from 1 to 0
Mon Jun 10 10:44:21 2019 daemon.err bmx[4110]: INFO enabled ethernet dev br-mesh_wan 10.201.6.152/16 brc 10.255.255.255 (use br-mesh_wan /l=2 to optimize for wireless)
Mon Jun 10 10:44:21 2019 daemon.err bmx[4110]: INFO changing /proc/sys/net/ipv4/conf/wlan0/send_redirects from 1 to 0
Mon Jun 10 10:44:21 2019 daemon.err bmx[4110]: INFO enabled wireless dev wlan0 10.201.6.152/16 brc 10.255.255.255 (use wlan0 /l=1 to optimize for ethernet)
Mon Jun 10 10:44:21 2019 daemon.err bmx[4110]: INFO changing /proc/sys/net/ipv4/conf/all/send_redirects from 1 to 0
Mon Jun 10 10:44:21 2019 daemon.err bmx[4110]: INFO changing /proc/sys/net/ipv4/conf/default/send_redirects from 1 to 0
Mon Jun 10 10:44:22 2019 daemon.notice procd: /etc/rc.d/S99freifunk: Starting backbone...
Mon Jun 10 10:44:23 2019 daemon.notice netifd: Interface 'tbb_fastd' is enabled
Mon Jun 10 10:44:23 2019 daemon.notice netifd: Interface 'tbb_fastd' is setting up now
Mon Jun 10 10:44:23 2019 daemon.notice netifd: Interface 'tbb_fastd' is now up
Mon Jun 10 10:44:23 2019 daemon.notice netifd: Network device 'tbb_fastd' link is up
Mon Jun 10 10:44:23 2019 daemon.notice netifd: Interface 'tbb_fastd' has link connectivity
Mon Jun 10 10:44:23 2019 user.notice ddmesh: boot start service privnet
Mon Jun 10 10:44:23 2019 daemon.notice procd: /etc/rc.d/S99freifunk: ddmesh: boot start service privnet
Mon Jun 10 10:44:23 2019 user.notice ddmesh: network-hotplug net: [tbb_fastd] add type:
Mon Jun 10 10:44:23 2019 user.notice ddmesh: network-hotplug iface: network:[tbb_fastd] interface:tbb_fastd ifup
Mon Jun 10 10:44:23 2019 daemon.notice netifd: Network device 'tbb_fastd' link is down
Mon Jun 10 10:44:23 2019 daemon.notice netifd: Interface 'tbb_fastd' has link connectivity loss
Mon Jun 10 10:44:23 2019 daemon.notice procd: /etc/rc.d/S99freifunk: ip: either "dev" is duplicate, or "promisc" is garbage
Mon Jun 10 10:44:23 2019 daemon.err bmx[4110]: INFO detected valid but disabled dev: tbb_fastd ! Activating now...
Mon Jun 10 10:44:23 2019 daemon.err bmx[4110]: INFO enabled ethernet dev tbb_fastd 10.201.6.152/16 brc 10.255.255.255 (use tbb_fastd /l=2 to optimize for wireless)
Mon Jun 10 10:44:23 2019 daemon.err bmx[4110]: WARN strange own OGM schedule, rescheduling IF tbb_fastd SQN 21058 from 1000 to 3047. Maybe we just woke up from power-save mode, --ogm_interval too small, --aggreg_interval to big or too much --ogm_broadcasts
Mon Jun 10 10:44:23 2019 daemon.notice netifd: Network device 'tbb_fastd' link is up
Mon Jun 10 10:44:23 2019 daemon.notice netifd: Interface 'tbb_fastd' has link connectivity
Mon Jun 10 10:44:24 2019 daemon.notice procd: /etc/rc.d/S99freifunk: Starting privnet...
Mon Jun 10 10:44:25 2019 daemon.notice netifd: Interface 'privnet' is enabled
Mon Jun 10 10:44:25 2019 daemon.notice netifd: Interface 'privnet' is setting up now
Mon Jun 10 10:44:25 2019 daemon.notice netifd: Interface 'privnet' is now up
Mon Jun 10 10:44:25 2019 daemon.notice netifd: Network device 'priv' link is up
Mon Jun 10 10:44:25 2019 user.notice ddmesh: boot start service openvpn
Mon Jun 10 10:44:25 2019 daemon.notice netifd: Interface 'privnet' has link connectivity
Mon Jun 10 10:44:25 2019 daemon.notice procd: /etc/rc.d/S99freifunk: ddmesh: boot start service openvpn
Mon Jun 10 10:44:25 2019 user.notice ddmesh: boot start service iperf3
Mon Jun 10 10:44:25 2019 daemon.notice procd: /etc/rc.d/S99freifunk: ddmesh: boot start service iperf3
Mon Jun 10 10:44:25 2019 user.notice ddmesh: boot register node
Mon Jun 10 10:44:25 2019 daemon.notice procd: /etc/rc.d/S99freifunk: ddmesh: boot register node
Mon Jun 10 10:44:25 2019 daemon.notice netifd: Network device 'priv' link is down
Mon Jun 10 10:44:25 2019 daemon.notice netifd: Interface 'privnet' has link connectivity loss
Mon Jun 10 10:44:25 2019 daemon.notice procd: /etc/rc.d/S99freifunk: ip: either "dev" is duplicate, or "promisc" is garbage
Mon Jun 10 10:44:25 2019 kern.info kernel: [ 62.372441] device priv entered promiscuous mode
Mon Jun 10 10:44:25 2019 user.notice ddmesh: network-hotplug net: [priv] add type:
Mon Jun 10 10:44:26 2019 daemon.notice netifd: Network device 'priv' link is up
Mon Jun 10 10:44:26 2019 daemon.notice netifd: Interface 'privnet' has link connectivity
Mon Jun 10 10:44:26 2019 kern.info kernel: [ 62.422803] br-lan: port 2(priv) entered listening state
Mon Jun 10 10:44:26 2019 kern.info kernel: [ 62.428306] br-lan: port 2(priv) entered listening state
Mon Jun 10 10:44:26 2019 user.notice ddmesh: network-hotplug iface: network:[privnet] interface:priv ifup
Mon Jun 10 10:44:26 2019 daemon.notice procd: /etc/rc.d/S99freifunk: usage: register_node.sh [new_node]
Mon Jun 10 10:44:26 2019 daemon.notice procd: /etc/rc.d/S99freifunk: current node: [1681]
Mon Jun 10 10:44:26 2019 daemon.notice procd: /etc/rc.d/S99freifunk: current key: [28:5f:7d:4a:77:1d:a9:ee:37:c9:fa:54:af:06:57:de:a6:8f:77:f0:f2:c4:08:cf:59:f0:61:95:73:47:de:12]
Mon Jun 10 10:44:26 2019 daemon.notice procd: /etc/rc.d/S99freifunk: Try to register node [1681], key [28:5f:7d:4a:77:1d:a9:ee:37:c9:fa:54:af:06:57:de:a6:8f:77:f0:f2:c4:08:cf:59:f0:61:95:73:47:de:12]
Mon Jun 10 10:44:28 2019 kern.info kernel: [ 64.428234] br-lan: port 2(priv) entered learning state
Mon Jun 10 10:44:30 2019 kern.info kernel: [ 66.428157] br-lan: topology change detected, propagating
Mon Jun 10 10:44:30 2019 kern.info kernel: [ 66.433659] br-lan: port 2(priv) entered forwarding state
Mon Jun 10 10:44:31 2019 daemon.notice procd: /etc/rc.d/S99freifunk: connection error
Mon Jun 10 10:44:31 2019 user.notice ddmesh: boot start cron.
Mon Jun 10 10:44:31 2019 daemon.notice procd: /etc/rc.d/S99freifunk: ddmesh: boot start cron.
Mon Jun 10 10:44:32 2019 user.notice ddmesh: boot finished.
Mon Jun 10 10:44:32 2019 daemon.notice procd: /etc/rc.d/S99freifunk: ddmesh: boot finished.
Mon Jun 10 10:44:32 2019 daemon.notice procd: /etc/rc.d/S99freifunk: platform: ar71xx
Mon Jun 10 10:44:32 2019 daemon.notice procd: /etc/rc.d/S99freifunk: board: cpe210
Mon Jun 10 10:44:32 2019 daemon.notice procd: /etc/rc.d/S99freifunk: status-led:
Mon Jun 10 10:44:32 2019 daemon.notice procd: /etc/rc.d/S99freifunk: wifi-led: tp-link:green:link2
Mon Jun 10 10:44:32 2019 daemon.info procd: - init complete -
Mon Jun 10 10:45:34 2019 daemon.info logread[623]: Logread connected to 172.17.17.56:514
Mon Jun 10 10:47:21 2019 daemon.err bmx[4110]: WARN critical system time drift detected: ++ca 100 s, 937985 us! Correcting reference!
Mon Jun 10 10:47:33 2019 daemon.err bmx[4110]: WARN validation: no id4him for primary originator 0.72.208.0. init_pifnb_node() ret=0
Mon Jun 10 10:47:35 2019 daemon.err bmx[4110]: INFO using new default tunnel to GW 10.200.200.18 (gw_flags: 63, packet_count: 1, gw_product: 0)
Mon Jun 10 10:47:35 2019 user.notice BMXD-SCRIPT[5869]: nameserver 10.200.200.18
Mon Jun 10 10:47:35 2019 user.notice ddmesh: network-hotplug net: [bat0] add type:
Mon Jun 10 10:47:35 2019 user.notice BMXD-SCRIPT[5869]: ssid: Freifunk Hotspot [1681]
Mon Jun 10 10:47:36 2019 daemon.err bmx[4110]: WARN critical system time drift detected: ++ca 0 s, 508018 us! Correcting reference!
Mon Jun 10 10:47:36 2019 daemon.err bmx[4110]: WARN validation: no id4him for primary originator 0.74.80.160. init_pifnb_node() ret=0
Mon Jun 10 10:47:43 2019 user.notice BMXD-SCRIPT[6263]: remove GATEWAY (del)
Mon Jun 10 10:47:43 2019 user.notice BMXD-SCRIPT[6263]: ssid: FF no-inet [1681]
Mon Jun 10 10:47:45 2019 daemon.err bmx[4110]: INFO del_dev_tun(): closing gwc_cleanup tunnel bat0 ip 10.200.6.152
Mon Jun 10 10:47:45 2019 daemon.err bmx[4110]: WARN critical system time drift detected: ++ca 1 s, 473677 us! Correcting reference!
Mon Jun 10 10:47:45 2019 daemon.err bmx[4110]: INFO Preferred gateway found: 10.200.200.2 (gw_flags: 63, packet_count: 3, ws: 100, gw_product: 0)
Mon Jun 10 10:47:45 2019 daemon.err bmx[4110]: INFO using new default tunnel to GW 10.200.200.2 (gw_flags: 63, packet_count: 16, gw_product: 0)
Mon Jun 10 10:47:45 2019 user.notice BMXD-SCRIPT[6568]: nameserver 10.200.200.2
Mon Jun 10 10:47:45 2019 user.notice ddmesh: network-hotplug net: [bat0] remove type:
Mon Jun 10 10:47:46 2019 user.notice ddmesh: network-hotplug net: [bat0] add type:
Mon Jun 10 10:47:46 2019 user.notice BMXD-SCRIPT[6568]: ssid: Freifunk Hotspot [1681]
Mon Jun 10 10:47:48 2019 daemon.err bmx[4110]: WARN critical system time drift detected: ++ca 2 s, 25218 us! Correcting reference!
Mon Jun 10 10:47:50 2019 daemon.err bmx[4110]: WARN validation: no id4him for primary originator 0.73.234.112. init_pifnb_node() ret=0
Mon Jun 10 10:47:53 2019 daemon.err bmx[4110]: WARN validation: no id4him for primary originator 0.75.217.192. init_pifnb_node() ret=0
Mon Jun 10 10:59:56 2019 daemon.info hostapd: wlan0-1: STA d0:d2:b0:47:b7:9c IEEE 802.11: authenticated
Mon Jun 10 10:59:56 2019 daemon.info hostapd: wlan0-1: STA d0:d2:b0:47:b7:9c IEEE 802.11: associated (aid 1)
Mon Jun 10 10:59:56 2019 daemon.notice hostapd: wlan0-1: AP-STA-CONNECTED d0:d2:b0:47:b7:9c
Mon Jun 10 11:00:01 2019 user.notice DHCP: cmd:add mac:d0:d2:b0:47:b7:9c ip:100.64.252.120 hostname:THX
Mon Jun 10 11:05:40 2019 daemon.notice hostapd: wlan0-1: AP-STA-DISCONNECTED d0:d2:b0:47:b7:9c
Mon Jun 10 11:05:40 2019 daemon.info hostapd: wlan0-1: STA d0:d2:b0:47:b7:9c IEEE 802.11: disassociated
Mon Jun 10 11:05:41 2019 daemon.info hostapd: wlan0-1: STA d0:d2:b0:47:b7:9c IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Jun 10 11:06:07 2019 daemon.info hostapd: wlan0-1: STA d0:d2:b0:47:b7:9c IEEE 802.11: authenticated
Mon Jun 10 11:06:07 2019 daemon.info hostapd: wlan0-1: STA d0:d2:b0:47:b7:9c IEEE 802.11: associated (aid 1)
Mon Jun 10 11:06:07 2019 daemon.notice hostapd: wlan0-1: AP-STA-CONNECTED d0:d2:b0:47:b7:9c
Mon Jun 10 11:06:07 2019 user.notice DHCP: cmd:old mac:d0:d2:b0:47:b7:9c ip:100.64.252.120 hostname:THX
Mon Jun 10 11:09:55 2019 daemon.info hostapd: wlan0-1: STA d0:d2:b0:47:b7:9c IEEE 802.11: authenticated
Mon Jun 10 11:09:55 2019 daemon.info hostapd: wlan0-1: STA d0:d2:b0:47:b7:9c IEEE 802.11: associated (aid 1)
Mon Jun 10 11:09:55 2019 daemon.notice hostapd: wlan0-1: AP-STA-CONNECTED d0:d2:b0:47:b7:9c
Mon Jun 10 11:09:55 2019 user.notice DHCP: cmd:old mac:d0:d2:b0:47:b7:9c ip:100.64.252.120 hostname:THX
Mon Jun 10 11:14:21 2019 daemon.notice hostapd: wlan0-1: AP-STA-DISCONNECTED d0:d2:b0:47:b7:9c
Mon Jun 10 11:14:21 2019 daemon.info hostapd: wlan0-1: STA d0:d2:b0:47:b7:9c IEEE 802.11: disassociated
Mon Jun 10 11:14:22 2019 daemon.info hostapd: wlan0-1: STA d0:d2:b0:47:b7:9c IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Jun 10 11:16:37 2019 daemon.info hostapd: wlan0-1: STA d0:d2:b0:47:b7:9c IEEE 802.11: authenticated
Mon Jun 10 11:16:37 2019 daemon.info hostapd: wlan0-1: STA d0:d2:b0:47:b7:9c IEEE 802.11: associated (aid 1)
Mon Jun 10 11:16:37 2019 daemon.notice hostapd: wlan0-1: AP-STA-CONNECTED d0:d2:b0:47:b7:9c
Mon Jun 10 11:16:38 2019 user.notice DHCP: cmd:old mac:d0:d2:b0:47:b7:9c ip:100.64.252.120 hostname:THX
Mon Jun 10 11:21:04 2019 daemon.notice hostapd: wlan0-1: AP-STA-DISCONNECTED d0:d2:b0:47:b7:9c
Mon Jun 10 11:21:04 2019 daemon.info hostapd: wlan0-1: STA d0:d2:b0:47:b7:9c IEEE 802.11: disassociated
Mon Jun 10 11:21:05 2019 daemon.info hostapd: wlan0-1: STA d0:d2:b0:47:b7:9c IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Jun 10 11:26:01 2019 user.notice DHCP: cmd:del mac:d0:d2:b0:47:b7:9c ip:100.64.252.120 hostname:THX
Mon Jun 10 11:29:15 2019 daemon.info hostapd: wlan0-1: STA d0:d2:b0:47:b7:9c IEEE 802.11: authenticated
Mon Jun 10 11:29:15 2019 daemon.info hostapd: wlan0-1: STA d0:d2:b0:47:b7:9c IEEE 802.11: associated (aid 1)
Mon Jun 10 11:29:15 2019 daemon.notice hostapd: wlan0-1: AP-STA-CONNECTED d0:d2:b0:47:b7:9c
Mon Jun 10 11:29:19 2019 user.notice DHCP: cmd:add mac:d0:d2:b0:47:b7:9c ip:100.64.252.120 hostname:THX
Mon Jun 10 11:32:10 2019 daemon.notice hostapd: wlan0-1: AP-STA-DISCONNECTED d0:d2:b0:47:b7:9c
Mon Jun 10 11:32:10 2019 daemon.info hostapd: wlan0-1: STA d0:d2:b0:47:b7:9c IEEE 802.11: disassociated
Mon Jun 10 11:32:11 2019 daemon.info hostapd: wlan0-1: STA d0:d2:b0:47:b7:9c IEEE 802.11: deauthenticated due to inactivity (timer DEAUTH/REMOVE)
Mon Jun 10 11:36:50 2019 user.notice DHCP: cmd:del mac:d0:d2:b0:47:b7:9c ip:100.64.252.120 hostname:THX
Mon Jun 10 12:12:21 2019 user.notice BMXD-SCRIPT[24225]: remove GATEWAY (del)
Mon Jun 10 12:12:21 2019 user.notice BMXD-SCRIPT[24225]: ssid: FF no-inet [1681]
Mon Jun 10 12:12:22 2019 daemon.err bmx[4110]: INFO del_dev_tun(): closing gwc_cleanup tunnel bat0 ip 10.200.6.152
Mon Jun 10 12:12:22 2019 daemon.err bmx[4110]: WARN critical system time drift detected: ++ca 0 s, 448266 us! Correcting reference!
Mon Jun 10 12:12:22 2019 daemon.err bmx[4110]: INFO using new default tunnel to GW 10.200.0.5 (gw_flags: 63, packet_count: 99, gw_product: 0)
Mon Jun 10 12:12:22 2019 user.notice BMXD-SCRIPT[24331]: nameserver 10.200.0.5
Mon Jun 10 12:12:22 2019 user.notice ddmesh: network-hotplug net: [bat0] remove type:
Mon Jun 10 12:12:23 2019 user.notice ddmesh: network-hotplug net: [bat0] add type:
Mon Jun 10 12:12:23 2019 user.notice BMXD-SCRIPT[24331]: ssid: Freifunk Hotspot [1681]
Mon Jun 10 12:12:24 2019 daemon.err bmx[4110]: WARN critical system time drift detected: ++ca 0 s, 543842 us! Correcting reference!
Mon Jun 10 12:12:27 2019 user.notice BMXD-SCRIPT[24453]: remove GATEWAY (del)
Mon Jun 10 12:12:27 2019 user.notice BMXD-SCRIPT[24453]: ssid: FF no-inet [1681]
Mon Jun 10 12:12:28 2019 daemon.err bmx[4110]: INFO del_dev_tun(): closing gwc_cleanup tunnel bat0 ip 10.200.6.152
Mon Jun 10 12:12:28 2019 daemon.err bmx[4110]: WARN critical system time drift detected: ++ca 0 s, 392471 us! Correcting reference!
Mon Jun 10 12:12:28 2019 daemon.err bmx[4110]: INFO Preferred gateway found: 10.200.200.2 (gw_flags: 63, packet_count: 3, ws: 100, gw_product: 0)
Mon Jun 10 12:12:28 2019 daemon.err bmx[4110]: INFO using new default tunnel to GW 10.200.200.2 (gw_flags: 63, packet_count: 98, gw_product: 0)
Mon Jun 10 12:12:28 2019 user.notice BMXD-SCRIPT[24558]: nameserver 10.200.200.2
Mon Jun 10 12:12:28 2019 user.notice ddmesh: network-hotplug net: [bat0] remove type:
Mon Jun 10 12:12:28 2019 user.notice ddmesh: network-hotplug net: [bat0] add type:
Mon Jun 10 12:12:29 2019 user.notice BMXD-SCRIPT[24558]: ssid: Freifunk Hotspot [1681]
Mon Jun 10 12:12:30 2019 daemon.err bmx[4110]: WARN critical system time drift detected: ++ca 0 s, 535554 us! Correcting reference!
Mon Jun 10 12:26:49 2019 daemon.err bmx[4110]: WARN validation: no id4him for primary originator 0.75.193.64. init_pifnb_node() ret=0

Ich bin ratlos, was kann ich tun?

Hi,

in den logs ist nichts auffällig. was ich sehe, ist dass der router ab und zu sein gateway wechselt.
Die statistic selbst, zeigt erstmal, dass soweit keine längeren ausfälle vorhanden sind. allerdings ist die auflösung bei den daten immer nur 5min.
Was du machen kannst, ist, ein anderes bevorzugtes gateway auszuwählen und zwar eines, wo du
mit dem backbone direkt verbunden bist. aktuell hast du 10.200.200.2 eingestellt und damit müssen die internet daten über ein server geleitet werden, der bereits auch schon selbst ein internet gw ist.
das könnten die abbrüche erklären, da verbindung bis da hin schlecher ist.

wähle ein gateway aus, wo die verbidnungsqualität mind grün ist.

Die verbindung zu deinem aktuellen GW ist „62“. wenn du entweder 10.200.0.112 oder 10.200.1.1 nutzt, sollte die verbindung besser sein.

vg
stephan

1 „Gefällt mir“

ist jetzt eingestellt, schauen wir mal was die nächsten 24 Stunden bringen.

Vielen Dank.

Mir war nicht klar, dass Ath7k/TP-Link-Router (?) genauso schlimm driften wie Raspberries.
Aber dass BMX das selbst fixt und (so wie ich verstehe) dafür keinen separaten NTP-client braucht finde ich toll. (oder umgekehrt: In Batman Adv ist so viel drin, aber das fehlt)

1 „Gefällt mir“

Danke, ich habe den NTP-Client auf die Gateway IP-Adresse eingestellt.
(eine FRITZ!Box mit NTP-Server)

uci set system.ntp.server='172.17.17.1'
uci commit system

Die letzten 12 Stunden hatte ich keine Ausfälle.

VG Thomas

1 „Gefällt mir“

In der aktuelle Firmware 5.0.5 verweisen die ntp Einträge noch auch die „lede“ server, das scheint das Problem zu sein. In der aktuellen Testfirmware 6.0.6 nennen sie sich wieder openwrt.

VG