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.
##########################################################
|
Tevfik Ceydeliler |
' (232)
435 82 88 dahili 131
* tevfik.ceydeliler@ astron.yasar.com.tr
ü www.astron.ws
7/24 Acil Müdahele: 0 533
658 85 57