Solved: Re: 1841 and HWIC-3G-HSPA cellular interface up then reset then down...

radiomean · ‎08-28-2020

I have a 1841 router and I'm trying to setup HWIC-3G-HSPA...

I have written a simple config but when I try to initiate a cellular connection, the cellular interface (Cellular0/0/0) comes up, goes to reset and goes down within a few seconds.

I initiate the connection by pinging 8.8.8.8

This is the output without debug:

girton-test#ping 8.8.8.8
Type escape sequence to abort.
Sending 5, 100-byte ICMP Echos to 8.8.8.8, timeout is 2 seconds:
...
*Aug 28 22:20:36.843: %LINK-3-UPDOWN: Interface Cellular0/0/0, changed state to up.
*Aug 28 22:20:43.447: %LINK-5-CHANGED: Interface Cellular0/0/0, changed state to reset.
Success rate is 0 percent (0/5)
girton-test#
*Aug 28 22:20:48.447: %LINK-3-UPDOWN: Interface Cellular0/0/0, changed state to down

This is the output with debug dialer and debug chat:

girton-test#ping 8.8.8.8
Type escape sequence to abort.
Sending 5, 100-byte ICMP Echos to 8.8.8.8, timeout is 2 seconds:

*Aug 28 22:25:43.083: Ce0/0/0 DDR: place call
*Aug 28 22:25:43.083: Ce0/0/0 DDR: Dialing cause ip (s=192.168.0.1, d=8.8.8.8)
*Aug 28 22:25:43.083: Ce0/0/0 DDR: Attempting to dial gsm
*Aug 28 22:25:43.083: CHAT0/0/0: Attempting async line dialer script
*Aug 28 22:25:43.083: CHAT0/0/0: Dialing using Modem script: gsm & System script: none
*Aug 28 22:25:43.083: CHAT0/0/0: process started
*Aug 28 22:25:43.087: CHAT0/0/0: Asserting DTR
*Aug 28 22:25:43.087: CHAT0/0/0: Chat script gsm started
*Aug 28 22:25:43.087: CHAT0/0/0: Sending string: ATDT*99*1#
*Aug 28 22:25:43.087: CHAT0/0/0: Expecting string: CONNECT
*Aug 28 22:25:43.135: CHAT0/0/0: Completed match for expect: CONNECT
*Aug 28 22:25:43.135: CHAT0/0/0: Chat script gsm finished, status = Success.
*Aug 28 22:25:45.135: %LINK-3-UPDOWN: Interface Cellular0/0/0, changed state to up
*Aug 28 22:25:45.135: Ce0/0/0 DDR: Dialer statechange to up
*Aug 28 22:25:45.135: Ce0/0/0 DDR: Dialer call has been placed..
*Aug 28 22:25:47.167: %LINK-5-CHANGED: Interface Cellular0/0/0, changed state to reset..
Success rate is 0 percent (0/5)
girton-test#
*Aug 28 22:25:52.167: %LINK-3-UPDOWN: Interface Cellular0/0/0, changed state to down
*Aug 28 22:26:02.167: Ce0/0/0 DDR: re-enable timeout

Any thoughts please? I have been working at the for hours. I have tried several IOS versions so I don't think it is a bug...

I can post the config if you like...

Thanks! Bill

radiomean · ‎08-29-2020

OK, this is the crucial line...

Aug 29 11:13:32.535: Ce0/0/0 CHAP: I CHALLENGE id 1 len 35 from "UMTS_CHAP_SRVR"

I looked for problems with UMTS_CHAP_SRVR and found another post on these forums. The cellular modem behaviour of up-reset-down felt to me like some login failure and someone else had this issue...

The TRICK is to create:

username UMTS_CHAP_SRVR

and the card connects and it WORKS!

Well, I have not seem that this is documented anywhere official...

Oh well...

View solution in original post

marce1000 · ‎08-28-2020

Make sure a compatible IOS release is being used

- Ref : https://www.cisco.com/c/en/us/products/collateral/interfaces-modules/3g-wireless-wan-high-speed-wan-interface-card/product_data_sheet0900aecd80600f41.html?dtid=osscdc000283

>...

Software compatibility

Modular Cisco 1841, 1861, 2800 and 3800 series Integrated Services Routers supported with the following Cisco IOS Releases:

• Cisco IOS Software feature set: IP Base IOS image

• HWIC-3G-HSPA supported with 15.0(1)M or later

• HWIC-3G-HSPA-A supported with 15.1(1)T1 or later

• HWIC-3G-HSPA-G supported with 15.1(1)T or later

-- Each morning when I wake up and look into the mirror I always say ' Why am I so brilliant ? '
When the mirror will then always repond to me with ' The only thing that exceeds your brilliance is your beauty! '

radiomean · ‎08-28-2020

Hi and thanks.

I am using c1841-adventerprisek9-mz.151-3.T2 and have tried c1841-adventerprisek9-mz.151-4.M and the card is recognised, but behaves as above.

I tried c1841-adventerprisek9-mz.124-24.T6 and - of course - the card is simply not recognised... No cellular interface appears.

It is not the version - maybe I will try another Sim card?

But all the Sim cards I have tried work fine in a USB dongle...

Cheers!

radiomean · ‎08-29-2020

Well, it's not the Sim card - I have tried an active EE Sim and an active 3UK Sim and both produce the above results - failure.

I have two of the HWIC-3G-HSPA cards and both give the same results...

My next step is to try the card in a 1921 router...

Thoughts please?

Georg Pauwen · ‎08-29-2020

Hello,

post the full running configuration of your 1841.

radiomean · ‎08-29-2020

OK, I have put the card in my 1921 router, cleared the config and written a minimal config for testing. I get the same error in the 1921 router as in the 1841. I have now posted the minimal config - with no passwords - below, and I show the cellular up/reset/down sequence when I execute "ping 8.8.8.8"

CONFIGURATION:

Current configuration : 1889 bytes
!
! Last configuration change at 08:52:31 UTC Sat Aug 29 2020
version 15.2
service timestamps debug datetime msec
service timestamps log datetime msec
no service password-encryption
!
hostname girton-1921
!
boot-start-marker
boot-end-marker
!
no aaa new-model
!
no ip source-route
ip cef
!
no ip domain lookup
no ipv6 cef
multilink bundle-name authenticated
!
chat-script gsm "" "ATDT*99*1#" TIMEOUT 30 "CONNECT"
!
license udi pid CISCO1921/K9 sn FCZ1751C12Y
!
controller Cellular 0/0
!
interface Embedded-Service-Engine0/0
no ip address
shutdown
!
interface GigabitEthernet0/0
ip address 192.168.0.1 255.255.255.0
duplex auto
speed auto
!
interface GigabitEthernet0/1
ip address 192.168.1.1 255.255.255.0
duplex auto
speed auto
!
interface GigabitEthernet0/1/0
no ip address
!
interface GigabitEthernet0/1/1
no ip address
!
interface GigabitEthernet0/1/2
no ip address
!
interface GigabitEthernet0/1/3
no ip address
!
interface Cellular0/0/0
ip address negotiated
encapsulation ppp
dialer in-band
dialer idle-timeout 0
dialer string gsm
dialer-group 1
async mode interactive
ppp ipcp dns request
!
interface Cellular0/0/1
no ip address
encapsulation ppp
!
interface Vlan1
no ip address
!
ip forward-protocol nd
!
no ip http server
no ip http secure-server
!
ip route 0.0.0.0 0.0.0.0 Cellular0/0/0
!
access-list 1 permit any
dialer-list 1 protocol ip list 1
!
control-plane
!
line con 0
line aux 0
line 2
no activation-character
no exec
transport preferred none
transport output pad telnet rlogin lapb-ta mop udptn v120 ssh
stopbits 1
line 0/0/0
exec-timeout 0 0
script dialer gsm
login
modem InOut
no exec
transport input all
transport output all
rxspeed 7200000
txspeed 2000000
line 0/0/1
no exec
rxspeed 7200000
txspeed 2000000
line vty 0 4
login
transport input all
!
scheduler allocate 20000 1000
!
end

girton-1921#ping 8.8.8.8

Type escape sequence to abort.
Sending 5, 100-byte ICMP Echos to 8.8.8.8, timeout is 2 seconds:
.
*Aug 29 08:52:53.715: %LINK-3-UPDOWN: Interface Cellular0/0/0, changed state to up.
*Aug 29 08:52:55.747: %LINK-5-CHANGED: Interface Cellular0/0/0, changed state to reset...
Success rate is 0 percent (0/5)
girton-1921#
*Aug 29 08:53:00.747: %LINK-3-UPDOWN: Interface Cellular0/0/0, changed state to down

DEBUG information when I execute ping 8.8.8.8:

girton-1921#debug dialer
Dial on demand events debugging is on
girton-1921#debug chat
Chat scripts activity debugging is on
girton-1921#ping 8.8.8.8
Type escape sequence to abort.
Sending 5, 100-byte ICMP Echos to 8.8.8.8, timeout is 2 seconds:

*Aug 29 08:58:04.227: Ce0/0/0 DDR: place call
*Aug 29 08:58:04.227: Ce0/0/0 DDR: Dialing cause ip (s=192.168.0.1, d=8.8.8.8)
*Aug 29 08:58:04.227: Ce0/0/0 DDR: Attempting to dial gsm
*Aug 29 08:58:04.227: CHAT0/0/0: Attempting async line dialer script
*Aug 29 08:58:04.227: CHAT0/0/0: Dialing using Modem script: gsm & System script: none
*Aug 29 08:58:04.227: CHAT0/0/0: process started
*Aug 29 08:58:04.227: CHAT0/0/0: Asserting DTR
*Aug 29 08:58:04.227: CHAT0/0/0: Chat script gsm started
*Aug 29 08:58:04.227: CHAT0/0/0: Sending string: ATDT*99*1#
*Aug 29 08:58:04.227: CHAT0/0/0: Expecting string: CONNECT
*Aug 29 08:58:04.275: CHAT0/0/0: Completed match for expect: CONNECT
*Aug 29 08:58:04.275: CHAT0/0/0: Chat script gsm finished, status = Success.
*Aug 29 08:58:06.275: %LINK-3-UPDOWN: Interface Cellular0/0/0, changed state to up
*Aug 29 08:58:06.275: Ce0/0/0 DDR: Dialer statechange to up
*Aug 29 08:58:06.275: Ce0/0/0 DDR: Dialer call has been placed.
*Aug 29 08:58:08.319: %LINK-5-CHANGED: Interface Cellular0/0/0, changed state to reset...
Success rate is 0 percent (0/5)
girton-1921#
*Aug 29 08:58:13.319: %LINK-3-UPDOWN: Interface Cellular0/0/0, changed state to down
girton-1921#

I have to admit that I am stumped...

I have a EHWIC-3G-HSPA+7 card that I am going to try in the 1921 router (it won't work in the 1841 of course) and see what happens with that...

OK, some more info - when I do sh cellular 0/0/0 all I get some cached error info from the modem. Is this of any use?

Error Information
=================

Cached info is displayed

at!err

00 F 31 wl1drxentity 00851
01 F 23 rrcllc 08185
02 F 63 mcalwcdma_dl 01360
03 F 1B ps_ifacei_event 01499
04 F 0C rrcllc 03301
05 F 03 srchcmmeas 05203
06 F 01 rrcllc 35224

07 F 01 rrcsend 00974
08 F 01 macdlhs 04936
09 F 02 rrcrce 05992
10 F 02 dsumtspshdlr 14304
11 FF cmals 00840
12 09 mmsim 00561
13 FF rrccsputil 18545
14 FF cmss 03439
15 FF cnnetwork 03862
16 FF mmgsdi_uicc_rsp 03126
17 FF ds3gsiolib 03045
18 FF rrcllcrbie 08760
19 FF rrcdispatcher 01956

OK

at!gcdump

No crash data available

OK

Modem Crashdump Information
===========================
Modem crashdump logging: off

Cheers, Bill

Georg Pauwen · ‎08-29-2020

Hello,

make the changes marked in bold (a dialer interface has been added):

Current configuration : 1889 bytes
!
! Last configuration change at 08:52:31 UTC Sat Aug 29 2020
version 15.2
service timestamps debug datetime msec
service timestamps log datetime msec
no service password-encryption
!
hostname girton-1921
!
boot-start-marker
boot-end-marker
!
no aaa new-model
!
no ip source-route
ip cef
!
no ip domain lookup
no ipv6 cef
multilink bundle-name authenticated
!
chat-script gsm "" "ATDT*99*1#" TIMEOUT 30 "CONNECT"
!
license udi pid CISCO1921/K9 sn FCZ1751C12Y
!
controller Cellular 0/0
!
interface Embedded-Service-Engine0/0
no ip address
shutdown
!
interface GigabitEthernet0/0
ip address 192.168.0.1 255.255.255.0
duplex auto
speed auto
!
interface GigabitEthernet0/1
ip address 192.168.1.1 255.255.255.0
duplex auto
speed auto
!
interface GigabitEthernet0/1/0
no ip address
!
interface GigabitEthernet0/1/1
no ip address
!
interface GigabitEthernet0/1/2
no ip address
!
interface GigabitEthernet0/1/3
no ip address
!
interface Cellular0/0/0
ip address negotiated
encapsulation ppp
dialer in-band
dialer idle-timeout 0
dialer pool-member 1
dialer-group 1
async mode interactive
routing dynamic
ppp ipcp dns request
!
interface Cellular0/0/1
no ip address
encapsulation ppp
!
interface Dialer1
ip address negotiated
ip nat enable
encapsulation ppp
dialer pool 1
dialer idle-timeout 0
dialer string gsm
dialer persistent
dialer-group 1
!
interface Vlan1
no ip address
!
ip forward-protocol nd
!
no ip http server
no ip http secure-server
!
ip nat source list 1 interface Dialer1 overload
ip route 0.0.0.0 0.0.0.0 Dialer1
!
access-list 1 permit 192.168.0.0 0.0.255.255
dialer-list 1 protocol ip permit
!
control-plane
!
line con 0
line aux 0
line 2
no activation-character
no exec
transport preferred none
transport output pad telnet rlogin lapb-ta mop udptn v120 ssh
stopbits 1
line 0/0/0
exec-timeout 0 0
script dialer gsm
login
modem InOut
no exec
transport input all
transport output all
rxspeed 7200000
txspeed 2000000
line 0/0/1
no exec
rxspeed 7200000
txspeed 2000000
line vty 0 4
login
transport input all
!
scheduler allocate 20000 1000
!
end

Also, post the output of:

sh cellular 0/0/0 profile

sh cell 0/0/0 all

radiomean · ‎08-29-2020

Hi and thanks... I have made the changes you suggest but the first thing I would say is I can't add dialer-group 1 to cellular 0/0/0 - I get the message
%Remove Dialer Profile Configuration first

I think because you have made the dialer persistent, I now get a continuous stream of cellular 0/0/0 up - reset - down, as I show below:

*Aug 29 11:01:19.267: %LINK-3-UPDOWN: Interface Cellular0/0/0, changed state to up
*Aug 29 11:01:19.267: %DIALER-6-BIND: Interface Ce0/0/0 bound to profile Di1
*Aug 29 11:01:21.295: %LINK-5-CHANGED: Interface Cellular0/0/0, changed state to reset
*Aug 29 11:01:21.295: %DIALER-6-UNBIND: Interface Ce0/0/0 unbound from profile Di1
*Aug 29 11:01:26.295: %LINK-3-UPDOWN: Interface Cellular0/0/0, changed state to down
*Aug 29 11:01:38.343: %LINK-3-UPDOWN: Interface Cellular0/0/0, changed state to up
*Aug 29 11:01:38.343: %DIALER-6-BIND: Interface Ce0/0/0 bound to profile Di1
*Aug 29 11:01:40.359: %LINK-5-CHANGED: Interface Cellular0/0/0, changed state to reset
*Aug 29 11:01:40.359: %DIALER-6-UNBIND: Interface Ce0/0/0 unbound from profile Di1
*Aug 29 11:01:45.359: %LINK-3-UPDOWN: Interface Cellular0/0/0, changed state to down
*Aug 29 11:01:57.411: %LINK-3-UPDOWN: Interface Cellular0/0/0, changed state to up
*Aug 29 11:01:57.411: %DIALER-6-BIND: Interface Ce0/0/0 bound to profile Di1
*Aug 29 11:01:59.439: %LINK-5-CHANGED: Interface Cellular0/0/0, changed state to reset
*Aug 29 11:01:59.439: %DIALER-6-UNBIND: Interface Ce0/0/0 unbound from profile Di1
*Aug 29 11:02:04.439: %LINK-3-UPDOWN: Interface Cellular0/0/0, changed state to down
*Aug 29 11:02:16.491: %LINK-3-UPDOWN: Interface Cellular0/0/0, changed state to up
*Aug 29 11:02:16.491: %DIALER-6-BIND: Interface Ce0/0/0 bound to profile Di1
*Aug 29 11:02:18.511: %LINK-5-CHANGED: Interface Cellular0/0/0, changed state to reset
*Aug 29 11:02:18.511: %DIALER-6-UNBIND: Interface Ce0/0/0 unbound from profile Di1
*Aug 29 11:02:23.511: %LINK-3-UPDOWN: Interface Cellular0/0/0, changed state to down

The output of sh cellular all is:

girton-1921#sh cellular 0/0/0 all
Hardware Information
====================
Modem Firmware Version = K2_0_7_19AP C:/WS/FW
Modem Firmware built = 10/26/09
Hardware Version = 1.0
International Mobile Subscriber Identity (IMSI) = 234202102774461
International Mobile Equipment Identity (IMEI) = 012073005003077
Integrated Circuit Card ID (ICCID) = 8944200200287042654
Mobile Subscriber International Subscriber
IDentity Number (MSISDN) =
Factory Serial Number (FSN) = C79332014471004
Modem Status = Online
Current Modem Temperature = 29 deg C, State = Normal
PRI SKU ID = 9993248, SKU Rev. = 1.3

Profile Information
====================
Profile 1 = INACTIVE*
--------
PDP Type = IPv4
Access Point Name (APN) = 3internet
Authentication = None
Username:
Password:

* - Default profile

Data Connection Information
===========================
Data Transmitted = 0 bytes, Received = 0 bytes
Profile 1, Packet Session Status = INACTIVE
Inactivity Reason = Normal inactivate state
Profile 2, Packet Session Status = INACTIVE
Inactivity Reason = Normal inactivate state
Profile 3, Packet Session Status = INACTIVE
Inactivity Reason = Normal inactivate state
Profile 4, Packet Session Status = INACTIVE
Inactivity Reason = Normal inactivate state
Profile 5, Packet Session Status = INACTIVE
Inactivity Reason = Normal inactivate state
Profile 6, Packet Session Status = INACTIVE
Inactivity Reason = Normal inactivate state
Profile 7, Packet Session Status = INACTIVE
Inactivity Reason = Normal inactivate state
Profile 8, Packet Session Status = INACTIVE
Inactivity Reason = Normal inactivate state
Profile 9, Packet Session Status = INACTIVE
Inactivity Reason = Normal inactivate state
Profile 10, Packet Session Status = INACTIVE
Inactivity Reason = Normal inactivate state
Profile 11, Packet Session Status = INACTIVE
Inactivity Reason = Normal inactivate state
Profile 12, Packet Session Status = INACTIVE
Inactivity Reason = Normal inactivate state
Profile 13, Packet Session Status = INACTIVE
Inactivity Reason = Normal inactivate state
Profile 14, Packet Session Status = INACTIVE
Inactivity Reason = Normal inactivate state
Profile 15, Packet Session Status = INACTIVE
Inactivity Reason = Normal inactivate state
Profile 16, Packet Session Status = INACTIVE
Inactivity Reason = Normal inactivate state

Network Information
===================
Current Service Status = Normal, Service Error = None
Current Service = Combined
Packet Service = HSPA (Attached)
Packet Session Status = Inactive
Current Roaming Status = Home
Network Selection Mode = Automatic
Country = GBR, Network = 3 UK
Mobile Country Code (MCC) = 234
Mobile Network Code (MNC) = 20
Location Area Code (LAC) = 37
Routing Area Code (RAC) = 37
Cell ID = 41618
Primary Scrambling Code = 116
PLMN Selection = Automatic
Registered PLMN = 3 , Abbreviated =
Service Provider =

Radio Information
=================
Radio power mode = ON
Current Band = WCDMA 2100, Channel Number = 10588
Current RSSI = -70 dBm
Band Selected = WCDMA All(900/1900/IMT 2000)
Number of nearby cells = 1
Cell 1
Primary Scrambling Code = 0x74
RSCP = -75 dBm, ECIO = -2 dBm

Modem Security Information
==========================
Card Holder Verification (CHV1) = Disabled
SIM Status = OK
SIM User Operation Required = None
Number of CHV1 Retries remaining = 3

Error Information
=================

at!err

00 F 31 wl1drxentity 00851
01 F 23 rrcllc 08185
02 F 63 mcalwcdma_dl 01360
03 F 1B ps_ifacei_event 01499
04 F 0C rrcllc 03301
05 F 03 srchcmmeas 05203
06 F 01 rrcllc 35224
07 F 01 rrcsend 00974
08 F 01 macdlhs 04936
09 F 02 rrcrce 05992
10 F 02 dsumtspshdlr 14304
11 FF cmals 00840
12 09 mmsim 00561
13 FF rrccsputil 18545
14 FF cmss 03439
15 FF cnnetwork 03862
16 FF mmgsdi_uicc_rsp 03126
17 FF ds3gsiolib 03045
18 FF rrcllcrbie 08760
19 FF rrcdispatcher 01956

OK

at!gcdump

No crash data available

OK

Modem Crashdump Information
===========================
Modem crashdump logging: off

The output of sh cell 0/0/0 pr is:

girton-1921# sh cell 0/0/0 pr
Profile 1 = INACTIVE*
--------
PDP Type = IPv4
Access Point Name (APN) = 3internet
Authentication = None
Username:
Password:

* - Default profile

Looks like I am doing something wrong but I don't know what!!

Cheers and thanks, Bill

radiomean · ‎08-29-2020

I've also turned on debug ppp authentication and ppp authorization and this is the output:

girton-1921#ping 8.8.8.8
Type escape sequence to abort.
Sending 5, 100-byte ICMP Echos to 8.8.8.8, timeout is 2 seconds:

*Aug 29 11:13:30.439: Ce0/0/0 DDR: rotor dialout [best] least recent failure is also most recent failure
*Aug 29 11:13:30.439: Ce0/0/0 DDR: rotor dialout [best] also has most recent failure
*Aug 29 11:13:30.439: Ce0/0/0 DDR: rotor dialout [best]
*Aug 29 11:13:30.439: Ce0/0/0 DDR: Dialing cause ip (s=192.168.0.1, d=8.8.8.8)
*Aug 29 11:13:30.439: Ce0/0/0 DDR: Attempting to dial gsm
*Aug 29 11:13:30.439: CHAT0/0/0: Attempting async line dialer script
*Aug 29 11:13:30.439: CHAT0/0/0: Dialing using Modem script: gsm & System script: none
*Aug 29 11:13:30.439: CHAT0/0/0: process started
*Aug 29 11:13:30.439: CHAT0/0/0: Asserting DTR
*Aug 29 11:13:30.439: CHAT0/0/0: Chat script gsm started
*Aug 29 11:13:30.443: CHAT0/0/0: Sending string: ATDT*99*1#
*Aug 29 11:13:30.443: CHAT0/0/0: Expecting string: CONNECT
*Aug 29 11:13:30.495: CHAT0/0/0: Completed match for expect: CONNECT
*Aug 29 11:13:30.495: CHAT0/0/0: Chat script gsm finished, status = Success.
*Aug 29 11:13:32.495: %LINK-3-UPDOWN: Interface Cellular0/0/0, changed state to up
*Aug 29 11:13:32.495: Ce0/0/0 DDR: Dialer statechange to up
*Aug 29 11:13:32.495: %DIALER-6-BIND: Interface Ce0/0/0 bound to profile Di1
*Aug 29 11:13:32.495: Ce0/0/0 DDR: Dialer call has been placed
*Aug 29 11:13:32.495: Ce0/0/0 PPP: Sending cstate UP notification
*Aug 29 11:13:32.495: Ce0/0/0 PPP: Processing CstateUp message
*Aug 29 11:13:32.495: PPP: Alloc Context [29EC50E0]
*Aug 29 11:13:32.495: ppp29 PPP: Phase is ESTABLISHING
*Aug 29 11:13:32.495: ppp29 PPP: Using AAA Unique Id = 29
*Aug 29 11:13:32.495: Ce0/0/0 PPP: Authorization required
*Aug 29 11:13:32.495: Ce0/0/0 PPP: Using dialer call direction
*Aug 29 11:13:32.495: Ce0/0/0 PPP: Treating connection as a callout
*Aug 29 11:13:32.495: Ce0/0/0 PPP: Session handle[9900001D] Session id[29]
*Aug 29 11:13:32.495: Ce0/0/0 PPP LCP: negotiation authorized = 1, tacacs author = 0
*Aug 29 11:13:32.495: Ce0/0/0 LCP: Event[OPEN] State[Initial to Starting]
*Aug .29 11:13:32.495: Ce0/0/0 PPP LCP: neg is authorized, processing CP UP event
*Aug 29 11:13:32.495: Ce0/0/0 PPP: No remote authentication for call-out
*Aug 29 11:13:32.495: Ce0/0/0 LCP: O CONFREQ [Starting] id 1 len 20
*Aug 29 11:13:32.495: Ce0/0/0 LCP: ACCM 0x000A0000 (0x0206000A0000)
*Aug 29 11:13:32.495: Ce0/0/0 LCP: MagicNumber 0x5AAA56AD (0x05065AAA56AD)
*Aug 29 11:13:32.495: Ce0/0/0 LCP: PFC (0x0702)
*Aug 29 11:13:32.495: Ce0/0/0 LCP: ACFC (0x0802)
*Aug 29 11:13:32.495: Ce0/0/0 LCP: Event[UP] State[Starting to REQsent]
*Aug 29 11:13:32.507: Ce0/0/0 LCP: I CONFREQ [REQsent] id 56 len 25
*Aug 29 11:13:32.507: Ce0/0/0 LCP: ACCM 0x00000000 (0x020600000000)
*Aug 29 11:13:32.507: Ce0/0/0 LCP: AuthProto CHAP (0x0305C22305)
*Aug 29 11:13:32.507: Ce0/0/0 LCP: MagicNumber 0x0111578F (0x05060111578F)
*Aug 29 11:13:32.507: Ce0/0/0 LCP: PFC (0x0702)
*Aug 29 11:13:32.507: Ce0/0/0 LCP: ACFC (0x0802)
*Aug 29 11:13:32.507: Ce0/0/0 PPP LCP: neg is authorized, processing incoming C.ONFREQ
*Aug 29 11:13:32.507: Ce0/0/0 LCP: O CONFACK [REQsent] id 56 len 25
*Aug 29 11:13:32.507: Ce0/0/0 LCP: ACCM 0x00000000 (0x020600000000)
*Aug 29 11:13:32.507: Ce0/0/0 LCP: AuthProto CHAP (0x0305C22305)
*Aug 29 11:13:32.507: Ce0/0/0 LCP: MagicNumber 0x0111578F (0x05060111578F)
*Aug 29 11:13:32.507: Ce0/0/0 LCP: PFC (0x0702)
*Aug 29 11:13:32.507: Ce0/0/0 LCP: ACFC (0x0802)
*Aug 29 11:13:32.507: Ce0/0/0 LCP: Event[Receive ConfReq+] State[REQsent to ACKsent]
*Aug 29 11:13:32.507: Ce0/0/0 LCP: I CONFACK [ACKsent] id 1 len 20
*Aug 29 11:13:32.507: Ce0/0/0 LCP: ACCM 0x000A0000 (0x0206000A0000)
*Aug 29 11:13:32.507: Ce0/0/0 LCP: MagicNumber 0x5AAA56AD (0x05065AAA56AD)
*Aug 29 11:13:32.507: Ce0/0/0 LCP: PFC (0x0702)
*Aug 29 11:13:32.507: Ce0/0/0 LCP: ACFC (0x0802)
*Aug 29 11:13:32.507: Ce0/0/0 LCP: Event[Receive ConfAck] State[ACKsent to Open]
*Aug 29 11:13:32.507: Ce0/0/0 PPP: Queue CHAP code[1] id[1]
*Aug 29 11:13:32.535: Ce0/0/0 PPP: No authorization without authent.ication
*Aug 29 11:13:32.535: Ce0/0/0 PPP: Phase is AUTHENTICATING, by the peer
*Aug 29 11:13:32.535: Ce0/0/0 CHAP: Redirect packet to Ce0/0/0
*Aug 29 11:13:32.535: Ce0/0/0 CHAP: I CHALLENGE id 1 len 35 from "UMTS_CHAP_SRVR"
*Aug 29 11:13:32.535: Ce0/0/0 PPP: Sent CHAP SENDAUTH Request
*Aug 29 11:13:32.535: Ce0/0/0 LCP: State is Open
*Aug 29 11:13:32.535: Ce0/0/0 PPP: Received SENDAUTH Response FAIL
*Aug 29 11:13:32.535: Ce0/0/0 CHAP: Unable to authenticate for peer
*Aug 29 11:13:32.535: Ce0/0/0 PPP DISC: User failed CHAP authentication
*Aug 29 11:13:32.535: Ce0/0/0 PPP: Sending AAA radius abort
*Aug 29 11:13:32.535: PPP: NET STOP send to AAA.
*Aug 29 11:13:32.535: Ce0/0/0 LCP: O TERMREQ [Open] id 2 len 4
*Aug 29 11:13:32.535: Ce0/0/0 LCP: Event[CLOSE] State[Open to Closing]
*Aug 29 11:13:32.535: Ce0/0/0 PPP: Phase is TERMINATING
*Aug 29 11:13:32.539: Ce0/0/0 LCP: I TERMACK [Closing] id 2 len 4
*Aug 29 11:13:32.539: Ce0/0/0 PPP: No remote authentication for call-out
*Aug 29 11:13:32.539: Ce0.
Success rate is 0 percent (0/5)
girton-1921#/0/0 LCP: Event[Receive TermAck] State[Closing to Closed]
*Aug 29 11:13:32.539: Ce0/0/0 LCP: Event[DOWN] State[Closed to Initial]
*Aug 29 11:13:32.539: Ce0/0/0 PPP: Clearing AAA Unique Id = 29
*Aug 29 11:13:32.539: Ce0/0/0 PPP: Phase is DOWN
*Aug 29 11:13:34.543: %LINK-5-CHANGED: Interface Cellular0/0/0, changed state to reset
*Aug 29 11:13:34.543: Ce0/0/0 DDR: has total 0 call(s), dial_out 0, dial_in 0
*Aug 29 11:13:34.543: %DIALER-6-UNBIND: Interface Ce0/0/0 unbound from profile Di1
*Aug 29 11:13:34.543: Ce0/0/0 PPP: Sending cstate DOWN notification
*Aug 29 11:13:34.543: Ce0/0/0 PPP: Processing CstateDown message
*Aug 29 11:13:36.439: Di1: No free dialer - starting fast idle timer
*Aug 29 11:13:38.439: Di1: No free dialer - starting fast idle timer
*Aug 29 11:13:39.543: %LINK-3-UPDOWN: Interface Cellular0/0/0, changed state to down
*Aug 29 11:13:39.543: Ce0/0/0 PPP: Sending cstate DOWN notification
*Aug 29 11:13:39.543: Ce0/0/0 PPP: Processing CstateDown message
*Aug 29 11:13:49.543: Ce0/0/0 DDR: re-enable timeout

Is there some auth issue?

radiomean · ‎08-29-2020

OK, this is the crucial line...

Aug 29 11:13:32.535: Ce0/0/0 CHAP: I CHALLENGE id 1 len 35 from "UMTS_CHAP_SRVR"

I looked for problems with UMTS_CHAP_SRVR and found another post on these forums. The cellular modem behaviour of up-reset-down felt to me like some login failure and someone else had this issue...

The TRICK is to create:

username UMTS_CHAP_SRVR

and the card connects and it WORKS!

Well, I have not seem that this is documented anywhere official...

Oh well...

Georg Pauwen · ‎08-29-2020

Hello,

good find. For the sake of reference, can you post the final, working config ?

radiomean · ‎08-29-2020

Thanks... I am still working on it but will try to post it in the next few days...

This post is carried over the cellular interface in the router now!

Cheers, Bill

missengineer · ‎09-25-2021

Hi,

I have same problem. did you solve it and if it is,please share your configuration?

Peter Paluch · ‎09-25-2021

Hello,

This is quite an old thread but it seems that the flaps of the interface were caused by an incomplete configuration of the CHAP authentication on the CPE side.

Since your case may be different, and may involve a different username even if the basic issue is the same, can you please post the following?

Full configuration
Console outputs produced by

debug ppp negotiation
debug ppp authentication

These are crucial to assist you.

Thanks!

Best regards,
Peter

missengineer · ‎10-06-2021

Dear Peter,

Thank you for reply. I solved my problem. It was IMEI registration problem. We made it. Now Cellular interface and Tunnel 0 up but still doesnt work. You can find configuration below. I wrote static route, I can ping tunnel IP but I cant see 10.2.1.1 IP on route table and not pinging. Is there any idea ?

Current configuration : 2665 bytes

chat-script HSPA "" "ATDT*98#" TIMEOUT 30 CONNECT

!

username r1