[afnog] Cisco AS5350 and calls from PABX number
Madhvi Gokool
madhvin at yahoo.com
Thu Oct 13 15:32:24 EAT 2005
Hello
A Cisco AS5350 has been configured to replace an old TCM dialup server. We
are encountering the following problem :-
1. users dialling from analogue lines are able to establish connection.
2. Users dialling via a PABX network are not able to connect - Error message
obtained is "Error 734: PPP Link Control Protocol was terminated" On the
RADIUS server, the user was successfully authenticated.
I am including the AS config and debug output as additional info. I'll be
grateful for any help in identifying the root of this problem.
Please note that with the TCM, the same users are able to connect.
The Cisco router config is as follows :-
rtras# sh run
Building configuration...
Current configuration : 3449 bytes
!
! Last configuration change at 16:05:19 UTC Wed Aug 10 2005 by cisco
!
version 12.3
service timestamps debug datetime msec
service timestamps log datetime msec
service password-encryption
!
hostname rtras
!
boot-start-marker
no boot startup-test
boot-end-marker
!
logging buffered informational
!
resource-pool disable
spe default-firmware spe-firmware-1
aaa new-model
!
!
aaa authentication login default local
aaa authentication ppp default group radius
aaa authorization network default group radius if-authenticated
aaa accounting delay-start
aaa accounting network default start-stop group radius
aaa session-id unique
ip subnet-zero
no ip rcmd domain-lookup
ip rcmd rsh-enable
ip rcmd remote-host cisco 10.10.20.51 cisco enable
ip rcmd remote-host cisco 10.1.1.51 cisco enable
!
!
ip cef
ip name-server 10.10.20.51
ip name-server 10.1.1.51
!
async-bootp dns-server 192.168.50.75
isdn switch-type primary-net5
controller E1 3/0
framing NO-CRC4
pri-group timeslots 1-31
!
controller E1 3/1
!
!
interface Loopback0
ip address 10.1.200.5 255.255.255.255
!
interface FastEthernet0/0
ip address 10.5.0.2 255.255.0.0
duplex auto
speed auto
!
interface FastEthernet0/1
no ip address
shutdown
duplex auto
speed auto
!
interface Serial0/0
no ip address
clockrate 2000000
!
interface Serial0/1
no ip address
shutdown
clockrate 2000000
!
interface Serial3/0:15
no ip address
encapsulation ppp
dialer-group 1
isdn switch-type primary-net5
isdn incoming-voice modem
no fair-queue
ppp authentication chap
ppp multilink
!
interface Group-Async0
ip unnumbered FastEthernet0/0
encapsulation ppp
dialer in-band
dialer idle-timeout 1200
dialer-group 1
async mode interactive
no peer default ip address
no fair-queue
ppp authentication chap
ppp multilink
group-range 1/00 1/59
!
interface Dialer1
no ip address
encapsulation ppp
dialer in-band
dialer idle-timeout 900
fair-queue
ppp authentication chap
ppp multilink
!
ip classless
ip route 0.0.0.0 0.0.0.0 10.5.0.1
no ip http server
!
!
logging 10.10.20.32
logging 10.10.20.51
access-list 97 permit 10.10.20.40
access-list 101 remark Interesting traffic for dialer-list 1
access-list 101 deny ospf any any
access-list 101 permit ip any any
dialer-list 1 protocol ip list 101
!
snmp-server community terrasky RO 97
snmp-server enable traps tty
!
radius-server host 10.10.20.40 auth-port 1645 acct-port 1646 key 7 11114B1D4
9
!
voice-port 3/0:D
!
!
!
!
!
line con 0
line aux 0
line 1/00 1/59
exec-timeout 0 0
absolute-timeout 240
refuse-message ^CCCCCCCCC!!! All lines are busy, try again later ###^C
modem InOut
modem autoconfigure type mica
no activation-character
transport preferred none
transport input all
transport output all
escape-character NONE
autoselect during-login
autoselect ppp
!
scheduler allocate 10000 400
Debug output
Debug info when calling fom ISDN line
rtras#
.Aug 12 08:24:08.602: ISDN Se3/0:15 Q931: RX <- SETUP pd = 8 callref =
0x57B2
Sending Complete
Bearer Capability i = 0x9090A3
Standard = CCITT
Transer Capability = 3.1kHz Audio
Transfer Mode = Circuit
Transfer Rate = 64 kbit/s
Channel ID i = 0xA98397
Exclusive, Channel 23
Progress Ind i = 0x8283 - Origination address is non-ISDN
Calling Party Number i = 0x2183, '4168353'
Plan:ISDN, Type:National
Called Party Number i = 0x81, '0101'
Plan:ISDN, Type:Unknown
.Aug 12 08:24:08.606: ISDN Se3/0:15 Q931: TX -> CALL_PROC pd = 8 callref =
0xD7
B2
Channel ID i = 0xA98397
Exclusive, Channel 23
.Aug 12 08:24:08.606: ISDN Se3/0:15 Q931: TX -> ALERTING pd = 8 callref =
0xD7B
2
.Aug 12 08:24:08.614: ISDN Se3/0:15 Q931: TX -> CONNECT pd = 8 callref =
0xD7B2
.Aug 12 08:24:09.158: ISDN Se3/0:15 Q931: RX <- CONNECT_ACK pd = 8 callref
= 0x
57B2
.Aug 12 08:24:14.830: As1/12 LCP: I CONFREQ [Closed] id 0 len 50
.Aug 12 08:24:14.830: As1/12 LCP: ACCM 0x00000000 (0x020600000000)
.Aug 12 08:24:14.830: As1/12 LCP: MagicNumber 0x4BC616D3 (0x05064BC616D3)
.Aug 12 08:24:14.830: As1/12 LCP: PFC (0x0702)
.Aug 12 08:24:14.830: As1/12 LCP: ACFC (0x0802)
.Aug 12 08:24:14.830: As1/12 LCP: Callback 6 (0x0D0306)
.Aug 12 08:24:14.830: As1/12 LCP: MRRU 1614 (0x1104064E)
.Aug 12 08:24:14.830: As1/12 LCP: EndpointDisc 1 Local
.Aug 12 08:24:14.834: As1/12 LCP: (0x131701E39ACF57CC984F80A34537B4D3)
.Aug 12 08:24:14.834: As1/12 LCP: (0x64C21300000000)
.Aug 12 08:24:14.834: As1/12 LCP: Lower layer not up, Fast Starting
.Aug 12 08:24:14.834: As1/12 PPP: Using dialer call direction
.Aug 12 08:24:14.834: As1/12 PPP: Treating connection as a callin
.Aug 12 08:24:14.834: As1/12 PPP: Phase is ESTABLISHING, Passive Open
.Aug 12 08:24:14.834: As1/12 LCP: State is Listen
.Aug 12 08:24:14.834: As1/12 PPP: Authorization required
.Aug 12 08:24:14.834: As1/12 LCP: O CONFREQ [Listen] id 10 len 37
.Aug 12 08:24:14.834: As1/12 LCP: ACCM 0x000A0000 (0x0206000A0000)
.Aug 12 08:24:14.834: As1/12 LCP: AuthProto CHAP (0x0305C22305)
.Aug 12 08:24:14.834: As1/12 LCP: MagicNumber 0xB18A5CB4 (0x0506B18A5CB4)
.Aug 12 08:24:14.834: As1/12 LCP: PFC (0x0702)
.Aug 12 08:24:14.834: As1/12 LCP: ACFC (0x0802)
.Aug 12 08:24:14.834: As1/12 LCP: MRRU 1524 (0x110405F4)
.Aug 12 08:24:14.834: As1/12 LCP: EndpointDisc 1 rtras
(0x1308017274726173)
.Aug 12 08:24:14.834: As1/12 LCP: O CONFREJ [Listen] id 0 len 7
.Aug 12 08:24:14.834: As1/12 LCP: Callback 6 (0x0D0306)
.Aug 12 08:24:14.950: As1/12 LCP: I CONFACK [REQsent] id 10 len 37
.Aug 12 08:24:14.950: As1/12 LCP: ACCM 0x000A0000 (0x0206000A0000)
.Aug 12 08:24:14.950: As1/12 LCP: AuthProto CHAP (0x0305C22305)
.Aug 12 08:24:14.950: As1/12 LCP: MagicNumber 0xB18A5CB4 (0x0506B18A5CB4)
.Aug 12 08:24:14.950: As1/12 LCP: PFC (0x0702)
.Aug 12 08:24:14.950: As1/12 LCP: ACFC (0x0802)
.Aug 12 08:24:14.950: As1/12 LCP: MRRU 1524 (0x110405F4)
.Aug 12 08:24:14.950: As1/12 LCP: EndpointDisc 1 rtras
(0x1308017274726173)
.Aug 12 08:24:14.966: As1/12 LCP: I CONFREQ [ACKrcvd] id 1 len 47
.Aug 12 08:24:14.966: As1/12 LCP: ACCM 0x00000000 (0x020600000000)
.Aug 12 08:24:14.966: As1/12 LCP: MagicNumber 0x4BC616D3 (0x05064BC616D3)
.Aug 12 08:24:14.966: As1/12 LCP: PFC (0x0702)
.Aug 12 08:24:14.966: As1/12 LCP: ACFC (0x0802)
.Aug 12 08:24:14.966: As1/12 LCP: MRRU 1614 (0x1104064E)
.Aug 12 08:24:14.966: As1/12 LCP: EndpointDisc 1 Local
.Aug 12 08:24:14.966: As1/12 LCP: (0x131701E39ACF57CC984F80A34537B4D3)
.Aug 12 08:24:14.966: As1/12 LCP: (0x64C21300000000)
.Aug 12 08:24:14.966: As1/12 LCP: O CONFACK [ACKrcvd] id 1 len 47
.Aug 12 08:24:14.966: As1/12 LCP: ACCM 0x00000000 (0x020600000000)
.Aug 12 08:24:14.966: As1/12 LCP: MagicNumber 0x4BC616D3 (0x05064BC616D3)
.Aug 12 08:24:14.966: As1/12 LCP: PFC (0x0702)
.Aug 12 08:24:14.966: As1/12 LCP: ACFC (0x0802)
.Aug 12 08:24:14.966: As1/12 LCP: MRRU 1614 (0x1104064E)
.Aug 12 08:24:14.966: As1/12 LCP: EndpointDisc 1 Local
.Aug 12 08:24:14.966: As1/12 LCP: (0x131701E39ACF57CC984F80A34537B4D3)
.Aug 12 08:24:14.966: As1/12 LCP: (0x64C21300000000)
.Aug 12 08:24:14.966: As1/12 LCP: State is Open
.Aug 12 08:24:14.966: As1/12 PPP: Phase is AUTHENTICATING, by this end
.Aug 12 08:24:14.970: As1/12 CHAP: O CHALLENGE id 4 len 26 from "rtras"
.Aug 12 08:24:15.082: As1/12 LCP: I IDENTIFY [Open] id 2 len 18 magic
0x4BC616D3
MSRASV5.00
.Aug 12 08:24:15.098: As1/12 LCP: I IDENTIFY [Open] id 3 len 24 magic
0x4BC616D3
MSRAS-1-LTOPJOHN
.Aug 12 08:24:15.106: As1/12 CHAP: I RESPONSE id 4 len 29 from "jjohnson"
.Aug 12 08:24:15.106: As1/12 PPP: Phase is FORWARDING, Attempting Forward
.Aug 12 08:24:15.110: As1/12 PPP: Phase is AUTHENTICATING, Unauthenticated
User
.Aug 12 08:24:15.110: As1/12 PPP: Sent CHAP LOGIN Request
.Aug 12 08:24:15.110: As1/12 PPP: Received LOGIN Response PASS
.Aug 12 08:24:15.110: As1/12 PPP: Phase is FORWARDING, Attempting Forward
.Aug 12 08:24:15.110: As1/12 PPP: Phase is AUTHENTICATING, Authenticated
User
.Aug 12 08:24:15.110: As1/12 CHAP: O SUCCESS id 4 len 4
.Aug 12 08:24:15.110: As1/12 PPP: Phase is VIRTUALIZED
.Aug 12 08:24:15.114: As1/12 PPP: Sending Acct Event[Down] id[125]
.Aug 12 08:24:15.114: As1/12 PPP: Phase is TERMINATING
.Aug 12 08:24:15.114: As1/12 LCP: O TERMREQ [Open] id 11 len 4
.Aug 12 08:24:15.114: Vi1 PPP: Phase is DOWN, Setup
.Aug 12 08:24:16.606: ISDN Se3/0:15 Q931: RX <- DISCONNECT pd = 8 callref =
0x5
7B1
Cause i = 0x8090 - Normal call clearing
Progress Ind i = 0x8288 - In-band info or appropriate now available
.Aug 12 08:24:16.610: ISDN Se3/0:15 Q931: TX -> RELEASE pd = 8 callref =
0xD7B1
.Aug 12 08:24:16.818: ISDN Se3/0:15 Q931: RX <- RELEASE_COMP pd = 8 callref
= 0
x57B1
.Aug 12 08:24:17.110: As1/12 LCP: TIMEout: State TERMsent
.Aug 12 08:24:17.110: As1/12 LCP: O TERMREQ [TERMsent] id 12 len 4
.Aug 12 08:24:17.622: As1/12 LCP: State is Closed
.Aug 12 08:24:17.622: As1/12 PPP: Phase is DOWN
.Aug 12 08:24:24.614: As1/11 PPP: Using dialer call direction
.Aug 12 08:24:24.614: As1/11 PPP: Treating connection as a callin
.Aug 12 08:24:24.614: As1/11 PPP: Phase is ESTABLISHING, Passive Open
.Aug 12 08:24:24.614: As1/11 LCP: State is Listen
.Aug 12 08:24:24.618: As1/11 LCP: I CONFREQ [Listen] id 0 len 50
.Aug 12 08:24:24.618: As1/11 LCP: ACCM 0x00000000 (0x020600000000)
.Aug 12 08:24:24.618: As1/11 LCP: MagicNumber 0x2D494BF7 (0x05062D494BF7)
.Aug 12 08:24:24.618: As1/11 LCP: PFC (0x0702)
.Aug 12 08:24:24.618: As1/11 LCP: ACFC (0x0802)
.Aug 12 08:24:24.618: As1/11 LCP: Callback 6 (0x0D0306)
.Aug 12 08:24:24.618: As1/11 LCP: MRRU 1614 (0x1104064E)
.Aug 12 08:24:24.618: As1/11 LCP: EndpointDisc 1 Local
.Aug 12 08:24:24.618: As1/11 LCP: (0x131701F4068D8C96314660B5C457E968)
.Aug 12 08:24:24.618: As1/11 LCP: (0x54A4B300000000)
.Aug 12 08:24:24.618: As1/11 PPP: Authorization required
.Aug 12 08:24:24.618: As1/11 LCP: O CONFREQ [Listen] id 9 len 37
.Aug 12 08:24:24.618: As1/11 LCP: ACCM 0x000A0000 (0x0206000A0000)
.Aug 12 08:24:24.618: As1/11 LCP: AuthProto CHAP (0x0305C22305)
.Aug 12 08:24:24.618: As1/11 LCP: MagicNumber 0xB18A82EE (0x0506B18A82EE)
.Aug 12 08:24:24.618: As1/11 LCP: PFC (0x0702)
.Aug 12 08:24:24.618: As1/11 LCP: ACFC (0x0802)
.Aug 12 08:24:24.618: As1/11 LCP: MRRU 1524 (0x110405F4)
.Aug 12 08:24:24.622: As1/11 LCP: EndpointDisc 1 rtras
(0x1308017274726173)
.Aug 12 08:24:24.622: As1/11 LCP: O CONFREJ [Listen] id 0 len 7
.Aug 12 08:24:24.622: As1/11 LCP: Callback 6 (0x0D0306)
.Aug 12 08:24:24.858: As1/11 LCP: I CONFACK [REQsent] id 9 len 37
.Aug 12 08:24:24.858: As1/11 LCP: ACCM 0x000A0000 (0x0206000A0000)
.Aug 12 08:24:24.858: As1/11 LCP: AuthProto CHAP (0x0305C22305)
.Aug 12 08:24:24.858: As1/11 LCP: MagicNumber 0xB18A82EE (0x0506B18A82EE)
.Aug 12 08:24:24.858: As1/11 LCP: PFC (0x0702)
.Aug 12 08:24:24.858: As1/11 LCP: ACFC (0x0802)
.Aug 12 08:24:24.862: As1/11 LCP: MRRU 1524 (0x110405F4)
.Aug 12 08:24:24.862: As1/11 LCP: EndpointDisc 1 rtras
(0x1308017274726173)
.Aug 12 08:24:24.886: As1/11 LCP: I CONFREQ [ACKrcvd] id 1 len 47
.Aug 12 08:24:24.886: As1/11 LCP: ACCM 0x00000000 (0x020600000000)
.Aug 12 08:24:24.886: As1/11 LCP: MagicNumber 0x2D494BF7 (0x05062D494BF7)
.Aug 12 08:24:24.886: As1/11 LCP: PFC (0x0702)
.Aug 12 08:24:24.886: As1/11 LCP: ACFC (0x0802)
.Aug 12 08:24:24.886: As1/11 LCP: MRRU 1614 (0x1104064E)
.Aug 12 08:24:24.886: As1/11 LCP: EndpointDisc 1 Local
.Aug 12 08:24:24.886: As1/11 LCP: (0x131701F4068D8C96314660B5C457E968)
.Aug 12 08:24:24.886: As1/11 LCP: (0x54A4B300000000)
.Aug 12 08:24:24.886: As1/11 LCP: O CONFACK [ACKrcvd] id 1 len 47
.Aug 12 08:24:24.886: As1/11 LCP: ACCM 0x00000000 (0x020600000000)
.Aug 12 08:24:24.886: As1/11 LCP: MagicNumber 0x2D494BF7 (0x05062D494BF7)
.Aug 12 08:24:24.886: As1/11 LCP: PFC (0x0702)
.Aug 12 08:24:24.886: As1/11 LCP: ACFC (0x0802)
.Aug 12 08:24:24.886: As1/11 LCP: MRRU 1614 (0x1104064E)
.Aug 12 08:24:24.886: As1/11 LCP: EndpointDisc 1 Local
.Aug 12 08:24:24.886: As1/11 LCP: (0x131701F4068D8C96314660B5C457E968)
.Aug 12 08:24:24.886: As1/11 LCP: (0x54A4B300000000)
.Aug 12 08:24:24.886: As1/11 LCP: State is Open
.Aug 12 08:24:24.886: As1/11 PPP: Phase is AUTHENTICATING, by this end
.Aug 12 08:24:24.886: As1/11 CHAP: O CHALLENGE id 4 len 26 from "rtras"
.Aug 12 08:24:25.118: As1/11 LCP: I IDENTIFY [Open] id 2 len 18 magic
0x2D494BF7
MSRASV5.00
.Aug 12 08:24:25.146: As1/11 LCP: I IDENTIFY [Open] id 3 len 25 magic
0x2D494BF7
MSRAS-1-RFD_UNIT1
.Aug 12 08:24:25.174: As1/11 CHAP: I RESPONSE id 4 len 27 from "lunite"
.Aug 12 08:24:25.174: As1/11 PPP: Phase is FORWARDING, Attempting Forward
.Aug 12 08:24:25.174: As1/11 PPP: Phase is AUTHENTICATING, Unauthenticated
User
.Aug 12 08:24:25.174: As1/11 PPP: Sent CHAP LOGIN Request
.Aug 12 08:24:25.178: As1/11 PPP: Received LOGIN Response PASS
.Aug 12 08:24:25.178: As1/11 PPP: Phase is FORWARDING, Attempting Forward
.Aug 12 08:24:25.178: As1/11 PPP: Phase is AUTHENTICATING, Authenticated
User
.Aug 12 08:24:25.178: As1/11 CHAP: O SUCCESS id 4 len 4
.Aug 12 08:24:25.178: As1/11 PPP: Phase is VIRTUALIZED
.Aug 12 08:24:25.178: As1/11 PPP: Sending Acct Event[Down] id[126]
.Aug 12 08:24:25.178: As1/11 PPP: Phase is TERMINATING
.Aug 12 08:24:25.178: As1/11 LCP: O TERMREQ [Open] id 10 len 4
.Aug 12 08:24:25.178: Vi1 PPP: Phase is DOWN, Setup
.Aug 12 08:24:26.630: ISDN Se3/0:15 Q931: RX <- DISCONNECT pd = 8 callref =
0x5
7B2
Cause i = 0x8290 - Normal call clearing
Progress Ind i = 0x8288 - In-band info or appropriate now available
.Aug 12 08:24:26.630: ISDN Se3/0:15 Q931: TX -> RELEASE pd = 8 callref =
0xD7B2
.Aug 12 08:24:26.822: ISDN Se3/0:15 Q931: RX <- RELEASE_COMP pd = 8 callref
= 0
x57B2
.Aug 12 08:24:27.190: As1/11 LCP: TIMEout: State TERMsent
.Aug 12 08:24:27.190: As1/11 LCP: O TERMREQ [TERMsent] id 11 len 4
.Aug 12 08:24:29.202: As1/11 LCP: State is Closed
.Aug 12 08:24:29.202: As1/11 PPP: Phase is DOWN
Thanx in advance
Madhvi
More information about the afnog
mailing list