Hi, we are using a cisco router 891 with an internal modem v.92 port. We are unable to do a dial-up connection with our usual dial-up provider. We are getting 2 confnak from him and then the event timeout occurs. Do you guys see anything wrong in our config?
Here is the debug information, the config will follow:
Jun 14 13:52:08: CHAT1: Attempting async line dialer script
Jun 14 13:52:08: CHAT1: Dialing using Modem script: dialout & System script: none
Jun 14 13:52:08: CHAT1: process started
Jun 14 13:52:08: CHAT1: Asserting DTR
Jun 14 13:52:08: CHAT1: Chat script dialout started
Jun 14 13:52:08: CHAT1: Sending string: ATDT \T<94186941429>
Jun 14 13:52:08: CHAT1: Expecting string: CONNECT
Jun 14 13:52:08: CHAT1: Input mismatch expecting: CONNECT :: ATDT 94186941429\015\015\012
Jun 14 13:52:29: CHAT1: Input match for: CONNECT:: CONNECT
Jun 14 13:52:29: CHAT1: Completed match for expect: CONNECT
Jun 14 13:52:29: CHAT1: Chat script dialout finished, status = Success
Jun 14 13:52:31: %LINK-3-UPDOWN: Interface Async1, changed state to up
Jun 14 13:52:31: As1 PPP: Sending cstate UP notification
Jun 14 13:52:31: As1 PPP: Processing CstateUp message
Jun 14 13:52:31: PPP: Alloc Context [85D30468]
Jun 14 13:52:31: ppp235 PPP: Phase is ESTABLISHING
Jun 14 13:52:31: As1 PPP: Using dialer call direction
Jun 14 13:52:31: As1 PPP: Treating connection as a callout
Jun 14 13:52:31: As1 PPP: Session handle[91000027] Session id[235]
Jun 14 13:52:31: As1 LCP: Event[OPEN] State[Initial to Starting]
Jun 14 13:52:31: As1 PPP: No remote authentication for call-out
Jun 14 13:52:31: As1 LCP: O CONFREQ [Starting] id 1 len 20
Jun 14 13:52:31: As1 LCP: ACCM 0x000A0000 (0x0206000A0000)
Jun 14 13:52:31: As1 LCP: MagicNumber 0x14667351 (0x050614667351)
Jun 14 13:52:31: As1 LCP: PFC (0x0702)
Jun 14 13:52:31: As1 LCP: ACFC (0x0802)
Jun 14 13:52:31: As1 LCP: Event[UP] State[Starting to REQsent]
Jun 14 13:52:33: As1 LCP: O CONFREQ [REQsent] id 2 len 20
Jun 14 13:52:33: As1 LCP: ACCM 0x000A0000 (0x0206000A0000)
Jun 14 13:52:33: As1 LCP: MagicNumber 0x14667351 (0x050614667351)
Jun 14 13:52:33: As1 LCP: PFC (0x0702)
Jun 14 13:52:33: As1 LCP: ACFC (0x0802)
Jun 14 13:52:33: As1 LCP: Event[Timeout+] State[REQsent to REQsent]
Jun 14 13:52:35: As1 LCP: O CONFREQ [REQsent] id 3 len 20
Jun 14 13:52:35: As1 LCP: ACCM 0x000A0000 (0x0206000A0000)
Jun 14 13:52:35: As1 LCP: MagicNumber 0x14667351 (0x050614667351)
Jun 14 13:52:35: As1 LCP: PFC (0x0702)
Jun 14 13:52:35: As1 LCP: ACFC (0x0802)
Jun 14 13:52:35: As1 LCP: Event[Timeout+] State[REQsent to REQsent]
Jun 14 13:52:37: As1 LCP: O CONFREQ [REQsent] id 4 len 20
Jun 14 13:52:37: As1 LCP: ACCM 0x000A0000 (0x0206000A0000)
Jun 14 13:52:37: As1 LCP: MagicNumber 0x14667351 (0x050614667351)
Jun 14 13:52:37: As1 LCP: PFC (0x0702)
Jun 14 13:52:37: As1 LCP: ACFC (0x0802)
Jun 14 13:52:37: As1 LCP: Event[Timeout+] State[REQsent to REQsent]
Jun 14 13:52:39: As1 LCP: O CONFREQ [REQsent] id 5 len 20
Jun 14 13:52:39: As1 LCP: ACCM 0x000A0000 (0x0206000A0000)
Jun 14 13:52:39: As1 LCP: MagicNumber 0x14667351 (0x050614667351)
Jun 14 13:52:39: As1 LCP: PFC (0x0702)
Jun 14 13:52:39: As1 LCP: ACFC (0x0802)
Jun 14 13:52:39: As1 LCP: Event[Timeout+] State[REQsent to REQsent]
Jun 14 13:52:41: As1 LCP: O CONFREQ [REQsent] id 6 len 20
Jun 14 13:52:41: As1 LCP: ACCM 0x000A0000 (0x0206000A0000)
Jun 14 13:52:41: As1 LCP: MagicNumber 0x14667351 (0x050614667351)
Jun 14 13:52:41: As1 LCP: PFC (0x0702)
Jun 14 13:52:41: As1 LCP: ACFC (0x0802)
Jun 14 13:52:41: As1 LCP: Event[Timeout+] State[REQsent to REQsent]
Jun 14 13:52:41: As1 LCP: I CONFREQ [REQsent] id 6 len 20
Jun 14 13:52:41: As1 LCP: ACCM 0x000A0000 (0x0206000A0000)
Jun 14 13:52:41: As1 LCP: MagicNumber 0x14667351 (0x050614667351)
Jun 14 13:52:41: As1 LCP: PFC (0x0702)
Jun 14 13:52:41: As1 LCP: ACFC (0x0802)
Jun 14 13:52:41: As1 LCP: Loopback seen, Magic number not Acked
Jun 14 13:52:41: As1 LCP: O CONFNAK [REQsent] id 6 len 10
Jun 14 13:52:41: As1 LCP: MagicNumber 0x14669AB0 (0x050614669AB0)
Jun 14 13:52:41: As1 LCP: Event[Receive ConfReq-] State[REQsent to REQsent]
Jun 14 13:52:41: As1 LCP: I CONFNAK [REQsent] id 6 len 10
Jun 14 13:52:41: As1 LCP: MagicNumber 0x14669AB0 (0x050614669AB0)
Jun 14 13:52:41: As1 PPP: Line appears to be looped back
Jun 14 13:52:44: As1 LCP: O CONFREQ [REQsent] id 7 len 20
Jun 14 13:52:44: As1 LCP: ACCM 0x000A0000 (0x0206000A0000)
Jun 14 13:52:44: As1 LCP: MagicNumber 0x14669AB5 (0x050614669AB5)
Jun 14 13:52:44: As1 LCP: PFC (0x0702)
Jun 14 13:52:44: As1 LCP: ACFC (0x0802)
Jun 14 13:52:44: As1 LCP: Event[Timeout+] State[REQsent to REQsent]
Jun 14 13:52:44: %LINK-5-CHANGED: Interface Async1, changed state to reset
Jun 14 13:52:44: As1 PPP: Sending cstate DOWN notification
Jun 14 13:52:44: As1 PPP: Processing CstateDown message
Jun 14 13:52:44: As1 PPP DISC: Lower Layer disconnected
Jun 14 13:52:44: As1 PPP: Sending Acct Event[Down] id[4E7]
Jun 14 13:52:44: PPP: NET STOP send to AAA.
Jun 14 13:52:44: As1 LCP: O TERMREQ [REQsent] id 8 len 4
Jun 14 13:52:44: As1 LCP: Event[CLOSE] State[REQsent to Closing]
Jun 14 13:52:44: As1 PPP: Phase is TERMINATING
Jun 14 13:52:44: As1 LCP: Event[DOWN] State[Closing to Initial]
Jun 14 13:52:44: As1 PPP: Phase is DOWN
Jun 14 13:52:49: %LINK-3-UPDOWN: Interface Async1, changed state to down
Jun 14 13:52:49: As1 PPP: Sending cstate DOWN notification
Jun 14 13:52:49: As1 PPP: Processing CstateDown message
Here is the config of the 891:
interface Async1
ip address negotiated
encapsulation ppp
dialer in-band
dialer idle-timeout 60
dialer wait-for-carrier-time 10
dialer string XXXXXXXXXXX modem-script dialout
dialer watch-group 8
async dynamic address
async dynamic routing
async mode dedicated
no keepalive
ppp authentication pap callin
ppp pap sent-username XXXXXXXXXX password 7 XXXXXXXXXXXX
end
Modemcap values for my_modem
Factory Defaults (FD): &F
Autoanswer (AA): S0=0
Carrier detect (CD): &C1
Drop with DTR (DTR): &D2
Hardware Flowcontrol (HFL): [not set]
Lock DTE speed (SPD): [not set]
DTE locking speed (DTE): [not set]
Best Error Control (BER): [not set]
Best Compression (BCP): [not set]
No Error Control (NER): [not set]
No Compression (NCP): [not set]
No Echo (NEC): E0
No Result Codes (NRS): Q1
Software Flowcontrol (SFL): [not set]
Caller ID (CID): [not set]
On-hook (ONH): [not set]
Off-hook (OFH): [not set]
Miscellaneous (MSC): [not set]
Template entry (TPL): [not set]
line 1
exec-timeout 0 0
modem InOut
modem autoconfigure type my_modem
stopbits 1
speed 115200
flowcontrol hardware
Hostname(config-if)# do sh debug
PPP:
PPP authentication debugging is on
PPP protocol negotiation debugging is on
Chat Scripts:
Chat scripts activity debugging is on for line number 1
Hostname(config-if)# do sh debug
PPP:
PPP authentication debugging is on
PPP protocol negotiation debugging is on
Chat Scripts:
Chat scripts activity debugging is on for line number 1
Hostname(config-if)#do sh line 1
Tty Typ Tx/Rx A Modem Roty AccO AccI Uses Noise Overruns Int
* 1 TTY - inout - - - 0 148 0/0 -
Line 1, Location: "", Type: ""
Length: 24 lines, Width: 80 columns
Status: Dialing out, Ready, Active, No Exit Banner, Modem Detected
CTS Raised
Capabilities: Hardware Flowcontrol In, Hardware Flowcontrol Out
Modem Callout, Modem RI is CD, Line is permanent async interface
Modem Autoconfigure, Integrated Modem
Modem state: Ready
Modem hardware state: CTS* noDSR DTR RTS, Modem Configured
Special Chars: Escape Hold Stop Start Disconnect Activation
^^x none - - none
Timeouts: Idle EXEC Idle Session Modem Answer Session Dispatch
never never none not set
Idle Session Disconnect Warning
never
Login-sequence User Response
00:00:30
Autoselect Initial Wait
not set
Modem type is my_modem.
Session limit is not set.
Time since activation: never
Editing is enabled.
History is enabled, history size is 20.
DNS resolution in show commands is enabled
Full user help is disabled
Allowed input transports are none.
Allowed output transports are pad telnet rlogin ssh.
Preferred transport is telnet.
Shell: enabled
Shell trace: off
No output characters are padded
No special data dispatching characters
Thx for help
John
