2.0RC1 : PPPoE not working yet

eri--

You mean that you want to triger a connection reload when the modem is put in?

wallabybob

@ermal:

I need the system logs.

which ones? ppp and system?
and is there a simple way to extract them or do I just download the log file?

In case you haven't figured t out already, truncated forms of the system logs are available in the web GUI from Status -> System Logs, click on the appropriate tab.

If you want the full system log(s) they can be displayed by shell command # clog /var/log/ (replace <file>by the name of the appropriate file, e.g. system.log, ppp.log etc)
The logs are "circular buffer" files which are a fixed size. Once the file is full old entries drop out to make room for new entries.</file>

greybeard

@ermal:

You mean that you want to triger a connection reload when the modem is put in?

anything that causes the link to drop should cause a connection reload. In this instance I've used a USB disconnect but the same issue occurs with power cycling the ASDL modem, disconnecting the ethernet connection to the ADSL, disconnecting the phone line into the ADSL. Unfortunately my options for testing a failover with a USB HSDPA modem are limited to 'pulling the plug'.

What I've seen is that there appears to be some change of link state conditions that aren't detected by the pfsense code and subsequantly there are issues in (re)establishing a WAN link.
It may be that it is being highlighted due to the low speed of my machine ( 800MHz ).

eri--

Can you give the related system log on this?
The section when the usb is detached and reattached and any other output in the 'vicinity' of these.

brunin82

hi all!!

I have the same problem since around beta2 (probably before) and i got it to work just clicking around the advanced options (/interfaces_ppps_edit.php). Only selecting the link interface and saving.
Although they are working, i can't access the webgui through any pppoe interface. I have created the corresponding rules to allow https but nothing…
I have 3 wan (2 pppoe, 1 DHCP) 1 LAN and i can only access the webgui through the DHCP o the LAN interface.
Any ideas?

2.0-RC1 (i386)
built on Mon Mar 14 20:46:06 EDT 2011

Bruno

greybeard

in this first log the usb/hsdpa modem reconnects ok

Mar 19 08:35:44 pfsense ppp: [opt3_link0] Link: reconnection attempt 16
Mar 19 08:35:44 pfsense ppp: [opt3_link0] can't open /dev/cuaU0.0: No such file or directory
Mar 19 08:35:44 pfsense ppp: [opt3_link0] Link: DOWN event
Mar 19 08:35:44 pfsense ppp: [opt3_link0] LCP: Down event
Mar 19 08:35:44 pfsense ppp: [opt3_link0] Link: reconnection attempt 17 in 4 seconds
Mar 19 08:35:45 pfsense kernel: ugen0.2: <huawei technologies="">at usbus0
Mar 19 08:35:45 pfsense kernel: ugen0.2: <huawei technologies="">at usbus0 (disconnected)
Mar 19 08:35:46 pfsense kernel: stray irq7
Mar 19 08:35:47 pfsense kernel: ugen0.2: <huawei technologies="">at usbus0
Mar 19 08:35:47 pfsense kernel: u3g0: <data interface="">on usbus0
Mar 19 08:35:47 pfsense kernel: u3g0: Found 2 ports.
Mar 19 08:35:47 pfsense kernel: umass0: <huawei 0="" 2="" technologies="" huawei="" mobile,="" class="" 0,="" rev="" 1.10="" 0.00,="" addr="">on usbus0
Mar 19 08:35:48 pfsense ppp: [opt3_link0] Link: reconnection attempt 17
Mar 19 08:35:48 pfsense ppp: [opt3_link0] chat: Detected Hayes compatible modem.
Mar 19 08:35:49 pfsense kernel: (probe0:umass-sim0:0:0:0): TEST UNIT READY. CDB: 0 0 0 0 0 0 
Mar 19 08:35:49 pfsense kernel: (probe0:umass-sim0:0:0:0): CAM status: SCSI Status Error
Mar 19 08:35:49 pfsense kernel: (probe0:umass-sim0:0:0:0): SCSI status: Check Condition
Mar 19 08:35:49 pfsense kernel: (probe0:umass-sim0:0:0:0): SCSI sense: NOT READY asc:3a,0 (Medium not present)
Mar 19 08:35:49 pfsense kernel: cd0 at umass-sim0 bus 0 scbus0 target 0 lun 0
Mar 19 08:35:49 pfsense kernel: cd0: <huawei mass="" storage="" 2.31="">Removable CD-ROM SCSI-2 device 
Mar 19 08:35:49 pfsense kernel: cd0: 1.000MB/s transfers
Mar 19 08:35:49 pfsense kernel: cd0: Attempt to query device size failed: NOT READY, Medium not present
Mar 19 08:35:49 pfsense ppp: [opt3_link0] chat: Dialing server at *99#...
Mar 19 08:35:49 pfsense ppp: [opt3_link0] chat: Connected at an unknown speed.
Mar 19 08:35:49 pfsense ppp: [opt3_link0] MODEM: chat script succeeded
Mar 19 08:35:49 pfsense ppp: [opt3_link0] Link: UP event
Mar 19 08:35:49 pfsense ppp: [opt3_link0] LCP: Up event
Mar 19 08:35:49 pfsense ppp: [opt3_link0] LCP: state change Starting --> Req-Sent
Mar 19 08:35:49 pfsense ppp: [opt3_link0] LCP: SendConfigReq #2
Mar 19 08:35:49 pfsense ppp: [opt3_link0]   ACFCOMP
Mar 19 08:35:49 pfsense ppp: [opt3_link0]   PROTOCOMP
Mar 19 08:35:49 pfsense ppp: [opt3_link0]   ACCMAP 0x000a0000
Mar 19 08:35:49 pfsense ppp: [opt3_link0]   MRU 1500
Mar 19 08:35:49 pfsense ppp: [opt3_link0]   MAGICNUM f9e01515
Mar 19 08:35:49 pfsense apinger: ALARM: GW_WAN(203.56.14.17)  *** GW_WANdown ***
Mar 19 08:35:49 pfsense ppp: [opt3_link0] LCP: rec'd Configure Request #0 (Req-Sent)
Mar 19 08:35:49 pfsense ppp: [opt3_link0]   MRU 1440
Mar 19 08:35:49 pfsense ppp: [opt3_link0]   ACCMAP 0x00000000
Mar 19 08:35:49 pfsense ppp: [opt3_link0]   AUTHPROTO CHAP MD5
Mar 19 08:35:49 pfsense ppp: [opt3_link0]   MAGICNUM 00c85e42
Mar 19 08:35:49 pfsense ppp: [opt3_link0]   PROTOCOMP
Mar 19 08:35:49 pfsense ppp: [opt3_link0]   ACFCOMP
Mar 19 08:35:49 pfsense ppp: [opt3_link0] LCP: SendConfigAck #0
Mar 19 08:35:49 pfsense ppp: [opt3_link0]   MRU 1440
Mar 19 08:35:49 pfsense ppp: [opt3_link0]   ACCMAP 0x00000000
Mar 19 08:35:49 pfsense ppp: [opt3_link0]   AUTHPROTO CHAP MD5
Mar 19 08:35:49 pfsense ppp: [opt3_link0]   MAGICNUM 00c85e42
Mar 19 08:35:49 pfsense ppp: [opt3_link0]   PROTOCOMP
Mar 19 08:35:49 pfsense ppp: [opt3_link0]   ACFCOMP
Mar 19 08:35:49 pfsense ppp: [opt3_link0] LCP: state change Req-Sent --> Ack-Sent
Mar 19 08:35:49 pfsense ppp: [opt3_link0] LCP: rec'd Configure Ack #2 (Ack-Sent)
Mar 19 08:35:49 pfsense ppp: [opt3_link0]   ACFCOMP
Mar 19 08:35:49 pfsense ppp: [opt3_link0]   PROTOCOMP
Mar 19 08:35:49 pfsense ppp: [opt3_link0]   ACCMAP 0x000a0000
Mar 19 08:35:49 pfsense ppp: [opt3_link0]   MRU 1500
Mar 19 08:35:49 pfsense ppp: [opt3_link0]   MAGICNUM f9e01515
Mar 19 08:35:49 pfsense ppp: [opt3_link0] LCP: state change Ack-Sent --> Opened
Mar 19 08:35:49 pfsense ppp: [opt3_link0] LCP: auth: peer wants CHAP, I want nothing
Mar 19 08:35:49 pfsense ppp: [opt3_link0] LCP: LayerUp
Mar 19 08:35:49 pfsense ppp: [opt3_link0] LCP: rec'd Discard Request #1 (Opened)
Mar 19 08:35:49 pfsense ppp: [opt3_link0] CHAP: rec'd CHALLENGE #1 len: 35
Mar 19 08:35:49 pfsense ppp: [opt3_link0]   Name: "UMTS_CHAP_SRVR"
Mar 19 08:35:49 pfsense ppp: [opt3_link0] CHAP: Using authname "blank"
Mar 19 08:35:49 pfsense ppp: [opt3_link0] CHAP: sending RESPONSE #1 len: 26
Mar 19 08:35:49 pfsense ppp: [opt3_link0] CHAP: rec'd SUCCESS #1 len: 4
Mar 19 08:35:49 pfsense ppp: [opt3_link0] LCP: authorization successful
Mar 19 08:35:49 pfsense ppp: [opt3_link0] Link: Matched action 'bundle "opt3" ""'
Mar 19 08:35:49 pfsense ppp: [opt3_link0] Link: Join bundle "opt3"
Mar 19 08:35:49 pfsense ppp: [opt3] Bundle: Status update: up 1 link, total bandwidth 28800 bps
Mar 19 08:35:49 pfsense ppp: [opt3] IPCP: Open event
Mar 19 08:35:49 pfsense ppp: [opt3] IPCP: state change Initial --> Starting
Mar 19 08:35:49 pfsense ppp: [opt3] IPCP: LayerStart
Mar 19 08:35:49 pfsense ppp: [opt3] IPCP: Up event
Mar 19 08:35:49 pfsense ppp: [opt3] IPCP: state change Starting --> Req-Sent
Mar 19 08:35:49 pfsense ppp: [opt3] IPCP: SendConfigReq #7
Mar 19 08:35:49 pfsense ppp: [opt3]   IPADDR 0.0.0.0
Mar 19 08:35:49 pfsense ppp: [opt3]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Mar 19 08:35:49 pfsense ppp: [opt3]   PRIDNS 0.0.0.0
Mar 19 08:35:49 pfsense ppp: [opt3]   SECDNS 0.0.0.0
Mar 19 08:35:49 pfsense apinger: alarm canceled: GW_WAN(203.56.14.17)  *** GW_WANdown ***
Mar 19 08:35:50 pfsense ppp: [opt3] IPCP: rec'd Configure Nak #7 (Req-Sent)
Mar 19 08:35:50 pfsense ppp: [opt3]   PRIDNS 10.11.12.13
Mar 19 08:35:50 pfsense ppp: [opt3]   SECDNS 10.11.12.14
Mar 19 08:35:50 pfsense ppp: [opt3]   PRINBNS 10.11.12.13
Mar 19 08:35:50 pfsense ppp: [opt3]   SECNBNS 10.11.12.14
Mar 19 08:35:50 pfsense ppp: [opt3] IPCP: SendConfigReq #8
Mar 19 08:35:50 pfsense ppp: [opt3]   IPADDR 0.0.0.0
Mar 19 08:35:50 pfsense ppp: [opt3]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Mar 19 08:35:50 pfsense ppp: [opt3]   PRIDNS 10.11.12.13
Mar 19 08:35:50 pfsense ppp: [opt3]   SECDNS 10.11.12.14
Mar 19 08:35:51 pfsense ppp: [opt3] IPCP: rec'd Configure Nak #8 (Req-Sent)
Mar 19 08:35:51 pfsense ppp: [opt3]   PRIDNS 10.11.12.13
Mar 19 08:35:51 pfsense ppp: [opt3]   SECDNS 10.11.12.14
Mar 19 08:35:51 pfsense ppp: [opt3]   PRINBNS 10.11.12.13
Mar 19 08:35:51 pfsense ppp: [opt3]   SECNBNS 10.11.12.14
Mar 19 08:35:51 pfsense ppp: [opt3] IPCP: SendConfigReq #9
Mar 19 08:35:51 pfsense ppp: [opt3]   IPADDR 0.0.0.0
Mar 19 08:35:51 pfsense ppp: [opt3]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Mar 19 08:35:51 pfsense ppp: [opt3]   PRIDNS 10.11.12.13
Mar 19 08:35:51 pfsense ppp: [opt3]   SECDNS 10.11.12.14
Mar 19 08:35:52 pfsense ppp: [opt3] IPCP: rec'd Configure Request #0 (Req-Sent)
Mar 19 08:35:52 pfsense ppp: [opt3] IPCP: SendConfigAck #0
Mar 19 08:35:52 pfsense ppp: [opt3] IPCP: state change Req-Sent --> Ack-Sent
Mar 19 08:35:52 pfsense ppp: [opt3] IPCP: rec'd Configure Reject #9 (Ack-Sent)
Mar 19 08:35:52 pfsense ppp: [opt3]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Mar 19 08:35:52 pfsense ppp: [opt3] IPCP: SendConfigReq #10
Mar 19 08:35:52 pfsense ppp: [opt3]   IPADDR 0.0.0.0
Mar 19 08:35:52 pfsense ppp: [opt3]   PRIDNS 10.11.12.13
Mar 19 08:35:52 pfsense ppp: [opt3]   SECDNS 10.11.12.14
Mar 19 08:35:52 pfsense ppp: [opt3] IPCP: rec'd Configure Nak #10 (Ack-Sent)
Mar 19 08:35:52 pfsense ppp: [opt3]   IPADDR 121.91.213.142
Mar 19 08:35:52 pfsense ppp: [opt3]     121.91.213.142 is OK
Mar 19 08:35:52 pfsense ppp: [opt3]   PRIDNS 123.200.191.17
Mar 19 08:35:52 pfsense ppp: [opt3]   SECDNS 123.200.191.18
Mar 19 08:35:52 pfsense ppp: [opt3] IPCP: SendConfigReq #11
Mar 19 08:35:52 pfsense ppp: [opt3]   IPADDR 121.91.213.142
Mar 19 08:35:52 pfsense ppp: [opt3]   PRIDNS 123.200.191.17
Mar 19 08:35:52 pfsense ppp: [opt3]   SECDNS 123.200.191.18
Mar 19 08:35:52 pfsense ppp: [opt3] IPCP: rec'd Configure Ack #11 (Ack-Sent)
Mar 19 08:35:52 pfsense ppp: [opt3]   IPADDR 121.91.213.142
Mar 19 08:35:52 pfsense ppp: [opt3]   PRIDNS 123.200.191.17
Mar 19 08:35:52 pfsense ppp: [opt3]   SECDNS 123.200.191.18
Mar 19 08:35:52 pfsense ppp: [opt3] IPCP: state change Ack-Sent --> Opened
Mar 19 08:35:52 pfsense ppp: [opt3] IPCP: LayerUp
Mar 19 08:35:52 pfsense ppp: [opt3]   121.91.213.142 -> 10.64.64.0
Mar 19 08:35:52 pfsense check_reload_status: Rewriting resolv.conf
Mar 19 08:35:53 pfsense check_reload_status: rc.newwanip starting ppp1
Mar 19 08:35:53 pfsense ppp: [opt3] IFACE: Up event
Mar 19 08:35:54 pfsense php: : rc.newwanip: Informational is starting ppp1.
Mar 19 08:35:54 pfsense php: : rc.newwanip: on (IP address: 121.91.213.142) (interface: opt3) (real interface: ppp1).
Mar 19 08:35:56 pfsense php: : ROUTING: change default route to 10.64.64.0
Mar 19 08:35:56 pfsense php: : Removing static route for monitor 123.200.191.17 and adding a new route through 10.64.64.0
Mar 19 08:35:56 pfsense php: : Removing static route for monitor 203.56.14.17 and adding a new route through 203.56.14.7
Mar 19 08:35:56 pfsense check_reload_status: reloading filter
Mar 19 08:35:56 pfsense check_reload_status: reloading filter
Mar 19 08:35:56 pfsense apinger: Exiting on signal 15.
Mar 19 08:35:57 pfsense check_reload_status: reloading filter
Mar 19 08:35:58 pfsense apinger: Starting Alarm Pinger, apinger(63604)
Mar 19 08:35:58 pfsense php: : DynDns: Running updatedns()
Mar 19 08:35:58 pfsense php: : DynDns: updatedns() starting
Mar 19 08:35:58 pfsense php: : DynDns: _detectChange() starting.
Mar 19 08:35:58 pfsense php: : DynDns: _checkIP() starting.
Mar 19 08:35:58 pfsense php: : DynDns debug information: 121.91.213.142 extracted from local system.
Mar 19 08:35:58 pfsense php: : DynDns: Current WAN IP: 121.91.213.142
Mar 19 08:35:58 pfsense php: : DynDns: Cached IP: 121.91.181.126
Mar 19 08:35:58 pfsense php: : DynDns debug information: DynDns: cacheIP != wan_ip.  Updating. Cached IP: 121.91.181.126 WAN IP: 121.91.213.142 
Mar 19 08:35:59 pfsense php: : DynDns: DynDns _update() starting.
Mar 19 08:36:01 pfsense php: : Gateways status could not be determined, considering all as up/active.
Mar 19 08:36:01 pfsense php: : DynDns: DynDns _checkStatus() starting.
Mar 19 08:36:01 pfsense php: : DynDns: Current Service: freedns
Mar 19 08:36:01 pfsense php: : DynDns: _checkIP() starting.
Mar 19 08:36:01 pfsense php: : DynDns debug information: 121.91.213.142 extracted from local system.
Mar 19 08:36:01 pfsense php: : phpDynDNS: updating cache file /conf/dyndns_opt3freedns'sages.jumpingcrab.com'.cache: 121.91.213.142
Mar 19 08:36:01 pfsense php: : phpDynDNS: (Success) IP Address Changed Successfully!
Mar 19 08:36:07 pfsense php: : Resyncing OpenVPN instances for interface NEXTG.
Mar 19 08:36:08 pfsense apinger: ALARM: NEXTG(123.200.191.17)  *** NEXTGdelay ***
Mar 19 08:36:13 pfsense hostapd: ath0_wlan0: STA 5c:ac:4c:58:ef:87 WPA: group key handshake completed (RSN)
Mar 19 08:36:14 pfsense apinger: alarm canceled: NEXTG(123.200.191.17)  *** NEXTGdelay ***
Mar 19 08:36:18 pfsense check_reload_status: reloading filter</huawei></huawei></data></huawei></huawei></huawei>

in this section it fails to reconnect after the USb is reinserted.

Mar 19 08:38:44 pfsense kernel: ugen0.2: <huawei technologies="">at usbus0
Mar 19 08:38:45 pfsense ppp: [wan_link0] PPPoE connection timeout after 9 seconds
Mar 19 08:38:45 pfsense ppp: [wan_link0] Link: DOWN event
Mar 19 08:38:45 pfsense ppp: [wan_link0] LCP: Down event
Mar 19 08:38:45 pfsense ppp: [wan_link0] Link: reconnection attempt 5 in 3 seconds
Mar 19 08:38:45 pfsense ppp: [opt3_link0] Link: reconnection attempt 11
Mar 19 08:38:45 pfsense kernel: ugen0.2: <huawei technologies="">at usbus0 (disconnected)
Mar 19 08:38:46 pfsense ppp: [opt3_link0] can't open /dev/cuaU0.0: No such file or directory
Mar 19 08:38:46 pfsense ppp: [opt3_link0] Link: DOWN event
Mar 19 08:38:46 pfsense ppp: [opt3_link0] LCP: Down event
Mar 19 08:38:46 pfsense ppp: [opt3_link0] Link: reconnection attempt 12 in 2 seconds
Mar 19 08:38:46 pfsense kernel: ugen0.2: <huawei technologies="">at usbus0
Mar 19 08:38:47 pfsense kernel: u3g0: <data interface="">on usbus0
Mar 19 08:38:47 pfsense kernel: u3g0: Found 2 ports.
Mar 19 08:38:47 pfsense kernel: umass0: <huawei 0="" 2="" technologies="" huawei="" mobile,="" class="" 0,="" rev="" 1.10="" 0.00,="" addr="">on usbus0
Mar 19 08:38:48 pfsense kernel: (probe0:umass-sim0:0:0:0): TEST UNIT READY. CDB: 0 0 0 0 0 0 
Mar 19 08:38:48 pfsense kernel: (probe0:umass-sim0:0:0:0): CAM status: SCSI Status Error
Mar 19 08:38:48 pfsense kernel: (probe0:umass-sim0:0:0:0): SCSI status: Check Condition
Mar 19 08:38:48 pfsense kernel: (probe0:umass-sim0:0:0:0): SCSI sense: NOT READY asc:3a,0 (Medium not present)
Mar 19 08:38:48 pfsense ppp: [wan_link0] Link: reconnection attempt 5
Mar 19 08:38:48 pfsense ppp: [opt3_link0] Link: reconnection attempt 12
Mar 19 08:38:48 pfsense ppp: [wan_link0] PPPoE: Connecting to '*'
Mar 19 08:38:49 pfsense kernel: cd0 at umass-sim0 bus 0 scbus0 target 0 lun 0
Mar 19 08:38:49 pfsense kernel: cd0: <huawei mass="" storage="" 2.31="">Removable CD-ROM SCSI-2 device 
Mar 19 08:38:49 pfsense kernel: cd0: 1.000MB/s transfers
Mar 19 08:38:49 pfsense kernel: cd0: Attempt to query device size failed: NOT READY, Medium not present
Mar 19 08:38:49 pfsense ppp: [opt3_link0] chat: Detected Hayes compatible modem.
Mar 19 08:38:50 pfsense ppp: [opt3_link0] chat: Dialing server at *99#...
Mar 19 08:38:50 pfsense ppp: [opt3_link0] chat: Connected at an unknown speed.
Mar 19 08:38:50 pfsense ppp: [opt3_link0] MODEM: chat script succeeded
Mar 19 08:38:50 pfsense ppp: [opt3_link0] Link: UP event
Mar 19 08:38:50 pfsense ppp: [opt3_link0] LCP: Up event
Mar 19 08:38:50 pfsense ppp: [opt3_link0] LCP: state change Starting --> Req-Sent
Mar 19 08:38:50 pfsense ppp: [opt3_link0] LCP: SendConfigReq #3
Mar 19 08:38:50 pfsense ppp: [opt3_link0]   ACFCOMP
Mar 19 08:38:50 pfsense ppp: [opt3_link0]   PROTOCOMP
Mar 19 08:38:50 pfsense ppp: [opt3_link0]   ACCMAP 0x000a0000
Mar 19 08:38:50 pfsense ppp: [opt3_link0]   MRU 1500
Mar 19 08:38:50 pfsense ppp: [opt3_link0]   MAGICNUM 305465d9
Mar 19 08:38:50 pfsense ppp: [opt3_link0] LCP: rec'd Configure Request #0 (Req-Sent)
Mar 19 08:38:50 pfsense ppp: [opt3_link0]   MRU 1440
Mar 19 08:38:50 pfsense ppp: [opt3_link0]   ACCMAP 0x00000000
Mar 19 08:38:50 pfsense ppp: [opt3_link0]   AUTHPROTO CHAP MD5
Mar 19 08:38:50 pfsense ppp: [opt3_link0]   MAGICNUM 00c862a9
Mar 19 08:38:50 pfsense ppp: [opt3_link0]   PROTOCOMP
Mar 19 08:38:50 pfsense ppp: [opt3_link0]   ACFCOMP
Mar 19 08:38:50 pfsense ppp: [opt3_link0] LCP: SendConfigAck #0
Mar 19 08:38:50 pfsense ppp: [opt3_link0]   MRU 1440
Mar 19 08:38:50 pfsense ppp: [opt3_link0]   ACCMAP 0x00000000
Mar 19 08:38:50 pfsense ppp: [opt3_link0]   AUTHPROTO CHAP MD5
Mar 19 08:38:50 pfsense ppp: [opt3_link0]   MAGICNUM 00c862a9
Mar 19 08:38:50 pfsense ppp: [opt3_link0]   PROTOCOMP
Mar 19 08:38:50 pfsense ppp: [opt3_link0]   ACFCOMP
Mar 19 08:38:50 pfsense ppp: [opt3_link0] LCP: state change Req-Sent --> Ack-Sent
Mar 19 08:38:50 pfsense ppp: [opt3_link0] LCP: rec'd Configure Ack #3 (Ack-Sent)
Mar 19 08:38:50 pfsense ppp: [opt3_link0]   ACFCOMP
Mar 19 08:38:50 pfsense ppp: [opt3_link0]   PROTOCOMP
Mar 19 08:38:50 pfsense ppp: [opt3_link0]   ACCMAP 0x000a0000
Mar 19 08:38:50 pfsense ppp: [opt3_link0]   MRU 1500
Mar 19 08:38:50 pfsense ppp: [opt3_link0]   MAGICNUM 305465d9
Mar 19 08:38:50 pfsense ppp: [opt3_link0] LCP: state change Ack-Sent --> Opened
Mar 19 08:38:50 pfsense ppp: [opt3_link0] LCP: auth: peer wants CHAP, I want nothing
Mar 19 08:38:50 pfsense ppp: [opt3_link0] LCP: LayerUp
Mar 19 08:38:50 pfsense ppp: [opt3_link0] LCP: rec'd Discard Request #1 (Opened)
Mar 19 08:38:50 pfsense ppp: [opt3_link0] CHAP: rec'd CHALLENGE #1 len: 35
Mar 19 08:38:50 pfsense ppp: [opt3_link0]   Name: "UMTS_CHAP_SRVR"
Mar 19 08:38:50 pfsense ppp: [opt3_link0] CHAP: Using authname "blank"
Mar 19 08:38:50 pfsense ppp: [opt3_link0] CHAP: sending RESPONSE #1 len: 26
Mar 19 08:38:50 pfsense ppp: [opt3_link0] CHAP: rec'd SUCCESS #1 len: 4
Mar 19 08:38:50 pfsense ppp: [opt3_link0] LCP: authorization successful
Mar 19 08:38:50 pfsense ppp: [opt3_link0] Link: Matched action 'bundle "opt3" ""'
Mar 19 08:38:50 pfsense ppp: [opt3_link0] Link: Join bundle "opt3"
Mar 19 08:38:50 pfsense ppp: [opt3] Bundle: Status update: up 1 link, total bandwidth 28800 bps
Mar 19 08:38:50 pfsense ppp: [opt3] IPCP: Open event
Mar 19 08:38:50 pfsense ppp: [opt3] IPCP: state change Initial --> Starting
Mar 19 08:38:50 pfsense ppp: [opt3] IPCP: LayerStart
Mar 19 08:38:50 pfsense ppp: [opt3] IPCP: Up event
Mar 19 08:38:50 pfsense ppp: [opt3] IPCP: state change Starting --> Req-Sent
Mar 19 08:38:50 pfsense ppp: [opt3] IPCP: SendConfigReq #13
Mar 19 08:38:50 pfsense ppp: [opt3]   IPADDR 0.0.0.0
Mar 19 08:38:50 pfsense ppp: [opt3]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Mar 19 08:38:50 pfsense ppp: [opt3]   PRIDNS 0.0.0.0
Mar 19 08:38:50 pfsense ppp: [opt3]   SECDNS 0.0.0.0
Mar 19 08:38:51 pfsense ppp: [opt3] IPCP: rec'd Configure Nak #13 (Req-Sent)
Mar 19 08:38:51 pfsense ppp: [opt3]   PRIDNS 10.11.12.13
Mar 19 08:38:51 pfsense ppp: [opt3]   SECDNS 10.11.12.14
Mar 19 08:38:51 pfsense ppp: [opt3]   PRINBNS 10.11.12.13
Mar 19 08:38:51 pfsense ppp: [opt3]   SECNBNS 10.11.12.14
Mar 19 08:38:51 pfsense ppp: [opt3] IPCP: SendConfigReq #14
Mar 19 08:38:51 pfsense ppp: [opt3]   IPADDR 0.0.0.0
Mar 19 08:38:51 pfsense ppp: [opt3]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Mar 19 08:38:51 pfsense ppp: [opt3]   PRIDNS 10.11.12.13
Mar 19 08:38:51 pfsense ppp: [opt3]   SECDNS 10.11.12.14
Mar 19 08:38:53 pfsense ppp: [opt3] IPCP: SendConfigReq #15
Mar 19 08:38:53 pfsense ppp: [opt3]   IPADDR 0.0.0.0
Mar 19 08:38:53 pfsense ppp: [opt3]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Mar 19 08:38:53 pfsense ppp: [opt3]   PRIDNS 10.11.12.13
Mar 19 08:38:53 pfsense ppp: [opt3]   SECDNS 10.11.12.14
Mar 19 08:38:55 pfsense ppp: [opt3] IPCP: SendConfigReq #16
Mar 19 08:38:55 pfsense ppp: [opt3]   IPADDR 0.0.0.0
Mar 19 08:38:55 pfsense ppp: [opt3]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Mar 19 08:38:55 pfsense ppp: [opt3]   PRIDNS 10.11.12.13
Mar 19 08:38:55 pfsense ppp: [opt3]   SECDNS 10.11.12.14
Mar 19 08:38:57 pfsense ppp: [opt3] IPCP: SendConfigReq #17
Mar 19 08:38:57 pfsense ppp: [opt3]   IPADDR 0.0.0.0
Mar 19 08:38:57 pfsense ppp: [opt3]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Mar 19 08:38:57 pfsense ppp: [opt3]   PRIDNS 10.11.12.13
Mar 19 08:38:57 pfsense ppp: [opt3]   SECDNS 10.11.12.14
Mar 19 08:38:57 pfsense ppp: [wan_link0] PPPoE connection timeout after 9 seconds
Mar 19 08:38:57 pfsense ppp: [wan_link0] Link: DOWN event
Mar 19 08:38:57 pfsense ppp: [wan_link0] LCP: Down event
Mar 19 08:38:57 pfsense ppp: [wan_link0] Link: reconnection attempt 6 in 4 seconds
Mar 19 08:38:59 pfsense ppp: [opt3] IPCP: SendConfigReq #18
Mar 19 08:38:59 pfsense ppp: [opt3]   IPADDR 0.0.0.0
Mar 19 08:38:59 pfsense ppp: [opt3]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Mar 19 08:38:59 pfsense ppp: [opt3]   PRIDNS 10.11.12.13
Mar 19 08:38:59 pfsense ppp: [opt3]   SECDNS 10.11.12.14
Mar 19 08:39:01 pfsense ppp: [opt3] IPCP: SendConfigReq #19
Mar 19 08:39:01 pfsense ppp: [opt3]   IPADDR 0.0.0.0
Mar 19 08:39:01 pfsense ppp: [opt3]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Mar 19 08:39:01 pfsense ppp: [opt3]   PRIDNS 10.11.12.13
Mar 19 08:39:01 pfsense ppp: [opt3]   SECDNS 10.11.12.14
Mar 19 08:39:01 pfsense ppp: [wan_link0] Link: reconnection attempt 6
Mar 19 08:39:01 pfsense ppp: [wan_link0] PPPoE: Connecting to '*'
Mar 19 08:39:03 pfsense ppp: [opt3] IPCP: SendConfigReq #20
Mar 19 08:39:03 pfsense ppp: [opt3]   IPADDR 0.0.0.0
Mar 19 08:39:03 pfsense ppp: [opt3]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Mar 19 08:39:03 pfsense ppp: [opt3]   PRIDNS 10.11.12.13
Mar 19 08:39:03 pfsense ppp: [opt3]   SECDNS 10.11.12.14
Mar 19 08:39:05 pfsense ppp: [opt3] IPCP: SendConfigReq #21
Mar 19 08:39:05 pfsense ppp: [opt3]   IPADDR 0.0.0.0
Mar 19 08:39:05 pfsense ppp: [opt3]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Mar 19 08:39:05 pfsense ppp: [opt3]   PRIDNS 10.11.12.13
Mar 19 08:39:05 pfsense ppp: [opt3]   SECDNS 10.11.12.14
Mar 19 08:39:07 pfsense ppp: [opt3] IPCP: SendConfigReq #22
Mar 19 08:39:07 pfsense ppp: [opt3]   IPADDR 0.0.0.0
Mar 19 08:39:07 pfsense ppp: [opt3]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Mar 19 08:39:07 pfsense ppp: [opt3]   PRIDNS 10.11.12.13
Mar 19 08:39:07 pfsense ppp: [opt3]   SECDNS 10.11.12.14
Mar 19 08:39:09 pfsense ppp: [opt3] IPCP: SendConfigReq #23
Mar 19 08:39:09 pfsense ppp: [opt3]   IPADDR 0.0.0.0
Mar 19 08:39:09 pfsense ppp: [opt3]   COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Mar 19 08:39:09 pfsense ppp: [opt3]   PRIDNS 10.11.12.13
Mar 19 08:39:09 pfsense ppp: [opt3]   SECDNS 10.11.12.14
Mar 19 08:39:10 pfsense ppp: [wan_link0] PPPoE connection timeout after 9 seconds
Mar 19 08:39:10 pfsense ppp: [wan_link0] Link: DOWN event
Mar 19 08:39:10 pfsense ppp: [wan_link0] LCP: Down event
Mar 19 08:39:10 pfsense ppp: [wan_link0] Link: reconnection attempt 7 in 2 seconds
Mar 19 08:39:11 pfsense ppp: [opt3] IPCP: parameter negotiation failed
Mar 19 08:39:11 pfsense ppp: [opt3] IPCP: state change Req-Sent --> Stopped
Mar 19 08:39:11 pfsense ppp: [opt3] IPCP: LayerFinish
Mar 19 08:39:11 pfsense ppp: [opt3] Bundle: No NCPs left. Closing links...
Mar 19 08:39:11 pfsense ppp: [opt3] Bundle: closing link "opt3_link0"...
Mar 19 08:39:11 pfsense ppp: [opt3_link0] Link: CLOSE event
Mar 19 08:39:11 pfsense ppp: [opt3_link0] LCP: Close event
Mar 19 08:39:11 pfsense ppp: [opt3_link0] LCP: state change Opened --> Closing
Mar 19 08:39:11 pfsense ppp: [opt3_link0] Link: Leave bundle "opt3"
Mar 19 08:39:11 pfsense ppp: [opt3] Bundle: Status update: up 0 links, total bandwidth 9600 bps
Mar 19 08:39:11 pfsense ppp: [opt3] IPCP: Close event
Mar 19 08:39:11 pfsense ppp: [opt3] IPCP: state change Stopped --> Closed
Mar 19 08:39:11 pfsense ppp: [opt3] IPCP: Down event
Mar 19 08:39:11 pfsense ppp: [opt3] IPCP: state change Closed --> Initial
Mar 19 08:39:11 pfsense ppp: [opt3_link0] LCP: SendTerminateReq #4
Mar 19 08:39:11 pfsense ppp: [opt3_link0] LCP: LayerDown
Mar 19 08:39:12 pfsense ppp: [wan_link0] Link: reconnection attempt 7
Mar 19 08:39:12 pfsense ppp: [wan_link0] PPPoE: Connecting to '*'
Mar 19 08:39:13 pfsense hostapd: ath0_wlan0: STA 5c:ac:4c:58:ef:87 WPA: group key handshake completed (RSN)
Mar 19 08:39:13 pfsense ppp: [opt3_link0] LCP: SendTerminateReq #5
Mar 19 08:39:15 pfsense ppp: [opt3_link0] LCP: state change Closing --> Closed
Mar 19 08:39:15 pfsense ppp: [opt3_link0] LCP: LayerFinish
Mar 19 08:39:15 pfsense ppp: [opt3_link0] Link: DOWN event
Mar 19 08:39:15 pfsense ppp: [opt3_link0] LCP: Down event
Mar 19 08:39:15 pfsense ppp: [opt3_link0] LCP: state change Closed --> Initial
Mar 19 08:39:21 pfsense ppp: [wan_link0] PPPoE connection timeout after 9 seconds
Mar 19 08:39:21 pfsense ppp: [wan_link0] Link: DOWN</huawei></huawei></data></huawei></huawei></huawei>

greybeard

have updated to latest snapshot.
issue is still there.
ppp connection via hsdpa appears to be random if link comes active.
once it fails, if it doesn't recover within a short period of time, the ppp log no longer has any entries for the link retrying.
this is without removing/installing the USB connection.

is anyone else using a USB hsdpa modem on RC2 successfully? either single or backup?

greybeard

after some scientific google ;) i found the following http://lists.freebsd.org/pipermail/freebsd-questions/2007-September/158811.html
specifically the entry into mpd.conf for "set bundle no noretry"

I had rebuilt a bog standard system with only a single WAN configured for a hsdpa usb modem. ie no failover.
i was still having problems with the modem not connecting.
I then edited the /var/etc/mpd_wan.conf file and added the line above.
The modem came up pretty much straight away.
[edit] unfortunately it hasn't done anything to resolve the problem of recovering from a failed link :'(

greybeard

to confirm if i had a hardware issue or not, i loaded up a CF with a copy of vyatta.
3g/hsdpa usb modem connects/reconnects/unplug/plug usb and it establishes a connection each time. ie doesn't have any issues with connectivity or failing to connect.

I'd like some feedback from anyone who has is using a usb hsdpa/3g modem to see if they have any issues on v2 rc1.
Vyatta is working but it's a pain to maintain with no graphic interface on the free version.

gnhb

I use a 3G modem for connectivity at two different sites. I'll try unplugging it and see what happens.

GB

roja

;) Being Very familiar with this product i was a bit miffed to have thsi same issue, PPPOe not connecting.
Was running 1.2.3 latest upgraded to 2.0 RC1. Migrations seems to go through with no issues except for WAN.

when WAN is changed to PPPOE, no connection. The Wan now says WAN/PPPOE, so i assume it sees that setup?
When it does not work it then creates a PPPOE:1 connection which i tried then it created a PPPOE:2 then PPPOE:3

After trying multiple changes i went back to 1.2.3 and all works 100%. Can you point me to some resource that explains
the change in WAN setup.

bouncer

For me I never managed to work pppoe. Each time i try to connect it goes down in few seconds.

infinityloop

Just tested yesterdays svn build of 2.0RC1 to see if PPPoE is finally working and it is still not…. :S

1. Interfaces -> assign -> PPP's -> edit interface which is from 1.2.3

NO INTERFACES ARE SELECTABLE - until I re-select PPPoE AGAIN in the drop down list.
Why does WAN and LAN appear there? thats not a physical interface. :S

But even then, PPPoE is not working:
System log reports this when trying to connect: May 5 22:02:20 - php: : Could not find gateway for interface(wan).