From mboxrd@z Thu Jan 1 00:00:00 1970 Return-Path: Received: from mout.gmx.net (mout.gmx.net [212.227.17.20]) (using TLSv1 with cipher DHE-RSA-AES256-SHA (256/256 bits)) (Client CN "mout.gmx.net", Issuer "TeleSec ServerPass DE-1" (verified OK)) by huchra.bufferbloat.net (Postfix) with ESMTPS id 9734621F33C for ; Tue, 3 Mar 2015 13:35:45 -0800 (PST) Received: from hms-beagle.home.lan ([217.247.219.72]) by mail.gmx.com (mrgmx103) with ESMTPSA (Nemesis) id 0MKYpv-1YRXZ3063r-001xfs; Tue, 03 Mar 2015 22:35:42 +0100 Content-Type: multipart/mixed; boundary="Apple-Mail=_627892D9-234B-45F9-BE26-F2CEB4598B79" Mime-Version: 1.0 (Mac OS X Mail 7.3 \(1878.6\)) From: Sebastian Moeller In-Reply-To: Date: Tue, 3 Mar 2015 22:35:39 +0100 Message-Id: <37283C9D-E6A5-4C2A-9079-FCC9EACDED83@gmx.de> References: <54E0AFB2.5040806@gmail.com> <87vbj3i5hz.fsf@toke.dk> <87r3tri2x3.fsf@toke.dk> <54E0CA64.7000101@gmail.com> <33C677EE-26CD-4F46-89A1-3E47702AEA5E@gmx.de> To: Alan Jenkins X-Mailer: Apple Mail (2.1878.6) X-Provags-ID: V03:K0:skBr+fj77QReOjJsnySvoYCvC/9o8i8oZcV/BxjbBKWV/wuLUFv rsouCc6mRCgQG6Y/jzJtnIEAk2xf4/d4AGpTIEpfTOzOm8Q6OsDwBK00DLxh82ClYN+l519 UBb5OfBiSmbz+VbYXI4b+vkk3QT6CnfxcnE+Lm5f0xQj+uvEGlZWMWb2A4D4oOWieO03dJw 4WWgCN4KfWN4qPmeH0Gqg== X-UI-Out-Filterresults: notjunk:1; Cc: cerowrt-devel@lists.bufferbloat.net Subject: Re: [Cerowrt-devel] [sqm-scripts] not started at boot? X-BeenThere: cerowrt-devel@lists.bufferbloat.net X-Mailman-Version: 2.1.13 Precedence: list List-Id: Development issues regarding the cerowrt test router project List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Tue, 03 Mar 2015 21:36:14 -0000 --Apple-Mail=_627892D9-234B-45F9-BE26-F2CEB4598B79 Content-Transfer-Encoding: quoted-printable Content-Type: text/plain; charset=windows-1252 Hi Alan, On Mar 3, 2015, at 22:23 , Alan Jenkins = wrote: >=20 > On 3 March 2015 at 17:06, Sebastian Moeller wrote: > Hi Alan, >=20 > I would be delighted if you could try the etc/hotplug.d/iface/11-sqm = and the updated usr/lib/sqm/run.sh from the attached files (I am not = sure about the root to the files in the attached folder, but you will = figure it out, I am sure ;) ). This should fix the reconnect when pop = interface transiently go away issue. I am not sure whether this fixes = the startup after reboot issue, so please feel free to test this as = well. >=20 > Best Regards > Sebastian >=20 > Thanks Sebastian! >=20 > The scripts work for reboot & manual ifdown/ifup. So I'd love to see = this fix in openwrt CC. Excellent, now we have positive results from CC (you are running = CC I believe) and cerowrt. >=20 > It still logs errors. =20 In the boot log? I tend to consider these not as errors but as = valid information; as long as sqm-scripts is still being tested I want = it to be verbose, so I have an easier time actually helping people=85 = But once we agree it is stable I will try to make it a bit more quiet=85 > Probably only from running the initscript first, it was just so noisy = I thought it was still happening for every interface that came up :). = I used `uci set system.@system[0].log_size=3D200; uci commit` to prevent = log overflow. Now that is useful, I always thought that this is a kernel = default and can not be changed; so I learned something useful. But I = take it you do not like to learn too intimate SQM details in your log = files=85 Let=92 revisit that once all real issues are solved, please. Best Regards Sebastian >=20 > Full boot log attached. >=20 > Warm regards > Alan >=20 --Apple-Mail=_627892D9-234B-45F9-BE26-F2CEB4598B79 Content-Disposition: attachment; filename=log.txt Content-Type: text/plain; name="log.txt" Content-Transfer-Encoding: quoted-printable Tue Mar 3 20:15:21 2015 kern.notice kernel: [ 0.000000] Linux = version 3.10.49 (bb@builder1) (gcc version 4.8.3 (OpenWrt/Linaro GCC = 4.8-2014.04 r42625) ) #3 Wed Oct 1 14:00:51 CEST 2014 Tue Mar 3 20:15:21 2015 kern.debug kernel: [ 0.000000] MyLoader: = sysp=3Daaaa5554, boardp=3Daaaa5554, parts=3Daaaa5554 Tue Mar 3 20:15:21 2015 kern.info kernel: [ 0.000000] bootconsole = [early0] enabled Tue Mar 3 20:15:21 2015 kern.info kernel: [ 0.000000] CPU revision = is: 00019374 (MIPS 24Kc) Tue Mar 3 20:15:21 2015 kern.info kernel: [ 0.000000] SoC: Atheros = AR7161 rev 2 Tue Mar 3 20:15:21 2015 kern.info kernel: [ 0.000000] Clocks: = CPU:680.000MHz, DDR:340.000MHz, AHB:170.000MHz, Ref:40.000MHz Tue Mar 3 20:15:21 2015 kern.info kernel: [ 0.000000] Determined = physical RAM map: Tue Mar 3 20:15:21 2015 kern.info kernel: [ 0.000000] memory: = 08000000 @ 00000000 (usable) Tue Mar 3 20:15:21 2015 kern.info kernel: [ 0.000000] Initrd not = found or empty - disabling initrd Tue Mar 3 20:15:21 2015 kern.warn kernel: [ 0.000000] Zone ranges: Tue Mar 3 20:15:21 2015 kern.warn kernel: [ 0.000000] Normal = [mem 0x00000000-0x07ffffff] Tue Mar 3 20:15:21 2015 kern.warn kernel: [ 0.000000] Movable zone = start for each node Tue Mar 3 20:15:21 2015 kern.warn kernel: [ 0.000000] Early memory = node ranges Tue Mar 3 20:15:21 2015 kern.warn kernel: [ 0.000000] node 0: = [mem 0x00000000-0x07ffffff] Tue Mar 3 20:15:21 2015 kern.debug kernel: [ 0.000000] On node 0 = totalpages: 32768 Tue Mar 3 20:15:21 2015 kern.debug kernel: [ 0.000000] = free_area_init_node: node 0, pgdat 80334b60, node_mem_map 81000000 Tue Mar 3 20:15:21 2015 kern.debug kernel: [ 0.000000] Normal = zone: 256 pages used for memmap Tue Mar 3 20:15:21 2015 kern.debug kernel: [ 0.000000] Normal = zone: 0 pages reserved Tue Mar 3 20:15:21 2015 kern.debug kernel: [ 0.000000] Normal = zone: 32768 pages, LIFO batch:7 Tue Mar 3 20:15:21 2015 kern.warn kernel: [ 0.000000] Primary = instruction cache 64kB, VIPT, 4-way, linesize 32 bytes. Tue Mar 3 20:15:21 2015 kern.warn kernel: [ 0.000000] Primary data = cache 32kB, 4-way, VIPT, cache aliases, linesize 32 bytes Tue Mar 3 20:15:21 2015 kern.debug kernel: [ 0.000000] pcpu-alloc: = s0 r0 d32768 u32768 alloc=3D1*32768 Tue Mar 3 20:15:21 2015 kern.debug kernel: [ 0.000000] pcpu-alloc: = [0] 0=20 Tue Mar 3 20:15:21 2015 kern.warn kernel: [ 0.000000] Built 1 = zonelists in Zone order, mobility grouping on. Total pages: 32512 Tue Mar 3 20:15:21 2015 kern.notice kernel: [ 0.000000] Kernel = command line: board=3DWNDR3700 console=3DttyS0,115200 = mtdparts=3Dspi0.0:320k(u-boot)ro,128k(u-boot-env)ro,15872k(firmware),64k(a= rt)ro rootfstype=3Dsquashfs,jffs2 noinitrd Tue Mar 3 20:15:21 2015 kern.info kernel: [ 0.000000] PID hash table = entries: 512 (order: -1, 2048 bytes) Tue Mar 3 20:15:21 2015 kern.info kernel: [ 0.000000] Dentry cache = hash table entries: 16384 (order: 4, 65536 bytes) Tue Mar 3 20:15:21 2015 kern.info kernel: [ 0.000000] Inode-cache = hash table entries: 8192 (order: 3, 32768 bytes) Tue Mar 3 20:15:21 2015 kern.info kernel: [ 0.000000] Writing ErrCtl = register=3D00000000 Tue Mar 3 20:15:21 2015 kern.info kernel: [ 0.000000] Readback = ErrCtl register=3D00000000 Tue Mar 3 20:15:21 2015 kern.info kernel: [ 0.000000] Memory: = 126052k/131072k available (2369k kernel code, 5020k reserved, 621k data, = 272k init, 0k highmem) Tue Mar 3 20:15:21 2015 kern.info kernel: [ 0.000000] SLUB: = HWalign=3D32, Order=3D0-3, MinObjects=3D0, CPUs=3D1, Nodes=3D1 Tue Mar 3 20:15:21 2015 kern.info kernel: [ 0.000000] NR_IRQS:51 Tue Mar 3 20:15:21 2015 kern.info kernel: [ 0.060000] Calibrating = delay loop... 452.19 BogoMIPS (lpj=3D2260992) Tue Mar 3 20:15:21 2015 kern.info kernel: [ 0.060000] pid_max: = default: 32768 minimum: 301 Tue Mar 3 20:15:21 2015 kern.info kernel: [ 0.060000] Mount-cache = hash table entries: 512 Tue Mar 3 20:15:21 2015 kern.info kernel: [ 0.070000] NET: = Registered protocol family 16 Tue Mar 3 20:15:21 2015 kern.info kernel: [ 0.070000] MIPS: machine = is NETGEAR WNDR3700/WNDR3800/WNDRMAC Tue Mar 3 20:15:21 2015 kern.warn kernel: [ 2.680000] registering = PCI controller with io_map_base unset Tue Mar 3 20:15:21 2015 kern.info kernel: [ 2.690000] bio: create = slab at 0 Tue Mar 3 20:15:21 2015 kern.info kernel: [ 2.690000] PCI host = bridge to bus 0000:00 Tue Mar 3 20:15:21 2015 kern.info kernel: [ 2.700000] pci_bus = 0000:00: root bus resource [mem 0x10000000-0x16ffffff] Tue Mar 3 20:15:21 2015 kern.info kernel: [ 2.700000] pci_bus = 0000:00: root bus resource [io 0x0000] Tue Mar 3 20:15:21 2015 kern.info kernel: [ 2.710000] pci_bus = 0000:00: No busn resource found for root bus, will use [bus 00-ff] Tue Mar 3 20:15:21 2015 kern.debug kernel: [ 2.710000] pci = 0000:00:11.0: [168c:ff1d] type 00 class 0x020000 Tue Mar 3 20:15:21 2015 kern.info kernel: [ 2.710000] pci = 0000:00:11.0: fixup device configuration Tue Mar 3 20:15:21 2015 kern.debug kernel: [ 2.720000] pci = 0000:00:11.0: reg 10: [mem 0x00000000-0x0000ffff] Tue Mar 3 20:15:21 2015 kern.debug kernel: [ 2.720000] pci = 0000:00:11.0: PME# supported from D0 D3hot Tue Mar 3 20:15:21 2015 kern.debug kernel: [ 2.720000] pci = 0000:00:12.0: [168c:ff1d] type 00 class 0x020000 Tue Mar 3 20:15:21 2015 kern.info kernel: [ 2.720000] pci = 0000:00:12.0: fixup device configuration Tue Mar 3 20:15:21 2015 kern.debug kernel: [ 2.720000] pci = 0000:00:12.0: reg 10: [mem 0x00000000-0x0000ffff] Tue Mar 3 20:15:21 2015 kern.debug kernel: [ 2.720000] pci = 0000:00:12.0: PME# supported from D0 D3hot Tue Mar 3 20:15:21 2015 kern.debug kernel: [ 2.720000] pci_bus = 0000:00: busn_res: [bus 00-ff] end is updated to 00 Tue Mar 3 20:15:21 2015 kern.info kernel: [ 2.720000] pci = 0000:00:11.0: BAR 0: assigned [mem 0x10000000-0x1000ffff] Tue Mar 3 20:15:21 2015 kern.info kernel: [ 2.730000] pci = 0000:00:12.0: BAR 0: assigned [mem 0x10010000-0x1001ffff] Tue Mar 3 20:15:21 2015 kern.info kernel: [ 2.730000] pci = 0000:00:11.0: using irq 40 for pin 1 Tue Mar 3 20:15:21 2015 kern.info kernel: [ 2.740000] pci = 0000:00:12.0: using irq 41 for pin 1 Tue Mar 3 20:15:21 2015 kern.info kernel: [ 2.740000] Switching to = clocksource MIPS Tue Mar 3 20:15:21 2015 kern.info kernel: [ 2.750000] NET: = Registered protocol family 2 Tue Mar 3 20:15:21 2015 kern.info kernel: [ 2.750000] TCP = established hash table entries: 1024 (order: 1, 8192 bytes) Tue Mar 3 20:15:21 2015 kern.info kernel: [ 2.750000] TCP bind hash = table entries: 1024 (order: 0, 4096 bytes) Tue Mar 3 20:15:21 2015 kern.info kernel: [ 2.760000] TCP: Hash = tables configured (established 1024 bind 1024) Tue Mar 3 20:15:21 2015 kern.info kernel: [ 2.760000] TCP: reno = registered Tue Mar 3 20:15:21 2015 kern.info kernel: [ 2.770000] UDP hash table = entries: 256 (order: 0, 4096 bytes) Tue Mar 3 20:15:21 2015 kern.info kernel: [ 2.770000] UDP-Lite hash = table entries: 256 (order: 0, 4096 bytes) Tue Mar 3 20:15:21 2015 kern.info kernel: [ 2.780000] NET: = Registered protocol family 1 Tue Mar 3 20:15:21 2015 kern.debug kernel: [ 2.780000] PCI: CLS 0 = bytes, default 32 Tue Mar 3 20:15:21 2015 kern.info kernel: [ 2.800000] squashfs: = version 4.0 (2009/01/31) Phillip Lougher Tue Mar 3 20:15:21 2015 kern.info kernel: [ 2.800000] jffs2: version = 2.2 (NAND) (SUMMARY) (LZMA) (RTIME) (CMODE_PRIORITY) (c) 2001-2006 Red = Hat, Inc. Tue Mar 3 20:15:21 2015 kern.info kernel: [ 2.810000] msgmni has = been set to 246 Tue Mar 3 20:15:21 2015 kern.info kernel: [ 2.820000] io scheduler = noop registered Tue Mar 3 20:15:21 2015 kern.info kernel: [ 2.820000] io scheduler = deadline registered (default) Tue Mar 3 20:15:21 2015 kern.info kernel: [ 2.830000] Serial: = 8250/16550 driver, 16 ports, IRQ sharing enabled Tue Mar 3 20:15:21 2015 kern.info kernel: [ 2.860000] serial8250.0: = ttyS0 at MMIO 0x18020000 (irq =3D 11) is a 16550A Tue Mar 3 20:15:21 2015 kern.info kernel: [ 2.860000] console = [ttyS0] enabled, bootconsole disabled Tue Mar 3 20:15:21 2015 kern.info kernel: [ 2.880000] ath79-spi = ath79-spi: master is unqueued, this is deprecated Tue Mar 3 20:15:21 2015 kern.warn kernel: [ 2.880000] m25p80 spi0.0: = found mx25l12805d, expected m25p80 Tue Mar 3 20:15:21 2015 kern.info kernel: [ 2.890000] m25p80 spi0.0: = mx25l12805d (16384 Kbytes) Tue Mar 3 20:15:21 2015 kern.notice kernel: [ 2.900000] 4 = cmdlinepart partitions found on MTD device spi0.0 Tue Mar 3 20:15:21 2015 kern.notice kernel: [ 2.900000] Creating 4 = MTD partitions on "spi0.0": Tue Mar 3 20:15:21 2015 kern.notice kernel: [ 2.910000] = 0x000000000000-0x000000050000 : "u-boot" Tue Mar 3 20:15:21 2015 kern.notice kernel: [ 2.910000] = 0x000000050000-0x000000070000 : "u-boot-env" Tue Mar 3 20:15:21 2015 kern.notice kernel: [ 2.920000] = 0x000000070000-0x000000ff0000 : "firmware" Tue Mar 3 20:15:21 2015 kern.notice kernel: [ 2.930000] 2 netgear-fw = partitions found on MTD device firmware Tue Mar 3 20:15:21 2015 kern.notice kernel: [ 2.930000] = 0x000000070000-0x000000182440 : "kernel" Tue Mar 3 20:15:21 2015 kern.warn kernel: [ 2.940000] mtd: partition = "kernel" must either start or end on erase block boundary or be smaller = than an erase block -- forcing read-only Tue Mar 3 20:15:21 2015 kern.notice kernel: [ 2.950000] = 0x000000182440-0x000000ff0000 : "rootfs" Tue Mar 3 20:15:21 2015 kern.warn kernel: [ 2.950000] mtd: partition = "rootfs" must either start or end on erase block boundary or be smaller = than an erase block -- forcing read-only Tue Mar 3 20:15:21 2015 kern.notice kernel: [ 2.970000] mtd: device = 4 (rootfs) set to be root filesystem Tue Mar 3 20:15:21 2015 kern.notice kernel: [ 2.970000] 1 = squashfs-split partitions found on MTD device rootfs Tue Mar 3 20:15:21 2015 kern.notice kernel: [ 2.980000] = 0x0000003b0000-0x000000ff0000 : "rootfs_data" Tue Mar 3 20:15:21 2015 kern.notice kernel: [ 2.990000] = 0x000000ff0000-0x000001000000 : "art" Tue Mar 3 20:15:21 2015 kern.notice kernel: [ 3.000000] Realtek = RTL8366S ethernet switch driver version 0.2.2 Tue Mar 3 20:15:21 2015 kern.info kernel: [ 3.000000] rtl8366s = rtl8366s: using GPIO pins 5 (SDA) and 7 (SCK) Tue Mar 3 20:15:21 2015 kern.info kernel: [ 3.010000] rtl8366s = rtl8366s: RTL8366 ver. 1 chip found Tue Mar 3 20:15:21 2015 kern.info kernel: [ 3.040000] libphy: = rtl8366s: probed Tue Mar 3 20:15:21 2015 kern.info kernel: [ 3.350000] eth0: Atheros = AG71xx at 0xb9000000, irq 4, mode:RGMII Tue Mar 3 20:15:21 2015 kern.info kernel: [ 3.650000] ag71xx = ag71xx.1: connected to PHY at rtl8366s:04 [uid=3D001cc960, = driver=3DGeneric PHY] Tue Mar 3 20:15:21 2015 kern.info kernel: [ 3.660000] eth1: Atheros = AG71xx at 0xba000000, irq 5, mode:RGMII Tue Mar 3 20:15:21 2015 kern.info kernel: [ 3.670000] TCP: cubic = registered Tue Mar 3 20:15:21 2015 kern.info kernel: [ 3.670000] NET: = Registered protocol family 17 Tue Mar 3 20:15:21 2015 kern.notice kernel: [ 3.680000] Bridge = firewalling registered Tue Mar 3 20:15:21 2015 kern.info kernel: [ 3.680000] 8021q: 802.1Q = VLAN Support v1.8 Tue Mar 3 20:15:21 2015 kern.info kernel: [ 3.690000] VFS: Mounted = root (squashfs filesystem) readonly on device 31:4. Tue Mar 3 20:15:21 2015 kern.info kernel: [ 3.700000] Freeing unused = kernel memory: 272K (8034c000 - 80390000) Tue Mar 3 20:15:21 2015 kern.info kernel: [ 5.710000] usbcore: = registered new interface driver usbfs Tue Mar 3 20:15:21 2015 kern.info kernel: [ 5.720000] usbcore: = registered new interface driver hub Tue Mar 3 20:15:21 2015 kern.info kernel: [ 5.720000] usbcore: = registered new device driver usb Tue Mar 3 20:15:21 2015 kern.info kernel: [ 5.730000] ehci_hcd: USB = 2.0 'Enhanced' Host Controller (EHCI) Driver Tue Mar 3 20:15:21 2015 kern.info kernel: [ 5.740000] ehci-platform: = EHCI generic platform driver Tue Mar 3 20:15:21 2015 kern.info kernel: [ 5.750000] ehci-platform = ehci-platform: EHCI Host Controller Tue Mar 3 20:15:21 2015 kern.info kernel: [ 5.750000] ehci-platform = ehci-platform: new USB bus registered, assigned bus number 1 Tue Mar 3 20:15:21 2015 kern.info kernel: [ 5.760000] ehci-platform = ehci-platform: irq 3, io mem 0x1b000000 Tue Mar 3 20:15:21 2015 kern.info kernel: [ 5.790000] ehci-platform = ehci-platform: USB 2.0 started, EHCI 1.00 Tue Mar 3 20:15:21 2015 kern.info kernel: [ 5.790000] hub 1-0:1.0: = USB hub found Tue Mar 3 20:15:21 2015 kern.info kernel: [ 5.800000] hub 1-0:1.0: 2 = ports detected Tue Mar 3 20:15:21 2015 kern.info kernel: [ 5.800000] ohci_hcd: USB = 1.1 'Open' Host Controller (OHCI) Driver Tue Mar 3 20:15:21 2015 kern.info kernel: [ 5.810000] ohci-platform = ohci-platform: Generic Platform OHCI Controller Tue Mar 3 20:15:21 2015 kern.info kernel: [ 5.820000] ohci-platform = ohci-platform: new USB bus registered, assigned bus number 2 Tue Mar 3 20:15:21 2015 kern.info kernel: [ 5.820000] ohci-platform = ohci-platform: irq 14, io mem 0x1c000000 Tue Mar 3 20:15:21 2015 kern.info kernel: [ 5.890000] hub 2-0:1.0: = USB hub found Tue Mar 3 20:15:21 2015 kern.info kernel: [ 5.890000] hub 2-0:1.0: 2 = ports detected Tue Mar 3 20:15:21 2015 kern.info kernel: [ 6.160000] usb 1-1: new = high-speed USB device number 2 using ehci-platform Tue Mar 3 20:15:21 2015 kern.debug kernel: [ 6.260000] ar71xx: = pll_reg 0xb8050010: 0x11110000 Tue Mar 3 20:15:21 2015 kern.info kernel: [ 6.260000] eth0: link up = (1000Mbps/Full duplex) Tue Mar 3 20:15:21 2015 kern.notice kernel: [ 9.550000] jffs2: = notice: (349) jffs2_build_xattr_subsystem: complete building xattr = subsystem, 1 of xdatum (1 unchecked, 0 orphan) and 26 of xref (0 dead, 5 = orphan) found. Tue Mar 3 20:15:21 2015 kern.info kernel: [ 9.600000] eth0: link = down Tue Mar 3 20:15:21 2015 kern.info kernel: [ 11.310000] NET: = Registered protocol family 10 Tue Mar 3 20:15:21 2015 kern.info kernel: [ 11.320000] nf_conntrack = version 0.5.0 (1973 buckets, 7892 max) Tue Mar 3 20:15:21 2015 kern.info kernel: [ 11.340000] ip6_tables: = (C) 2000-2006 Netfilter Core Team Tue Mar 3 20:15:21 2015 kern.info kernel: [ 11.380000] u32 classifier Tue Mar 3 20:15:21 2015 kern.info kernel: [ 11.380000] input = device check on Tue Mar 3 20:15:21 2015 kern.info kernel: [ 11.390000] Actions = configured Tue Mar 3 20:15:21 2015 kern.info kernel: [ 11.400000] = Mirror/redirect action on Tue Mar 3 20:15:21 2015 kern.warn kernel: [ 11.400000] Failed to load = ipt action Tue Mar 3 20:15:21 2015 kern.info kernel: [ 11.440000] netem: version = 1.3 Tue Mar 3 20:15:21 2015 kern.info kernel: [ 11.460000] Loading = modules backported from Linux version master-2014-05-22-0-gf2032ea Tue Mar 3 20:15:21 2015 kern.info kernel: [ 11.470000] Backport = generated by backports.git backports-20140320-37-g5c33da0 Tue Mar 3 20:15:21 2015 kern.info kernel: [ 11.480000] ip_tables: (C) = 2000-2006 Netfilter Core Team Tue Mar 3 20:15:21 2015 kern.info kernel: [ 11.570000] xt_time: = kernel timezone is -0000 Tue Mar 3 20:15:21 2015 kern.info kernel: [ 11.610000] cfg80211: = Calling CRDA to update world regulatory domain Tue Mar 3 20:15:21 2015 kern.info kernel: [ 11.630000] cfg80211: = World regulatory domain updated: Tue Mar 3 20:15:21 2015 kern.info kernel: [ 11.640000] cfg80211: DFS = Master region: unset Tue Mar 3 20:15:21 2015 kern.info kernel: [ 11.640000] cfg80211: = (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), = (dfs_cac_time) Tue Mar 3 20:15:21 2015 kern.info kernel: [ 11.650000] cfg80211: = (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A) Tue Mar 3 20:15:21 2015 kern.info kernel: [ 11.660000] cfg80211: = (2457000 KHz - 2482000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A) Tue Mar 3 20:15:21 2015 kern.info kernel: [ 11.670000] cfg80211: = (2474000 KHz - 2494000 KHz @ 20000 KHz), (N/A, 2000 mBm), (N/A) Tue Mar 3 20:15:21 2015 kern.info kernel: [ 11.670000] cfg80211: = (5170000 KHz - 5250000 KHz @ 160000 KHz), (N/A, 2000 mBm), (N/A) Tue Mar 3 20:15:21 2015 kern.info kernel: [ 11.680000] cfg80211: = (5250000 KHz - 5330000 KHz @ 160000 KHz), (N/A, 2000 mBm), (0 s) Tue Mar 3 20:15:21 2015 kern.info kernel: [ 11.690000] cfg80211: = (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2000 mBm), (0 s) Tue Mar 3 20:15:21 2015 kern.info kernel: [ 11.700000] cfg80211: = (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 2000 mBm), (N/A) Tue Mar 3 20:15:21 2015 kern.info kernel: [ 11.710000] cfg80211: = (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 0 mBm), (N/A) Tue Mar 3 20:15:21 2015 kern.info kernel: [ 11.760000] PPP generic = driver version 2.4.2 Tue Mar 3 20:15:21 2015 kern.info kernel: [ 11.780000] NET: = Registered protocol family 24 Tue Mar 3 20:15:21 2015 kern.warn kernel: [ 11.830000] PCI: Enabling = device 0000:00:11.0 (0000 -> 0002) Tue Mar 3 20:15:21 2015 kern.debug kernel: [ 11.840000] ath: EEPROM = regdomain: 0x0 Tue Mar 3 20:15:21 2015 kern.debug kernel: [ 11.840000] ath: EEPROM = indicates default country code should be used Tue Mar 3 20:15:21 2015 kern.debug kernel: [ 11.840000] ath: doing = EEPROM country->regdmn map search Tue Mar 3 20:15:21 2015 kern.debug kernel: [ 11.840000] ath: country = maps to regdmn code: 0x3a Tue Mar 3 20:15:21 2015 kern.debug kernel: [ 11.840000] ath: Country = alpha2 being used: US Tue Mar 3 20:15:21 2015 kern.debug kernel: [ 11.840000] ath: Regpair = used: 0x3a Tue Mar 3 20:15:21 2015 kern.debug kernel: [ 11.860000] ieee80211 = phy0: Selected rate control algorithm 'minstrel_ht' Tue Mar 3 20:15:21 2015 kern.info kernel: [ 11.870000] cfg80211: = Calling CRDA for country: US Tue Mar 3 20:15:21 2015 kern.info kernel: [ 11.870000] cfg80211: = Regulatory domain changed to country: US Tue Mar 3 20:15:21 2015 kern.info kernel: [ 11.880000] cfg80211: DFS = Master region: FCC Tue Mar 3 20:15:21 2015 kern.info kernel: [ 11.880000] cfg80211: = (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), = (dfs_cac_time) Tue Mar 3 20:15:21 2015 kern.info kernel: [ 11.890000] cfg80211: = (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 3000 mBm), (N/A) Tue Mar 3 20:15:21 2015 kern.info kernel: [ 11.900000] cfg80211: = (5170000 KHz - 5250000 KHz @ 80000 KHz), (N/A, 1700 mBm), (N/A) Tue Mar 3 20:15:21 2015 kern.info kernel: [ 11.910000] cfg80211: = (5250000 KHz - 5330000 KHz @ 80000 KHz), (N/A, 2300 mBm), (0 s) Tue Mar 3 20:15:21 2015 kern.info kernel: [ 11.920000] cfg80211: = (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 3000 mBm), (N/A) Tue Mar 3 20:15:21 2015 kern.info kernel: [ 11.930000] cfg80211: = (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 4000 mBm), (N/A) Tue Mar 3 20:15:21 2015 kern.info kernel: [ 11.930000] ieee80211 = phy0: Atheros AR9280 Rev:2 mem=3D0xb0000000, irq=3D40 Tue Mar 3 20:15:21 2015 kern.warn kernel: [ 11.960000] PCI: Enabling = device 0000:00:12.0 (0000 -> 0002) Tue Mar 3 20:15:21 2015 kern.err kernel: [ 11.970000] ath: phy1: = eeprom contains invalid mac address: ff:ff:ff:ff:ff:ff Tue Mar 3 20:15:21 2015 kern.err kernel: [ 11.970000] ath: phy1: = random mac address will be used: 52:7e:58:c2:81:89 Tue Mar 3 20:15:21 2015 kern.debug kernel: [ 11.980000] ath: EEPROM = regdomain: 0x0 Tue Mar 3 20:15:21 2015 kern.debug kernel: [ 11.980000] ath: EEPROM = indicates default country code should be used Tue Mar 3 20:15:21 2015 kern.debug kernel: [ 11.980000] ath: doing = EEPROM country->regdmn map search Tue Mar 3 20:15:21 2015 kern.debug kernel: [ 11.980000] ath: country = maps to regdmn code: 0x3a Tue Mar 3 20:15:21 2015 kern.debug kernel: [ 11.980000] ath: Country = alpha2 being used: US Tue Mar 3 20:15:21 2015 kern.debug kernel: [ 11.980000] ath: Regpair = used: 0x3a Tue Mar 3 20:15:21 2015 kern.debug kernel: [ 12.040000] ieee80211 = phy1: Selected rate control algorithm 'minstrel_ht' Tue Mar 3 20:15:21 2015 kern.info kernel: [ 12.040000] ieee80211 = phy1: Atheros AR9280 Rev:2 mem=3D0xb0010000, irq=3D41 Tue Mar 3 20:15:23 2015 authpriv.info dropbear[968]: Not backgrounding Tue Mar 3 20:15:24 2015 user.emerg syslog: SQM: marching on... Tue Mar 3 20:15:24 2015 user.notice SQM: marching on... Tue Mar 3 20:15:25 2015 user.emerg syslog: SQM: /usr/lib/sqm/run.sh = Queue Setup Script: /usr/lib/sqm/simple.qos Tue Mar 3 20:15:25 2015 user.notice SQM: /usr/lib/sqm/run.sh Queue = Setup Script: /usr/lib/sqm/simple.qos Tue Mar 3 20:15:25 2015 user.emerg syslog: Cannot find device = "pppoe-wan" Tue Mar 3 20:15:25 2015 user.emerg syslog: SQM: ifb associated with = interface pppoe-wan: Tue Mar 3 20:15:25 2015 user.notice SQM: ifb associated with interface = pppoe-wan: Tue Mar 3 20:15:25 2015 user.emerg syslog: SQM: trying to create new = IFB: ifb4pppoe-wan Tue Mar 3 20:15:25 2015 user.notice SQM: trying to create new IFB: = ifb4pppoe-wan Tue Mar 3 20:15:25 2015 user.emerg syslog: Failed to find sch_fq_codel. = Maybe it is a built in module ? Tue Mar 3 20:15:26 2015 kern.debug kernel: [ 19.050000] ar71xx: = pll_reg 0xb8050010: 0x11110000 Tue Mar 3 20:15:26 2015 kern.info kernel: [ 19.050000] eth0: link up = (1000Mbps/Full duplex) Tue Mar 3 20:15:26 2015 kern.info kernel: [ 19.050000] device eth0.1 = entered promiscuous mode Tue Mar 3 20:15:26 2015 kern.info kernel: [ 19.060000] device eth0 = entered promiscuous mode Tue Mar 3 20:15:26 2015 kern.info kernel: [ 19.080000] br-lan: port = 1(eth0.1) entered forwarding state Tue Mar 3 20:15:26 2015 kern.info kernel: [ 19.080000] br-lan: port = 1(eth0.1) entered forwarding state Tue Mar 3 20:15:26 2015 daemon.notice netifd: Interface 'lan' is = enabled Tue Mar 3 20:15:26 2015 daemon.notice netifd: Interface 'lan' is = setting up now Tue Mar 3 20:15:26 2015 daemon.notice netifd: Interface 'lan' is now up Tue Mar 3 20:15:26 2015 daemon.notice netifd: Interface 'loopback' is = enabled Tue Mar 3 20:15:26 2015 daemon.notice netifd: Interface 'loopback' is = setting up now Tue Mar 3 20:15:26 2015 daemon.notice netifd: Interface 'loopback' is = now up Tue Mar 3 20:15:26 2015 kern.info kernel: [ 19.120000] IPv6: = ADDRCONF(NETDEV_UP): eth1: link is not ready Tue Mar 3 20:15:26 2015 daemon.notice netifd: Interface 'wan' is = enabled Tue Mar 3 20:15:26 2015 daemon.notice netifd: Interface 'modem' is = enabled Tue Mar 3 20:15:26 2015 daemon.notice netifd: Network device 'eth0' = link is up Tue Mar 3 20:15:26 2015 daemon.notice netifd: VLAN 'eth0.1' link is up Tue Mar 3 20:15:26 2015 daemon.notice netifd: VLAN 'eth0.1' link is = down Tue Mar 3 20:15:26 2015 daemon.notice netifd: VLAN 'eth0.1' link is up Tue Mar 3 20:15:26 2015 daemon.notice netifd: Bridge 'br-lan' link is = up Tue Mar 3 20:15:26 2015 daemon.notice netifd: Interface 'lan' has link = connectivity=20 Tue Mar 3 20:15:26 2015 daemon.notice netifd: Network device 'lo' link = is up Tue Mar 3 20:15:26 2015 daemon.notice netifd: Interface 'loopback' has = link connectivity=20 Tue Mar 3 20:15:27 2015 kern.info kernel: [ 19.870000] cfg80211: = Calling CRDA for country: GB Tue Mar 3 20:15:27 2015 kern.info kernel: [ 19.870000] cfg80211: = Regulatory domain changed to country: GB Tue Mar 3 20:15:27 2015 kern.info kernel: [ 19.880000] cfg80211: DFS = Master region: ETSI Tue Mar 3 20:15:27 2015 kern.info kernel: [ 19.880000] cfg80211: = (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), = (dfs_cac_time) Tue Mar 3 20:15:27 2015 kern.info kernel: [ 19.890000] cfg80211: = (2402000 KHz - 2482000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A) Tue Mar 3 20:15:27 2015 kern.info kernel: [ 19.900000] cfg80211: = (5170000 KHz - 5250000 KHz @ 80000 KHz), (N/A, 2000 mBm), (N/A) Tue Mar 3 20:15:27 2015 kern.info kernel: [ 19.910000] cfg80211: = (5250000 KHz - 5330000 KHz @ 80000 KHz), (N/A, 2000 mBm), (0 s) Tue Mar 3 20:15:27 2015 kern.info kernel: [ 19.920000] cfg80211: = (5490000 KHz - 5710000 KHz @ 80000 KHz), (N/A, 2700 mBm), (0 s) Tue Mar 3 20:15:27 2015 kern.info kernel: [ 19.920000] cfg80211: = (57240000 KHz - 65880000 KHz @ 2160000 KHz), (N/A, 4000 mBm), (N/A) Tue Mar 3 20:15:27 2015 user.notice SQM: hotplug on interface: br-lan = action: ifup Tue Mar 3 20:15:27 2015 user.notice SQM: Re/starting sqm on interface = br-lan Tue Mar 3 20:15:27 2015 user.emerg syslog: SQM: Squashing = differentiated services code points (DSCP) from ingress. Tue Mar 3 20:15:27 2015 user.notice SQM: Squashing differentiated = services code points (DSCP) from ingress. Tue Mar 3 20:15:27 2015 user.notice firewall: Reloading firewall due to = ifup of lan (br-lan) Tue Mar 3 20:15:27 2015 user.emerg syslog: cat: can't open = '/sys/class/net/pppoe-wan/mtu': No such file or directory Tue Mar 3 20:15:27 2015 user.emerg syslog: SQM: STAB: stab mtu 2047 = tsize 512 mpu 0 overhead 40 linklayer atm Tue Mar 3 20:15:27 2015 user.notice SQM: STAB: stab mtu 2047 tsize 512 = mpu 0 overhead 40 linklayer atm Tue Mar 3 20:15:27 2015 user.emerg syslog: Cannot find device = "pppoe-wan" Tue Mar 3 20:15:27 2015 user.emerg syslog: Cannot find device = "pppoe-wan" Tue Mar 3 20:15:27 2015 user.emerg syslog: Cannot find device = "pppoe-wan" Tue Mar 3 20:15:27 2015 user.emerg syslog: Cannot find device = "pppoe-wan" Tue Mar 3 20:15:27 2015 user.emerg syslog: Cannot find device = "pppoe-wan" Tue Mar 3 20:15:27 2015 user.emerg syslog: Cannot find device = "pppoe-wan" Tue Mar 3 20:15:27 2015 user.emerg syslog: SQM: get_limit: CURLIMIT: = 1001 Tue Mar 3 20:15:27 2015 user.notice SQM: get_limit: CURLIMIT: 1001 Tue Mar 3 20:15:27 2015 user.emerg syslog: SQM: cur_target: auto = cur_bandwidth: 850 Tue Mar 3 20:15:27 2015 user.notice SQM: cur_target: auto = cur_bandwidth: 850 Tue Mar 3 20:15:27 2015 user.emerg syslog: Cannot find device = "pppoe-wan" Tue Mar 3 20:15:27 2015 user.emerg syslog: SQM: get_limit: CURLIMIT: = 1001 Tue Mar 3 20:15:27 2015 user.notice SQM: get_limit: CURLIMIT: 1001 Tue Mar 3 20:15:27 2015 user.emerg syslog: SQM: cur_target: auto = cur_bandwidth: 850 Tue Mar 3 20:15:27 2015 user.notice SQM: cur_target: auto = cur_bandwidth: 850 Tue Mar 3 20:15:27 2015 user.emerg syslog: Cannot find device = "pppoe-wan" Tue Mar 3 20:15:27 2015 user.emerg syslog: SQM: get_limit: CURLIMIT: = 1001 Tue Mar 3 20:15:27 2015 user.notice SQM: get_limit: CURLIMIT: 1001 Tue Mar 3 20:15:27 2015 user.emerg syslog: SQM: cur_target: auto = cur_bandwidth: 850 Tue Mar 3 20:15:27 2015 user.notice SQM: cur_target: auto = cur_bandwidth: 850 Tue Mar 3 20:15:28 2015 user.emerg syslog: Cannot find device = "pppoe-wan" Tue Mar 3 20:15:28 2015 user.emerg syslog: Cannot find device = "pppoe-wan" Tue Mar 3 20:15:28 2015 user.emerg syslog: Cannot find device = "pppoe-wan" Tue Mar 3 20:15:28 2015 user.emerg syslog: Cannot find device = "pppoe-wan" Tue Mar 3 20:15:28 2015 kern.info kernel: [ 21.080000] br-lan: port = 1(eth0.1) entered forwarding state Tue Mar 3 20:15:28 2015 user.emerg syslog: Cannot find device = "pppoe-wan" Tue Mar 3 20:15:28 2015 user.emerg syslog: Cannot find device = "pppoe-wan" Tue Mar 3 20:15:28 2015 user.emerg syslog: Cannot find device = "pppoe-wan" Tue Mar 3 20:15:28 2015 user.emerg syslog: Cannot find device = "pppoe-wan" Tue Mar 3 20:15:28 2015 user.emerg syslog: Cannot find device = "pppoe-wan" Tue Mar 3 20:15:28 2015 user.emerg syslog: Cannot find device = "pppoe-wan" Tue Mar 3 20:15:28 2015 user.emerg syslog: Cannot find device = "pppoe-wan" Tue Mar 3 20:15:28 2015 user.emerg syslog: SQM: egress shaping = activated Tue Mar 3 20:15:28 2015 user.notice SQM: egress shaping activated Tue Mar 3 20:15:28 2015 user.emerg syslog: cat: can't open = '/sys/class/net/pppoe-wan/mtu': No such file or directory Tue Mar 3 20:15:28 2015 user.emerg syslog: Cannot find device = "pppoe-wan" Tue Mar 3 20:15:28 2015 user.emerg syslog: SQM: Do not perform DSCP = based filtering on ingress. (1-tier classification) Tue Mar 3 20:15:28 2015 user.notice SQM: Do not perform DSCP based = filtering on ingress. (1-tier classification) Tue Mar 3 20:15:28 2015 user.emerg syslog: SQM: STAB: stab mtu 2047 = tsize 512 mpu 0 overhead 40 linklayer atm Tue Mar 3 20:15:28 2015 user.notice SQM: STAB: stab mtu 2047 tsize 512 = mpu 0 overhead 40 linklayer atm Tue Mar 3 20:15:28 2015 user.emerg syslog: SQM: get_limit: CURLIMIT: = 1001 Tue Mar 3 20:15:28 2015 user.notice SQM: get_limit: CURLIMIT: 1001 Tue Mar 3 20:15:28 2015 user.emerg syslog: SQM: cur_target: auto = cur_bandwidth: 11500 Tue Mar 3 20:15:28 2015 user.notice SQM: cur_target: auto = cur_bandwidth: 11500 Tue Mar 3 20:15:28 2015 user.emerg syslog: Cannot find device = "pppoe-wan" Tue Mar 3 20:15:28 2015 user.emerg syslog: SQM: ingress shaping = activated Tue Mar 3 20:15:28 2015 user.notice SQM: ingress shaping activated Tue Mar 3 20:15:29 2015 kern.info kernel: [ 22.160000] IPv6: = ADDRCONF(NETDEV_UP): wlan0: link is not ready Tue Mar 3 20:15:29 2015 kern.info kernel: [ 22.230000] IPv6: = ADDRCONF(NETDEV_UP): wlan1: link is not ready Tue Mar 3 20:15:29 2015 kern.debug kernel: [ 22.870000] ar71xx: = pll_reg 0xb8050014: 0x1099 Tue Mar 3 20:15:29 2015 kern.info kernel: [ 22.870000] eth1: link up = (100Mbps/Full duplex) Tue Mar 3 20:15:29 2015 kern.info kernel: [ 22.870000] IPv6: = ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready Tue Mar 3 20:15:29 2015 daemon.notice netifd: Network device 'eth1' = link is up Tue Mar 3 20:15:29 2015 daemon.notice netifd: Interface 'wan' has link = connectivity=20 Tue Mar 3 20:15:29 2015 daemon.notice netifd: Interface 'wan' is = setting up now Tue Mar 3 20:15:29 2015 daemon.notice netifd: Interface 'modem' has = link connectivity=20 Tue Mar 3 20:15:29 2015 daemon.notice netifd: Interface 'modem' is = setting up now Tue Mar 3 20:15:30 2015 daemon.notice netifd: modem (1669): udhcpc = (v1.22.1) started Tue Mar 3 20:15:30 2015 daemon.notice netifd: modem (1669): Sending = discover... Tue Mar 3 20:15:30 2015 daemon.notice netifd: modem (1669): Sending = select for 192.168.1.100... Tue Mar 3 20:15:30 2015 daemon.notice netifd: modem (1669): Lease of = 192.168.1.100 obtained, lease time 259200 Tue Mar 3 20:15:30 2015 kern.info kernel: [ 23.610000] IPv6: = ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready Tue Mar 3 20:15:30 2015 kern.info kernel: [ 23.650000] IPv6: = ADDRCONF(NETDEV_UP): wlan0-1: link is not ready Tue Mar 3 20:15:31 2015 daemon.notice netifd: Interface 'modem' is now = up Tue Mar 3 20:15:31 2015 daemon.info pppd[1674]: Plugin rp-pppoe.so = loaded. Tue Mar 3 20:15:31 2015 daemon.info pppd[1674]: RP-PPPoE plugin version = 3.8p compiled against pppd 2.4.7 Tue Mar 3 20:15:31 2015 daemon.notice pppd[1674]: pppd 2.4.7 started by = root, uid 0 Tue Mar 3 20:15:31 2015 daemon.info pppd[1674]: PPP session is 3233 Tue Mar 3 20:15:31 2015 daemon.warn pppd[1674]: Connected to = 00:30:88:1e:48:ad via interface eth1 Tue Mar 3 20:15:31 2015 daemon.info pppd[1674]: Using interface = pppoe-wan Tue Mar 3 20:15:31 2015 daemon.notice pppd[1674]: Connect: pppoe-wan = <--> eth1 Tue Mar 3 20:15:31 2015 daemon.info pppd[1674]: CHAP authentication = succeeded: CHAP authentication success, unit 30516 Tue Mar 3 20:15:31 2015 daemon.notice pppd[1674]: CHAP authentication = succeeded Tue Mar 3 20:15:31 2015 daemon.notice pppd[1674]: peer from calling = number 00:30:88:1E:48:AD authorized Tue Mar 3 20:15:31 2015 daemon.notice pppd[1674]: local IP address = 89.243.97.109 Tue Mar 3 20:15:31 2015 daemon.notice pppd[1674]: remote IP address = 89.243.96.1 Tue Mar 3 20:15:31 2015 daemon.notice pppd[1674]: primary DNS address = 62.24.243.1 Tue Mar 3 20:15:31 2015 daemon.notice pppd[1674]: secondary DNS address = 62.24.134.2 Tue Mar 3 20:15:32 2015 kern.info kernel: [ 25.150000] IPv6: = ADDRCONF(NETDEV_CHANGE): wlan0-1: link becomes ready Tue Mar 3 20:15:32 2015 user.notice SQM: hotplug on interface: lo = action: ifup Tue Mar 3 20:15:32 2015 user.notice SQM: Re/starting sqm on interface = lo Tue Mar 3 20:15:32 2015 daemon.notice netifd: Network device = 'pppoe-wan' link is up Tue Mar 3 20:15:32 2015 daemon.notice netifd: Interface 'wan' is now up Tue Mar 3 20:15:32 2015 daemon.notice netifd: Network device 'wlan0' = link is up Tue Mar 3 20:15:32 2015 daemon.notice netifd: Interface 'wifi_b_guest' = is enabled Tue Mar 3 20:15:32 2015 daemon.notice netifd: Interface 'wifi_b_guest' = is setting up now Tue Mar 3 20:15:32 2015 daemon.notice netifd: Interface 'wifi_b_guest' = is now up Tue Mar 3 20:15:32 2015 daemon.notice netifd: Interface 'wifi_b_guest' = has link connectivity=20 Tue Mar 3 20:15:32 2015 daemon.notice netifd: Network device 'wlan0-1' = link is up Tue Mar 3 20:15:32 2015 daemon.notice netifd: Interface 'wifi_b_alan' = is enabled Tue Mar 3 20:15:32 2015 daemon.notice netifd: Interface 'wifi_b_alan' = is setting up now Tue Mar 3 20:15:32 2015 daemon.notice netifd: Interface 'wifi_b_alan' = is now up Tue Mar 3 20:15:32 2015 daemon.notice netifd: Interface 'wifi_b_alan' = has link connectivity=20 Tue Mar 3 20:15:32 2015 daemon.info avahi-daemon[1784]: Found user = 'nobody' (UID 65534) and group 'nogroup' (GID 65534). Tue Mar 3 20:15:32 2015 daemon.info avahi-daemon[1784]: Successfully = dropped root privileges. Tue Mar 3 20:15:32 2015 daemon.info avahi-daemon[1784]: avahi-daemon = 0.6.31 starting up. Tue Mar 3 20:15:32 2015 daemon.warn avahi-daemon[1784]: WARNING: No NSS = support for mDNS detected, consider installing nss-mdns! Tue Mar 3 20:15:32 2015 daemon.info avahi-daemon[1784]: Loading service = file /etc/avahi/services/http.service. Tue Mar 3 20:15:32 2015 daemon.info avahi-daemon[1784]: Loading service = file /etc/avahi/services/ssh.service. Tue Mar 3 20:15:32 2015 daemon.info avahi-daemon[1784]: Joining mDNS = multicast group on interface wlan0-1.IPv4 with address 192.168.7.1. Tue Mar 3 20:15:32 2015 daemon.info avahi-daemon[1784]: New relevant = interface wlan0-1.IPv4 for mDNS. Tue Mar 3 20:15:32 2015 daemon.info avahi-daemon[1784]: Joining mDNS = multicast group on interface wlan0.IPv4 with address 192.168.5.1. Tue Mar 3 20:15:32 2015 daemon.info avahi-daemon[1784]: New relevant = interface wlan0.IPv4 for mDNS. Tue Mar 3 20:15:32 2015 daemon.info avahi-daemon[1784]: Joining mDNS = multicast group on interface br-lan.IPv4 with address 10.209.37.18. Tue Mar 3 20:15:32 2015 daemon.info avahi-daemon[1784]: New relevant = interface br-lan.IPv4 for mDNS. Tue Mar 3 20:15:32 2015 daemon.info avahi-daemon[1784]: Joining mDNS = multicast group on interface eth1.IPv4 with address 192.168.1.100. Tue Mar 3 20:15:32 2015 daemon.info avahi-daemon[1784]: New relevant = interface eth1.IPv4 for mDNS. Tue Mar 3 20:15:32 2015 daemon.info avahi-daemon[1784]: Network = interface enumeration completed. Tue Mar 3 20:15:32 2015 daemon.info avahi-daemon[1784]: Registering new = address record for fe80::7444:1ff:fe86:42d4 on wlan0-1.*. Tue Mar 3 20:15:32 2015 daemon.info avahi-daemon[1784]: Registering new = address record for 192.168.7.1 on wlan0-1.IPv4. Tue Mar 3 20:15:32 2015 daemon.info avahi-daemon[1784]: Registering new = address record for fe80::7644:1ff:fe86:42d4 on wlan0.*. Tue Mar 3 20:15:32 2015 daemon.info avahi-daemon[1784]: Registering new = address record for 192.168.5.1 on wlan0.IPv4. Tue Mar 3 20:15:32 2015 daemon.info avahi-daemon[1784]: Registering new = address record for fe80::7444:1ff:fe86:42d4 on br-lan.*. Tue Mar 3 20:15:32 2015 daemon.info avahi-daemon[1784]: Registering new = address record for 10.209.37.18 on br-lan.IPv4. Tue Mar 3 20:15:32 2015 daemon.info avahi-daemon[1784]: Registering new = address record for fe80::7644:1ff:fe86:42d5 on eth1.*. Tue Mar 3 20:15:32 2015 daemon.info avahi-daemon[1784]: Registering new = address record for 192.168.1.100 on eth1.IPv4. Tue Mar 3 20:15:32 2015 daemon.info avahi-daemon[1784]: Registering new = address record for fe80::7444:1ff:fe86:42d4 on eth0.*. Tue Mar 3 20:15:32 2015 daemon.info avahi-daemon[1784]: Registering = HINFO record with values 'MIPS'/'LINUX'. Tue Mar 3 20:15:33 2015 user.emerg syslog: setting up led WAN LED = (green) Tue Mar 3 20:15:33 2015 user.emerg syslog: setting up led USB Tue Mar 3 20:15:33 2015 user.emerg syslog: setting up led radio0 Tue Mar 3 20:15:33 2015 user.emerg syslog: setting up led radio1 Tue Mar 3 20:15:33 2015 daemon.info avahi-daemon[1784]: Server startup = complete. Host name is mortar.local. Local service cookie is 102577710. Tue Mar 3 20:15:34 2015 user.emerg syslog: - init complete - Tue Mar 3 20:15:34 2015 daemon.notice netifd: radio1 (1121): = Configuration file: /var/run/hostapd-phy1.conf Tue Mar 3 20:15:34 2015 daemon.notice netifd: radio1 (1121): wlan1: = interface state UNINITIALIZED->COUNTRY_UPDATE Tue Mar 3 20:15:34 2015 daemon.notice netifd: radio1 (1121): ACS: = Automatic channel selection started, this may take a bit Tue Mar 3 20:15:34 2015 daemon.notice netifd: radio1 (1121): wlan1: = interface state COUNTRY_UPDATE->ACS Tue Mar 3 20:15:34 2015 daemon.notice netifd: radio1 (1121): wlan1: = ACS-STARTED=20 Tue Mar 3 20:15:34 2015 daemon.notice netifd: radio1 (1121): wlan1: = ACS-COMPLETED freq=3D5300 channel=3D60 Tue Mar 3 20:15:34 2015 daemon.notice netifd: radio1 (1121): wlan1: = interface state ACS->HT_SCAN Tue Mar 3 20:15:34 2015 daemon.notice netifd: radio1 (1121): wlan1: = interface state HT_SCAN->DFS Tue Mar 3 20:15:34 2015 daemon.notice netifd: radio1 (1121): wlan1: = DFS-CAC-START freq=3D5300 chan=3D60 sec_chan=3D1, width=3D0, seg0=3D0, = seg1=3D0, cac_time=3D60s Tue Mar 3 20:15:34 2015 daemon.notice netifd: radio1 (1121): DFS = start_dfs_cac() failed, -1 Tue Mar 3 20:15:34 2015 daemon.notice netifd: radio1 (1121): Interface = initialization failed Tue Mar 3 20:15:34 2015 daemon.notice netifd: radio1 (1121): cat: can't = open '/var/run/wifi-phy1.pid': No such file or directory Tue Mar 3 20:15:34 2015 daemon.notice netifd: radio1 (1121): Command = failed: Invalid argument Tue Mar 3 20:15:34 2015 kern.info kernel: [ 27.510000] IPv6: = ADDRCONF(NETDEV_UP): wlan1: link is not ready Tue Mar 3 20:15:34 2015 daemon.info dnsmasq[1724]: started, version = 2.71 cachesize 150 Tue Mar 3 20:15:34 2015 daemon.info dnsmasq[1724]: compile time = options: IPv6 GNU-getopt no-DBus no-i18n no-IDN DHCP DHCPv6 no-Lua TFTP = no-conntrack ipset auth DNSSEC Tue Mar 3 20:15:34 2015 daemon.info dnsmasq-dhcp[1724]: DHCP, IP range = 10.0.0.100 -- 10.0.0.249, lease time 12h Tue Mar 3 20:15:34 2015 daemon.info dnsmasq[1724]: using local = addresses only for domain scree.dyndns.org Tue Mar 3 20:15:34 2015 daemon.info dnsmasq[1724]: reading = /tmp/resolv.conf.auto Tue Mar 3 20:15:34 2015 daemon.info dnsmasq[1724]: using local = addresses only for domain scree.dyndns.org Tue Mar 3 20:15:34 2015 daemon.info dnsmasq[1724]: using nameserver = 62.24.243.1#53 Tue Mar 3 20:15:34 2015 daemon.info dnsmasq[1724]: using nameserver = 62.24.134.2#53 Tue Mar 3 20:15:34 2015 daemon.info dnsmasq[1724]: read /etc/hosts - 13 = addresses Tue Mar 3 20:15:34 2015 daemon.info dnsmasq[1724]: read /tmp/hosts/dhcp = - 1 addresses Tue Mar 3 20:15:34 2015 daemon.info dnsmasq-dhcp[1724]: read = /etc/ethers - 0 addresses Tue Mar 3 20:15:34 2015 daemon.notice netifd: radio1 (1121): ifconfig: = SIOCGIFFLAGS: No such device Tue Mar 3 20:15:34 2015 daemon.notice netifd: radio1 (1121): Interface = 1 setup failed: IFUP_ERROR Tue Mar 3 20:15:34 2015 daemon.notice netifd: Interface 'wifi_a_guest' = is enabled Tue Mar 3 20:15:34 2015 daemon.notice netifd: Interface 'wifi_a_guest' = is setting up now Tue Mar 3 20:15:34 2015 daemon.info avahi-daemon[1784]: Joining mDNS = multicast group on interface wlan1.IPv4 with address 192.168.4.1. Tue Mar 3 20:15:34 2015 daemon.info avahi-daemon[1784]: New relevant = interface wlan1.IPv4 for mDNS. Tue Mar 3 20:15:34 2015 daemon.info avahi-daemon[1784]: Registering new = address record for 192.168.4.1 on wlan1.IPv4. Tue Mar 3 20:15:34 2015 daemon.notice netifd: Interface 'wifi_a_guest' = is now up Tue Mar 3 20:15:35 2015 daemon.info avahi-daemon[1784]: Service "Web = Server on mortar" (/etc/avahi/services/http.service) successfully = established. Tue Mar 3 20:15:35 2015 daemon.info avahi-daemon[1784]: Service "Secure = Shell on mortar" (/etc/avahi/services/ssh.service) successfully = established. Tue Mar 3 20:15:40 2015 daemon.info dnsmasq[1724]: exiting on receipt = of SIGTERM Tue Mar 3 20:15:40 2015 daemon.info dnsmasq[1899]: started, version = 2.71 cachesize 150 Tue Mar 3 20:15:40 2015 daemon.info dnsmasq[1899]: compile time = options: IPv6 GNU-getopt no-DBus no-i18n no-IDN DHCP DHCPv6 no-Lua TFTP = no-conntrack ipset auth DNSSEC Tue Mar 3 20:15:40 2015 daemon.info dnsmasq-dhcp[1899]: DHCP, IP range = 192.168.7.100 -- 192.168.7.249, lease time 12h Tue Mar 3 20:15:40 2015 daemon.info dnsmasq-dhcp[1899]: DHCP, IP range = 192.168.5.100 -- 192.168.5.249, lease time 12h Tue Mar 3 20:15:40 2015 daemon.info dnsmasq-dhcp[1899]: DHCP, IP range = 10.0.0.100 -- 10.0.0.249, lease time 12h Tue Mar 3 20:15:40 2015 daemon.info dnsmasq[1899]: using local = addresses only for domain scree.dyndns.org Tue Mar 3 20:15:40 2015 daemon.info dnsmasq[1899]: reading = /tmp/resolv.conf.auto Tue Mar 3 20:15:40 2015 daemon.info dnsmasq[1899]: using local = addresses only for domain scree.dyndns.org Tue Mar 3 20:15:40 2015 daemon.info dnsmasq[1899]: using nameserver = 62.24.243.1#53 Tue Mar 3 20:15:40 2015 daemon.info dnsmasq[1899]: using nameserver = 62.24.134.2#53 Tue Mar 3 20:15:40 2015 daemon.info dnsmasq[1899]: read /etc/hosts - 13 = addresses Tue Mar 3 20:15:40 2015 daemon.info dnsmasq[1899]: read /tmp/hosts/dhcp = - 1 addresses Tue Mar 3 20:15:40 2015 daemon.info dnsmasq-dhcp[1899]: read = /etc/ethers - 0 addresses Tue Mar 3 20:15:40 2015 user.notice SQM: hotplug on interface: eth1 = action: ifup Tue Mar 3 20:15:40 2015 user.notice SQM: Re/starting sqm on interface = eth1 Tue Mar 3 20:15:40 2015 user.notice firewall: Reloading firewall due to = ifup of modem (eth1) Tue Mar 3 20:15:44 2015 daemon.info dnsmasq[1899]: exiting on receipt = of SIGTERM Tue Mar 3 20:15:44 2015 daemon.info dnsmasq[2002]: started, version = 2.71 cachesize 150 Tue Mar 3 20:15:44 2015 daemon.info dnsmasq[2002]: compile time = options: IPv6 GNU-getopt no-DBus no-i18n no-IDN DHCP DHCPv6 no-Lua TFTP = no-conntrack ipset auth DNSSEC Tue Mar 3 20:15:44 2015 daemon.info dnsmasq-dhcp[2002]: DHCP, IP range = 192.168.7.100 -- 192.168.7.249, lease time 12h Tue Mar 3 20:15:44 2015 daemon.info dnsmasq-dhcp[2002]: DHCP, IP range = 192.168.5.100 -- 192.168.5.249, lease time 12h Tue Mar 3 20:15:44 2015 daemon.info dnsmasq-dhcp[2002]: DHCP, IP range = 192.168.4.100 -- 192.168.4.249, lease time 12h Tue Mar 3 20:15:44 2015 daemon.info dnsmasq-dhcp[2002]: DHCP, IP range = 10.0.0.100 -- 10.0.0.249, lease time 12h Tue Mar 3 20:15:44 2015 daemon.info dnsmasq[2002]: using local = addresses only for domain scree.dyndns.org Tue Mar 3 20:15:44 2015 daemon.info dnsmasq[2002]: reading = /tmp/resolv.conf.auto Tue Mar 3 20:15:44 2015 daemon.info dnsmasq[2002]: using local = addresses only for domain scree.dyndns.org Tue Mar 3 20:15:44 2015 daemon.info dnsmasq[2002]: using nameserver = 62.24.243.1#53 Tue Mar 3 20:15:44 2015 daemon.info dnsmasq[2002]: using nameserver = 62.24.134.2#53 Tue Mar 3 20:15:44 2015 daemon.info dnsmasq[2002]: read /etc/hosts - 13 = addresses Tue Mar 3 20:15:44 2015 daemon.info dnsmasq[2002]: read /tmp/hosts/dhcp = - 1 addresses Tue Mar 3 20:15:44 2015 daemon.info dnsmasq-dhcp[2002]: read = /etc/ethers - 0 addresses Tue Mar 3 20:15:44 2015 user.notice SQM: hotplug on interface: = pppoe-wan action: ifup Tue Mar 3 20:15:44 2015 user.notice SQM: Re/starting sqm on interface = pppoe-wan Tue Mar 3 20:15:44 2015 user.notice SQM: /usr/lib/sqm/run.sh Stopping = SQM on interface: pppoe-wan Tue Mar 3 20:15:44 2015 user.notice SQM: ifb associated with interface = pppoe-wan: Tue Mar 3 20:15:44 2015 user.notice SQM: trying to create new IFB: = ifb4pppoe-wan Tue Mar 3 20:15:44 2015 user.notice SQM: /usr/lib/sqm/stop.sh: Stopping = pppoe-wan Tue Mar 3 20:15:45 2015 user.notice SQM: ifb associated with interface = pppoe-wan: Tue Mar 3 20:15:45 2015 user.notice SQM: marching on... Tue Mar 3 20:15:45 2015 user.notice SQM: /usr/lib/sqm/run.sh Queue = Setup Script: /usr/lib/sqm/simple.qos Tue Mar 3 20:15:45 2015 user.notice SQM: ifb associated with interface = pppoe-wan: Tue Mar 3 20:15:45 2015 user.notice SQM: trying to create new IFB: = ifb4pppoe-wan Tue Mar 3 20:15:45 2015 user.notice SQM: Squashing differentiated = services code points (DSCP) from ingress. Tue Mar 3 20:54:17 2015 user.notice SQM: STAB: stab mtu 2047 tsize 512 = mpu 0 overhead 40 linklayer atm Tue Mar 3 20:54:17 2015 user.notice SQM: get_limit: CURLIMIT: 1001 Tue Mar 3 20:54:17 2015 user.notice SQM: cur_target: auto = cur_bandwidth: 850 Tue Mar 3 20:54:17 2015 user.notice SQM: get_limit: CURLIMIT: 1001 Tue Mar 3 20:54:17 2015 user.notice SQM: cur_target: auto = cur_bandwidth: 850 Tue Mar 3 20:54:17 2015 user.notice SQM: get_limit: CURLIMIT: 1001 Tue Mar 3 20:54:17 2015 user.notice SQM: cur_target: auto = cur_bandwidth: 850 Tue Mar 3 20:54:17 2015 user.notice SQM: egress shaping activated Tue Mar 3 20:54:17 2015 user.notice SQM: Do not perform DSCP based = filtering on ingress. (1-tier classification) Tue Mar 3 20:54:17 2015 user.notice SQM: STAB: stab mtu 2047 tsize 512 = mpu 0 overhead 40 linklayer atm Tue Mar 3 20:54:17 2015 user.notice SQM: get_limit: CURLIMIT: 1001 Tue Mar 3 20:54:17 2015 user.notice SQM: cur_target: auto = cur_bandwidth: 11500 Tue Mar 3 20:54:17 2015 user.notice SQM: ingress shaping activated Tue Mar 3 20:54:17 2015 user.notice firewall: Reloading firewall due to = ifup of wan (pppoe-wan) Tue Mar 3 20:54:18 2015 daemon.info pppd[1674]: System time change = detected. Tue Mar 3 20:54:18 2015 user.notice SQM: hotplug on interface: wlan0 = action: ifup Tue Mar 3 20:54:18 2015 user.notice SQM: Re/starting sqm on interface = wlan0 Tue Mar 3 20:54:19 2015 user.notice firewall: Reloading firewall due to = ifup of wifi_b_guest (wlan0) Tue Mar 3 20:54:20 2015 user.notice SQM: hotplug on interface: wlan0-1 = action: ifup Tue Mar 3 20:54:20 2015 user.notice SQM: Re/starting sqm on interface = wlan0-1 Tue Mar 3 20:54:20 2015 user.notice firewall: Reloading firewall due to = ifup of wifi_b_alan (wlan0-1) Tue Mar 3 20:54:21 2015 user.notice SQM: hotplug on interface: wlan1 = action: ifup Tue Mar 3 20:54:21 2015 user.notice SQM: Re/starting sqm on interface = wlan1 Tue Mar 3 20:54:21 2015 user.notice firewall: Reloading firewall due to = ifup of wifi_a_guest (wlan1) Tue Mar 3 20:54:33 2015 daemon.info hostapd: wlan0-1: STA = 00:24:d7:46:2e:b4 IEEE 802.11: authenticated Tue Mar 3 20:54:33 2015 daemon.info hostapd: wlan0-1: STA = 00:24:d7:46:2e:b4 IEEE 802.11: associated (aid 1) Tue Mar 3 20:54:33 2015 daemon.info hostapd: wlan0-1: STA = 00:24:d7:46:2e:b4 WPA: pairwise key handshake completed (RSN) Tue Mar 3 20:54:33 2015 daemon.info dnsmasq-dhcp[2002]: = DHCPREQUEST(wlan0-1) 192.168.7.226 00:24:d7:46:2e:b4=20 Tue Mar 3 20:54:33 2015 daemon.info dnsmasq-dhcp[2002]: = DHCPACK(wlan0-1) 192.168.7.226 00:24:d7:46:2e:b4=20 Tue Mar 3 20:54:41 2015 authpriv.info dropbear[2755]: Child connection = from 192.168.7.226:45986 Tue Mar 3 20:54:45 2015 authpriv.notice dropbear[2755]: Password auth = succeeded for 'root' from 192.168.7.226:45986 --Apple-Mail=_627892D9-234B-45F9-BE26-F2CEB4598B79 Content-Transfer-Encoding: 7bit Content-Type: text/plain; charset=us-ascii > --Apple-Mail=_627892D9-234B-45F9-BE26-F2CEB4598B79--