Hi,
I have now managed to authenticate to an LDAP server by not using the
non-user ID just a plain user.
I now want to get the group membership for a user.
I have followed the example by Richard Green but do not seem to get any
results back.
The various config file entries and logs are listed below:
LDIF
---------------------------------------------------------------------------
dn: cn=public,ou=groups,ou=system
objectClass: groupOfNames
objectClass: top
cn: public
member: cn=test, cn=billy
dn: cn=fedora,ou=groups,ou=system
objectClass: groupOfNames
objectClass: top
cn: fedora
member: cn=test
dn: cn=test,ou=users,ou=system
objectClass: person
objectClass: top
cn: test
sn: ryan
description: a test LDAP user
userPassword:: dGVzdA==
---------------------------------------------------------------------------
WEB XML
---------------------------------------------------------------------------
<filter-name>LdapFilterForAttributes</filter-name>
<filter-class>fedora.server.security.servletfilters.ldap.FilterLdap</filter-class>
<init-param>
<param-name>version</param-name>
<param-value>3</param-value>
</init-param>
<init-param>
<param-name>authenticate</param-name>
<param-value>true</param-value>
</init-param>
<init-param>
<param-name>url</param-name>
<param-value>ldap://localhost:10389</param-value>
</init-param>
<init-param>
<param-name>search-base</param-name>
<param-value>ou=users,ou=system</param-value>
</init-param>
<init-param>
<param-name>search-filter</param-name>
<param-value>(cn={0})</param-value>
</init-param>
<init-param>
<param-name>bind-filter</param-name>
<param-value>cn={0},ou=users,ou=system</param-value>
</init-param>
<init-param>
<param-name>id-attribute</param-name>
<param-value>cn</param-value>
</init-param>
<init-param>
<param-name>attributes</param-name>
<param-value>cn,userPassword,description</param-value>
</init-param>
<init-param>
<param-name>security-authentication</param-name>
<param-value>simple</param-value>
</init-param>
<!--<init-param>
<param-name>security-principal</param-name>
<param-value>commonName=ben, ou=users, ou=system</param-value>
</init-param>
<init-param>
<param-name>security-credentials</param-name>
<param-value>42mara86</param-value>
</init-param>
<init-param>
<param-name>password-attribute</param-name>
<param-value>userPassword</param-value>
</init-param>-->
</filter>
<filter>
<filter-name>LdapFilterForGroups</filter-name>
<filter-class>fedora.server.security.servletfilters.ldap.FilterLdap</filter-class>
<init-param>
<param-name>associated-filters</param-name>
<param-value>LdapFilterForAttributes,LdapFilterForGroups</param-value>
</init-param>
<init-param>
<param-name>version</param-name>
<param-value>3</param-value>
</init-param>
<init-param>
<param-name>authenticate</param-name>
<param-value>false</param-value>
</init-param>
<init-param>
<param-name>security-authentication</param-name>
<param-value>simple</param-value>
</init-param>
<init-param>
<param-name>id-attribute</param-name>
<param-value>cn</param-value>
</init-param>
<init-param>
<param-name>bind-filter</param-name>
<param-value>cn={0},ou=users,ou=system</param-value>
</init-param>
<init-param>
<param-name>url</param-name>
<param-value>ldap://localhost:10389</param-value>
</init-param>
<init-param>
<param-name>search-base</param-name>
<param-value>ou=groups,ou=system</param-value>
</init-param>
<init-param>
<param-name>search-filter</param-name>
<param-value>(member=cn={0},ou=groups,ou=system)</param-value>
</init-param>
<init-param>
<param-name>attributes</param-name>
<param-value>member</param-value>
</init-param>
<init-param>
<param-name>attributes-common-name</param-name>
<param-value>groups</param-value>
</init-param>
</filter>
---------------------------------------------------------------------------
FEDORA LOG
---------------------------------------------------------------------------
DEBUG 2010-07-30 12:26:07.703 [http-8080-Processor25] (FilterSetup)
FilterLdap.doFilter() . . .
DEBUG 2010-07-30 12:26:07.703 [http-8080-Processor25] (FilterSetup)
FilterLdap.doFilter() : XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX
DEBUG 2010-07-30 12:26:07.703 [http-8080-Processor25] (FilterSetup)
FilterLdap.doFilter() : FILTER_NAME LdapFilterForAttributes==
DEBUG 2010-07-30 12:26:07.703 [http-8080-Processor25] (FilterSetup)
FilterLdap.doFilter() : using existing request...
DEBUG 2010-07-30 12:26:07.703 [http-8080-Processor25] (FilterSetup)
FilterLdap.doFilter() : passed HttpServletResponse test
DEBUG 2010-07-30 12:26:07.703 [http-8080-Processor25] (BaseContributing)
FilterLdap.doThisSubclass() . . .
DEBUG 2010-07-30 12:26:07.703 [http-8080-Processor25] (FilterSetup)
FilterLdap.doThisSubclass() . . .
DEBUG 2010-07-30 12:26:07.703 [http-8080-Processor25] (FilterSetup)
FilterLdap.doThisSubclass() : passed init test
DEBUG 2010-07-30 12:26:07.703 [http-8080-Processor25] (FilterSetup)
FilterLdap.doThisSubclass() : passed HttpServletRequest test
DEBUG 2010-07-30 12:26:07.703 [http-8080-Processor25] (FilterSetup)
FilterLdap.. . . doThisSubclass()
DEBUG 2010-07-30 12:26:07.703 [http-8080-Processor25] (BaseContributing)
FilterLdap.doThisSubclass() : alreadyAuthenticated==false
DEBUG 2010-07-30 12:26:07.703 [http-8080-Processor25] (BaseContributing)
FilterLdap.doThisSubclass() : AUTHENTICATE==true
DEBUG 2010-07-30 12:26:07.703 [http-8080-Processor25] (BaseContributing)
FilterLdap.doThisSubclass() : calling authenticate() . . .
DEBUG 2010-07-30 12:26:07.703 [http-8080-Processor25] (BaseCaching)
FilterLdap.authenticate() . . .
DEBUG 2010-07-30 12:26:07.703 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) username==null, so will grok now
DEBUG 2010-07-30 12:26:07.703 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) getAuthorizationHeader()
DEBUG 2010-07-30 12:26:07.703 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) getting this headers
DEBUG 2010-07-30 12:26:07.703 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) another headername==authorization
DEBUG 2010-07-30 12:26:07.703 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) another headervalue==Basic dGVzdDp0ZXN0
DEBUG 2010-07-30 12:26:07.703 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) another headername==user-agent
DEBUG 2010-07-30 12:26:07.703 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) another headervalue==Jakarta
Commons-HttpClient/3.1
DEBUG 2010-07-30 12:26:07.703 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) another headername==host
DEBUG 2010-07-30 12:26:07.703 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) another headervalue==localhost:8080
DEBUG 2010-07-30 12:26:07.703 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) getting super headers
DEBUG 2010-07-30 12:26:07.703 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) another headername==authorization
DEBUG 2010-07-30 12:26:07.703 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) another headervalue==Basic dGVzdDp0ZXN0
DEBUG 2010-07-30 12:26:07.703 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) another headername==user-agent
DEBUG 2010-07-30 12:26:07.703 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) another headervalue==Jakarta
Commons-HttpClient/3.1
DEBUG 2010-07-30 12:26:07.703 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) another headername==host
DEBUG 2010-07-30 12:26:07.703 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) another headervalue==localhost:8080
DEBUG 2010-07-30 12:26:07.703 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) authorizationHeader==Basic dGVzdDp0ZXN0
DEBUG 2010-07-30 12:26:07.703 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) authorizationHeader is intact
DEBUG 2010-07-30 12:26:07.703 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) parseUsernamePassword():header
intactsucceeded
DEBUG 2010-07-30 12:26:07.703 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) parseUsernamePassword():header
splitsucceeded
DEBUG 2010-07-30 12:26:07.703 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) parseUsernamePassword():auth schemesucceeded
DEBUG 2010-07-30 12:26:07.703 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) parseUsernamePassword():digest
non-nullsucceeded, usernamepassword==dGVzdDp0ZXN0
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) parseUsernamePassword():digest
base64-encodedsucceeded, encoded=...@156e0b4
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) parseUsernamePassword():got decoded
bytessucceeded, decodedasbytearray=...@ba8180
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) parseUsernamePassword():got decoded
stringsucceeded, decoded==test:test
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) parseUsernamePassword():digest
decodedsucceeded
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) parseUsernamePassword():user/password
splitsucceeded
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) usernamePassword[] length==2
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) username (usernamePassword[0])==test
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) had none before
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) return user==test
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25] (BaseCaching)
FilterLdap.authenticate(): userid==test
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) getAuthorizationHeader()
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) getting this headers
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) another headername==authorization
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) another headervalue==Basic dGVzdDp0ZXN0
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) another headername==user-agent
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) another headervalue==Jakarta
Commons-HttpClient/3.1
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) another headername==host
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) another headervalue==localhost:8080
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) getting super headers
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) another headername==authorization
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) another headervalue==Basic dGVzdDp0ZXN0
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) another headername==user-agent
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) another headervalue==Jakarta
Commons-HttpClient/3.1
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) another headername==host
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) another headervalue==localhost:8080
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) parseUsernamePassword():header
intactsucceeded
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) parseUsernamePassword():header
splitsucceeded
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) parseUsernamePassword():auth schemesucceeded
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) parseUsernamePassword():digest
non-nullsucceeded, usernamepassword==dGVzdDp0ZXN0
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) parseUsernamePassword():digest
base64-encodedsucceeded, encoded=...@8c2d23
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) parseUsernamePassword():got decoded
bytessucceeded, decodedasbytearray=...@e776f7
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) parseUsernamePassword():got decoded
stringsucceeded, decoded==test:test
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) parseUsernamePassword():digest
decodedsucceeded
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) parseUsernamePassword():user/password
splitsucceeded
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) return password==test
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25] (BaseCaching)
FilterLdap.authenticate(): password==test
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25] (BaseCaching)
FilterLdap.. . . getCache() . . .
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25] (BaseCaching)
FilterLdap.authenticate(): calling cache.authenticate()
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25] (Cache) asserts are not
turned on
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25] (Cache) A authenticate()
----------------------------------------------
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25] (Cache) A authenticate()
> LdapFilterForAttributes [test] [test]
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25] (Cache) A
getCacheElement() keytemp==test
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25] (Cache) A
getCacheElement() key==3556498
DEBUG 2010-07-30 12:26:07.734 [http-8080-Processor25] (Cache) A
getCacheElement() cache does not have element; create and put
DEBUG 2010-07-30 12:26:07.750 [http-8080-Processor25] (Cache) A
getCacheElement() element retrieved from cache successfully
DEBUG 2010-07-30 12:26:07.750 [http-8080-Processor25] (Cache) A authenticate()
cacheElement==161b0bc
DEBUG 2010-07-30 12:26:07.750 [http-8080-Processor25] (CacheElement) A
authenticate() >
DEBUG 2010-07-30 12:26:07.750 [http-8080-Processor25] (CacheElement) A
authenticate() m_valid==false
DEBUG 2010-07-30 12:26:07.750 [http-8080-Processor25] (CacheElement) A
authenticate() expired or invalid, so try to repopulate
DEBUG 2010-07-30 12:26:07.750 [http-8080-Processor25] (FilterSetup)
>>>>>>>>>>>>>>>>>>LdapFilterForAttributes
DEBUG 2010-07-30 12:26:07.750 [http-8080-Processor25] (FilterLdap) A
populateCacheElement() >
DEBUG 2010-07-30 12:26:07.750 [http-8080-Processor25] (FilterLdap) A
populateCacheElement() about to call getNamingEnumeration()
DEBUG 2010-07-30 12:26:07.750 [http-8080-Processor25] (FilterSetup)
>>>>>>>>>>>>>>>>>>LdapFilterForAttributes
DEBUG 2010-07-30 12:26:07.750 [http-8080-Processor25] (FilterLdap) A
getFilter() >
DEBUG 2010-07-30 12:26:07.750 [http-8080-Processor25] (FilterLdap) A
getFilter() < (cn=test)
DEBUG 2010-07-30 12:26:07.750 [http-8080-Processor25] (FilterSetup)
>>>>>>>>>>>>>>>>>>LdapFilterForAttributes
DEBUG 2010-07-30 12:26:07.750 [http-8080-Processor25] (FilterLdap) A
getSearchControls() >
DEBUG 2010-07-30 12:26:07.750 [http-8080-Processor25] (FilterLdap) A
getSearchControls() <
javax.naming.directory.searchcontr...@9a41d2<mailto:javax.naming.directory.searchcontr...@9a41d2>
DEBUG 2010-07-30 12:26:07.750 [http-8080-Processor25] (FilterSetup)
>>>>>>>>>>>>>>>>>>LdapFilterForAttributes
DEBUG 2010-07-30 12:26:07.750 [http-8080-Processor25] (FilterLdap) A
getEnvironment() ldap explicit version==3
DEBUG 2010-07-30 12:26:07.750 [http-8080-Processor25] (FilterLdap) A
getEnvironment() ldap version==3
DEBUG 2010-07-30 12:26:07.750 [http-8080-Processor25] (FilterLdap) A
getEnvironment() ldap url==ldap://localhost:10389
DEBUG 2010-07-30 12:26:07.750 [http-8080-Processor25] (FilterLdap) A
getEnvironment() binding for real user
DEBUG 2010-07-30 12:26:07.750 [http-8080-Processor25] (FilterSetup)
>>>>>>>>>>>>>>>>>>LdapFilterForAttributes
DEBUG 2010-07-30 12:26:07.750 [http-8080-Processor25] (FilterLdap) A
applyFilter() result==cn={0},ou=users,ou=system
DEBUG 2010-07-30 12:26:07.750 [http-8080-Processor25] (FilterLdap) A
applyFilter() regex ==\{0\}
DEBUG 2010-07-30 12:26:07.750 [http-8080-Processor25] (FilterLdap) A
applyFilter() arg ==test
DEBUG 2010-07-30 12:26:07.750 [http-8080-Processor25] (FilterLdap) A
applyFilter() result==cn=test,ou=users,ou=system
DEBUG 2010-07-30 12:26:07.750 [http-8080-Processor25] (FilterLdap) A
getEnvironment() bind w simple
DEBUG 2010-07-30 12:26:07.750 [http-8080-Processor25] (FilterLdap) A
getEnvironment() user== cn=test,ou=users,ou=system
DEBUG 2010-07-30 12:26:07.750 [http-8080-Processor25] (FilterLdap) A
getEnvironment() passwd==test
DEBUG 2010-07-30 12:26:07.750 [http-8080-Processor25] (FilterLdap) A
getEnvironment() < {java.naming.provider.url=ldap://localhost:10389,
java.naming.factory.initial=com.sun.jndi.ldap.LdapCtxFactory,
java.naming.ldap.version=3,
java.naming.security.principal=cn=test,ou=users,ou=system,
java.naming.security.authentication=simple,
java.naming.security.credentials=test}
DEBUG 2010-07-30 12:26:07.750 [http-8080-Processor25] (FilterSetup)
>>>>>>>>>>>>>>>>>>LdapFilterForAttributes
DEBUG 2010-07-30 12:26:07.750 [http-8080-Processor25] (FilterLdap) A
getNamingEnumeration() >
INFO 2010-07-30 12:26:07.750 [http-8080-Processor25] (FilterLdap) A
getNamingEnumeration() -binding- individual user
DEBUG 2010-07-30 12:26:07.750 [http-8080-Processor25] (FilterSetup)
>>>>>>>>>>>>>>>>>>LdapFilterForAttributes
DEBUG 2010-07-30 12:26:07.750 [http-8080-Processor25] (FilterLdap) A
getNamingEnumeration() >
DEBUG 2010-07-30 12:26:07.781 [http-8080-Processor25] (FilterLdap) A
getNamingEnumeration() <
com.sun.jndi.ldap.ldapsearchenumerat...@1ba5016<mailto:com.sun.jndi.ldap.ldapsearchenumerat...@1ba5016>
DEBUG 2010-07-30 12:26:07.781 [http-8080-Processor25] (FilterLdap) A
getNamingEnumeration() enumeration has elements
DEBUG 2010-07-30 12:26:07.781 [http-8080-Processor25] (FilterLdap) A
getNamingEnumeration() <
com.sun.jndi.ldap.ldapsearchenumerat...@1ba5016<mailto:com.sun.jndi.ldap.ldapsearchenumerat...@1ba5016>
DEBUG 2010-07-30 12:26:07.781 [http-8080-Processor25] (FilterLdap) A
populateCacheElement() got expected non-null ne, no exception thrown
DEBUG 2010-07-30 12:26:07.781 [http-8080-Processor25] (FilterLdap) A
populateCacheElement() about to call processNamingEnumeration()
DEBUG 2010-07-30 12:26:07.781 [http-8080-Processor25] (FilterSetup)
>>>>>>>>>>>>>>>>>>LdapFilterForAttributes
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (FilterLdap) A
processNamingEnumeration() >
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (FilterLdap) A
processNamingEnumeration() another element
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (FilterLdap) A
processNamingEnumeration() got a javax.naming.directory.SearchResult
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (FilterSetup)
>>>>>>>>>>>>>>>>>>LdapFilterForAttributes
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (FilterLdap) A
getAttributes() >
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (FilterLdap) A
getAttributes() looking for return attribute==cn
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (FilterLdap) A
getAttributes() making+storing a value-set for attribute==cn
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (FilterLdap) A
getAttributes() object with n==1
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (FilterLdap) A
getAttributes() added value==test, class==java.lang.String
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (FilterLdap) A
getAttributes() looking for return attribute==userPassword
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (FilterLdap) A
getAttributes() making+storing a value-set for attribute==userPassword
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (FilterLdap) A
getAttributes() object with n==1
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (FilterLdap) A
getAttributes() added value=...@1e21540, class==[B
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (FilterLdap) A
getAttributes() looking for return attribute==description
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (FilterLdap) A
getAttributes() making+storing a value-set for attribute==description
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (FilterLdap) A
getAttributes() object with n==1
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (FilterLdap) A
getAttributes() added value==a test LDAP user, class==java.lang.String
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (FilterLdap) A
getAttributes() <
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (FilterLdap) A
processNamingEnumeration() < authenticated==true
map=={userpassword=...@1e21540], description=[a test LDAP user], cn=[test]}
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (FilterLdap) A
populateCacheElement() back from pNE. AUTHENTICATE==true authenticated==true
map=={userpassword=...@1e21540], description=[a test LDAP user], cn=[test]}
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (FilterLdap) A
populateCacheElement() authenticated.booleanValue()==true
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (FilterLdap) A
populateCacheElement() map.isEmpty()==false
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (FilterLdap) A
populateCacheElement() before catch
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (FilterLdap) A
populateCacheElement() in finally, authenticated==true
map=={userpassword=...@1e21540], description=[a test LDAP user], cn=[test]}
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (CacheElement) A
populate() >
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (CacheElement) A
populate() <
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (FilterLdap) A
populateCacheElement() <
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (CacheElement) A
authenticate() populate completed
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (CacheElement) A
authenticate() populate succeeded
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (CacheElement) -
calcExpiration(int,int) >
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (CacheElement) -
calcExpiration(int,int) expires in 0 days 00:10:00.000
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (CacheElement) -
calcExpiration(int,int) < 2010-07-30 12:36:07.796
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (CacheElement) A audit()
> LdapFilterForAttributes 161b0bc @ 2010-07-30 12:26:07.796
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (CacheElement) A audit()
valid==true
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (CacheElement) A audit()
userid==test
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (CacheElement) A audit()
password==test
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (CacheElement) A audit()
authenticated==true
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (CacheElement) A audit()
errorMessage==null
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (CacheElement) A audit()
expiration==2010-07-30 12:36:07.796
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (CacheElement) A audit()
expires in 0 days 00:10:00.000
ERROR 2010-07-30 12:26:07.796 [http-8080-Processor25] (CacheElement) A audit()
set member not string, =...@1e21540
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (CacheElement) A audit()
userPassword==(1) {}
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (CacheElement) A audit()
description==(1) {a test LDAP user}
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (CacheElement) A audit()
cn==(1) {test}
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (CacheElement) A audit() <
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (CacheElement) A
authenticate() < true
DEBUG 2010-07-30 12:26:07.796 [http-8080-Processor25] (Cache) A authenticate()
< true
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (BaseCaching)
FilterLdap.authenticate(): set authenticated
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (BaseCaching)
FilterLdap.authenticate(): calling audit user==test
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (Cache) A
getCacheElement() keytemp==test
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (Cache) A
getCacheElement() key==3556498
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (Cache) A
getCacheElement() cache already has element
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (Cache) A
getCacheElement() element retrieved from cache successfully
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A audit()
> LdapFilterForAttributes 161b0bc @ 2010-07-30 12:26:07.812
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A audit()
valid==true
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A audit()
userid==test
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A audit()
password==test
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A audit()
authenticated==true
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A audit()
errorMessage==null
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A audit()
expiration==2010-07-30 12:36:07.796
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A audit()
expires in 0 days 00:09:59.984
ERROR 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A audit()
set member not string, =...@1e21540
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A audit()
userPassword==(1) {}
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A audit()
description==(1) {a test LDAP user}
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A audit()
cn==(1) {test}
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A audit() <
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (BaseCaching)
FilterLdap.. . . authenticate()
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (BaseContributing)
FilterLdap.doThisSubclass() : authority==LdapFilterForAttributes
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (BaseContributing)
FilterLdap.doThisSubclass() : user not already sponsored
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (BaseContributing)
FilterLdap.doThisSubclass() : calling gatherAuthenticatedAttributes() . . .
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (BaseCaching)
FilterLdap.gatherAuthenticatedAttributes() . . .
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) return user==test
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) return password==test
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (BaseCaching)
FilterLdap.gatherAttributes() . . .
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (BaseCaching)
FilterLdap.. . . getCache() . . .
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (BaseCaching)
FilterLdap.gatherAttributes(): calling cache.getNamedValues()
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (Cache) A
getNamedValues() ----------------------------------------------
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (Cache) A
getNamedValues() > LdapFilterForAttributes [test] [test]
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (Cache) A
getCacheElement() keytemp==test
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (Cache) A
getCacheElement() key==3556498
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (Cache) A
getCacheElement() cache already has element
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (Cache) A
getCacheElement() element retrieved from cache successfully
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (Cache) A
getNamedValues() cacheElement==161b0bc
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A
namedValues >
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A
namedValues valid==true
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) -
isExpired() >
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) -
isExpired() now==2010-07-30 12:26:07.812
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) -
isExpired() exp==2010-07-30 12:36:07.796
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) -
isExpired() expires in 0 days 00:09:59.984
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) -
isExpired() < false
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A
namedValues valid and not expired, so use
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A audit()
> LdapFilterForAttributes 161b0bc @ 2010-07-30 12:26:07.812
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A audit()
valid==true
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A audit()
userid==test
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A audit()
password==test
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A audit()
authenticated==true
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A audit()
errorMessage==null
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A audit()
expiration==2010-07-30 12:36:07.796
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A audit()
expires in 0 days 00:09:59.984
ERROR 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A audit()
set member not string, =...@1e21540
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A audit()
userPassword==(1) {}
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A audit()
description==(1) {a test LDAP user}
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A audit()
cn==(1) {test}
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A audit() <
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A
namedValues < {userpassword=...@1e21540], description=[a test LDAP user],
cn=[test]}
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (Cache) A
getNamedValues() < {userpassword=...@1e21540], description=[a test LDAP user],
cn=[test]}
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) mapping LdapFilterForAttributes =>
{userpassword=...@1e21540], description=[a test LDAP user], cn=[test]} in {}
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (BaseCaching)
FilterLdap.gatherAttributes(): gatherAttributes calling audit
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (Cache) A
getCacheElement() keytemp==test
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (Cache) A
getCacheElement() key==3556498
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (Cache) A
getCacheElement() cache already has element
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (Cache) A
getCacheElement() element retrieved from cache successfully
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A audit()
> LdapFilterForAttributes 161b0bc @ 2010-07-30 12:26:07.812
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A audit()
valid==true
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A audit()
userid==test
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A audit()
password==test
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A audit()
authenticated==true
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A audit()
errorMessage==null
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A audit()
expiration==2010-07-30 12:36:07.796
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A audit()
expires in 0 days 00:09:59.984
ERROR 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A audit()
set member not string, =...@1e21540
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A audit()
userPassword==(1) {}
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A audit()
description==(1) {a test LDAP user}
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A audit()
cn==(1) {test}
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (CacheElement) A audit() <
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (BaseCaching)
FilterLdap.gatherAttributes(): at end of gatherAttributes
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (BaseCaching)
FilterLdap.. . . gatherAttributes()
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (BaseCaching)
FilterLdap.. . . gatherAuthenticatedAttributes()
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (BaseContributing)
FilterLdap.doThisSubclass() : no surrogate role configured
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (BaseContributing)
FilterLdap.doThisSubclass() : no surrogate attribute configured
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (FilterSetup)
FilterLdap.doFilter() : before next doFilter()
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (FilterSetup)
FilterLdap.doFilter() :
extendedhttpservletrequest==fedora.server.security.servletfilters.extendedhttpservletrequestwrap...@1ec4535<mailto:extendedhttpservletrequest==fedora.server.security.servletfilters.extendedhttpservletrequestwrap...@1ec4535>
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (FilterSetup)
FilterLdap.doFilter() : extendedHttpServletRequest
fedora.server.security.servletfilters.ExtendedHttpServletRequestWrapper==
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (FilterSetup)
FilterLdap.doFilter() :
responseorg.apache.catalina.connector.responsefac...@b3a5d1<mailto:responseorg.apache.catalina.connector.responsefac...@b3a5d1>==
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (FilterSetup)
FilterLdap.doFilter() . . .
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (FilterSetup)
FilterLdap.doFilter() : XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (FilterSetup)
FilterLdap.doFilter() : FILTER_NAME LdapFilterForGroups==
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (FilterSetup)
FilterLdap.doFilter() : using existing request...
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (FilterSetup)
FilterLdap.doFilter() : passed HttpServletResponse test
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (BaseContributing)
FilterLdap.doThisSubclass() . . .
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (FilterSetup)
FilterLdap.doThisSubclass() . . .
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (FilterSetup)
FilterLdap.doThisSubclass() : passed init test
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (FilterSetup)
FilterLdap.doThisSubclass() : passed HttpServletRequest test
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (FilterSetup)
FilterLdap.. . . doThisSubclass()
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (BaseContributing)
FilterLdap.doThisSubclass() : alreadyAuthenticated==true
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (BaseContributing)
FilterLdap.doThisSubclass() : AUTHENTICATE==false
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (BaseContributing)
FilterLdap.doThisSubclass() : not calling authenticate()
DEBUG 2010-07-30 12:26:07.812 [http-8080-Processor25] (BaseContributing)
FilterLdap.doThisSubclass() : authority==LdapFilterForAttributes
DEBUG 2010-07-30 12:26:07.828 [http-8080-Processor25] (BaseContributing)
FilterLdap.doThisSubclass() : user not already sponsored
DEBUG 2010-07-30 12:26:07.828 [http-8080-Processor25] (BaseContributing)
FilterLdap.doThisSubclass() : calling gatherAuthenticatedAttributes() . . .
DEBUG 2010-07-30 12:26:07.828 [http-8080-Processor25] (BaseCaching)
FilterLdap.gatherAuthenticatedAttributes() . . .
DEBUG 2010-07-30 12:26:07.828 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) return user==test
DEBUG 2010-07-30 12:26:07.828 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) return password==test
DEBUG 2010-07-30 12:26:07.828 [http-8080-Processor25] (BaseCaching)
FilterLdap.gatherAttributes() . . .
DEBUG 2010-07-30 12:26:07.828 [http-8080-Processor25] (BaseCaching)
FilterLdap.. . . getCache() . . .
DEBUG 2010-07-30 12:26:07.828 [http-8080-Processor25] (BaseCaching)
FilterLdap.gatherAttributes(): calling cache.getNamedValues()
DEBUG 2010-07-30 12:26:07.828 [http-8080-Processor25] (Cache) G
getNamedValues() ----------------------------------------------
DEBUG 2010-07-30 12:26:07.828 [http-8080-Processor25] (Cache) G
getNamedValues() > LdapFilterForGroups [test] [test]
DEBUG 2010-07-30 12:26:07.828 [http-8080-Processor25] (Cache) G
getCacheElement() keytemp==test
DEBUG 2010-07-30 12:26:07.828 [http-8080-Processor25] (Cache) G
getCacheElement() key==3556498
DEBUG 2010-07-30 12:26:07.828 [http-8080-Processor25] (Cache) G
getCacheElement() cache does not have element; create and put
DEBUG 2010-07-30 12:26:07.828 [http-8080-Processor25] (Cache) G
getCacheElement() element retrieved from cache successfully
DEBUG 2010-07-30 12:26:07.828 [http-8080-Processor25] (Cache) G
getNamedValues() cacheElement==b03512
DEBUG 2010-07-30 12:26:07.843 [http-8080-Processor25] (CacheElement) G
namedValues >
DEBUG 2010-07-30 12:26:07.843 [http-8080-Processor25] (CacheElement) G
namedValues valid==false
DEBUG 2010-07-30 12:26:07.843 [http-8080-Processor25] (CacheElement) G
namedValues expired or invalid, so try to repopulate
DEBUG 2010-07-30 12:26:07.843 [http-8080-Processor25] (FilterSetup)
>>>>>>>>>>>>>>>>>>LdapFilterForGroups
DEBUG 2010-07-30 12:26:07.843 [http-8080-Processor25] (FilterLdap) G
populateCacheElement() >
DEBUG 2010-07-30 12:26:07.843 [http-8080-Processor25] (FilterLdap) G
populateCacheElement() about to call getNamingEnumeration()
DEBUG 2010-07-30 12:26:07.843 [http-8080-Processor25] (FilterSetup)
>>>>>>>>>>>>>>>>>>LdapFilterForGroups
DEBUG 2010-07-30 12:26:07.843 [http-8080-Processor25] (FilterLdap) G
getFilter() >
DEBUG 2010-07-30 12:26:07.843 [http-8080-Processor25] (FilterLdap) G
getFilter() < (member=cn=test,ou=groups,ou=system)
DEBUG 2010-07-30 12:26:07.843 [http-8080-Processor25] (FilterSetup)
>>>>>>>>>>>>>>>>>>LdapFilterForGroups
DEBUG 2010-07-30 12:26:07.843 [http-8080-Processor25] (FilterLdap) G
getSearchControls() >
DEBUG 2010-07-30 12:26:07.843 [http-8080-Processor25] (FilterLdap) G
getSearchControls() <
javax.naming.directory.searchcontr...@6c570c<mailto:javax.naming.directory.searchcontr...@6c570c>
DEBUG 2010-07-30 12:26:07.843 [http-8080-Processor25] (FilterSetup)
>>>>>>>>>>>>>>>>>>LdapFilterForGroups
DEBUG 2010-07-30 12:26:07.843 [http-8080-Processor25] (FilterLdap) G
getEnvironment() ldap explicit version==3
DEBUG 2010-07-30 12:26:07.843 [http-8080-Processor25] (FilterLdap) G
getEnvironment() ldap version==3
DEBUG 2010-07-30 12:26:07.843 [http-8080-Processor25] (FilterLdap) G
getEnvironment() ldap url==ldap://localhost:10389
DEBUG 2010-07-30 12:26:07.843 [http-8080-Processor25] (FilterLdap) G
getEnvironment() binding to protected directory
ERROR 2010-07-30 12:26:07.843 [http-8080-Processor25] (FilterLdap) G
getEnvironment() couldn't set up env for DirContextnull
DEBUG 2010-07-30 12:26:07.843 [http-8080-Processor25] (FilterLdap) G
getEnvironment() < {java.naming.provider.url=ldap://localhost:10389,
java.naming.factory.initial=com.sun.jndi.ldap.LdapCtxFactory,
java.naming.ldap.version=3, java.naming.security.authentication=simple}
DEBUG 2010-07-30 12:26:07.843 [http-8080-Processor25] (FilterSetup)
>>>>>>>>>>>>>>>>>>LdapFilterForGroups
DEBUG 2010-07-30 12:26:07.843 [http-8080-Processor25] (FilterLdap) G
getNamingEnumeration() >
INFO 2010-07-30 12:26:07.843 [http-8080-Processor25] (FilterLdap) G
getNamingEnumeration() -not- binding individual user
DEBUG 2010-07-30 12:26:07.843 [http-8080-Processor25] (FilterSetup)
>>>>>>>>>>>>>>>>>>LdapFilterForGroups
DEBUG 2010-07-30 12:26:07.843 [http-8080-Processor25] (FilterLdap) G
getNamingEnumeration() >
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (FilterLdap) G
getNamingEnumeration() <
com.sun.jndi.ldap.ldapsearchenumerat...@1a15597<mailto:com.sun.jndi.ldap.ldapsearchenumerat...@1a15597>
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (FilterLdap) G
getNamingEnumeration() enumeration has no elements, yet no exceptions
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (FilterLdap) G
getNamingEnumeration() failed security bind
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (FilterLdap) G
getNamingEnumeration() <
com.sun.jndi.ldap.ldapsearchenumerat...@1a15597<mailto:com.sun.jndi.ldap.ldapsearchenumerat...@1a15597>
ERROR 2010-07-30 12:26:07.859 [http-8080-Processor25] (FilterLdap) G
populateCacheElement() unexpected null ne w/o exception thrown
ERROR 2010-07-30 12:26:07.859 [http-8080-Processor25] (FilterLdap) G
populateCacheElement() wasn't authenticating
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (FilterLdap) G
populateCacheElement() in finally, authenticated==null map=={}
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (CacheElement) G
populate() >
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (CacheElement) G
populate() <
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (FilterLdap) G
populateCacheElement() <
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (CacheElement) G
namedValues populate completed
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (CacheElement) G
namedValues populate succeeded
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (CacheElement) -
calcExpiration(int,int) >
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (CacheElement) -
calcExpiration(int,int) expires in 0 days 00:10:00.000
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (CacheElement) -
calcExpiration(int,int) < 2010-07-30 12:36:07.859
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (CacheElement) G audit()
> LdapFilterForGroups b03512 @ 2010-07-30 12:26:07.859
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (CacheElement) G audit()
valid==true
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (CacheElement) G audit()
userid==test
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (CacheElement) G audit()
password==test
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (CacheElement) G audit()
authenticated==null
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (CacheElement) G audit()
errorMessage==null
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (CacheElement) G audit()
expiration==2010-07-30 12:36:07.859
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (CacheElement) G audit()
expires in 0 days 00:10:00.000
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (CacheElement) G audit() <
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (CacheElement) G
namedValues < {}
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (Cache) G
getNamedValues() < {}
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25]
(ExtendedHttpServletRequestWrapper) mapping LdapFilterForGroups => {} in
{ldapfilterforattributes={userpassword=...@1e21540], description=[a test LDAP
user], cn=[test]}}
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (BaseCaching)
FilterLdap.gatherAttributes(): gatherAttributes calling audit
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (Cache) G
getCacheElement() keytemp==test
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (Cache) G
getCacheElement() key==3556498
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (Cache) G
getCacheElement() cache already has element
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (Cache) G
getCacheElement() element retrieved from cache successfully
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (CacheElement) G audit()
> LdapFilterForGroups b03512 @ 2010-07-30 12:26:07.859
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (CacheElement) G audit()
valid==true
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (CacheElement) G audit()
userid==test
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (CacheElement) G audit()
password==test
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (CacheElement) G audit()
authenticated==null
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (CacheElement) G audit()
errorMessage==null
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (CacheElement) G audit()
expiration==2010-07-30 12:36:07.859
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (CacheElement) G audit()
expires in 0 days 00:10:00.000
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (CacheElement) G audit() <
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (BaseCaching)
FilterLdap.gatherAttributes(): at end of gatherAttributes
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (BaseCaching)
FilterLdap.. . . gatherAttributes()
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (BaseCaching)
FilterLdap.. . . gatherAuthenticatedAttributes()
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (BaseContributing)
FilterLdap.doThisSubclass() : no surrogate role configured
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (BaseContributing)
FilterLdap.doThisSubclass() : no surrogate attribute configured
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (FilterSetup)
FilterLdap.doFilter() : before next doFilter()
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (FilterSetup)
FilterLdap.doFilter() :
extendedhttpservletrequest==fedora.server.security.servletfilters.extendedhttpservletrequestwrap...@1ec4535<mailto:extendedhttpservletrequest==fedora.server.security.servletfilters.extendedhttpservletrequestwrap...@1ec4535>
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (FilterSetup)
FilterLdap.doFilter() : extendedHttpServletRequest
fedora.server.security.servletfilters.ExtendedHttpServletRequestWrapper==
DEBUG 2010-07-30 12:26:07.859 [http-8080-Processor25] (FilterSetup)
FilterLdap.doFilter() :
responseorg.apache.catalina.connector.responsefac...@b3a5d1<mailto:responseorg.apache.catalina.connector.responsefac...@b3a5d1>==
---------------------------------------------------------------------------
Regards,
Ben
---------------------------------------------------------------------
Dr Ben Ryan
Timescapes Archive Technical Officer
School of Sociology and Social Policy
Faculty of Education, Social Sciences and Law
Social Science Building
The University of Leeds
Leeds LS2 9JT
Email: [email protected]<mailto:[email protected]>
Tel: 0113 343 7319
Website: http://www.timescapes.leeds.ac.uk<http://www.timescapes.leeds.ac.uk/>
---------------------------------------------------------------------
------------------------------------------------------------------------------
The Palm PDK Hot Apps Program offers developers who use the
Plug-In Development Kit to bring their C/C++ apps to Palm for a share
of $1 Million in cash or HP Products. Visit us here for more details:
http://p.sf.net/sfu/dev2dev-palm
_______________________________________________
Fedora-commons-users mailing list
[email protected]
https://lists.sourceforge.net/lists/listinfo/fedora-commons-users
