Dear All expert,
I'm doing an PoC for ISR 1921 with 3G-GSM module test. I couldn't get the IP negotiation from ISP. From the debug logs i found CHAP authentication failure but the same sim card and similar configuration applied to ISR 819 model do not need to pass through this authentication stage. Pls advice me if you have ever came across this issue and comment if anything goes wrong with my config. Thanks and appreciated. My related configuration and debug output are below.
Related config:
===========
chat-script gsm "" "ATDT*99*1#" TIMEOUT 60 "CONNECT"
ip route 10.2.0.0 255.255.0.0 Cellular0/0/0
interface Cellular0/0/0
ip address negotiated
ip nat outside
ip virtual-reassembly in
encapsulation ppp
dialer in-band
dialer idle-timeout 2147483
dialer string gsm
dialer-group 1
async mode interactive
end
line 0/0/0
exec-timeout 0 0
script dialer gsm
modem InOut
no exec
rxspeed 3600000
txspeed 384000
Debug PPP negotiation and Chat:
===========================
POC_1921_3G#p 10.2.0.2
Type escape sequence to abort.
Sending 5, 100-byte ICMP Echos to 10.2.0.2, timeout is 2 seconds:
Dec 19 10:49:05.082: CHAT0/0/0: Attempting async line dialer script
Dec 19 10:49:05.082: CHAT0/0/0: Dialing using Modem script: gsm & System script: none
Dec 19 10:49:05.082: CHAT0/0/0: process started
Dec 19 10:49:05.082: CHAT0/0/0: Asserting DTR
Dec 19 10:49:05.082: CHAT0/0/0: Chat script gsm started
Dec 19 10:49:05.082: CHAT0/0/0: Sending string: ATDT*99*1#
Dec 19 10:49:05.082: CHAT0/0/0: Expecting string: CONNECT
Dec 19 10:49:05.130: CHAT0/0/0: Completed match for expect: CONNECT
Dec 19 10:49:05.130: CHAT0/0/0: Chat script gsm finished, status = Success. -------------------> Chat script stage completed.
Dec 19 10:49:07.130: %LINK-3-UPDOWN: Interface Cellular0/0/0, changed state to up
Dec 19 10:49:07.130: Ce0/0/0 PPP: Sending cstate UP notification
Dec 19 10:49:07.130: Ce0/0/0 PPP: Processing CstateUp message
Dec 19 10:49:07.130: PPP: Alloc Context [30CF2944]
Dec 19 10:49:07.130: ppp20 PPP: Phase is ESTABLISHING
Dec 19 10:49:07.130: Ce0/0/0 PPP: Using dialer call direction
Dec 19 10:49:07.130: Ce0/0/0 PPP: Treating connection as a callout
Dec 19 10:49:07.130: Ce0/0/0 PPP: Session handle[F000014] Session id[20]
Dec 19 10:49:07.130: Ce0/0/0 LCP: Event[OPEN] State[Initial to Starting]
Dec 19 10:49:07.130: Ce0/0/0 PPP: No remote authentication for call-out
Dec 19 10:49:07.130: Ce0/0/0 LCP: O CONFREQ [Starting] id 1 len 20
Dec 19 10:49:07.130: Ce0/0/0 LCP: ACCM 0x000A0000 (0x0206000A0000)
Dec 19 10:49:07.130: Ce0/0/0 LCP: MagicNumber 0x9A29901D (0x05069A29901D)
Dec 19 10:49:07.130: Ce0/0/0 LCP: PFC (0x0702)
Dec 19 10:49:07.130: Ce0/0/0 LCP: ACFC (0x0802)
Dec 19 10:49:07.130: Ce0/0/0 LCP: Event[UP] State[Starting to REQsent]
Dec 19 10:49:07.134: Ce0/0/0 LCP: I CONFREQ [REQsent] id 38 len 25
Dec 19 10:49:07.134: Ce0/0/0 LCP: ACCM 0x00000000 (0x020600000000)
Dec 19 10:49:07.134: Ce0/0/0 LCP: AuthProto CHAP (0x0305C22305)
Dec 19 10:49:07.134: Ce0/0/0 LCP: MagicNumber 0x01A5C8D1 (0x050601A5C8D1)
Dec 19 10:49:07.134: Ce0/0/0 LCP: PFC (0x0702)
Dec 19 10:49:07.134: Ce0/0/0 LCP: ACFC (0x0802)
Dec 19 10:49:07.134: Ce0/0/0 LCP: O CONFACK [REQsent] id 38 len 25
Dec 19 10:49:07.134: Ce0/0/0 LCP: ACCM 0x00000000 (0x020600000000)
Dec 19 10:49:07.134: Ce0/0/0 LCP: AuthProto CHAP (0x0305C22305)
Dec 19 10:49:07.134: Ce0/0/0 LCP: MagicNumber 0x01A5C8D1 (0x050601A5C8D1)
Dec 19 10:49:07.134: Ce0/0/0 LCP: PFC (0x0702)
Dec 19 10:49:07.134: Ce0/0/0 LCP: ACFC (0x0802)
Dec 19 10:49:07.134: Ce0/0/0 LCP: Event[Receive ConfReq+] State[REQsent to ACKsent]
Dec 19 10:49:07.134: Ce0/0/0 LCP: I CONFACK [ACKsent] id 1 len 20
Dec 19 10:49:07.134: Ce0/0/0 LCP: ACCM 0x000A0000 (0x0206000A0000)
Dec 19 10:49:07.134: Ce0/0/0 LCP: MagicNumber 0x9A29901D (0x05069A29901D)
Dec 19 10:49:07.134: Ce0/0/0 LCP: PFC (0x0702)
Dec 19 10:49:07.134: Ce0/0/0 LCP: ACFC (0x0802)
Dec 19 10:49:07.134: Ce0/0/0 LCP: Event[Receive ConfAck] State[ACKsent to Open]
Dec 19 10:49:07.138: Ce0/0/0 PPP: Queue CHAP code[1] id[1]
Dec 19 10:49:07.150: Ce0/0/0 PPP: No authorization without authentication
Dec 19 10:49:07.150: Ce0/0/0 PPP: Phase is AUTHENTICATING, by the peer
Dec 19 10:49:07.150: Ce0/0/0 CHAP: Redirect packet to Ce0/0/0
Dec 19 10:49:07.150: Ce0/0/0 CHAP: I CHALLENGE id 1 len 35 from "UMTS_CHAP_SRVR"
Dec 19 10:49:07.150: Ce0/0/0 PPP: Sent CHAP SENDAUTH Request
Dec 19 10:49:07.150: Ce0/0/0 LCP: State is Open
Dec 19 10:49:07.150: Ce0/0/0 PPP: Received SENDAUTH Response FAIL
Dec 19 10:49:07.150: Ce0/0/0 CHAP: Unable to authenticate for peer
Dec 19 10:49:07.150: Ce0/0/0 PPP DISC: User failed CHAP authentication
Dec 19 10:49:07.150: Ce0/0/0 PPP: Sending AAA radius abort
Dec 19 10:49:07.150: PPP: NET STOP send to AAA.
Dec 19 10:49:07.150: Ce0/0/0 LCP: O TERMREQ [Open] id 2 len 4
Dec 19 10:49:07.150: Ce0/0/0 LCP: Event[CLOSE] State[Open to Closing]
Dec 19 10:49:07.150: Ce0/0/0 PPP: Phase is TERMINATING
Dec 19 10:49:07.150: Ce0/0/0 LCP: I TERMACK [Closing] id 2 len 4
Dec 19 10:49:07.150: Ce0/0/0 PPP: No remote authentication for call-out
Dec 19 10:49:07.150: Ce0/0/0 LCP: Event[Receive TermAck] State[Closing to Closed]
Dec 19 10:49:07.150: Ce0/0/0 LCP: Event[DOWN] State[Closed to Initial]
Dec 19 10:49:07.150: Ce0/0/0 PPP: Phase is DOWN.
Dec 19 10:49:09.150: %LINK-5-CHANGED: Interface Cellular0/0/0, changed state to reset
Dec 19 10:49:09.150: Ce0/0/0 PPP: Sending cstate DOWN notification
Dec 19 10:49:09.150: Ce0/0/0 PPP: Processing CstateDown message..
Dec 19 10:49:14.150: %LINK-3-UPDOWN: Interface Cellular0/0/0, changed state to down.
Success rate is 0 percent (0/5)
POC_1921_3G#
Dec 19 10:49:14.150: Ce0/0/0 PPP: Sending cstate DOWN notification
Dec 19 10:49:14.150: Ce0/0/0 PPP: Processing CstateDown message
POC_1921_3G#
POC_1921_3G#
