1. This site uses cookies. By continuing to use this site, you are agreeing to our use of cookies. Learn More.

Shibby build 105 on RT-N10U reboot is slow

Discussion in 'Tomato Firmware' started by jeonny, Jan 27, 2013.

  1. jeonny

    jeonny Serious Server Member

    build 105 on RT-N10U still reboot is slow :(
    until they begin to respond to ping:
    oleg firm : 31 sec
    085V BT-VPN : 38 sec
    104-Big-VPN : 87 sec
    105-Big-VPN : 86 sec
    completely wireless reboot test., until the laptop connects to the router
    105-Big-VPN : 95 sec
     
  2. Mangix

    Mangix Networkin' Nut Member

    would a serial log be out of the question?
     
  3. jeonny

    jeonny Serious Server Member

    Jan 1 01:00:09 unknown syslog.info syslogd started: BusyBox v1.18.5
    Jan 1 01:00:09 unknown user.notice kernel: klogd started: BusyBox v1.18.5 (2013-01-20 00:51:06 CET)
    Jan 1 01:00:09 unknown user.notice kernel: Linux version 2.6.22.19 (root@tomato) (gcc version 4.2.4) #17 Sun Jan 20 01:06:57 CET 2013
    Jan 1 01:00:09 unknown user.warn kernel: CPU revision is: 00019749
    Jan 1 01:00:09 unknown user.warn kernel: Determined physical RAM map:
    Jan 1 01:00:09 unknown user.warn kernel: memory: 02000000 @ 00000000 (usable)
    Jan 1 01:00:09 unknown user.debug kernel: Entering add_active_range(0, 0, 8192) 0 entries of 256 used
    Jan 1 01:00:09 unknown user.info kernel: Zone PFN ranges:
    Jan 1 01:00:09 unknown user.warn kernel: Normal 0 -> 8192
    Jan 1 01:00:09 unknown user.warn kernel: HighMem 8192 -> 8192
    Jan 1 01:00:09 unknown user.info kernel: early_node_map[1] active PFN ranges
    Jan 1 01:00:09 unknown user.warn kernel: 0: 0 -> 8192
    Jan 1 01:00:09 unknown user.debug kernel: On node 0 totalpages: 8192
    Jan 1 01:00:09 unknown user.debug kernel: Normal zone: 0 pages used for memmap
    Jan 1 01:00:09 unknown user.debug kernel: Normal zone: 0 pages reserved
    Jan 1 01:00:09 unknown user.debug kernel: Normal zone: 8192 pages, LIFO batch:1
    Jan 1 01:00:09 unknown user.debug kernel: HighMem zone: 0 pages used for memmap
    Jan 1 01:00:09 unknown user.warn kernel: Built 1 zonelists. Total pages: 8192
    Jan 1 01:00:09 unknown user.notice kernel: Kernel command line: root=/dev/mtdblock2 noinitrd console=ttyS0,115200
    Jan 1 01:00:09 unknown user.warn kernel: Primary instruction cache 32kB, physically tagged, 4-way, linesize 32 bytes.
    Jan 1 01:00:09 unknown user.warn kernel: Primary data cache 32kB, 4-way, linesize 32 bytes.
    Jan 1 01:00:09 unknown user.info kernel: Synthesized TLB refill handler (20 instructions).
    Jan 1 01:00:09 unknown user.info kernel: Synthesized TLB load handler fastpath (32 instructions).
    Jan 1 01:00:09 unknown user.info kernel: Synthesized TLB store handler fastpath (32 instructions).
    Jan 1 01:00:09 unknown user.info kernel: Synthesized TLB modify handler fastpath (31 instructions).
    Jan 1 01:00:09 unknown user.warn kernel: PID hash table entries: 256 (order: 8, 1024 bytes)
    Jan 1 01:00:10 unknown user.warn kernel: CPU: BCM5357 rev 2 pkg 8 at 300 MHz
    Jan 1 01:00:10 unknown user.warn kernel: Using 150.000 MHz high precision timer.
    Jan 1 01:00:10 unknown user.info kernel: console [ttyS0] enabled
    Jan 1 01:00:10 unknown user.warn kernel: Dentry cache hash table entries: 4096 (order: 2, 16384 bytes)
    Jan 1 01:00:10 unknown user.warn kernel: Inode-cache hash table entries: 2048 (order: 1, 8192 bytes)
    Jan 1 01:00:10 unknown user.info kernel: Memory: 28552k/32768k available (33k kernel code, 4200k reserved, 2778k data, 128k init, 0k highmem)
    Jan 1 01:00:10 unknown user.debug kernel: Calibrating delay loop... 149.91 BogoMIPS (lpj=749568)
    Jan 1 01:00:10 unknown user.warn kernel: Mount-cache hash table entries: 512
    Jan 1 01:00:10 unknown user.info kernel: NET: Registered protocol family 16
    Jan 1 01:00:10 unknown user.warn kernel: PCI: no core
    Jan 1 01:00:10 unknown user.warn kernel: PCI: no core
    Jan 1 01:00:10 unknown user.warn kernel: PCI: Fixing up bus 0
    Jan 1 01:00:10 unknown user.info kernel: NET: Registered protocol family 2
    Jan 1 01:00:10 unknown user.info kernel: Time: MIPS clocksource has been installed.
    Jan 1 01:00:10 unknown user.warn kernel: IP route cache hash table entries: 1024 (order: 0, 4096 bytes)
    Jan 1 01:00:10 unknown user.warn kernel: TCP established hash table entries: 1024 (order: 1, 8192 bytes)
    Jan 1 01:00:10 unknown user.warn kernel: TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
    Jan 1 01:00:10 unknown user.info kernel: TCP: Hash tables configured (established 1024 bind 1024)
    Jan 1 01:00:10 unknown user.info kernel: TCP reno registered
    Jan 1 01:00:10 unknown user.info kernel: squashfs: version 3.0 (2006/03/15) Phillip Lougher
    Jan 1 01:00:10 unknown user.info kernel: io scheduler noop registered (default)
    Jan 1 01:00:10 unknown user.info kernel: HDLC line discipline: version $Revision: 4.8 $, maxframe=4096
    Jan 1 01:00:10 unknown user.info kernel: N_HDLC line discipline registered.
    Jan 1 01:00:10 unknown user.info kernel: Serial: 8250/16550 driver $Revision: 1.90 $ 2 ports, IRQ sharing disabled
    Jan 1 01:00:10 unknown user.info kernel: serial8250: ttyS0 at MMIO 0xb8000300 (irq = 8) is a 16550A
    Jan 1 01:00:10 unknown user.info kernel: PPP generic driver version 2.4.2
    Jan 1 01:00:10 unknown user.info kernel: MPPE/MPPC encryption/compression module registered
    Jan 1 01:00:10 unknown user.info kernel: NET: Registered protocol family 24
    Jan 1 01:00:10 unknown user.info kernel: PPPoL2TP kernel driver, V0.18.3
    Jan 1 01:00:10 unknown user.info kernel: PPTP driver version 0.8.5
    Jan 1 01:00:10 unknown user.err kernel: pflash: found no supported devices
    Jan 1 01:00:10 unknown user.warn kernel: Found an ST compatible serial flash with 128 64KB blocks; total size 8MB
    Jan 1 01:00:10 unknown user.notice kernel: Creating 5 MTD partitions on "sflash":
    Jan 1 01:00:10 unknown user.notice kernel: 0x00000000-0x00020000 : "pmon"
    Jan 1 01:00:10 unknown user.notice kernel: 0x00020000-0x007f0000 : "linux"
    Jan 1 01:00:10 unknown user.notice kernel: 0x0010c400-0x007d0000 : "rootfs"
    Jan 1 01:00:10 unknown user.notice kernel: 0x007d0000-0x007f0000 : "jffs2"
    Jan 1 01:00:10 unknown user.notice kernel: 0x007f0000-0x00800000 : "nvram"
    Jan 1 01:00:10 unknown user.warn kernel: _nvram_init: allocat header: 2165997568, size= 32768
    Jan 1 01:00:10 unknown user.warn kernel: u32 classifier
    Jan 1 01:00:10 unknown user.warn kernel: OLD policer on
    Jan 1 01:00:10 unknown user.warn kernel: Netfilter messages via NETLINK v0.30.
    Jan 1 01:00:10 unknown user.warn kernel: nf_conntrack version 0.5.0 (512 buckets, 4096 max)
    Jan 1 01:00:10 unknown user.warn kernel: ip_tables: (C) 2000-2006 Netfilter Core Team
    Jan 1 01:00:10 unknown user.info kernel: ipt_account 0.1.21 : Piotr Gasidlo <quaker@barbara.eu.org>, http://www.barbara.eu.org/~quaker/ipt_account/
    Jan 1 01:00:10 unknown user.warn kernel: net/ipv4/netfilter/tomato_ct.c [Jan 19 2013 23:38:05]
    Jan 1 01:00:10 unknown user.info kernel: NET: Registered protocol family 1
    Jan 1 01:00:10 unknown user.info kernel: NET: Registered protocol family 10
    Jan 1 01:00:10 unknown user.warn kernel: ip6_tables: (C) 2000-2006 Netfilter Core Team
    Jan 1 01:00:10 unknown user.info kernel: NET: Registered protocol family 17
    Jan 1 01:00:10 unknown user.info kernel: 802.1Q VLAN Support v1.8 Ben Greear <greearb@candelatech.com>
    Jan 1 01:00:10 unknown user.info kernel: All bugs added by David S. Miller <davem@redhat.com>
    Jan 1 01:00:10 unknown user.warn kernel: VFS: Mounted root (squashfs filesystem) readonly.
    Jan 1 01:00:10 unknown user.info kernel: Freeing unused kernel memory: 128k freed
    Jan 1 01:00:10 unknown user.warn kernel: Warning: unable to open an initial console.
    Jan 1 01:00:10 unknown user.warn kernel: emf: module license 'Proprietary' taints kernel.
    Jan 1 01:00:10 unknown user.debug kernel: PCI: Setting latency timer of device 0000:00:02.0 to 64
    Jan 1 01:00:10 unknown user.warn kernel: eth0: Broadcom BCM47XX 10/100/1000 Mbps Ethernet Controller 5.100.138.20
    Jan 1 01:00:10 unknown user.warn kernel: wl_module_init: passivemode set to 0x0
    Jan 1 01:00:10 unknown user.debug kernel: PCI: Setting latency timer of device 0000:00:01.0 to 64
    Jan 1 01:00:10 unknown user.warn kernel: eth1: Broadcom BCM4329 802.11 Wireless Controller 5.100.138.20
    Jan 1 01:00:10 unknown user.warn kernel: Algorithmics/MIPS FPU Emulator v1.5
    Jan 1 01:00:10 unknown user.info kernel: usbcore: registered new interface driver usbfs
    Jan 1 01:00:10 unknown user.info kernel: usbcore: registered new interface driver hub
    Jan 1 01:00:10 unknown user.info kernel: usbcore: registered new device driver usb
    Jan 1 01:00:10 unknown user.notice kernel: SCSI subsystem initialized
    Jan 1 01:00:10 unknown user.info kernel: Initializing USB Mass Storage driver...
    Jan 1 01:00:10 unknown user.info kernel: usbcore: registered new interface driver usb-storage
    Jan 1 01:00:10 unknown user.info kernel: USB Mass Storage support registered.
    Jan 1 01:00:11 unknown user.info kernel: ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
    Jan 1 01:00:11 unknown user.debug kernel: PCI: Setting latency timer of device 0000:00:04.1 to 64
    Jan 1 01:00:11 unknown user.info kernel: ehci_hcd 0000:00:04.1: EHCI Host Controller
    Jan 1 01:00:11 unknown user.info kernel: ehci_hcd 0000:00:04.1: new USB bus registered, assigned bus number 1
    Jan 1 01:00:11 unknown user.info kernel: ehci_hcd 0000:00:04.1: irq 5, io mem 0x18004000
    Jan 1 01:00:11 unknown user.info kernel: ehci_hcd 0000:00:04.1: USB 0.0 started, EHCI 1.00
    Jan 1 01:00:11 unknown user.info kernel: usb usb1: configuration #1 chosen from 1 choice
    Jan 1 01:00:11 unknown user.info kernel: hub 1-0:1.0: USB hub found
    Jan 1 01:00:11 unknown user.info kernel: hub 1-0:1.0: 2 ports detected
    Jan 1 01:00:11 unknown user.debug hotplug[371]: Attached USB device 1-0:1.0 [INTERFACE=9/0/0 PRODUCT=1d6b/2/206]
    Jan 1 01:00:12 unknown user.debug kernel: vlan0: add 33:33:00:00:00:01 mcast address to master interface
    Jan 1 01:00:12 unknown user.debug kernel: vlan0: add 01:00:5e:00:00:01 mcast address to master interface
    Jan 1 01:00:12 unknown user.info kernel: vlan0: dev_set_allmulti(master, 1)
    Jan 1 01:00:12 unknown user.info kernel: vlan0: dev_set_promiscuity(master, 1)
    Jan 1 01:00:12 unknown user.info kernel: device eth0 entered promiscuous mode
    Jan 1 01:00:12 unknown user.info kernel: device vlan0 entered promiscuous mode
    Jan 1 01:00:12 unknown user.info kernel: device eth1 entered promiscuous mode
    Jan 1 01:00:12 unknown user.info kernel: br0: port 2(eth1) entering forwarding state
    Jan 1 01:00:12 unknown user.info kernel: br0: port 1(vlan0) entering forwarding state
    Jan 1 01:00:12 unknown user.warn kernel: vlan1: Setting MAC address to 08 60 6e e0 48 ad.
    Jan 1 01:00:12 unknown user.debug kernel: vlan1: add 33:33:00:00:00:01 mcast address to master interface
    Jan 1 01:00:12 unknown user.debug kernel: vlan1: add 01:00:5e:00:00:01 mcast address to master interface
    Jan 1 01:00:12 unknown user.info kernel: IMQ starting with 2 devices...
    Jan 1 01:00:12 unknown user.info kernel: IMQ driver loaded successfully.
    Jan 1 01:00:12 unknown user.info kernel:Hooking IMQ after NAT on PREROUTING.
    Jan 1 01:00:12 unknown user.info kernel:Hooking IMQ before NAT on POSTROUTING.
    Jan 1 01:00:14 unknown user.debug dhcpc-event[464]: 182: pptp peerdns disabled
    Jan 1 01:00:41 unknown authpriv.info dropbear[521]: Running in background
    Jan 1 01:00:41 unknown daemon.info dnsmasq[526]: started, version 2.61 cachesize 1500
    Jan 1 01:00:41 unknown daemon.info dnsmasq[526]: compile time options: IPv6 GNU-getopt no-RTC no-DBus no-i18n no-IDN DHCP DHCPv6 no-Lua TFTP no-conntrack
    Jan 1 01:00:41 unknown daemon.info dnsmasq[526]: asynchronous logging enabled, queue limit is 5 messages
    Jan 1 01:00:41 unknown daemon.info dnsmasq-dhcp[526]: DHCP, IP range 192.168.1.2 -- 192.168.1.51, lease time 1d
    Jan 1 01:00:41 unknown daemon.info dnsmasq[526]: reading /etc/resolv.dnsmasq
    Jan 1 01:00:41 unknown daemon.warn dnsmasq[526]: ignoring nameserver 192.168.1.1 - local interface
    Jan 1 01:00:41 unknown daemon.info dnsmasq[526]: read /etc/hosts - 2 addresses
    Jan 1 01:00:41 unknown daemon.info dnsmasq[526]: read /etc/dnsmasq/hosts/hosts - 3 addresses
    Jan 1 01:00:41 unknown daemon.info dnsmasq-dhcp[526]: read /etc/dnsmasq/dhcp/dhcp-hosts
    Jan 1 01:00:42 unknown user.debug init[1]: starting rstats.
    Jan 1 01:00:42 unknown user.debug init[1]: starting cstats.
    Jan 1 01:00:43 unknown user.notice root: Stoping NFS Server ...
    Jan 1 01:00:43 unknown user.notice root: Transmission daemon successfully stoped
    Jan 1 01:00:43 unknown user.info init[1]: Asus RT-N10U: Tomato 1.28.0000 MIPSR2-105 K26 USB Big-VPN
    Jan 1 01:00:43 unknown user.debug init[1]: 182: pptp peerdns disabled
    Jan 1 01:00:43 unknown daemon.info dnsmasq[526]: exiting on receipt of SIGTERM
    Jan 1 01:00:43 unknown daemon.info dnsmasq[562]: started, version 2.61 cachesize 1500
    Jan 1 01:00:43 unknown daemon.info dnsmasq[562]: compile time options: IPv6 GNU-getopt no-RTC no-DBus no-i18n no-IDN DHCP DHCPv6 no-Lua TFTP no-conntrack
    Jan 1 01:00:43 unknown daemon.info dnsmasq[562]: asynchronous logging enabled, queue limit is 5 messages
    Jan 1 01:00:43 unknown daemon.info dnsmasq-dhcp[562]: DHCP, IP range 192.168.1.2 -- 192.168.1.51, lease time 1d
    Jan 1 01:00:43 unknown daemon.info dnsmasq[562]: reading /etc/resolv.dnsmasq
    Jan 1 01:00:43 unknown daemon.warn dnsmasq[562]: ignoring nameserver 192.168.1.1 - local interface
    Jan 1 01:00:43 unknown daemon.info dnsmasq[562]: read /etc/hosts - 2 addresses
    Jan 1 01:00:43 unknown daemon.info dnsmasq[562]: read /etc/dnsmasq/hosts/hosts - 3 addresses
    Jan 1 01:00:43 unknown daemon.info dnsmasq-dhcp[562]: read /etc/dnsmasq/dhcp/dhcp-hosts
    Jan 1 01:00:43 unknown user.notice root: NFS Server stoped ...
     
  4. koitsu

    koitsu Network Guru Member

    Here's the delay to focus on. You can confirm this by rolling back to 085V BT-VPN and posting the results/comparing. I know it will take up most of your afternoon, but please try to narrow it down and see if that is always where the delay is.

    If so: my guess: the DHCP client on the WAN side (i.e. the router negotiating DHCP with your ISP) is what's taking so long. I imagine that dropbear only starts after the DHCP negotiation takes place completely (i.e. the DHCP negotiation is atomic -- but please don't quote me on this). And don't get confused: dnsmasq handles the DHCP server side of things for the LAN and isn't the client.

    Guess what's used for the DHCP client side of things on the WAN? Busybox itself. What version of Busybox is used in 085V BT-VPN, and what version is used in 104-Big-VPN? It's easy to find out: just run busybox | head -1 from the shell. My money is on Busybox adding new (or breaking existing) features between versions, causing DHCP negotiation to now take longer with ISPs. Sorry, I don't have a positive opinion of Busybox at all because they have a very long-established history of writing utterly crap code combined with major/catastrophic bugs (I've grown tired of fixing them).

    Please note there will always be some variance in DHCP negotiation times with ISPs (in some cases up to 15 seconds) so I think this explains the other variances of 86->95 seconds. And when ISPs like AT&T have entire regions broken/down due to a single DHCP server which is broken/busted, you can imagine how this affects things.

    So like I said: if you can narrow down exactly where the delay always is in firmware version X compared to firmware version Y, then that helps the devs greatly. :)
     
  5. jeonny

    jeonny Serious Server Member

    RT-10U use it as a second router with "WAN / Internet static", first router is an huawei HG655b .
    later I will post and log for 085V BT-VPN
     
  6. jeonny

    jeonny Serious Server Member

    BusyBox v1.18.5 (2012-02-25 17:45:38 CET) multi-call binary.
    Log for 085V BT-VPN

    Jan 1 01:00:09 unknown syslog.info syslogd started: BusyBox v1.18.5
    Jan 1 01:00:09 unknown user.notice kernel: klogd started: BusyBox v1.18.5 (2012-02-25 17:45:38 CET)
    Jan 1 01:00:09 unknown user.notice kernel: Linux version 2.6.22.19 (root@tomato) (gcc version 4.2.4) #9 Sat Feb 25 18:01:10 CET 2012
    Jan 1 01:00:09 unknown user.warn kernel: CPU revision is: 00019749
    Jan 1 01:00:09 unknown user.warn kernel: Determined physical RAM map:
    Jan 1 01:00:09 unknown user.warn kernel: memory: 02000000 @ 00000000 (usable)
    Jan 1 01:00:09 unknown user.debug kernel: Entering add_active_range(0, 0, 8192) 0 entries of 256 used
    Jan 1 01:00:09 unknown user.info kernel: Zone PFN ranges:
    Jan 1 01:00:09 unknown user.warn kernel: Normal 0 -> 8192
    Jan 1 01:00:09 unknown user.warn kernel: HighMem 8192 -> 8192
    Jan 1 01:00:09 unknown user.info kernel: early_node_map[1] active PFN ranges
    Jan 1 01:00:09 unknown user.warn kernel: 0: 0 -> 8192
    Jan 1 01:00:09 unknown user.debug kernel: On node 0 totalpages: 8192
    Jan 1 01:00:09 unknown user.debug kernel: Normal zone: 0 pages used for memmap
    Jan 1 01:00:09 unknown user.debug kernel: Normal zone: 0 pages reserved
    Jan 1 01:00:09 unknown user.debug kernel: Normal zone: 8192 pages, LIFO batch:1
    Jan 1 01:00:09 unknown user.debug kernel: HighMem zone: 0 pages used for memmap
    Jan 1 01:00:09 unknown user.warn kernel: Built 1 zonelists. Total pages: 8192
    Jan 1 01:00:10 unknown user.notice kernel: Kernel command line: root=/dev/mtdblock2 noinitrd console=ttyS0,115200
    Jan 1 01:00:10 unknown user.warn kernel: Primary instruction cache 32kB, physically tagged, 4-way, linesize 32 bytes.
    Jan 1 01:00:10 unknown user.warn kernel: Primary data cache 32kB, 4-way, linesize 32 bytes.
    Jan 1 01:00:10 unknown user.info kernel: Synthesized TLB refill handler (20 instructions).
    Jan 1 01:00:10 unknown user.info kernel: Synthesized TLB load handler fastpath (32 instructions).
    Jan 1 01:00:10 unknown user.info kernel: Synthesized TLB store handler fastpath (32 instructions).
    Jan 1 01:00:10 unknown user.info kernel: Synthesized TLB modify handler fastpath (31 instructions).
    Jan 1 01:00:10 unknown user.warn kernel: PID hash table entries: 256 (order: 8, 1024 bytes)
    Jan 1 01:00:10 unknown user.warn kernel: CPU: BCM5357 rev 2 pkg 8 at 300 MHz
    Jan 1 01:00:10 unknown user.warn kernel: Using 150.000 MHz high precision timer.
    Jan 1 01:00:10 unknown user.info kernel: console [ttyS0] enabled
    Jan 1 01:00:10 unknown user.warn kernel: Dentry cache hash table entries: 4096 (order: 2, 16384 bytes)
    Jan 1 01:00:10 unknown user.warn kernel: Inode-cache hash table entries: 2048 (order: 1, 8192 bytes)
    Jan 1 01:00:10 unknown user.info kernel: Memory: 28632k/32768k available (33k kernel code, 4120k reserved, 2701k data, 124k init, 0k highmem)
    Jan 1 01:00:10 unknown user.debug kernel: Calibrating delay loop... 149.91 BogoMIPS (lpj=749568)
    Jan 1 01:00:10 unknown user.warn kernel: Mount-cache hash table entries: 512
    Jan 1 01:00:10 unknown user.info kernel: NET: Registered protocol family 16
    Jan 1 01:00:10 unknown user.warn kernel: PCI: no core
    Jan 1 01:00:10 unknown user.warn kernel: PCI: no core
    Jan 1 01:00:10 unknown user.warn kernel: PCI: Fixing up bus 0
    Jan 1 01:00:10 unknown user.info kernel: NET: Registered protocol family 2
    Jan 1 01:00:10 unknown user.info kernel: Time: MIPS clocksource has been installed.
    Jan 1 01:00:10 unknown user.warn kernel: IP route cache hash table entries: 1024 (order: 0, 4096 bytes)
    Jan 1 01:00:10 unknown user.warn kernel: TCP established hash table entries: 1024 (order: 1, 8192 bytes)
    Jan 1 01:00:10 unknown user.warn kernel: TCP bind hash table entries: 1024 (order: 0, 4096 bytes)
    Jan 1 01:00:10 unknown user.info kernel: TCP: Hash tables configured (established 1024 bind 1024)
    Jan 1 01:00:10 unknown user.info kernel: TCP reno registered
    Jan 1 01:00:10 unknown user.info kernel: squashfs: version 3.0 (2006/03/15) Phillip Lougher
    Jan 1 01:00:10 unknown user.info kernel: io scheduler noop registered (default)
    Jan 1 01:00:10 unknown user.info kernel: HDLC line discipline: version $Revision: 4.8 $, maxframe=4096
    Jan 1 01:00:10 unknown user.info kernel: N_HDLC line discipline registered.
    Jan 1 01:00:10 unknown user.info kernel: Serial: 8250/16550 driver $Revision: 1.90 $ 2 ports, IRQ sharing disabled
    Jan 1 01:00:10 unknown user.info kernel: serial8250: ttyS0 at MMIO 0xb8000300 (irq = 8) is a 16550A
    Jan 1 01:00:10 unknown user.info kernel: PPP generic driver version 2.4.2
    Jan 1 01:00:10 unknown user.info kernel: MPPE/MPPC encryption/compression module registered
    Jan 1 01:00:10 unknown user.info kernel: NET: Registered protocol family 24
    Jan 1 01:00:10 unknown user.info kernel: PPPoL2TP kernel driver, V0.18.3
    Jan 1 01:00:10 unknown user.info kernel: PPTP driver version 0.8.5
    Jan 1 01:00:10 unknown user.err kernel: pflash: found no supported devices
    Jan 1 01:00:10 unknown user.warn kernel: Found an ST compatible serial flash with 128 64KB blocks; total size 8MB
    Jan 1 01:00:10 unknown user.notice kernel: Creating 5 MTD partitions on "sflash":
    Jan 1 01:00:10 unknown user.notice kernel: 0x00000000-0x00020000 : "pmon"
    Jan 1 01:00:10 unknown user.notice kernel: 0x00020000-0x007f0000 : "linux"
    Jan 1 01:00:10 unknown user.notice kernel: 0x00106c00-0x007c0000 : "rootfs"
    Jan 1 01:00:10 unknown user.notice kernel: 0x007c0000-0x007f0000 : "jffs2"
    Jan 1 01:00:10 unknown user.notice kernel: 0x007f0000-0x00800000 : "nvram"
    Jan 1 01:00:10 unknown user.warn kernel: u32 classifier
    Jan 1 01:00:10 unknown user.warn kernel: OLD policer on
    Jan 1 01:00:10 unknown user.warn kernel: Netfilter messages via NETLINK v0.30.
    Jan 1 01:00:10 unknown user.warn kernel: nf_conntrack version 0.5.0 (512 buckets, 4096 max)
    Jan 1 01:00:10 unknown user.warn kernel: ip_tables: (C) 2000-2006 Netfilter Core Team
    Jan 1 01:00:10 unknown user.info kernel: ipt_account 0.1.21 : Piotr Gasidlo <quaker@barbara.eu.org>, http://www.barbara.eu.org/~quaker/ipt_account/
    Jan 1 01:00:10 unknown user.warn kernel: net/ipv4/netfilter/tomato_ct.c [Feb 25 2012 17:38:39]
    Jan 1 01:00:10 unknown user.info kernel: NET: Registered protocol family 1
    Jan 1 01:00:10 unknown user.info kernel: NET: Registered protocol family 10
    Jan 1 01:00:10 unknown user.warn kernel: ip6_tables: (C) 2000-2006 Netfilter Core Team
    Jan 1 01:00:10 unknown user.info kernel: NET: Registered protocol family 17
    Jan 1 01:00:10 unknown user.info kernel: 802.1Q VLAN Support v1.8 Ben Greear <greearb@candelatech.com>
    Jan 1 01:00:10 unknown user.info kernel: All bugs added by David S. Miller <davem@redhat.com>
    Jan 1 01:00:10 unknown user.warn kernel: VFS: Mounted root (squashfs filesystem) readonly.
    Jan 1 01:00:10 unknown user.info kernel: Freeing unused kernel memory: 124k freed
    Jan 1 01:00:10 unknown user.warn kernel: Warning: unable to open an initial console.
    Jan 1 01:00:10 unknown user.warn kernel: emf: module license 'Proprietary' taints kernel.
    Jan 1 01:00:10 unknown user.debug kernel: PCI: Setting latency timer of device 0000:00:02.0 to 64
    Jan 1 01:00:10 unknown user.warn kernel: eth0: Broadcom BCM47XX 10/100/1000 Mbps Ethernet Controller 5.100.138.9
    Jan 1 01:00:10 unknown user.warn kernel: wl_module_init: passivemode set to 0x0
    Jan 1 01:00:10 unknown user.debug kernel: PCI: Setting latency timer of device 0000:00:01.0 to 64
    Jan 1 01:00:10 unknown user.warn kernel: eth1: Broadcom BCM4329 802.11 Wireless Controller 5.100.138.9
    Jan 1 01:00:10 unknown user.warn kernel: Algorithmics/MIPS FPU Emulator v1.5
    Jan 1 01:00:10 unknown user.debug kernel: vlan0: add 33:33:00:00:00:01 mcast address to master interface
    Jan 1 01:00:10 unknown user.debug kernel: vlan0: add 01:00:5e:00:00:01 mcast address to master interface
    Jan 1 01:00:10 unknown user.info kernel: vlan0: dev_set_allmulti(master, 1)
    Jan 1 01:00:10 unknown user.info kernel: vlan0: dev_set_promiscuity(master, 1)
    Jan 1 01:00:10 unknown user.info kernel: device eth0 entered promiscuous mode
    Jan 1 01:00:10 unknown user.info kernel: device vlan0 entered promiscuous mode
    Jan 1 01:00:10 unknown user.info kernel: device eth1 entered promiscuous mode
    Jan 1 01:00:11 unknown user.info kernel: br0: port 2(eth1) entering forwarding state
    Jan 1 01:00:11 unknown user.info kernel: br0: port 1(vlan0) entering forwarding state
    Jan 1 01:00:11 unknown user.warn kernel: vlan1: Setting MAC address to 08 60 6e e0 48 ad.
    Jan 1 01:00:11 unknown user.debug kernel: vlan1: add 33:33:00:00:00:01 mcast address to master interface
    Jan 1 01:00:11 unknown user.debug kernel: vlan1: add 01:00:5e:00:00:01 mcast address to master interface
    Jan 1 01:00:21 unknown authpriv.info dropbear[416]: Running in background
    Jan 1 01:00:22 unknown daemon.info dnsmasq[421]: started, version 2.58 cachesize 1500
    Jan 1 01:00:22 unknown daemon.info dnsmasq[421]: compile time options: IPv6 GNU-getopt no-RTC no-DBus no-i18n DHCP TFTP no-conntrack no-IDN
    Jan 1 01:00:22 unknown daemon.info dnsmasq[421]: asynchronous logging enabled, queue limit is 5 messages
    Jan 1 01:00:22 unknown daemon.info dnsmasq-dhcp[421]: DHCP, IP range 192.168.1.2 -- 192.168.1.51, lease time 1d
    Jan 1 01:00:22 unknown daemon.info dnsmasq[421]: reading /etc/resolv.dnsmasq
    Jan 1 01:00:22 unknown daemon.warn dnsmasq[421]: ignoring nameserver 192.168.1.1 - local interface
    Jan 1 01:00:22 unknown daemon.info dnsmasq[421]: read /etc/hosts - 2 addresses
    Jan 1 01:00:22 unknown daemon.info dnsmasq[421]: read /etc/dnsmasq/hosts/hosts - 3 addresses
    Jan 1 01:00:22 unknown daemon.info dnsmasq-dhcp[421]: read /etc/dnsmasq/dhcp/dhcp-hosts
    Jan 1 01:00:22 unknown user.debug init[1]: starting rstats.
    Jan 1 01:00:22 unknown user.debug init[1]: starting cstats.
    Jan 1 01:00:23 unknown user.notice root: Transmission daemon successfully stoped
    Jan 1 01:00:23 unknown user.info init[1]: Asus RT-N10U: Tomato 1.28.0000 MIPSR2-085V K26 USB BT-VPN
    Jan 1 01:00:23 unknown daemon.info dnsmasq[421]: exiting on receipt of SIGTERM
    Jan 1 01:00:23 unknown daemon.info dnsmasq[450]: started, version 2.58 cachesize 1500
    Jan 1 01:00:23 unknown daemon.info dnsmasq[450]: compile time options: IPv6 GNU-getopt no-RTC no-DBus no-i18n DHCP TFTP no-conntrack no-IDN
    Jan 1 01:00:23 unknown daemon.info dnsmasq[450]: asynchronous logging enabled, queue limit is 5 messages
    Jan 1 01:00:23 unknown daemon.info dnsmasq-dhcp[450]: DHCP, IP range 192.168.1.2 -- 192.168.1.51, lease time 1d
    Jan 1 01:00:23 unknown daemon.info dnsmasq[450]: reading /etc/resolv.dnsmasq
    Jan 1 01:00:23 unknown daemon.warn dnsmasq[450]: ignoring nameserver 192.168.1.1 - local interface
    Jan 1 01:00:23 unknown daemon.info dnsmasq[450]: read /etc/hosts - 2 addresses
    Jan 1 01:00:23 unknown daemon.info dnsmasq[450]: read /etc/dnsmasq/hosts/hosts - 3 addresses
    Jan 1 01:00:23 unknown daemon.info dnsmasq-dhcp[450]: read /etc/dnsmasq/dhcp/dhcp-hosts
     
  7. jeonny

    jeonny Serious Server Member

    shibby build 105-Big-VPN
    BusyBox v1.18.5 (2013-01-20 00:51:06 CET) multi-call binary.


    Tomato v1.28.0501 MIPSR2Toastman-RT-N K26 USB Ext
    root@ASUS:/tmp/home/root# busybox | head -1
    BusyBox v1.18.5 (2013-01-11 15:36:23 ICT) multi-call binary.

    I tested Toastman build reboot is very fast
    v1.28.0501 MIPSR2Toastman-RT-N K26 USB Std : 31 sec
    v1.28.0501 MIPSR2Toastman-RT-N K26 USB Ext : 38 sec
     
  8. jeonny

    jeonny Serious Server Member

    I found another bug.,FTP read from usb disk speed is 45KB/s, writing is okay., Samba works well
    Manifests in all shibby build and Toastman build
     
  9. koitsu

    koitsu Network Guru Member

    There is no FTP server built-in to Toastman builds. Please do not necessarily blame the firmware for issues that may be in third-party software you're installing on your router somehow. You should be aware that Linux sendfile() on these routers is known to perform badly due to a bug in the kernel (which one cannot upgrade due to binary blobs being used for wireless drivers), so if the FTP server you're using uses sendfile, you'll need to disable it -- talk to the person who built the FTP server about that.

    Regardless, please stay focused in this thread on the issue of the long start-up delay. Please do not introduce new problems/unrelated issues. Start a separate thread for the other issue if you wish.
     
  10. koitsu

    koitsu Network Guru Member

    Heh. I'm not sure what to say about this:

    How is it a newer firmware (105-Big-VPN) has an older build timestamp for BusyBox?

    *shakes head* Anyway...

    Note that in 085V BT-VPN there is no log message from dhcpc-event before dropbear starts, indicating no DHCP client on the WAN side is running.

    If you aren't using DHCP on the WAN interface of your Tomato router in 105-Big-VPN (i.e. WAN is set to a Static IP), then the bug is that the DHCP client on the WAN side is still running/trying to get an IP. Please verify this.

    Otherwise this looks like a bug shibby will need to look into and shouldn't be too hard to figure out.
     
  11. shibby20

    shibby20 Network Guru Member

    ok i checked this problem and it`s exists from 097. When we look to changelog:
    [RELEASE] 097
    - Add PPTP VPN client runtime and GUI

    Jya made two commits with pptp.
    - Add PPTP VPN client runtime and GUI
    - kill PPTP client when WAN is stopped

    I merger both, Toastman only one (first). Look into rc/wan.c file and we will see stop_wan function:
    Lines 1059 and 1060 are from second jya`s commit. We have stop pptp client twice and this IMO make a problem with long boot. I removed that both lines and compile test build 105.2:
    http://tomato.groov.pl/download/K26RT-N/testing/tomato-K26USB-1.28.RT-N5x-MIPSR2-105.2-Big-VPN.trx
    i tested this build on my RT-N10U (black) and now i have:
    10secs well result is the same as on 085V.
    @jeonny - please check 105.2 and let us know.

    BTW Toastman`s builds have DHCP disabled by default and this may be a reason why reboot time may be a little faster.
     
    koitsu likes this.
  12. jeonny

    jeonny Serious Server Member

    Log for build 105.2 on my rt-n10u

    Jan 1 01:00:14 unknown user.debug dhcpc-event[464]: 182: pptp peerdns disabled
    Jan 1 01:00:24 unknown authpriv.info dropbear[468]: Running in background

    All my tests are with default router setting, and static ip on my pc.

    we tested build 105.2 reboot speed is the same : 86 sec

    I tried with WAN / Internet disabled, and DHCP Server disabled same result.
     

Share This Page