LinuxQuestions.org

LinuxQuestions.org (/questions/)
-   Linux - Networking (https://www.linuxquestions.org/questions/linux-networking-3/)
-   -   PPP problems (https://www.linuxquestions.org/questions/linux-networking-3/ppp-problems-706219/)

Fredde87 02-20-2009 10:57 AM
PPP problems
 
Hi,

I am having problems getting my PPP to work. I am trying to dial to my ISP without any luck. I tried a different RAS server as well but still got the same issues.

I use WvDial with pppd to connect. If I dont use Stupid Mode then I get stuck on the "Carrier detected. Waiting for prompt." line, this is my log then,

Quote:
Using interface ppp0
local IP address 10.64.64.64
remote IP address 10.112.112.112
Starting link
--> WvDial: Internet dialer version 1.60
--> Initializing modem.
--> Sending: AT
AT
OK
--> Sending: AT&F1 %V2 %P3
AT&F1 %V2 %P3
OK
--> Modem initialized.
--> Idle Seconds = 120, disabling automatic reconnect.
--> Sending: ATDT28#
--> Waiting for carrier.
ATDT28#
CONNECT 115200
--> Carrier detected. Waiting for prompt.
~[7f][03]@![01]7 ![03][04]@#[05][06]\VI+[11][04][05]t[13][0f][01]SNET-NY-AS01[15]5~~[7f][03]@![01]8 ![03][04]@#[05][06]\VI+[11][04][05]t[13][0f][01]SNET-NY-AS01 Z~~[7f][03]@![01]9 ![03][04]@#[05][06]\VI+[11][04][05]t[13][0f][01]SNET-NY-AS01k-~~[7f][03]@![01]: ![03][04]@#[05][06]\VI+[11][04][05]t[13][0f][01]SNET-NY-AS01M4~~[7f][03]@![01]; ![03][04]@#[05][06]\VI+[11][04][05]t[13][0f][01]SNET-NY-AS01/C~~[7f][03]@![01]< ![03][04]@#[05][06]\VI+[11][04][05]t[13][0f][01]SNET-NY-AS01[10][0f]~~[7f][03]@![01]= ![03][04]@#[05][06]\VI+[11][04][05]t[13][0f][01]SNET-NY-AS01rx~~[7f][03]@![01]> ![03][04]@#[05][06]\VI+[11][04][05]t[13][0f][01]SNET-NY-AS01Ta~~[7f][03]@![01]? ![03][04]@#[05][06]\VI+[11][04][05]t[13][0f][01]SNET-NY-AS016[16]~~[7f][03]@![01]@ ![03][04]@#[05][06]\VI+[11][04][05]t[13][0f][01]SNET-NY-AS01[1e][1a]~
--> Don't know what to do! Starting pppd and hoping for the best.
Serial connection established.
using channel 71
Connect: ppp0 <--> /dev/ttyS0
sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0xd997ea52> <pcomp> <accomp>]
Hangup (SIGHUP)
Modem hangup
Connection terminated.

If I turn Stupid Mode on then the pppd will start and I can se my machine sending ConfReq as below,

Quote:
Using interface ppp0
local IP address 10.64.64.64
remote IP address 10.112.112.112
Starting link
--> WvDial: Internet dialer version 1.60
--> Initializing modem.
--> Sending: AT
ERROR
--> Bad init string.
Caught signal 15: Attempting to exit gracefully...
Connect script failed
Using interface ppp0
local IP address 10.64.64.64
remote IP address 10.112.112.112
Starting link
--> WvDial: Internet dialer version 1.60
--> Initializing modem.
--> Sending: AT
AT
OK
--> Sending: AT&F1 %V2 %P3
AT&F1 %V2 %P3
OK
--> Modem initialized.
--> Idle Seconds = 120, disabling automatic reconnect.
--> Sending: ATDT28#
--> Waiting for carrier.
ATDT28#
CONNECT 115200
--> Carrier detected. Chatmode finished.
Serial connection established.
using channel 69
Connect: ppp0 <--> /dev/ttyS0
sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x62f90f68> <pcomp> <accomp>]
sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x62f90f68> <pcomp> <accomp>]
sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x62f90f68> <pcomp> <accomp>]
sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x62f90f68> <pcomp> <accomp>]
sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x62f90f68> <pcomp> <accomp>]
sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x62f90f68> <pcomp> <accomp>]
sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x62f90f68> <pcomp> <accomp>]
sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x62f90f68> <pcomp> <accomp>]
sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x62f90f68> <pcomp> <accomp>]
sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x62f90f68> <pcomp> <accomp>]
LCP: timeout sending Config-Requests
Connection terminated.
Hangup (SIGHUP)

If I add "sync" to my PPP config (I can change into HDLC mode on my modem by sending %P2 rather then %P3) then I get the following,

Quote:
Using interface ppp0
local IP address 10.64.64.64
remote IP address 10.112.112.112
Starting link
--> WvDial: Internet dialer version 1.60
--> Initializing modem.
--> Sending: AT
AT
OK
--> Sending: AT&F1 %V2 %P2
AT&F1 %V2 %P2
OK
--> Modem initialized.
--> Idle Seconds = 120, disabling automatic reconnect.
--> Sending: ATDT28#
--> Waiting for carrier.
ATDT28#
CONNECT 115200
--> Carrier detected. Chatmode finished.
Serial connection established.
using channel 72
Connect: ppp0 <--> /dev/ttyS0
sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x6eb8a57f> <pcomp> <accomp>]
rcvd [LCP] 01 c3 00 21 03 04 c0 23 05 06 5c 59 b9 f2 11 04 05 f4 13 0f
rcvd [proto=0x1] 53 4e 45 54 2d 4e 59 2d 41 53 30 31
Discarded non-LCP packet when LCP not open
rcvd [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0x6eb8a57f> <pcomp> <accomp>]
sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x6eb8a57f> <pcomp> <accomp>]
rcvd [LCP] 02 01 00 14
rcvd [proto=0x206] 00 00 00 00 05 06 6e b8 a5 7f 07 02 08 02
Discarded non-LCP packet when LCP not open
sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x6eb8a57f> <pcomp> <accomp>]
rcvd [LCP] 02 01 00 14
rcvd [proto=0x206] 00 00 00 00 05 06 6e b8 a5 7f 07 02 08 02
Discarded non-LCP packet when LCP not open
sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x6eb8a57f> <pcomp> <accomp>]
rcvd [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0x6eb8a57f> <pcomp> <accomp>]
rcvd [LCP] 01 c4 00 21 03 04 c0 23 05 06 5c 59
rcvd [proto=0xb9] f2 11 04 05 f4 13 0f 01 53 4e 45 54 2d 4e 59 2d 41 53 30 31
Discarded non-LCP packet when LCP not open
sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x6eb8a57f> <pcomp> <accomp>]
sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x6eb8a57f> <pcomp> <accomp>]
rcvd [LCP] 02 01 00 14
rcvd [proto=0x206] 00 00 00 00 05 06 6e b8 a5 7f 07 02 08 02
Discarded non-LCP packet when LCP not open
sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x6eb8a57f> <pcomp> <accomp>]
rcvd [LCP] 01 c5 00 21 03 04 c0 23 05 06 5c 59
rcvd [proto=0xb9] f2 11 04 05 f4 13 0f 01 53 4e 45 54 2d 4e 59 2d 41 53 30 31
Discarded non-LCP packet when LCP not open
sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x6eb8a57f> <pcomp> <accomp>]
sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x6eb8a57f> <pcomp> <accomp>]
rcvd [LCP] 02 01 00 14
rcvd [proto=0x206] 00 00 00 00 05 06 6e b8 a5 7f 07 02 08 02
Discarded non-LCP packet when LCP not open
sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x6eb8a57f> <pcomp> <accomp>]
sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x6eb8a57f> <pcomp> <accomp>]
rcvd [LCP] 01 c6 00 21
rcvd [proto=0x3] 04 c0 23 05 06 5c 59 b9 f2 11 04 05 f4 13 0f 01 53 4e 45 54 2d 4e 59 2d 41 53 30 31
Discarded non-LCP packet when LCP not open
sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x6eb8a57f> <pcomp> <accomp>]
sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x6eb8a57f> <pcomp> <accomp>]
rcvd [LCP] 02 01 00 14
rcvd [proto=0x206] 00 00 00 00 05 06 6e b8 a5 7f 07 02 08 02
Discarded non-LCP packet when LCP not open
sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x6eb8a57f> <pcomp> <accomp>]
rcvd [LCP] 02 01 00 14
rcvd [proto=0x206] 00 00 00 00 05 06 6e b8 a5 7f 07 02 08 02
Discarded non-LCP packet when LCP not open
rcvd [LCP] 01 c7 00 21 03 04 c0 23 05 06 5c 59 b9 f2 11 04 05 f4 13 0f 01 53 4e 45 54 2d 4e 59
LCP: timeout sending Config-Requests
Connection terminated.
Hangup (SIGHUP)

Here are the pppd options I am using

debug usepeerdns usehostname crtscts modem defaultroute noipdefault idle 120 user ***** password ***** demand


It seems that in HLDC I atleast get back ConfAcks but my pppd seems to ignore them and send out a new exact same ConfReq.

Any ideas why?


Edit: Forgot to mention that it dials fine (in PPP mode, not HLDC) in Windows. Here is some of the trace I got from my Windows XP machine.

Quote:
[172] 15:44:49:562: PPPEMSG_Start recvd, d=, hPort=10,callback=0,mask=830348,IfType=-1
[2016] 15:44:49:562: Line up event occurred on port 10
[2016] 15:44:49:562: Local identification = MSRAS-0-LWC-IBM-01
[2016] 15:44:49:562: PortName: COM5
[2016] 15:44:49:562: Starting PPP on link with IfType=0xffffffff,IPIf=0xffffffff,IPXIf=0xffffffff
[2016] 15:44:49:562: RasGetBuffer returned 3838fc8 for SendBuf
[2016] 15:44:49:562: FsmInit called for protocol = c021, port = 10
[2016] 15:44:49:562: ConfigInfo = 830348
[2016] 15:44:49:562: APs available = 5e
[2016] 15:44:49:562: FsmReset called for protocol = c021, port = 10
[2016] 15:44:49:562: Inserting port in bucket # 0
[2016] 15:44:49:562: Inserting bundle in bucket # 3
[2016] 15:44:49:562: FsmOpen event received for protocol c021 on port 10
[2016] 15:44:49:562: FsmThisLayerStarted called for protocol = c021, port = 10
[2016] 15:44:49:562: FsmUp event received for protocol c021 on port 10
[2016] 15:44:49:562: <PPP packet sent at 02/17/2009 15:44:49:562
[2016] 15:44:49:562: <Protocol = LCP, Type = Configure-Req, Length = 0x19, Id = 0x0, Port = 10
[2016] 15:44:49:562: <C0 21 01 00 00 17 02 06 00 00 00 00 05 06 60 DF |.!............`.|
[2016] 15:44:49:562: <42 80 07 02 08 02 0D 03 06 00 00 00 00 00 00 00 |B...............|
[2016] 15:44:49:562:
[2016] 15:44:49:562: InsertInTimerQ called portid=4,Id=0,Protocol=c021,EventType=0,fAuth=0
[2016] 15:44:49:562: InsertInTimerQ called portid=4,Id=0,Protocol=0,EventType=3,fAuth=0
[1248] 15:44:50:343: Packet received (35 bytes) for hPort 10
[2016] 15:44:50:343: >PPP packet received at 02/17/2009 15:44:50:343
[2016] 15:44:50:343: >Protocol = LCP, Type = Configure-Req, Length = 0x23, Id = 0x41, Port = 10
[2016] 15:44:50:343: >C0 21 01 41 00 21 03 04 C0 23 05 06 4C A7 C7 B2 |.!.A.!...#..L...|
[2016] 15:44:50:343: >11 04 05 F4 13 0F 01 53 4E 45 54 2D 4E 59 2D 41 |.......SNET-NY-A|
[2016] 15:44:50:343: >53 30 31 00 00 00 00 00 00 00 00 00 00 00 00 00 |S01.............|
[2016] 15:44:50:343:
[2016] 15:44:50:343: <PPP packet sent at 02/17/2009 15:44:50:343
[2016] 15:44:50:343: <Protocol = LCP, Type = Configure-Reject, Length = 0x19, Id = 0x41, Port = 10
[2016] 15:44:50:343: <C0 21 04 41 00 17 11 04 05 F4 13 0F 01 53 4E 45 |.!.A.........SNE|
[2016] 15:44:50:343: <54 2D 4E 59 2D 41 53 30 31 00 00 00 00 00 00 00 |T-NY-AS01.......|
[2016] 15:44:50:343:
[1248] 15:44:50:343: Packet received (9 bytes) for hPort 10
[2016] 15:44:50:343: >PPP packet received at 02/17/2009 15:44:50:343
[2016] 15:44:50:343: >Protocol = LCP, Type = Configure-Reject, Length = 0x9, Id = 0x0, Port = 10
[2016] 15:44:50:343: >C0 21 04 00 00 07 0D 03 06 00 00 00 00 00 00 00 |.!..............|
[2016] 15:44:50:343:
[2016] 15:44:50:343: RemoveFromTimerQ called portid=4,Id=0,Protocol=c021,EventType=0,fAuth=0
[2016] 15:44:50:343: <PPP packet sent at 02/17/2009 15:44:50:343
[2016] 15:44:50:343: <Protocol = LCP, Type = Configure-Req, Length = 0x16, Id = 0x1, Port = 10
[2016] 15:44:50:343: <C0 21 01 01 00 14 02 06 00 00 00 00 05 06 60 DF |.!............`.|
[2016] 15:44:50:343: <42 80 07 02 08 02 00 00 00 00 00 00 00 00 00 00 |B...............|
[2016] 15:44:50:343:
[2016] 15:44:50:343: InsertInTimerQ called portid=4,Id=1,Protocol=c021,EventType=0,fAuth=0
[1248] 15:44:51:125: Packet received (16 bytes) for hPort 10
[2016] 15:44:51:125: >PPP packet received at 02/17/2009 15:44:51:125
[2016] 15:44:51:125: >Protocol = LCP, Type = Configure-Req, Length = 0x10, Id = 0x42, Port = 10
[2016] 15:44:51:125: >C0 21 01 42 00 0E 03 04 C0 23 05 06 4C A7 C7 B2 |.!.B.....#..L...|
[2016] 15:44:51:125:
[2016] 15:44:51:125: <PPP packet sent at 02/17/2009 15:44:51:125
[2016] 15:44:51:125: <Protocol = LCP, Type = Configure-Ack, Length = 0x10, Id = 0x42, Port = 10
[2016] 15:44:51:125: <C0 21 02 42 00 0E 03 04 C0 23 05 06 4C A7 C7 B2 |.!.B.....#..L...|
[2016] 15:44:51:125:
[1248] 15:44:51:125: Packet received (22 bytes) for hPort 10
[2016] 15:44:51:125: >PPP packet received at 02/17/2009 15:44:51:125
[2016] 15:44:51:125: >Protocol = LCP, Type = Configure-Ack, Length = 0x16, Id = 0x1, Port = 10
[2016] 15:44:51:125: >C0 21 02 01 00 14 02 06 00 00 00 00 05 06 60 DF |.!............`.|
[2016] 15:44:51:125: >42 80 07 02 08 02 00 00 00 00 00 00 00 00 00 00 |B...............|
[2016] 15:44:51:125:
[2016] 15:44:51:125: RemoveFromTimerQ called portid=4,Id=1,Protocol=c021,EventType=0,fAuth=0
[2016] 15:44:51:125: FsmThisLayerUp called for protocol = c021, port = 10
[2016] 15:44:51:125: LCP Local Options-------------
[2016] 15:44:51:125: MRU=1500,ACCM=0,Auth=0,MagicNumber=1625244288,PFC=ON,ACFC=ON
[2016] 15:44:51:125: Recv Framing = PPP,SSHF=OFF,MRRU=1500,LinkDiscrim=0,BAP=OFF
[2016] 15:44:51:125: LCP Remote Options-------------
[2016] 15:44:51:125: MRU=1500,ACCM=-1,Auth=c023,MagicNumber=1286064050,PFC=OFF,ACFC=OFF
[2016] 15:44:51:125: Send Framing = PPP,SSHF=OFF,MRRU=1500,LinkDiscrim=0
[2016] 15:44:51:125: LCP Configured successfully
[2016] 15:44:51:125: <PPP packet sent at 02/17/2009 15:44:51:125
[2016] 15:44:51:125: <Protocol = LCP, Type = Identification, Length = 0x14, Id = 0x2, Port = 10
[2016] 15:44:51:125: <C0 21 0C 02 00 12 60 DF 42 80 4D 53 52 41 53 56 |.!....`.B.MSRASV|
[2016] 15:44:51:125: <35 2E 31 30 00 00 00 00 00 00 00 00 00 00 00 00 |5.10............|
[2016] 15:44:51:125:
[2016] 15:44:51:125: <PPP packet sent at 02/17/2009 15:44:51:125
[2016] 15:44:51:125: <Protocol = LCP, Type = Identification, Length = 0x1c, Id = 0x3, Port = 10
[2016] 15:44:51:125: <C0 21 0C 03 00 1A 60 DF 42 80 4D 53 52 41 53 2D |.!....`.B.MSRAS-|
[2016] 15:44:51:125: <30 2D 4C 57 43 2D 49 42 4D 2D 30 31 00 00 00 00 |0-LWC-IBM-01....|
[2016] 15:44:51:125:
[2016] 15:44:51:125: Authenticating phase started
[2016] 15:44:51:125: Calling APWork in APStart
[2016] 15:44:51:125: <PPP packet sent at 02/17/2009 15:44:51:125
[2016] 15:44:51:125: <Protocol = PAP, Type = Protocol specific, Length = 0x16, Id = 0x2, Port = 10
[2016] 15:44:51:125:
[2016] 15:44:51:125: InsertInTimerQ called portid=4,Id=2,Protocol=c023,EventType=0,fAuth=0
[1248] 15:44:52:000: Packet received (18 bytes) for hPort 10
[2016] 15:44:52:000: >PPP packet received at 02/17/2009 15:44:52:000
[2016] 15:44:52:000: >Protocol = IPCP, Type = Configure-Req, Length = 0x12, Id = 0x1, Port = 10
[2016] 15:44:52:000: >80 21 01 01 00 10 02 06 00 2D 0F 01 03 06 D8 81 |.!.......-......|
[2016] 15:44:52:000: >AA 08 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |................|
[2016] 15:44:52:000:
[2016] 15:44:52:000: Non-LCP packet received when LCP is not opened
[2016] 15:44:52:000: Packet being silently discarded
[2016] 15:44:52:578: Recv timeout event received for portid=4,Id=2,Protocol=c023,fAuth=0
[2016] 15:44:52:578: <PPP packet sent at 02/17/2009 15:44:52:578
[2016] 15:44:52:578: <Protocol = PAP, Type = Protocol specific, Length = 0x16, Id = 0x3, Port = 10
[2016] 15:44:52:578:
[2016] 15:44:52:578: InsertInTimerQ called portid=4,Id=3,Protocol=c023,EventType=0,fAuth=0
[1248] 15:44:53:343: Packet received (7 bytes) for hPort 10
[2016] 15:44:53:343: >PPP packet received at 02/17/2009 15:44:53:343
[2016] 15:44:53:343: >Protocol = PAP, Type = Protocol specific, Length = 0x7, Id = 0x3, Port = 10
[2016] 15:44:53:343:
[2016] 15:44:53:343: RemoveFromTimerQ called portid=4,Id=3,Protocol=c023,EventType=0,fAuth=0
[2016] 15:44:53:343: FsmThisLayerUp called for protocol = c023, port = 10
[2016] 15:44:53:343: NotifyCaller(hPort=10, dwMsgId=17)
[2016] 15:44:53:343: NotifyCaller(hPort=10, dwMsgId=3)
[2016] 15:44:53:343: Will not initialize CP 8029
[2016] 15:44:53:343: Will not initialize CP 802b
[2016] 15:44:53:343: FsmInit called for protocol = 80fd, port = 10
[2016] 15:44:53:343: FsmInit for protocol = 80fd failed with error 731
[2016] 15:44:53:343: Will not initialize CP 8021
[2016] 15:44:53:343: NotifyCaller(hPort=10, dwMsgId=1)
[172] 15:44:53:359: PppStop

[172] 15:44:53:359: PPPEMSG_Stop recvd

[2016] 15:44:53:359: FsmClose event received for protocol c021 on port 10
[2016] 15:44:53:359: RemoveFromTimerQ called portid=4,Id=1,Protocol=c021,EventType=0,fAuth=0
[2016] 15:44:53:359: FsmThisLayerDown called for protocol = c021, port = 10
[2016] 15:44:53:359: RemoveFromTimerQ called portid=4,Id=3,Protocol=c023,EventType=0,fAuth=0
[2016] 15:44:53:359: RemoveFromTimerQ called portid=4,Id=0,Protocol=c029,EventType=0,fAuth=0
[2016] 15:44:53:359: <PPP packet sent at 02/17/2009 15:44:53:359
[2016] 15:44:53:359: <Protocol = LCP, Type = Terminate-Req, Length = 0x12, Id = 0x4, Port = 10
[2016] 15:44:53:359: <C0 21 05 04 00 10 60 DF 42 80 00 3C CD 74 00 00 |.!....`.B..<.t..|
[2016] 15:44:53:359: <02 D0 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |................|
[2016] 15:44:53:359:
[2016] 15:44:53:359: InsertInTimerQ called portid=4,Id=4,Protocol=c021,EventType=0,fAuth=0
[1248] 15:44:54:140: Packet received (6 bytes) for hPort 10
[2016] 15:44:54:140: >PPP packet received at 02/17/2009 15:44:54:140
[2016] 15:44:54:140: >Protocol = LCP, Type = Terminate-Ack, Length = 0x6, Id = 0x4, Port = 10
[2016] 15:44:54:140: >C0 21 06 04 00 04 00 00 00 00 00 00 00 00 00 00 |.!..............|
[2016] 15:44:54:140:
[2016] 15:44:54:140: RemoveFromTimerQ called portid=4,Id=4,Protocol=c021,EventType=0,fAuth=0
[2016] 15:44:54:140: FsmThisLayerFinished called for protocol = c021, port = 10
[2016] 15:44:54:140: NotifyCaller(hPort=10, dwMsgId=10)
[1248] 15:44:55:250: PPPEMSG_LineDown recvd, hPort=10

[2016] 15:44:55:250: Line down event occurred on port 10
[2016] 15:44:55:312: FsmDown event received for protocol c021 on port 10
[2016] 15:44:55:312: RemoveFromTimerQ called portid=4,Id=4,Protocol=c021,EventType=0,fAuth=0
[2016] 15:44:55:312: FsmReset called for protocol = c021, port = 10
[2016] 15:44:55:312: RemoveFromTimerQ called portid=4,Id=0,Protocol=0,EventType=3,fAuth=0
[2016] 15:44:55:312: RemoveFromTimerQ called portid=4,Id=0,Protocol=0,EventType=7,fAuth=0
[2016] 15:44:55:312: RemoveFromTimerQ called portid=4,Id=0,Protocol=0,EventType=2,fAuth=0
[2016] 15:44:55:312: RemoveFromTimerQ called portid=4,Id=0,Protocol=0,EventType=1,fAuth=0
[2016] 15:44:55:312: RemoveFromTimerQ called portid=4,Id=0,Protocol=c029,EventType=0,fAuth=0
[2016] 15:44:55:312: LcpEnd
[2016] 15:44:55:312: Post line down event occurred on port 10
[2016] 15:44:55:312: NotifyCaller(hPort=10, dwMsgId=23)
[1352] 15:13:05:937: PPPEMSG_Start recvd, d=, hPort=6,callback=0,mask=830348,IfType=-1
[3932] 15:13:05:953: Line up event occurred on port 6
[3932] 15:13:05:953: Local identification = MSRAS-0-LWC-IBM-01
[3932] 15:13:05:953: PortName: COM2
[3932] 15:13:05:953: Starting PPP on link with IfType=0xffffffff,IPIf=0xffffffff,IPXIf=0xffffffff
[3932] 15:13:05:953: RasGetBuffer returned 38791f0 for SendBuf
[3932] 15:13:05:953: FsmInit called for protocol = c021, port = 6
[3932] 15:13:05:953: ConfigInfo = 830348
[3932] 15:13:05:953: APs available = 5e
[3932] 15:13:05:953: FsmReset called for protocol = c021, port = 6
[3932] 15:13:05:953: Inserting port in bucket # 6
[3932] 15:13:05:953: Inserting bundle in bucket # 0
[3932] 15:13:05:953: FsmOpen event received for protocol c021 on port 6
[3932] 15:13:05:953: FsmThisLayerStarted called for protocol = c021, port = 6
[3932] 15:13:05:953: FsmUp event received for protocol c021 on port 6
[3932] 15:13:05:968: <PPP packet sent at 02/20/2009 15:13:05:953
[3932] 15:13:05:968: <Protocol = LCP, Type = Configure-Req, Length = 0x19, Id = 0x0, Port = 6
[3932] 15:13:05:968: <C0 21 01 00 00 17 02 06 00 00 00 00 05 06 12 48 |.!.............H|
[3932] 15:13:05:968: <49 BD 07 02 08 02 0D 03 06 00 00 00 00 00 00 00 |I...............|
[3932] 15:13:05:968:
[3932] 15:13:05:968: InsertInTimerQ called portid=0,Id=0,Protocol=c021,EventType=0,fAuth=0
[3932] 15:13:05:968: InsertInTimerQ called portid=0,Id=0,Protocol=0,EventType=3,fAuth=0
[3776] 15:13:06:750: Packet received (35 bytes) for hPort 6
[3776] 15:13:06:750: Packet received (9 bytes) for hPort 6
[3932] 15:13:06:750: >PPP packet received at 02/20/2009 15:13:06:750
[3932] 15:13:06:750: >Protocol = LCP, Type = Configure-Req, Length = 0x23, Id = 0x80, Port = 6
[3932] 15:13:06:750: >C0 21 01 80 00 21 03 04 C0 23 05 06 5B FD F1 B2 |.!...!...#..[...|
[3932] 15:13:06:750: >11 04 05 F4 13 0F 01 53 4E 45 54 2D 4E 59 2D 41 |.......SNET-NY-A|
[3932] 15:13:06:750: >53 30 31 00 00 00 00 00 00 00 00 00 00 00 00 00 |S01.............|
[3932] 15:13:06:750:
[3932] 15:13:06:750: <PPP packet sent at 02/20/2009 15:13:06:750
[3932] 15:13:06:750: <Protocol = LCP, Type = Configure-Reject, Length = 0x19, Id = 0x80, Port = 6
[3932] 15:13:06:750: <C0 21 04 80 00 17 11 04 05 F4 13 0F 01 53 4E 45 |.!...........SNE|
[3932] 15:13:06:750: <54 2D 4E 59 2D 41 53 30 31 00 00 00 00 00 00 00 |T-NY-AS01.......|
[3932] 15:13:06:750:
[3932] 15:13:06:750: >PPP packet received at 02/20/2009 15:13:06:750
[3932] 15:13:06:750: >Protocol = LCP, Type = Configure-Reject, Length = 0x9, Id = 0x0, Port = 6
[3932] 15:13:06:750: >C0 21 04 00 00 07 0D 03 06 00 00 00 00 00 00 00 |.!..............|
[3932] 15:13:06:750:
[3932] 15:13:06:750: RemoveFromTimerQ called portid=0,Id=0,Protocol=c021,EventType=0,fAuth=0
[3932] 15:13:06:750: <PPP packet sent at 02/20/2009 15:13:06:750
[3932] 15:13:06:750: <Protocol = LCP, Type = Configure-Req, Length = 0x16, Id = 0x1, Port = 6
[3932] 15:13:06:750: <C0 21 01 01 00 14 02 06 00 00 00 00 05 06 12 48 |.!.............H|
[3932] 15:13:06:750: <49 BD 07 02 08 02 00 00 00 00 00 00 00 00 00 00 |I...............|
[3932] 15:13:06:750:
[3932] 15:13:06:750: InsertInTimerQ called portid=0,Id=1,Protocol=c021,EventType=0,fAuth=0
[3776] 15:13:07:531: Packet received (16 bytes) for hPort 6
[3932] 15:13:07:531: >PPP packet received at 02/20/2009 15:13:07:531
[3932] 15:13:07:531: >Protocol = LCP, Type = Configure-Req, Length = 0x10, Id = 0x81, Port = 6
[3932] 15:13:07:531: >C0 21 01 81 00 0E 03 04 C0 23 05 06 5B FD F1 B2 |.!.......#..[...|
[3932] 15:13:07:531:
[3932] 15:13:07:531: <PPP packet sent at 02/20/2009 15:13:07:531
[3932] 15:13:07:531: <Protocol = LCP, Type = Configure-Ack, Length = 0x10, Id = 0x81, Port = 6
[3932] 15:13:07:531: <C0 21 02 81 00 0E 03 04 C0 23 05 06 5B FD F1 B2 |.!.......#..[...|
[3932] 15:13:07:531:
[3776] 15:13:07:531: Packet received (22 bytes) for hPort 6
[3932] 15:13:07:531: >PPP packet received at 02/20/2009 15:13:07:531
[3932] 15:13:07:531: >Protocol = LCP, Type = Configure-Ack, Length = 0x16, Id = 0x1, Port = 6
[3932] 15:13:07:531: >C0 21 02 01 00 14 02 06 00 00 00 00 05 06 12 48 |.!.............H|
[3932] 15:13:07:531: >49 BD 07 02 08 02 00 00 00 00 00 00 00 00 00 00 |I...............|
[3932] 15:13:07:531:
[3932] 15:13:07:531: RemoveFromTimerQ called portid=0,Id=1,Protocol=c021,EventType=0,fAuth=0
[3932] 15:13:07:531: FsmThisLayerUp called for protocol = c021, port = 6
[3932] 15:13:07:531: LCP Local Options-------------
[3932] 15:13:07:531: MRU=1500,ACCM=0,Auth=0,MagicNumber=306727357,PFC=ON,ACFC=ON
[3932] 15:13:07:531: Recv Framing = PPP,SSHF=OFF,MRRU=1500,LinkDiscrim=0,BAP=OFF
[3932] 15:13:07:531: LCP Remote Options-------------
[3932] 15:13:07:531: MRU=1500,ACCM=-1,Auth=c023,MagicNumber=1543369138,PFC=OFF,ACFC=OFF
[3932] 15:13:07:531: Send Framing = PPP,SSHF=OFF,MRRU=1500,LinkDiscrim=0
[3932] 15:13:07:531: LCP Configured successfully
[3932] 15:13:07:531: <PPP packet sent at 02/20/2009 15:13:07:531
[3932] 15:13:07:531: <Protocol = LCP, Type = Identification, Length = 0x14, Id = 0x2, Port = 6
[3932] 15:13:07:531: <C0 21 0C 02 00 12 12 48 49 BD 4D 53 52 41 53 56 |.!.....HI.MSRASV|
[3932] 15:13:07:531: <35 2E 31 30 00 00 00 00 00 00 00 00 00 00 00 00 |5.10............|
[3932] 15:13:07:531:
[3932] 15:13:07:531: <PPP packet sent at 02/20/2009 15:13:07:531
[3932] 15:13:07:531: <Protocol = LCP, Type = Identification, Length = 0x1c, Id = 0x3, Port = 6
[3932] 15:13:07:531: <C0 21 0C 03 00 1A 12 48 49 BD 4D 53 52 41 53 2D |.!.....HI.MSRAS-|
[3932] 15:13:07:531: <30 2D 4C 57 43 2D 49 42 4D 2D 30 31 00 00 00 00 |0-LWC-IBM-01....|
[3932] 15:13:07:531:
[3932] 15:13:07:531: Authenticating phase started
[3932] 15:13:07:531: Calling APWork in APStart
[3932] 15:13:07:531: <PPP packet sent at 02/20/2009 15:13:07:531
[3932] 15:13:07:531: <Protocol = PAP, Type = Protocol specific, Length = 0x16, Id = 0x0, Port = 6

rossonieri#1 02-21-2009 07:38 AM
hi fredde,

what is the problem actually?
have you got your PPP setting from your ISP?
have you tried to configure your PPP using wvdialconf command?

Fredde87 02-23-2009 03:19 AM
Quote:
Originally Posted by rossonieri#1 (Post 3452199)
hi fredde,

what is the problem actually?
have you got your PPP setting from your ISP?
have you tried to configure your PPP using wvdialconf command?
Hi, my ISP does not offer any linux support. I have got all the username, passwords and the telephone number from them but that is about it.

Using wvdialconf doesnt do anything differently. It detects the modem on the correct port and that the speed is 115200 but that is about it.

I cant seem to get PPP talking to the RAS. Using HDLC seems to be the best option but by the looks of it the PPP client is getting stuck in a loop.

Quote:
sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x6eb8a57f> <pcomp> <accomp>]
rcvd [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0x6eb8a57f> <pcomp> <accomp>]
Shouldnt we move on to the next step after the RAS has acknowledge our ConfReq? But we seem to just send a new ConfReq after we receive their ConfAck.


All times are GMT -5. The time now is 11:19 AM.