Following is a debug of an PPPoe PAP authentication attempt between a Cisco 2610 connected to a ADSL modem. When attempting to logon using PAP the message dated "*Feb 28 22:55:09.474" where "choice:" is being send back to the router appears to be causing an authentication problem. The ISP hasn't a clue. Any ideas.
Thanks
Lars
*Feb 28 22:55:08.043: %DIALER-6-BIND: Interface Vi1 bound to profile Di1
*Feb 28 22:55:08.047: %LINK-3-UPDOWN: Interface Virtual-Access1, changed state t
o up
*Feb 28 22:55:08.063: Vi1 PPP: Treating connection as a callout
*Feb 28 22:55:08.063: Vi1 PPP: Phase is ESTABLISHING, Active Open [0 sess, 0 loa
d]
*Feb 28 22:55:08.067: Vi1 PPP: No remote authentication for call-out
*Feb 28 22:55:08.067: Vi1 LCP: O CONFREQ [Closed] id 1 len 10
*Feb 28 22:55:08.067: Vi1 LCP: MagicNumber 0x05CE8AB4 (0x050605CE8AB4)
*Feb 28 22:55:08.091: Vi1 LCP: I CONFREQ [REQsent] id 112 len 18
*Feb 28 22:55:08.091: Vi1 LCP: MRU 1492 (0x010405D4)
*Feb 28 22:55:08.091: Vi1 LCP: AuthProto PAP (0x0304C023)
*Feb 28 22:55:08.091: Vi1 LCP: MagicNumber 0x08250D35 (0x050608250D35)
*Feb 28 22:55:08.095: Vi1 LCP: O CONFNAK [REQsent] id 112 len 8
*Feb 28 22:55:08.095: Vi1 LCP: MRU 1500 (0x010405DC)
*Feb 28 22:55:08.095: Vi1 LCP: I CONFACK [REQsent] id 1 len 10
*Feb 28 22:55:08.095: Vi1 LCP: MagicNumber 0x05CE8AB4 (0x050605CE8AB4)
*Feb 28 22:55:08.123: Vi1 LCP: I CONFREQ [ACKrcvd] id 113 len 18
*Feb 28 22:55:08.123: Vi1 LCP: MRU 1492 (0x010405D4)
*Feb 28 22:55:08.123: Vi1 LCP: AuthProto PAP (0x0304C023)
*Feb 28 22:55:08.127: Vi1 LCP: MagicNumber 0x08250D35 (0x050608250D35)
*Feb 28 22:55:08.127: Vi1 LCP: O CONFNAK [ACKrcvd] id 113 len 8
*Feb 28 22:55:08.127: Vi1 LCP: MRU 1500 (0x010405DC)
*Feb 28 22:55:08.139: Vi1 LCP: I CONFREQ [ACKrcvd] id 114 len 18
*Feb 28 22:55:08.139: Vi1 LCP: MRU 1492 (0x010405D4)
*Feb 28 22:55:08.139: Vi1 LCP: AuthProto PAP (0x0304C023)
*Feb 28 22:55:08.143: Vi1 LCP: MagicNumber 0x08250D35 (0x050608250D35)
*Feb 28 22:55:08.143: Vi1 LCP: O CONFNAK [ACKrcvd] id 114 len 8
*Feb 28 22:55:08.143: Vi1 LCP: MRU 1500 (0x010405DC)
*Feb 28 22:55:08.155: Vi1 LCP: I CONFREQ [ACKrcvd] id 115 len 18
*Feb 28 22:55:08.155: Vi1 LCP: MRU 1492 (0x010405D4)
*Feb 28 22:55:08.159: Vi1 LCP: AuthProto PAP (0x0304C023)
*Feb 28 22:55:08.159: Vi1 LCP: MagicNumber 0x08250D35 (0x050608250D35)
*Feb 28 22:55:08.159: Vi1 LCP: O CONFNAK [ACKrcvd] id 115 len 8
*Feb 28 22:55:08.159: Vi1 LCP: MRU 1500 (0x010405DC)
*Feb 28 22:55:08.171: Vi1 LCP: I CONFREQ [ACKrcvd] id 116 len 18
*Feb 28 22:55:08.175: Vi1 LCP: MRU 1492 (0x010405D4)
*Feb 28 22:55:08.175: Vi1 LCP: AuthProto PAP (0x0304C023)
*Feb 28 22:55:08.175: Vi1 LCP: MagicNumber 0x08250D35 (0x050608250D35)
*Feb 28 22:55:08.175: Vi1 LCP: O CONFNAK [ACKrcvd] id 116 len 8
*Feb 28 22:55:08.175: Vi1 LCP: MRU 1500 (0x010405DC)
*Feb 28 22:55:08.191: Vi1 LCP: I CONFREQ [ACKrcvd] id 117 len 18
*Feb 28 22:55:08.191: Vi1 LCP: MRU 1492 (0x010405D4)
*Feb 28 22:55:08.191: Vi1 LCP: AuthProto PAP (0x0304C023)
*Feb 28 22:55:08.191: Vi1 LCP: MagicNumber 0x08250D35 (0x050608250D35)
*Feb 28 22:55:08.191: Vi1 LCP: O CONFREJ [ACKrcvd] id 117 len 8
*Feb 28 22:55:08.191: Vi1 LCP: MRU 1492 (0x010405D4)
*Feb 28 22:55:08.223: Vi1 LCP: I CONFREQ [ACKrcvd] id 118 len 14
*Feb 28 22:55:08.223: Vi1 LCP: AuthProto PAP (0x0304C023)
*Feb 28 22:55:08.223: Vi1 LCP: MagicNumber 0x08250D35 (0x050608250D35)
*Feb 28 22:55:08.223: Vi1 LCP: O CONFACK [ACKrcvd] id 118 len 14
*Feb 28 22:55:08.227: Vi1 LCP: AuthProto PAP (0x0304C023)
*Feb 28 22:55:08.227: Vi1 LCP: MagicNumber 0x08250D35 (0x050608250D35)
*Feb 28 22:55:08.227: Vi1 LCP: State is Open
*Feb 28 22:55:08.227: Vi1 PPP: Phase is AUTHENTICATING, by the peer [0 sess, 0 l
oad]
*Feb 28 22:55:08.231: Vi1 PAP: O AUTH-REQ id 20 len 22 from "aq15a6"
*Feb 28 22:55:09.474: Vi1 PAP: I AUTH-ACK id 20 len 13 msg is "choice: "
*Feb 28 22:55:09.474: Vi1 PPP: Phase is UP [0 sess, 0 load]
*Feb 28 22:55:09.474: Vi1 IPCP: O CONFREQ [Closed] id 1 len 10
*Feb 28 22:55:09.478: Vi1 IPCP: Address 0.0.0.0 (0x030600000000)
*Feb 28 22:55:09.478: Vi1 LCP: I TERMREQ [Open] id 119 len 4
*Feb 28 22:55:09.478: Vi1 LCP: O TERMACK [Open] id 119 len 4
*Feb 28 22:55:10.475: %LINEPROTO-5-UPDOWN: Line protocol on Interface Virtual-Ac
cess1, changed state to up
*Feb 28 22:55:11.473: Vi1 IPCP: TIMEout: State REQsent
*Feb 28 22:55:11.473: Vi1 IPCP: O CONFREQ [REQsent] id 2 len 10
*Feb 28 22:55:11.473: Vi1 IPCP: Address 0.0.0.0 (0x030600000000)
*Feb 28 22:55:11.477: Vi1 LCP: TIMEout: State TERMsent
*Feb 28 22:55:11.477: Vi1 LCP: State is Closed
*Feb 28 22:55:11.477: Vi1 IPCP: State is Closed
*Feb 28 22:55:11.477: Vi1 PPP: Phase is DOWN [0 sess, 0 load]
*Feb 28 22:55:11.477: %DIALER-6-UNBIND: Interface Vi1 unbound from profile Di1
*Feb 28 22:55:11.481: Vi1 PPP: Phase is ESTABLISHING, Passive Open [0 sess, 0 lo
ad]
*Feb 28 22:55:11.481: Vi1 PPP: No remote authentication for call-out
*Feb 28 22:55:11.481: Vi1 LCP: State is Listen
*Feb 28 22:55:11.573: Vi1 LCP: State is Closed
*Feb 28 22:55:11.577: Vi1 PPP: Phase is DOWN [0 sess, 0 load]
*Feb 28 22:55:12.478: %LINEPROTO-5-UPDOWN: Line protocol on Interface Virtual-Access1, changed state to down
