Wolfgang, Are these cisco routers on both sides ? do you have control of both or not? It appears as this is an async connection on this end (modem).
We get a input packet with CONFREJ (reject) but it appears to get further later and rejects on IPCP. Then theres a protocol reject (PROTREJ) inbound. Once sides has a MRU of 2048 and other has MRU of 1524. I've seen some PPP connections have problems with mismatched MRUs. Need to know more about the configs on both sides, and possibly get debugs from both sides if possible. erick --- wolfgang klages wrote: > Hi Erick, thanks for the feedback. The debug output > is > below... > > May 29 21:30:39.033 UTC: As132 LCP: I CONFREQ > [Closed] > id 41 len 24 > May 29 21:30:39.033 UTC: As132 LCP: MRU 2048 > (0x01040800) > May 29 21:30:39.033 UTC: As132 LCP: ACCM > 0x000A0000 > (0x0206000A0000) > May 29 21:30:39.033 UTC: As132 LCP: PFC (0x0702) > May 29 21:30:39.033 UTC: As132 LCP: ACFC (0x0802) > May 29 21:30:39.033 UTC: As132 LCP: MagicNumber > 0xA3266B61 (0x0506A3266B61) > May 29 21:30:39.037 UTC: As132 LCP: Lower layer not > up, Fast Starting > May 29 21:30:39.037 UTC: As132 PPP: Treating > connection as a dedicated line > May 29 21:30:39.037 UTC: As132 PPP: Phase is > ESTABLISHING, Active Open > May 29 21:30:39.037 UTC: As132 AAA/AUTHOR/FSM: (0): > LCP succeeds trivially > May 29 21:30:39.037 UTC: As132 LCP: O CONFREQ > [Closed] > id 1 len 44 > May 29 21:30:39.037 UTC: As132 LCP: ACCM > 0x000A0000 > (0x0206000A0000) > May 29 21:30:39.037 UTC: As132 LCP: AuthProto PAP > (0x0304C023) > May 29 21:30:39.037 UTC: As132 LCP: MagicNumber > 0xB1DD6D13 (0x0506B1DD6D13) > May 29 21:30:39.037 UTC: As132 LCP: PFC (0x0702) > May 29 21:30:39.037 UTC: As132 LCP: ACFC (0x0802) > May 29 21:30:39.037 UTC: As132 LCP: MRRU 1524 > (0x110405F4) > May 29 21:30:39.037 UTC: As132 LCP: EndpointDisc > 1 > Local (0x1310015741502D4D5 > 54C54494C494E4B) > May 29 21:30:39.037 UTC: As132 LCP: O CONFACK > [REQsent] id 41 len 24 > May 29 21:30:39.037 UTC: As132 LCP: MRU 2048 > (0x01040800) > May 29 21:30:39.037 UTC: As132 LCP: ACCM > 0x000A0000 > (0x0206000A0000) > May 29 21:30:39.037 UTC: As132 LCP: PFC (0x0702) > May 29 21:30:39.037 UTC: As132 LCP: ACFC (0x0802) > May 29 21:30:39.037 UTC: As132 LCP: MagicNumber > 0xA3266B61 (0x0506A3266B61) > May 29 21:30:39.785 UTC: As132 LCP: I CONFREJ > [ACKsent] id 1 len 24 > May 29 21:30:39.785 UTC: As132 LCP: MRRU 1524 > (0x110405F4) > May 29 21:30:39.785 UTC: As132 LCP: EndpointDisc > 1 > Local (0x1310015741502D4D5 > 54C54494C494E4B) > May 29 21:30:39.789 UTC: As132 LCP: O CONFREQ > [ACKsent] id 2 len 24 > May 29 21:30:39.789 UTC: As132 LCP: ACCM > 0x000A0000 > (0x0206000A0000) > May 29 21:30:39.789 UTC: As132 LCP: AuthProto PAP > (0x0304C023) > May 29 21:30:39.789 UTC: As132 LCP: MagicNumber > 0xB1DD6D13 (0x0506B1DD6D13) > May 29 21:30:39.789 UTC: As132 LCP: PFC (0x0702) > May 29 21:30:39.789 UTC: As132 LCP: ACFC (0x0802) > May 29 21:30:40.525 UTC: As132 LCP: I CONFACK > [ACKsent] id 2 len 24 > May 29 21:30:40.525 UTC: As132 LCP: ACCM > 0x000A0000 > (0x0206000A0000) > May 29 21:30:40.525 UTC: As132 LCP: AuthProto PAP > (0x0304C023) > May 29 21:30:40.525 UTC: As132 LCP: MagicNumber > 0xB1DD6D13 (0x0506B1DD6D13) > May 29 21:30:40.525 UTC: As132 LCP: PFC (0x0702) > May 29 21:30:40.525 UTC: As132 LCP: ACFC (0x0802) > May 29 21:30:40.525 UTC: As132 LCP: State is Open > May 29 21:30:40.525 UTC: As132 PPP: Phase is > AUTHENTICATING, by this end > May 29 21:30:40.541 UTC: As132 PAP: I AUTH-REQ id 1 > len 17 from "ciscowap" > May 29 21:30:40.541 UTC: As132 PPP: Phase is > FORWARDING > May 29 21:30:40.541 UTC: As132 PPP: Phase is > AUTHENTICATING > May 29 21:30:40.541 UTC: As132 PAP: Authenticating > peer ciscowap > May 29 21:30:40.541 UTC: AAA: parse name=Async132 > idb > type=10 tty=132 > May 29 21:30:40.541 UTC: AAA: name=Async132 > flags=0x11 > type=4 shelf=0 slot=0 ada > pter=0 port=132 channel=0 > May 29 21:30:40.541 UTC: AAA: parse name=Serial0:0 > idb > type=13 tty=-1 > May 29 21:30:40.541 UTC: AAA: name=Serial0:0 > flags=0x51 type=1 shelf=0 slot=0 ad > apter=0 port=0 channel=0 > May 29 21:30:40.541 UTC: AAA/MEMORY: create_user > (0x625F47BC) user='ciscowap' ru > ser='' port='Async132' > rem_addr='07714226291/01212757990' authen_type=PAP > servic > e=PPP priv=1 > May 29 21:30:40.541 UTC: AAA/AUTHEN/START > (2546412185): port='Async132' list='IS > DN' action=LOGIN service=PPP > May 29 21:30:40.541 UTC: AAA/AUTHEN/START > (2546412185): found list ISDN > May 29 21:30:40.541 UTC: AAA/AUTHEN/START > (2546412185): Method=LOCAL > May 29 21:30:40.541 UTC: AAA/AUTHEN (2546412185): > status = PASS > May 29 21:30:40.541 UTC: As132 AAA/AUTHOR/LCP: > Authorize LCP > May 29 21:30:40.541 UTC: As132 AAA/AUTHOR/LCP > (3166493837): Port='Async132' list > ='' service=NET > May 29 21:30:40.541 UTC: AAA/AUTHOR/LCP: As132 > (3166493837) user='ciscowap' > May 29 21:30:40.541 UTC: As132 AAA/AUTHOR/LCP > (3166493837): send AV service=ppp > May 29 21:30:40.541 UTC: As132 AAA/AUTHOR/LCP > (3166493837): send AV protocol=lcp > May 29 21:30:40.541 UTC: As132 AAA/AUTHOR/LCP > (3166493837): found list "default" > May 29 21:30:40.541 UTC: As132 AAA/AUTHOR/LCP > (3166493837): Method=LOCAL > May 29 21:30:40.541 UTC: As132 AAA/AUTHOR > (3166493837): Post authorization statu > s = PASS_REPL > May 29 21:30:40.541 UTC: As132 AAA/AUTHOR/LCP: > Processing AV service=ppp > May 29 21:30:40.541 UTC: As132 AAA/AUTHOR/LCP: > Processing AV protocol=lcp > May 29 21:30:40.545 UTC: As132 PAP: O AUTH-ACK id 1 > len 5 > May 29 21:30:40.545 UTC: Vi1 PPP: Phase is DOWN, > Setup > May 29 21:30:40.689 UTC: Vi1 PPP: Using set call > direction > May 29 21:30:40.689 UTC: Vi1 PPP: Treating > connection > as a callin > May 29 21:30:40.689 UTC: Vi1 PPP: Phase is > ESTABLISHING, Passive Open > May 29 21:30:40.689 UTC: Vi1 LCP: State is Listen > May 29 21:30:40.689 UTC: As132 PPP: Phase is > FORWARDED > May 29 21:30:40.689 UTC: Vi1 LCP: I FORCED CONFREQ > len > 20 > May 29 21:30:40.689 UTC: Vi1 LCP: ACCM 0x000A0000 > (0x0206000A0000) > May 29 21:30:40.689 UTC: Vi1 LCP: AuthProto PAP > (0x0304C023) > May 29 21:30:40.689 UTC: Vi1 LCP: MagicNumber > 0xB1DD6D13 (0x0506B1DD6D13) > May 29 21:30:40.689 UTC: Vi1 LCP: PFC (0x0702) > May 29 21:30:40.689 UTC: Vi1 LCP: ACFC (0x0802) > May 29 21:30:40.689 UTC: Vi1 PPP: Phase is UP > May 29 21:30:40.693 UTC: Vi1 AAA/AUTHOR/FSM: (0): > Can > we start IPCP? > May 29 21:30:40.693 UTC: Vi1 AAA/AUTHOR/FSM > (911729916): Port='Async132' list='' > service=NET > May 29 21:30:40.693 UTC: AAA/AUTHOR/FSM: Vi1 > (911729916) user='ciscowap' > May 29 21:30:40.693 UTC: Vi1 AAA/AUTHOR/FSM > (911729916): send AV service=ppp > May 29 21:30:40.693 UTC: Vi1 AAA/AUTHOR/FSM > (911729916): send AV protocol=ip > May 29 21:30:40.693 UTC: Vi1 AAA/AUTHOR/FSM > (911729916): found list "default" > May 29 21:30:40.693 UTC: Vi1 AAA/AUTHOR/FSM > (911729916): Method=LOCAL > May 29 21:30:40.693 UTC: Vi1 AAA/AUTHOR (911729916): > Post authorization status = > PASS_REPL > May 29 21:30:40.693 UTC: Vi1 AAA/AUTHOR/FSM: We can > start IPCP > May 29 21:30:40.693 UTC: Vi1 IPCP: O CONFREQ > [Closed] > id 1 len 10 > May 29 21:30:40.693 UTC: Vi1 IPCP: Address > 195.92.71.165 (0x0306C35C47A5) > May 29 21:30:40.693 UTC: Vi1 AAA/AUTHOR/FSM: (0): > Can > we start CCP? > May 29 21:30:40.693 UTC: Vi1 AAA/AUTHOR/FSM > (1020998467): Port='Async132' list=' > ' service=NET > May 29 21:30:40.693 UTC: AAA/AUTHOR/FSM: Vi1 > (1020998467) user='ciscowap' > May 29 21:30:40.693 UTC: Vi1 AAA/AUTHOR/FSM > (1020998467): send AV service=ppp > May 29 21:30:40.693 UTC: Vi1 AAA/AUTHOR/FSM > (1020998467): send AV protocol=ccp > May 29 21:30:40.693 UTC: Vi1 AAA/AUTHOR/FSM > (1020998467): found list "default" > May 29 21:30:40.693 UTC: Vi1 AAA/AUTHOR/FSM > (1020998467): Method=LOCAL > May 29 21:30:40.693 UTC: Vi1 AAA/AUTHOR > (1020998467): > Post authorization status > = PASS_REPL > May 29 21:30:40.693 UTC: Vi1 AAA/AUTHOR/FSM: We can > start CCP > May 29 21:30:40.693 UTC: Vi1 CCP: O CONFREQ [Closed] > id 1 len 10 > May 29 21:30:40.693 UTC: Vi1 CCP: LZSDCP history > 1 > check mode SEQ process UNC > OMPRESSSED (0x170600010201) > May 29 21:30:41.277 UTC: Vi1 IPCP: I CONFREQ > [REQsent] > id 39 len 28 > May 29 21:30:41.277 UTC: Vi1 IPCP: Address > 0.0.0.0 > (0x030600000000) > May 29 21:30:41.277 UTC: Vi1 IPCP: PrimaryDNS > 0.0.0.0 (0x810600000000) > May 29 21:30:41.277 UTC: Vi1 IPCP: SecondaryDNS > 0.0.0.0 (0x830600000000) > May 29 21:30:41.277 UTC: Vi1 IPCP: CompressType > VJ > 15 slots CompressSlotID (0 > x0206002D0F01) > May 29 21:30:41.277 UTC: Vi1 AAA/AUTHOR/IPCP: Start. > > Her address 0.0.0.0, we wa > nt 0.0.0.0 > May 29 21:30:41.277 UTC: Vi1 AAA/AUTHOR/IPCP: > Processing AV service=ppp > May 29 21:30:41.277 UTC: Vi1 AAA/AUTHOR/IPCP: > Processing AV protocol=ip > May 29 21:30:41.277 UTC: Vi1 AAA/AUTHOR/IPCP: > Authorization succeeded > May 29 21:30:41.277 UTC: Vi1 AAA/AUTHOR/IPCP: Done. > Her address 0.0.0.0, we wan > t 0.0.0.0 > May 29 21:30:41.277 UTC: Vi1 IPCP: Pool returned > 62.136.202.1 > May 29 21:30:41.277 UTC: Vi1 IPCP: O CONFREJ > [REQsent] > id 39 len 10 > May 29 21:30:41.277 UTC: Vi1 IPCP: CompressType > VJ > 15 slots CompressSlotID (0 > x0206002D0F01) > May 29 21:30:41.405 UTC: Vi1 IPCP: I CONFACK > [REQsent] > id 1 len 10 > May 29 21:30:41.405 UTC: Vi1 IPCP: Address > 195.92.71.165 (0x0306C35C47A5) > May 29 21:30:41.405 UTC: Vi1 LCP: I PROTREJ [Open] > id > 42 len 16 protocol CCP (0x > 80FD0101000A170600010201) > May 29 21:30:41.405 UTC: Vi1 AAA/AUTHOR/FSM: (0): > LCP > succeeds trivially > May 29 21:30:41.405 UTC: Vi1 CCP: State is Closed > May 29 21:30:42.029 UTC: Vi1 IPCP: I CONFREQ > [ACKrcvd] > id 41 len 22 > May 29 21:30:42.029 UTC: Vi1 IPCP: Address > 0.0.0.0 > (0x030600000000) > May 29 21:30:42.029 UTC: Vi1 IPCP: PrimaryDNS > 0.0.0.0 (0x810600000000) > May 29 21:30:42.029 UTC: Vi1 IPCP: SecondaryDNS > 0.0.0.0 (0x830600000000) > May 29 21:30:42.029 UTC: Vi1 AAA/AUTHOR/IPCP: Start. > > Her address 0.0.0.0, we wa > nt 62.136.202.1 > May 29 21:30:42.029 UTC: Vi1 AAA/AUTHOR/IPCP: > Processing AV service=ppp > May 29 21:30:42.029 UTC: Vi1 AAA/AUTHOR/IPCP: > Processing AV protocol=ip > May 29 21:30:42.029 UTC: Vi1 AAA/AUTHOR/IPCP: > Authorization succeeded > May 29 21:30:42.029 UTC: Vi1 AAA/AUTHOR/IPCP: Done. > Her address 0.0.0.0, we wan > t 62.136.202.1 > May 29 21:30:42.029 UTC: Vi1 IPCP: O CONFNAK > [ACKrcvd] > id 41 len 22 > May 29 21:30:42.029 UTC: Vi1 IPCP: Address > 62.136.202.1 (0x03063E88CA01) > May 29 21:30:42.029 UTC: Vi1 IPCP: PrimaryDNS > 195.92.195.95 (0x8106C35CC35F) > May 29 21:30:42.029 UTC: Vi1 IPCP: SecondaryDNS > 195.92.195.94 (0x8306C35CC35E > ) > May 29 21:30:42.689 UTC: Vi1 LCP: TIMEout: State > Open > May 29 21:30:42.693 UTC: Vi1 IPCP: TIMEout: State > ACKrcvd > May 29 21:30:42.693 UTC: Vi1 IPCP: O CONFREQ > [ACKrcvd] > id 2 len 10 > May 29 21:30:42.693 UTC: Vi1 IPCP: Address > 195.92.71.165 (0x0306C35C47A5) > May 29 21:30:42.781 UTC: Vi1 IPCP: I CONFREQ > [REQsent] > id 43 len 22 > May 29 21:30:42.781 UTC: Vi1 IPCP: Address > 62.136.202.1 (0x03063E88CA01) > May 29 21:30:42.781 UTC: Vi1 IPCP: PrimaryDNS > 195.92.195.95 (0x8106C35CC35F) > May 29 21:30:42.781 UTC: Vi1 IPCP: SecondaryDNS > 195.92.195.94 (0x8306C35CC35E > ) > May 29 21:30:42.781 UTC: Vi1 AAA/AUTHOR/IPCP: Start. > > Her address 62.136.202.1, > we want 62.136.202.1 > May 29 21:30:42.781 UTC: Vi1 AAA/AUTHOR/IPCP > (2038924148): Port='Async132' list= > '' service=NET > May 29 21:30:42.781 UTC: AAA/AUTHOR/IPCP: Vi1 > (2038924148) user='ciscowap' > May 29 21:30:42.781 UTC: Vi1 AAA/AUTHOR/IPCP > (2038924148): send AV service=ppp > May 29 21:30:42.781 UTC: Vi1 AAA/AUTHOR/IPCP > (2038924148): send AV protocol=ip > May 29 21:30:42.781 UTC: Vi1 AAA/AUTHOR/IPCP > (2038924148): send AV addr*62.136.2 > 02.1 > May 29 21:30:42.781 UTC: Vi1 AAA/AUTHOR/IPCP > (2038924148): found list "default" > May 29 21:30:42.781 UTC: Vi1 AAA/AUTHOR/IPCP > (2038924148): Method=LOCAL > May 29 21:30:42.781 UTC: Vi1 AAA/AUTHOR > (2038924148): > Post authorization status > = PASS_REPL > May 29 21:30:42.781 UTC: Vi1 AAA/AUTHOR/IPCP: Reject > 62.136.202.1, using 62.136. > 202.1 > May 29 21:30:42.781 UTC: Vi1 AAA/AUTHOR/IPCP: > Processing AV service=ppp > May 29 21:30:42.785 UTC: Vi1 AAA/AUTHOR/IPCP: > Processing AV protocol=ip > May 29 21:30:42.785 UTC: Vi1 AAA/AUTHOR/IPCP: > Processing AV addr*62.136.202.1 > May 29 21:30:42.785 UTC: Vi1 AAA/AUTHOR/IPCP: > Authorization succeeded > May 29 21:30:42.785 UTC: Vi1 AAA/AUTHOR/IPCP: Done. > Her address 62.136.202.1, w > e want 62.136.202.1 > May 29 21:30:42.785 UTC: Vi1 IPCP: O CONFACK > [REQsent] > id 43 len 22 > May 29 21:30:42.785 UTC: Vi1 IPCP: Address > 62.136.202.1 (0x03063E88CA01) > May 29 21:30:42.785 UTC: Vi1 IPCP: PrimaryDNS > 195.92.195.95 (0x8106C35CC35F) > May 29 21:30:42.785 UTC: Vi1 IPCP: SecondaryDNS > 195.92.195.94 (0x8306C35CC35E > ) > May 29 21:30:43.405 UTC: Vi1 IPCP: I CONFACK > [ACKsent] > id 2 len 10 > May 29 21:30:43.405 UTC: Vi1 IPCP: Address > 195.92.71.165 (0x0306C35C47A5) > May 29 21:30:43.405 UTC: Vi1 IPCP: State is Open > May 29 21:30:43.409 UTC: Vi1 IPCP: Install route to > 62.136.202.1 > May 29 21:31:06.702 UTC: ISDN Se0:15 SC: TX -> RRp > sapi = 0 tei = 0 nr = 54 > May 29 21:31:06.702 UTC: ISDN Se0:15 SC: RX wrote: > > Perhaps > the router isn't seeing the CONFREQ from the > > other router so is sending it's own. I've seen > this > > kind of activity when troubleshooting PPP > problems. > > Could be a line issue of some sort, code issue, > > misconfiguration perhaps, etc. > > > > What type of connection is this (Point to point, > > ISDN, > > etc)? > > > > You're best bet would be to post the debug output > > here > > from both routers (debug ppp nego). Set the > > timestamp > > to datetime format also (service timestamp debug > > datetime msec). > > > > HTH, Erick > > > > --- wolfgang klages > > wrote: > > > Group, > > > > > > Couple of PPP questions... > > > > > > [1] I'm looking at the debug output of a PPP > > > negotiation on a Cisco router. > > > The router receives a CONFREQ in the 'Closed' > > state. > > > RFC1661 specifies > > > that the router should reply with a > Terminate-Ack. > > > However, the router > > > replies with a CONFREQ of its own. The router > then > > > moves from the 'Closed' > > > state to the 'REQsent' state. Hard to believe > but > > > could it be that the > > > router is not behaving according to RFC1661. > > > > > > [2] In this same debug output, I see the router > > > receive a 'FORCED > > > CONFREQ'. This message is not in RFC1661. Is > this > > > something internal > > > only to Cisco routers? If so, what is its > purpose? > > > > > > __________________________________________________ > > Do you Yahoo!? > > Yahoo! Mail Plus - Powerful. Affordable. Sign up > > now. > > http://mailplus.yahoo.com > > __________________________________________________ > Do You Yahoo!? > Everything you'll ever need on one web page > from News and Sport to Email and Music Charts > http://uk.my.yahoo.com [EMAIL PROTECTED] __________________________________________________ Do you Yahoo!? Yahoo! Mail Plus - Powerful. Affordable. Sign up now. http://mailplus.yahoo.com Message Posted at: http://www.groupstudy.com/form/read.php?f=7&i=58775&t=58462 -------------------------------------------------- FAQ, list archives, and subscription info: http://www.groupstudy.com/list/cisco.html Report misconduct and Nondisclosure violations to [EMAIL PROTECTED]
