Hello,
after upgrading DSL speed from 3MBits to 6Mbits my monowall can't dial in properly (no stable connection). After a look into the logs it looks like the server closes the connection (May 7 21:16:43 mpd: [pppoe] LCP: rec'd Terminate Request #117 (Opened)). The problem occurse on two different DSL lines with different hardware.
Hardware setup on both lines:
mono 1.33 - DSL modem - PSTN
On my private line I tested other DSL modems (Telekom Speedport, DLink, Fritzbox with modem only setting) and mono on other hardware (alix 2d..., soekris net5501, vmware) with my old config and configs from scratch, but the problem persists. Changing from mono to pfsense or zeroshell solves the problem and the connection is stable again. Connection other hardware routers (fritzbox, linksys, funkwerk) also solves the problem. Connection mono behind another router breaks all (my) ipsec connections.
After googling I found a post from manuel(Sorry, can't remember the url and can't find it right anymore) about a beta version of mono 1.3 which basically said that checking "Allow DNS server list to be overridden by DHCP/PPP on WAN" solves the problem. After changing and rebooting the connection SOMETIMES works but not always.
Comparing the mpd config file on pfsense and mono also doesn’t bring up a difference.
Thanks,
Christoph
Complete log since bootup:
May 7 21:14:53 syslogd: kernel boot file is /kernel
May 7 21:14:53 kernel: Waiting (max 60 seconds) for system process `vnlru' to stop...done
May 7 21:14:53 kernel: Waiting (max 60 seconds) for system process `bufdaemon' to stop...done
May 7 21:14:53 kernel: Waiting (max 60 seconds) for system process `syncer' to stop...
May 7 21:14:53 kernel: Syncing disks, vnodes remaining...0 0 done
May 7 21:14:53 kernel: All buffers synced.
May 7 21:14:53 kernel: Uptime: 41s
May 7 21:14:53 kernel: ukphy0: detached
May 7 21:14:53 kernel: miibus0: detached
May 7 21:14:53 kernel: ukphy1: detached
May 7 21:14:53 kernel: miibus1: detached
May 7 21:14:53 kernel: ukphy2: detached
May 7 21:14:53 kernel: miibus2: detached
May 7 21:14:53 kernel: ukphy3: detached
May 7 21:14:53 kernel: miibus3: detached
May 7 21:14:53 kernel: Copyright (c) 1992-2008 The FreeBSD Project.
May 7 21:14:53 kernel: Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994
May 7 21:14:53 kernel: The Regents of the University of California. All rights reserved.
May 7 21:14:53 kernel: FreeBSD is a registered trademark of The FreeBSD Foundation.
May 7 21:14:53 kernel: FreeBSD 6.4-RELEASE-p5 #0: Sun Jan 9 22:24:57 CET 2011
May 7 21:14:53 kernel: root@mb64.neon1.net:/usr/src/sys/i386/compile/M0N0WALL_EMBEDDED
May 7 21:14:53 kernel: Timecounter "i8254" frequency 1193182 Hz quality 0
May 7 21:14:53 kernel: CPU: Geode(TM) Integrated Processor by AMD PCS (433.25-MHz 586-class CPU)
May 7 21:14:53 kernel: Origin = "AuthenticAMD" Id = 0x5a2 Stepping = 2
May 7 21:14:53 kernel: Features=0x88a93d<FPU,DE,PSE,TSC,MSR,CX8,SEP,PGE,CMOV,CLFLUSH,MMX>
May 7 21:14:53 kernel: AMD Features=0xc0400000<MMX+,3DNow!+,3DNow!>
May 7 21:14:53 kernel: real memory = 268435456 (256 MB)
May 7 21:14:53 kernel: avail memory = 236527616 (225 MB)
May 7 21:14:53 kernel: wlan: mac acl policy registered
May 7 21:14:53 kernel: K6-family MTRR support enabled (2 registers)
May 7 21:14:53 kernel: ath_hal: 0.9.20.3 (AR5210, AR5211, AR5212, RF5111, RF5112, RF2413, RF5413)
May 7 21:14:53 kernel: cpu0 on motherboard
May 7 21:14:53 kernel: pcib0: <Host to PCI bridge> pcibus 0 on motherboard
May 7 21:14:53 kernel: pci0: <PCI bus> on pcib0
May 7 21:14:53 kernel: Geode LX: Soekris net5501 comBIOS ver. 1.33c 20080626 Copyright (C) 2000-2008
May 7 21:14:53 kernel: pci0: <encrypt/decrypt, entertainment crypto> at device 1.2 (no driver attached)
May 7 21:14:53 kernel: vr0: <VIA VT6105M Rhine III 10/100BaseTX> port 0xe100-0xe1ff mem 0xa0004000-0xa00040ff irq 11 at device 6.0 on pci0
May 7 21:14:53 kernel: vr0: Quirks: 0x2
May 7 21:14:53 kernel: miibus0: <MII bus> on vr0
May 7 21:14:53 kernel: ukphy0: <Generic IEEE 802.3u media interface> on miibus0
May 7 21:14:53 kernel: ukphy0: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto
May 7 21:14:53 kernel: vr0: Ethernet address: 00:00:24:ce:3e:60
May 7 21:14:53 kernel: vr1: <VIA VT6105M Rhine III 10/100BaseTX> port 0xe200-0xe2ff mem 0xa0004100-0xa00041ff irq 5 at device 7.0 on pci0
May 7 21:14:53 kernel: vr1: Quirks: 0x2
May 7 21:14:53 kernel: miibus1: <MII bus> on vr1
May 7 21:14:53 kernel: ukphy1: <Generic IEEE 802.3u media interface> on miibus1
May 7 21:14:53 kernel: ukphy1: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto
May 7 21:14:53 kernel: vr1: Ethernet address: 00:00:24:ce:3e:61
May 7 21:14:53 kernel: vr2: <VIA VT6105M Rhine III 10/100BaseTX> port 0xe300-0xe3ff mem 0xa0004200-0xa00042ff irq 9 at device 8.0 on pci0
May 7 21:14:53 kernel: vr2: Quirks: 0x2
May 7 21:14:53 kernel: miibus2: <MII bus> on vr2
May 7 21:14:53 kernel: ukphy2: <Generic IEEE 802.3u media interface> on miibus2
May 7 21:14:53 kernel: ukphy2: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto
May 7 21:14:53 kernel: vr2: Ethernet address: 00:00:24:ce:3e:62
May 7 21:14:53 kernel: vr3: <VIA VT6105M Rhine III 10/100BaseTX> port 0xe400-0xe4ff mem 0xa0004300-0xa00043ff irq 12 at device 9.0 on pci0
May 7 21:14:53 kernel: vr3: Quirks: 0x2
May 7 21:14:53 kernel: miibus3: <MII bus> on vr3
May 7 21:14:53 kernel: ukphy3: <Generic IEEE 802.3u media interface> on miibus3
May 7 21:14:53 kernel: ukphy3: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto
May 7 21:14:53 kernel: vr3: Ethernet address: 00:00:24:ce:3e:63
May 7 21:14:53 kernel: isab0: <PCI-ISA bridge> at device 20.0 on pci0
May 7 21:14:53 kernel: isa0: <ISA bus> on isab0
May 7 21:14:53 kernel: atapci0: <AMD CS5536 UDMA100 controller> port 0x1f0-0x1f7,0x3f6,0x170-0x177,0x376,0xe000-0xe00f at device 20.2 on pci0
May 7 21:14:53 kernel: ata0: <ATA channel 0> on atapci0
May 7 21:14:53 kernel: ata1: <ATA channel 1> on atapci0
May 7 21:14:53 kernel: ohci0: <OHCI (generic) USB controller> mem 0xa0005000-0xa0005fff irq 15 at device 21.0 on pci0
May 7 21:14:53 kernel: ohci0: [GIANT-LOCKED]
May 7 21:14:53 kernel: usb0: OHCI version 1.0, legacy support
May 7 21:14:53 kernel: usb0: <OHCI (generic) USB controller> on ohci0
May 7 21:14:53 kernel: usb0: USB revision 1.0
May 7 21:14:53 kernel: uhub0: AMD OHCI root hub, class 9/0, rev 1.00/1.00, addr 1
May 7 21:14:53 kernel: uhub0: 4 ports with 4 removable, self powered
May 7 21:14:53 kernel: ehci0: <AMD CS5536 USB 2.0 controller> mem 0xa0006000-0xa0006fff irq 15 at device 21.1 on pci0
May 7 21:14:53 kernel: ehci0: [GIANT-LOCKED]
May 7 21:14:53 kernel: usb1: EHCI version 1.0
May 7 21:14:53 kernel: usb1: companion controller, 4 ports each: usb0
May 7 21:14:53 kernel: usb1: <AMD CS5536 USB 2.0 controller> on ehci0
May 7 21:14:53 kernel: usb1: USB revision 2.0
May 7 21:14:53 kernel: uhub1: AMD EHCI root hub, class 9/0, rev 2.00/1.00, addr 1
May 7 21:14:53 kernel: uhub1: 4 ports with 4 removable, self powered
May 7 21:14:53 kernel: pmtimer0 on isa0
May 7 21:14:53 kernel: orm0: <ISA Option ROM> at iomem 0xc8000-0xd27ff on isa0
May 7 21:14:53 kernel: sio0 at port 0x3f8-0x3ff irq 4 flags 0x10 on isa0
May 7 21:14:53 kernel: sio0: type 16550A, console
May 7 21:14:53 kernel: sio1 at port 0x2f8-0x2ff irq 3 on isa0
May 7 21:14:53 kernel: sio1: type 16550A
May 7 21:14:53 kernel: Timecounter "TSC" frequency 433250417 Hz quality 800
May 7 21:14:53 kernel: Timecounters tick every 1.000 msec
May 7 21:14:53 kernel: Fast IPsec: Initialized Security Association Processing.
May 7 21:14:53 kernel: IP Filter: v4.1.33 initialized. Default = block all, Logging = enabled
May 7 21:14:53 kernel: md0: Preloaded image </mfsroot> 16777216 bytes at 0xc086b0e8
May 7 21:14:53 kernel: ad0: 1903MB <ELITE PRO CF CARD 2GB 20070315> at ata0-master PIO4
May 7 21:14:53 kernel: Trying to mount root from ufs:/dev/md0
May 7 21:14:53 dnsmasq[130]: started, version 2.45 cachesize 150
May 7 21:14:53 dnsmasq[130]: compile time options: IPv6 GNU-getopt BSD-bridge ISC-leasefile no-DBus no-I18N TFTP
May 7 21:14:53 dnsmasq[130]: no servers found in /etc/resolv.conf, will retry
May 7 21:14:53 dnsmasq[130]: no servers found in /etc/resolv.conf, will retry
May 7 21:14:53 dnsmasq[130]: read /etc/hosts - 2 addresses
May 7 21:14:53 dhcpd: Internet Systems Consortium DHCP Server V3.0.7
May 7 21:14:53 dhcpd: Copyright 2004-2008 Internet Systems Consortium.
May 7 21:14:53 dhcpd: All rights reserved.
May 7 21:14:53 dhcpd: For info, please visit http://www.isc.org/sw/dhcp/
May 7 21:14:57 kernel: vr0: watchdog timeout
May 7 21:15:10 dnsmasq[130]: exiting on receipt of SIGTERM
May 7 21:15:11 dnsmasq[270]: started, version 2.45 cachesize 150
May 7 21:15:11 dnsmasq[270]: compile time options: IPv6 GNU-getopt BSD-bridge ISC-leasefile no-DBus no-I18N TFTP
May 7 21:15:11 dnsmasq[270]: no servers found in /etc/resolv.conf, will retry
May 7 21:15:11 dnsmasq[270]: no servers found in /etc/resolv.conf, will retry
May 7 21:15:11 dnsmasq[270]: read /etc/hosts - 2 addresses
May 7 21:15:11 kernel: vr0: watchdog timeout
May 7 21:15:32 dnsmasq[270]: exiting on receipt of SIGTERM
May 7 21:15:33 dnsmasq[415]: started, version 2.45 cachesize 150
May 7 21:15:33 dnsmasq[415]: compile time options: IPv6 GNU-getopt BSD-bridge ISC-leasefile no-DBus no-I18N TFTP
May 7 21:15:33 dnsmasq[415]: no servers found in /etc/resolv.conf, will retry
May 7 21:15:33 dnsmasq[415]: no servers found in /etc/resolv.conf, will retry
May 7 21:15:33 dnsmasq[415]: read /etc/hosts - 2 addresses
May 7 21:15:33 kernel: vr0: watchdog timeout
May 7 21:15:40 kernel: vr0: link state changed to UP
May 7 21:15:40 kernel: vlan0: link state changed to UP
May 7 21:15:48 dhclient: DHCPDISCOVER on vlan0 to 255.255.255.255 port 67 interval 4
May 7 21:15:52 dhclient: DHCPDISCOVER on vlan0 to 255.255.255.255 port 67 interval 7
May 7 21:15:59 dhclient: DHCPDISCOVER on vlan0 to 255.255.255.255 port 67 interval 14
May 7 21:16:13 dhclient: DHCPDISCOVER on vlan0 to 255.255.255.255 port 67 interval 17
May 7 21:16:30 dhclient: DHCPDISCOVER on vlan0 to 255.255.255.255 port 67 interval 7
May 7 21:16:39 mpd: Multi-link PPP daemon for FreeBSD
May 7 21:16:39 mpd:
May 7 21:16:39 mpd: process 614 started, version 4.4.1 (root@mb64.neon1.net 21:35 23-Jul-2009)
May 7 21:16:39 mpd: [pppoe] using interface ng0
May 7 21:16:39 mpd: [pppoe] link: OPEN event
May 7 21:16:39 mpd: [pppoe] LCP: Open event
May 7 21:16:39 mpd: [pppoe] LCP: state change Initial --> Starting
May 7 21:16:39 mpd: [pppoe] LCP: LayerStart
May 7 21:16:39 mpd: [pppoe] PPPoE: Connecting to ''
May 7 21:16:39 mpd: PPPoE: rec'd ACNAME "BONR72-se800-B"
May 7 21:16:39 mpd: [pppoe] PPPoE: connection successful
May 7 21:16:39 mpd: [pppoe] link: UP event
May 7 21:16:39 mpd: [pppoe] link: origination is local
May 7 21:16:39 mpd: [pppoe] LCP: Up event
May 7 21:16:39 mpd: [pppoe] LCP: state change Starting --> Req-Sent
May 7 21:16:39 mpd: [pppoe] LCP: SendConfigReq #1
May 7 21:16:39 mpd: MRU 1492
May 7 21:16:39 mpd: MAGICNUM 58011f56
May 7 21:16:39 mpd: [pppoe] LCP: rec'd Configure Request #116 (Req-Sent)
May 7 21:16:39 mpd: MRU 1492
May 7 21:16:39 mpd: AUTHPROTO PAP
May 7 21:16:39 mpd: MAGICNUM 7977b346
May 7 21:16:39 mpd: [pppoe] LCP: SendConfigAck #116
May 7 21:16:39 mpd: MRU 1492
May 7 21:16:39 mpd: AUTHPROTO PAP
May 7 21:16:39 mpd: MAGICNUM 7977b346
May 7 21:16:39 mpd: [pppoe] LCP: state change Req-Sent --> Ack-Sent
May 7 21:16:39 mpd: [pppoe] LCP: rec'd Configure Ack #1 (Ack-Sent)
May 7 21:16:39 mpd: MRU 1492
May 7 21:16:39 mpd: MAGICNUM 58011f56
May 7 21:16:39 mpd: [pppoe] LCP: state change Ack-Sent --> Opened
May 7 21:16:39 mpd: [pppoe] LCP: auth: peer wants PAP, I want nothing
May 7 21:16:39 mpd: [pppoe] PAP: using authname "xxx"
May 7 21:16:39 mpd: [pppoe] PAP: sending REQUEST len:50
May 7 21:16:39 mpd: [pppoe] LCP: LayerUp
May 7 21:16:39 mpd: [pppoe] PAP: rec'd ACK #1
May 7 21:16:39 mpd: [pppoe] LCP: authorization successful
May 7 21:16:39 mpd: [pppoe] Bundle up: 1 link, total bandwidth 64000 bps
May 7 21:16:39 mpd: [pppoe] IPCP: Open event
May 7 21:16:39 mpd: [pppoe] IPCP: state change Initial --> Starting
May 7 21:16:39 mpd: [pppoe] IPCP: LayerStart
May 7 21:16:39 mpd: [pppoe] IPCP: Up event
May 7 21:16:39 mpd: [pppoe] IPCP: state change Starting --> Req-Sent
May 7 21:16:39 mpd: [pppoe] IPCP: SendConfigReq #1
May 7 21:16:39 mpd: IPADDR 0.0.0.0
May 7 21:16:39 mpd: COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
May 7 21:16:39 mpd: PRIDNS 0.0.0.0
May 7 21:16:39 mpd: SECDNS 0.0.0.0
May 7 21:16:39 mpd: [pppoe] IPCP: rec'd Configure Reject #1 (Req-Sent)
May 7 21:16:39 mpd: COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
May 7 21:16:39 mpd: [pppoe] IPCP: SendConfigReq #2
May 7 21:16:39 mpd: IPADDR 0.0.0.0
May 7 21:16:39 mpd: PRIDNS 0.0.0.0
May 7 21:16:39 mpd: SECDNS 0.0.0.0
May 7 21:16:39 mpd: [pppoe] IPCP: rec'd Configure Nak #2 (Req-Sent)
May 7 21:16:39 mpd: IPADDR 91.10.23.158
May 7 21:16:39 mpd: 91.10.23.158 is OK
May 7 21:16:39 mpd: PRIDNS 217.237.150.115
May 7 21:16:39 mpd: SECDNS 217.237.151.205
May 7 21:16:39 mpd: [pppoe] IPCP: SendConfigReq #3
May 7 21:16:39 mpd: IPADDR 91.10.23.158
May 7 21:16:39 mpd: PRIDNS 217.237.150.115
May 7 21:16:39 mpd: SECDNS 217.237.151.205
May 7 21:16:41 mpd: [pppoe] IPCP: SendConfigReq #4
May 7 21:16:41 mpd: IPADDR 91.10.23.158
May 7 21:16:41 mpd: PRIDNS 217.237.150.115
May 7 21:16:41 mpd: SECDNS 217.237.151.205
May 7 21:16:42 dnsmasq[415]: exiting on receipt of SIGTERM
May 7 21:16:42 mpd: [pppoe] IPCP: rec'd Configure Request #152 (Req-Sent)
May 7 21:16:42 mpd: IPADDR 217.0.119.53
May 7 21:16:42 mpd: 217.0.119.53 is OK
May 7 21:16:42 mpd: [pppoe] IPCP: SendConfigAck #152
May 7 21:16:42 mpd: IPADDR 217.0.119.53
May 7 21:16:42 mpd: [pppoe] IPCP: state change Req-Sent --> Ack-Sent
May 7 21:16:43 dnsmasq[688]: started, version 2.45 cachesize 150
May 7 21:16:43 dnsmasq[688]: compile time options: IPv6 GNU-getopt BSD-bridge ISC-leasefile no-DBus no-I18N TFTP
May 7 21:16:43 dnsmasq[688]: no servers found in /etc/resolv.conf, will retry
May 7 21:16:43 dnsmasq[688]: no servers found in /etc/resolv.conf, will retry
May 7 21:16:43 dnsmasq[688]: read /etc/hosts - 2 addresses
May 7 21:16:43 mpd: [pppoe] IPCP: SendConfigReq #5
May 7 21:16:43 mpd: IPADDR 91.10.23.158
May 7 21:16:43 mpd: PRIDNS 217.237.150.115
May 7 21:16:43 mpd: SECDNS 217.237.151.205
May 7 21:16:43 mpd: [pppoe] LCP: rec'd Terminate Request #117 (Opened)
May 7 21:16:43 mpd: [pppoe] LCP: state change Opened --> Stopping
May 7 21:16:43 mpd: [pppoe] AUTH: Accounting data for user : 4 seconds, 109 octets in, 248 octets out
May 7 21:16:43 mpd: [pppoe] Bundle up: 0 links, total bandwidth 9600 bps
May 7 21:16:43 mpd: [pppoe] IPCP: Close event
May 7 21:16:43 mpd: [pppoe] IPCP: state change Ack-Sent --> Closing
May 7 21:16:43 mpd: [pppoe] IPCP: SendTerminateReq #6
May 7 21:16:43 mpd: [pppoe] error writing len 8 frame to bypass: Network is down
May 7 21:16:43 mpd: [pppoe] IPCP: Down event
May 7 21:16:43 mpd: [pppoe] IPCP: LayerFinish
May 7 21:16:43 mpd: [pppoe] No NCPs left. Closing links...
May 7 21:16:43 mpd: [pppoe] closing link "pppoe"...
May 7 21:16:43 mpd: [pppoe] IPCP: state change Closing --> Initial
May 7 21:16:43 mpd: [pppoe] Last link has gone and no noretry option, will reopen in 3 seconds