Hi Guys,
I need some help from the comunity ...
I have a Cisco 3725 IOS 12.2.(3)with ISDN interfaces working as a RAS server. The main config is authenticate remote users against a Win2003 Radius Server. The clients are Windows 2000 & XP dialers. All works properly.
I wont to include callback funcionality. I'm trying to test it configuring the callback attributes to the radius user and at the router.
All seems to work until the callback must start. If the client dialer is configured with LCP the router will wait NCP packets. If the LCP is not selected the callback cannot negociate.
Any idea?
Following a debug dump:
*Mar 2 14:27:58.018: ISDN BR1/7 EVENT: service_queue_from_physical_layer: Recvd
L1 prim 7 state is 3
*Mar 2 14:27:58.078: ISDN BR1/7 EVENT: service_queue_from_physical_layer: Recvd
L1 prim 1 state is 3
*Mar 2 14:27:58.078: ISDN BR1/7 EVENT: isdn_sw_cstate: State = 4, Old State = 4
*Mar 2 14:27:58.122: %ISDN-6-LAYER2UP: Layer 2 for Interface BR1/7, TEI 109 cha
nged to up
*Mar 2 14:27:58.166: ISDN BR1/7 EVENT: process_rxstate: ces/callid 1/0x2A callt
ype 2 HOST_INCOMING_CALL
*Mar 2 14:27:58.166: ISDN BR1/7 EVENT: UserIdle: callid 0x2A received ACCEPT_CA
LL (0x13)
*Mar 2 14:27:58.198: ISDN BR1/7 EVENT: process_modem_command: received event VO
ICE_ANS on callid 0x2A and bchan 0 ces 1 cause 16 switch type 1
*Mar 2 14:27:58.394: ISDN BR1/7 EVENT: process_rxstate: ces/callid 1/0x2A callt
ype 2 HOST_CONNECT
*Mar 2 14:28:04.394: %ISDN-6-CONNECT: Interface BRI1/7:1 is now connected to un
known unknown
*Mar 2 14:28:13.046: As70 EVT: Packet 0 0x620E206C
*Mar 2 14:28:13.046: As70 LCP: I CONFREQ [Closed] id 1 len 20
*Mar 2 14:28:13.046: As70 LCP: ACCM 0x00000000 (0x020600000000)
*Mar 2 14:28:13.046: As70 LCP: MagicNumber 0x06DB3A40 (0x050606DB3A40)
*Mar 2 14:28:13.046: As70 LCP: PFC (0x0702)
*Mar 2 14:28:13.046: As70 LCP: ACFC (0x0802)
*Mar 2 14:28:13.050: As70 LCP: Lower layer not up, Fast Starting
*Mar 2 14:28:13.050: As70 PPP: Using dialer call direction
*Mar 2 14:28:13.050: As70 PPP: Treating connection as a callin
*Mar 2 14:28:13.050: As70 PPP: Phase is ESTABLISHING, Passive Open
*Mar 2 14:28:13.050: As70 LCP: State is Listen
*Mar 2 14:28:13.050: As70 PPP: Authorization required
*Mar 2 14:28:13.050: As70 LCP: O CONFREQ [Listen] id 1 len 24
*Mar 2 14:28:13.050: As70 LCP: ACCM 0x000A0000 (0x0206000A0000)
*Mar 2 14:28:13.050: As70 LCP: AuthProto PAP (0x0304C023)
*Mar 2 14:28:13.050: As70 LCP: MagicNumber 0x1B1A6AC4 (0x05061B1A6AC4)
*Mar 2 14:28:13.050: As70 LCP: PFC (0x0702)
*Mar 2 14:28:13.050: As70 LCP: ACFC (0x0802)
*Mar 2 14:28:13.050: As70 LCP: O CONFACK [Listen] id 1 len 20
*Mar 2 14:28:13.050: As70 LCP: ACCM 0x00000000 (0x020600000000)
*Mar 2 14:28:13.050: As70 LCP: MagicNumber 0x06DB3A40 (0x050606DB3A40)
*Mar 2 14:28:13.050: As70 LCP: PFC (0x0702)
*Mar 2 14:28:13.050: As70 LCP: ACFC (0x0802)
*Mar 2 14:28:13.054: %LINK-3-UPDOWN: Interface Async70, changed state to up
*Mar 2 14:28:15.058: As70 LCP: TIMEout: State ACKsent
*Mar 2 14:28:15.058: As70 LCP: O CONFREQ [ACKsent] id 2 len 24
*Mar 2 14:28:15.058: As70 LCP: ACCM 0x000A0000 (0x0206000A0000)
*Mar 2 14:28:15.058: As70 LCP: AuthProto PAP (0x0304C023)
*Mar 2 14:28:15.058: As70 LCP: MagicNumber 0x1B1A6AC4 (0x05061B1A6AC4)
*Mar 2 14:28:15.058: As70 LCP: PFC (0x0702)
*Mar 2 14:28:15.058: As70 LCP: ACFC (0x0802)
*Mar 2 14:28:15.234: As70 EVT: Packet 0 0x620E2368
*Mar 2 14:28:15.234: As70 LCP: I CONFACK [ACKsent] id 2 len 24
*Mar 2 14:28:15.234: As70 LCP: ACCM 0x000A0000 (0x0206000A0000)
*Mar 2 14:28:15.234: As70 LCP: AuthProto PAP (0x0304C023)
*Mar 2 14:28:15.234: As70 LCP: MagicNumber 0x1B1A6AC4 (0x05061B1A6AC4)
*Mar 2 14:28:15.234: As70 LCP: PFC (0x0702)
*Mar 2 14:28:15.234: As70 LCP: ACFC (0x0802)
*Mar 2 14:28:15.234: As70 LCP: State is Open
*Mar 2 14:28:15.234: As70 PPP: Phase is AUTHENTICATING, by this end
*Mar 2 14:28:16.094: As70 EVT: Packet 0 0x624B7F70
*Mar 2 14:28:16.094: As70 LCP: I CONFREQ [Open] id 2 len 20
*Mar 2 14:28:16.094: As70 LCP: ACCM 0x00000000 (0x020600000000)
*Mar 2 14:28:16.094: As70 LCP: MagicNumber 0x06DB3A40 (0x050606DB3A40)
*Mar 2 14:28:16.094: As70 LCP: PFC (0x0702)
*Mar 2 14:28:16.094: As70 LCP: ACFC (0x0802)
*Mar 2 14:28:16.094: As70 PPP: Sending Acct Event[Reneg] id[18]
*Mar 2 14:28:16.094: As70 PPP: Phase is TERMINATING
*Mar 2 14:28:16.094: As70 PPP: Authorization required
*Mar 2 14:28:16.094: As70 PPP: Phase is ESTABLISHING
*Mar 2 14:28:16.094: As70 LCP: O CONFREQ [Open] id 3 len 24
*Mar 2 14:28:16.094: As70 LCP: ACCM 0x000A0000 (0x0206000A0000)
*Mar 2 14:28:16.094: As70 LCP: AuthProto PAP (0x0304C023)
*Mar 2 14:28:16.094: As70 LCP: MagicNumber 0x1B1A76A9 (0x05061B1A76A9)
*Mar 2 14:28:16.094: As70 LCP: PFC (0x0702)
*Mar 2 14:28:16.094: As70 LCP: ACFC (0x0802)
*Mar 2 14:28:16.094: As70 LCP: O CONFACK [Open] id 2 len 20
*Mar 2 14:28:16.094: As70 LCP: ACCM 0x00000000 (0x020600000000)
*Mar 2 14:28:16.094: As70 LCP: MagicNumber 0x06DB3A40 (0x050606DB3A40)
*Mar 2 14:28:16.094: As70 LCP: PFC (0x0702)
*Mar 2 14:28:16.094: As70 LCP: ACFC (0x0802)
*Mar 2 14:28:16.282: As70 EVT: Packet 0 0x624B6C6C
*Mar 2 14:28:16.282: As70 LCP: I CONFACK [ACKsent] id 3 len 24
*Mar 2 14:28:16.282: As70 LCP: ACCM 0x000A0000 (0x0206000A0000)
*Mar 2 14:28:16.282: As70 LCP: AuthProto PAP (0x0304C023)
*Mar 2 14:28:16.282: As70 LCP: MagicNumber 0x1B1A76A9 (0x05061B1A76A9)
*Mar 2 14:28:16.282: As70 LCP: PFC (0x0702)
*Mar 2 14:28:16.282: As70 LCP: ACFC (0x0802)
*Mar 2 14:28:16.282: As70 LCP: State is Open
*Mar 2 14:28:16.282: As70 PPP: Phase is AUTHENTICATING, by this end
*Mar 2 14:28:16.286: As70 EVT: Auth Packet 0 0x624B6970
*Mar 2 14:28:16.286: As70 PAP: I AUTH-REQ id 17 len 31 from "Administrator"
*Mar 2 14:28:16.286: As70 PAP: Authenticating peer Administrator
*Mar 2 14:28:16.286: As70 PPP: Phase is FORWARDING, Attempting Forward
*Mar 2 14:28:16.286: As70 EVT: Hook 1 0x00000000
*Mar 2 14:28:16.286: As70 EVT: Forwarded 0 0x00000000
*Mar 2 14:28:16.286: As70 PPP: Phase is AUTHENTICATING, Unauthenticated User
*Mar 2 14:28:16.286: As70 PPP: Sent PAP LOGIN Request
*Mar 2 14:28:16.286: RADIUS/ENCODE(00000018):Orig. component type = ISDN
*Mar 2 14:28:16.286: RADIUS: AAA Unsupported Attr: interface [153] 7
*Mar 2 14:28:16.286: RADIUS: 41 73 79 6E 63
[Async]
*Mar 2 14:28:16.286: RADIUS(00000018): Storing nasport 70 in rad_db
*Mar 2 14:28:16.286: RADIUS/ENCODE(00000018): Unsupported AAA attribute parent-
interface
*Mar 2 14:28:16.286: RADIUS/ENCODE(00000018): Unsupported AAA attribute parent-
interface-type
*Mar 2 14:28:16.286: RADIUS(00000018): Config NAS IP: 0.0.0.0
*Mar 2 14:28:16.286: RADIUS/ENCODE(00000018): acct_session_id: 24
*Mar 2 14:28:16.286: RADIUS(00000018): sending
*Mar 2 14:28:16.286: RADIUS/ENCODE: Best Local IP-Address 10.40.27.254 for Radi
us-Server 10.40.2.214
*Mar 2 14:28:16.286: RADIUS(00000018): Send Access-Request to 10.40.2.214:1645
id 1645/17, len 127
*Mar 2 14:28:16.286: RADIUS: authenticator D2 BC 0C D5 52 2B 6C BC - 98 95 5B
32 7A 47 78 0D
*Mar 2 14:28:16.286: RADIUS: Framed-Protocol [7] 6 PPP
[1]
*Mar 2 14:28:16.290: RADIUS: User-Name [1] 15 "Administrator"
*Mar 2 14:28:16.290: RADIUS: User-Password [2] 18 *
*Mar 2 14:28:16.290: RADIUS: Called-Station-Id [30] 11 "91111111"
*Mar 2 14:28:16.290: RADIUS: NAS-Port-Type [61] 6 Async
[0]
*Mar 2 14:28:16.290: RADIUS: Calling-Station-Id [31] 7 "async"
*Mar 2 14:28:16.290: RADIUS: Connect-Info [77] 26 "52000/28800 V90/V4
4/LAPM"
*Mar 2 14:28:16.290: RADIUS: NAS-Port [5] 6 70
*Mar 2 14:28:16.290: RADIUS: Service-Type [6] 6 Framed
[2]
*Mar 2 14:28:16.290: RADIUS: NAS-IP-Address [4] 6 10.40.27.254
*Mar 2 14:28:16.450: RADIUS: Received from id 1645/17 10.40.2.214:1645, Access-
Accept, len 130
*Mar 2 14:28:16.450: RADIUS: authenticator 80 59 F7 41 DC 2F 14 C8 - 77 B7 0D
70 38 24 17 9F
*Mar 2 14:28:16.450: RADIUS: Service-Type [6] 6 Callback Framed
[4]
*Mar 2 14:28:16.450: RADIUS: Class [25] 32
*Mar 2 14:28:16.450: RADIUS: 4C 1F 05 1B 00 00 01 37 00 01 C0 A8 01 66 01 C5
[L??????7?????f??]
*Mar 2 14:28:16.450: RADIUS: 57 2B 24 DF 47 70 00 00 00 00 00 00 00 10
[W+$?Gp????????]
*Mar 2 14:28:16.450: RADIUS: Vendor, Cisco [26] 41
*Mar 2 14:28:16.450: RADIUS: Cisco AVpair [1] 35 "lcp:callback-dials
tring=913024556"
*Mar 2 14:28:16.450: RADIUS: Vendor, Cisco [26] 31
*Mar 2 14:28:16.450: RADIUS: Cisco AVpair [1] 25 "lcp:nocallback-ver
ify=1"
*Mar 2 14:28:16.450: RADIUS(00000018): Received from id 1645/17
*Mar 2 14:28:16.450: As70 EVT: AAA Response 0 0x621BA548
*Mar 2 14:28:16.450: As70 PPP: Received LOGIN Response PASS
*Mar 2 14:28:16.450: As70 PPP: Phase is FORWARDING, Attempting Forward
*Mar 2 14:28:16.454: As70 EVT: Hook 1 0x00000000
*Mar 2 14:28:16.454: As70 EVT: Forwarded 0 0x00000000
*Mar 2 14:28:16.454: As70 PPP: Phase is AUTHENTICATING, Authenticated User
*Mar 2 14:28:16.454: As70 PAP: O AUTH-ACK id 17 len 5
*Mar 2 14:28:16.454: As70 PPP: Callback user did not negotiate LCP Callback
*Mar 2 14:28:16.454: As70 EVT: Soft Disc 0 0x00000000
*Mar 2 14:28:16.454: As70 PPP: Sending Acct Event[Down] id[18]
*Mar 2 14:28:16.454: As70 PPP: Phase is TERMINATING
*Mar 2 14:28:16.454: As70 LCP: O TERMREQ [Open] id 4 len 4
*Mar 2 14:28:16.746: ISDN BR1/7 EVENT: process_modem_command: received event VO
ICE_HANGUP on callid 0x2A and bchan 0 ces 1 cause 16 switch type 1
*Mar 2 14:28:16.746: %ISDN-6-DISCONNECT: Interface BRI1/7:1 disconnected from
unknown , call lasted 18 seconds
*Mar 2 14:28:16.842: ISDN BR1/7 EVENT: process_rxstate: ces/callid 1/0x2A callt
ype 2 HOST_DISCONNECT_ACK
*Mar 2 14:28:16.842: ISDN BR1/7 **ERROR**: host_disconnect_ack: VOICE ERROR: NU
LL VDEV Common(0xFC): b channel 0, call id 0x2A
*Mar 2 14:28:18.450: As70 LCP: TIMEout: State TERMsent
*Mar 2 14:28:18.450: As70 LCP: O TERMREQ [TERMsent] id 5 len 4
*Mar 2 14:28:20.074: %LINK-5-CHANGED: Interface Async70, changed state to reset
*Mar 2 14:28:20.074: As70 EVT: Cstate 5 0x4D00001F
*Mar 2 14:28:20.074: As70 LCP: State is Closed
*Mar 2 14:28:20.074: As70 PPP: Phase is DOWN
*Mar 2 14:28:25.074: %LINK-3-UPDOWN: Interface Async70, changed state to down
*Mar 2 14:28:25.074: As70 EVT: Cstate 0 0x69000048
*Mar 2 14:28:31.974: %ISDN-6-LAYER2DOWN: Layer 2 for Interface BR1/7, TEI 109 c
hanged to down
*Mar 2 14:29:02.658: ISDN BR1/7 EVENT: isdn_sw_cstate: State = 4, Old State = 4
*Mar 2 14:29:02.658: ISDN BR1/7 EVENT: service_queue_from_physical_layer: Recvd
L1 prim 3 state is 1
*Mar 2 14:29:02.658: ISDN BR1/7 EVENT: isdn_sw_cstate: State = 0, Old State = 4
I need some help from the comunity ...
I have a Cisco 3725 IOS 12.2.(3)with ISDN interfaces working as a RAS server. The main config is authenticate remote users against a Win2003 Radius Server. The clients are Windows 2000 & XP dialers. All works properly.
I wont to include callback funcionality. I'm trying to test it configuring the callback attributes to the radius user and at the router.
All seems to work until the callback must start. If the client dialer is configured with LCP the router will wait NCP packets. If the LCP is not selected the callback cannot negociate.
Any idea?
Following a debug dump:
*Mar 2 14:27:58.018: ISDN BR1/7 EVENT: service_queue_from_physical_layer: Recvd
L1 prim 7 state is 3
*Mar 2 14:27:58.078: ISDN BR1/7 EVENT: service_queue_from_physical_layer: Recvd
L1 prim 1 state is 3
*Mar 2 14:27:58.078: ISDN BR1/7 EVENT: isdn_sw_cstate: State = 4, Old State = 4
*Mar 2 14:27:58.122: %ISDN-6-LAYER2UP: Layer 2 for Interface BR1/7, TEI 109 cha
nged to up
*Mar 2 14:27:58.166: ISDN BR1/7 EVENT: process_rxstate: ces/callid 1/0x2A callt
ype 2 HOST_INCOMING_CALL
*Mar 2 14:27:58.166: ISDN BR1/7 EVENT: UserIdle: callid 0x2A received ACCEPT_CA
LL (0x13)
*Mar 2 14:27:58.198: ISDN BR1/7 EVENT: process_modem_command: received event VO
ICE_ANS on callid 0x2A and bchan 0 ces 1 cause 16 switch type 1
*Mar 2 14:27:58.394: ISDN BR1/7 EVENT: process_rxstate: ces/callid 1/0x2A callt
ype 2 HOST_CONNECT
*Mar 2 14:28:04.394: %ISDN-6-CONNECT: Interface BRI1/7:1 is now connected to un
known unknown
*Mar 2 14:28:13.046: As70 EVT: Packet 0 0x620E206C
*Mar 2 14:28:13.046: As70 LCP: I CONFREQ [Closed] id 1 len 20
*Mar 2 14:28:13.046: As70 LCP: ACCM 0x00000000 (0x020600000000)
*Mar 2 14:28:13.046: As70 LCP: MagicNumber 0x06DB3A40 (0x050606DB3A40)
*Mar 2 14:28:13.046: As70 LCP: PFC (0x0702)
*Mar 2 14:28:13.046: As70 LCP: ACFC (0x0802)
*Mar 2 14:28:13.050: As70 LCP: Lower layer not up, Fast Starting
*Mar 2 14:28:13.050: As70 PPP: Using dialer call direction
*Mar 2 14:28:13.050: As70 PPP: Treating connection as a callin
*Mar 2 14:28:13.050: As70 PPP: Phase is ESTABLISHING, Passive Open
*Mar 2 14:28:13.050: As70 LCP: State is Listen
*Mar 2 14:28:13.050: As70 PPP: Authorization required
*Mar 2 14:28:13.050: As70 LCP: O CONFREQ [Listen] id 1 len 24
*Mar 2 14:28:13.050: As70 LCP: ACCM 0x000A0000 (0x0206000A0000)
*Mar 2 14:28:13.050: As70 LCP: AuthProto PAP (0x0304C023)
*Mar 2 14:28:13.050: As70 LCP: MagicNumber 0x1B1A6AC4 (0x05061B1A6AC4)
*Mar 2 14:28:13.050: As70 LCP: PFC (0x0702)
*Mar 2 14:28:13.050: As70 LCP: ACFC (0x0802)
*Mar 2 14:28:13.050: As70 LCP: O CONFACK [Listen] id 1 len 20
*Mar 2 14:28:13.050: As70 LCP: ACCM 0x00000000 (0x020600000000)
*Mar 2 14:28:13.050: As70 LCP: MagicNumber 0x06DB3A40 (0x050606DB3A40)
*Mar 2 14:28:13.050: As70 LCP: PFC (0x0702)
*Mar 2 14:28:13.050: As70 LCP: ACFC (0x0802)
*Mar 2 14:28:13.054: %LINK-3-UPDOWN: Interface Async70, changed state to up
*Mar 2 14:28:15.058: As70 LCP: TIMEout: State ACKsent
*Mar 2 14:28:15.058: As70 LCP: O CONFREQ [ACKsent] id 2 len 24
*Mar 2 14:28:15.058: As70 LCP: ACCM 0x000A0000 (0x0206000A0000)
*Mar 2 14:28:15.058: As70 LCP: AuthProto PAP (0x0304C023)
*Mar 2 14:28:15.058: As70 LCP: MagicNumber 0x1B1A6AC4 (0x05061B1A6AC4)
*Mar 2 14:28:15.058: As70 LCP: PFC (0x0702)
*Mar 2 14:28:15.058: As70 LCP: ACFC (0x0802)
*Mar 2 14:28:15.234: As70 EVT: Packet 0 0x620E2368
*Mar 2 14:28:15.234: As70 LCP: I CONFACK [ACKsent] id 2 len 24
*Mar 2 14:28:15.234: As70 LCP: ACCM 0x000A0000 (0x0206000A0000)
*Mar 2 14:28:15.234: As70 LCP: AuthProto PAP (0x0304C023)
*Mar 2 14:28:15.234: As70 LCP: MagicNumber 0x1B1A6AC4 (0x05061B1A6AC4)
*Mar 2 14:28:15.234: As70 LCP: PFC (0x0702)
*Mar 2 14:28:15.234: As70 LCP: ACFC (0x0802)
*Mar 2 14:28:15.234: As70 LCP: State is Open
*Mar 2 14:28:15.234: As70 PPP: Phase is AUTHENTICATING, by this end
*Mar 2 14:28:16.094: As70 EVT: Packet 0 0x624B7F70
*Mar 2 14:28:16.094: As70 LCP: I CONFREQ [Open] id 2 len 20
*Mar 2 14:28:16.094: As70 LCP: ACCM 0x00000000 (0x020600000000)
*Mar 2 14:28:16.094: As70 LCP: MagicNumber 0x06DB3A40 (0x050606DB3A40)
*Mar 2 14:28:16.094: As70 LCP: PFC (0x0702)
*Mar 2 14:28:16.094: As70 LCP: ACFC (0x0802)
*Mar 2 14:28:16.094: As70 PPP: Sending Acct Event[Reneg] id[18]
*Mar 2 14:28:16.094: As70 PPP: Phase is TERMINATING
*Mar 2 14:28:16.094: As70 PPP: Authorization required
*Mar 2 14:28:16.094: As70 PPP: Phase is ESTABLISHING
*Mar 2 14:28:16.094: As70 LCP: O CONFREQ [Open] id 3 len 24
*Mar 2 14:28:16.094: As70 LCP: ACCM 0x000A0000 (0x0206000A0000)
*Mar 2 14:28:16.094: As70 LCP: AuthProto PAP (0x0304C023)
*Mar 2 14:28:16.094: As70 LCP: MagicNumber 0x1B1A76A9 (0x05061B1A76A9)
*Mar 2 14:28:16.094: As70 LCP: PFC (0x0702)
*Mar 2 14:28:16.094: As70 LCP: ACFC (0x0802)
*Mar 2 14:28:16.094: As70 LCP: O CONFACK [Open] id 2 len 20
*Mar 2 14:28:16.094: As70 LCP: ACCM 0x00000000 (0x020600000000)
*Mar 2 14:28:16.094: As70 LCP: MagicNumber 0x06DB3A40 (0x050606DB3A40)
*Mar 2 14:28:16.094: As70 LCP: PFC (0x0702)
*Mar 2 14:28:16.094: As70 LCP: ACFC (0x0802)
*Mar 2 14:28:16.282: As70 EVT: Packet 0 0x624B6C6C
*Mar 2 14:28:16.282: As70 LCP: I CONFACK [ACKsent] id 3 len 24
*Mar 2 14:28:16.282: As70 LCP: ACCM 0x000A0000 (0x0206000A0000)
*Mar 2 14:28:16.282: As70 LCP: AuthProto PAP (0x0304C023)
*Mar 2 14:28:16.282: As70 LCP: MagicNumber 0x1B1A76A9 (0x05061B1A76A9)
*Mar 2 14:28:16.282: As70 LCP: PFC (0x0702)
*Mar 2 14:28:16.282: As70 LCP: ACFC (0x0802)
*Mar 2 14:28:16.282: As70 LCP: State is Open
*Mar 2 14:28:16.282: As70 PPP: Phase is AUTHENTICATING, by this end
*Mar 2 14:28:16.286: As70 EVT: Auth Packet 0 0x624B6970
*Mar 2 14:28:16.286: As70 PAP: I AUTH-REQ id 17 len 31 from "Administrator"
*Mar 2 14:28:16.286: As70 PAP: Authenticating peer Administrator
*Mar 2 14:28:16.286: As70 PPP: Phase is FORWARDING, Attempting Forward
*Mar 2 14:28:16.286: As70 EVT: Hook 1 0x00000000
*Mar 2 14:28:16.286: As70 EVT: Forwarded 0 0x00000000
*Mar 2 14:28:16.286: As70 PPP: Phase is AUTHENTICATING, Unauthenticated User
*Mar 2 14:28:16.286: As70 PPP: Sent PAP LOGIN Request
*Mar 2 14:28:16.286: RADIUS/ENCODE(00000018):Orig. component type = ISDN
*Mar 2 14:28:16.286: RADIUS: AAA Unsupported Attr: interface [153] 7
*Mar 2 14:28:16.286: RADIUS: 41 73 79 6E 63
[Async]
*Mar 2 14:28:16.286: RADIUS(00000018): Storing nasport 70 in rad_db
*Mar 2 14:28:16.286: RADIUS/ENCODE(00000018): Unsupported AAA attribute parent-
interface
*Mar 2 14:28:16.286: RADIUS/ENCODE(00000018): Unsupported AAA attribute parent-
interface-type
*Mar 2 14:28:16.286: RADIUS(00000018): Config NAS IP: 0.0.0.0
*Mar 2 14:28:16.286: RADIUS/ENCODE(00000018): acct_session_id: 24
*Mar 2 14:28:16.286: RADIUS(00000018): sending
*Mar 2 14:28:16.286: RADIUS/ENCODE: Best Local IP-Address 10.40.27.254 for Radi
us-Server 10.40.2.214
*Mar 2 14:28:16.286: RADIUS(00000018): Send Access-Request to 10.40.2.214:1645
id 1645/17, len 127
*Mar 2 14:28:16.286: RADIUS: authenticator D2 BC 0C D5 52 2B 6C BC - 98 95 5B
32 7A 47 78 0D
*Mar 2 14:28:16.286: RADIUS: Framed-Protocol [7] 6 PPP
[1]
*Mar 2 14:28:16.290: RADIUS: User-Name [1] 15 "Administrator"
*Mar 2 14:28:16.290: RADIUS: User-Password [2] 18 *
*Mar 2 14:28:16.290: RADIUS: Called-Station-Id [30] 11 "91111111"
*Mar 2 14:28:16.290: RADIUS: NAS-Port-Type [61] 6 Async
[0]
*Mar 2 14:28:16.290: RADIUS: Calling-Station-Id [31] 7 "async"
*Mar 2 14:28:16.290: RADIUS: Connect-Info [77] 26 "52000/28800 V90/V4
4/LAPM"
*Mar 2 14:28:16.290: RADIUS: NAS-Port [5] 6 70
*Mar 2 14:28:16.290: RADIUS: Service-Type [6] 6 Framed
[2]
*Mar 2 14:28:16.290: RADIUS: NAS-IP-Address [4] 6 10.40.27.254
*Mar 2 14:28:16.450: RADIUS: Received from id 1645/17 10.40.2.214:1645, Access-
Accept, len 130
*Mar 2 14:28:16.450: RADIUS: authenticator 80 59 F7 41 DC 2F 14 C8 - 77 B7 0D
70 38 24 17 9F
*Mar 2 14:28:16.450: RADIUS: Service-Type [6] 6 Callback Framed
[4]
*Mar 2 14:28:16.450: RADIUS: Class [25] 32
*Mar 2 14:28:16.450: RADIUS: 4C 1F 05 1B 00 00 01 37 00 01 C0 A8 01 66 01 C5
[L??????7?????f??]
*Mar 2 14:28:16.450: RADIUS: 57 2B 24 DF 47 70 00 00 00 00 00 00 00 10
[W+$?Gp????????]
*Mar 2 14:28:16.450: RADIUS: Vendor, Cisco [26] 41
*Mar 2 14:28:16.450: RADIUS: Cisco AVpair [1] 35 "lcp:callback-dials
tring=913024556"
*Mar 2 14:28:16.450: RADIUS: Vendor, Cisco [26] 31
*Mar 2 14:28:16.450: RADIUS: Cisco AVpair [1] 25 "lcp:nocallback-ver
ify=1"
*Mar 2 14:28:16.450: RADIUS(00000018): Received from id 1645/17
*Mar 2 14:28:16.450: As70 EVT: AAA Response 0 0x621BA548
*Mar 2 14:28:16.450: As70 PPP: Received LOGIN Response PASS
*Mar 2 14:28:16.450: As70 PPP: Phase is FORWARDING, Attempting Forward
*Mar 2 14:28:16.454: As70 EVT: Hook 1 0x00000000
*Mar 2 14:28:16.454: As70 EVT: Forwarded 0 0x00000000
*Mar 2 14:28:16.454: As70 PPP: Phase is AUTHENTICATING, Authenticated User
*Mar 2 14:28:16.454: As70 PAP: O AUTH-ACK id 17 len 5
*Mar 2 14:28:16.454: As70 PPP: Callback user did not negotiate LCP Callback
*Mar 2 14:28:16.454: As70 EVT: Soft Disc 0 0x00000000
*Mar 2 14:28:16.454: As70 PPP: Sending Acct Event[Down] id[18]
*Mar 2 14:28:16.454: As70 PPP: Phase is TERMINATING
*Mar 2 14:28:16.454: As70 LCP: O TERMREQ [Open] id 4 len 4
*Mar 2 14:28:16.746: ISDN BR1/7 EVENT: process_modem_command: received event VO
ICE_HANGUP on callid 0x2A and bchan 0 ces 1 cause 16 switch type 1
*Mar 2 14:28:16.746: %ISDN-6-DISCONNECT: Interface BRI1/7:1 disconnected from
unknown , call lasted 18 seconds
*Mar 2 14:28:16.842: ISDN BR1/7 EVENT: process_rxstate: ces/callid 1/0x2A callt
ype 2 HOST_DISCONNECT_ACK
*Mar 2 14:28:16.842: ISDN BR1/7 **ERROR**: host_disconnect_ack: VOICE ERROR: NU
LL VDEV Common(0xFC): b channel 0, call id 0x2A
*Mar 2 14:28:18.450: As70 LCP: TIMEout: State TERMsent
*Mar 2 14:28:18.450: As70 LCP: O TERMREQ [TERMsent] id 5 len 4
*Mar 2 14:28:20.074: %LINK-5-CHANGED: Interface Async70, changed state to reset
*Mar 2 14:28:20.074: As70 EVT: Cstate 5 0x4D00001F
*Mar 2 14:28:20.074: As70 LCP: State is Closed
*Mar 2 14:28:20.074: As70 PPP: Phase is DOWN
*Mar 2 14:28:25.074: %LINK-3-UPDOWN: Interface Async70, changed state to down
*Mar 2 14:28:25.074: As70 EVT: Cstate 0 0x69000048
*Mar 2 14:28:31.974: %ISDN-6-LAYER2DOWN: Layer 2 for Interface BR1/7, TEI 109 c
hanged to down
*Mar 2 14:29:02.658: ISDN BR1/7 EVENT: isdn_sw_cstate: State = 4, Old State = 4
*Mar 2 14:29:02.658: ISDN BR1/7 EVENT: service_queue_from_physical_layer: Recvd
L1 prim 3 state is 1
*Mar 2 14:29:02.658: ISDN BR1/7 EVENT: isdn_sw_cstate: State = 0, Old State = 4