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" <derek_lew...@yahoo.com.sg> > 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 > > > >