[xwiki-users] LDAP Based login works after 3 attempts for new users

Hi , I am getting some quirky behaviour with the LDAP based login for the first time, it only seems to work after 3 tries for new users, and also only if I have lowercase the first two times then uppercase the third time (in our LDAP, the usernames are uppercase P and a unique number). I have attached the log output, just wondering if anyone can tell me whats happening. on the logon screen I get:- 1st try: No message - just returns to the login page - lowercase p 2nd try: "Wrong password" - lowercase p 3rd try: Works OK. - uppercase P If it try it with uppercase P all the time then the user record created is somehow messed up and i can login with it. here is the log output: ----------------------------Log output --------------------------------------------------------------------------------- 14:41:29,871 [http://localhost:8080/xwiki/bin/loginsubmit/XWiki/XWikiLogin] [http-8080-Processor24] DEBUG ldap.XWikiLDAPConnection - Connection to LDAP server [<LDAP_HOST>:389] 14:41:29,886 [http://localhost:8080/xwiki/bin/loginsubmit/XWiki/XWikiLogin] [http-8080-Processor24] DEBUG ldap.XWikiLDAPConnection - Binding to LDAP server with credentials login=[CN=JAM_WIKI,CN=AdminUsers,O=MyCompanyGroup] password=[<LDAP_PWD>] 14:41:29,886 [http://localhost:8080/xwiki/bin/loginsubmit/XWiki/XWikiLogin] [http-8080-Processor24] DEBUG LDAP.XWikiLDAPAuthServiceImpl - Found user dn with the user object: null 14:41:29,902 [http://localhost:8080/xwiki/bin/loginsubmit/XWiki/XWikiLogin] [http-8080-Processor24] DEBUG ldap.XWikiLDAPConfig - Ready to create user from LDAP with fields last_name=sn,first_name=givenName,email=mail,ldap_dn=dn 14:41:29,902 [http://localhost:8080/xwiki/bin/loginsubmit/XWiki/XWikiLogin] [http-8080-Processor24] DEBUG ldap.XWikiLDAPUtils - Searching for the user in LDAP: user:p50000351 base:O=MyCompanyGroup query:(cn=p50000351) uid:cn 14:41:29,918 [http://localhost:8080/xwiki/bin/loginsubmit/XWiki/XWikiLogin] [http-8080-Processor24] DEBUG LDAP.XWikiLDAPAuthServiceImpl - LDAP attributes will be used to update XWiki attributes. 14:41:29,918 [http://localhost:8080/xwiki/bin/loginsubmit/XWiki/XWikiLogin] [http-8080-Processor24] DEBUG LDAP.XWikiLDAPAuthServiceImpl - Creating new XWiki user based on LDAP attribues located at CN=P50000351,CN=MiisUsers,O=MyCompanyGroup 14:41:29,918 [http://localhost:8080/xwiki/bin/loginsubmit/XWiki/XWikiLogin] [http-8080-Processor24] DEBUG ldap.XWikiLDAPConfig - Ready to create user from LDAP with fields last_name=sn,first_name=givenName,email=mail,ldap_dn=dn 14:41:30,042 [http://localhost:8080/xwiki/bin/loginsubmit/XWiki/XWikiLogin] [http-8080-Processor24] DEBUG LDAP.XWikiLDAPAuthServiceImpl - New XWiki user created: null 14:41:30,587 [ http://localhost:8080/xwiki/bin/view/Main/WebHome?srid=DAVxwJmD] [http-8080-Processor24] DEBUG ldap.XWikiLDAPConnection - Connection to LDAP server [<LDAP_HOST>:389] 14:41:30,603 [ http://localhost:8080/xwiki/bin/view/Main/WebHome?srid=DAVxwJmD] [http-8080-Processor24] DEBUG ldap.XWikiLDAPConnection - Binding to LDAP server with credentials login=[CN=JAM_WIKI,CN=AdminUsers,O=MyCompanyGroup] password=[<LDAP_PWD>] 14:41:30,618 [ http://localhost:8080/xwiki/bin/view/Main/WebHome?srid=DAVxwJmD] [http-8080-Processor24] DEBUG LDAP.XWikiLDAPAuthServiceImpl - Found user dn with the user object: CN=P50000351,CN=MiisUsers,O=MyCompanyGroup 14:41:30,634 [ http://localhost:8080/xwiki/bin/view/Main/WebHome?srid=DAVxwJmD] [http-8080-Processor24] DEBUG LDAP.XWikiLDAPAuthServiceImpl - LDAP attributes will be used to update XWiki attributes. 14:41:30,634 [ http://localhost:8080/xwiki/bin/view/Main/WebHome?srid=DAVxwJmD] [http-8080-Processor24] DEBUG ldap.XWikiLDAPConfig - Ready to create user from LDAP with fields last_name=sn,first_name=givenName,email=mail,ldap_dn=dn 14:41:30,634 [ http://localhost:8080/xwiki/bin/view/Main/WebHome?srid=DAVxwJmD] [http-8080-Processor24] DEBUG ldap.XWikiLDAPConnection - LDAP Search failed LDAPException: No Such Object (32) No Such Object LDAPException: Server Message: 0000208D: NameErr: DSID-031521D2, problem 2001 (NO_OBJECT), data 0, best match of: 'O=MyCompanyGroup' LDAPException: Matched DN: O=MyCompanyGroup at com.novell.ldap.LDAPResponse.getResultException(Unknown Source) at com.novell.ldap.LDAPResponse.chkResultCode(Unknown Source) at com.novell.ldap.LDAPSearchResults.next(Unknown Source) at com.xpn.xwiki.plugin.ldap.XWikiLDAPConnection.searchLDAP(XWikiLDAPConnection.java:303) at com.xpn.xwiki.user.impl.LDAP.XWikiLDAPAuthServiceImpl.syncUser(XWikiLDAPAuthServiceImpl.java:454) at com.xpn.xwiki.user.impl.LDAP.XWikiLDAPAuthServiceImpl.ldapAuthenticateInContext(XWikiLDAPAuthServiceImpl.java:379) at com.xpn.xwiki.user.impl.LDAP.XWikiLDAPAuthServiceImpl.ldapAuthenticate(XWikiLDAPAuthServiceImpl.java:176) at com.xpn.xwiki.user.impl.LDAP.XWikiLDAPAuthServiceImpl.authenticate(XWikiLDAPAuthServiceImpl.java:131) at com.xpn.xwiki.user.impl.xwiki.MyFormAuthenticator.authenticate(MyFormAuthenticator.java:239) at com.xpn.xwiki.user.impl.xwiki.MyFormAuthenticator.processLogin(MyFormAuthenticator.java:129) at com.xpn.xwiki.user.impl.xwiki.XWikiAuthServiceImpl.checkAuth(XWikiAuthServiceImpl.java:205) at com.xpn.xwiki.XWiki.checkAuth(XWiki.java:3566) at com.xpn.xwiki.user.impl.xwiki.XWikiRightServiceImpl.checkAccess(XWikiRightServiceImpl.java:170) at com.xpn.xwiki.XWiki.checkAccess(XWiki.java:3574) at com.xpn.xwiki.XWiki.prepareDocuments(XWiki.java:4480) at com.xpn.xwiki.web.XWikiAction.execute(XWikiAction.java:190) at com.xpn.xwiki.web.XWikiAction.execute(XWikiAction.java:115) at org.apache.struts.action.RequestProcessor.processActionPerform(RequestProcessor.java:431) at org.apache.struts.action.RequestProcessor.process(RequestProcessor.java:236) at org.apache.struts.action.ActionServlet.process(ActionServlet.java:1196) at org.apache.struts.action.ActionServlet.doGet(ActionServlet.java:414) at javax.servlet.http.HttpServlet.service(HttpServlet.java:690) at javax.servlet.http.HttpServlet.service(HttpServlet.java:803) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:269) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188) at com.xpn.xwiki.wysiwyg.server.filter.ConversionFilter.doFilter(ConversionFilter.java:94) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188) at com.xpn.xwiki.web.SavedRequestRestorerFilter.doFilter(SavedRequestRestorerFilter.java:287) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188) at com.xpn.xwiki.web.SetCharacterEncodingFilter.doFilter(SetCharacterEncodingFilter.java:112) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188) at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:210) at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:174) at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127) at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:117) at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:108) at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:151) at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:870) at org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection(Http11BaseProtocol.java:665) at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:528) at org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt(LeaderFollowerWorkerThread.java:81) at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:685) at java.lang.Thread.run(Unknown Source) 14:41:30,634 [ http://localhost:8080/xwiki/bin/view/Main/WebHome?srid=DAVxwJmD] [http-8080-Processor24] DEBUG LDAP.XWikiLDAPAuthServiceImpl - Updating existing user with LDAP attribues located at CN=P50000351,CN=MiisUsers,O=MyCompanyGroup 14:41:30,634 [ http://localhost:8080/xwiki/bin/view/Main/WebHome?srid=DAVxwJmD] [http-8080-Processor24] DEBUG ldap.XWikiLDAPConfig - Ready to create user from LDAP with fields last_name=sn,first_name=givenName,email=mail,ldap_dn=dn 14:41:30,634 [ http://localhost:8080/xwiki/bin/view/Main/WebHome?srid=DAVxwJmD] [http-8080-Processor24] DEBUG LDAP.XWikiLDAPAuthServiceImpl - Local LDAP authentication failed. java.lang.NullPointerException at com.xpn.xwiki.user.impl.LDAP.XWikiLDAPAuthServiceImpl.updateUserFromLDAP(XWikiLDAPAuthServiceImpl.java:728) at com.xpn.xwiki.user.impl.LDAP.XWikiLDAPAuthServiceImpl.syncUser(XWikiLDAPAuthServiceImpl.java:474) at com.xpn.xwiki.user.impl.LDAP.XWikiLDAPAuthServiceImpl.ldapAuthenticateInContext(XWikiLDAPAuthServiceImpl.java:379) at com.xpn.xwiki.user.impl.LDAP.XWikiLDAPAuthServiceImpl.ldapAuthenticate(XWikiLDAPAuthServiceImpl.java:176) at com.xpn.xwiki.user.impl.LDAP.XWikiLDAPAuthServiceImpl.authenticate(XWikiLDAPAuthServiceImpl.java:131) at com.xpn.xwiki.user.impl.xwiki.MyFormAuthenticator.authenticate(MyFormAuthenticator.java:239) at com.xpn.xwiki.user.impl.xwiki.MyFormAuthenticator.processLogin(MyFormAuthenticator.java:129) at com.xpn.xwiki.user.impl.xwiki.XWikiAuthServiceImpl.checkAuth(XWikiAuthServiceImpl.java:205) at com.xpn.xwiki.XWiki.checkAuth(XWiki.java:3566) at com.xpn.xwiki.user.impl.xwiki.XWikiRightServiceImpl.checkAccess(XWikiRightServiceImpl.java:170) at com.xpn.xwiki.XWiki.checkAccess(XWiki.java:3574) at com.xpn.xwiki.XWiki.prepareDocuments(XWiki.java:4480) at com.xpn.xwiki.web.XWikiAction.execute(XWikiAction.java:190) at com.xpn.xwiki.web.XWikiAction.execute(XWikiAction.java:115) at org.apache.struts.action.RequestProcessor.processActionPerform(RequestProcessor.java:431) at org.apache.struts.action.RequestProcessor.process(RequestProcessor.java:236) at org.apache.struts.action.ActionServlet.process(ActionServlet.java:1196) at org.apache.struts.action.ActionServlet.doGet(ActionServlet.java:414) at javax.servlet.http.HttpServlet.service(HttpServlet.java:690) at javax.servlet.http.HttpServlet.service(HttpServlet.java:803) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:269) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188) at com.xpn.xwiki.wysiwyg.server.filter.ConversionFilter.doFilter(ConversionFilter.java:94) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188) at com.xpn.xwiki.web.SavedRequestRestorerFilter.doFilter(SavedRequestRestorerFilter.java:287) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188) at com.xpn.xwiki.web.SetCharacterEncodingFilter.doFilter(SetCharacterEncodingFilter.java:112) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188) at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:210) at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:174) at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127) at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:117) at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:108) at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:151) at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:870) at org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection(Http11BaseProtocol.java:665) at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:528) at org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt(LeaderFollowerWorkerThread.java:81) at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:685) at java.lang.Thread.run(Unknown Source) 14:41:30,634 [ http://localhost:8080/xwiki/bin/view/Main/WebHome?srid=DAVxwJmD] [http-8080-Processor24] DEBUG LDAP.XWikiLDAPAuthServiceImpl - Trying authentication against XWiki DB 14:41:39,512 [http://localhost:8080/xwiki/bin/loginsubmit/XWiki/XWikiLogin] [http-8080-Processor24] DEBUG ldap.XWikiLDAPConnection - Connection to LDAP server [<LDAP_HOST>:389] 14:41:39,527 [http://localhost:8080/xwiki/bin/loginsubmit/XWiki/XWikiLogin] [http-8080-Processor24] DEBUG ldap.XWikiLDAPConnection - Binding to LDAP server with credentials login=[CN=JAM_WIKI,CN=AdminUsers,O=MyCompanyGroup] password=[<LDAP_PWD>] 14:41:39,527 [http://localhost:8080/xwiki/bin/loginsubmit/XWiki/XWikiLogin] [http-8080-Processor24] DEBUG LDAP.XWikiLDAPAuthServiceImpl - Found user dn with the user object: CN=P50000351,CN=MiisUsers,O=MyCompanyGroup 14:41:39,558 [http://localhost:8080/xwiki/bin/loginsubmit/XWiki/XWikiLogin] [http-8080-Processor24] DEBUG LDAP.XWikiLDAPAuthServiceImpl - LDAP attributes will be used to update XWiki attributes. 14:41:39,558 [http://localhost:8080/xwiki/bin/loginsubmit/XWiki/XWikiLogin] [http-8080-Processor24] DEBUG ldap.XWikiLDAPConfig - Ready to create user from LDAP with fields last_name=sn,first_name=givenName,email=mail,ldap_dn=dn 14:41:39,558 [http://localhost:8080/xwiki/bin/loginsubmit/XWiki/XWikiLogin] [http-8080-Processor24] DEBUG ldap.XWikiLDAPConnection - LDAP Search failed LDAPException: No Such Object (32) No Such Object LDAPException: Server Message: 0000208D: NameErr: DSID-031521D2, problem 2001 (NO_OBJECT), data 0, best match of: 'O=MyCompanyGroup' LDAPException: Matched DN: O=MyCompanyGroup at com.novell.ldap.LDAPResponse.getResultException(Unknown Source) at com.novell.ldap.LDAPResponse.chkResultCode(Unknown Source) at com.novell.ldap.LDAPSearchResults.next(Unknown Source) at com.xpn.xwiki.plugin.ldap.XWikiLDAPConnection.searchLDAP(XWikiLDAPConnection.java:303) at com.xpn.xwiki.user.impl.LDAP.XWikiLDAPAuthServiceImpl.syncUser(XWikiLDAPAuthServiceImpl.java:454) at com.xpn.xwiki.user.impl.LDAP.XWikiLDAPAuthServiceImpl.ldapAuthenticateInContext(XWikiLDAPAuthServiceImpl.java:379) at com.xpn.xwiki.user.impl.LDAP.XWikiLDAPAuthServiceImpl.ldapAuthenticate(XWikiLDAPAuthServiceImpl.java:176) at com.xpn.xwiki.user.impl.LDAP.XWikiLDAPAuthServiceImpl.authenticate(XWikiLDAPAuthServiceImpl.java:131) at com.xpn.xwiki.user.impl.xwiki.MyFormAuthenticator.authenticate(MyFormAuthenticator.java:239) at com.xpn.xwiki.user.impl.xwiki.MyFormAuthenticator.processLogin(MyFormAuthenticator.java:165) at com.xpn.xwiki.user.impl.xwiki.MyFormAuthenticator.processLogin(MyFormAuthenticator.java:148) at com.xpn.xwiki.user.impl.xwiki.XWikiAuthServiceImpl.checkAuth(XWikiAuthServiceImpl.java:205) at com.xpn.xwiki.XWiki.checkAuth(XWiki.java:3566) at com.xpn.xwiki.user.impl.xwiki.XWikiRightServiceImpl.checkAccess(XWikiRightServiceImpl.java:139) at com.xpn.xwiki.XWiki.checkAccess(XWiki.java:3574) at com.xpn.xwiki.XWiki.prepareDocuments(XWiki.java:4480) at com.xpn.xwiki.web.XWikiAction.execute(XWikiAction.java:190) at com.xpn.xwiki.web.XWikiAction.execute(XWikiAction.java:115) at org.apache.struts.action.RequestProcessor.processActionPerform(RequestProcessor.java:431) at org.apache.struts.action.RequestProcessor.process(RequestProcessor.java:236) at org.apache.struts.action.ActionServlet.process(ActionServlet.java:1196) at org.apache.struts.action.ActionServlet.doPost(ActionServlet.java:432) at javax.servlet.http.HttpServlet.service(HttpServlet.java:710) at javax.servlet.http.HttpServlet.service(HttpServlet.java:803) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:269) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188) at com.xpn.xwiki.wysiwyg.server.filter.ConversionFilter.doFilter(ConversionFilter.java:94) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188) at com.xpn.xwiki.web.SavedRequestRestorerFilter.doFilter(SavedRequestRestorerFilter.java:287) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188) at com.xpn.xwiki.web.SetCharacterEncodingFilter.doFilter(SetCharacterEncodingFilter.java:112) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188) at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:210) at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:174) at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127) at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:117) at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:108) at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:151) at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:870) at org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection(Http11BaseProtocol.java:665) at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:528) at org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt(LeaderFollowerWorkerThread.java:81) at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:685) at java.lang.Thread.run(Unknown Source) 14:41:39,558 [http://localhost:8080/xwiki/bin/loginsubmit/XWiki/XWikiLogin] [http-8080-Processor24] DEBUG LDAP.XWikiLDAPAuthServiceImpl - Updating existing user with LDAP attribues located at CN=P50000351,CN=MiisUsers,O=MyCompanyGroup 14:41:39,558 [http://localhost:8080/xwiki/bin/loginsubmit/XWiki/XWikiLogin] [http-8080-Processor24] DEBUG ldap.XWikiLDAPConfig - Ready to create user from LDAP with fields last_name=sn,first_name=givenName,email=mail,ldap_dn=dn 14:41:39,558 [http://localhost:8080/xwiki/bin/loginsubmit/XWiki/XWikiLogin] [http-8080-Processor24] DEBUG LDAP.XWikiLDAPAuthServiceImpl - Local LDAP authentication failed. java.lang.NullPointerException at com.xpn.xwiki.user.impl.LDAP.XWikiLDAPAuthServiceImpl.updateUserFromLDAP(XWikiLDAPAuthServiceImpl.java:728) at com.xpn.xwiki.user.impl.LDAP.XWikiLDAPAuthServiceImpl.syncUser(XWikiLDAPAuthServiceImpl.java:474) at com.xpn.xwiki.user.impl.LDAP.XWikiLDAPAuthServiceImpl.ldapAuthenticateInContext(XWikiLDAPAuthServiceImpl.java:379) at com.xpn.xwiki.user.impl.LDAP.XWikiLDAPAuthServiceImpl.ldapAuthenticate(XWikiLDAPAuthServiceImpl.java:176) at com.xpn.xwiki.user.impl.LDAP.XWikiLDAPAuthServiceImpl.authenticate(XWikiLDAPAuthServiceImpl.java:131) at com.xpn.xwiki.user.impl.xwiki.MyFormAuthenticator.authenticate(MyFormAuthenticator.java:239) at com.xpn.xwiki.user.impl.xwiki.MyFormAuthenticator.processLogin(MyFormAuthenticator.java:165) at com.xpn.xwiki.user.impl.xwiki.MyFormAuthenticator.processLogin(MyFormAuthenticator.java:148) at com.xpn.xwiki.user.impl.xwiki.XWikiAuthServiceImpl.checkAuth(XWikiAuthServiceImpl.java:205) at com.xpn.xwiki.XWiki.checkAuth(XWiki.java:3566) at com.xpn.xwiki.user.impl.xwiki.XWikiRightServiceImpl.checkAccess(XWikiRightServiceImpl.java:139) at com.xpn.xwiki.XWiki.checkAccess(XWiki.java:3574) at com.xpn.xwiki.XWiki.prepareDocuments(XWiki.java:4480) at com.xpn.xwiki.web.XWikiAction.execute(XWikiAction.java:190) at com.xpn.xwiki.web.XWikiAction.execute(XWikiAction.java:115) at org.apache.struts.action.RequestProcessor.processActionPerform(RequestProcessor.java:431) at org.apache.struts.action.RequestProcessor.process(RequestProcessor.java:236) at org.apache.struts.action.ActionServlet.process(ActionServlet.java:1196) at org.apache.struts.action.ActionServlet.doPost(ActionServlet.java:432) at javax.servlet.http.HttpServlet.service(HttpServlet.java:710) at javax.servlet.http.HttpServlet.service(HttpServlet.java:803) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:269) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188) at com.xpn.xwiki.wysiwyg.server.filter.ConversionFilter.doFilter(ConversionFilter.java:94) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188) at com.xpn.xwiki.web.SavedRequestRestorerFilter.doFilter(SavedRequestRestorerFilter.java:287) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188) at com.xpn.xwiki.web.SetCharacterEncodingFilter.doFilter(SetCharacterEncodingFilter.java:112) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188) at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:210) at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:174) at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127) at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:117) at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:108) at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:151) at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:870) at org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection(Http11BaseProtocol.java:665) at org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:528) at org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt(LeaderFollowerWorkerThread.java:81) at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:685) at java.lang.Thread.run(Unknown Source) 14:41:39,574 [http://localhost:8080/xwiki/bin/loginsubmit/XWiki/XWikiLogin] [http-8080-Processor24] DEBUG LDAP.XWikiLDAPAuthServiceImpl - Trying authentication against XWiki DB 14:41:49,573 [http://localhost:8080/xwiki/bin/loginsubmit/XWiki/XWikiLogin] [http-8080-Processor24] DEBUG ldap.XWikiLDAPConnection - Connection to LDAP server [<LDAP_HOST>:389] 14:41:49,589 [http://localhost:8080/xwiki/bin/loginsubmit/XWiki/XWikiLogin] [http-8080-Processor24] DEBUG ldap.XWikiLDAPConnection - Binding to LDAP server with credentials login=[CN=JAM_WIKI,CN=AdminUsers,O=MyCompanyGroup] password=[<LDAP_PWD>] 14:41:49,589 [http://localhost:8080/xwiki/bin/loginsubmit/XWiki/XWikiLogin] [http-8080-Processor24] DEBUG LDAP.XWikiLDAPAuthServiceImpl - Found user dn with the user object: null 14:41:49,589 [http://localhost:8080/xwiki/bin/loginsubmit/XWiki/XWikiLogin] [http-8080-Processor24] DEBUG ldap.XWikiLDAPConfig - Ready to create user from LDAP with fields last_name=sn,first_name=givenName,email=mail,ldap_dn=dn 14:41:49,589 [http://localhost:8080/xwiki/bin/loginsubmit/XWiki/XWikiLogin] [http-8080-Processor24] DEBUG ldap.XWikiLDAPUtils - Searching for the user in LDAP: user:P50000351 base:O=MyCompanyGroup query:(cn=P50000351) uid:cn 14:41:49,620 [http://localhost:8080/xwiki/bin/loginsubmit/XWiki/XWikiLogin] [http-8080-Processor24] DEBUG LDAP.XWikiLDAPAuthServiceImpl - LDAP attributes will be used to update XWiki attributes. 14:41:49,620 [http://localhost:8080/xwiki/bin/loginsubmit/XWiki/XWikiLogin] [http-8080-Processor24] DEBUG LDAP.XWikiLDAPAuthServiceImpl - Updating existing user with LDAP attribues located at CN=P50000351,CN=MiisUsers,O=MyCompanyGroup 14:41:49,620 [http://localhost:8080/xwiki/bin/loginsubmit/XWiki/XWikiLogin] [http-8080-Processor24] DEBUG ldap.XWikiLDAPConfig - Ready to create user from LDAP with fields last_name=sn,first_name=givenName,email=mail,ldap_dn=dn 14:41:49,822 [http://localhost:8080/xwiki/bin/view/Main/WebHome] [http-8080-Processor24] DEBUG ldap.XWikiLDAPConnection - Connection to LDAP server [<LDAP_HOST>:389] 14:41:49,838 [http://localhost:8080/xwiki/bin/view/Main/WebHome] [http-8080-Processor24] DEBUG ldap.XWikiLDAPConnection - Binding to LDAP server with credentials login=[CN=JAM_WIKI,CN=AdminUsers,O=MyCompanyGroup] password=[<LDAP_PWD>] 14:41:49,853 [http://localhost:8080/xwiki/bin/view/Main/WebHome] [http-8080-Processor24] DEBUG LDAP.XWikiLDAPAuthServiceImpl - Found user dn with the user object: null 14:41:49,853 [http://localhost:8080/xwiki/bin/view/Main/WebHome] [http-8080-Processor24] DEBUG ldap.XWikiLDAPConfig - Ready to create user from LDAP with fields last_name=sn,first_name=givenName,email=mail,ldap_dn=dn 14:41:49,853 [http://localhost:8080/xwiki/bin/view/Main/WebHome] [http-8080-Processor24] DEBUG ldap.XWikiLDAPUtils - Searching for the user in LDAP: user:P50000351 base:O=MyCompanyGroup query:(cn=P50000351) uid:cn 14:41:49,869 [http://localhost:8080/xwiki/bin/view/Main/WebHome] [http-8080-Processor24] DEBUG LDAP.XWikiLDAPAuthServiceImpl - LDAP attributes will be used to update XWiki attributes. 14:41:49,869 [http://localhost:8080/xwiki/bin/view/Main/WebHome] [http-8080-Processor24] DEBUG LDAP.XWikiLDAPAuthServiceImpl - Updating existing user with LDAP attribues located at CN=P50000351,CN=MiisUsers,O=MyCompanyGroup 14:41:49,869 [http://localhost:8080/xwiki/bin/view/Main/WebHome] [http-8080-Processor24] DEBUG ldap.XWikiLDAPConfig - Ready to create user from LDAP with fields last_name=sn,first_name=givenName,email=mail,ldap_dn=dn 14:41:50,056 [http://localhost:8080/xwiki/bin/view/Main/WebHome] [http-8080-Processor24] DEBUG ldap.XWikiLDAPConnection - Connection to LDAP server [<LDAP_HOST>:389] 14:41:50,071 [http://localhost:8080/xwiki/bin/view/Main/WebHome] [http-8080-Processor24] DEBUG ldap.XWikiLDAPConnection - Binding to LDAP server with credentials login=[CN=JAM_WIKI,CN=AdminUsers,O=MyCompanyGroup] password=[<LDAP_PWD>] 14:41:50,087 [http://localhost:8080/xwiki/bin/view/Main/WebHome] [http-8080-Processor24] DEBUG LDAP.XWikiLDAPAuthServiceImpl - Found user dn with the user object: null 14:41:50,087 [http://localhost:8080/xwiki/bin/view/Main/WebHome] [http-8080-Processor24] DEBUG ldap.XWikiLDAPConfig - Ready to create user from LDAP with fields last_name=sn,first_name=givenName,email=mail,ldap_dn=dn 14:41:50,087 [http://localhost:8080/xwiki/bin/view/Main/WebHome] [http-8080-Processor24] DEBUG ldap.XWikiLDAPUtils - Searching for the user in LDAP: user:P50000351 base:O=MyCompanyGroup query:(cn=P50000351) uid:cn 14:41:50,103 [http://localhost:8080/xwiki/bin/view/Main/WebHome] [http-8080-Processor24] DEBUG LDAP.XWikiLDAPAuthServiceImpl - LDAP attributes will be used to update XWiki attributes. 14:41:50,103 [http://localhost:8080/xwiki/bin/view/Main/WebHome] [http-8080-Processor24] DEBUG LDAP.XWikiLDAPAuthServiceImpl - Updating existing user with LDAP attribues located at CN=P50000351,CN=MiisUsers,O=MyCompanyGroup 14:41:50,103 [http://localhost:8080/xwiki/bin/view/Main/WebHome] [http-8080-Processor24] DEBUG ldap.XWikiLDAPConfig - Ready to create user from LDAP with fields last_name=sn,first_name=givenName,email=mail,ldap_dn=dn 14:41:50,772 [ http://localhost:8080/xwiki/bin/view/Main/?xpage=xpart&vm=commentsinlin…] [http-8080-Processor24] DEBUG ldap.XWikiLDAPConnection - Connection to LDAP server [<LDAP_HOST>:389] 14:41:50,788 [ http://localhost:8080/xwiki/bin/view/Main/?xpage=xpart&vm=commentsinlin…] [http-8080-Processor24] DEBUG ldap.XWikiLDAPConnection - Binding to LDAP server with credentials login=[CN=JAM_WIKI,CN=AdminUsers,O=MyCompanyGroup] password=[<LDAP_PWD>] 14:41:50,803 [ http://localhost:8080/xwiki/bin/view/Main/?xpage=xpart&vm=commentsinlin…] [http-8080-Processor24] DEBUG LDAP.XWikiLDAPAuthServiceImpl - Found user dn with the user object: null 14:41:50,803 [ http://localhost:8080/xwiki/bin/view/Main/?xpage=xpart&vm=commentsinlin…] [http-8080-Processor24] DEBUG ldap.XWikiLDAPConfig - Ready to create user from LDAP with fields last_name=sn,first_name=givenName,email=mail,ldap_dn=dn 14:41:50,803 [ http://localhost:8080/xwiki/bin/view/Main/?xpage=xpart&vm=commentsinlin…] [http-8080-Processor24] DEBUG ldap.XWikiLDAPUtils - Searching for the user in LDAP: user:P50000351 base:O=MyCompanyGroup query:(cn=P50000351) uid:cn 14:41:50,819 [ http://localhost:8080/xwiki/bin/view/Main/?xpage=xpart&vm=commentsinlin…] [http-8080-Processor24] DEBUG LDAP.XWikiLDAPAuthServiceImpl - LDAP attributes will be used to update XWiki attributes. 14:41:50,819 [ http://localhost:8080/xwiki/bin/view/Main/?xpage=xpart&vm=commentsinlin…] [http-8080-Processor24] DEBUG LDAP.XWikiLDAPAuthServiceImpl - Updating existing user with LDAP attribues located at CN=P50000351,CN=MiisUsers,O=MyCompanyGroup 14:41:50,819 [ http://localhost:8080/xwiki/bin/view/Main/?xpage=xpart&vm=commentsinlin…] [http-8080-Processor24] DEBUG ldap.XWikiLDAPConfig - Ready to create user from LDAP with fields last_name=sn,first_name=givenName,email=mail,ldap_dn=dn ----------------------------End Log output ---------------------------------------------------------------------------------