Problem in DialUp access configuration

vcoscodan · ‎06-25-2002

Hi there,

We have a Cisco 2662 with NM-8AM and IOS version 12.8(T4). When a remote user dials in with W95/W98/w2k the system asks for the number to be called back. After a few seconds the client is called back and the connection is made. So far so good.

But in debug information I see that my chat-script "modem-callback" is't call. Why? (see first debug listing).

Other problem is when a remote user dials in with W95/W98/w2k the system asks for the number to be called back, but the client press escape (cancel) for direct connection (without callback). In this case, connection is droped (see second debug listing). Why? In old IOS version of IOS (12.0(T1)) it worked fine. We don't change configuration.

Do you have seen similar problems like this or does anyone know how to configure DialIn for this situations?

Thanks in advance for helping us.

Our config is:

version 12.2

service timestamps debug datetime localtime

service timestamps log datetime localtime

service password-encryption

!

hostname bnm

!

logging buffered 10000 debugging

aaa new-model

!

aaa authentication login admins local

aaa authentication ppp dialup group radius local

aaa authorization network default group radius local

aaa accounting network default start-stop group radius

aaa session-id common

enable secret 5 $1$u3EP$F5l49mkVFKmhf6GEXdy2C/

enable password 7 1511021F457C7B742A3D38

!

username admin password 7 120D3044065A5E57

username BackUp password 7 121D0C617E1E1C

clock timezone OMT 2

clock summer-time OMTS recurring last Sun Mar 3:00 last Sun Oct 3:00

modem country microcom_hdms europe

ip subnet-zero

no ip source-route

!

no ip domain-lookup

ip domain-name bn.org

!

chat-script offhook "" "ATH1" OK

chat-script modem-callback ABORT ERROR ABORT BUSY ABORT "NO ANSWER" "" "ATH" OK "ATDT\T" TIMEOUT 30 CONNECT

modemcap entry MY_MODEMCAP:MSC=&F&D2

!

fax interface-type fax-mail

mta receive maximum-recipients 0

!

interface Loopback0

ip address 172.16.0.247 255.255.255.255

!

interface Group-Async2

description multicanal line (tel.225008)

ip unnumbered Loopback0

no ip unreachables

ip nat outside

ip audit AUDIT.1 in

encapsulation ppp

no ip route-cache

ip tcp header-compression passive

no ip mroute-cache

dialer in-band

dialer idle-timeout 300

dialer-group 1

async mode dedicated

peer default ip address pool dialup1

pulse-time 3

compress mppc

ppp max-bad-auth 3

ppp callback accept

ppp authentication chap dialup

group-range 33 40

!

dialer-list 1 protocol ip permit

!

radius-server host 192.168.194.129 auth-port 1645 acct-port 1646

radius-server retransmit 3

radius-server key 7 073326466607310C391A5E5450273327716B170C190C1C5058525E

call rsvp-sync

!

mgcp profile default

!

dial-peer cor custom

!

line con 0

line 33 40

session-timeout 30

absolute-timeout 240

script modem-off-hook offhook

script callback modem-callback

login authentication admins

modem InOut

modem autoconfigure type mica

transport input all

autoselect during-login

autoselect ppp

callback nodsr-wait 10000

stopbits 1

flowcontrol hardware

line aux 0

line vty 0 4

access-class 5 in

password 7 030752185459234243

line vty 5 15

!

end

Debug setings is:

General OS:

Modem control/process activation debugging is on

AAA Authorization debugging is on

CSM Modem Management:

Modem Management Call Switching Module debugging is on

PPP:

PPP protocol negotiation debugging is on

PPP Callback Control Protocol debugging is on

Chat Scripts:

Chat scripts activity debugging is on

Callback:

Callback activity debugging is on

Radius protocol debugging is on

Radius packet protocol debugging is on

Start first debug listing ==================================================================================

CSM_ANALOG_MODEM_IDLE: MODEM_STARTING_CONNECT at slot 1, port 4

Modem 1/4 Mcom: in modem state 'Dialing/Answering'

ANALOG_CONNECT_INITIATED: MODEM_ANALOG_CALL_IN at slot 1, port 4

Modem 1/4 Mcom: in modem state 'Incoming ring'

Modem 1/4 Mcom: in modem state 'Waiting for Carrier'

Modem 1/4 Mcom: in modem state 'Connected'

ANALOG_CONNECT_INITIATED: MODEM_CONNECTED at slot 1, port 4

Call Handle failed for Modem 1/4

Modem 1/4 Mcom: CONNECT at 28800/24000(Tx/Rx), V34, LAPM, V42bis, Answer

TTY37: DSR came up

Modem 1/4 Mcom: switching to PPP mode

TTY37: no timer type 1 to destroy

TTY37: no timer type 0 to destroy

TTY37: create timer type 2, 1800 seconds

TTY37: create timer type 0, 14400 seconds

tty37: Modem: IDLE->(unknown)

As37 LCP: I CONFREQ [Closed] id 1 len 23

As37 LCP: ACCM 0x00000000 (0x020600000000)

As37 LCP: MagicNumber 0x32F108D4 (0x050632F108D4)

As37 LCP: PFC (0x0702)

As37 LCP: ACFC (0x0802)

As37 LCP: Callback 6 (0x0D0306)

As37 LCP: Lower layer not up, Fast Starting

As37 PPP: Treating connection as a callin

As37 PPP: Phase is ESTABLISHING, Passive Open

As37 LCP: State is Listen

Modem 1/4 Mcom: PPP escape map: Tx map = FFFFFFFF, Rx map = 0

As37 LCP: O CONFREQ [Listen] id 71 len 25

As37 LCP: ACCM 0x000A0000 (0x0206000A0000)

As37 LCP: AuthProto CHAP (0x0305C22305)

As37 LCP: MagicNumber 0x15589567 (0x050615589567)

As37 LCP: PFC (0x0702)

As37 LCP: ACFC (0x0802)

As37 LCP: O CONFACK [Listen] id 1 len 23

As37 LCP: ACCM 0x00000000 (0x020600000000)

As37 LCP: MagicNumber 0x32F108D4 (0x050632F108D4)

As37 LCP: PFC (0x0702)

As37 LCP: ACFC (0x0802)

As37 LCP: Callback 6 (0x0D0306)

%LINK-3-UPDOWN: Interface Async37, changed state to up

As37 LCP: I CONFACK [ACKsent] id 71 len 25

As37 LCP: ACCM 0x000A0000 (0x0206000A0000)

As37 LCP: AuthProto CHAP (0x0305C22305)

As37 LCP: MagicNumber 0x15589567 (0x050615589567)

As37 LCP: PFC (0x0702)

As37 LCP: ACFC (0x0802)

As37 LCP: State is Open

Modem 1/4 Mcom: PPP escape map: Tx map = 0, Rx map = 0

As37 MCB: Initialize

As37 MCB: Flush

As37 PPP: Phase is AUTHENTICATING, by this end

As37 CHAP: O CHALLENGE id 28 len 24 from "bnm"

As37 LCP: I IDENTIFY [Open] id 2 len 18 magic 0x32F108D4 MSRASV5.00

As37 CHAP: I RESPONSE id 28 len 34 from ".rig.auto.nbm"

As37 PPP: Phase is FORWARDING, Attempting Forward

As37 PPP: Phase is AUTHENTICATING, Unauthenticated User

RADIUS/ENCODE(000004A8): acct_session_id: 1438

RADIUS(000004A8): sending

RADIUS: Send to unknown id 28 212.56.194.129:1645, Access-Request, len 91

RADIUS: authenticator 63 AF 2A F1 7B FE C5 5E - BD 93 B9 1D C7 87 15 E4

RADIUS: Framed-Protocol [7] 6 PPP [1]

RADIUS: User-Name [1] 15 ".rig.auto.nbm"

RADIUS: CHAP-Password [3] 19 *

RADIUS: NAS-Port [5] 6 37

RADIUS: NAS-Port-Type [61] 6 Async [0]

RADIUS: Calling-Station-Id [31] 7 "async"

RADIUS: Service-Type [6] 6 Framed [2]

RADIUS: NAS-IP-Address [4] 6 212.56.194.130

RADIUS: Received from id 28 212.56.194.129:1645, Access-Accept, len 136

RADIUS: authenticator 66 80 28 48 6D 4A 7D 81 - 05 B8 BC 03 2D C2 D2 A8

RADIUS: Framed-IP-Address [8] 6 192.168.5.7

RADIUS: Framed-Protocol [7] 6 PPP [1]

RADIUS: Service-Type [6] 6 Framed [2]

RADIUS: Vendor, Cisco [26] 20

RADIUS: Cisco AVpair [1] 14 "ip:inacl=106"

RADIUS: Idle-Timeout [28] 6 1800

RADIUS: Vendor, Cisco [26] 34

RADIUS: Cisco AVpair [1] 28 "ip:dns-servers=192.168.0.1"

RADIUS: Session-Timeout [27] 6 7200

RADIUS: Vendor, Cisco [26] 32

RADIUS: Cisco AVpair [1] 26 "lcp:callback-dialstring="

RADIUS: Received from id 4A8

As37 PPP/AAA: Check Attr: addr

As37 PPP/AAA: Check Attr: Framed-Protocol

As37 PPP/AAA: Check Attr: service-type

As37 PPP/AAA: Check Attr: inacl:Peruser

As37 PPP/AAA: Check Attr: idletime:Peruser

As37 PPP/AAA: Check Attr: dns-servers

As37 PPP/AAA: Check Attr: timeout:Peruser

As37 PPP/AAA: Check Attr: callback-dialstring

As37 PPP: Phase is FORWARDING, Attempting Forward

As37 PPP: Phase is AUTHENTICATING, Authenticated User

As37 AAA/AUTHOR/LCP: Process Author

As37 AAA/AUTHOR/LCP: Process Attr: idletime

AAA/AUTHOR: Processing PerUser AV idletime

As37 AAA/AUTHOR/LCP: Process Attr: timeout

AAA/AUTHOR: Processing PerUser AV timeout

As37 AAA/AUTHOR/LCP: Process Attr: callback-dialstring

As37 CHAP: O SUCCESS id 28 len 4

As37 MCB: Start

As37 MCB: User .rig.auto.nbm Callback Number - Client ANY

As37 MCB: Newstate 0-INIT -> 2-WAIT_RESPONSE

Async37 PPP: O MCB Request(1) id 44 len 9

Async37 MCB: O 1 2C 0 9 2 5 0 1 0

As37 MCB: O Request Id 44 Callback Type Client-Num delay 0

As37 PPP: Phase is CBCP

RADIUS/ENCODE(000004A8): Unsupported AAA attribute start_time

RADIUS/ENCODE(000004A8): Unsupported AAA attribute timezone

RADIUS(000004A8): sending

RADIUS: Send to unknown id 237 212.56.194.129:1646, Accounting-Request, len 135

RADIUS: authenticator 64 83 7E BD DA 5B 65 92 - 61 4D 0B 01 4D 4A 59 12

RADIUS: Acct-Session-Id [44] 10 "0000059E"

RADIUS: Framed-Protocol [7] 6 PPP [1]

RADIUS: Connect-Info [77] 29 "28800/24000 V34/V42bis/LAPM"

RADIUS: Authentic [45] 6 RADIUS [1]

RADIUS: User-Name [1] 15 ".rig.auto.nbm"

RADIUS: Acct-Status-Type [40] 6 Start [1]

RADIUS: NAS-Port [5] 6 37

RADIUS: NAS-Port-Type [61] 6 Async [0]

RADIUS: Calling-Station-Id [31] 7 "async"

RADIUS: Service-Type [6] 6 Framed [2]

RADIUS: NAS-IP-Address [4] 6 212.56.194.130

RADIUS: Event-Timestamp [55] 6 1025017394

RADIUS: Acct-Delay-Time [41] 6 0

RADIUS: Received from id 237 212.56.194.129:1646, Accounting-response, len 20

RADIUS: authenticator 70 32 56 D1 49 34 17 23 - 58 0F 57 99 27 76 5A 38

Async37 PPP: I MCB Response(2) id 44 len 15

Async37 MCB: I 2 2C 0 F 2 B C 1 32 32 36 36 39 34 0

As37 MCB: Received response

As37 MCB: Response CBK-Client-Num 2 11 12, addr 1-226694

Async37 PPP: O MCB Ack(3) id 45 len 15

Async37 MCB: O 3 2D 0 F 2 B C 1 32 32 36 36 39 34 0

As37 MCB: O Ack Id 45 Callback Type Client-Num delay 12

As37 MCB: Negotiated MCB with peer

As37 MCB: Newstate 2-WAIT_RESPONSE -> 3-WAIT_TERMINATE

As37 LCP: I TERMREQ [Open] id 3 len 16 (0x32F108D4003CCD7400000000)

As37 LCP: O TERMACK [Open] id 3 len 4

As37 MCB: Peer terminating the link

As37 MCB: Newstate 3-WAIT_TERMINATE -> 5-WAIT_LINKDOWN

As37 CCP: State is Closed

As37 CCP: Compression already closed

As37 MCB: Link terminated by peer, Callback Needed

As37 MCB: Newstate 5-WAIT_LINKDOWN -> 6-CALLING

As37 MCB: Initiate Callback for ¦í-ÿÀ]þô at 226694 using Async DDR

As37 MCB: Dialer callback started

As37 PPP: Phase is TERMINATING

RADIUS/ENCODE(000004A8): Unsupported AAA attribute start_time

RADIUS/ENCODE(000004A8): Unsupported AAA attribute timezone

RADIUS(000004A8): sending

RADIUS: Send to unknown id 238 212.56.194.129:1646, Accounting-Request, len 186

RADIUS: authenticator 31 47 1C CC 12 25 99 5F - B4 A6 B7 C7 74 E6 29 84

RADIUS: Acct-Session-Id [44] 10 "0000059E"

RADIUS: Framed-Protocol [7] 6 PPP [1]

RADIUS: User-Name [1] 15 ".rig.auto.nbm"

RADIUS: Acct-Session-Time [46] 6 2

RADIUS: Connect-Info [77] 29 "28800/24000 V34/V42bis/LAPM"

RADIUS: Acct-Input-Octets [42] 6 37

RADIUS: Acct-Output-Octets [43] 6 27

RADIUS: Acct-Input-Packets [47] 6 2

RADIUS: Acct-Output-Packets [48] 6 2

RADIUS: Acct-Terminate-Cause[49] 6 user-request [1]

RADIUS: Authentic [45] 6 RADIUS [1]

RADIUS: User-Name [1] 15 ".rig.auto.nbm"

RADIUS: Acct-Status-Type [40] 6 Stop [2]

RADIUS: NAS-Port [5] 6 37

RADIUS: NAS-Port-Type [61] 6 Async [0]

RADIUS: Calling-Station-Id [31] 7 "async"

RADIUS: Service-Type [6] 6 Framed [2]

RADIUS: NAS-IP-Address [4] 6 212.56.194.130

RADIUS: Event-Timestamp [55] 6 1025017396

RADIUS: Acct-Delay-Time [41] 6 0

RADIUS: Received from id 238 212.56.194.129:1646, Accounting-response, len 20

RADIUS: authenticator 00 62 A3 3F 3A 4A E4 91 - 22 60 06 A6 65 15 1B A0

ANALOG_MODEM_CONNECTED: MODEM_DISCONNECTED at slot 1, port 4

Modem 1/4 CSM: CSM_MODEM_DEALLOCATE: modem is deallocated

Modem 1/4 Mcom: in modem state 'Disconnecting'

Modem 1/4 Mcom: DISCONNECT, duration = 00:00:06, reason (0xE) Remote Link Disc

TTY37: DSR was dropped

tty37: Modem: READY->(unknown)

Modem 1/4 Mcom: in modem state 'Idle'

TTY37: dropping DTR, hanging up

TTY37: Async Int reset: Dropping DTR

tty37: Modem: HANGUP->(unknown)

As37 LCP: TIMEout: State TERMsent

As37 LCP: State is Closed

As37 PPP: Phase is DOWN

As37 PPP: Phase is ESTABLISHING, Passive Open

As37 LCP: State is Listen

TTY37: cleanup pending. Delaying DTR

%LINK-5-CHANGED: Interface Async37, changed state to reset

As37 LCP: State is Closed

As37 PPP: Phase is DOWN

TTY37: cleanup pending. Delaying DTR

Modem 1/4 Mcom: switching to character mode

TTY37: destroy timer type 0

TTY37: no timer type 1 to destroy

TTY37: no timer type 3 to destroy

TTY37: no timer type 4 to destroy

TTY37: destroy timer type 2

Async37: allowing modem_process to continue hangup

TTY37: restoring DTR

TTY37: autoconfigure probe started

%LINK-3-UPDOWN: Interface Async37, changed state to down

As37 LCP: State is Closed

%FR-5-DLCICHANGE: Interface Serial0/0 - DLCI 580 state changed to INACTIVE

%LINEPROTO-5-UPDOWN: Line protocol on Interface Serial0/0.580, changed state to down

CHAT37: Attempting async line dialer script

CHAT37: no matching chat script found for 226694

CHAT37: Dialing using Modem script: d0efault-d0ials0cript & System script: none

CHAT37: process started

CHAT37: Asserting DTR

CHAT37: Chat script d0efault-d0ials0cript started

CHAT37: Sending string: ATZ

CHAT37: Expecting string: OK

CHAT37: Completed match for expect: OK

CHAT37: Sending string: AT

CHAT37: Expecting string: OK

CHAT37: Completed match for expect: OK

CHAT37: Sending string: ATDT\T<226694>

CHAT37: Expecting string: CONNECT

CSM_ANALOG_MODEM_IDLE: MODEM_STARTING_CONNECT at slot 1, port 4

Modem 1/4 Mcom: in modem state 'Dialing/Answering'

Modem 1/4 Mcom: in modem state 'Waiting for Carrier'

Modem 1/4 Mcom: in modem state 'Connected'

ANALOG_CONNECT_INITIATED: MODEM_CONNECTED at slot 1, port 4

Call Handle failed for Modem 1/4

Modem 1/4 Mcom: CONNECT at 28800/24000(Tx/Rx), V34, LAPM, V42bis, Originate

CHAT37: Completed match for expect: CONNECT

CHAT37: Chat script d0efault-d0ials0cript finished, status = Success

Modem 1/4 Mcom: switching to PPP mode

TTY37: no timer type 1 to destroy

TTY37: no timer type 0 to destroy

TTY37: create timer type 2, 1800 seconds

TTY37: create timer type 0, 14400 seconds

As37 LCP: I CONFREQ [Closed] id 1 len 20

As37 LCP: ACCM 0x00000000 (0x020600000000)

As37 LCP: MagicNumber 0x6A201FD0 (0x05066A201FD0)

As37 LCP: PFC (0x0702)

As37 LCP: ACFC (0x0802)

As37 LCP: Lower layer not up, Fast Starting

As37 PPP: Treating connection as a callout

As37 PPP: Phase is ESTABLISHING, Active Open

Modem 1/4 Mcom: PPP escape map: Tx map = FFFFFFFF, Rx map = 0

As37 AAA/AUTHOR/LCP: Authorization succeeds trivially

As37 LCP: O CONFREQ [Closed] id 72 len 25

As37 LCP: ACCM 0x000A0000 (0x0206000A0000)

As37 LCP: AuthProto CHAP (0x0305C22305)

As37 LCP: MagicNumber 0x15596A45 (0x050615596A45)

As37 LCP: PFC (0x0702)

As37 LCP: ACFC (0x0802)

As37 LCP: O CONFACK [REQsent] id 1 len 20

As37 LCP: ACCM 0x00000000 (0x020600000000)

As37 LCP: MagicNumber 0x6A201FD0 (0x05066A201FD0)

As37 LCP: PFC (0x0702)

As37 LCP: ACFC (0x0802)

%LINK-3-UPDOWN: Interface Async37, changed state to up

As37 LCP: I CONFACK [ACKsent] id 72 len 25

As37 LCP: ACCM 0x000A0000 (0x0206000A0000)

As37 LCP: AuthProto CHAP (0x0305C22305)

As37 LCP: MagicNumber 0x15596A45 (0x050615596A45)

As37 LCP: PFC (0x0702)

As37 LCP: ACFC (0x0802)

As37 LCP: State is Open

Modem 1/4 Mcom: PPP escape map: Tx map = 0, Rx map = 0

As37 MCB: Initialize

As37 PPP: Phase is AUTHENTICATING, by this end

As37 CHAP: O CHALLENGE id 29 len 24 from "bnm"

As37 LCP: I IDENTIFY [Open] id 2 len 18 magic 0x6A201FD0 MSRASV5.00

As37 CHAP: I RESPONSE id 29 len 34 from ".rig.auto.nbm"

As37 PPP: Phase is FORWARDING, Attempting Forward

As37 PPP: Phase is AUTHENTICATING, Unauthenticated User

RADIUS/ENCODE(000004A9): acct_session_id: 1440

RADIUS(000004A9): sending

RADIUS: Send to unknown id 29 212.56.194.129:1645, Access-Request, len 91

RADIUS: authenticator 59 01 FF FA 74 23 49 41 - BD 93 B9 1D DD D0 4B C3

RADIUS: Framed-Protocol [7] 6 PPP [1]

RADIUS: User-Name [1] 15 ".rig.auto.nbm"

RADIUS: CHAP-Password [3] 19 *

RADIUS: NAS-Port [5] 6 37

RADIUS: NAS-Port-Type [61] 6 Async [0]

RADIUS: Calling-Station-Id [31] 7 "async"

RADIUS: Service-Type [6] 6 Framed [2]

RADIUS: NAS-IP-Address [4] 6 212.56.194.130

RADIUS: Received from id 29 212.56.194.129:1645, Access-Accept, len 136

RADIUS: authenticator A0 6E A1 63 54 CC 42 D3 - 08 03 7B BE 12 AB 40 84

RADIUS: Framed-IP-Address [8] 6 192.168.5.7

RADIUS: Framed-Protocol [7] 6 PPP [1]

RADIUS: Service-Type [6] 6 Framed [2]

RADIUS: Vendor, Cisco [26] 20

RADIUS: Cisco AVpair [1] 14 "ip:inacl=106"

RADIUS: Idle-Timeout [28] 6 1800

RADIUS: Vendor, Cisco [26] 34

RADIUS: Cisco AVpair [1] 28 "ip:dns-servers=192.168.0.1"

RADIUS: Session-Timeout [27] 6 7200

RADIUS: Vendor, Cisco [26] 32

RADIUS: Cisco AVpair [1] 26 "lcp:callback-dialstring="

RADIUS: Received from id 4A9

As37 PPP/AAA: Check Attr: addr

As37 PPP/AAA: Check Attr: Framed-Protocol

As37 PPP/AAA: Check Attr: service-type

As37 PPP/AAA: Check Attr: inacl:Peruser

As37 PPP/AAA: Check Attr: idletime:Peruser

As37 PPP/AAA: Check Attr: dns-servers

As37 PPP/AAA: Check Attr: timeout:Peruser

As37 PPP/AAA: Check Attr: callback-dialstring

As37 PPP: Phase is FORWARDING, Attempting Forward

As37 PPP: Phase is AUTHENTICATING, Authenticated User

As37 AAA/AUTHOR/LCP: Process Author

As37 AAA/AUTHOR/LCP: Process Attr: idletime

AAA/AUTHOR: Processing PerUser AV idletime

As37 AAA/AUTHOR/LCP: Process Attr: timeout

AAA/AUTHOR: Processing PerUser AV timeout

As37 AAA/AUTHOR/LCP: Process Attr: callback-dialstring

As37 CHAP: O SUCCESS id 29 len 4

As37 PPP: Phase is UP

As37 AAA/AUTHOR/IPCP: FSM authorization not needed

As37 AAA/AUTHOR/FSM: We can start IPCP

As37 IPCP: O CONFREQ [Closed] id 21 len 16

As37 IPCP: CompressType VJ 15 slots (0x0206002D0F00)

As37 IPCP: Address 172.16.0.247 (0x0306AC1000F7)

As37 CCP: Compression already closed

As37 AAA/AUTHOR/CCP: FSM authorization not needed

As37 AAA/AUTHOR/FSM: We can start CCP

As37 CCP: O CONFREQ [Closed] id 20 len 10

As37 CCP: MS-PPC supported bits 0x00000001 (0x120600000001)

RADIUS/ENCODE(000004A9): Unsupported AAA attribute start_time

RADIUS/ENCODE(000004A9): Unsupported AAA attribute timezone

RADIUS(000004A9): sending

RADIUS: Send to unknown id 239 212.56.194.129:1646, Accounting-Request, len 135

RADIUS: authenticator E5 9D 09 D1 D1 18 B2 9B - F7 39 F6 04 0C D9 C3 7B

RADIUS: Acct-Session-Id [44] 10 "000005A0"

RADIUS: Framed-Protocol [7] 6 PPP [1]

RADIUS: Connect-Info [77] 29 "28800/24000 V34/V42bis/LAPM"

RADIUS: Authentic [45] 6 RADIUS [1]

RADIUS: User-Name [1] 15 ".rig.auto.nbm"

RADIUS: Acct-Status-Type [40] 6 Start [1]

RADIUS: NAS-Port [5] 6 37

RADIUS: NAS-Port-Type [61] 6 Async [0]

RADIUS: Calling-Station-Id [31] 7 "async"

RADIUS: Service-Type [6] 6 Framed [2]

RADIUS: NAS-IP-Address [4] 6 212.56.194.130

RADIUS: Event-Timestamp [55] 6 1025017449

RADIUS: Acct-Delay-Time [41] 6 0

RADIUS: Received from id 239 212.56.194.129:1646, Accounting-response, len 20

RADIUS: authenticator 60 68 BE D5 4B 6A 28 2A - 20 C7 EB DF 12 DD F9 31

As37 CCP: I CONFREQ [REQsent] id 3 len 10

As37 CCP: MS-PPC supported bits 0x00000001 (0x120600000001)

As37 CCP: O CONFACK [REQsent] id 3 len 10

As37 CCP: MS-PPC supported bits 0x00000001 (0x120600000001)

As37 IPCP: I CONFREQ [REQsent] id 4 len 40

As37 IPCP: CompressType VJ 15 slots CompressSlotID (0x0206002D0F01)

As37 IPCP: Address 0.0.0.0 (0x030600000000)

As37 IPCP: PrimaryDNS 0.0.0.0 (0x810600000000)

As37 IPCP: PrimaryWINS 0.0.0.0 (0x820600000000)

As37 IPCP: SecondaryDNS 0.0.0.0 (0x830600000000)

As37 IPCP: SecondaryWINS 0.0.0.0 (0x840600000000)

As37 AAA/AUTHOR/IPCP: Start. Her address 0.0.0.0, we want 0.0.0.0

As37 AAA/AUTHOR/IPCP: Processing AV addr

As37 AAA/AUTHOR/IPCP: Processing AV inacl

As37 AAA/AUTHOR/IPCP: Processing AV dns-servers

As37 AAA/AUTHOR/IPCP: Authorization succeeded

As37 AAA/AUTHOR/IPCP: Done. Her address 0.0.0.0, we want 192.168.5.7

As37 AAA/AUTHOR/IPCP: primary dns server 192.168.0.1

As37 AAA/AUTHOR/IPCP: no author-info for primary wins

As37 AAA/AUTHOR/IPCP: no author-info for seconday wins

As37 IPCP: O CONFREJ [REQsent] id 4 len 22

As37 IPCP: PrimaryWINS 0.0.0.0 (0x820600000000)

As37 IPCP: SecondaryDNS 0.0.0.0 (0x830600000000)

As37 IPCP: SecondaryWINS 0.0.0.0 (0x840600000000)

As37 IPCP: I CONFACK [REQsent] id 21 len 16

As37 IPCP: CompressType VJ 15 slots (0x0206002D0F00)

As37 IPCP: Address 172.16.0.247 (0x0306AC1000F7)

As37 CCP: I CONFACK [ACKsent] id 20 len 10

As37 CCP: MS-PPC supported bits 0x00000001 (0x120600000001)

As37 CCP: State is Open

As37 CCP: ppp_hwcomp_open: no h/w

As37 IPCP: I CONFREQ [ACKrcvd] id 5 len 22

As37 IPCP: CompressType VJ 15 slots CompressSlotID (0x0206002D0F01)

As37 IPCP: Address 0.0.0.0 (0x030600000000)

As37 IPCP: PrimaryDNS 0.0.0.0 (0x810600000000)

As37 AAA/AUTHOR/IPCP: primary dns server 192.168.0.1

As37 IPCP: O CONFNAK [ACKrcvd] id 5 len 16

As37 IPCP: Address 192.168.5.7 (0x0306C0A80507)

As37 IPCP: PrimaryDNS 192.168.0.1 (0x8106C0A80001)

As37 IPCP: I CONFREQ [ACKrcvd] id 6 len 22

As37 IPCP: CompressType VJ 15 slots CompressSlotID (0x0206002D0F01)

As37 IPCP: Address 192.168.5.7 (0x0306C0A80507)

As37 IPCP: PrimaryDNS 192.168.0.1 (0x8106C0A80001)

As37 AAA/AUTHOR/IPCP: primary dns server 192.168.0.1

As37 IPCP: O CONFACK [ACKrcvd] id 6 len 22

As37 IPCP: CompressType VJ 15 slots CompressSlotID (0x0206002D0F01)

As37 IPCP: Address 192.168.5.7 (0x0306C0A80507)

As37 IPCP: PrimaryDNS 192.168.0.1 (0x8106C0A80001)

As37 IPCP: State is Open

AAA/AUTHOR: Processing PerUser AV inacl

As37 IPCP: Install route to 192.168.5.7

As37 IPCP: Add link info for cef entry 192.168.5.7

%LINEPROTO-5-UPDOWN: Line protocol on Interface Async37, changed state to up

As37 LCP: I TERMREQ [Open] id 7 len 16 (0x6A201FD0003CCD7400000000)

As37 LCP: O TERMACK [Open] id 7 len 4

As37 IPCP: Remove link info for cef entry 192.168.5.7

As37 IPCP: State is Closed

As37 CCP: Compression already closed

As37 CCP: State is Closed

As37 CCP: Compression already closed

As37 PPP: Phase is TERMINATING

RADIUS/ENCODE(000004A9): Unsupported AAA attribute start_time

RADIUS/ENCODE(000004A9): Unsupported AAA attribute timezone

RADIUS(000004A9): sending

As37 IPCP: Remove route to 192.168.5.7

RADIUS: Send to unknown id 240 212.56.194.129:1646, Accounting-Request, len 192

RADIUS: authenticator E5 F2 4C 28 8A 37 EE 17 - 08 0C B3 FF B6 DE 09 B2

RADIUS: Acct-Session-Id [44] 10 "000005A0"

RADIUS: Framed-Protocol [7] 6 PPP [1]

RADIUS: Framed-IP-Address [8] 6 192.168.5.7

RADIUS: User-Name [1] 15 ".rig.auto.nbm"

RADIUS: Acct-Session-Time [46] 6 13

RADIUS: Connect-Info [77] 29 "28800/24000 V34/V42bis/LAPM"

RADIUS: Acct-Input-Octets [42] 6 604

RADIUS: Acct-Output-Octets [43] 6 128

RADIUS: Acct-Input-Packets [47] 6 17

RADIUS: Acct-Output-Packets [48] 6 7

RADIUS: Acct-Terminate-Cause[49] 6 user-request [1]

RADIUS: Authentic [45] 6 RADIUS [1]

RADIUS: User-Name [1] 15 ".rig.auto.nbm"

RADIUS: Acct-Status-Type [40] 6 Stop [2]

RADIUS: NAS-Port [5] 6 37

RADIUS: NAS-Port-Type [61] 6 Async [0]

RADIUS: Calling-Station-Id [31] 7 "async"

RADIUS: Service-Type [6] 6 Framed [2]

RADIUS: NAS-IP-Address [4] 6 212.56.194.130

RADIUS: Event-Timestamp [55] 6 1025017461

RADIUS: Acct-Delay-Time [41] 6 0

RADIUS: Received from id 240 212.56.194.129:1646, Accounting-response, len 20

RADIUS: authenticator CC 9A F8 A5 01 DF 08 12 - 71 7C D2 AC A7 25 FA 39

ANALOG_MODEM_CONNECTED: MODEM_DISCONNECTED at slot 1, port 4

Modem 1/4 CSM: CSM_MODEM_DEALLOCATE: modem is deallocated

Modem 1/4 Mcom: in modem state 'Disconnecting'

Modem 1/4 Mcom: DISCONNECT, duration = 00:00:16, reason (0xE) Remote Link Disc

TTY37: DSR was dropped

tty37: Modem: READY->(unknown)

%LINEPROTO-5-UPDOWN: Line protocol on Interface Async37, changed state to down

TTY37: dropping DTR, hanging up

TTY37: Async Int reset: Dropping DTR

tty37: Modem: HANGUP->(unknown)

Modem 1/4 Mcom: in modem state 'Idle'

As37 LCP: TIMEout: State TERMsent

As37 LCP: State is Closed

As37 PPP: Phase is DOWN

As37 PPP: Phase is ESTABLISHING, Passive Open

As37 LCP: State is Listen

TTY37: cleanup pending. Delaying DTR

%LINK-5-CHANGED: Interface Async37, changed state to reset

As37 LCP: State is Closed

As37 PPP: Phase is DOWN

TTY37: cleanup pending. Delaying DTR

Modem 1/4 Mcom: switching to character mode

TTY37: destroy timer type 0

TTY37: no timer type 1 to destroy

TTY37: no timer type 3 to destroy

TTY37: no timer type 4 to destroy

TTY37: destroy timer type 2

Async37: allowing modem_process to continue hangup

TTY37: restoring DTR

TTY37: autoconfigure probe started

%LINK-3-UPDOWN: Interface Async37, changed state to down

As37 LCP: State is Closed

End first debug listing ==================================================================================

Start second debug listing ==================================================================================

CSM_ANALOG_MODEM_IDLE: MODEM_STARTING_CONNECT at slot 1, port 4

Modem 1/4 Mcom: in modem state 'Dialing/Answering'

ANALOG_CONNECT_INITIATED: MODEM_ANALOG_CALL_IN at slot 1, port 4

Modem 1/4 Mcom: in modem state 'Incoming ring'

Modem 1/4 Mcom: in modem state 'Waiting for Carrier'

Modem 1/4 Mcom: in modem state 'Connected'

ANALOG_CONNECT_INITIATED: MODEM_CONNECTED at slot 1, port 4

Call Handle failed for Modem 1/4

Modem 1/4 Mcom: CONNECT at 28800/24000(Tx/Rx), V34, LAPM, V42bis, Answer

TTY37: DSR came up

Modem 1/4 Mcom: switching to PPP mode

TTY37: no timer type 1 to destroy

TTY37: no timer type 0 to destroy

TTY37: create timer type 2, 1800 seconds

TTY37: create timer type 0, 14400 seconds

tty37: Modem: IDLE->(unknown)

As37 LCP: I CONFREQ [Closed] id 1 len 23

As37 LCP: ACCM 0x00000000 (0x020600000000)

As37 LCP: MagicNumber 0x45C67C58 (0x050645C67C58)

As37 LCP: PFC (0x0702)

As37 LCP: ACFC (0x0802)

As37 LCP: Callback 6 (0x0D0306)

As37 LCP: Lower layer not up, Fast Starting

As37 PPP: Treating connection as a callin

As37 PPP: Phase is ESTABLISHING, Passive Open

As37 LCP: State is Listen

Modem 1/4 Mcom: PPP escape map: Tx map = FFFFFFFF, Rx map = 0

As37 LCP: O CONFREQ [Listen] id 73 len 25

As37 LCP: ACCM 0x000A0000 (0x0206000A0000)

As37 LCP: AuthProto CHAP (0x0305C22305)

As37 LCP: MagicNumber 0x15628EEE (0x050615628EEE)

As37 LCP: PFC (0x0702)

As37 LCP: ACFC (0x0802)

As37 LCP: O CONFACK [Listen] id 1 len 23

As37 LCP: ACCM 0x00000000 (0x020600000000)

As37 LCP: MagicNumber 0x45C67C58 (0x050645C67C58)

As37 LCP: PFC (0x0702)

As37 LCP: ACFC (0x0802)

As37 LCP: Callback 6 (0x0D0306)

%LINK-3-UPDOWN: Interface Async37, changed state to up

As37 LCP: I CONFACK [ACKsent] id 73 len 25

As37 LCP: ACCM 0x000A0000 (0x0206000A0000)

As37 LCP: AuthProto CHAP (0x0305C22305)

As37 LCP: MagicNumber 0x15628EEE (0x050615628EEE)

As37 LCP: PFC (0x0702)

As37 LCP: ACFC (0x0802)

As37 LCP: State is Open

Modem 1/4 Mcom: PPP escape map: Tx map = 0, Rx map = 0

As37 MCB: Initialize

As37 MCB: Flush

As37 PPP: Phase is AUTHENTICATING, by this end

As37 CHAP: O CHALLENGE id 30 len 24 from "bnm"

As37 LCP: I IDENTIFY [Open] id 2 len 18 magic 0x45C67C58 MSRASV5.00

As37 CHAP: I RESPONSE id 30 len 34 from ".rig.auto.nbm"

As37 PPP: Phase is FORWARDING, Attempting Forward

As37 PPP: Phase is AUTHENTICATING, Unauthenticated User

RADIUS/ENCODE(000004AA): acct_session_id: 1442

RADIUS(000004AA): sending

RADIUS: Send to unknown id 30 212.56.194.129:1645, Access-Request, len 91

RADIUS: authenticator 82 44 82 E2 53 E5 B0 70 - BD 93 B9 1D 0E 77 C8 D9

RADIUS: Framed-Protocol [7] 6 PPP [1]

RADIUS: User-Name [1] 15 ".rig.auto.nbm"

RADIUS: CHAP-Password [3] 19 *

RADIUS: NAS-Port [5] 6 37

RADIUS: NAS-Port-Type [61] 6 Async [0]

RADIUS: Calling-Station-Id [31] 7 "async"

RADIUS: Service-Type [6] 6 Framed [2]

RADIUS: NAS-IP-Address [4] 6 212.56.194.130

RADIUS: Received from id 30 212.56.194.129:1645, Access-Accept, len 136

RADIUS: authenticator 69 30 73 9E 34 A6 DF F4 - 30 35 CB 02 42 15 97 79

RADIUS: Framed-IP-Address [8] 6 192.168.5.7

RADIUS: Framed-Protocol [7] 6 PPP [1]

RADIUS: Service-Type [6] 6 Framed [2]

RADIUS: Vendor, Cisco [26] 20

RADIUS: Cisco AVpair [1] 14 "ip:inacl=106"

RADIUS: Idle-Timeout [28] 6 1800

RADIUS: Vendor, Cisco [26] 34

RADIUS: Cisco AVpair [1] 28 "ip:dns-servers=192.168.0.1"

RADIUS: Session-Timeout [27] 6 7200

RADIUS: Vendor, Cisco [26] 32

RADIUS: Cisco AVpair [1] 26 "lcp:callback-dialstring="

RADIUS: Received from id 4AA

As37 PPP/AAA: Check Attr: addr

As37 PPP/AAA: Check Attr: Framed-Protocol

As37 PPP/AAA: Check Attr: service-type

As37 PPP/AAA: Check Attr: inacl:Peruser

As37 PPP/AAA: Check Attr: idletime:Peruser

As37 PPP/AAA: Check Attr: dns-servers

As37 PPP/AAA: Check Attr: timeout:Peruser

As37 PPP/AAA: Check Attr: callback-dialstring

As37 PPP: Phase is FORWARDING, Attempting Forward

As37 PPP: Phase is AUTHENTICATING, Authenticated User

As37 AAA/AUTHOR/LCP: Process Author

As37 AAA/AUTHOR/LCP: Process Attr: idletime

AAA/AUTHOR: Processing PerUser AV idletime

As37 AAA/AUTHOR/LCP: Process Attr: timeout

AAA/AUTHOR: Processing PerUser AV timeout

As37 AAA/AUTHOR/LCP: Process Attr: callback-dialstring

As37 CHAP: O SUCCESS id 30 len 4

As37 MCB: Start

As37 MCB: User .rig.auto.nbm Callback Number - Client ANY

As37 MCB: Newstate 0-INIT -> 2-WAIT_RESPONSE

Async37 PPP: O MCB Request(1) id 46 len 9

Async37 MCB: O 1 2E 0 9 2 5 0 1 0

As37 MCB: O Request Id 46 Callback Type Client-Num delay 0

As37 PPP: Phase is CBCP

RADIUS/ENCODE(000004AA): Unsupported AAA attribute start_time

RADIUS/ENCODE(000004AA): Unsupported AAA attribute timezone

RADIUS(000004AA): sending

RADIUS: Send to unknown id 241 212.56.194.129:1646, Accounting-Request, len 135

RADIUS: authenticator 86 A2 5B 28 FB F9 2F 72 - FF 7D 48 9A 88 3E 89 8B

RADIUS: Acct-Session-Id [44] 10 "000005A2"

RADIUS: Framed-Protocol [7] 6 PPP [1]

RADIUS: Connect-Info [77] 29 "28800/24000 V34/V42bis/LAPM"

RADIUS: Authentic [45] 6 RADIUS [1]

RADIUS: User-Name [1] 15 ".rig.auto.nbm"

RADIUS: Acct-Status-Type [40] 6 Start [1]

RADIUS: NAS-Port [5] 6 37

RADIUS: NAS-Port-Type [61] 6 Async [0]

RADIUS: Calling-Station-Id [31] 7 "async"

RADIUS: Service-Type [6] 6 Framed [2]

RADIUS: NAS-IP-Address [4] 6 212.56.194.130

RADIUS: Event-Timestamp [55] 6 1025018048

RADIUS: Acct-Delay-Time [41] 6 0

RADIUS: Received from id 241 212.56.194.129:1646, Accounting-response, len 20

RADIUS: authenticator 10 44 12 F6 5A 8B 0B D7 - 0D 19 64 5A 5B E2 9D 84

As37 MCB: Timeout in state WAIT_RESPONSE sending new Request 2

Async37 PPP: O MCB Request(1) id 47 len 9

Async37 MCB: O 1 2F 0 9 2 5 0 1 0

As37 MCB: O Request Id 47 Callback Type Client-Num delay 0

As37 MCB: Timeout in state WAIT_RESPONSE sending new Request 3

Async37 PPP: O MCB Request(1) id 48 len 9

Async37 MCB: O 1 30 0 9 2 5 0 1 0

As37 MCB: O Request Id 48 Callback Type Client-Num delay 0

As37 MCB: Timeout in state WAIT_RESPONSE sending new Request 4

Async37 PPP: O MCB Request(1) id 49 len 9

Async37 MCB: O 1 31 0 9 2 5 0 1 0

As37 MCB: O Request Id 49 Callback Type Client-Num delay 0

Async37 PPP: I MCB Response(2) id 49 len 6

Async37 MCB: I 2 31 0 6 1 2

As37 MCB: Received response

As37 MCB: Response CBK-None 1 2

As37 MCB: Resp with None type, Expected Client-Num type

As37 MCB: Shutdown

As37 MCB: Flush

TTY37: Async Int reset: Dropping DTR

As37 CCP: State is Closed

As37 CCP: Compression already closed

As37 PPP: Phase is TERMINATING

As37 LCP: State is Closed

As37 PPP: Phase is DOWN

RADIUS/ENCODE(000004AA): Unsupported AAA attribute start_time

RADIUS/ENCODE(000004AA): Unsupported AAA attribute timezone

RADIUS(000004AA): sending

RADIUS: Send to unknown id 242 212.56.194.129:1646, Accounting-Request, len 186

RADIUS: authenticator A5 74 83 05 E6 CE C8 C4 - F3 4B FE 6C 0B AA 7F 84

RADIUS: Acct-Session-Id [44] 10 "000005A2"

RADIUS: Framed-Protocol [7] 6 PPP [1]

RADIUS: User-Name [1] 15 ".rig.auto.nbm"

RADIUS: Acct-Session-Time [46] 6 7

RADIUS: Connect-Info [77] 29 "28800/24000 V34/V42bis/LAPM"

RADIUS: Acct-Input-Octets [42] 6 8

RADIUS: Acct-Output-Octets [43] 6 39

RADIUS: Acct-Input-Packets [47] 6 1

RADIUS: Acct-Output-Packets [48] 6 3

RADIUS: Acct-Terminate-Cause[49] 6 lost-carrier [2]

RADIUS: Authentic [45] 6 RADIUS [1]

RADIUS: User-Name [1] 15 ".rig.auto.nbm"

RADIUS: Acct-Status-Type [40] 6 Stop [2]

RADIUS: NAS-Port [5] 6 37

RADIUS: NAS-Port-Type [61] 6 Async [0]

RADIUS: Calling-Station-Id [31] 7 "async"

RADIUS: Service-Type [6] 6 Framed [2]

RADIUS: NAS-IP-Address [4] 6 212.56.194.130

RADIUS: Event-Timestamp [55] 6 1025018055

RADIUS: Acct-Delay-Time [41] 6 0

RADIUS: Received from id 242 212.56.194.129:1646, Accounting-response, len 20

RADIUS: authenticator F0 6D A9 8D F8 7E 82 78 - C7 19 A1 1D BB A6 08 FB

ANALOG_MODEM_CONNECTED: MODEM_DISCONNECTED at slot 1, port 4

Modem 1/4 CSM: CSM_MODEM_DEALLOCATE: modem is deallocated

Modem 1/4 Mcom: in modem state 'Disconnecting'

Modem 1/4 Mcom: DISCONNECT, duration = 00:00:11, reason (0x9) DTR Drop

TTY37: DSR was dropped

tty37: Modem: READY->(unknown)

Modem 1/4 Mcom: in modem state 'Idle'

%LINK-5-CHANGED: Interface Async37, changed state to reset

As37 LCP: State is Closed

TTY37: dropping DTR, hanging up

tty37: Modem: HANGUP->(unknown)

Modem 1/4 Mcom: switching to character mode

TTY37: destroy timer type 0

TTY37: no timer type 1 to destroy

TTY37: no timer type 3 to destroy

TTY37: no timer type 4 to destroy

TTY37: destroy timer type 2

Async37: allowing modem_process to continue hangup

%LINK-3-UPDOWN: Interface Async37, changed state to down

As37 LCP: State is Closed

TTY37: restoring DTR

TTY37: autoconfigure probe started

End second debug listing ==================================================================================

tepatel · ‎06-25-2002

IOS will always create the "chat-script modem-callback" when callbcak is initiated by the callback server to communicate between ios and modem.

Now if you do not want to get called back (by clicking cancel or escape) and wants to login straight in, use "ppp callback permit" under the "interface group-async 1"...It will allow callback if you want to..Thanks..Tejal

vcoscodan · ‎06-25-2002

Thanks, now it is work fine.