Huawei E220 USB 3G Modem - IPCP: parameter negotiation failed



  • Dear All

    I'd like to test new pfSense.
    I'd like to connect to internet with a 3g modem, but it is failed.
    The same hardware under linux (Debian lenny) works perfectly.

    The 3G modem detected well, it seems to me, that it is a software-layer problem
    (maybe in mpd…?).

    I attach the pfsense and linux logs (sorry for long list):

    pfSense:

    Jun  8 22:00:36 pfSense kernel: ugen3.2: <huawei technologies="">at usbus3
    Jun  8 22:00:36 pfSense kernel: ugen3.2: <huawei technologies="">at usbus3 (disconnected)
    Jun  8 22:00:38 pfSense kernel: ugen3.2: <huawei technologies="">at usbus3
    Jun  8 22:00:38 pfSense kernel: u3g0: <data interface="">on usbus3
    Jun  8 22:00:38 pfSense kernel: u3g0: Found 2 ports.
    Jun  8 22:00:38 pfSense kernel: umass0: <huawei 0="" 2="" technologies="" huawei="" mobile,="" class="" 0,="" rev="" 1.10="" 0.00,="" addr="">on usbus3
    Jun  8 22:00:38 pfSense kernel: umass0:  SCSI over Bulk-Only; quirks = 0x0000
    Jun  8 22:00:39 pfSense kernel: umass0:0:0👎 Attached to scbus0
    Jun  8 22:00:40 pfSense kernel: (probe0:umass-sim0:0:0:0): TEST UNIT READY. CDB: 0 0 0 0 0 0
    Jun  8 22:00:40 pfSense kernel: (probe0:umass-sim0:0:0:0): CAM status: SCSI Status Error
    Jun  8 22:00:40 pfSense kernel: (probe0:umass-sim0:0:0:0): SCSI status: Check Condition
    Jun  8 22:00:40 pfSense kernel: (probe0:umass-sim0:0:0:0): SCSI sense: NOT READY asc:3a,0 (Medium not present)
    Jun  8 22:00:40 pfSense kernel: cd0 at umass-sim0 bus 0 scbus0 target 0 lun 0
    Jun  8 22:00:40 pfSense kernel: cd0: <huawei mass="" storage="" 2.31="">Removable CD-ROM SCSI-2 device
    Jun  8 22:00:40 pfSense kernel: cd0: 1.000MB/s transfers
    Jun  8 22:00:40 pfSense kernel: cd0: Attempt to query device size failed: NOT READY, Medium not present
    Jun  8 22:01:02 pfSense ppp: Multi-link PPP daemon for FreeBSD
    Jun  8 22:01:02 pfSense ppp:
    Jun  8 22:01:02 pfSense ppp: process 59235 started, version 5.5 (root@FreeBSD_8.0_pfSense_2.0-AMD64.snaps.pfsense.org 16:23 22-Feb-2011)
    Jun  8 22:01:02 pfSense ppp: web: web is not running
    Jun  8 22:01:02 pfSense ppp: [opt1] Bundle: Interface ng0 created
    Jun  8 22:01:02 pfSense ppp: [opt1_link0] Link: OPEN event
    Jun  8 22:01:02 pfSense kernel: ng0: changing name to 'ppp0'
    Jun  8 22:01:02 pfSense ppp: [opt1_link0] LCP: Open event
    Jun  8 22:01:02 pfSense ppp: [opt1_link0] LCP: state change Initial –> Starting
    Jun  8 22:01:02 pfSense ppp: [opt1_link0] LCP: LayerStart
    Jun  8 22:01:02 pfSense ppp: Multi-link PPP daemon for FreeBSD
    Jun  8 22:01:02 pfSense ppp:
    Jun  8 22:01:02 pfSense ppp: process 60160 started, version 5.5 (root@FreeBSD_8.0_pfSense_2.0-AMD64.snaps.pfsense.org 16:23 22-Feb-2011)
    Jun  8 22:01:02 pfSense ppp: waiting for process 59235 to die…
    Jun  8 22:01:02 pfSense ppp: caught fatal signal term
    Jun  8 22:01:02 pfSense ppp: [opt1] IFACE: Close event
    Jun  8 22:01:02 pfSense ppp: [opt1] IPCP: Close event
    Jun  8 22:01:02 pfSense ppp: [opt1_link0] chat: Detected Hayes compatible modem.
    Jun  8 22:01:02 pfSense ppp: [opt1_link0] chat: Dialing server at *99#…
    Jun  8 22:01:02 pfSense ppp: [opt1_link0] chat: Connected at an unknown speed.
    Jun  8 22:01:02 pfSense ppp: [opt1_link0] MODEM: chat script succeeded
    Jun  8 22:01:02 pfSense ppp: [opt1_link0] Link: UP event
    Jun  8 22:01:02 pfSense ppp: [opt1_link0] LCP: Up event
    Jun  8 22:01:02 pfSense ppp: [opt1_link0] LCP: state change Starting –> Req-Sent
    Jun  8 22:01:02 pfSense ppp: [opt1_link0] LCP: SendConfigReq #1
    Jun  8 22:01:02 pfSense ppp: [opt1_link0]  ACFCOMP
    Jun  8 22:01:02 pfSense ppp: [opt1_link0]  PROTOCOMP
    Jun  8 22:01:02 pfSense ppp: [opt1_link0]  ACCMAP 0x000a0000
    Jun  8 22:01:02 pfSense ppp: [opt1_link0]  MRU 1500
    Jun  8 22:01:02 pfSense ppp: [opt1_link0]  MAGICNUM fc154f72
    Jun  8 22:01:02 pfSense ppp: [opt1_link0] LCP: rec'd Configure Request #0 (Req-Sent)
    Jun  8 22:01:02 pfSense ppp: [opt1_link0]  ACCMAP 0x00000000
    Jun  8 22:01:02 pfSense ppp: [opt1_link0]  AUTHPROTO CHAP MD5
    Jun  8 22:01:02 pfSense ppp: [opt1_link0]  MAGICNUM 00c89b82
    Jun  8 22:01:02 pfSense ppp: [opt1_link0]  PROTOCOMP
    Jun  8 22:01:02 pfSense ppp: [opt1_link0]  ACFCOMP
    Jun  8 22:01:02 pfSense ppp: [opt1_link0] LCP: SendConfigAck #0
    Jun  8 22:01:02 pfSense ppp: [opt1_link0]  ACCMAP 0x00000000
    Jun  8 22:01:02 pfSense ppp: [opt1_link0]  AUTHPROTO CHAP MD5
    Jun  8 22:01:02 pfSense ppp: [opt1_link0]  MAGICNUM 00c89b82
    Jun  8 22:01:02 pfSense ppp: [opt1_link0]  PROTOCOMP
    Jun  8 22:01:02 pfSense ppp: [opt1_link0]  ACFCOMP
    Jun  8 22:01:02 pfSense ppp: [opt1_link0] LCP: state change Req-Sent –> Ack-Sent
    Jun  8 22:01:02 pfSense ppp: [opt1_link0] LCP: rec'd Configure Ack #1 (Ack-Sent)
    Jun  8 22:01:02 pfSense ppp: [opt1_link0]  ACFCOMP
    Jun  8 22:01:02 pfSense ppp: [opt1_link0]  PROTOCOMP
    Jun  8 22:01:02 pfSense ppp: [opt1_link0]  ACCMAP 0x000a0000
    Jun  8 22:01:02 pfSense ppp: [opt1_link0]  MRU 1500
    Jun  8 22:01:02 pfSense ppp: [opt1_link0]  MAGICNUM fc154f72
    Jun  8 22:01:02 pfSense ppp: [opt1_link0] LCP: state change Ack-Sent –> Opened
    Jun  8 22:01:02 pfSense ppp: [opt1_link0] LCP: auth: peer wants CHAP, I want nothing
    Jun  8 22:01:02 pfSense ppp: [opt1_link0] LCP: LayerUp
    Jun  8 22:01:02 pfSense ppp: [opt1_link0] LCP: rec'd Discard Request #1 (Opened)
    Jun  8 22:01:02 pfSense ppp: [opt1_link0] CHAP: rec'd CHALLENGE #1 len: 35
    Jun  8 22:01:02 pfSense ppp: [opt1_link0]  Name: "UMTS_CHAP_SRVR"
    Jun  8 22:01:02 pfSense ppp: [opt1_link0] CHAP: Using authname "admin"
    Jun  8 22:01:02 pfSense ppp: [opt1_link0] CHAP: sending RESPONSE #1 len: 26
    Jun  8 22:01:02 pfSense ppp: [opt1_link0] CHAP: rec'd SUCCESS #1 len: 4
    Jun  8 22:01:02 pfSense ppp: [opt1_link0] LCP: authorization successful
    Jun  8 22:01:02 pfSense ppp: Shutdown sequence in progress, BundJoin() denied
    Jun  8 22:01:02 pfSense ppp: [opt1_link0] link did not validate in bundle
    Jun  8 22:01:02 pfSense ppp: [opt1_link0] LCP: parameter negotiation failed
    Jun  8 22:01:02 pfSense ppp: [opt1_link0] LCP: state change Opened –> Stopping
    Jun  8 22:01:02 pfSense ppp: [opt1_link0] LCP: SendTerminateReq #2
    Jun  8 22:01:02 pfSense ppp: [opt1_link0] LCP: LayerDown
    Jun  8 22:01:02 pfSense ppp: [opt1_link0] LCP: rec'd Terminate Ack #2 (Stopping)
    Jun  8 22:01:02 pfSense ppp: [opt1_link0] LCP: state change Stopping –> Stopped
    Jun  8 22:01:02 pfSense ppp: [opt1_link0] LCP: LayerFinish
    Jun  8 22:01:02 pfSense ppp: [opt1_link0] Link: DOWN event
    Jun  8 22:01:02 pfSense ppp: [opt1_link0] Link: giving up after 0 reconnection attempts
    Jun  8 22:01:02 pfSense ppp: [opt1_link0] LCP: Close event
    Jun  8 22:01:02 pfSense ppp: [opt1_link0] LCP: state change Stopped –> Closed
    Jun  8 22:01:02 pfSense ppp: [opt1_link0] LCP: Down event
    Jun  8 22:01:02 pfSense ppp: [opt1_link0] LCP: state change Closed –> Initial
    Jun  8 22:01:04 pfSense ppp: [opt1] Bundle: Shutdown
    Jun  8 22:01:04 pfSense ppp: [opt1_link0] Link: Shutdown
    Jun  8 22:01:04 pfSense ppp: process 59235 terminated
    Jun  8 22:01:05 pfSense ppp: last message repeated 2 times
    Jun  8 22:01:05 pfSense ppp: web: web is not running
    Jun  8 22:01:05 pfSense ppp: [opt1] Bundle: Interface ng0 created
    Jun  8 22:01:05 pfSense ppp: [opt1_link0] Link: OPEN event
    Jun  8 22:01:05 pfSense kernel: ng0: changing name to 'ppp0'
    Jun  8 22:01:05 pfSense ppp: [opt1_link0] LCP: Open event
    Jun  8 22:01:05 pfSense ppp: [opt1_link0] LCP: state change Initial –> Starting
    Jun  8 22:01:05 pfSense ppp: [opt1_link0] LCP: LayerStart
    Jun  8 22:01:05 pfSense ppp: [opt1_link0] chat: Detected Hayes compatible modem.
    Jun  8 22:01:05 pfSense ppp: [opt1_link0] chat: Dialing server at *99#…
    Jun  8 22:01:05 pfSense ppp: [opt1_link0] chat: Connected at an unknown speed.
    Jun  8 22:01:05 pfSense ppp: [opt1_link0] MODEM: chat script succeeded
    Jun  8 22:01:05 pfSense ppp: [opt1_link0] Link: UP event
    Jun  8 22:01:05 pfSense ppp: [opt1_link0] LCP: Up event
    Jun  8 22:01:05 pfSense ppp: [opt1_link0] LCP: state change Starting –> Req-Sent
    Jun  8 22:01:05 pfSense ppp: [opt1_link0] LCP: SendConfigReq #1
    Jun  8 22:01:05 pfSense ppp: [opt1_link0]  ACFCOMP
    Jun  8 22:01:05 pfSense ppp: [opt1_link0]  PROTOCOMP
    Jun  8 22:01:05 pfSense ppp: [opt1_link0]  ACCMAP 0x000a0000
    Jun  8 22:01:05 pfSense ppp: [opt1_link0]  MRU 1500
    Jun  8 22:01:05 pfSense ppp: [opt1_link0]  MAGICNUM 16e32000
    Jun  8 22:01:05 pfSense ppp: [opt1_link0] LCP: rec'd Configure Request #2 (Req-Sent)
    Jun  8 22:01:05 pfSense ppp: [opt1_link0]  ACCMAP 0x00000000
    Jun  8 22:01:05 pfSense ppp: [opt1_link0]  AUTHPROTO CHAP MD5
    Jun  8 22:01:05 pfSense ppp: [opt1_link0]  MAGICNUM 00c8a6e6
    Jun  8 22:01:05 pfSense ppp: [opt1_link0]  PROTOCOMP
    Jun  8 22:01:05 pfSense ppp: [opt1_link0]  ACFCOMP
    Jun  8 22:01:05 pfSense ppp: [opt1_link0] LCP: SendConfigAck #2
    Jun  8 22:01:05 pfSense ppp: [opt1_link0]  ACCMAP 0x00000000
    Jun  8 22:01:05 pfSense ppp: [opt1_link0]  AUTHPROTO CHAP MD5
    Jun  8 22:01:05 pfSense ppp: [opt1_link0]  MAGICNUM 00c8a6e6
    Jun  8 22:01:05 pfSense ppp: [opt1_link0]  PROTOCOMP
    Jun  8 22:01:05 pfSense ppp: [opt1_link0]  ACFCOMP
    Jun  8 22:01:05 pfSense ppp: [opt1_link0] LCP: state change Req-Sent –> Ack-Sent
    Jun  8 22:01:05 pfSense ppp: [opt1_link0] LCP: rec'd Configure Ack #1 (Ack-Sent)
    Jun  8 22:01:05 pfSense ppp: [opt1_link0]  ACFCOMP
    Jun  8 22:01:05 pfSense ppp: [opt1_link0]  PROTOCOMP
    Jun  8 22:01:05 pfSense ppp: [opt1_link0]  ACCMAP 0x000a0000
    Jun  8 22:01:05 pfSense ppp: [opt1_link0]  MRU 1500
    Jun  8 22:01:05 pfSense ppp: [opt1_link0]  MAGICNUM 16e32000
    Jun  8 22:01:05 pfSense ppp: [opt1_link0] LCP: state change Ack-Sent –> Opened
    Jun  8 22:01:05 pfSense ppp: [opt1_link0] LCP: auth: peer wants CHAP, I want nothing
    Jun  8 22:01:05 pfSense ppp: [opt1_link0] LCP: LayerUp
    Jun  8 22:01:05 pfSense ppp: [opt1_link0] LCP: rec'd Discard Request #3 (Opened)
    Jun  8 22:01:05 pfSense ppp: [opt1_link0] CHAP: rec'd CHALLENGE #1 len: 35
    Jun  8 22:01:05 pfSense ppp: [opt1_link0]  Name: "UMTS_CHAP_SRVR"
    Jun  8 22:01:05 pfSense ppp: [opt1_link0] CHAP: Using authname "admin"
    Jun  8 22:01:05 pfSense ppp: [opt1_link0] CHAP: sending RESPONSE #1 len: 26
    Jun  8 22:01:05 pfSense ppp: [opt1_link0] CHAP: rec'd SUCCESS #1 len: 4
    Jun  8 22:01:05 pfSense ppp: [opt1_link0] LCP: authorization successful
    Jun  8 22:01:05 pfSense ppp: [opt1_link0] Link: Matched action 'bundle "opt1" ""'
    Jun  8 22:01:05 pfSense ppp: [opt1_link0] Link: Join bundle "opt1"
    Jun  8 22:01:05 pfSense ppp: [opt1] Bundle: Status update: up 1 link, total bandwidth 28800 bps
    Jun  8 22:01:05 pfSense ppp: [opt1] IPCP: Open event
    Jun  8 22:01:05 pfSense ppp: [opt1] IPCP: state change Initial –> Starting
    Jun  8 22:01:05 pfSense ppp: [opt1] IPCP: LayerStart
    Jun  8 22:01:05 pfSense ppp: [opt1] IPCP: Up event
    Jun  8 22:01:05 pfSense ppp: [opt1] IPCP: state change Starting –> Req-Sent
    Jun  8 22:01:05 pfSense ppp: [opt1] IPCP: SendConfigReq #1
    Jun  8 22:01:05 pfSense ppp: [opt1]  IPADDR 0.0.0.0
    Jun  8 22:01:05 pfSense ppp: [opt1]  COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
    Jun  8 22:01:05 pfSense ppp: [opt1]  PRIDNS 0.0.0.0
    Jun  8 22:01:05 pfSense ppp: [opt1]  SECDNS 0.0.0.0
    Jun  8 22:01:06 pfSense ppp: [opt1] IPCP: rec'd Configure Nak #1 (Req-Sent)
    Jun  8 22:01:06 pfSense ppp: [opt1]  PRIDNS 10.11.12.13
    Jun  8 22:01:06 pfSense ppp: [opt1]  SECDNS 10.11.12.14
    Jun  8 22:01:06 pfSense ppp: [opt1]  PRINBNS 10.11.12.13
    Jun  8 22:01:06 pfSense ppp: [opt1]  SECNBNS 10.11.12.14
    Jun  8 22:01:06 pfSense ppp: [opt1] IPCP: SendConfigReq #2
    Jun  8 22:01:06 pfSense ppp: [opt1]  IPADDR 0.0.0.0
    Jun  8 22:01:06 pfSense ppp: [opt1]  COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
    Jun  8 22:01:06 pfSense ppp: [opt1]  PRIDNS 10.11.12.13
    Jun  8 22:01:06 pfSense ppp: [opt1]  SECDNS 10.11.12.14
    Jun  8 22:01:08 pfSense ppp: [opt1] IPCP: SendConfigReq #3
    Jun  8 22:01:08 pfSense ppp: [opt1]  IPADDR 0.0.0.0
    Jun  8 22:01:08 pfSense ppp: [opt1]  COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
    Jun  8 22:01:08 pfSense ppp: [opt1]  PRIDNS 10.11.12.13
    Jun  8 22:01:08 pfSense ppp: [opt1]  SECDNS 10.11.12.14
    Jun  8 22:01:10 pfSense ppp: [opt1] IPCP: SendConfigReq #4
    Jun  8 22:01:10 pfSense ppp: [opt1]  IPADDR 0.0.0.0
    Jun  8 22:01:10 pfSense ppp: [opt1]  COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
    Jun  8 22:01:10 pfSense ppp: [opt1]  PRIDNS 10.11.12.13
    Jun  8 22:01:10 pfSense ppp: [opt1]  SECDNS 10.11.12.14
    Jun  8 22:01:12 pfSense ppp: [opt1] IPCP: SendConfigReq #5
    Jun  8 22:01:12 pfSense ppp: [opt1]  IPADDR 0.0.0.0
    Jun  8 22:01:12 pfSense ppp: [opt1]  COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
    Jun  8 22:01:12 pfSense ppp: [opt1]  PRIDNS 10.11.12.13
    Jun  8 22:01:12 pfSense ppp: [opt1]  SECDNS 10.11.12.14
    Jun  8 22:01:14 pfSense ppp: [opt1] IPCP: SendConfigReq #6
    Jun  8 22:01:14 pfSense ppp: [opt1]  IPADDR 0.0.0.0
    Jun  8 22:01:14 pfSense ppp: [opt1]  COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
    Jun  8 22:01:14 pfSense ppp: [opt1]  PRIDNS 10.11.12.13
    Jun  8 22:01:14 pfSense ppp: [opt1]  SECDNS 10.11.12.14
    Jun  8 22:01:16 pfSense ppp: [opt1] IPCP: SendConfigReq #7
    Jun  8 22:01:16 pfSense ppp: [opt1]  IPADDR 0.0.0.0
    Jun  8 22:01:16 pfSense ppp: [opt1]  COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
    Jun  8 22:01:16 pfSense ppp: [opt1]  PRIDNS 10.11.12.13
    Jun  8 22:01:16 pfSense ppp: [opt1]  SECDNS 10.11.12.14
    Jun  8 22:01:18 pfSense ppp: [opt1] IPCP: SendConfigReq #8
    Jun  8 22:01:18 pfSense ppp: [opt1]  IPADDR 0.0.0.0
    Jun  8 22:01:18 pfSense ppp: [opt1]  COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
    Jun  8 22:01:18 pfSense ppp: [opt1]  PRIDNS 10.11.12.13
    Jun  8 22:01:18 pfSense ppp: [opt1]  SECDNS 10.11.12.14
    Jun  8 22:01:20 pfSense ppp: [opt1] IPCP: SendConfigReq #9
    Jun  8 22:01:20 pfSense ppp: [opt1]  IPADDR 0.0.0.0
    Jun  8 22:01:20 pfSense ppp: [opt1]  COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
    Jun  8 22:01:20 pfSense ppp: [opt1]  PRIDNS 10.11.12.13
    Jun  8 22:01:20 pfSense ppp: [opt1]  SECDNS 10.11.12.14
    Jun  8 22:01:22 pfSense ppp: [opt1] IPCP: SendConfigReq #10
    Jun  8 22:01:22 pfSense ppp: [opt1]  IPADDR 0.0.0.0
    Jun  8 22:01:22 pfSense ppp: [opt1]  COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
    Jun  8 22:01:22 pfSense ppp: [opt1]  PRIDNS 10.11.12.13
    Jun  8 22:01:22 pfSense ppp: [opt1]  SECDNS 10.11.12.14
    Jun  8 22:01:24 pfSense ppp: [opt1] IPCP: SendConfigReq #11
    Jun  8 22:01:24 pfSense ppp: [opt1]  IPADDR 0.0.0.0
    Jun  8 22:01:24 pfSense ppp: [opt1]  COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
    Jun  8 22:01:24 pfSense ppp: [opt1]  PRIDNS 10.11.12.13
    Jun  8 22:01:24 pfSense ppp: [opt1]  SECDNS 10.11.12.14
    Jun  8 22:01:26 pfSense ppp: [opt1] IPCP: parameter negotiation failed
    Jun  8 22:01:26 pfSense ppp: [opt1] IPCP: state change Req-Sent –> Stopped
    Jun  8 22:01:26 pfSense ppp: [opt1] IPCP: LayerFinish
    Jun  8 22:01:26 pfSense ppp: [opt1] Bundle: No NCPs left. Closing links…
    Jun  8 22:01:26 pfSense ppp: [opt1] Bundle: closing link "opt1_link0"…
    Jun  8 22:01:26 pfSense ppp: [opt1_link0] Link: CLOSE event
    Jun  8 22:01:26 pfSense ppp: [opt1_link0] LCP: Close event
    Jun  8 22:01:26 pfSense ppp: [opt1_link0] LCP: state change Opened –> Closing
    Jun  8 22:01:26 pfSense ppp: [opt1_link0] Link: Leave bundle "opt1"
    Jun  8 22:01:26 pfSense ppp: [opt1] Bundle: Status update: up 0 links, total bandwidth 9600 bps
    Jun  8 22:01:26 pfSense ppp: [opt1] IPCP: Close event
    Jun  8 22:01:26 pfSense ppp: [opt1] IPCP: state change Stopped –> Closed
    Jun  8 22:01:26 pfSense ppp: [opt1] IPCP: Down event
    Jun  8 22:01:26 pfSense ppp: [opt1] IPCP: state change Closed –> Initial
    Jun  8 22:01:26 pfSense ppp: [opt1_link0] LCP: SendTerminateReq #2
    Jun  8 22:01:26 pfSense ppp: [opt1_link0] LCP: LayerDown
    Jun  8 22:01:28 pfSense ppp: [opt1_link0] LCP: SendTerminateReq #3
    Jun  8 22:01:30 pfSense ppp: [opt1_link0] LCP: state change Closing –> Closed
    Jun  8 22:01:30 pfSense ppp: [opt1_link0] LCP: LayerFinish
    Jun  8 22:01:30 pfSense ppp: [opt1_link0] Link: DOWN event
    Jun  8 22:01:30 pfSense ppp: [opt1_link0] LCP: Down event
    Jun  8 22:01:30 pfSense ppp: [opt1_link0] LCP: state change Closed –> Initial

    Under Linux (same hardware - works perfectly):

    Jun  5 15:10:47 voip chat[21726]: abort on (BUSY)
    Jun  5 15:10:47 voip chat[21726]: abort on (NO CARRIER)
    Jun  5 15:10:47 voip chat[21726]: abort on (VOICE)
    Jun  5 15:10:47 voip chat[21726]: abort on (NO DIALTONE)
    Jun  5 15:10:47 voip chat[21726]: abort on (NO DIAL TONE)
    Jun  5 15:10:47 voip chat[21726]: abort on (NO ANSWER)
    Jun  5 15:10:47 voip chat[21726]: abort on (DELAYED)
    Jun  5 15:10:47 voip chat[21726]: send (ATZ^M)
    Jun  5 15:10:47 voip chat[21726]: expect (OK)
    Jun  5 15:10:47 voip chat[21726]: ATZ^M^M
    Jun  5 15:10:47 voip chat[21726]: OK
    Jun  5 15:10:47 voip chat[21726]:  – got it
    Jun  5 15:10:47 voip chat[21726]: send (at+cgdcont=1,"IP","internet"^M)
    Jun  5 15:10:48 voip chat[21726]: expect (OK)
    Jun  5 15:10:48 voip chat[21726]: ^M
    Jun  5 15:10:48 voip chat[21726]: at+cgdcont=1,"IP","internet"^M^M
    Jun  5 15:10:48 voip chat[21726]: OK
    Jun  5 15:10:48 voip chat[21726]:  – got it
    Jun  5 15:10:48 voip chat[21726]: send (ATDT99#^M)
    Jun  5 15:10:48 voip chat[21726]: expect (CONNECT)
    Jun  5 15:10:48 voip chat[21726]: ^M
    Jun  5 15:10:48 voip chat[21726]: ATDT
    99#^M^M
    Jun  5 15:10:48 voip chat[21726]: CONNECT
    Jun  5 15:10:48 voip chat[21726]:  – got it
    Jun  5 15:10:48 voip chat[21726]: send (\d)
    Jun  5 15:10:49 voip pppd[21343]: Serial connection established.
    Jun  5 15:10:49 voip pppd[21343]: using channel 5
    Jun  5 15:10:49 voip pppd[21343]: Using interface ppp0
    Jun  5 15:10:49 voip pppd[21343]: Connect: ppp0 <–> /dev/ttyUSB0
    Jun  5 15:10:50 voip pppd[21343]: sent [LCP ConfReq id=0x3 <asyncmap 0x0=""><magic 0xa3319297=""><pcomp><accomp>]
    Jun  5 15:10:50 voip pppd[21343]: rcvd [LCP ConfReq id=0xc <asyncmap 0x0=""><auth chap="" md5=""><magic 0x10cc66f=""><pcomp><accomp>]
    Jun  5 15:10:50 voip pppd[21343]: No auth is possible
    Jun  5 15:10:50 voip pppd[21343]: sent [LCP ConfRej id=0xc <auth chap="" md5="">]
    Jun  5 15:10:50 voip pppd[21343]: rcvd [LCP ConfAck id=0x3 <asyncmap 0x0=""><magic 0xa3319297=""><pcomp><accomp>]
    Jun  5 15:10:50 voip pppd[21343]: rcvd [LCP ConfReq id=0xd <asyncmap 0x0=""><magic 0x10cc66f=""><pcomp><accomp>]
    Jun  5 15:10:50 voip pppd[21343]: sent [LCP ConfAck id=0xd <asyncmap 0x0=""><magic 0x10cc66f=""><pcomp><accomp>]
    Jun  5 15:10:50 voip pppd[21343]: sent [LCP EchoReq id=0x0 magic=0xa3319297]
    Jun  5 15:10:50 voip pppd[21343]: sent [CCP ConfReq id=0x2 <deflate 15=""><deflate(old#) 15=""><bsd 15="" v1="">]
    Jun  5 15:10:50 voip pppd[21343]: sent [IPCP ConfReq id=0x6 <compress vj="" 0f="" 01=""><addr 0.0.0.0=""><ms-dns1 0.0.0.0=""><ms-dns3 0.0.0.0="">]
    Jun  5 15:10:50 voip pppd[21343]: rcvd [LCP DiscReq id=0xe magic=0x10cc66f]
    Jun  5 15:10:50 voip pppd[21343]: rcvd [LCP EchoRep id=0x0 magic=0x10cc66f a3 31 92 97]
    Jun  5 15:10:50 voip pppd[21343]: rcvd [LCP ProtRej id=0xf 80 fd 01 02 00 0f 1a 04 78 00 18 04 78 00 15 03 2f]
    Jun  5 15:10:50 voip pppd[21343]: Protocol-Reject for 'Compression Control Protocol' (0x80fd) received
    Jun  5 15:10:51 voip pppd[21343]: rcvd [IPCP ConfNak id=0x6 <ms-dns1 10.11.12.13=""><ms-dns3 10.11.12.14=""><ms-wins 10.11.12.13=""><ms-wins 10.11.12.14="">]
    Jun  5 15:10:51 voip pppd[21343]: sent [IPCP ConfReq id=0x7 <compress vj="" 0f="" 01=""><addr 0.0.0.0=""><ms-dns1 10.11.12.13=""><ms-dns3 10.11.12.14="">]
    Jun  5 15:10:52 voip pppd[21343]: rcvd [IPCP ConfNak id=0x7 <ms-dns1 10.11.12.13=""><ms-dns3 10.11.12.14=""><ms-wins 10.11.12.13=""><ms-wins 10.11.12.14="">]
    Jun  5 15:10:52 voip pppd[21343]: sent [IPCP ConfReq id=0x8 <compress vj="" 0f="" 01=""><addr 0.0.0.0=""><ms-dns1 10.11.12.13=""><ms-dns3 10.11.12.14="">]
    Jun  5 15:10:52 voip pppd[21343]: rcvd [IPCP ConfReq id=0x6]
    Jun  5 15:10:52 voip pppd[21343]: sent [IPCP ConfNak id=0x6 <addr 0.0.0.0="">]
    Jun  5 15:10:52 voip pppd[21343]: rcvd [IPCP ConfRej id=0x8 <compress vj="" 0f="" 01="">]
    Jun  5 15:10:52 voip pppd[21343]: sent [IPCP ConfReq id=0x9 <addr 0.0.0.0=""><ms-dns1 10.11.12.13=""><ms-dns3 10.11.12.14="">]
    Jun  5 15:10:52 voip pppd[21343]: rcvd [IPCP ConfReq id=0x7]
    Jun  5 15:10:52 voip pppd[21343]: sent [IPCP ConfAck id=0x7]
    Jun  5 15:10:52 voip pppd[21343]: rcvd [IPCP ConfNak id=0x9 <addr 94.27.223.95=""><ms-dns1 84.2.46.1=""><ms-dns3 84.2.44.1="">]
    Jun  5 15:10:52 voip pppd[21343]: sent [IPCP ConfReq id=0xa <addr 94.27.223.95=""><ms-dns1 84.2.46.1=""><ms-dns3 84.2.44.1="">]
    Jun  5 15:10:52 voip pppd[21343]: rcvd [IPCP ConfAck id=0xa <addr 94.27.223.95=""><ms-dns1 84.2.46.1=""><ms-dns3 84.2.44.1="">]
    Jun  5 15:10:52 voip pppd[21343]: Could not determine remote IP address: defaulting to 10.64.64.64
    Jun  5 15:10:52 voip pppd[21343]: Cannot determine ethernet address for proxy ARP
    Jun  5 15:10:52 voip pppd[21343]: local  IP address 94.27.223.95
    Jun  5 15:10:52 voip pppd[21343]: remote IP address 10.64.64.64
    Jun  5 15:10:52 voip pppd[21343]: primary  DNS address 84.2.46.1
    Jun  5 15:10:52 voip pppd[21343]: secondary DNS address 84.2.44.1

    Please help me, what can i do to eliminate:
    "IPCP: parameter negotiation failed" problem.

    Thank You in advance!</ms-dns3></ms-dns1></addr></ms-dns3></ms-dns1></addr></ms-dns3></ms-dns1></addr></ms-dns3></ms-dns1></addr></compress></addr></ms-dns3></ms-dns1></addr></compress></ms-wins></ms-wins></ms-dns3></ms-dns1></ms-dns3></ms-dns1></addr></compress></ms-wins></ms-wins></ms-dns3></ms-dns1></ms-dns3></ms-dns1></addr></compress></bsd></deflate(old#)></deflate></accomp></pcomp></magic></asyncmap></accomp></pcomp></magic></asyncmap></accomp></pcomp></magic></asyncmap></auth></accomp></pcomp></magic></auth></asyncmap></accomp></pcomp></magic></asyncmap></huawei></huawei></data></huawei></huawei></huawei>


Locked