Hi
I am having a problem with Multi-tenant demo using MySQL, please refer to the log entries below. What could have been the problem? Using Derby and default Multi-tenant setting: 2013-03-31 23:25:27,981 (http-bio-0.0.0.0-8443-exec-11) [ GenericDispatcher.java:69 :INFO ] Creating new dispatcher [catalog#DEMO1] (http-bio-0.0.0.0-8443-exec-11) 2013-03-31 23:25:29,236 (http-bio-0.0.0.0-8443-exec-11) [ ServiceDispatcher.java:585:INFO ] Slow sync service execution detected: service [catalog#DEMO1/userLogin] finished in [1254] milliseconds 2013-03-31 23:25:29,237 (http-bio-0.0.0.0-8443-exec-11) [ ServiceDispatcher.java:607:INFO ] Sync service [catalog#DEMO1/userLogin] finished in [1254] milliseconds with response [{userLogin=[GenericEntity:UserLogin][createdStamp,2013-03-31 19:47:04.28(java.sql.Timestamp)][createdTxStamp,2013-03-31 19:47:02.531(java.sql.Timestamp)][currentPassword,{SHA}47ca69ebb4bdc9ae0adec 130880165d2cc05db1a(java.lang.String)][disabledDateTime,null()][enabled,Y(ja va.lang.String)][externalAuthId,null()][hasLoggedOut,N(java.lang.String)][is System,null()][lastCurrencyUom,null()][lastLocale,null()][lastTimeZone,null( )][lastUpdatedStamp,2013-03-31 23:25:28.506(java.sql.Timestamp)][lastUpdatedTxStamp,2013-03-31 23:25:28.505(java.sql.Timestamp)][partyId,admin(java.lang.String)][passwordH int,null()][requirePasswordChange,null()][successiveFailedLogins,null()][use rLdapDn,null()][userLoginId,admin(java.lang.String)], responseMessage=success}] 2013-03-31 23:25:29,242 (http-bio-0.0.0.0-8443-exec-11) [ VisitHandler.java:232:INFO ] Found visitorId [10000] in cookie Using MySQL and default Multi-tenant setting: 2013-04-01 17:21:42,154 (http-bio-0.0.0.0-8443-exec-4) [ GenericDispatcher.java:69 :INFO ] Creating new dispatcher [catalog#DEMO2] (http-bio-0.0.0.0-8443-exec-4) 2013-04-01 17:21:42,197 (http-bio-0.0.0.0-8443-exec-4) [ LoginServices.java:410:INFO ] [LoginServices.userLogin] : Invalid User : User not found. 2013-04-01 17:21:42,197 (http-bio-0.0.0.0-8443-exec-4) [ ServiceDispatcher.java:525:ERROR] Error in Service [userLogin]: User not found. 2013-04-01 17:21:42,200 (http-bio-0.0.0.0-8443-exec-4) [ TransactionUtil.java:342:ERROR] ---- exception report ---------------------------------------------------------- [TransactionUtil.rollback] Exception: java.lang.Exception Message: Stack Trace ---- stack trace --------------------------------------------------------------- java.lang.Exception: Stack Trace org.ofbiz.entity.transaction.TransactionUtil.rollback(TransactionUtil.java:3 41) org.ofbiz.entity.transaction.TransactionUtil.rollback(TransactionUtil.java:3 18) org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:529) org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:225) org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:163) org.ofbiz.webapp.control.LoginWorker.login(LoginWorker.java:395) sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57 ) sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl .java:43) java.lang.reflect.Method.invoke(Method.java:601) org.ofbiz.webapp.event.JavaEventHandler.invoke(JavaEventHandler.java:93) org.ofbiz.webapp.event.JavaEventHandler.invoke(JavaEventHandler.java:79) org.ofbiz.webapp.control.RequestHandler.runEvent(RequestHandler.java:660) org.ofbiz.webapp.control.RequestHandler.doRequest(RequestHandler.java:406) org.ofbiz.webapp.control.ControlServlet.doGet(ControlServlet.java:224) org.ofbiz.webapp.control.ControlServlet.doPost(ControlServlet.java:87) javax.servlet.http.HttpServlet.service(HttpServlet.java:641) javax.servlet.http.HttpServlet.service(HttpServlet.java:722) org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(Application FilterChain.java:305) org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterCh ain.java:210) org.ofbiz.webapp.control.ContextFilter.doFilter(ContextFilter.java:337) org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(Application FilterChain.java:243) org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterCh ain.java:210) org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.ja va:222) org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.ja va:123) org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase .java:472) org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:168 ) org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:99) org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java :118) org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:929) org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:407) org.apache.coyote.http11.AbstractHttp11Processor.process(AbstractHttp11Proce ssor.java:1002) org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(Abstrac tProtocol.java:585) org.apache.tomcat.util.net.JIoEndpoint$SocketProcessor.run(JIoEndpoint.java: 312) java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:11 45) java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:6 15) java.lang.Thread.run(Thread.java:722) ---------------------------------------------------------------------------- ---- 2013-04-01 17:21:42,202 (http-bio-0.0.0.0-8443-exec-4) [ TransactionUtil.java:352:INFO ] [TransactionUtil.rollback] transaction rolled back 2013-04-01 17:21:42,202 (http-bio-0.0.0.0-8443-exec-4) [ RequestHandler.java:661:INFO ] Ran Event [java:org.ofbiz.webapp.control.LoginWorker#login] from [request], result is [error] 2013-04-01 17:21:42,203 (http-bio-0.0.0.0-8443-exec-4) [ RequestHandler.java:444:ERROR] Request login caused an error with the following message: following error occurred during login: User not found. 2013-04-01 17:21:42,203 (http-bio-0.0.0.0-8443-exec-4) [ RequestHandler.java:763:INFO ] Rendering View [login], sessionId=8C89BBB1FD50E6CB7532C88BC90E6006.jvm1 Best regards. Derek Lew |
Administrator
|
MySql had issues with milliseconds until last version. Better use Postgres or update to last MySql version
You may find plenty about it searching in the OFBiz ML or googling for "mysql milliseconds" Note: there is still an issue with VisitHandler but with Derby and Postgres you have 1000 time less risk to cross it. Ha finally found the related Jira: https://issues.apache.org/jira/browse/OFBIZ-3972 Jacques From: "Derek Lew" <[hidden email]> > Hi > > > > I am having a problem with Multi-tenant demo using MySQL, please refer to > the log entries below. What could have been the problem? > > > > Using Derby and default Multi-tenant setting: > > > > 2013-03-31 23:25:27,981 (http-bio-0.0.0.0-8443-exec-11) [ > GenericDispatcher.java:69 :INFO ] Creating new dispatcher [catalog#DEMO1] > (http-bio-0.0.0.0-8443-exec-11) > > 2013-03-31 23:25:29,236 (http-bio-0.0.0.0-8443-exec-11) [ > ServiceDispatcher.java:585:INFO ] Slow sync service execution detected: > service [catalog#DEMO1/userLogin] finished in [1254] milliseconds > > 2013-03-31 23:25:29,237 (http-bio-0.0.0.0-8443-exec-11) [ > ServiceDispatcher.java:607:INFO ] Sync service [catalog#DEMO1/userLogin] > finished in [1254] milliseconds with response > [{userLogin=[GenericEntity:UserLogin][createdStamp,2013-03-31 > 19:47:04.28(java.sql.Timestamp)][createdTxStamp,2013-03-31 > 19:47:02.531(java.sql.Timestamp)][currentPassword,{SHA}47ca69ebb4bdc9ae0adec > 130880165d2cc05db1a(java.lang.String)][disabledDateTime,null()][enabled,Y(ja > va.lang.String)][externalAuthId,null()][hasLoggedOut,N(java.lang.String)][is > System,null()][lastCurrencyUom,null()][lastLocale,null()][lastTimeZone,null( > )][lastUpdatedStamp,2013-03-31 > 23:25:28.506(java.sql.Timestamp)][lastUpdatedTxStamp,2013-03-31 > 23:25:28.505(java.sql.Timestamp)][partyId,admin(java.lang.String)][passwordH > int,null()][requirePasswordChange,null()][successiveFailedLogins,null()][use > rLdapDn,null()][userLoginId,admin(java.lang.String)], > responseMessage=success}] > > 2013-03-31 23:25:29,242 (http-bio-0.0.0.0-8443-exec-11) [ > VisitHandler.java:232:INFO ] Found visitorId [10000] in cookie > > > > Using MySQL and default Multi-tenant setting: > > > > 2013-04-01 17:21:42,154 (http-bio-0.0.0.0-8443-exec-4) [ > GenericDispatcher.java:69 :INFO ] Creating new dispatcher [catalog#DEMO2] > (http-bio-0.0.0.0-8443-exec-4) > > 2013-04-01 17:21:42,197 (http-bio-0.0.0.0-8443-exec-4) [ > LoginServices.java:410:INFO ] [LoginServices.userLogin] : Invalid User : > User not found. > > 2013-04-01 17:21:42,197 (http-bio-0.0.0.0-8443-exec-4) [ > ServiceDispatcher.java:525:ERROR] Error in Service [userLogin]: User not > found. > > 2013-04-01 17:21:42,200 (http-bio-0.0.0.0-8443-exec-4) [ > TransactionUtil.java:342:ERROR] > > ---- exception report > ---------------------------------------------------------- > > [TransactionUtil.rollback] > > Exception: java.lang.Exception > > Message: Stack Trace > > ---- stack trace > --------------------------------------------------------------- > > java.lang.Exception: Stack Trace > > org.ofbiz.entity.transaction.TransactionUtil.rollback(TransactionUtil.java:3 > 41) > > org.ofbiz.entity.transaction.TransactionUtil.rollback(TransactionUtil.java:3 > 18) > > org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:529) > > org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:225) > > org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:163) > > org.ofbiz.webapp.control.LoginWorker.login(LoginWorker.java:395) > > sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > > sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57 > ) > > sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl > .java:43) > > java.lang.reflect.Method.invoke(Method.java:601) > > org.ofbiz.webapp.event.JavaEventHandler.invoke(JavaEventHandler.java:93) > > org.ofbiz.webapp.event.JavaEventHandler.invoke(JavaEventHandler.java:79) > > org.ofbiz.webapp.control.RequestHandler.runEvent(RequestHandler.java:660) > > org.ofbiz.webapp.control.RequestHandler.doRequest(RequestHandler.java:406) > > org.ofbiz.webapp.control.ControlServlet.doGet(ControlServlet.java:224) > > org.ofbiz.webapp.control.ControlServlet.doPost(ControlServlet.java:87) > > javax.servlet.http.HttpServlet.service(HttpServlet.java:641) > > javax.servlet.http.HttpServlet.service(HttpServlet.java:722) > > org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(Application > FilterChain.java:305) > > org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterCh > ain.java:210) > > org.ofbiz.webapp.control.ContextFilter.doFilter(ContextFilter.java:337) > > org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(Application > FilterChain.java:243) > > org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterCh > ain.java:210) > > org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.ja > va:222) > > org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.ja > va:123) > > org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase > .java:472) > > org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:168 > ) > > org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:99) > > org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java > :118) > > org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:929) > > org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:407) > > org.apache.coyote.http11.AbstractHttp11Processor.process(AbstractHttp11Proce > ssor.java:1002) > > org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(Abstrac > tProtocol.java:585) > > org.apache.tomcat.util.net.JIoEndpoint$SocketProcessor.run(JIoEndpoint.java: > 312) > > java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:11 > 45) > > java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:6 > 15) > > java.lang.Thread.run(Thread.java:722) > > ---------------------------------------------------------------------------- > ---- > > > > 2013-04-01 17:21:42,202 (http-bio-0.0.0.0-8443-exec-4) [ > TransactionUtil.java:352:INFO ] [TransactionUtil.rollback] transaction > rolled back > > 2013-04-01 17:21:42,202 (http-bio-0.0.0.0-8443-exec-4) [ > RequestHandler.java:661:INFO ] Ran Event > [java:org.ofbiz.webapp.control.LoginWorker#login] from [request], result is > [error] > > 2013-04-01 17:21:42,203 (http-bio-0.0.0.0-8443-exec-4) [ > RequestHandler.java:444:ERROR] Request login caused an error with the > following message: following error occurred during login: User not found. > > 2013-04-01 17:21:42,203 (http-bio-0.0.0.0-8443-exec-4) [ > RequestHandler.java:763:INFO ] Rendering View [login], > sessionId=8C89BBB1FD50E6CB7532C88BC90E6006.jvm1 > > > > Best regards. > > > > Derek Lew > > > > |
Ok, will use Postgres instead, thanks!
Best regards. Derek Lew -----Original Message----- From: Jacques Le Roux [mailto:[hidden email]] Sent: Tuesday, 2 April, 2013 2:11 PM To: [hidden email] Subject: Re: Multi-tenant Demo using MySQL - LoginServices.java:410:INFO ] [LoginServices.userLogin] : Invalid User : User not found. MySql had issues with milliseconds until last version. Better use Postgres or update to last MySql version You may find plenty about it searching in the OFBiz ML or googling for "mysql milliseconds" Note: there is still an issue with VisitHandler but with Derby and Postgres you have 1000 time less risk to cross it. Ha finally found the related Jira: https://issues.apache.org/jira/browse/OFBIZ-3972 Jacques From: "Derek Lew" <[hidden email]> > Hi > > > > I am having a problem with Multi-tenant demo using MySQL, please refer > to the log entries below. What could have been the problem? > > > > Using Derby and default Multi-tenant setting: > > > > 2013-03-31 23:25:27,981 (http-bio-0.0.0.0-8443-exec-11) [ > GenericDispatcher.java:69 :INFO ] Creating new dispatcher > [catalog#DEMO1] > (http-bio-0.0.0.0-8443-exec-11) > > 2013-03-31 23:25:29,236 (http-bio-0.0.0.0-8443-exec-11) [ > ServiceDispatcher.java:585:INFO ] Slow sync service execution detected: > service [catalog#DEMO1/userLogin] finished in [1254] milliseconds > > 2013-03-31 23:25:29,237 (http-bio-0.0.0.0-8443-exec-11) [ > ServiceDispatcher.java:607:INFO ] Sync service > [catalog#DEMO1/userLogin] finished in [1254] milliseconds with > response > [{userLogin=[GenericEntity:UserLogin][createdStamp,2013-03-31 > 19:47:04.28(java.sql.Timestamp)][createdTxStamp,2013-03-31 > 19:47:02.531(java.sql.Timestamp)][currentPassword,{SHA}47ca69ebb4bdc9a > e0adec > 130880165d2cc05db1a(java.lang.String)][disabledDateTime,null()][enable > d,Y(ja > va.lang.String)][externalAuthId,null()][hasLoggedOut,N(java.lang.Strin > g)][is > System,null()][lastCurrencyUom,null()][lastLocale,null()][lastTimeZone > ,null( > )][lastUpdatedStamp,2013-03-31 > 23:25:28.506(java.sql.Timestamp)][lastUpdatedTxStamp,2013-03-31 > 23:25:28.505(java.sql.Timestamp)][partyId,admin(java.lang.String)][pas > swordH > int,null()][requirePasswordChange,null()][successiveFailedLogins,null( > )][use rLdapDn,null()][userLoginId,admin(java.lang.String)], > responseMessage=success}] > > 2013-03-31 23:25:29,242 (http-bio-0.0.0.0-8443-exec-11) [ > VisitHandler.java:232:INFO ] Found visitorId [10000] in cookie > > > > Using MySQL and default Multi-tenant setting: > > > > 2013-04-01 17:21:42,154 (http-bio-0.0.0.0-8443-exec-4) [ > GenericDispatcher.java:69 :INFO ] Creating new dispatcher > [catalog#DEMO2] > (http-bio-0.0.0.0-8443-exec-4) > > 2013-04-01 17:21:42,197 (http-bio-0.0.0.0-8443-exec-4) [ > LoginServices.java:410:INFO ] [LoginServices.userLogin] : Invalid User : > User not found. > > 2013-04-01 17:21:42,197 (http-bio-0.0.0.0-8443-exec-4) [ > ServiceDispatcher.java:525:ERROR] Error in Service [userLogin]: User > not found. > > 2013-04-01 17:21:42,200 (http-bio-0.0.0.0-8443-exec-4) [ > TransactionUtil.java:342:ERROR] > > ---- exception report > ---------------------------------------------------------- > > [TransactionUtil.rollback] > > Exception: java.lang.Exception > > Message: Stack Trace > > ---- stack trace > --------------------------------------------------------------- > > java.lang.Exception: Stack Trace > > org.ofbiz.entity.transaction.TransactionUtil.rollback(TransactionUtil. > java:3 > 41) > > org.ofbiz.entity.transaction.TransactionUtil.rollback(TransactionUtil. > java:3 > 18) > > org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:529 > ) > > org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:225 > ) > > org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:163 > ) > > org.ofbiz.webapp.control.LoginWorker.login(LoginWorker.java:395) > > sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) > > sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.j > ava:57 > ) > > sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccess > orImpl > .java:43) > > java.lang.reflect.Method.invoke(Method.java:601) > > org.ofbiz.webapp.event.JavaEventHandler.invoke(JavaEventHandler.java:9 > 3) > > org.ofbiz.webapp.event.JavaEventHandler.invoke(JavaEventHandler.java:7 > 9) > > org.ofbiz.webapp.control.RequestHandler.runEvent(RequestHandler.java:6 > 60) > > org.ofbiz.webapp.control.RequestHandler.doRequest(RequestHandler.java: > 406) > > org.ofbiz.webapp.control.ControlServlet.doGet(ControlServlet.java:224) > > org.ofbiz.webapp.control.ControlServlet.doPost(ControlServlet.java:87) > > javax.servlet.http.HttpServlet.service(HttpServlet.java:641) > > javax.servlet.http.HttpServlet.service(HttpServlet.java:722) > > org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(Appli > cation > FilterChain.java:305) > > org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFi > lterCh > ain.java:210) > > org.ofbiz.webapp.control.ContextFilter.doFilter(ContextFilter.java:337 > ) > > org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(Appli > cation > FilterChain.java:243) > > org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFi > lterCh > ain.java:210) > > org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperVa > lve.ja > va:222) > > org.apache.catalina.core.StandardContextValve.invoke(StandardContextVa > lve.ja > va:123) > > org.apache.catalina.authenticator.AuthenticatorBase.invoke(Authenticat > orBase > .java:472) > > org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.ja > va:168 > ) > > org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.ja > va:99) > > org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValv > e.java > :118) > > org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:9 > 29) > > org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java > :407) > > org.apache.coyote.http11.AbstractHttp11Processor.process(AbstractHttp1 > 1Proce > ssor.java:1002) > > org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(A > bstrac > tProtocol.java:585) > > > 312) > > java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.j > ava:11 > 45) > > java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor. > java:6 > 15) > > java.lang.Thread.run(Thread.java:722) > > ---------------------------------------------------------------------- > ------ > ---- > > > > 2013-04-01 17:21:42,202 (http-bio-0.0.0.0-8443-exec-4) [ > TransactionUtil.java:352:INFO ] [TransactionUtil.rollback] transaction > rolled back > > 2013-04-01 17:21:42,202 (http-bio-0.0.0.0-8443-exec-4) [ > RequestHandler.java:661:INFO ] Ran Event > [java:org.ofbiz.webapp.control.LoginWorker#login] from [request], > result is [error] > > 2013-04-01 17:21:42,203 (http-bio-0.0.0.0-8443-exec-4) [ > RequestHandler.java:444:ERROR] Request login caused an error with the > following message: following error occurred during login: User not found. > > 2013-04-01 17:21:42,203 (http-bio-0.0.0.0-8443-exec-4) [ > RequestHandler.java:763:INFO ] Rendering View [login], > sessionId=8C89BBB1FD50E6CB7532C88BC90E6006.jvm1 > > > > Best regards. > > > > Derek Lew > > > > No virus found in this message. Checked by AVG - www.avg.com Version: 2013.0.2904 / Virus Database: 2641/6215 - Release Date: 03/30/13 |
Free forum by Nabble | Edit this page |