Public bug reported: Hello all,
I am trying to install Dhis on ubuntu desktop 14.04.I av installed tomcat,java and copied the war file to webapps.The tomcat starts well and deploys the war file. But when i try to access the dhis from my webbrowser,it gives the error below HTTP Status 404 - /dhis type Status report message /dhis description The requested resource is not available. Apache Tomcat/7.0.42 I have tried it on several browsers,its the same thing.Can someone please help me ? This is a snap shot of the catalina.out result of the tomcat Aug 20, 2015 4:21:00 PM org.apache.coyote.AbstractProtocol init INFO: Initializing ProtocolHandler ["ajp-bio-8009"] Aug 20, 2015 4:21:00 PM org.apache.catalina.startup.Catalina load INFO: Initialization processed in 375 ms Aug 20, 2015 4:21:01 PM org.apache.catalina.core.StandardService startInternal INFO: Starting service Catalina Aug 20, 2015 4:21:01 PM org.apache.catalina.core.StandardEngine startInternal INFO: Starting Servlet Engine: Apache Tomcat/7.0.42 Aug 20, 2015 4:21:01 PM org.apache.catalina.startup.HostConfig deployWAR INFO: Deploying web application archive /usr/share/apache-tomcat-7.0.42/webapps/dhis.war * INFO 2015-08-20 16:21:11,055 System property dhis2.home not set (DefaultLocationManager.java [localhost-startStop-1]) * INFO 2015-08-20 16:21:11,057 Environment variable DHIS2_HOME points to /home/inveneo/DHIS2_HOME/dhis (DefaultLocationManager.java [localhost-startStop-1]) * INFO 2015-08-20 16:21:12,083 Hibernate configuration loaded, using dialect: org.hibernate.dialect.PostgreSQLDialect (DefaultHibernateConfigurationProvider.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,179 HHH000388: Unsuccessful: alter table completedatasetregistration add column attributeoptioncomboid int4 not null (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,181 ERROR: column "attributeoptioncomboid" contains null values (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,185 HHH000388: Unsuccessful: alter table datavalue add column attributeoptioncomboid int4 not null (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,187 ERROR: column "attributeoptioncomboid" contains null values (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,190 HHH000388: Unsuccessful: alter table minmaxdataelement add column minimumvalue int4 not null (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,191 ERROR: column "minimumvalue" contains null values (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,194 HHH000388: Unsuccessful: alter table minmaxdataelement add column maximumvalue int4 not null (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,195 ERROR: column "maximumvalue" contains null values (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,199 HHH000388: Unsuccessful: alter table translation add column translationid int4 not null (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,199 ERROR: column "translationid" contains null values (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,202 HHH000388: Unsuccessful: alter table completedatasetregistration add constraint fk_completedatasetregistration_attributeoptioncomboid foreign key (attributeoptioncomboid) references categoryoptioncombo (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,203 ERROR: column "attributeoptioncomboid" referenced in foreign key constraint does not exist (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,206 HHH000388: Unsuccessful: alter table datavalue add constraint fk_datavalue_attributeoptioncomboid foreign key (attributeoptioncomboid) references categoryoptioncombo (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,208 ERROR: column "attributeoptioncomboid" referenced in foreign key constraint does not exist (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,210 HHH000388: Unsuccessful: alter table programstage add constraint fk_programstage_dataentryform foreign key (dataentryformid) references dataentryform (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,211 ERROR: constraint "fk_programstage_dataentryform" for relation "programstage" already exists (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,215 HHH000388: Unsuccessful: alter table sqlviewusergroupaccesses add constraint FK_ftn43gpevax8by169pld7dl5d foreign key (sqlviewid) references sqlview (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,216 ERROR: constraint "fk_ftn43gpevax8by169pld7dl5d" for relation "sqlviewusergroupaccesses" already exists (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,219 HHH000388: Unsuccessful: alter table validationrulegroupmembers add constraint fk_validationrulegroupmembers_validationrulegroupid foreign key (validationgroupid) references validationrulegroup (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,221 ERROR: constraint "fk_validationrulegroupmembers_validationrulegroupid" for relation "validationrulegroupmembers" already exists (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,224 HHH000388: Unsuccessful: alter table validationrulegroupusergroupaccesses add constraint FK_932w5l8vbyvybhsrwwadjd3cp foreign key (validationrulegroupid) references validationrulegroup (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,225 ERROR: constraint "fk_932w5l8vbyvybhsrwwadjd3cp" for relation "validationrulegroupusergroupaccesses" already exists (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,228 HHH000388: Unsuccessful: alter table validationrulegroupusergroupstoalert add constraint fk_validationrulegroupusergroupstoalert_validationgroupid foreign key (validationgroupid) references validationrulegroup (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,229 ERROR: constraint "fk_validationrulegroupusergroupstoalert_validationgroupid" for relation "validationrulegroupusergroupstoalert" already exists (SchemaUpdate.java [localhost-startStop-1]) * INFO 2015-08-20 16:21:26,000 Found the following message senders: [org.hisp.dhis.message.EmailMessageSender@7c267dd0, org.hisp.dhis.sms.SmsMessageSender@615318b9] (DefaultMessageService.java [localhost-startStop-1]) * INFO 2015-08-20 16:21:31,908 Detected apps: [] (DefaultAppManager.java [localhost-startStop-1]) * INFO 2015-08-20 16:21:33,190 Added logger: org.hisp.dhis.resourcetable using file: /home/inveneo/DHIS2_HOME/dhis/logs/dhis-analytics-table.log (Log4JLogConfigInitializer.java [localhost-startStop-1]) * INFO 2015-08-20 16:21:33,191 Added logger: org.hisp.dhis.analytics.table using file: /home/inveneo/DHIS2_HOME/dhis/logs/dhis-analytics-table.log (Log4JLogConfigInitializer.java [localhost-startStop-1]) * INFO 2015-08-20 16:21:33,191 Added logger: org.hisp.dhis.dxf2 using file: /home/inveneo/DHIS2_HOME/dhis/logs/dhis-data-exchange.log (Log4JLogConfigInitializer.java [localhost-startStop-1]) * INFO 2015-08-20 16:21:33,191 Added logger: org.hisp.dhis.dxf2.synch using file: /home/inveneo/DHIS2_HOME/dhis/logs/dhis-data-sync.log (Log4JLogConfigInitializer.java [localhost-startStop-1]) * INFO 2015-08-20 16:21:33,192 Added root logger using file: /home/inveneo/DHIS2_HOME/dhis/logs/dhis.log (Log4JLogConfigInitializer.java [localhost-startStop-1]) * WARN 2015-08-20 16:21:33,724 RP discovery / realm validation disabled; (RealmVerifier.java [localhost-startStop-1]) * INFO 2015-08-20 16:21:34,751 Executing startup routine [1 of 14, runlevel 1]: InitTableAlteror (DefaultStartupRoutineExecutor.java [localhost-startStop-1]) * INFO 2015-08-20 16:21:34,950 Upgraded program stage data elements (InitTableAlteror.java [localhost-startStop-1]) * INFO 2015-08-20 16:21:34,951 Executing startup routine [2 of 14, runlevel 2]: PeriodTypePopulator (DefaultStartupRoutineExecutor.java [localhost-startStop-1]) * INFO 2015-08-20 16:21:34,969 Executing startup routine [3 of 14, runlevel 2]: DataElementDefaultDimensionPopulator (DefaultStartupRoutineExecutor.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:34,986 org.hibernate.PropertyAccessException: Null value was assigned to a property of primitive type setter of org.hisp.dhis.common.BaseDimensionalObject.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:379) at org.hibernate.persister.entity.AbstractEntityPersister.setPropertyValues(AbstractEntityPersister.java:4522) at org.hibernate.engine.internal.TwoPhaseLoad.doInitializeEntity(TwoPhaseLoad.java:186) at org.hibernate.engine.internal.TwoPhaseLoad.initializeEntity(TwoPhaseLoad.java:137) at org.hibernate.loader.Loader.initializeEntitiesAndCollections(Loader.java:1112) at org.hibernate.loader.Loader.processResultSet(Loader.java:969) at org.hibernate.loader.Loader.doQuery(Loader.java:917) at org.hibernate.loader.Loader.doQueryAndInitializeNonLazyCollections(Loader.java:348) at org.hibernate.loader.Loader.doList(Loader.java:2550) at org.hibernate.loader.Loader.listUsingQueryCache(Loader.java:2395) at org.hibernate.loader.Loader.list(Loader.java:2358) at org.hibernate.loader.criteria.CriteriaLoader.list(CriteriaLoader.java:124) at org.hibernate.internal.SessionImpl.list(SessionImpl.java:1623) at org.hibernate.internal.CriteriaImpl.list(CriteriaImpl.java:374) at org.hisp.dhis.common.hibernate.HibernateIdentifiableObjectStore.getAllEqName(HibernateIdentifiableObjectStore.java:180) at org.hisp.dhis.dataelement.DefaultDataElementCategoryService.getDataElementCategoryByName(DefaultDataElementCategoryService.java:212) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:606) at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:190) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:157) at org.springframework.transaction.interceptor.TransactionInterceptor$1.proceedWithInvocation(TransactionInterceptor.java:99) at org.springframework.transaction.interceptor.TransactionAspectSupport.invokeWithinTransaction(TransactionAspectSupport.java:281) at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:96) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:179) at org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:92) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:179) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:207) at com.sun.proxy.$Proxy38.getDataElementCategoryByName(Unknown Source) at org.hisp.dhis.dataelement.DataElementDefaultDimensionPopulator.execute(DataElementDefaultDimensionPopulator.java:82) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:606) at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:190) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:157) at org.springframework.transaction.interceptor.TransactionInterceptor$1.proceedWithInvocation(TransactionInterceptor.java:99) at org.springframework.transaction.interceptor.TransactionAspectSupport.invokeWithinTransaction(TransactionAspectSupport.java:281) at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:96) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:179) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:207) at com.sun.proxy.$Proxy84.execute(Unknown Source) at org.hisp.dhis.system.startup.DefaultStartupRoutineExecutor.execute(DefaultStartupRoutineExecutor.java:119) at org.hisp.dhis.system.startup.DefaultStartupRoutineExecutor.execute(DefaultStartupRoutineExecutor.java:88) at org.hisp.dhis.system.startup.StartupListener.contextInitialized(StartupListener.java:68) at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:4939) at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5434) at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150) at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:901) at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:877) at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:633) at org.apache.catalina.startup.HostConfig.deployWAR(HostConfig.java:976) at org.apache.catalina.startup.HostConfig$DeployWar.run(HostConfig.java:1653) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:724) Caused by: java.lang.IllegalArgumentException at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:606) at org.hibernate.property.BasicPropertyAccessor$BasicSetter.set(BasicPropertyAccessor.java:65) ... 63 more (StartupListener.java [localhost-startStop-1]) Aug 20, 2015 4:21:34 PM org.apache.catalina.core.StandardContext startInternal SEVERE: Error listenerStart Aug 20, 2015 4:21:34 PM org.apache.catalina.core.StandardContext startInternal SEVERE: Context [/dhis] startup failed due to previous errors * INFO 2015-08-20 16:21:34,993 De-registering jdbc driver: sun.jdbc.odbc.JdbcOdbcDriver@1629bc56 (StartupListener.java [localhost-startStop-1]) * INFO 2015-08-20 16:21:34,993 De-registering jdbc driver: org.postgresql.Driver@14c944d8 (StartupListener.java [localhost-startStop-1]) Aug 20, 2015 4:21:35 PM org.apache.catalina.loader.WebappClassLoader checkThreadLocalMapForLeaks SEVERE: The web application [/dhis] created a ThreadLocal with key of type [java.lang.ThreadLocal] (value [java.lang.ThreadLocal@726b1eee]) and a value of type [org.springframework.security.core.context.SecurityContextImpl] (value [org.springframework.security.core.context.SecurityContextImpl@ffffffff: Null authentication]) but failed to remove it when the web application was stopped. Threads are going to be renewed over time to try and avoid a probable memory leak. Aug 20, 2015 4:21:35 PM org.apache.catalina.startup.HostConfig deployDirectory INFO: Deploying web application directory /usr/share/apache-tomcat-7.0.42/webapps/ROOT Aug 20, 2015 4:21:35 PM org.apache.catalina.startup.HostConfig deployDirectory INFO: Deploying web application directory /usr/share/apache-tomcat-7.0.42/webapps/host-manager Aug 20, 2015 4:21:35 PM org.apache.catalina.startup.HostConfig deployDirectory INFO: Deploying web application directory /usr/share/apache-tomcat-7.0.42/webapps/examples Aug 20, 2015 4:21:35 PM org.apache.catalina.startup.HostConfig deployDirectory INFO: Deploying web application directory /usr/share/apache-tomcat-7.0.42/webapps/manager Aug 20, 2015 4:21:35 PM org.apache.catalina.startup.HostConfig deployDirectory INFO: Deploying web application directory /usr/share/apache-tomcat-7.0.42/webapps/docs Aug 20, 2015 4:21:35 PM org.apache.coyote.AbstractProtocol start INFO: Starting ProtocolHandler ["http-bio-8080"] Aug 20, 2015 4:21:35 PM org.apache.coyote.AbstractProtocol start INFO: Starting ProtocolHandler ["ajp-bio-8009"] Aug 20, 2015 4:21:35 PM org.apache.catalina.startup.Catalina start INFO: Server startup in 34288 ms ** Affects: dhis2 Importance: Undecided Status: New -- You received this bug notification because you are a member of DHIS 2 developers, which is subscribed to DHIS. https://bugs.launchpad.net/bugs/1487121 Title: tomcat is not displaying dhis on web browser Status in DHIS: New Bug description: Hello all, I am trying to install Dhis on ubuntu desktop 14.04.I av installed tomcat,java and copied the war file to webapps.The tomcat starts well and deploys the war file. But when i try to access the dhis from my webbrowser,it gives the error below HTTP Status 404 - /dhis type Status report message /dhis description The requested resource is not available. Apache Tomcat/7.0.42 I have tried it on several browsers,its the same thing.Can someone please help me ? This is a snap shot of the catalina.out result of the tomcat Aug 20, 2015 4:21:00 PM org.apache.coyote.AbstractProtocol init INFO: Initializing ProtocolHandler ["ajp-bio-8009"] Aug 20, 2015 4:21:00 PM org.apache.catalina.startup.Catalina load INFO: Initialization processed in 375 ms Aug 20, 2015 4:21:01 PM org.apache.catalina.core.StandardService startInternal INFO: Starting service Catalina Aug 20, 2015 4:21:01 PM org.apache.catalina.core.StandardEngine startInternal INFO: Starting Servlet Engine: Apache Tomcat/7.0.42 Aug 20, 2015 4:21:01 PM org.apache.catalina.startup.HostConfig deployWAR INFO: Deploying web application archive /usr/share/apache-tomcat-7.0.42/webapps/dhis.war * INFO 2015-08-20 16:21:11,055 System property dhis2.home not set (DefaultLocationManager.java [localhost-startStop-1]) * INFO 2015-08-20 16:21:11,057 Environment variable DHIS2_HOME points to /home/inveneo/DHIS2_HOME/dhis (DefaultLocationManager.java [localhost-startStop-1]) * INFO 2015-08-20 16:21:12,083 Hibernate configuration loaded, using dialect: org.hibernate.dialect.PostgreSQLDialect (DefaultHibernateConfigurationProvider.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,179 HHH000388: Unsuccessful: alter table completedatasetregistration add column attributeoptioncomboid int4 not null (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,181 ERROR: column "attributeoptioncomboid" contains null values (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,185 HHH000388: Unsuccessful: alter table datavalue add column attributeoptioncomboid int4 not null (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,187 ERROR: column "attributeoptioncomboid" contains null values (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,190 HHH000388: Unsuccessful: alter table minmaxdataelement add column minimumvalue int4 not null (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,191 ERROR: column "minimumvalue" contains null values (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,194 HHH000388: Unsuccessful: alter table minmaxdataelement add column maximumvalue int4 not null (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,195 ERROR: column "maximumvalue" contains null values (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,199 HHH000388: Unsuccessful: alter table translation add column translationid int4 not null (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,199 ERROR: column "translationid" contains null values (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,202 HHH000388: Unsuccessful: alter table completedatasetregistration add constraint fk_completedatasetregistration_attributeoptioncomboid foreign key (attributeoptioncomboid) references categoryoptioncombo (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,203 ERROR: column "attributeoptioncomboid" referenced in foreign key constraint does not exist (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,206 HHH000388: Unsuccessful: alter table datavalue add constraint fk_datavalue_attributeoptioncomboid foreign key (attributeoptioncomboid) references categoryoptioncombo (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,208 ERROR: column "attributeoptioncomboid" referenced in foreign key constraint does not exist (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,210 HHH000388: Unsuccessful: alter table programstage add constraint fk_programstage_dataentryform foreign key (dataentryformid) references dataentryform (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,211 ERROR: constraint "fk_programstage_dataentryform" for relation "programstage" already exists (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,215 HHH000388: Unsuccessful: alter table sqlviewusergroupaccesses add constraint FK_ftn43gpevax8by169pld7dl5d foreign key (sqlviewid) references sqlview (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,216 ERROR: constraint "fk_ftn43gpevax8by169pld7dl5d" for relation "sqlviewusergroupaccesses" already exists (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,219 HHH000388: Unsuccessful: alter table validationrulegroupmembers add constraint fk_validationrulegroupmembers_validationrulegroupid foreign key (validationgroupid) references validationrulegroup (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,221 ERROR: constraint "fk_validationrulegroupmembers_validationrulegroupid" for relation "validationrulegroupmembers" already exists (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,224 HHH000388: Unsuccessful: alter table validationrulegroupusergroupaccesses add constraint FK_932w5l8vbyvybhsrwwadjd3cp foreign key (validationrulegroupid) references validationrulegroup (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,225 ERROR: constraint "fk_932w5l8vbyvybhsrwwadjd3cp" for relation "validationrulegroupusergroupaccesses" already exists (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,228 HHH000388: Unsuccessful: alter table validationrulegroupusergroupstoalert add constraint fk_validationrulegroupusergroupstoalert_validationgroupid foreign key (validationgroupid) references validationrulegroup (SchemaUpdate.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:22,229 ERROR: constraint "fk_validationrulegroupusergroupstoalert_validationgroupid" for relation "validationrulegroupusergroupstoalert" already exists (SchemaUpdate.java [localhost-startStop-1]) * INFO 2015-08-20 16:21:26,000 Found the following message senders: [org.hisp.dhis.message.EmailMessageSender@7c267dd0, org.hisp.dhis.sms.SmsMessageSender@615318b9] (DefaultMessageService.java [localhost-startStop-1]) * INFO 2015-08-20 16:21:31,908 Detected apps: [] (DefaultAppManager.java [localhost-startStop-1]) * INFO 2015-08-20 16:21:33,190 Added logger: org.hisp.dhis.resourcetable using file: /home/inveneo/DHIS2_HOME/dhis/logs/dhis-analytics-table.log (Log4JLogConfigInitializer.java [localhost-startStop-1]) * INFO 2015-08-20 16:21:33,191 Added logger: org.hisp.dhis.analytics.table using file: /home/inveneo/DHIS2_HOME/dhis/logs/dhis-analytics-table.log (Log4JLogConfigInitializer.java [localhost-startStop-1]) * INFO 2015-08-20 16:21:33,191 Added logger: org.hisp.dhis.dxf2 using file: /home/inveneo/DHIS2_HOME/dhis/logs/dhis-data-exchange.log (Log4JLogConfigInitializer.java [localhost-startStop-1]) * INFO 2015-08-20 16:21:33,191 Added logger: org.hisp.dhis.dxf2.synch using file: /home/inveneo/DHIS2_HOME/dhis/logs/dhis-data-sync.log (Log4JLogConfigInitializer.java [localhost-startStop-1]) * INFO 2015-08-20 16:21:33,192 Added root logger using file: /home/inveneo/DHIS2_HOME/dhis/logs/dhis.log (Log4JLogConfigInitializer.java [localhost-startStop-1]) * WARN 2015-08-20 16:21:33,724 RP discovery / realm validation disabled; (RealmVerifier.java [localhost-startStop-1]) * INFO 2015-08-20 16:21:34,751 Executing startup routine [1 of 14, runlevel 1]: InitTableAlteror (DefaultStartupRoutineExecutor.java [localhost-startStop-1]) * INFO 2015-08-20 16:21:34,950 Upgraded program stage data elements (InitTableAlteror.java [localhost-startStop-1]) * INFO 2015-08-20 16:21:34,951 Executing startup routine [2 of 14, runlevel 2]: PeriodTypePopulator (DefaultStartupRoutineExecutor.java [localhost-startStop-1]) * INFO 2015-08-20 16:21:34,969 Executing startup routine [3 of 14, runlevel 2]: DataElementDefaultDimensionPopulator (DefaultStartupRoutineExecutor.java [localhost-startStop-1]) * ERROR 2015-08-20 16:21:34,986 org.hibernate.PropertyAccessException: Null value was assigned to a property of primitive type setter of org.hisp.dhis.common.BaseDimensionalObject.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:379) at org.hibernate.persister.entity.AbstractEntityPersister.setPropertyValues(AbstractEntityPersister.java:4522) at org.hibernate.engine.internal.TwoPhaseLoad.doInitializeEntity(TwoPhaseLoad.java:186) at org.hibernate.engine.internal.TwoPhaseLoad.initializeEntity(TwoPhaseLoad.java:137) at org.hibernate.loader.Loader.initializeEntitiesAndCollections(Loader.java:1112) at org.hibernate.loader.Loader.processResultSet(Loader.java:969) at org.hibernate.loader.Loader.doQuery(Loader.java:917) at org.hibernate.loader.Loader.doQueryAndInitializeNonLazyCollections(Loader.java:348) at org.hibernate.loader.Loader.doList(Loader.java:2550) at org.hibernate.loader.Loader.listUsingQueryCache(Loader.java:2395) at org.hibernate.loader.Loader.list(Loader.java:2358) at org.hibernate.loader.criteria.CriteriaLoader.list(CriteriaLoader.java:124) at org.hibernate.internal.SessionImpl.list(SessionImpl.java:1623) at org.hibernate.internal.CriteriaImpl.list(CriteriaImpl.java:374) at org.hisp.dhis.common.hibernate.HibernateIdentifiableObjectStore.getAllEqName(HibernateIdentifiableObjectStore.java:180) at org.hisp.dhis.dataelement.DefaultDataElementCategoryService.getDataElementCategoryByName(DefaultDataElementCategoryService.java:212) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:606) at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:190) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:157) at org.springframework.transaction.interceptor.TransactionInterceptor$1.proceedWithInvocation(TransactionInterceptor.java:99) at org.springframework.transaction.interceptor.TransactionAspectSupport.invokeWithinTransaction(TransactionAspectSupport.java:281) at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:96) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:179) at org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:92) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:179) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:207) at com.sun.proxy.$Proxy38.getDataElementCategoryByName(Unknown Source) at org.hisp.dhis.dataelement.DataElementDefaultDimensionPopulator.execute(DataElementDefaultDimensionPopulator.java:82) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:606) at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:190) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:157) at org.springframework.transaction.interceptor.TransactionInterceptor$1.proceedWithInvocation(TransactionInterceptor.java:99) at org.springframework.transaction.interceptor.TransactionAspectSupport.invokeWithinTransaction(TransactionAspectSupport.java:281) at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:96) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:179) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:207) at com.sun.proxy.$Proxy84.execute(Unknown Source) at org.hisp.dhis.system.startup.DefaultStartupRoutineExecutor.execute(DefaultStartupRoutineExecutor.java:119) at org.hisp.dhis.system.startup.DefaultStartupRoutineExecutor.execute(DefaultStartupRoutineExecutor.java:88) at org.hisp.dhis.system.startup.StartupListener.contextInitialized(StartupListener.java:68) at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:4939) at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5434) at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150) at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:901) at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:877) at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:633) at org.apache.catalina.startup.HostConfig.deployWAR(HostConfig.java:976) at org.apache.catalina.startup.HostConfig$DeployWar.run(HostConfig.java:1653) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:724) Caused by: java.lang.IllegalArgumentException at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:606) at org.hibernate.property.BasicPropertyAccessor$BasicSetter.set(BasicPropertyAccessor.java:65) ... 63 more (StartupListener.java [localhost-startStop-1]) Aug 20, 2015 4:21:34 PM org.apache.catalina.core.StandardContext startInternal SEVERE: Error listenerStart Aug 20, 2015 4:21:34 PM org.apache.catalina.core.StandardContext startInternal SEVERE: Context [/dhis] startup failed due to previous errors * INFO 2015-08-20 16:21:34,993 De-registering jdbc driver: sun.jdbc.odbc.JdbcOdbcDriver@1629bc56 (StartupListener.java [localhost-startStop-1]) * INFO 2015-08-20 16:21:34,993 De-registering jdbc driver: org.postgresql.Driver@14c944d8 (StartupListener.java [localhost-startStop-1]) Aug 20, 2015 4:21:35 PM org.apache.catalina.loader.WebappClassLoader checkThreadLocalMapForLeaks SEVERE: The web application [/dhis] created a ThreadLocal with key of type [java.lang.ThreadLocal] (value [java.lang.ThreadLocal@726b1eee]) and a value of type [org.springframework.security.core.context.SecurityContextImpl] (value [org.springframework.security.core.context.SecurityContextImpl@ffffffff: Null authentication]) but failed to remove it when the web application was stopped. Threads are going to be renewed over time to try and avoid a probable memory leak. Aug 20, 2015 4:21:35 PM org.apache.catalina.startup.HostConfig deployDirectory INFO: Deploying web application directory /usr/share/apache-tomcat-7.0.42/webapps/ROOT Aug 20, 2015 4:21:35 PM org.apache.catalina.startup.HostConfig deployDirectory INFO: Deploying web application directory /usr/share/apache-tomcat-7.0.42/webapps/host-manager Aug 20, 2015 4:21:35 PM org.apache.catalina.startup.HostConfig deployDirectory INFO: Deploying web application directory /usr/share/apache-tomcat-7.0.42/webapps/examples Aug 20, 2015 4:21:35 PM org.apache.catalina.startup.HostConfig deployDirectory INFO: Deploying web application directory /usr/share/apache-tomcat-7.0.42/webapps/manager Aug 20, 2015 4:21:35 PM org.apache.catalina.startup.HostConfig deployDirectory INFO: Deploying web application directory /usr/share/apache-tomcat-7.0.42/webapps/docs Aug 20, 2015 4:21:35 PM org.apache.coyote.AbstractProtocol start INFO: Starting ProtocolHandler ["http-bio-8080"] Aug 20, 2015 4:21:35 PM org.apache.coyote.AbstractProtocol start INFO: Starting ProtocolHandler ["ajp-bio-8009"] Aug 20, 2015 4:21:35 PM org.apache.catalina.startup.Catalina start INFO: Server startup in 34288 ms To manage notifications about this bug go to: https://bugs.launchpad.net/dhis2/+bug/1487121/+subscriptions _______________________________________________ Mailing list: https://launchpad.net/~dhis2-devs Post to : dhis2-devs@lists.launchpad.net Unsubscribe : https://launchpad.net/~dhis2-devs More help : https://help.launchpad.net/ListHelp