Re: Proxy problem in FreeRADIUS 1.1.3

8 Sep 2006


      ...
Please post a config & debug logs from 1.1.3.
OK, I took out blank lines, commented lines, and obfuscated IPs and 
passwords.  Let me know if there's anything else I can provide, and 
thanks in advance for all your help!

------------------ radiusd -X -x debug output --------------------

rad_recv: Access-Request packet from host xx.xx.xx.xx:4587, id=3, length=60
         User-Name = "bob@domain.com"
         User-Password = "XXXX"
Fri Sep  8 12:37:40 2006 : Debug:   Processing the authorize section of 
radiusd.conf
Fri Sep  8 12:37:40 2006 : Debug: modcall: entering group authorize for 
request 2
Fri Sep  8 12:37:40 2006 : Debug:   modsingle[authorize]: calling 
preprocess (rlm_preprocess) for request 2
Fri Sep  8 12:37:40 2006 : Debug:   modsingle[authorize]: returned from 
preprocess (rlm_preprocess) for request 2
Fri Sep  8 12:37:40 2006 : Debug:   modcall[authorize]: module 
"preprocess" returns ok for request 2
Fri Sep  8 12:37:40 2006 : Debug:   modsingle[authorize]: calling chap 
(rlm_chap) for request 2
Fri Sep  8 12:37:40 2006 : Debug:   modsingle[authorize]: returned from 
chap (rlm_chap) for request 2
Fri Sep  8 12:37:40 2006 : Debug:   modcall[authorize]: module "chap" 
returns noop for request 2
Fri Sep  8 12:37:40 2006 : Debug:   modsingle[authorize]: calling 
realmsuffix (rlm_realm) for request 2
Fri Sep  8 12:37:40 2006 : Debug:     rlm_realm: Looking up realm 
"domain.com" for User-Name = "bob@domain.com"
Fri Sep  8 12:37:40 2006 : Debug:     rlm_realm: Found realm "domain.com"
Fri Sep  8 12:37:40 2006 : Debug:     rlm_realm: Adding 
Stripped-User-Name = "bob"
Fri Sep  8 12:37:40 2006 : Debug:     rlm_realm: Proxying request from 
user bob to realm domain.com
Fri Sep  8 12:37:40 2006 : Debug:     rlm_realm: Adding Realm = "domain.com"
Fri Sep  8 12:37:40 2006 : Debug:     rlm_realm: Preparing to proxy 
authentication request to realm "domain.com"
Fri Sep  8 12:37:40 2006 : Debug:   modsingle[authorize]: returned from 
realmsuffix (rlm_realm) for request 2
Fri Sep  8 12:37:40 2006 : Debug:   modcall[authorize]: module 
"realmsuffix" returns updated for request 2
Fri Sep  8 12:37:40 2006 : Debug:   modsingle[authorize]: calling files 
(rlm_files) for request 2
Fri Sep  8 12:37:40 2006 : Debug:     users: Matched entry DEFAULT at 
line 54
Fri Sep  8 12:37:40 2006 : Debug:     users: Matched entry DEFAULT at 
line 72
Fri Sep  8 12:37:40 2006 : Debug:   modsingle[authorize]: returned from 
files (rlm_files) for request 2
Fri Sep  8 12:37:40 2006 : Debug:   modcall[authorize]: module "files" 
returns ok for request 2
Fri Sep  8 12:37:40 2006 : Debug:   modsingle[authorize]: calling 
monthlycounter (rlm_sqlcounter) for request 2
Fri Sep  8 12:37:40 2006 : Debug: rlm_sqlcounter: Entering module 
authorize code
Fri Sep  8 12:37:40 2006 : Debug: rlm_sqlcounter: Could not find Check 
item value pair
Fri Sep  8 12:37:40 2006 : Debug:   modsingle[authorize]: returned from 
monthlycounter (rlm_sqlcounter) for request 2
Fri Sep  8 12:37:40 2006 : Debug:   modcall[authorize]: module 
"monthlycounter" returns noop for request 2
Fri Sep  8 12:37:40 2006 : Debug: modcall: leaving group authorize 
(returns updated) for request 2
Fri Sep  8 12:37:40 2006 : Debug: Cancelling proxy as request was 
already rejected
Fri Sep  8 12:37:40 2006 : Debug: Request 2 rejected in proxy_send.
Fri Sep  8 12:37:40 2006 : Debug: Server rejecting request 2.
Sending Access-Reject of id 3 to xx.xx.xx.xx port 4587
         Reply-Message = "Your account has been disabled."
Fri Sep  8 12:37:40 2006 : Debug: Finished request 2
Fri Sep  8 12:37:40 2006 : Debug: Going to the next request
Fri Sep  8 12:37:40 2006 : Debug: --- Walking the entire request list ---
Fri Sep  8 12:37:40 2006 : Debug: Waking up in 6 seconds...
Fri Sep  8 12:37:46 2006 : Debug: --- Walking the entire request list ---
Fri Sep  8 12:37:46 2006 : Debug: Cleaning up request 2 ID 3 with 
timestamp 4501aa64
Fri Sep  8 12:37:46 2006 : Debug: Nothing to do.  Sleeping until we see 
a request.


------------------ radiusd.conf ---------------------

prefix = /usr/local
exec_prefix = ${prefix}
sysconfdir = ${prefix}/etc
localstatedir = ${prefix}/var
sbindir = ${exec_prefix}/sbin
logdir = ${localstatedir}/log/radius
raddbdir = ${sysconfdir}/raddb
radacctdir = ${logdir}/radacct
confdir = ${raddbdir}
run_dir = ${localstatedir}/run/radiusd
log_file = ${logdir}/radius.log
libdir = ${exec_prefix}/lib
pidfile = ${run_dir}/radiusd.pid
user = root
group = radius
max_request_time = 30
delete_blocked_requests = no
cleanup_delay = 5
max_requests = 8000
bind_address = xx.xx.xx.xx
port = 0
hostname_lookups = no
allow_core_dumps = no
regular_expressions	= yes
extended_expressions	= yes
log_stripped_names = no
log_auth = yes
log_auth_badpass = yes
log_auth_goodpass = yes
usercollide = no
lower_user = no
lower_pass = no
nospace_user = no
nospace_pass = no
checkrad = ${sbindir}/checkrad
security {
	max_attributes = 200
	reject_delay = 0
	status_server = no
}
proxy_requests  = yes
$INCLUDE  ${confdir}/proxy.conf
$INCLUDE  ${confdir}/clients.conf
snmp	= yes
$INCLUDE  ${confdir}/snmp.conf
thread pool {
	start_servers = 20
	max_servers = 128
	min_spare_servers = 10
	max_spare_servers = 30
	max_requests_per_server = 300
}
modules {
	pap {
		encryption_scheme = crypt
	}
	chap {
		authtype = CHAP
	}
	pam {
		pam_auth = radiusd
	}
	unix {
		cache = no
		cache_reload = 600
		radwtmp = ${logdir}/radwtmp
	}
$INCLUDE ${confdir}/eap.conf
	mschap {
	}
	ldap {
		server = "ldap.your.domain"
		basedn = "o=My Org,c=UA"
		filter = "(uid=%{Stripped-User-Name:-%{User-Name}})"
		start_tls = no
		access_attr = "dialupAccess"
		dictionary_mapping = ${raddbdir}/ldap.attrmap
		ldap_connections_number = 5
		timeout = 4
		timelimit = 3
		net_timeout = 1
	}
	realm IPASS {
		format = prefix
		delimiter = "/"
		ignore_default = no
		ignore_null = no
	}
	realm realmsuffix {
		format = suffix
		delimiter = "@"
		ignore_default = no
		ignore_null = no
	}
	realm realmpercent {
		format = suffix
		delimiter = "%"
		ignore_default = no
		ignore_null = no
	}
	realm ntdomain {
		format = prefix
		delimiter = "\\"
		ignore_default = no
		ignore_null = no
	}	
	checkval {
		item-name = Calling-Station-Id
		check-name = Calling-Station-Id
		data-type = string
	}
	
	preprocess {
		huntgroups = ${confdir}/huntgroups
		hints = ${confdir}/hints
		with_ascend_hack = no
		ascend_channels_per_line = 23
		with_ntdomain_hack = no
		with_specialix_jetstream_hack = no
		with_cisco_vsa_hack = no
	}
	files {
		usersfile = ${confdir}/users
		acctusersfile = ${confdir}/acct_users
		preproxy_usersfile = ${confdir}/preproxy_users
		compat = no
	}
	detail {
		detailfile = ${radacctdir}/%{Client-IP-Address}/detail
		detailperm = 0660
	}
	acct_unique {
		key = "User-Name, Acct-Session-Id, NAS-IP-Address, Client-IP-Address, 
NAS-Port"
	}
	$INCLUDE  ${confdir}/sql.conf
	
	radutmp {
		filename = ${logdir}/radutmp
		username = %{Stripped-User-Name:-%{User-Name}}
		case_sensitive = no
		check_with_nas = yes		
		perm = 0600
		callerid = "yes"
	}
	radutmp sradutmp {
		filename = ${logdir}/sradutmp
		perm = 0644
		callerid = "no"
	}
	attr_filter {
		attrsfile = ${confdir}/attrs
	}
	counter daily {
		filename = ${raddbdir}/db.daily
		key = User-Name
		count-attribute = Acct-Session-Time
		reset = daily
		counter-name = Daily-Session-Time
		check-name = Max-Daily-Session
		allowed-servicetype = Framed-User
		cache-size = 5000
	}
	sqlcounter dailycounter {
		counter-name = Daily-Session-Time
		check-name = Max-Daily-Session
		sqlmod-inst = sql
		key = User-Name
		reset = daily
		query = "SELECT SUM(AcctSessionTime - \
                  GREATEST((%b - UNIX_TIMESTAMP(AcctStartTime)), 0)) \
                  FROM radacct WHERE UserName='%{%k}' AND \
                  UNIX_TIMESTAMP(AcctStartTime) + AcctSessionTime > '%b'"
	}
	sqlcounter monthlycounter {
		counter-name = Monthly-Session-Time
		check-name = Max-Monthly-Session
		sqlmod-inst = sql
		key = User-Name
		reset = monthly
		query = "SELECT SUM(AcctSessionTime - \
                  GREATEST((%b - UNIX_TIMESTAMP(AcctStartTime)), 0)) \
                  FROM radacct WHERE UserName='%{%k}' AND \
                  UNIX_TIMESTAMP(AcctStartTime) + AcctSessionTime > '%b'"
	}
	always fail {
		rcode = fail
	}
	always reject {
		rcode = reject
	}
	always ok {
		rcode = ok
		simulcount = 0
		mpp = no
	}
	expr {
	}
	digest {
	}
	exec {
		wait = yes
		input_pairs = request
	}
	exec echo {
		wait = yes
		program = "/bin/echo %{User-Name}"
		input_pairs = request
		output_pairs = reply
	}
	ippool main_pool {
		range-start = 192.168.1.1
		range-stop = 192.168.3.254
		netmask = 255.255.255.0
		cache-size = 800
		session-db = ${raddbdir}/db.ippool
		ip-index = ${raddbdir}/db.ipindex
		override = no
		maximum-timeout = 0
	}
}
instantiate {
	monthlycounter
}
authorize {
	preprocess
	chap
	realmsuffix
	files
	monthlycounter
}
authenticate {
	unix
}
preacct {
	preprocess
	acct_unique
	realmsuffix
	files
}
accounting {
	detail
	sql
}
session {
	sql
}
post-auth {
}
pre-proxy {
}
post-proxy {
}

-------------------- proxy.conf --------------------

proxy server {
	synchronous = no
	retry_delay = 5
	retry_count = 3
	dead_time = 120
	default_fallback = no
	post_proxy_authorize = no
}
realm LOCAL {
	type		= radius
	authhost	= LOCAL
	accthost	= LOCAL
}
realm domain.com {
	type		= radius
	authhost	= xx.xx.xx.xx:1645
	accthost	= xx.xx.xx.xx:1646
	secret		= KeyKeyKey
	strip
}
realm NULL {
	type		= radius
	authhost	= LOCAL
	accthost	= LOCAL
}

Re: Proxy problem in FreeRADIUS 1.1.3

Chris A. Kalin