Sorry I forgot to attach the file ... Untitled DocumentErnesto Freyre Ram�rez �rea de Operaciones Red Privada Virtual S.A. Av. Paseo de la Rep�blica 4675 - Lima 34 Telf.: (511) 241-4122 Anexo 2245 Fax: (511) 446-8135 Vis�tenos en: www.qnet.com.pe ----- Original Message ----- From: "Ernesto Freyre" <[EMAIL PROTECTED]> To: <[EMAIL PROTECTED]> Sent: Monday, June 28, 2004 2:38 PM Subject: Re: about the ISDN problem
> Hi Mr. DeKok the log file that lets my NAS is attached in this mail, > Please could you help me to see if here appears some thing with information > that helps to debug the problem?. I don�t have experience with NAS. > > thak you for your valuable time and help > > Regards. > > Ernesto. > > > > > Untitled DocumentErnesto Freyre Ram�rez �rea de Operaciones Red Privada > Virtual S.A. Av. Paseo de la Rep�blica 4675 - Lima 34 Telf.: (511) 241-4122 > Anexo 2245 Fax: (511) 446-8135 Vis�tenos en: www.qnet.com.pe > ----- Original Message ----- > From: "Alan DeKok" <[EMAIL PROTECTED]> > To: <[EMAIL PROTECTED]> > Sent: Monday, June 28, 2004 9:50 AM > Subject: Re: about the ISDN problem > > > > "Ernesto Freyre" <[EMAIL PROTECTED]> wrote: > > > We have tested the NAS for authenticating locally the ISDN calls , > > > so all works fine, but when we set the NAS for radius > > > authentication, the user are rejected, however when I see the log on > > > my radius, this records the session as accepted, as you can see in > > > the attached logfile. > > > > I suggest looking at the debug logs on the NAS to see why it doesn't > > like the response from FreeRADIUS. > > > > Alan DeKok. > > > > > > > > > > - > > List info/subscribe/unsubscribe? See > http://www.freeradius.org/list/users.html > > >
as5300-li03#!test con cambio de authe en virtual template! as5300-li03# Jun 28 11:48:19: ISDN Se5:15 Q931: RX <- SETUP pd = 8 callref = 0x05DC Sending Complete Bearer Capability i = 0x8890 Standard = CCITT Transer Capability = Unrestricted Digital Transfer Mode = Circuit Transfer Rate = 64 kbit/s Channel ID i = 0xA18392 Preferred, Channel 18 Calling Party Number i = 0x2181, '12125222' Plan:ISDN, Type:National Called Party Number i = 0xC1, '7199999' Plan:ISDN, Type:Subscriber(local) Jun 28 11:48:19: %LINK-3-UPDOWN: Interface Serial5:17, changed state to up Jun 28 11:48:19: ISDN Se5:15 Q931: TX -> CALL_PROC pd = 8 callref = 0x85DC Channel ID i = 0xA98392 Exclusive, Channel 18 Jun 28 11:48:19: ISDN Se5:15 Q931: TX -> CONNECT pd = 8 callref = 0x85DC Channel ID i = 0xA98392 Exclusive, Channel 18 Jun 28 11:48:19: Se5:17 PPP: Using dialer call direction Jun 28 11:48:19: Se5:17 PPP: Treating connection as a callin Jun 28 11:48:19: Se5:17 PPP: Phase is ESTABLISHING, Passive Open Jun 28 11:48:19: Se5:17 LCP: State is Listen Jun 28 11:48:19: ISDN Se5:15 Q931: RX <- CONNECT_ACK pd = 8 callref = 0x05DC Jun 28 11:48:19: Se5:17 LCP: I CONFREQ [Listen] id 1 len 23 Jun 28 11:48:19: Se5:17 LCP: ACCM 0x000A0000 (0x0206000A0000) Jun 28 11:48:19: Se5:17 LCP: MagicNumber 0x00A457E6 (0x050600A457E6) Jun 28 11:48:19: Se5:17 LCP: PFC (0x0702) Jun 28 11:48:19: Se5:17 LCP: ACFC (0x0802) Jun 28 11:48:19: Se5:17 LCP: Callback 6 (0x0D0306) Jun 28 11:48:19: Se5:17 PPP: Authorization required Jun 28 11:48:19: Se5:17 LCP: O CONFREQ [Listen] id 5 len 14 Jun 28 11:48:19: Se5:17 LCP: AuthProto PAP (0x0304C023) Jun 28 11:48:19: Se5:17 LCP: MagicNumber 0x6698E434 (0x05066698E434) Jun 28 11:48:19: Se5:17 LCP: O CONFREJ [Listen] id 1 len 7 Jun 28 11:48:19: Se5:17 LCP: Callback 6 (0x0D0306) Jun 28 11:48:19: Se5:17 LCP: I CONFACK [REQsent] id 5 len 14 Jun 28 11:48:19: Se5:17 LCP: AuthProto PAP (0x0304C023) Jun 28 11:48:19: Se5:17 LCP: MagicNumber 0x6698E434 (0x05066698E434) Jun 28 11:48:19: Se5:17 LCP: I CONFREQ [ACKrcvd] id 2 len 20 Jun 28 11:48:19: Se5:17 LCP: ACCM 0x000A0000 (0x0206000A0000) Jun 28 11:48:19: Se5:17 LCP: MagicNumber 0x00A457E6 (0x050600A457E6) Jun 28 11:48:19: Se5:17 LCP: PFC (0x0702) Jun 28 11:48:19: Se5:17 LCP: ACFC (0x0802) Jun 28 11:48:19: Se5:17 LCP: O CONFACK [ACKrcvd] id 2 len 20 Jun 28 11:48:19: Se5:17 LCP: ACCM 0x000A0000 (0x0206000A0000) Jun 28 11:48:19: Se5:17 LCP: MagicNumber 0x00A457E6 (0x050600A457E6) Jun 28 11:48:19: Se5:17 LCP: PFC (0x0702) Jun 28 11:48:19: Se5:17 LCP: ACFC (0x0802) Jun 28 11:48:19: Se5:17 LCP: State is Open Jun 28 11:48:19: Se5:17 PPP: Phase is AUTHENTICATING, by this end Jun 28 11:48:19: Se5:17 PAP: I AUTH-REQ id 1 len 25 from "[EMAIL PROTECTED]" Jun 28 11:48:19: Se5:17 PAP: Authenticating peer [EMAIL PROTECTED] Jun 28 11:48:19: Se5:17 PPP: Phase is FORWARDING, Attempting Forward Jun 28 11:48:19: Se5:17 PPP: Phase is AUTHENTICATING, Unauthenticated User Jun 28 11:48:19: Se5:17 PPP: Sent PAP LOGIN Request Jun 28 11:48:19: RADIUS: AAA Unsupported [150] 10 Jun 28 11:48:19: RADIUS: 53 65 72 69 61 6C 35 3A [Serial5:] Jun 28 11:48:19: RADIUS(00005D95): Storing nasport 20517 in rad_db Jun 28 11:48:19: RADIUS(00005D95): Config NAS IP: 0.0.0.0 Jun 28 11:48:19: RADIUS/ENCODE(00005D95): acct_session_id: 39067 Jun 28 11:48:19: RADIUS(00005D95): sending Jun 28 11:48:19: RADIUS/ENCODE: Best Local IP-Address 200.31.97.2 for Radius-Server 200.31.110.167 Jun 28 11:48:19: RADIUS(00005D95): Send Access-Request to 200.31.110.167:1645 id 21672/185, len 100 Jun 28 11:48:19: RADIUS: authenticator 48 DC 91 EB E1 1C BC 81 - FC 7F 43 66 28 50 35 EB Jun 28 11:48:19: RADIUS: Framed-Protocol [7] 6 PPP [1] Jun 28 11:48:19: RADIUS: User-Name [1] 13 "[EMAIL PROTECTED]" Jun 28 11:48:19: RADIUS: User-Password [2] 18 * Jun 28 11:48:19: RADIUS: NAS-Port [5] 6 20517 Jun 28 11:48:19: RADIUS: NAS-Port-Type [61] 6 ISDN [2] Jun 28 11:48:19: RADIUS: Calling-Station-Id [31] 10 "12125222" Jun 28 11:48:19: RADIUS: Called-Station-Id [30] 9 "7199999" Jun 28 11:48:19: RADIUS: Service-Type [6] 6 Framed [2] Jun 28 11:48:19: RADIUS: NAS-IP-Address [4] 6 200.31.97.2 Jun 28 11:48:19: RADIUS: Received from id 21672/185 200.31.110.167:1645, Access-Accept, len 44 Jun 28 11:48:19: RADIUS: authenticator 41 7B 35 D5 52 A7 B7 DA - 9E 00 DE 36 C0 97 8C 4B Jun 28 11:48:19: RADIUS: Service-Type [6] 6 Framed [2] Jun 28 11:48:19: RADIUS: Framed-Protocol [7] 6 PPP [1] Jun 28 11:48:19: RADIUS: Framed-Compression [13] 6 VJ TCP/IP Header Compressi[1] Jun 28 11:48:19: RADIUS: Framed-MTU [12] 6 1500 Jun 28 11:48:19: RADIUS(00005D95): Received from id 21672/185 Jun 28 11:48:19: Se5:17 PPP: Received LOGIN Response PASS Jun 28 11:48:19: Se5:17 PPP: Phase is FORWARDING, Attempting Forward Jun 28 11:48:19: Se5:17 PPP: Phase is AUTHENTICATING, Authenticated User Jun 28 11:48:19: Se5:17 PAP: O AUTH-ACK id 1 len 5 Jun 28 11:48:19: Se5:17 PPP: Phase is FORWARDING Jun 28 11:48:19: Vi3 PPP: Outbound context-status packet dropped, line protocol not up Jun 28 11:48:19: Vi3 PPP: Phase is DOWN, Setup Jun 28 11:48:19: Vi3 PPP: Authorization required Jun 28 11:48:19: Vi3 PPP: No remote authentication for call-in Jun 28 11:48:19: Vi3 PPP: Phase is ESTABLISHING Jun 28 11:48:19: Se5:17 PPP: Phase is FORWARDED Jun 28 11:48:19: Vi3 LCP: I FORCED rcvd CONFACK len 10 Jun 28 11:48:19: Vi3 LCP: AuthProto PAP (0x0304C023) Jun 28 11:48:19: Vi3 LCP: MagicNumber 0x6698E434 (0x05066698E434) Jun 28 11:48:19: Vi3 LCP: I FORCED sent CONFACK len 16 Jun 28 11:48:19: Vi3 LCP: ACCM 0x000A0000 (0x0206000A0000) Jun 28 11:48:19: Vi3 LCP: MagicNumber 0x00A457E6 (0x050600A457E6) Jun 28 11:48:19: Vi3 LCP: PFC (0x0702) Jun 28 11:48:19: Vi3 LCP: ACFC (0x0802) Jun 28 11:48:19: Vi3 PPP: Phase is AUTHENTICATING, by this end Jun 28 11:48:19: Vi3 PPP: Phase is UP Jun 28 11:48:19: Vi3 IPCP: O CONFREQ [Closed] id 1 len 16 Jun 28 11:48:19: Vi3 IPCP: CompressType VJ 15 slots (0x0206002D0F00) Jun 28 11:48:19: Vi3 IPCP: Address 0.0.0.0 (0x030600000000) Jun 28 11:48:19: Vi3 PPP: Process pending packets Jun 28 11:48:19: Se5:17 PPP: Process pending packets Jun 28 11:48:19: Vi3 IPCP: I CONFREQ [REQsent] id 1 len 34 Jun 28 11:48:19: Vi3 IPCP: Address 0.0.0.0 (0x030600000000) Jun 28 11:48:19: Vi3 IPCP: PrimaryDNS 0.0.0.0 (0x810600000000) Jun 28 11:48:19: Vi3 IPCP: PrimaryWINS 0.0.0.0 (0x820600000000) Jun 28 11:48:19: Vi3 IPCP: SecondaryDNS 0.0.0.0 (0x830600000000) Jun 28 11:48:19: Vi3 IPCP: SecondaryWINS 0.0.0.0 (0x840600000000) Jun 28 11:48:19: Vi3 AAA/AUTHOR/IPCP: Start. Her address 0.0.0.0, we want 0.0.0.0 Jun 28 11:48:19: Vi3 AAA/AUTHOR/IPCP: Done. Her address 0.0.0.0, we want 0.0.0.0 Jun 28 11:48:19: Vi3 IPCP: Cannot satisfy pool request Jun 28 11:48:19: Vi3 IPCP: Neither side knows remote address Jun 28 11:48:19: Vi3 IPCP: O CONFREJ [REQsent] id 1 len 22 Jun 28 11:48:19: Vi3 IPCP: Address 0.0.0.0 (0x030600000000) Jun 28 11:48:19: Vi3 IPCP: PrimaryWINS 0.0.0.0 (0x820600000000) Jun 28 11:48:19: Vi3 IPCP: SecondaryWINS 0.0.0.0 (0x840600000000) Jun 28 11:48:19: %LINK-3-UPDOWN: Interface Virtual-Access3, changed state to up Jun 28 11:48:19: Vi3 IPCP: I CONFREJ [REQsent] id 1 len 10 Jun 28 11:48:19: Vi3 IPCP: CompressType VJ 15 slots (0x0206002D0F00) Jun 28 11:48:19: Vi3 IPCP: O CONFREQ [REQsent] id 2 len 10 Jun 28 11:48:19: Vi3 IPCP: Address 0.0.0.0 (0x030600000000) Jun 28 11:48:19: Vi3 IPCP: I CONFREQ [REQsent] id 2 len 26 Jun 28 11:48:19: Vi3 IPCP: Addresses(Deprecated) 0.0.0.0 0.0.0.0 (0x010A0000000000000000) Jun 28 11:48:19: Vi3 IPCP: PrimaryDNS 0.0.0.0 (0x810600000000) Jun 28 11:48:19: Vi3 IPCP: SecondaryDNS 0.0.0.0 (0x830600000000) Jun 28 11:48:19: Vi3 AAA/AUTHOR/IPCP: Start. Her address 0.0.0.0, we want 0.0.0.0 Jun 28 11:48:19: Vi3 AAA/AUTHOR/IPCP: Done. Her address 0.0.0.0, we want 0.0.0.0 Jun 28 11:48:19: Vi3 IPCP: Cannot satisfy pool request Jun 28 11:48:19: Vi3 IPCP: Neither side knows remote address Jun 28 11:48:19: Vi3 IPCP: O CONFREJ [REQsent] id 2 len 14 Jun 28 11:48:19: Vi3 IPCP: Addresses(Deprecated) 0.0.0.0 0.0.0.0 (0x010A0000000000000000) Jun 28 11:48:19: Vi3 IPCP: I CONFACK [REQsent] id 2 len 10 Jun 28 11:48:19: Vi3 IPCP: Address 0.0.0.0 (0x030600000000) Jun 28 11:48:19: Vi3 IPCP: I TERMREQ [ACKrcvd] id 3 len 4 Jun 28 11:48:19: Vi3 IPCP: O TERMACK [ACKrcvd] id 3 len 4 Jun 28 11:48:20: %LINEPROTO-5-UPDOWN: Line protocol on Interface Serial5:17, changed state to up Jun 28 11:48:20: %LINEPROTO-5-UPDOWN: Line protocol on Interface Virtual-Access3, changed state to up Jun 28 11:48:21: Vi3 LCP: I TERMREQ [Open] id 3 len 4 Jun 28 11:48:21: Vi3 LCP: O TERMACK [Open] id 3 len 4 Jun 28 11:48:21: Vi3 PPP: Sending Acct Event[Down] id[5D95] Jun 28 11:48:21: Vi3 IPCP: State is Closed Jun 28 11:48:21: Vi3 PPP: Phase is TERMINATING Jun 28 11:48:21: ISDN Se5:15 Q931: RX <- DISCONNECT pd = 8 callref = 0x05DC Cause i = 0x8090 - Normal call clearing Jun 28 11:48:21: %ISDN-6-CONNECT: Interface Serial5:17 is now connected to 12125222 [EMAIL PROTECTED] Jun 28 11:48:21: %ISDN-6-DISCONNECT: Interface Serial5:17 disconnected from 12125222 [EMAIL PROTECTED], call lasted 2 seconds Jun 28 11:48:21: %LINK-3-UPDOWN: Interface Serial5:17, changed state to down Jun 28 11:48:21: ISDN Se5:15 Q931: TX -> RELEASE pd = 8 callref = 0x85DC Jun 28 11:48:21: Se5:17 PPP: Sending Acct Event[Down] id[5D95] Jun 28 11:48:21: Se5:17 PPP: Phase is TERMINATING Jun 28 11:48:21: Se5:17 LCP: State is Closed Jun 28 11:48:21: Se5:17 PPP: Phase is DOWN Jun 28 11:48:21: %LINK-3-UPDOWN: Interface Virtual-Access3, changed state to down Jun 28 11:48:21: Vi3 LCP:
