I cant Connect to GSM Operator by using APN
Hi, I have freeradius and I have a Secovid OTP server. I use free radius as proxy. When I try to connect via APN, altough OTP server accept-access , I get access-reject as final result. Can anybody say what is wrong??? Here is my debug: ########################################################## rad_recv: Access-Request packet from host A.B.C.D port 2460, id=104, length=139 NAS-IP-Address = A.B.C.D NAS-Identifier = "GGFILE02" Called-Station-Id = "yasarapn" Framed-Protocol = GPRS-PDP-Context Service-Type = Framed-User NAS-Port-Type = Virtual NAS-Port = 14457480 CHAP-Challenge = 0xd0ceda5e56ea3f6f28ed31cd5646c988 User-Name = "tevfikceydeliler" CHAP-Password = 0x0126f5f525a93d37c4caf7e55d586ce5cd Calling-Station-Id = "905308507313" Wed Dec 9 15:04:57 2009 : Info: +- entering group authorize {...} Wed Dec 9 15:04:57 2009 : Info: ++[preprocess] returns ok Wed Dec 9 15:04:57 2009 : Debug: expand: /var/log/freeradius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d -> /var/log/freeradius/radacct/A.B.C.D/auth-detail-20091209 Wed Dec 9 15:04:57 2009 : Info: [auth_log] /var/log/freeradius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d expands to /var/log/freeradius/radacct/A.B.C.D/auth-detail-20091209 Wed Dec 9 15:04:57 2009 : Debug: expand: %t -> Wed Dec 9 15:04:57 2009 Wed Dec 9 15:04:57 2009 : Info: ++[auth_log] returns ok Wed Dec 9 15:04:57 2009 : Info: [chap] Setting 'Auth-Type := CHAP' Wed Dec 9 15:04:57 2009 : Info: ++[chap] returns ok Wed Dec 9 15:04:57 2009 : Info: ++[mschap] returns noop Wed Dec 9 15:04:57 2009 : Info: [suffix] No '@' in User-Name = "tevfikceydeliler", looking up realm NULL Wed Dec 9 15:04:57 2009 : Info: [suffix] No such realm "NULL" Wed Dec 9 15:04:57 2009 : Info: ++[suffix] returns noop Wed Dec 9 15:04:57 2009 : Info: [eap] No EAP-Message, not doing EAP Wed Dec 9 15:04:57 2009 : Info: ++[eap] returns noop Wed Dec 9 15:04:57 2009 : Info: ++[unix] returns notfound Wed Dec 9 15:04:57 2009 : Info: [files] users: Matched entry tevfikceydeliler at line 100 Wed Dec 9 15:04:57 2009 : Debug: expand: Hello, %{User-Name} -> Hello, tevfikceydeliler Wed Dec 9 15:04:57 2009 : Info: ++[files] returns ok Wed Dec 9 15:04:57 2009 : Info: ++[expiration] returns noop Wed Dec 9 15:04:57 2009 : Info: ++[logintime] returns noop Wed Dec 9 15:04:57 2009 : Info: ++[pap] returns noop Wed Dec 9 15:04:57 2009 : Info: +- entering group pre-proxy {...} Wed Dec 9 15:04:57 2009 : Debug: expand: /var/log/freeradius/radacct/%{Client-IP-Address}/pre-proxy-detail-%Y%m%d -> /var/log/freeradius/radacct/A.B.C.D/pre-proxy-detail-20091209 Wed Dec 9 15:04:57 2009 : Info: [pre_proxy_log] /var/log/freeradius/radacct/%{Client-IP-Address}/pre-proxy-detail-%Y%m%d expands to /var/log/freeradius/radacct/A.B.C.D/pre-proxy-detail-20091209 Wed Dec 9 15:04:57 2009 : Debug: expand: %t -> Wed Dec 9 15:04:57 2009 Wed Dec 9 15:04:57 2009 : Info: ++[pre_proxy_log] returns ok Sending Access-Request of id 111 to W.X.Y.Z port 1812 NAS-IP-Address = A.B.C.D NAS-Identifier = "GGFILE02" Called-Station-Id = "yasarapn" Framed-Protocol = GPRS-PDP-Context Service-Type = Framed-User NAS-Port-Type = Virtual NAS-Port = 14457480 CHAP-Challenge = 0xd0ceda5e56ea3f6f28ed31cd5646c988 User-Name = "tevfikceydeliler" CHAP-Password = 0x0126f5f525a93d37c4caf7e55d586ce5cd Calling-Station-Id = "905308507313" Proxy-State = 0x313034 Wed Dec 9 15:04:57 2009 : Info: Proxying request 0 to home server W.X.Y.Z port 1812 Sending Access-Request of id 111 to W.X.Y.Z port 1812 NAS-IP-Address = A.B.C.D NAS-Identifier = "GGFILE02" Called-Station-Id = "yasarapn" Framed-Protocol = GPRS-PDP-Context Service-Type = Framed-User NAS-Port-Type = Virtual NAS-Port = 14457480 CHAP-Challenge = 0xd0ceda5e56ea3f6f28ed31cd5646c988 User-Name = "tevfikceydeliler" CHAP-Password = 0x0126f5f525a93d37c4caf7e55d586ce5cd Calling-Station-Id = "905308507313" Proxy-State = 0x313034 Wed Dec 9 15:04:57 2009 : Debug: Going to the next request Wed Dec 9 15:04:57 2009 : Debug: Waking up in 0.9 seconds. rad_recv: Access-Accept packet from host W.X.Y.Z port 1812, id=111, length=25 Proxy-State = 0x313034 Wed Dec 9 15:04:57 2009 : Info: +- entering group post-proxy {...} Wed Dec 9 15:04:57 2009 : Debug: expand: /var/log/freeradius/radacct/%{Client-IP-Address}/post-proxy-detail-%Y%m%d -> /var/log/freeradius/radacct/A.B.C.D/post-proxy-detail-20091209 Wed Dec 9 15:04:57 2009 : Info: [post_proxy_log] /var/log/freeradius/radacct/%{Client-IP-Address}/post-proxy-detail-%Y%m%d expands to /var/log/freeradius/radacct/A.B.C.D/post-proxy-detail-20091209 Wed Dec 9 15:04:57 2009 : Debug: expand: %t -> Wed Dec 9 15:04:57 2009 Wed Dec 9 15:04:57 2009 : Info: ++[post_proxy_log] returns ok Wed Dec 9 15:04:57 2009 : Info: [eap] No pre-existing handler found Wed Dec 9 15:04:57 2009 : Info: ++[eap] returns noop Wed Dec 9 15:04:57 2009 : Info: Found Auth-Type = CHAP Wed Dec 9 15:04:57 2009 : Info: Found Auth-Type = Accept Wed Dec 9 15:04:57 2009 : Error: Warning: Found 2 auth-types on request for user 'tevfikceydeliler' Wed Dec 9 15:04:57 2009 : Info: Auth-Type = Accept, accepting the user Wed Dec 9 15:04:57 2009 : Auth: Login OK: [tevfikceydeliler/<CHAP-Password>] (from client turkcellAPN port 14457480 cli 905308507313) Wed Dec 9 15:04:57 2009 : Info: +- entering group post-auth {...} Wed Dec 9 15:04:57 2009 : Info: ++[TCELL_pool] returns noop Wed Dec 9 15:04:57 2009 : Debug: expand: %{NAS-IP-Address} %{NAS-Port} -> A.B.C.D 14457480 Wed Dec 9 15:04:57 2009 : Info: [BirmasGPRS_pool] MD5 on 'key' directive maps to: 156b20174482d4044a5602154300512b Wed Dec 9 15:04:57 2009 : Info: [BirmasGPRS_pool] Searching for an entry for key: '156b20174482d4044a5602154300512b' Wed Dec 9 15:04:57 2009 : Debug: rlm_ippool: Allocating ip to key: '156b20174482d4044a5602154300512b' Wed Dec 9 15:04:57 2009 : Info: [BirmasGPRS_pool] num: 1 Wed Dec 9 15:04:57 2009 : Info: [BirmasGPRS_pool] Allocated ip 192.168.2.120 to client key: 156b20174482d4044a5602154300512b Wed Dec 9 15:04:57 2009 : Info: ++[BirmasGPRS_pool] returns ok Wed Dec 9 15:04:57 2009 : Info: ++[PSUOtonom_pool] returns noop Wed Dec 9 15:04:57 2009 : Info: ++[YBPOtonom1_pool] returns noop Wed Dec 9 15:04:57 2009 : Info: ++[YBPOtonom2_pool] returns noop Wed Dec 9 15:04:57 2009 : Info: ++[YBPOtonom3_pool] returns noop Wed Dec 9 15:04:57 2009 : Debug: expand: /var/log/freeradius/radacct/%{Client-IP-Address}/reply-detail-%Y%m%d -> /var/log/freeradius/radacct/A.B.C.D/reply-detail-20091209 Wed Dec 9 15:04:57 2009 : Info: [reply_log] /var/log/freeradius/radacct/%{Client-IP-Address}/reply-detail-%Y%m%d expands to /var/log/freeradius/radacct/A.B.C.D/reply-detail-20091209 Wed Dec 9 15:04:57 2009 : Debug: expand: %t -> Wed Dec 9 15:04:57 2009 Wed Dec 9 15:04:57 2009 : Info: ++[reply_log] returns ok Wed Dec 9 15:04:57 2009 : Info: ++[exec] returns noop Sending Access-Accept of id 104 to A.B.C.D port 2460 Framed-IP-Address = 192.168.2.120 Framed-IP-Netmask = 255.255.240.0 Wed Dec 9 15:04:57 2009 : Info: Finished request 0. Wed Dec 9 15:04:57 2009 : Debug: Going to the next request Wed Dec 9 15:04:57 2009 : Debug: Waking up in 4.9 seconds. rad_recv: Access-Request packet from host A.B.C.D port 2005, id=250, length=139 NAS-IP-Address = A.B.C.D NAS-Identifier = "GGFILE02" Called-Station-Id = "yasarapn" Framed-Protocol = GPRS-PDP-Context Service-Type = Framed-User NAS-Port-Type = Virtual NAS-Port = 79921368 CHAP-Challenge = 0xd0ceda5e56ea3f6f28ed31cd5646c988 User-Name = "tevfikceydeliler" CHAP-Password = 0x0126f5f525a93d37c4caf7e55d586ce5cd Calling-Station-Id = "905308507313" Wed Dec 9 15:04:57 2009 : Info: +- entering group authorize {...} Wed Dec 9 15:04:57 2009 : Info: ++[preprocess] returns ok Wed Dec 9 15:04:57 2009 : Debug: expand: /var/log/freeradius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d -> /var/log/freeradius/radacct/A.B.C.D/auth-detail-20091209 Wed Dec 9 15:04:57 2009 : Info: [auth_log] /var/log/freeradius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d expands to /var/log/freeradius/radacct/A.B.C.D/auth-detail-20091209 Wed Dec 9 15:04:57 2009 : Debug: expand: %t -> Wed Dec 9 15:04:57 2009 Wed Dec 9 15:04:57 2009 : Info: ++[auth_log] returns ok Wed Dec 9 15:04:57 2009 : Info: [chap] Setting 'Auth-Type := CHAP' Wed Dec 9 15:04:57 2009 : Info: ++[chap] returns ok Wed Dec 9 15:04:57 2009 : Info: ++[mschap] returns noop Wed Dec 9 15:04:57 2009 : Info: [suffix] No '@' in User-Name = "tevfikceydeliler", looking up realm NULL Wed Dec 9 15:04:57 2009 : Info: [suffix] No such realm "NULL" Wed Dec 9 15:04:57 2009 : Info: ++[suffix] returns noop Wed Dec 9 15:04:57 2009 : Info: [eap] No EAP-Message, not doing EAP Wed Dec 9 15:04:57 2009 : Info: ++[eap] returns noop Wed Dec 9 15:04:57 2009 : Info: ++[unix] returns notfound Wed Dec 9 15:04:57 2009 : Info: [files] users: Matched entry tevfikceydeliler at line 100 Wed Dec 9 15:04:57 2009 : Debug: expand: Hello, %{User-Name} -> Hello, tevfikceydeliler Wed Dec 9 15:04:57 2009 : Info: ++[files] returns ok Wed Dec 9 15:04:57 2009 : Info: ++[expiration] returns noop Wed Dec 9 15:04:57 2009 : Info: ++[logintime] returns noop Wed Dec 9 15:04:57 2009 : Info: ++[pap] returns noop Wed Dec 9 15:04:57 2009 : Info: +- entering group pre-proxy {...} Wed Dec 9 15:04:57 2009 : Debug: expand: /var/log/freeradius/radacct/%{Client-IP-Address}/pre-proxy-detail-%Y%m%d -> /var/log/freeradius/radacct/A.B.C.D/pre-proxy-detail-20091209 Wed Dec 9 15:04:57 2009 : Info: [pre_proxy_log] /var/log/freeradius/radacct/%{Client-IP-Address}/pre-proxy-detail-%Y%m%d expands to /var/log/freeradius/radacct/A.B.C.D/pre-proxy-detail-20091209 Wed Dec 9 15:04:57 2009 : Debug: expand: %t -> Wed Dec 9 15:04:57 2009 Wed Dec 9 15:04:57 2009 : Info: ++[pre_proxy_log] returns ok Sending Access-Request of id 105 to W.X.Y.Z port 1812 NAS-IP-Address = A.B.C.D NAS-Identifier = "GGFILE02" Called-Station-Id = "yasarapn" Framed-Protocol = GPRS-PDP-Context Service-Type = Framed-User NAS-Port-Type = Virtual NAS-Port = 79921368 CHAP-Challenge = 0xd0ceda5e56ea3f6f28ed31cd5646c988 User-Name = "tevfikceydeliler" CHAP-Password = 0x0126f5f525a93d37c4caf7e55d586ce5cd Calling-Station-Id = "905308507313" Proxy-State = 0x323530 Wed Dec 9 15:04:58 2009 : Info: Proxying request 1 to home server W.X.Y.Z port 1812 Sending Access-Request of id 105 to W.X.Y.Z port 1812 NAS-IP-Address = A.B.C.D NAS-Identifier = "GGFILE02" Called-Station-Id = "yasarapn" Framed-Protocol = GPRS-PDP-Context Service-Type = Framed-User NAS-Port-Type = Virtual NAS-Port = 79921368 CHAP-Challenge = 0xd0ceda5e56ea3f6f28ed31cd5646c988 User-Name = "tevfikceydeliler" CHAP-Password = 0x0126f5f525a93d37c4caf7e55d586ce5cd Calling-Station-Id = "905308507313" Proxy-State = 0x323530 Wed Dec 9 15:04:58 2009 : Debug: Going to the next request Wed Dec 9 15:04:58 2009 : Debug: Waking up in 0.9 seconds. rad_recv: Access-Reject packet from host W.X.Y.Z port 1812, id=105, length=25 Proxy-State = 0x323530 Wed Dec 9 15:04:58 2009 : Info: +- entering group post-proxy {...} Wed Dec 9 15:04:58 2009 : Debug: expand: /var/log/freeradius/radacct/%{Client-IP-Address}/post-proxy-detail-%Y%m%d -> /var/log/freeradius/radacct/A.B.C.D/post-proxy-detail-20091209 Wed Dec 9 15:04:58 2009 : Info: [post_proxy_log] /var/log/freeradius/radacct/%{Client-IP-Address}/post-proxy-detail-%Y%m%d expands to /var/log/freeradius/radacct/A.B.C.D/post-proxy-detail-20091209 Wed Dec 9 15:04:58 2009 : Debug: expand: %t -> Wed Dec 9 15:04:57 2009 Wed Dec 9 15:04:58 2009 : Info: ++[post_proxy_log] returns ok Wed Dec 9 15:04:58 2009 : Info: [eap] No pre-existing handler found Wed Dec 9 15:04:58 2009 : Info: ++[eap] returns noop Wed Dec 9 15:04:58 2009 : Auth: Login incorrect (Home Server says so): [tevfikceydeliler/<CHAP-Password>] (from client turkcellAPN port 79921368 cli 905308507313) Wed Dec 9 15:04:58 2009 : Info: Using Post-Auth-Type Reject Wed Dec 9 15:04:58 2009 : Info: +- entering group REJECT {...} Wed Dec 9 15:04:58 2009 : Debug: expand: %{User-Name} -> tevfikceydeliler Wed Dec 9 15:04:58 2009 : Debug: attr_filter: Matched entry DEFAULT at line 11 Wed Dec 9 15:04:58 2009 : Info: ++[attr_filter.access_reject] returns updated Wed Dec 9 15:04:58 2009 : Info: Delaying reject of request 1 for 3 seconds Wed Dec 9 15:04:58 2009 : Debug: Going to the next request Wed Dec 9 15:04:58 2009 : Debug: Waking up in 0.9 seconds. Wed Dec 9 15:04:59 2009 : Debug: Waking up in 1.9 seconds. Wed Dec 9 15:05:01 2009 : Info: Sending delayed reject for request 1 Sending Access-Reject of id 250 to A.B.C.D port 2005 Wed Dec 9 15:05:01 2009 : Debug: Waking up in 1.9 seconds. rad_recv: Access-Request packet from host A.B.C.D port 4817, id=133, length=139 NAS-IP-Address = A.B.C.D NAS-Identifier = "GGFILE02" Called-Station-Id = "yasarapn" Framed-Protocol = GPRS-PDP-Context Service-Type = Framed-User NAS-Port-Type = Virtual NAS-Port = 152187272 CHAP-Challenge = 0xd0ceda5e56ea3f6f28ed31cd5646c988 User-Name = "tevfikceydeliler" CHAP-Password = 0x0126f5f525a93d37c4caf7e55d586ce5cd Calling-Station-Id = "905308507313" Wed Dec 9 15:05:01 2009 : Info: +- entering group authorize {...} Wed Dec 9 15:05:01 2009 : Info: ++[preprocess] returns ok Wed Dec 9 15:05:01 2009 : Debug: expand: /var/log/freeradius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d -> /var/log/freeradius/radacct/A.B.C.D/auth-detail-20091209 Wed Dec 9 15:05:01 2009 : Info: [auth_log] /var/log/freeradius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d expands to /var/log/freeradius/radacct/A.B.C.D/auth-detail-20091209 Wed Dec 9 15:05:01 2009 : Debug: expand: %t -> Wed Dec 9 15:05:01 2009 Wed Dec 9 15:05:01 2009 : Info: ++[auth_log] returns ok Wed Dec 9 15:05:01 2009 : Info: [chap] Setting 'Auth-Type := CHAP' Wed Dec 9 15:05:01 2009 : Info: ++[chap] returns ok Wed Dec 9 15:05:01 2009 : Info: ++[mschap] returns noop Wed Dec 9 15:05:01 2009 : Info: [suffix] No '@' in User-Name = "tevfikceydeliler", looking up realm NULL Wed Dec 9 15:05:01 2009 : Info: [suffix] No such realm "NULL" Wed Dec 9 15:05:01 2009 : Info: ++[suffix] returns noop Wed Dec 9 15:05:01 2009 : Info: [eap] No EAP-Message, not doing EAP Wed Dec 9 15:05:01 2009 : Info: ++[eap] returns noop Wed Dec 9 15:05:01 2009 : Info: ++[unix] returns notfound Wed Dec 9 15:05:01 2009 : Info: [files] users: Matched entry tevfikceydeliler at line 100 Wed Dec 9 15:05:01 2009 : Debug: expand: Hello, %{User-Name} -> Hello, tevfikceydeliler Wed Dec 9 15:05:01 2009 : Info: ++[files] returns ok Wed Dec 9 15:05:01 2009 : Info: ++[expiration] returns noop Wed Dec 9 15:05:01 2009 : Info: ++[logintime] returns noop Wed Dec 9 15:05:01 2009 : Info: ++[pap] returns noop Wed Dec 9 15:05:01 2009 : Info: +- entering group pre-proxy {...} Wed Dec 9 15:05:01 2009 : Debug: expand: /var/log/freeradius/radacct/%{Client-IP-Address}/pre-proxy-detail-%Y%m%d -> /var/log/freeradius/radacct/A.B.C.D/pre-proxy-detail-20091209 Wed Dec 9 15:05:01 2009 : Info: [pre_proxy_log] /var/log/freeradius/radacct/%{Client-IP-Address}/pre-proxy-detail-%Y%m%d expands to /var/log/freeradius/radacct/A.B.C.D/pre-proxy-detail-20091209 Wed Dec 9 15:05:01 2009 : Debug: expand: %t -> Wed Dec 9 15:05:01 2009 Wed Dec 9 15:05:01 2009 : Info: ++[pre_proxy_log] returns ok Sending Access-Request of id 220 to W.X.Y.Z port 1812 NAS-IP-Address = A.B.C.D NAS-Identifier = "GGFILE02" Called-Station-Id = "yasarapn" Framed-Protocol = GPRS-PDP-Context Service-Type = Framed-User NAS-Port-Type = Virtual NAS-Port = 152187272 CHAP-Challenge = 0xd0ceda5e56ea3f6f28ed31cd5646c988 User-Name = "tevfikceydeliler" CHAP-Password = 0x0126f5f525a93d37c4caf7e55d586ce5cd Calling-Station-Id = "905308507313" Proxy-State = 0x313333 Wed Dec 9 15:05:01 2009 : Info: Proxying request 2 to home server W.X.Y.Z port 1812 Sending Access-Request of id 220 to W.X.Y.Z port 1812 NAS-IP-Address = A.B.C.D NAS-Identifier = "GGFILE02" Called-Station-Id = "yasarapn" Framed-Protocol = GPRS-PDP-Context Service-Type = Framed-User NAS-Port-Type = Virtual NAS-Port = 152187272 CHAP-Challenge = 0xd0ceda5e56ea3f6f28ed31cd5646c988 User-Name = "tevfikceydeliler" CHAP-Password = 0x0126f5f525a93d37c4caf7e55d586ce5cd Calling-Station-Id = "905308507313" Proxy-State = 0x313333 Wed Dec 9 15:05:01 2009 : Debug: Going to the next request Wed Dec 9 15:05:01 2009 : Debug: Waking up in 0.9 seconds. rad_recv: Access-Reject packet from host W.X.Y.Z port 1812, id=220, length=25 Proxy-State = 0x313333 Wed Dec 9 15:05:01 2009 : Info: +- entering group post-proxy {...} Wed Dec 9 15:05:01 2009 : Debug: expand: /var/log/freeradius/radacct/%{Client-IP-Address}/post-proxy-detail-%Y%m%d -> /var/log/freeradius/radacct/A.B.C.D/post-proxy-detail-20091209 Wed Dec 9 15:05:01 2009 : Info: [post_proxy_log] /var/log/freeradius/radacct/%{Client-IP-Address}/post-proxy-detail-%Y%m%d expands to /var/log/freeradius/radacct/A.B.C.D/post-proxy-detail-20091209 Wed Dec 9 15:05:01 2009 : Debug: expand: %t -> Wed Dec 9 15:05:01 2009 Wed Dec 9 15:05:01 2009 : Info: ++[post_proxy_log] returns ok Wed Dec 9 15:05:01 2009 : Info: [eap] No pre-existing handler found Wed Dec 9 15:05:01 2009 : Info: ++[eap] returns noop Wed Dec 9 15:05:01 2009 : Auth: Login incorrect (Home Server says so): [tevfikceydeliler/<CHAP-Password>] (from client turkcellAPN port 152187272 cli 905308507313) Wed Dec 9 15:05:01 2009 : Info: Using Post-Auth-Type Reject Wed Dec 9 15:05:01 2009 : Info: +- entering group REJECT {...} Wed Dec 9 15:05:01 2009 : Debug: expand: %{User-Name} -> tevfikceydeliler Wed Dec 9 15:05:01 2009 : Debug: attr_filter: Matched entry DEFAULT at line 11 Wed Dec 9 15:05:01 2009 : Info: ++[attr_filter.access_reject] returns updated Wed Dec 9 15:05:01 2009 : Info: Delaying reject of request 2 for 3 seconds Wed Dec 9 15:05:01 2009 : Debug: Going to the next request Wed Dec 9 15:05:01 2009 : Debug: Waking up in 0.9 seconds. Wed Dec 9 15:05:02 2009 : Debug: Waking up in 0.8 seconds. Wed Dec 9 15:05:02 2009 : Info: Cleaning up request 0 ID 104 with timestamp +185 Wed Dec 9 15:05:02 2009 : Debug: Waking up in 1.0 seconds. Wed Dec 9 15:05:04 2009 : Info: Sending delayed reject for request 2 Sending Access-Reject of id 133 to A.B.C.D port 4817 Wed Dec 9 15:05:04 2009 : Debug: Waking up in 1.9 seconds. rad_recv: Access-Request packet from host A.B.C.D port 3820, id=182, length=139 NAS-IP-Address = A.B.C.D NAS-Identifier = "GGFILE02" Called-Station-Id = "yasarapn" Framed-Protocol = GPRS-PDP-Context Service-Type = Framed-User NAS-Port-Type = Virtual NAS-Port = 163401920 CHAP-Challenge = 0xd0ceda5e56ea3f6f28ed31cd5646c988 User-Name = "tevfikceydeliler" CHAP-Password = 0x0126f5f525a93d37c4caf7e55d586ce5cd Calling-Station-Id = "905308507313" Wed Dec 9 15:05:04 2009 : Info: +- entering group authorize {...} Wed Dec 9 15:05:04 2009 : Info: ++[preprocess] returns ok Wed Dec 9 15:05:04 2009 : Debug: expand: /var/log/freeradius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d -> /var/log/freeradius/radacct/A.B.C.D/auth-detail-20091209 Wed Dec 9 15:05:04 2009 : Info: [auth_log] /var/log/freeradius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d expands to /var/log/freeradius/radacct/A.B.C.D/auth-detail-20091209 Wed Dec 9 15:05:04 2009 : Debug: expand: %t -> Wed Dec 9 15:05:04 2009 Wed Dec 9 15:05:04 2009 : Info: ++[auth_log] returns ok Wed Dec 9 15:05:04 2009 : Info: [chap] Setting 'Auth-Type := CHAP' Wed Dec 9 15:05:04 2009 : Info: ++[chap] returns ok Wed Dec 9 15:05:04 2009 : Info: ++[mschap] returns noop Wed Dec 9 15:05:04 2009 : Info: [suffix] No '@' in User-Name = "tevfikceydeliler", looking up realm NULL Wed Dec 9 15:05:04 2009 : Info: [suffix] No such realm "NULL" Wed Dec 9 15:05:04 2009 : Info: ++[suffix] returns noop Wed Dec 9 15:05:04 2009 : Info: [eap] No EAP-Message, not doing EAP Wed Dec 9 15:05:04 2009 : Info: ++[eap] returns noop Wed Dec 9 15:05:04 2009 : Info: ++[unix] returns notfound Wed Dec 9 15:05:04 2009 : Info: [files] users: Matched entry tevfikceydeliler at line 100 Wed Dec 9 15:05:04 2009 : Debug: expand: Hello, %{User-Name} -> Hello, tevfikceydeliler Wed Dec 9 15:05:04 2009 : Info: ++[files] returns ok Wed Dec 9 15:05:04 2009 : Info: ++[expiration] returns noop Wed Dec 9 15:05:04 2009 : Info: ++[logintime] returns noop Wed Dec 9 15:05:04 2009 : Info: ++[pap] returns noop Wed Dec 9 15:05:04 2009 : Info: +- entering group pre-proxy {...} Wed Dec 9 15:05:04 2009 : Debug: expand: /var/log/freeradius/radacct/%{Client-IP-Address}/pre-proxy-detail-%Y%m%d -> /var/log/freeradius/radacct/A.B.C.D/pre-proxy-detail-20091209 Wed Dec 9 15:05:04 2009 : Info: [pre_proxy_log] /var/log/freeradius/radacct/%{Client-IP-Address}/pre-proxy-detail-%Y%m%d expands to /var/log/freeradius/radacct/A.B.C.D/pre-proxy-detail-20091209 Wed Dec 9 15:05:04 2009 : Debug: expand: %t -> Wed Dec 9 15:05:04 2009 Wed Dec 9 15:05:04 2009 : Info: ++[pre_proxy_log] returns ok Sending Access-Request of id 216 to W.X.Y.Z port 1812 NAS-IP-Address = A.B.C.D NAS-Identifier = "GGFILE02" Called-Station-Id = "yasarapn" Framed-Protocol = GPRS-PDP-Context Service-Type = Framed-User NAS-Port-Type = Virtual NAS-Port = 163401920 CHAP-Challenge = 0xd0ceda5e56ea3f6f28ed31cd5646c988 User-Name = "tevfikceydeliler" CHAP-Password = 0x0126f5f525a93d37c4caf7e55d586ce5cd Calling-Station-Id = "905308507313" Proxy-State = 0x313832 Wed Dec 9 15:05:04 2009 : Info: Proxying request 3 to home server W.X.Y.Z port 1812 Sending Access-Request of id 216 to W.X.Y.Z port 1812 NAS-IP-Address = A.B.C.D NAS-Identifier = "GGFILE02" Called-Station-Id = "yasarapn" Framed-Protocol = GPRS-PDP-Context Service-Type = Framed-User NAS-Port-Type = Virtual NAS-Port = 163401920 CHAP-Challenge = 0xd0ceda5e56ea3f6f28ed31cd5646c988 User-Name = "tevfikceydeliler" CHAP-Password = 0x0126f5f525a93d37c4caf7e55d586ce5cd Calling-Station-Id = "905308507313" Proxy-State = 0x313832 Wed Dec 9 15:05:04 2009 : Debug: Going to the next request Wed Dec 9 15:05:04 2009 : Debug: Waking up in 0.9 seconds. rad_recv: Access-Reject packet from host W.X.Y.Z port 1812, id=216, length=25 Proxy-State = 0x313832 Wed Dec 9 15:05:04 2009 : Info: +- entering group post-proxy {...} Wed Dec 9 15:05:04 2009 : Debug: expand: /var/log/freeradius/radacct/%{Client-IP-Address}/post-proxy-detail-%Y%m%d -> /var/log/freeradius/radacct/A.B.C.D/post-proxy-detail-20091209 Wed Dec 9 15:05:04 2009 : Info: [post_proxy_log] /var/log/freeradius/radacct/%{Client-IP-Address}/post-proxy-detail-%Y%m%d expands to /var/log/freeradius/radacct/A.B.C.D/post-proxy-detail-20091209 Wed Dec 9 15:05:04 2009 : Debug: expand: %t -> Wed Dec 9 15:05:04 2009 Wed Dec 9 15:05:04 2009 : Info: ++[post_proxy_log] returns ok Wed Dec 9 15:05:04 2009 : Info: [eap] No pre-existing handler found Wed Dec 9 15:05:04 2009 : Info: ++[eap] returns noop Wed Dec 9 15:05:04 2009 : Auth: Login incorrect (Home Server says so): [tevfikceydeliler/<CHAP-Password>] (from client turkcellAPN port 163401920 cli 905308507313) Wed Dec 9 15:05:04 2009 : Info: Using Post-Auth-Type Reject Wed Dec 9 15:05:04 2009 : Info: +- entering group REJECT {...} Wed Dec 9 15:05:04 2009 : Debug: expand: %{User-Name} -> tevfikceydeliler Wed Dec 9 15:05:04 2009 : Debug: attr_filter: Matched entry DEFAULT at line 11 Wed Dec 9 15:05:04 2009 : Info: ++[attr_filter.access_reject] returns updated Wed Dec 9 15:05:04 2009 : Info: Delaying reject of request 3 for 3 seconds Wed Dec 9 15:05:04 2009 : Debug: Going to the next request Wed Dec 9 15:05:04 2009 : Debug: Waking up in 0.9 seconds. Wed Dec 9 15:05:05 2009 : Debug: Waking up in 0.8 seconds. Wed Dec 9 15:05:05 2009 : Info: Cleaning up request 1 ID 250 with timestamp +185 Wed Dec 9 15:05:05 2009 : Debug: Waking up in 1.0 seconds. Wed Dec 9 15:05:07 2009 : Info: Sending delayed reject for request 3 Sending Access-Reject of id 182 to A.B.C.D port 3820 Wed Dec 9 15:05:07 2009 : Debug: Waking up in 1.9 seconds. Wed Dec 9 15:05:09 2009 : Info: Cleaning up request 2 ID 133 with timestamp +189 Wed Dec 9 15:05:09 2009 : Debug: Waking up in 3.0 seconds. Wed Dec 9 15:05:12 2009 : Info: Cleaning up request 3 ID 182 with timestamp +192 Wed Dec 9 15:05:12 2009 : Debug: Ready to process requests. ########################################################## <http://www.astron.ws/> Tevfik Ceydeliler Ağ &Web Teknolojileri Uzmanı Astron Proje Ofisi ' (232) 435 82 88 dahili 131 * tevfik.ceydeliler@ astron.yasar.com.tr ü www.astron.ws <http://www.astron.ws/> 7/24 Acil Müdahele: 0 533 658 85 57 Bu elektronik postada bulunan tum fikir ve gorusler ve ekindeki dosyalar sadece adres sahip/sahiplerine ait olup, Yasar Toplulugu Sirketleri bu mesajin icerigi ile ilgili olarak hic bir hukuksal sorumlulugu kabul etmez. Eger gonderilmesi dusunulen kisi veya kurulus degilseniz, lutfen gonderen kisiyi derhal haberdar ediniz ve mesaji sisteminizden siliniz.The information contained in this e-mail and any files transmitted with it are intended solely for the use of the individual or entity to whom they are addressed and Yasar Group Companies do not accept legal responsibility for the contents. If you are not the intended recipient, please immediately notify the sender and delete it from your system.
On 09-12-09 2:27 PM, Tevfik Ceydeliler wrote:
Hi, I have freeradius and I have a Secovid OTP server. I use free radius as proxy. When I try to connect via APN, altough OTP server accept-access , I get access-reject as final result. Can anybody say what is wrong???
This packet receives an accept:
rad_recv: Access-Accept packet from host W.X.Y.Z port 1812, id=111, length=25
And this one receives a reject:
rad_recv: Access-Reject packet from host W.X.Y.Z port 1812, id=105, length=25 ... Wed Dec 9 15:04:58 2009 : Auth: Login incorrect (Home Server says so):
Fix the home server to send access accepts for this other user. I don't understand why you're looking at FreeRADIUS in order to debug a problem where the home server returns reject. Alan DEKok.
participants (2)
-
Alan DeKok -
Tevfik Ceydeliler