[prev in list] [next in list] [prev in thread] [next in thread] 

List:       pfsense-dev
Subject:    [pfS-Dev] Multi-WAN-PPPoE Issues on 2.0 Release
From:       holger.bauer () googlemail ! com (Holger Bauer)
Date:       2011-10-20 6:48:20
Message-ID: CAOW5EVJf15MjD8c74i=RTfdi3XTpRha+mTa=Lcs1k8-4eiGvCQ () mail ! gmail ! com
[Download RAW message or body]

Hi,

it has been a while since my last appearance. First: Congratulations
to release 2.0. It's really awesome. I'll soon have a 250 Euros
donation heading to the official paypal account. I know it's too late
for this years hackathon but my boss was on vacation. Bad timing but
you'll receive it soon.

Now to an issue that I have:

I upgraded our work firewall to 2.0 last night by switching to new
hardware and rebuilding the configuration completely from scratch. As
2.0 is capable of managing multiple pppoe WANs I dumped the
Modemrouters that I had in front of the 1.2.3 WANs.

The System now has 5 WANs (1x static IP and 4x PPPoE; 2x 3g as Backup
when everything fails waiting for deployment but not yet installed or
configured).

I'm using the PPPoE periodic reconnect feature to make the forced 24h
disconnect during the night.

When this happened for the first time tonight all PPPoE WANs came back
up fine except one. I can't force it to go only by saving the
WAN-Settings again nor by hitting the connect button. From the logs I
can see that the reconnect seems to work but that the system has some
sort of issue assigning it's IP-address (see log below, I added
">>>>>>"  at the position where I think it falls appart; you have to
read it buttom up).

Interestingly I had the same issue after a reboot but was able to fix
it by saving the PPPoE-Settings again and hitting apply.

If you need additional Info please let me know.

Holger

Oct 20 07:35:46 	ppp: [opt10_link0] LCP: state change Closed --> Initial
Oct 20 07:35:46 	ppp: [opt10_link0] LCP: Down event
Oct 20 07:35:46 	ppp: [opt10_link0] Link: DOWN event
Oct 20 07:35:46 	ppp: [opt10_link0] LCP: LayerFinish
Oct 20 07:35:46 	ppp: [opt10_link0] LCP: state change Closing --> Closed
Oct 20 07:35:46 	ppp: [opt10_link0] LCP: rec'd Terminate Ack #2 (Closing)
Oct 20 07:35:46 	ppp: [opt10_link0] LCP: LayerDown
Oct 20 07:35:46 	ppp: [opt10_link0] LCP: SendTerminateReq #2
Oct 20 07:35:46 	ppp: [opt10] IPCP: state change Closed --> Initial
Oct 20 07:35:46 	ppp: [opt10] IPCP: Down event
Oct 20 07:35:46 	ppp: [opt10] IPCP: state change Stopped --> Closed
Oct 20 07:35:46 	ppp: [opt10] IPCP: Close event
Oct 20 07:35:46 	ppp: [opt10] Bundle: Status update: up 0 links, total
bandwidth 9600 bps
Oct 20 07:35:46 	ppp: [opt10_link0] Link: Leave bundle "opt10"
Oct 20 07:35:46 	ppp: [opt10_link0] LCP: state change Opened --> Closing
Oct 20 07:35:46 	ppp: [opt10_link0] LCP: Close event
Oct 20 07:35:46 	ppp: [opt10_link0] Link: CLOSE event
Oct 20 07:35:46 	ppp: [opt10] Bundle: closing link "opt10_link0"...
Oct 20 07:35:46 	ppp: [opt10] Bundle: No NCPs left. Closing links...
Oct 20 07:35:46 	ppp: [opt10] IPCP: LayerFinish
Oct 20 07:35:46 	ppp: [opt10] IPCP: state change Stopping --> Stopped
Oct 20 07:35:46 	ppp: [opt10] IPCP: rec'd Terminate Ack #4 (Stopping)
Oct 20 07:35:46 	ppp: [opt10] IPCP: LayerDown
Oct 20 07:35:46 	ppp: [opt10] IPCP: SendTerminateReq #4
Oct 20 07:35:46 	ppp: [opt10] IPCP: state change Opened --> Stopping
Oct 20 07:35:46 	ppp: [opt10] IPCP: parameter negotiation failed
Oct 20 07:35:46 	ppp: [opt10] IFACE: IfaceChangeAddr() error, closing IPCP

>>>>>Oct 20 07:35:46 	ppp: [opt10] IFACE: Adding IPv4 address to pppoe2 failed: File exists

Oct 20 07:35:46 	ppp: [opt10] 217.86.242.75 -> 217.5.98.13
Oct 20 07:35:46 	ppp: [opt10] IPCP: LayerUp
Oct 20 07:35:46 	ppp: [opt10] IPCP: state change Ack-Rcvd --> Opened
Oct 20 07:35:46 	ppp: [opt10] IPADDR 217.5.98.13
Oct 20 07:35:46 	ppp: [opt10] IPCP: SendConfigAck #125
Oct 20 07:35:46 	ppp: [opt10] 217.5.98.13 is OK
Oct 20 07:35:46 	ppp: [opt10] IPADDR 217.5.98.13
Oct 20 07:35:46 	ppp: [opt10] IPCP: rec'd Configure Request #125 (Ack-Rcvd)
Oct 20 07:35:46 	ppp: [opt10] IPCP: state change Req-Sent --> Ack-Rcvd
Oct 20 07:35:46 	ppp: [opt10] IPADDR 217.86.242.75
Oct 20 07:35:46 	ppp: [opt10] IPCP: rec'd Configure Ack #3 (Req-Sent)
Oct 20 07:35:45 	ppp: [opt10] IPADDR 217.86.242.75
Oct 20 07:35:45 	ppp: [opt10] IPCP: SendConfigReq #3
Oct 20 07:35:45 	ppp: [opt10] 217.86.242.75 is OK
Oct 20 07:35:45 	ppp: [opt10] IPADDR 217.86.242.75
Oct 20 07:35:45 	ppp: [opt10] IPCP: rec'd Configure Nak #2 (Req-Sent)
Oct 20 07:35:45 	ppp: [opt10] IPADDR 0.0.0.0
Oct 20 07:35:45 	ppp: [opt10] IPCP: SendConfigReq #2
Oct 20 07:35:45 	ppp: [opt10] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Oct 20 07:35:45 	ppp: [opt10] IPCP: rec'd Configure Reject #1 (Req-Sent)
Oct 20 07:35:45 	ppp: [opt10] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Oct 20 07:35:45 	ppp: [opt10] IPADDR 0.0.0.0
Oct 20 07:35:45 	ppp: [opt10] IPCP: SendConfigReq #1
Oct 20 07:35:45 	ppp: [opt10] IPCP: state change Starting --> Req-Sent
Oct 20 07:35:45 	ppp: [opt10] IPCP: Up event
Oct 20 07:35:45 	ppp: [opt10] IPCP: LayerStart
Oct 20 07:35:45 	ppp: [opt10] IPCP: state change Initial --> Starting
Oct 20 07:35:45 	ppp: [opt10] IPCP: Open event
Oct 20 07:35:45 	ppp: [opt10] Bundle: Status update: up 1 link, total
bandwidth 64000 bps
Oct 20 07:35:45 	ppp: [opt10_link0] Link: Join bundle "opt10"
Oct 20 07:35:45 	ppp: [opt10_link0] Link: Matched action 'bundle "opt10" ""'
Oct 20 07:35:45 	ppp: [opt10_link0] LCP: authorization successful
Oct 20 07:35:45 	ppp: [opt10_link0] PAP: rec'd ACK #1 len: 5
Oct 20 07:35:45 	ppp: [opt10_link0] LCP: LayerUp
Oct 20 07:35:45 	ppp: [opt10_link0] PAP: sending REQUEST #1 len: 53
Oct 20 07:35:45 	ppp: [opt10_link0] PAP: using authname
"feste-ip11/1TBG4AODKMGT at t-online-com.de"
Oct 20 07:35:45 	ppp: [opt10_link0] LCP: auth: peer wants PAP, I want nothing
Oct 20 07:35:45 	ppp: [opt10_link0] LCP: state change Ack-Sent --> Opened
Oct 20 07:35:45 	ppp: [opt10_link0] MAGICNUM cd606431
Oct 20 07:35:45 	ppp: [opt10_link0] MRU 1492
Oct 20 07:35:45 	ppp: [opt10_link0] PROTOCOMP
Oct 20 07:35:45 	ppp: [opt10_link0] LCP: rec'd Configure Ack #1 (Ack-Sent)
Oct 20 07:35:45 	ppp: [opt10_link0] LCP: state change Req-Sent --> Ack-Sent
Oct 20 07:35:45 	ppp: [opt10_link0] MAGICNUM 111f9fcb
Oct 20 07:35:45 	ppp: [opt10_link0] AUTHPROTO PAP
Oct 20 07:35:45 	ppp: [opt10_link0] MRU 1492
Oct 20 07:35:45 	ppp: [opt10_link0] LCP: SendConfigAck #148
Oct 20 07:35:45 	ppp: [opt10_link0] MAGICNUM 111f9fcb
Oct 20 07:35:45 	ppp: [opt10_link0] AUTHPROTO PAP
Oct 20 07:35:45 	ppp: [opt10_link0] MRU 1492
Oct 20 07:35:45 	ppp: [opt10_link0] LCP: rec'd Configure Request #148 (Req-Sent)
Oct 20 07:35:45 	ppp: [opt10_link0] MAGICNUM cd606431
Oct 20 07:35:45 	ppp: [opt10_link0] MRU 1492
Oct 20 07:35:45 	ppp: [opt10_link0] PROTOCOMP
Oct 20 07:35:45 	ppp: [opt10_link0] LCP: SendConfigReq #1
Oct 20 07:35:45 	ppp: [opt10_link0] LCP: state change Starting --> Req-Sent
Oct 20 07:35:45 	ppp: [opt10_link0] LCP: Up event
Oct 20 07:35:45 	ppp: [opt10_link0] Link: UP event
Oct 20 07:35:45 	ppp: [opt10_link0] PPPoE: connection successful
Oct 20 07:35:45 	ppp: PPPoE: rec'd ACNAME "FFMX72-ths"
Oct 20 07:35:43 	ppp: [opt10_link0] PPPoE: Connecting to ''
Oct 20 07:35:43 	ppp: [opt10_link0] LCP: LayerStart
Oct 20 07:35:43 	ppp: [opt10_link0] LCP: state change Initial --> Starting
Oct 20 07:35:43 	ppp: [opt10_link0] LCP: Open event
Oct 20 07:35:43 	ppp: [opt10_link0] Link: OPEN event
Oct 20 07:35:43 	ppp: [opt10] Bundle: Interface ng0 created

[prev in list] [next in list] [prev in thread] [next in thread] 

Configure | About | News | Add a list | Sponsored by KoreLogic