Mar 18, 2014 11:50:31 AM org.apache.catalina.core.AprLifecycleListener init Informações: The APR based Apache Tomcat Native library which allows optimal performance in production environments was not found on the java.library.path: C:\Program Files\Java\jre7\bin;C:\Windows\Sun\Java\bin;C:\Windows\system32;C:\Windows;C:\Program Files (x86)\NVIDIA Corporation\PhysX\Common;C:\Program Files\Common Files\Microsoft Shared\Windows Live;C:\Program Files (x86)\Common Files\Microsoft Shared\Windows Live;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0\;C:\Program Files\TortoiseSVN\bin;C:\Program Files (x86)\Windows Live\Shared;. Mar 18, 2014 11:50:31 AM org.apache.tomcat.util.digester.SetPropertiesRule begin Advertência: [SetPropertiesRule]{Server/Service/Engine/Host/Context} Setting property 'source' to 'org.eclipse.jst.j2ee.server:dhis2' did not find a matching property. Mar 18, 2014 11:50:31 AM org.apache.coyote.AbstractProtocol init Informações: Initializing ProtocolHandler ["http-bio-8080"] Mar 18, 2014 11:50:31 AM org.apache.coyote.AbstractProtocol init Informações: Initializing ProtocolHandler ["ajp-bio-8009"] Mar 18, 2014 11:50:31 AM org.apache.catalina.startup.Catalina load Informações: Initialization processed in 643 ms Mar 18, 2014 11:50:31 AM org.apache.catalina.core.StandardService startInternal Informações: Starting service Catalina Mar 18, 2014 11:50:31 AM org.apache.catalina.core.StandardEngine startInternal Informações: Starting Servlet Engine: Apache Tomcat/7.0.52 Mar 18, 2014 11:50:42 AM org.apache.catalina.core.ApplicationContext log Informações: No Spring WebApplicationInitializer types detected on classpath Mar 18, 2014 11:50:42 AM org.apache.catalina.core.ApplicationContext log Informações: Initializing Spring root WebApplicationContext * INFO 2014-03-18 11:50:46,532 System property dhis2.home not set (DefaultLocationManager.java [localhost-startStop-1]) * INFO 2014-03-18 11:50:46,535 Environment variable DHIS2_HOME not set (DefaultLocationManager.java [localhost-startStop-1]) * INFO 2014-03-18 11:50:46,535 Home directory set to /opt/dhis2 (DefaultLocationManager.java [localhost-startStop-1]) * INFO 2014-03-18 11:50:47,743 Hibernate configuration loaded, using dialect: org.hibernate.dialect.MySQLDialect (DefaultHibernateConfigurationProvider.java [localhost-startStop-1]) * ERROR 2014-03-18 11:54:45,439 HHH000388: Unsuccessful: alter table dataelementcategoryoption add index fk_dataelementcategory_conceptid (conceptid), add constraint fk_dataelementcategory_conceptid foreign key (conceptid) references concept (conceptid) (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2014-03-18 11:54:45,441 Can't write; duplicate key in table '#sql-1204_282' (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2014-03-18 11:54:49,092 HHH000388: Unsuccessful: alter table dataset add index fk_indicator_legendset (legendsetid), add constraint fk_indicator_legendset foreign key (legendsetid) references maplegendset (maplegendsetid) (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2014-03-18 11:54:49,094 Can't write; duplicate key in table '#sql-1204_282' (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2014-03-18 11:54:51,570 HHH000388: Unsuccessful: alter table datavalue add index fk_datavalue_attributeoptioncomboid (attributeoptioncomboid), add constraint fk_datavalue_attributeoptioncomboid foreign key (attributeoptioncomboid) references categoryoptioncombo (categoryoptioncomboid) (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2014-03-18 11:54:51,572 Cannot add or update a child row: a foreign key constraint fails (`dhis2`.`#sql-1204_282`, CONSTRAINT `fk_datavalue_attributeoptioncomboid` FOREIGN KEY (`attributeoptioncomboid`) REFERENCES `categoryoptioncombo` (`categoryoptioncomboid`)) (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2014-03-18 11:54:51,878 HHH000388: Unsuccessful: alter table interpretationcomment add index fk_interpretation_userid (userid), add constraint fk_interpretation_userid foreign key (userid) references userinfo (userinfoid) (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2014-03-18 11:54:51,880 Can't write; duplicate key in table '#sql-1204_282' (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2014-03-18 11:55:13,492 HHH000388: Unsuccessful: alter table orgunitgroup add index fk_indicator_userid (userid), add constraint fk_indicator_userid foreign key (userid) references userinfo (userinfoid) (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2014-03-18 11:55:13,493 Can't write; duplicate key in table '#sql-1204_282' (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2014-03-18 11:56:01,747 HHH000388: Unsuccessful: alter table report add index fk_report_relativeperiodsid (relativeperiodsid), add constraint fk_report_relativeperiodsid foreign key (relativeperiodsid) references relativeperiods (relativeperiodsid) (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2014-03-18 11:56:01,749 Can't write; duplicate key in table '#sql-1204_282' (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2014-03-18 11:56:02,388 HHH000388: Unsuccessful: alter table reporttable add index fk_report_relativeperiodsid (relativeperiodsid), add constraint fk_report_relativeperiodsid foreign key (relativeperiodsid) references relativeperiods (relativeperiodsid) (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2014-03-18 11:56:02,390 Can't write; duplicate key in table '#sql-1204_282' (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2014-03-18 11:56:13,579 HHH000388: Unsuccessful: alter table usergroupmembers add index fk_usergroup_userid (userid), add constraint fk_usergroup_userid foreign key (userid) references userinfo (userinfoid) (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2014-03-18 11:56:13,580 Can't write; duplicate key in table '#sql-1204_282' (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2014-03-18 11:56:15,727 HHH000388: Unsuccessful: alter table validationrulegroupuserrolestoalert add index fk_validationrulegroupuserrolestoalert_validationgroupid (validationgroupid), add constraint fk_validationrulegroupuserrolestoalert_validationgroupid foreign key (validationgroupid) references validationrulegroup (validationrulegroupid) (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2014-03-18 11:56:15,728 Cannot add foreign key constraint (SchemaUpdate.java [localhost-startStop-1]) * INFO 2014-03-18 11:56:20,944 Found the following message senders: [org.hisp.dhis.message.EmailMessageSender@e63c6c4, org.hisp.dhis.sms.SmsMessageSender@388e753d] (DefaultMessageService.java [localhost-startStop-1]) * INFO 2014-03-18 11:56:26,093 Detected apps: [] (DefaultAppManager.java [localhost-startStop-1]) * INFO 2014-03-18 11:56:27,271 Scheduled task with key: 0 0 0 ? * 1-6 and cron: 0 0 0 ? * 1-6 (SpringScheduler.java [localhost-startStop-1]) * INFO 2014-03-18 11:56:27,272 Scheduled task with key: 0 0 0 ? * 0 and cron: 0 0 0 ? * 0 (SpringScheduler.java [localhost-startStop-1]) * INFO 2014-03-18 11:56:29,185 Executing startup routine [1 of 11, runlevel 1]: PeriodTypePopulator (DefaultStartupRoutineExecutor.java [localhost-startStop-1]) * INFO 2014-03-18 11:56:29,190 Executing startup routine [2 of 11, runlevel 1]: DataElementDefaultDimensionPopulator (DefaultStartupRoutineExecutor.java [localhost-startStop-1]) * ERROR 2014-03-18 11:56:29,366 org.hibernate.PropertyAccessException: Null value was assigned to a property of primitive type setter of org.hisp.dhis.dataelement.DataElementCategory.dataDimension at org.hibernate.property.BasicPropertyAccessor$BasicSetter.set(BasicPropertyAccessor.java:108) at org.hibernate.tuple.entity.AbstractEntityTuplizer.setPropertyValues(AbstractEntityTuplizer.java:710) at org.hibernate.tuple.entity.PojoEntityTuplizer.setPropertyValues(PojoEntityTuplizer.java:371) at org.hibernate.persister.entity.AbstractEntityPersister.setPropertyValues(AbstractEntityPersister.java:4499) at org.hibernate.engine.internal.TwoPhaseLoad.doInitializeEntity(TwoPhaseLoad.java:185) at org.hibernate.engine.internal.TwoPhaseLoad.initializeEntity(TwoPhaseLoad.java:137) at org.hibernate.loader.Loader.initializeEntitiesAndCollections(Loader.java:1103) at org.hibernate.loader.Loader.processResultSet(Loader.java:960) at org.hibernate.loader.Loader.doQuery(Loader.java:910) at org.hibernate.loader.Loader.doQueryAndInitializeNonLazyCollections(Loader.java:341) at org.hibernate.loader.Loader.doList(Loader.java:2516) at org.hibernate.loader.Loader.listUsingQueryCache(Loader.java:2361) at org.hibernate.loader.Loader.list(Loader.java:2324) at org.hibernate.loader.hql.QueryLoader.list(QueryLoader.java:490) at org.hibernate.hql.internal.ast.QueryTranslatorImpl.list(QueryTranslatorImpl.java:355) at org.hibernate.engine.query.spi.HQLQueryPlan.performList(HQLQueryPlan.java:195) at org.hibernate.internal.SessionImpl.list(SessionImpl.java:1268) at org.hibernate.internal.QueryImpl.list(QueryImpl.java:101) at org.hisp.dhis.common.hibernate.HibernateIdentifiableObjectStore.getAllEqName(HibernateIdentifiableObjectStore.java:146) at org.hisp.dhis.dataelement.DefaultDataElementCategoryService.getDataElementCategoryByName(DefaultDataElementCategoryService.java:175) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) at java.lang.reflect.Method.invoke(Unknown Source) at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150) at org.springframework.transaction.interceptor.TransactionInterceptor$1.proceedWithInvocation(TransactionInterceptor.java:96) at org.springframework.transaction.interceptor.TransactionAspectSupport.invokeWithinTransaction(TransactionAspectSupport.java:260) at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:94) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) at org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:91) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204) at com.sun.proxy.$Proxy36.getDataElementCategoryByName(Unknown Source) at org.hisp.dhis.dataelement.DataElementDefaultDimensionPopulator.execute(DataElementDefaultDimensionPopulator.java:101) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) at java.lang.reflect.Method.invoke(Unknown Source) at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150) at org.springframework.transaction.interceptor.TransactionInterceptor$1.proceedWithInvocation(TransactionInterceptor.java:96) at org.springframework.transaction.interceptor.TransactionAspectSupport.invokeWithinTransaction(TransactionAspectSupport.java:260) at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:94) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204) at com.sun.proxy.$Proxy79.execute(Unknown Source) at org.hisp.dhis.system.startup.DefaultStartupRoutineExecutor.execute(DefaultStartupRoutineExecutor.java:115) at org.hisp.dhis.system.startup.DefaultStartupRoutineExecutor.execute(DefaultStartupRoutineExecutor.java:85) at org.hisp.dhis.system.startup.StartupListener.contextInitialized(StartupListener.java:68) at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:4973) at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5467) at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1559) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1549) at java.util.concurrent.FutureTask.run(Unknown Source) at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at java.lang.Thread.run(Unknown Source) Caused by: java.lang.IllegalArgumentException at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) at java.lang.reflect.Method.invoke(Unknown Source) at org.hibernate.property.BasicPropertyAccessor$BasicSetter.set(BasicPropertyAccessor.java:65) ... 60 more (StartupListener.java [localhost-startStop-1]) Mar 18, 2014 11:56:29 AM org.apache.catalina.core.StandardContext listenerStart Grave: Exception sending context initialized event to listener instance of class org.hisp.dhis.system.startup.StartupListener java.lang.RuntimeException: Failed to run startup routines: Null value was assigned to a property of primitive type setter of org.hisp.dhis.dataelement.DataElementCategory.dataDimension at org.hisp.dhis.system.startup.StartupListener.contextInitialized(StartupListener.java:74) at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:4973) at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5467) at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1559) at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1549) at java.util.concurrent.FutureTask.run(Unknown Source) at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at java.lang.Thread.run(Unknown Source) Caused by: org.hibernate.PropertyAccessException: Null value was assigned to a property of primitive type setter of org.hisp.dhis.dataelement.DataElementCategory.dataDimension at org.hibernate.property.BasicPropertyAccessor$BasicSetter.set(BasicPropertyAccessor.java:108) at org.hibernate.tuple.entity.AbstractEntityTuplizer.setPropertyValues(AbstractEntityTuplizer.java:710) at org.hibernate.tuple.entity.PojoEntityTuplizer.setPropertyValues(PojoEntityTuplizer.java:371) at org.hibernate.persister.entity.AbstractEntityPersister.setPropertyValues(AbstractEntityPersister.java:4499) at org.hibernate.engine.internal.TwoPhaseLoad.doInitializeEntity(TwoPhaseLoad.java:185) at org.hibernate.engine.internal.TwoPhaseLoad.initializeEntity(TwoPhaseLoad.java:137) at org.hibernate.loader.Loader.initializeEntitiesAndCollections(Loader.java:1103) at org.hibernate.loader.Loader.processResultSet(Loader.java:960) at org.hibernate.loader.Loader.doQuery(Loader.java:910) at org.hibernate.loader.Loader.doQueryAndInitializeNonLazyCollections(Loader.java:341) at org.hibernate.loader.Loader.doList(Loader.java:2516) at org.hibernate.loader.Loader.listUsingQueryCache(Loader.java:2361) at org.hibernate.loader.Loader.list(Loader.java:2324) at org.hibernate.loader.hql.QueryLoader.list(QueryLoader.java:490) at org.hibernate.hql.internal.ast.QueryTranslatorImpl.list(QueryTranslatorImpl.java:355) at org.hibernate.engine.query.spi.HQLQueryPlan.performList(HQLQueryPlan.java:195) at org.hibernate.internal.SessionImpl.list(SessionImpl.java:1268) at org.hibernate.internal.QueryImpl.list(QueryImpl.java:101) at org.hisp.dhis.common.hibernate.HibernateIdentifiableObjectStore.getAllEqName(HibernateIdentifiableObjectStore.java:146) at org.hisp.dhis.dataelement.DefaultDataElementCategoryService.getDataElementCategoryByName(DefaultDataElementCategoryService.java:175) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) at java.lang.reflect.Method.invoke(Unknown Source) at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150) at org.springframework.transaction.interceptor.TransactionInterceptor$1.proceedWithInvocation(TransactionInterceptor.java:96) at org.springframework.transaction.interceptor.TransactionAspectSupport.invokeWithinTransaction(TransactionAspectSupport.java:260) at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:94) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) at org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:91) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204) at com.sun.proxy.$Proxy36.getDataElementCategoryByName(Unknown Source) at org.hisp.dhis.dataelement.DataElementDefaultDimensionPopulator.execute(DataElementDefaultDimensionPopulator.java:101) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) at java.lang.reflect.Method.invoke(Unknown Source) at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150) at org.springframework.transaction.interceptor.TransactionInterceptor$1.proceedWithInvocation(TransactionInterceptor.java:96) at org.springframework.transaction.interceptor.TransactionAspectSupport.invokeWithinTransaction(TransactionAspectSupport.java:260) at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:94) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204) at com.sun.proxy.$Proxy79.execute(Unknown Source) at org.hisp.dhis.system.startup.DefaultStartupRoutineExecutor.execute(DefaultStartupRoutineExecutor.java:115) at org.hisp.dhis.system.startup.DefaultStartupRoutineExecutor.execute(DefaultStartupRoutineExecutor.java:85) at org.hisp.dhis.system.startup.StartupListener.contextInitialized(StartupListener.java:68) ... 9 more Caused by: java.lang.IllegalArgumentException at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) at java.lang.reflect.Method.invoke(Unknown Source) at org.hibernate.property.BasicPropertyAccessor$BasicSetter.set(BasicPropertyAccessor.java:65) ... 60 more Mar 18, 2014 11:56:29 AM org.apache.catalina.core.StandardContext startInternal Grave: Error listenerStart Mar 18, 2014 11:56:29 AM org.apache.catalina.core.StandardContext startInternal Grave: Context [/dhis2] startup failed due to previous errors * INFO 2014-03-18 11:56:29,374 De-registering jdbc driver: sun.jdbc.odbc.JdbcOdbcDriver@7cc88805 (StartupListener.java [localhost-startStop-1]) * INFO 2014-03-18 11:56:29,374 De-registering jdbc driver: com.mysql.jdbc.Driver@3ce01410 (StartupListener.java [localhost-startStop-1]) Mar 18, 2014 11:56:29 AM org.apache.catalina.core.ApplicationContext log Informações: Closing Spring root WebApplicationContext Mar 18, 2014 11:56:29 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads Grave: The web application [/dhis2] appears to have started a thread named [Abandoned connection cleanup thread] but has failed to stop it. This is very likely to create a memory leak. Mar 18, 2014 11:56:29 AM org.apache.coyote.AbstractProtocol start Informações: Starting ProtocolHandler ["http-bio-8080"] Mar 18, 2014 11:56:29 AM org.apache.coyote.AbstractProtocol start Informações: Starting ProtocolHandler ["ajp-bio-8009"] Mar 18, 2014 11:56:29 AM org.apache.catalina.startup.Catalina start Informações: Server startup in 357801 ms