Errors with latest version

Hi there. I am having some issues with the latest version. Sorry for
the lengthy mail, but I have copied and pasted a bunch of log files.

I am using Postgres, Java 1.6.19, Tomcat 6.0.24 on Ubuntu.

During startup on a clean DB, only these are the tables that are created.

dhis2_tj=# \dt
                     List of relations
Schema | Name | Type | Owner

···

--------+-------------------------------+-------+----------
public | aggregateddatasetcompleteness | table | postgres
public | aggregateddatavalue | table | postgres
public | aggregatedindicatorvalue | table | postgres
public | categoryoptioncombo | table | postgres
public | dataelement | table | postgres
public | datavaluearchive | table | postgres
public | period | table | postgres
public | source | table | postgres
(8 rows)

DHIS2 appears to start correctly the application, I get this..

May 3, 2010 10:48:05 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory ROOT
May 3, 2010 10:48:05 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory docs
May 3, 2010 10:48:05 AM org.apache.coyote.http11.Http11AprProtocol start
INFO: Starting Coyote HTTP/1.1 on http-8080
May 3, 2010 10:48:05 AM org.apache.coyote.ajp.AjpAprProtocol start
INFO: Starting Coyote AJP/1.3 on ajp-8009
May 3, 2010 10:48:05 AM org.apache.catalina.startup.Catalina start
INFO: Server startup in 36108 ms
May 3, 2010 11:35:12 AM org.apache.catalina.core.AprLifecycleListener init
INFO: Loaded APR based Apache Tomcat Native library 1.1.20.
May 3, 2010 11:35:12 AM org.apache.catalina.core.AprLifecycleListener init
INFO: APR capabilities: IPv6 [true], sendfile [true], accept filters
[false], random [true].
May 3, 2010 11:35:12 AM org.apache.catalina.core.AprLifecycleListener
lifecycleEvent
INFO: Failed to initialize the SSLEngine.
May 3, 2010 11:35:12 AM org.apache.coyote.http11.Http11AprProtocol init
INFO: Initializing Coyote HTTP/1.1 on http-8080
May 3, 2010 11:35:12 AM org.apache.coyote.ajp.AjpAprProtocol init
INFO: Initializing Coyote AJP/1.3 on ajp-8009
May 3, 2010 11:35:12 AM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 2020 ms
May 3, 2010 11:35:13 AM org.apache.catalina.core.StandardService start
INFO: Starting service Catalina
May 3, 2010 11:35:13 AM org.apache.catalina.core.StandardEngine start
INFO: Starting Servlet Engine: Apache Tomcat/6.0.26
May 3, 2010 11:35:13 AM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deploying configuration descriptor manager.xml
May 3, 2010 11:35:13 AM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deploying configuration descriptor host-manager.xml
May 3, 2010 11:35:14 AM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive dhis.war
May 3, 2010 11:35:17 AM org.apache.catalina.loader.WebappClassLoader
validateJarFile
INFO: validateJarFile(/usr/local/apache-tomcat-6.0.26/webapps/dhis/WEB-INF/lib/servlet-api-2.4.jar)
- jar not loaded. See Servlet Spec 2.3, section 9.7.2. Offending
class: javax/servlet/Servlet.class
log4j:WARN No such property [maxFileSize] in
org.apache.log4j.DailyRollingFileAppender.
log4j:WARN No such property [maxBackupIndex] in
org.apache.log4j.DailyRollingFileAppender.
* INFO 11:35:25,504 System property dhis2.home not set
(DefaultLocationManager.java [main])
* INFO 11:35:25,523 Environment variable DHIS2_HOME points to
/usr/local/apache-tomcat-6.0.26/conf/dhis2/
(DefaultLocationManager.java [main])
* INFO 11:35:45,159 Executing startup routine [1 of 13, runlevel 0]:
DataSetShortNamePopulator (DefaultStartupRoutineExecutor.java [main])
* INFO 11:35:45,383 Populated DataSet short name and code
(DataSetShortNamePopulator.java [main])
* INFO 11:35:45,384 Executing startup routine [2 of 13, runlevel 0]:
UuidPopulator (DefaultStartupRoutineExecutor.java [main])
* INFO 11:35:45,392 Checked CategoryOption uuids (UuidPopulator.java [main])
* INFO 11:35:45,400 Checked Category uuids (UuidPopulator.java [main])
* INFO 11:35:45,413 Checked DataElement uuids (UuidPopulator.java [main])
* INFO 11:35:45,413 Executing startup routine [3 of 13, runlevel 0]:
OrganisationUnitHierarchyVerifier (DefaultStartupRoutineExecutor.java
[main])
* WARN 11:35:45,729 firstResult/maxResults specified with collection
fetch; applying in memory! (QueryTranslatorImpl.java [main])
* INFO 11:35:45,821 Added organistion unit hierarchy
(OrganisationUnitHierarchyVerifier.java [main])
* INFO 11:35:45,821 Executing startup routine [4 of 13, runlevel 0]:
OrganisationUnitGroupSetPopulator (DefaultStartupRoutineExecutor.java
[main])
* INFO 11:35:45,841 Executing startup routine [5 of 13, runlevel 0]:
OpenHealthDataSourceWriter (DefaultStartupRoutineExecutor.java [main])
* INFO 11:35:45,841 Environment variable OPENHEALTH_HOME not set
(OpenHealthDataSourceWriter.java [main])
* INFO 11:35:45,842 Executing startup routine [6 of 13, runlevel 0]:
ReportTableUpgrader (DefaultStartupRoutineExecutor.java [main])
* INFO 11:35:45,845 Upgraded report tables (ReportTableUpgrader.java [main])
* INFO 11:35:45,846 Executing startup routine [7 of 13, runlevel 1]:
$Proxy34 (DefaultStartupRoutineExecutor.java [main])
* INFO 11:35:46,183 Updated Category sort order and primary keys
(OptionsCategoriesDefaultSortOrderPopulator.java [main])
* INFO 11:35:46,184 Executing startup routine [8 of 13, runlevel 1]:
$Proxy34 (DefaultStartupRoutineExecutor.java [main])
* INFO 11:35:46,202 Executing startup routine [9 of 13, runlevel 1]:
$Proxy34 (DefaultStartupRoutineExecutor.java [main])
* INFO 11:35:46,203 Removing datasetid column from dataentryform
table (DataEntryFormPopulator.java [main])
* INFO 11:35:46,346 Executing startup routine [10 of 13, runlevel 2]:
$Proxy34 (DefaultStartupRoutineExecutor.java [main])
* INFO 11:35:46,383 Added default dataelement dimension
(DataElementDefaultDimensionPopulator.java [main])
* INFO 11:35:46,407 Executing startup routine [11 of 13, runlevel 2]:
$Proxy34 (DefaultStartupRoutineExecutor.java [main])
* INFO 11:35:46,434 Executing startup routine [12 of 13, runlevel 3]:
TableCreator (DefaultStartupRoutineExecutor.java [main])
* INFO 11:35:46,438 Created table aggregateddatavalue
(TableCreator.java [main])
* INFO 11:35:46,441 Created table aggregatedindicatorvalue
(TableCreator.java [main])
* INFO 11:35:46,457 Created index crosstab on table datavalue
(TableCreator.java [main])
* INFO 11:35:46,460 Created table aggregateddatasetcompleteness
(TableCreator.java [main])
* INFO 11:35:46,473 Created table aggregateddatasetcompleteness
(TableCreator.java [main])
* INFO 11:35:46,473 Executing startup routine [13 of 13, runlevel
10]: I18nUpgrader (DefaultStartupRoutineExecutor.java [main])
* INFO 11:35:46,508 I18n Upgrader running using locale English
(United Kingdom) (I18nUpgrader.java [main])
* INFO 11:35:46,527 I18n Upgrader: OrganisationUnitGroupSet (2)
(I18nUpgrader.java [main])
* INFO 11:35:46,534 I81n Upgrader: DataElementCategory (1)
(I18nUpgrader.java [main])
* INFO 11:35:46,537 I81n Upgrader: DataElementCategoryOption (1)
(I18nUpgrader.java [main])
* INFO 11:35:46,540 I81n Upgrader: DataElementCategoryCombo (1)
(I18nUpgrader.java [main])
* INFO 11:35:46,541 All startup routines done
(DefaultStartupRoutineExecutor.java [main])
May 3, 2010 11:35:49 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory examples
May 3, 2010 11:35:49 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory ROOT
May 3, 2010 11:35:49 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory docs
May 3, 2010 11:35:49 AM org.apache.coyote.http11.Http11AprProtocol start
INFO: Starting Coyote HTTP/1.1 on http-8080
May 3, 2010 11:35:49 AM org.apache.coyote.ajp.AjpAprProtocol start
INFO: Starting Coyote AJP/1.3 on ajp-8009
May 3, 2010 11:35:49 AM org.apache.catalina.startup.Catalina start
INFO: Server startup in 36984 ms
* AUDIT_TRAIL 11:36:11,757 User "null" added User "admin admin"
(HibernateUserStore.java [http-8080-1])
* INFO 11:36:17,940 User login success: 'admin'
(DefaultUserAuditService.java [http-8080-1])
* INFO 11:36:21,364 User logout: 'admin'
(DefaultUserAuditService.java [http-8080-2])

I was able to login, which seems very strange, as there is no user
table. But when I logout I see this..

INFO: Stopping service Catalina
May 3, 2010 11:36:31 AM org.apache.catalina.loader.WebappClassLoader
clearReferencesJdbc
SEVERE: A web application registered the JBDC driver [org.h2.Driver]
but failed to unregister it when the web application was stopped. To
prevent a memory leak, the JDBC Driver has been forcibly unregistered.
May 3, 2010 11:36:31 AM org.apache.catalina.loader.WebappClassLoader
clearReferencesJdbc
SEVERE: A web application registered the JBDC driver
[com.mysql.jdbc.Driver] but failed to unregister it when the web
application was stopped. To prevent a memory leak, the JDBC Driver has
been forcibly unregistered.
May 3, 2010 11:36:31 AM org.apache.catalina.loader.WebappClassLoader
clearReferencesJdbc
SEVERE: A web application registered the JBDC driver
[org.postgresql.Driver] but failed to unregister it when the web
application was stopped. To prevent a memory leak, the JDBC Driver has
been forcibly unregistered.
May 3, 2010 11:36:31 AM org.apache.catalina.loader.WebappClassLoader
clearThreadLocalMap
SEVERE: A web application created a ThreadLocal with key of type
[null] (value [com.opensymphony.xwork2.inject.ContainerImpl$10@ffeba4])
and a value of type [java.lang.Object[]] (value
[[Ljava.lang.Object;@1c78066]) but failed to remove it when the web
application was stopped. To prevent a memory leak, the ThreadLocal has
been forcibly removed.
May 3, 2010 11:36:31 AM org.apache.catalina.loader.WebappClassLoader
clearThreadLocalMap
SEVERE: A web application created a ThreadLocal with key of type
[null] (value [com.opensymphony.xwork2.inject.ContainerImpl$10@becf68])
and a value of type [java.lang.Object[]] (value
[[Ljava.lang.Object;@122c082]) but failed to remove it when the web
application was stopped. To prevent a memory leak, the ThreadLocal has
been forcibly removed.
May 3, 2010 11:36:31 AM org.apache.catalina.loader.WebappClassLoader
clearThreadLocalMap
SEVERE: A web application created a ThreadLocal with key of type
[null] (value [com.opensymphony.xwork2.inject.ContainerImpl$10@becf68])
and a value of type [java.lang.Object[]] (value
[[Ljava.lang.Object;@1fd8905]) but failed to remove it when the web
application was stopped. To prevent a memory leak, the ThreadLocal has
been forcibly removed.
No protocol specified

Finally, when I try and start the application again, I get this..

ction 9.7.2. Offending class: javax/servlet/Servlet.class
log4j:WARN No such property [maxFileSize] in
org.apache.log4j.DailyRollingFileAppender.
log4j:WARN No such property [maxBackupIndex] in
org.apache.log4j.DailyRollingFileAppender.
* INFO 11:41:47,577 System property dhis2.home not set
(DefaultLocationManager.java [main])
* INFO 11:41:47,582 Environment variable DHIS2_HOME points to
/usr/local/apache-tomcat-6.0.26/conf/dhis2/
(DefaultLocationManager.java [main])
* ERROR 11:41:56,075 Unsuccessful: create table categoryoptioncombo
(categoryoptioncomboid int4 not null, primary key
(categoryoptioncomboid)) (SchemaExport.java [main])
* ERROR 11:41:56,076 ERROR: relation "categoryoptioncombo" already
exists (SchemaExport.java [main])
* ERROR 11:41:56,277 Unsuccessful: create table dataelement
(dataelementid int4 not null, uuid varchar(40), name varchar(230) not
null unique, alternativename varchar(230) unique, shortname
varchar(25) not null unique, code varchar(100), description text,
active bool, valuetype varchar(16) not null, domaintype varchar(16),
aggregationtype varchar(16) not null, extendeddataelementid int4
unique, categorycomboid int4, sortOrder int4, url varchar(255),
lastUpdated timestamp, zeroIsSignificant bool, primary key
(dataelementid)) (SchemaExport.java [main])
* ERROR 11:41:56,279 ERROR: relation "dataelement" already exists
(SchemaExport.java [main])
* ERROR 11:41:57,400 Unsuccessful: create table period (periodid int4
not null, periodtypeid int4, startdate date not null, enddate date not
null, primary key (periodid), unique (periodtypeid, startdate,
enddate)) (SchemaExport.java [main])
* ERROR 11:41:57,402 ERROR: relation "period" already exists
(SchemaExport.java [main])
* ERROR 11:41:57,877 Unsuccessful: create table source (sourceid int4
not null, primary key (sourceid)) (SchemaExport.java [main])
* ERROR 11:41:57,879 ERROR: relation "source" already exists
(SchemaExport.java [main])
* INFO 11:42:08,405 Executing startup routine [1 of 13, runlevel 0]:
DataSetShortNamePopulator (DefaultStartupRoutineExecutor.java [main])
* INFO 11:42:08,572 Populated DataSet short name and code
(DataSetShortNamePopulator.java [main])
* INFO 11:42:08,572 Executing startup routine [2 of 13, runlevel 0]:
UuidPopulator (DefaultStartupRoutineExecutor.java [main])
* INFO 11:42:08,581 Checked CategoryOption uuids (UuidPopulator.java [main])
* INFO 11:42:08,591 Checked Category uuids (UuidPopulator.java [main])
* INFO 11:42:08,604 Checked DataElement uuids (UuidPopulator.java [main])
* INFO 11:42:08,604 Executing startup routine [3 of 13, runlevel 0]:
OrganisationUnitHierarchyVerifier (DefaultStartupRoutineExecutor.java
[main])
* WARN 11:42:08,901 firstResult/maxResults specified with collection
fetch; applying in memory! (QueryTranslatorImpl.java [main])
* INFO 11:42:08,975 Added organistion unit hierarchy
(OrganisationUnitHierarchyVerifier.java [main])
* INFO 11:42:08,975 Executing startup routine [4 of 13, runlevel 0]:
OrganisationUnitGroupSetPopulator (DefaultStartupRoutineExecutor.java
[main])
* INFO 11:42:08,993 Executing startup routine [5 of 13, runlevel 0]:
OpenHealthDataSourceWriter (DefaultStartupRoutineExecutor.java [main])
* INFO 11:42:08,993 Environment variable OPENHEALTH_HOME not set
(OpenHealthDataSourceWriter.java [main])
* INFO 11:42:08,994 Executing startup routine [6 of 13, runlevel 0]:
ReportTableUpgrader (DefaultStartupRoutineExecutor.java [main])
* INFO 11:42:08,997 Upgraded report tables (ReportTableUpgrader.java [main])
* INFO 11:42:08,997 Executing startup routine [7 of 13, runlevel 1]:
$Proxy34 (DefaultStartupRoutineExecutor.java [main])
* INFO 11:42:09,151 Updated Category sort order and primary keys
(OptionsCategoriesDefaultSortOrderPopulator.java [main])
* INFO 11:42:09,152 Executing startup routine [8 of 13, runlevel 1]:
$Proxy34 (DefaultStartupRoutineExecutor.java [main])
* INFO 11:42:09,166 Executing startup routine [9 of 13, runlevel 1]:
$Proxy34 (DefaultStartupRoutineExecutor.java [main])
* INFO 11:42:09,167 Removing datasetid column from dataentryform
table (DataEntryFormPopulator.java [main])
* INFO 11:42:09,286 Executing startup routine [10 of 13, runlevel 2]:
$Proxy34 (DefaultStartupRoutineExecutor.java [main])
* INFO 11:42:09,338 Added default dataelement dimension
(DataElementDefaultDimensionPopulator.java [main])
* WARN 11:42:09,352 SQL Error: 0, SQLState: 23505
(JDBCExceptionReporter.java [main])
* ERROR 11:42:09,353 Batch entry 0 insert into categoryoptioncombo
(categoryoptioncomboid) values ('16') was aborted. Call
getNextException to see the cause. (JDBCExceptionReporter.java [main])
* WARN 11:42:09,353 SQL Error: 0, SQLState: 23505
(JDBCExceptionReporter.java [main])
* ERROR 11:42:09,355 ERROR: duplicate key value violates unique
constraint "categoryoptioncombo_pkey" (JDBCExceptionReporter.java
[main])
* ERROR 11:42:09,358 Could not synchronize database state with session
(AbstractFlushingEventListener.java [main])
org.hibernate.exception.ConstraintViolationException: Could not
execute JDBC batch update
  at org.hibernate.exception.SQLStateConverter.convert(SQLStateConverter.java:71)
  at org.hibernate.exception.JDBCExceptionHelper.convert(JDBCExceptionHelper.java:43)
  at org.hibernate.jdbc.AbstractBatcher.executeBatch(AbstractBatcher.java:253)
  at org.hibernate.jdbc.AbstractBatcher.prepareStatement(AbstractBatcher.java:92)
  at org.hibernate.jdbc.AbstractBatcher.prepareStatement(AbstractBatcher.java:87)
  at org.hibernate.persister.entity.AbstractEntityPersister.insert(AbstractEntityPersister.java:2237)
  at org.hibernate.persister.entity.AbstractEntityPersister.insert(AbstractEntityPersister.java:2665)
  at org.hibernate.action.EntityInsertAction.execute(EntityInsertAction.java:60)
  at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:279)
  at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:263)
  at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:167)
  at org.hibernate.event.def.AbstractFlushingEventListener.performExecutions(AbstractFlushingEventListener.java:298)
  at org.hibernate.event.def.DefaultAutoFlushEventListener.onAutoFlush(DefaultAutoFlushEventListener.java:41)
  at org.hibernate.impl.SessionImpl.autoFlushIfRequired(SessionImpl.java:969)
  at org.hibernate.impl.SessionImpl.list(SessionImpl.java:1562)
  at org.hibernate.impl.CriteriaImpl.list(CriteriaImpl.java:283)
  at org.hibernate.impl.CriteriaImpl.uniqueResult(CriteriaImpl.java:305)
  at org.hisp.dhis.hibernate.HibernateGenericStore.getObject(HibernateGenericStore.java:125)
  at org.hisp.dhis.hibernate.HibernateGenericStore.getByName(HibernateGenericStore.java:178)
  at org.hisp.dhis.dataelement.DefaultDataElementCategoryService.getDataElementCategoryComboByName(DefaultDataElementCategoryService.java:243)
  at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
  at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
  at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
  at java.lang.reflect.Method.invoke(Method.java:597)
  at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)
  at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:182)
  at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:149)
  at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:106)
  at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171)
  at org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:89)
  at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171)
  at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
  at $Proxy15.getDataElementCategoryComboByName(Unknown Source)
  at org.hisp.dhis.dataelement.DataElementDefaultDimensionPopulator.execute(DataElementDefaultDimensionPopulator.java:90)
  at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
  at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
  at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
  at java.lang.reflect.Method.invoke(Method.java:597)
  at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)
  at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:182)
  at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:149)
  at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:106)
  at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171)
  at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
  at $Proxy34.execute(Unknown Source)
  at org.hisp.dhis.system.startup.DefaultStartupRoutineExecutor.execute(DefaultStartupRoutineExecutor.java:101)
  at org.hisp.dhis.system.startup.DefaultStartupRoutineExecutor.execute(DefaultStartupRoutineExecutor.java:77)
  at org.hisp.dhis.system.startup.StartupListener.contextInitialized(StartupListener.java:57)
  at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:3972)
  at org.apache.catalina.core.StandardContext.start(StandardContext.java:4467)
  at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:791)
  at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:771)
  at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:546)
  at org.apache.catalina.startup.HostConfig.deployWAR(HostConfig.java:905)
  at org.apache.catalina.startup.HostConfig.deployWARs(HostConfig.java:740)
  at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:500)
  at org.apache.catalina.startup.HostConfig.start(HostConfig.java:1277)
  at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:321)
  at org.apache.catalina.util.LifecycleSupport.fireLifecycleEvent(LifecycleSupport.java:119)
  at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1053)
  at org.apache.catalina.core.StandardHost.start(StandardHost.java:785)
  at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1045)
  at org.apache.catalina.core.StandardEngine.start(StandardEngine.java:443)
  at org.apache.catalina.core.StandardService.start(StandardService.java:519)
  at org.apache.catalina.core.StandardServer.start(StandardServer.java:710)
  at org.apache.catalina.startup.Catalina.start(Catalina.java:581)
  at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
  at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
  at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
  at java.lang.reflect.Method.invoke(Method.java:597)
  at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:289)
  at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:414)
Caused by: java.sql.BatchUpdateException: Batch entry 0 insert into
categoryoptioncombo (categoryoptioncomboid) values ('16') was aborted.
Call getNextException to see the cause.
  at org.postgresql.jdbc2.AbstractJdbc2Statement$BatchResultHandler.handleError(AbstractJdbc2Statement.java:2569)
  at org.postgresql.core.v3.QueryExecutorImpl.processResults(QueryExecutorImpl.java:1796)
  at org.postgresql.core.v3.QueryExecutorImpl.execute(QueryExecutorImpl.java:407)
  at org.postgresql.jdbc2.AbstractJdbc2Statement.executeBatch(AbstractJdbc2Statement.java:2708)
  at com.mchange.v2.c3p0.impl.NewProxyPreparedStatement.executeBatch(NewProxyPreparedStatement.java:1723)
  at org.hibernate.jdbc.BatchingBatcher.doExecuteBatch(BatchingBatcher.java:48)
  at org.hibernate.jdbc.AbstractBatcher.executeBatch(AbstractBatcher.java:246)
  ... 69 more
May 3, 2010 11:42:09 AM org.apache.catalina.core.StandardContext start
SEVERE: Error listenerStart
May 3, 2010 11:42:09 AM org.apache.catalina.core.StandardContext start
SEVERE: Context [/dhis] startup failed due to previous errors
May 3, 2010 11:42:10 AM org.apache.catalina.loader.WebappClassLoader
clearReferencesJdbc
SEVERE: A web application registered the JBDC driver [org.h2.Driver]
but failed to unregister it when the web application was stopped. To
prevent a memory leak, the JDBC Driver has been forcibly unregistered.
May 3, 2010 11:42:10 AM org.apache.catalina.loader.WebappClassLoader
clearReferencesJdbc
SEVERE: A web application registered the JBDC driver
[com.mysql.jdbc.Driver] but failed to unregister it when the web
application was stopped. To prevent a memory leak, the JDBC Driver has
been forcibly unregistered.
May 3, 2010 11:42:10 AM org.apache.catalina.loader.WebappClassLoader
clearReferencesJdbc
SEVERE: A web application registered the JBDC driver
[org.postgresql.Driver] but failed to unregister it when the web
application was stopped. To prevent a memory leak, the JDBC Driver has
been forcibly unregistered.
No protocol specified
May 3, 2010 11:42:10 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory examples
May 3, 2010 11:42:11 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory ROOT
May 3, 2010 11:42:11 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory docs
May 3, 2010 11:42:11 AM org.apache.coyote.http11.Http11AprProtocol start
INFO: Starting Coyote HTTP/1.1 on http-8080
May 3, 2010 11:42:11 AM org.apache.coyote.ajp.AjpAprProtocol start
INFO: Starting Coyote AJP/1.3 on ajp-8009
May 3, 2010 11:42:11 AM org.apache.catalina.startup.Catalina start
INFO: Server startup in 31159 ms

Regards,
Jason

---
Jason P. Pickering
email: jason.p.pickering@gmail.com
tel:+260968395190

Duh, sorry for this. My own mistake, but at least it is documented now..

I was missing

hibernate.hbm2ddl.auto = update

in the hibernate.properties file.

Since this seems to be required, it might be a good idea to be sure it
is there before the app attempts to fire itself up!

Regards,
Jason

···

On Mon, May 3, 2010 at 11:44 AM, Jason Pickering <jason.p.pickering@gmail.com> wrote:

Hi there. I am having some issues with the latest version. Sorry for
the lengthy mail, but I have copied and pasted a bunch of log files.

I am using Postgres, Java 1.6.19, Tomcat 6.0.24 on Ubuntu.

During startup on a clean DB, only these are the tables that are created.

dhis2_tj=# \dt
List of relations
Schema | Name | Type | Owner
--------+-------------------------------+-------+----------
public | aggregateddatasetcompleteness | table | postgres
public | aggregateddatavalue | table | postgres
public | aggregatedindicatorvalue | table | postgres
public | categoryoptioncombo | table | postgres
public | dataelement | table | postgres
public | datavaluearchive | table | postgres
public | period | table | postgres
public | source | table | postgres
(8 rows)

DHIS2 appears to start correctly the application, I get this..

May 3, 2010 10:48:05 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory ROOT
May 3, 2010 10:48:05 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory docs
May 3, 2010 10:48:05 AM org.apache.coyote.http11.Http11AprProtocol start
INFO: Starting Coyote HTTP/1.1 on http-8080
May 3, 2010 10:48:05 AM org.apache.coyote.ajp.AjpAprProtocol start
INFO: Starting Coyote AJP/1.3 on ajp-8009
May 3, 2010 10:48:05 AM org.apache.catalina.startup.Catalina start
INFO: Server startup in 36108 ms
May 3, 2010 11:35:12 AM org.apache.catalina.core.AprLifecycleListener init
INFO: Loaded APR based Apache Tomcat Native library 1.1.20.
May 3, 2010 11:35:12 AM org.apache.catalina.core.AprLifecycleListener init
INFO: APR capabilities: IPv6 [true], sendfile [true], accept filters
[false], random [true].
May 3, 2010 11:35:12 AM org.apache.catalina.core.AprLifecycleListener
lifecycleEvent
INFO: Failed to initialize the SSLEngine.
May 3, 2010 11:35:12 AM org.apache.coyote.http11.Http11AprProtocol init
INFO: Initializing Coyote HTTP/1.1 on http-8080
May 3, 2010 11:35:12 AM org.apache.coyote.ajp.AjpAprProtocol init
INFO: Initializing Coyote AJP/1.3 on ajp-8009
May 3, 2010 11:35:12 AM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 2020 ms
May 3, 2010 11:35:13 AM org.apache.catalina.core.StandardService start
INFO: Starting service Catalina
May 3, 2010 11:35:13 AM org.apache.catalina.core.StandardEngine start
INFO: Starting Servlet Engine: Apache Tomcat/6.0.26
May 3, 2010 11:35:13 AM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deploying configuration descriptor manager.xml
May 3, 2010 11:35:13 AM org.apache.catalina.startup.HostConfig deployDescriptor
INFO: Deploying configuration descriptor host-manager.xml
May 3, 2010 11:35:14 AM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive dhis.war
May 3, 2010 11:35:17 AM org.apache.catalina.loader.WebappClassLoader
validateJarFile
INFO: validateJarFile(/usr/local/apache-tomcat-6.0.26/webapps/dhis/WEB-INF/lib/servlet-api-2.4.jar)
- jar not loaded. See Servlet Spec 2.3, section 9.7.2. Offending
class: javax/servlet/Servlet.class
log4j:WARN No such property [maxFileSize] in
org.apache.log4j.DailyRollingFileAppender.
log4j:WARN No such property [maxBackupIndex] in
org.apache.log4j.DailyRollingFileAppender.
* INFO 11:35:25,504 System property dhis2.home not set
(DefaultLocationManager.java [main])
* INFO 11:35:25,523 Environment variable DHIS2_HOME points to
/usr/local/apache-tomcat-6.0.26/conf/dhis2/
(DefaultLocationManager.java [main])
* INFO 11:35:45,159 Executing startup routine [1 of 13, runlevel 0]:
DataSetShortNamePopulator (DefaultStartupRoutineExecutor.java [main])
* INFO 11:35:45,383 Populated DataSet short name and code
(DataSetShortNamePopulator.java [main])
* INFO 11:35:45,384 Executing startup routine [2 of 13, runlevel 0]:
UuidPopulator (DefaultStartupRoutineExecutor.java [main])
* INFO 11:35:45,392 Checked CategoryOption uuids (UuidPopulator.java [main])
* INFO 11:35:45,400 Checked Category uuids (UuidPopulator.java [main])
* INFO 11:35:45,413 Checked DataElement uuids (UuidPopulator.java [main])
* INFO 11:35:45,413 Executing startup routine [3 of 13, runlevel 0]:
OrganisationUnitHierarchyVerifier (DefaultStartupRoutineExecutor.java
[main])
* WARN 11:35:45,729 firstResult/maxResults specified with collection
fetch; applying in memory! (QueryTranslatorImpl.java [main])
* INFO 11:35:45,821 Added organistion unit hierarchy
(OrganisationUnitHierarchyVerifier.java [main])
* INFO 11:35:45,821 Executing startup routine [4 of 13, runlevel 0]:
OrganisationUnitGroupSetPopulator (DefaultStartupRoutineExecutor.java
[main])
* INFO 11:35:45,841 Executing startup routine [5 of 13, runlevel 0]:
OpenHealthDataSourceWriter (DefaultStartupRoutineExecutor.java [main])
* INFO 11:35:45,841 Environment variable OPENHEALTH_HOME not set
(OpenHealthDataSourceWriter.java [main])
* INFO 11:35:45,842 Executing startup routine [6 of 13, runlevel 0]:
ReportTableUpgrader (DefaultStartupRoutineExecutor.java [main])
* INFO 11:35:45,845 Upgraded report tables (ReportTableUpgrader.java [main])
* INFO 11:35:45,846 Executing startup routine [7 of 13, runlevel 1]:
$Proxy34 (DefaultStartupRoutineExecutor.java [main])
* INFO 11:35:46,183 Updated Category sort order and primary keys
(OptionsCategoriesDefaultSortOrderPopulator.java [main])
* INFO 11:35:46,184 Executing startup routine [8 of 13, runlevel 1]:
$Proxy34 (DefaultStartupRoutineExecutor.java [main])
* INFO 11:35:46,202 Executing startup routine [9 of 13, runlevel 1]:
$Proxy34 (DefaultStartupRoutineExecutor.java [main])
* INFO 11:35:46,203 Removing datasetid column from dataentryform
table (DataEntryFormPopulator.java [main])
* INFO 11:35:46,346 Executing startup routine [10 of 13, runlevel 2]:
$Proxy34 (DefaultStartupRoutineExecutor.java [main])
* INFO 11:35:46,383 Added default dataelement dimension
(DataElementDefaultDimensionPopulator.java [main])
* INFO 11:35:46,407 Executing startup routine [11 of 13, runlevel 2]:
$Proxy34 (DefaultStartupRoutineExecutor.java [main])
* INFO 11:35:46,434 Executing startup routine [12 of 13, runlevel 3]:
TableCreator (DefaultStartupRoutineExecutor.java [main])
* INFO 11:35:46,438 Created table aggregateddatavalue
(TableCreator.java [main])
* INFO 11:35:46,441 Created table aggregatedindicatorvalue
(TableCreator.java [main])
* INFO 11:35:46,457 Created index crosstab on table datavalue
(TableCreator.java [main])
* INFO 11:35:46,460 Created table aggregateddatasetcompleteness
(TableCreator.java [main])
* INFO 11:35:46,473 Created table aggregateddatasetcompleteness
(TableCreator.java [main])
* INFO 11:35:46,473 Executing startup routine [13 of 13, runlevel
10]: I18nUpgrader (DefaultStartupRoutineExecutor.java [main])
* INFO 11:35:46,508 I18n Upgrader running using locale English
(United Kingdom) (I18nUpgrader.java [main])
* INFO 11:35:46,527 I18n Upgrader: OrganisationUnitGroupSet (2)
(I18nUpgrader.java [main])
* INFO 11:35:46,534 I81n Upgrader: DataElementCategory (1)
(I18nUpgrader.java [main])
* INFO 11:35:46,537 I81n Upgrader: DataElementCategoryOption (1)
(I18nUpgrader.java [main])
* INFO 11:35:46,540 I81n Upgrader: DataElementCategoryCombo (1)
(I18nUpgrader.java [main])
* INFO 11:35:46,541 All startup routines done
(DefaultStartupRoutineExecutor.java [main])
May 3, 2010 11:35:49 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory examples
May 3, 2010 11:35:49 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory ROOT
May 3, 2010 11:35:49 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory docs
May 3, 2010 11:35:49 AM org.apache.coyote.http11.Http11AprProtocol start
INFO: Starting Coyote HTTP/1.1 on http-8080
May 3, 2010 11:35:49 AM org.apache.coyote.ajp.AjpAprProtocol start
INFO: Starting Coyote AJP/1.3 on ajp-8009
May 3, 2010 11:35:49 AM org.apache.catalina.startup.Catalina start
INFO: Server startup in 36984 ms
* AUDIT_TRAIL 11:36:11,757 User "null" added User "admin admin"
(HibernateUserStore.java [http-8080-1])
* INFO 11:36:17,940 User login success: 'admin'
(DefaultUserAuditService.java [http-8080-1])
* INFO 11:36:21,364 User logout: 'admin'
(DefaultUserAuditService.java [http-8080-2])

I was able to login, which seems very strange, as there is no user
table. But when I logout I see this..

INFO: Stopping service Catalina
May 3, 2010 11:36:31 AM org.apache.catalina.loader.WebappClassLoader
clearReferencesJdbc
SEVERE: A web application registered the JBDC driver [org.h2.Driver]
but failed to unregister it when the web application was stopped. To
prevent a memory leak, the JDBC Driver has been forcibly unregistered.
May 3, 2010 11:36:31 AM org.apache.catalina.loader.WebappClassLoader
clearReferencesJdbc
SEVERE: A web application registered the JBDC driver
[com.mysql.jdbc.Driver] but failed to unregister it when the web
application was stopped. To prevent a memory leak, the JDBC Driver has
been forcibly unregistered.
May 3, 2010 11:36:31 AM org.apache.catalina.loader.WebappClassLoader
clearReferencesJdbc
SEVERE: A web application registered the JBDC driver
[org.postgresql.Driver] but failed to unregister it when the web
application was stopped. To prevent a memory leak, the JDBC Driver has
been forcibly unregistered.
May 3, 2010 11:36:31 AM org.apache.catalina.loader.WebappClassLoader
clearThreadLocalMap
SEVERE: A web application created a ThreadLocal with key of type
[null] (value [com.opensymphony.xwork2.inject.ContainerImpl$10@ffeba4])
and a value of type [java.lang.Object] (value
[[Ljava.lang.Object;@1c78066]) but failed to remove it when the web
application was stopped. To prevent a memory leak, the ThreadLocal has
been forcibly removed.
May 3, 2010 11:36:31 AM org.apache.catalina.loader.WebappClassLoader
clearThreadLocalMap
SEVERE: A web application created a ThreadLocal with key of type
[null] (value [com.opensymphony.xwork2.inject.ContainerImpl$10@becf68])
and a value of type [java.lang.Object] (value
[[Ljava.lang.Object;@122c082]) but failed to remove it when the web
application was stopped. To prevent a memory leak, the ThreadLocal has
been forcibly removed.
May 3, 2010 11:36:31 AM org.apache.catalina.loader.WebappClassLoader
clearThreadLocalMap
SEVERE: A web application created a ThreadLocal with key of type
[null] (value [com.opensymphony.xwork2.inject.ContainerImpl$10@becf68])
and a value of type [java.lang.Object] (value
[[Ljava.lang.Object;@1fd8905]) but failed to remove it when the web
application was stopped. To prevent a memory leak, the ThreadLocal has
been forcibly removed.
No protocol specified

Finally, when I try and start the application again, I get this..

ction 9.7.2. Offending class: javax/servlet/Servlet.class
log4j:WARN No such property [maxFileSize] in
org.apache.log4j.DailyRollingFileAppender.
log4j:WARN No such property [maxBackupIndex] in
org.apache.log4j.DailyRollingFileAppender.
* INFO 11:41:47,577 System property dhis2.home not set
(DefaultLocationManager.java [main])
* INFO 11:41:47,582 Environment variable DHIS2_HOME points to
/usr/local/apache-tomcat-6.0.26/conf/dhis2/
(DefaultLocationManager.java [main])
* ERROR 11:41:56,075 Unsuccessful: create table categoryoptioncombo
(categoryoptioncomboid int4 not null, primary key
(categoryoptioncomboid)) (SchemaExport.java [main])
* ERROR 11:41:56,076 ERROR: relation "categoryoptioncombo" already
exists (SchemaExport.java [main])
* ERROR 11:41:56,277 Unsuccessful: create table dataelement
(dataelementid int4 not null, uuid varchar(40), name varchar(230) not
null unique, alternativename varchar(230) unique, shortname
varchar(25) not null unique, code varchar(100), description text,
active bool, valuetype varchar(16) not null, domaintype varchar(16),
aggregationtype varchar(16) not null, extendeddataelementid int4
unique, categorycomboid int4, sortOrder int4, url varchar(255),
lastUpdated timestamp, zeroIsSignificant bool, primary key
(dataelementid)) (SchemaExport.java [main])
* ERROR 11:41:56,279 ERROR: relation "dataelement" already exists
(SchemaExport.java [main])
* ERROR 11:41:57,400 Unsuccessful: create table period (periodid int4
not null, periodtypeid int4, startdate date not null, enddate date not
null, primary key (periodid), unique (periodtypeid, startdate,
enddate)) (SchemaExport.java [main])
* ERROR 11:41:57,402 ERROR: relation "period" already exists
(SchemaExport.java [main])
* ERROR 11:41:57,877 Unsuccessful: create table source (sourceid int4
not null, primary key (sourceid)) (SchemaExport.java [main])
* ERROR 11:41:57,879 ERROR: relation "source" already exists
(SchemaExport.java [main])
* INFO 11:42:08,405 Executing startup routine [1 of 13, runlevel 0]:
DataSetShortNamePopulator (DefaultStartupRoutineExecutor.java [main])
* INFO 11:42:08,572 Populated DataSet short name and code
(DataSetShortNamePopulator.java [main])
* INFO 11:42:08,572 Executing startup routine [2 of 13, runlevel 0]:
UuidPopulator (DefaultStartupRoutineExecutor.java [main])
* INFO 11:42:08,581 Checked CategoryOption uuids (UuidPopulator.java [main])
* INFO 11:42:08,591 Checked Category uuids (UuidPopulator.java [main])
* INFO 11:42:08,604 Checked DataElement uuids (UuidPopulator.java [main])
* INFO 11:42:08,604 Executing startup routine [3 of 13, runlevel 0]:
OrganisationUnitHierarchyVerifier (DefaultStartupRoutineExecutor.java
[main])
* WARN 11:42:08,901 firstResult/maxResults specified with collection
fetch; applying in memory! (QueryTranslatorImpl.java [main])
* INFO 11:42:08,975 Added organistion unit hierarchy
(OrganisationUnitHierarchyVerifier.java [main])
* INFO 11:42:08,975 Executing startup routine [4 of 13, runlevel 0]:
OrganisationUnitGroupSetPopulator (DefaultStartupRoutineExecutor.java
[main])
* INFO 11:42:08,993 Executing startup routine [5 of 13, runlevel 0]:
OpenHealthDataSourceWriter (DefaultStartupRoutineExecutor.java [main])
* INFO 11:42:08,993 Environment variable OPENHEALTH_HOME not set
(OpenHealthDataSourceWriter.java [main])
* INFO 11:42:08,994 Executing startup routine [6 of 13, runlevel 0]:
ReportTableUpgrader (DefaultStartupRoutineExecutor.java [main])
* INFO 11:42:08,997 Upgraded report tables (ReportTableUpgrader.java [main])
* INFO 11:42:08,997 Executing startup routine [7 of 13, runlevel 1]:
$Proxy34 (DefaultStartupRoutineExecutor.java [main])
* INFO 11:42:09,151 Updated Category sort order and primary keys
(OptionsCategoriesDefaultSortOrderPopulator.java [main])
* INFO 11:42:09,152 Executing startup routine [8 of 13, runlevel 1]:
$Proxy34 (DefaultStartupRoutineExecutor.java [main])
* INFO 11:42:09,166 Executing startup routine [9 of 13, runlevel 1]:
$Proxy34 (DefaultStartupRoutineExecutor.java [main])
* INFO 11:42:09,167 Removing datasetid column from dataentryform
table (DataEntryFormPopulator.java [main])
* INFO 11:42:09,286 Executing startup routine [10 of 13, runlevel 2]:
$Proxy34 (DefaultStartupRoutineExecutor.java [main])
* INFO 11:42:09,338 Added default dataelement dimension
(DataElementDefaultDimensionPopulator.java [main])
* WARN 11:42:09,352 SQL Error: 0, SQLState: 23505
(JDBCExceptionReporter.java [main])
* ERROR 11:42:09,353 Batch entry 0 insert into categoryoptioncombo
(categoryoptioncomboid) values ('16') was aborted. Call
getNextException to see the cause. (JDBCExceptionReporter.java [main])
* WARN 11:42:09,353 SQL Error: 0, SQLState: 23505
(JDBCExceptionReporter.java [main])
* ERROR 11:42:09,355 ERROR: duplicate key value violates unique
constraint "categoryoptioncombo_pkey" (JDBCExceptionReporter.java
[main])
* ERROR 11:42:09,358 Could not synchronize database state with session
(AbstractFlushingEventListener.java [main])
org.hibernate.exception.ConstraintViolationException: Could not
execute JDBC batch update
at org.hibernate.exception.SQLStateConverter.convert(SQLStateConverter.java:71)
at org.hibernate.exception.JDBCExceptionHelper.convert(JDBCExceptionHelper.java:43)
at org.hibernate.jdbc.AbstractBatcher.executeBatch(AbstractBatcher.java:253)
at org.hibernate.jdbc.AbstractBatcher.prepareStatement(AbstractBatcher.java:92)
at org.hibernate.jdbc.AbstractBatcher.prepareStatement(AbstractBatcher.java:87)
at org.hibernate.persister.entity.AbstractEntityPersister.insert(AbstractEntityPersister.java:2237)
at org.hibernate.persister.entity.AbstractEntityPersister.insert(AbstractEntityPersister.java:2665)
at org.hibernate.action.EntityInsertAction.execute(EntityInsertAction.java:60)
at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:279)
at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:263)
at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:167)
at org.hibernate.event.def.AbstractFlushingEventListener.performExecutions(AbstractFlushingEventListener.java:298)
at org.hibernate.event.def.DefaultAutoFlushEventListener.onAutoFlush(DefaultAutoFlushEventListener.java:41)
at org.hibernate.impl.SessionImpl.autoFlushIfRequired(SessionImpl.java:969)
at org.hibernate.impl.SessionImpl.list(SessionImpl.java:1562)
at org.hibernate.impl.CriteriaImpl.list(CriteriaImpl.java:283)
at org.hibernate.impl.CriteriaImpl.uniqueResult(CriteriaImpl.java:305)
at org.hisp.dhis.hibernate.HibernateGenericStore.getObject(HibernateGenericStore.java:125)
at org.hisp.dhis.hibernate.HibernateGenericStore.getByName(HibernateGenericStore.java:178)
at org.hisp.dhis.dataelement.DefaultDataElementCategoryService.getDataElementCategoryComboByName(DefaultDataElementCategoryService.java:243)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)
at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:182)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:149)
at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:106)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171)
at org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:89)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171)
at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
at $Proxy15.getDataElementCategoryComboByName(Unknown Source)
at org.hisp.dhis.dataelement.DataElementDefaultDimensionPopulator.execute(DataElementDefaultDimensionPopulator.java:90)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)
at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:182)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:149)
at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:106)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171)
at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
at $Proxy34.execute(Unknown Source)
at org.hisp.dhis.system.startup.DefaultStartupRoutineExecutor.execute(DefaultStartupRoutineExecutor.java:101)
at org.hisp.dhis.system.startup.DefaultStartupRoutineExecutor.execute(DefaultStartupRoutineExecutor.java:77)
at org.hisp.dhis.system.startup.StartupListener.contextInitialized(StartupListener.java:57)
at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:3972)
at org.apache.catalina.core.StandardContext.start(StandardContext.java:4467)
at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:791)
at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:771)
at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:546)
at org.apache.catalina.startup.HostConfig.deployWAR(HostConfig.java:905)
at org.apache.catalina.startup.HostConfig.deployWARs(HostConfig.java:740)
at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:500)
at org.apache.catalina.startup.HostConfig.start(HostConfig.java:1277)
at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:321)
at org.apache.catalina.util.LifecycleSupport.fireLifecycleEvent(LifecycleSupport.java:119)
at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1053)
at org.apache.catalina.core.StandardHost.start(StandardHost.java:785)
at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1045)
at org.apache.catalina.core.StandardEngine.start(StandardEngine.java:443)
at org.apache.catalina.core.StandardService.start(StandardService.java:519)
at org.apache.catalina.core.StandardServer.start(StandardServer.java:710)
at org.apache.catalina.startup.Catalina.start(Catalina.java:581)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:289)
at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:414)
Caused by: java.sql.BatchUpdateException: Batch entry 0 insert into
categoryoptioncombo (categoryoptioncomboid) values ('16') was aborted.
Call getNextException to see the cause.
at org.postgresql.jdbc2.AbstractJdbc2Statement$BatchResultHandler.handleError(AbstractJdbc2Statement.java:2569)
at org.postgresql.core.v3.QueryExecutorImpl.processResults(QueryExecutorImpl.java:1796)
at org.postgresql.core.v3.QueryExecutorImpl.execute(QueryExecutorImpl.java:407)
at org.postgresql.jdbc2.AbstractJdbc2Statement.executeBatch(AbstractJdbc2Statement.java:2708)
at com.mchange.v2.c3p0.impl.NewProxyPreparedStatement.executeBatch(NewProxyPreparedStatement.java:1723)
at org.hibernate.jdbc.BatchingBatcher.doExecuteBatch(BatchingBatcher.java:48)
at org.hibernate.jdbc.AbstractBatcher.executeBatch(AbstractBatcher.java:246)
... 69 more
May 3, 2010 11:42:09 AM org.apache.catalina.core.StandardContext start
SEVERE: Error listenerStart
May 3, 2010 11:42:09 AM org.apache.catalina.core.StandardContext start
SEVERE: Context [/dhis] startup failed due to previous errors
May 3, 2010 11:42:10 AM org.apache.catalina.loader.WebappClassLoader
clearReferencesJdbc
SEVERE: A web application registered the JBDC driver [org.h2.Driver]
but failed to unregister it when the web application was stopped. To
prevent a memory leak, the JDBC Driver has been forcibly unregistered.
May 3, 2010 11:42:10 AM org.apache.catalina.loader.WebappClassLoader
clearReferencesJdbc
SEVERE: A web application registered the JBDC driver
[com.mysql.jdbc.Driver] but failed to unregister it when the web
application was stopped. To prevent a memory leak, the JDBC Driver has
been forcibly unregistered.
May 3, 2010 11:42:10 AM org.apache.catalina.loader.WebappClassLoader
clearReferencesJdbc
SEVERE: A web application registered the JBDC driver
[org.postgresql.Driver] but failed to unregister it when the web
application was stopped. To prevent a memory leak, the JDBC Driver has
been forcibly unregistered.
No protocol specified
May 3, 2010 11:42:10 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory examples
May 3, 2010 11:42:11 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory ROOT
May 3, 2010 11:42:11 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory docs
May 3, 2010 11:42:11 AM org.apache.coyote.http11.Http11AprProtocol start
INFO: Starting Coyote HTTP/1.1 on http-8080
May 3, 2010 11:42:11 AM org.apache.coyote.ajp.AjpAprProtocol start
INFO: Starting Coyote AJP/1.3 on ajp-8009
May 3, 2010 11:42:11 AM org.apache.catalina.startup.Catalina start
INFO: Server startup in 31159 ms

Regards,
Jason

---
Jason P. Pickering
email: jason.p.pickering@gmail.com
tel:+260968395190

--
--
Jason P. Pickering
email: jason.p.pickering@gmail.com
tel:+260968395190

Duh, sorry for this. My own mistake, but at least it is documented now…

I was missing

hibernate.hbm2ddl.auto = update

in the hibernate.properties file.

Since this seems to be required, it might be a good idea to be sure it

is there before the app attempts to fire itself up!

Regards,

Jason

Okay. Using hbm2dll in production settings is not really recommended but we are doing it anyway. The alternative is to distribute sql scripts that creates the database - and publishing new ones/amendments every time we make a change.

I guess you were starting up a new database this time…

Lars

···

On Mon, May 3, 2010 at 12:44 PM, Jason Pickering jason.p.pickering@gmail.com wrote:

On Mon, May 3, 2010 at 11:44 AM, Jason Pickering > jason.p.pickering@gmail.com wrote:

Hi there. I am having some issues with the latest version. Sorry for

the lengthy mail, but I have copied and pasted a bunch of log files.

I am using Postgres, Java 1.6.19, Tomcat 6.0.24 on Ubuntu.

During startup on a clean DB, only these are the tables that are created.

dhis2_tj=# \dt

                List of relations

Schema | Name | Type | Owner

--------±------------------------------±------±---------

public | aggregateddatasetcompleteness | table | postgres

public | aggregateddatavalue | table | postgres

public | aggregatedindicatorvalue | table | postgres

public | categoryoptioncombo | table | postgres

public | dataelement | table | postgres

public | datavaluearchive | table | postgres

public | period | table | postgres

public | source | table | postgres

(8 rows)

DHIS2 appears to start correctly the application, I get this…

May 3, 2010 10:48:05 AM org.apache.catalina.startup.HostConfig deployDirectory

INFO: Deploying web application directory ROOT

May 3, 2010 10:48:05 AM org.apache.catalina.startup.HostConfig deployDirectory

INFO: Deploying web application directory docs

May 3, 2010 10:48:05 AM org.apache.coyote.http11.Http11AprProtocol start

INFO: Starting Coyote HTTP/1.1 on http-8080

May 3, 2010 10:48:05 AM org.apache.coyote.ajp.AjpAprProtocol start

INFO: Starting Coyote AJP/1.3 on ajp-8009

May 3, 2010 10:48:05 AM org.apache.catalina.startup.Catalina start

INFO: Server startup in 36108 ms

May 3, 2010 11:35:12 AM org.apache.catalina.core.AprLifecycleListener init

INFO: Loaded APR based Apache Tomcat Native library 1.1.20.

May 3, 2010 11:35:12 AM org.apache.catalina.core.AprLifecycleListener init

INFO: APR capabilities: IPv6 [true], sendfile [true], accept filters

[false], random [true].

May 3, 2010 11:35:12 AM org.apache.catalina.core.AprLifecycleListener

lifecycleEvent

INFO: Failed to initialize the SSLEngine.

May 3, 2010 11:35:12 AM org.apache.coyote.http11.Http11AprProtocol init

INFO: Initializing Coyote HTTP/1.1 on http-8080

May 3, 2010 11:35:12 AM org.apache.coyote.ajp.AjpAprProtocol init

INFO: Initializing Coyote AJP/1.3 on ajp-8009

May 3, 2010 11:35:12 AM org.apache.catalina.startup.Catalina load

INFO: Initialization processed in 2020 ms

May 3, 2010 11:35:13 AM org.apache.catalina.core.StandardService start

INFO: Starting service Catalina

May 3, 2010 11:35:13 AM org.apache.catalina.core.StandardEngine start

INFO: Starting Servlet Engine: Apache Tomcat/6.0.26

May 3, 2010 11:35:13 AM org.apache.catalina.startup.HostConfig deployDescriptor

INFO: Deploying configuration descriptor manager.xml

May 3, 2010 11:35:13 AM org.apache.catalina.startup.HostConfig deployDescriptor

INFO: Deploying configuration descriptor host-manager.xml

May 3, 2010 11:35:14 AM org.apache.catalina.startup.HostConfig deployWAR

INFO: Deploying web application archive dhis.war

May 3, 2010 11:35:17 AM org.apache.catalina.loader.WebappClassLoader

validateJarFile

INFO: validateJarFile(/usr/local/apache-tomcat-6.0.26/webapps/dhis/WEB-INF/lib/servlet-api-2.4.jar)

  • jar not loaded. See Servlet Spec 2.3, section 9.7.2. Offending

class: javax/servlet/Servlet.class

log4j:WARN No such property [maxFileSize] in

org.apache.log4j.DailyRollingFileAppender.

log4j:WARN No such property [maxBackupIndex] in

org.apache.log4j.DailyRollingFileAppender.

  • INFO 11:35:25,504 System property dhis2.home not set

(DefaultLocationManager.java [main])

  • INFO 11:35:25,523 Environment variable DHIS2_HOME points to

/usr/local/apache-tomcat-6.0.26/conf/dhis2/

(DefaultLocationManager.java [main])

  • INFO 11:35:45,159 Executing startup routine [1 of 13, runlevel 0]:

DataSetShortNamePopulator (DefaultStartupRoutineExecutor.java [main])

  • INFO 11:35:45,383 Populated DataSet short name and code

(DataSetShortNamePopulator.java [main])

  • INFO 11:35:45,384 Executing startup routine [2 of 13, runlevel 0]:

UuidPopulator (DefaultStartupRoutineExecutor.java [main])

  • INFO 11:35:45,392 Checked CategoryOption uuids (UuidPopulator.java [main])
  • INFO 11:35:45,400 Checked Category uuids (UuidPopulator.java [main])
  • INFO 11:35:45,413 Checked DataElement uuids (UuidPopulator.java [main])
  • INFO 11:35:45,413 Executing startup routine [3 of 13, runlevel 0]:

OrganisationUnitHierarchyVerifier (DefaultStartupRoutineExecutor.java

[main])

  • WARN 11:35:45,729 firstResult/maxResults specified with collection

fetch; applying in memory! (QueryTranslatorImpl.java [main])

  • INFO 11:35:45,821 Added organistion unit hierarchy

(OrganisationUnitHierarchyVerifier.java [main])

  • INFO 11:35:45,821 Executing startup routine [4 of 13, runlevel 0]:

OrganisationUnitGroupSetPopulator (DefaultStartupRoutineExecutor.java

[main])

  • INFO 11:35:45,841 Executing startup routine [5 of 13, runlevel 0]:

OpenHealthDataSourceWriter (DefaultStartupRoutineExecutor.java [main])

  • INFO 11:35:45,841 Environment variable OPENHEALTH_HOME not set

(OpenHealthDataSourceWriter.java [main])

  • INFO 11:35:45,842 Executing startup routine [6 of 13, runlevel 0]:

ReportTableUpgrader (DefaultStartupRoutineExecutor.java [main])

  • INFO 11:35:45,845 Upgraded report tables (ReportTableUpgrader.java [main])
  • INFO 11:35:45,846 Executing startup routine [7 of 13, runlevel 1]:

$Proxy34 (DefaultStartupRoutineExecutor.java [main])

  • INFO 11:35:46,183 Updated Category sort order and primary keys

(OptionsCategoriesDefaultSortOrderPopulator.java [main])

  • INFO 11:35:46,184 Executing startup routine [8 of 13, runlevel 1]:

$Proxy34 (DefaultStartupRoutineExecutor.java [main])

  • INFO 11:35:46,202 Executing startup routine [9 of 13, runlevel 1]:

$Proxy34 (DefaultStartupRoutineExecutor.java [main])

  • INFO 11:35:46,203 Removing datasetid column from dataentryform

table (DataEntryFormPopulator.java [main])

  • INFO 11:35:46,346 Executing startup routine [10 of 13, runlevel 2]:

$Proxy34 (DefaultStartupRoutineExecutor.java [main])

  • INFO 11:35:46,383 Added default dataelement dimension

(DataElementDefaultDimensionPopulator.java [main])

  • INFO 11:35:46,407 Executing startup routine [11 of 13, runlevel 2]:

$Proxy34 (DefaultStartupRoutineExecutor.java [main])

  • INFO 11:35:46,434 Executing startup routine [12 of 13, runlevel 3]:

TableCreator (DefaultStartupRoutineExecutor.java [main])

  • INFO 11:35:46,438 Created table aggregateddatavalue

(TableCreator.java [main])

  • INFO 11:35:46,441 Created table aggregatedindicatorvalue

(TableCreator.java [main])

  • INFO 11:35:46,457 Created index crosstab on table datavalue

(TableCreator.java [main])

  • INFO 11:35:46,460 Created table aggregateddatasetcompleteness

(TableCreator.java [main])

  • INFO 11:35:46,473 Created table aggregateddatasetcompleteness

(TableCreator.java [main])

  • INFO 11:35:46,473 Executing startup routine [13 of 13, runlevel

10]: I18nUpgrader (DefaultStartupRoutineExecutor.java [main])

  • INFO 11:35:46,508 I18n Upgrader running using locale English

(United Kingdom) (I18nUpgrader.java [main])

  • INFO 11:35:46,527 I18n Upgrader: OrganisationUnitGroupSet (2)

(I18nUpgrader.java [main])

  • INFO 11:35:46,534 I81n Upgrader: DataElementCategory (1)

(I18nUpgrader.java [main])

  • INFO 11:35:46,537 I81n Upgrader: DataElementCategoryOption (1)

(I18nUpgrader.java [main])

  • INFO 11:35:46,540 I81n Upgrader: DataElementCategoryCombo (1)

(I18nUpgrader.java [main])

  • INFO 11:35:46,541 All startup routines done

(DefaultStartupRoutineExecutor.java [main])

May 3, 2010 11:35:49 AM org.apache.catalina.startup.HostConfig deployDirectory

INFO: Deploying web application directory examples

May 3, 2010 11:35:49 AM org.apache.catalina.startup.HostConfig deployDirectory

INFO: Deploying web application directory ROOT

May 3, 2010 11:35:49 AM org.apache.catalina.startup.HostConfig deployDirectory

INFO: Deploying web application directory docs

May 3, 2010 11:35:49 AM org.apache.coyote.http11.Http11AprProtocol start

INFO: Starting Coyote HTTP/1.1 on http-8080

May 3, 2010 11:35:49 AM org.apache.coyote.ajp.AjpAprProtocol start

INFO: Starting Coyote AJP/1.3 on ajp-8009

May 3, 2010 11:35:49 AM org.apache.catalina.startup.Catalina start

INFO: Server startup in 36984 ms

  • AUDIT_TRAIL 11:36:11,757 User “null” added User “admin admin”

(HibernateUserStore.java [http-8080-1])

  • INFO 11:36:17,940 User login success: ‘admin’

(DefaultUserAuditService.java [http-8080-1])

  • INFO 11:36:21,364 User logout: ‘admin’

(DefaultUserAuditService.java [http-8080-2])

I was able to login, which seems very strange, as there is no user

table. But when I logout I see this…

INFO: Stopping service Catalina

May 3, 2010 11:36:31 AM org.apache.catalina.loader.WebappClassLoader

clearReferencesJdbc

SEVERE: A web application registered the JBDC driver [org.h2.Driver]

but failed to unregister it when the web application was stopped. To

prevent a memory leak, the JDBC Driver has been forcibly unregistered.

May 3, 2010 11:36:31 AM org.apache.catalina.loader.WebappClassLoader

clearReferencesJdbc

SEVERE: A web application registered the JBDC driver

[com.mysql.jdbc.Driver] but failed to unregister it when the web

application was stopped. To prevent a memory leak, the JDBC Driver has

been forcibly unregistered.

May 3, 2010 11:36:31 AM org.apache.catalina.loader.WebappClassLoader

clearReferencesJdbc

SEVERE: A web application registered the JBDC driver

[org.postgresql.Driver] but failed to unregister it when the web

application was stopped. To prevent a memory leak, the JDBC Driver has

been forcibly unregistered.

May 3, 2010 11:36:31 AM org.apache.catalina.loader.WebappClassLoader

clearThreadLocalMap

SEVERE: A web application created a ThreadLocal with key of type

[null] (value [com.opensymphony.xwork2.inject.ContainerImpl$10@ffeba4])

and a value of type [java.lang.Object] (value

[[Ljava.lang.Object;@1c78066]) but failed to remove it when the web

application was stopped. To prevent a memory leak, the ThreadLocal has

been forcibly removed.

May 3, 2010 11:36:31 AM org.apache.catalina.loader.WebappClassLoader

clearThreadLocalMap

SEVERE: A web application created a ThreadLocal with key of type

[null] (value [com.opensymphony.xwork2.inject.ContainerImpl$10@becf68])

and a value of type [java.lang.Object] (value

[[Ljava.lang.Object;@122c082]) but failed to remove it when the web

application was stopped. To prevent a memory leak, the ThreadLocal has

been forcibly removed.

May 3, 2010 11:36:31 AM org.apache.catalina.loader.WebappClassLoader

clearThreadLocalMap

SEVERE: A web application created a ThreadLocal with key of type

[null] (value [com.opensymphony.xwork2.inject.ContainerImpl$10@becf68])

and a value of type [java.lang.Object] (value

[[Ljava.lang.Object;@1fd8905]) but failed to remove it when the web

application was stopped. To prevent a memory leak, the ThreadLocal has

been forcibly removed.

No protocol specified

Finally, when I try and start the application again, I get this…

ction 9.7.2. Offending class: javax/servlet/Servlet.class

log4j:WARN No such property [maxFileSize] in

org.apache.log4j.DailyRollingFileAppender.

log4j:WARN No such property [maxBackupIndex] in

org.apache.log4j.DailyRollingFileAppender.

  • INFO 11:41:47,577 System property dhis2.home not set

(DefaultLocationManager.java [main])

  • INFO 11:41:47,582 Environment variable DHIS2_HOME points to

/usr/local/apache-tomcat-6.0.26/conf/dhis2/

(DefaultLocationManager.java [main])

  • ERROR 11:41:56,075 Unsuccessful: create table categoryoptioncombo

(categoryoptioncomboid int4 not null, primary key

(categoryoptioncomboid)) (SchemaExport.java [main])

  • ERROR 11:41:56,076 ERROR: relation “categoryoptioncombo” already

exists (SchemaExport.java [main])

  • ERROR 11:41:56,277 Unsuccessful: create table dataelement

(dataelementid int4 not null, uuid varchar(40), name varchar(230) not

null unique, alternativename varchar(230) unique, shortname

varchar(25) not null unique, code varchar(100), description text,

active bool, valuetype varchar(16) not null, domaintype varchar(16),

aggregationtype varchar(16) not null, extendeddataelementid int4

unique, categorycomboid int4, sortOrder int4, url varchar(255),

lastUpdated timestamp, zeroIsSignificant bool, primary key

(dataelementid)) (SchemaExport.java [main])

  • ERROR 11:41:56,279 ERROR: relation “dataelement” already exists

(SchemaExport.java [main])

  • ERROR 11:41:57,400 Unsuccessful: create table period (periodid int4

not null, periodtypeid int4, startdate date not null, enddate date not

null, primary key (periodid), unique (periodtypeid, startdate,

enddate)) (SchemaExport.java [main])

  • ERROR 11:41:57,402 ERROR: relation “period” already exists

(SchemaExport.java [main])

  • ERROR 11:41:57,877 Unsuccessful: create table source (sourceid int4

not null, primary key (sourceid)) (SchemaExport.java [main])

  • ERROR 11:41:57,879 ERROR: relation “source” already exists

(SchemaExport.java [main])

  • INFO 11:42:08,405 Executing startup routine [1 of 13, runlevel 0]:

DataSetShortNamePopulator (DefaultStartupRoutineExecutor.java [main])

  • INFO 11:42:08,572 Populated DataSet short name and code

(DataSetShortNamePopulator.java [main])

  • INFO 11:42:08,572 Executing startup routine [2 of 13, runlevel 0]:

UuidPopulator (DefaultStartupRoutineExecutor.java [main])

  • INFO 11:42:08,581 Checked CategoryOption uuids (UuidPopulator.java [main])
  • INFO 11:42:08,591 Checked Category uuids (UuidPopulator.java [main])
  • INFO 11:42:08,604 Checked DataElement uuids (UuidPopulator.java [main])
  • INFO 11:42:08,604 Executing startup routine [3 of 13, runlevel 0]:

OrganisationUnitHierarchyVerifier (DefaultStartupRoutineExecutor.java

[main])

  • WARN 11:42:08,901 firstResult/maxResults specified with collection

fetch; applying in memory! (QueryTranslatorImpl.java [main])

  • INFO 11:42:08,975 Added organistion unit hierarchy

(OrganisationUnitHierarchyVerifier.java [main])

  • INFO 11:42:08,975 Executing startup routine [4 of 13, runlevel 0]:

OrganisationUnitGroupSetPopulator (DefaultStartupRoutineExecutor.java

[main])

  • INFO 11:42:08,993 Executing startup routine [5 of 13, runlevel 0]:

OpenHealthDataSourceWriter (DefaultStartupRoutineExecutor.java [main])

  • INFO 11:42:08,993 Environment variable OPENHEALTH_HOME not set

(OpenHealthDataSourceWriter.java [main])

  • INFO 11:42:08,994 Executing startup routine [6 of 13, runlevel 0]:

ReportTableUpgrader (DefaultStartupRoutineExecutor.java [main])

  • INFO 11:42:08,997 Upgraded report tables (ReportTableUpgrader.java [main])
  • INFO 11:42:08,997 Executing startup routine [7 of 13, runlevel 1]:

$Proxy34 (DefaultStartupRoutineExecutor.java [main])

  • INFO 11:42:09,151 Updated Category sort order and primary keys

(OptionsCategoriesDefaultSortOrderPopulator.java [main])

  • INFO 11:42:09,152 Executing startup routine [8 of 13, runlevel 1]:

$Proxy34 (DefaultStartupRoutineExecutor.java [main])

  • INFO 11:42:09,166 Executing startup routine [9 of 13, runlevel 1]:

$Proxy34 (DefaultStartupRoutineExecutor.java [main])

  • INFO 11:42:09,167 Removing datasetid column from dataentryform

table (DataEntryFormPopulator.java [main])

  • INFO 11:42:09,286 Executing startup routine [10 of 13, runlevel 2]:

$Proxy34 (DefaultStartupRoutineExecutor.java [main])

  • INFO 11:42:09,338 Added default dataelement dimension

(DataElementDefaultDimensionPopulator.java [main])

  • WARN 11:42:09,352 SQL Error: 0, SQLState: 23505

(JDBCExceptionReporter.java [main])

  • ERROR 11:42:09,353 Batch entry 0 insert into categoryoptioncombo

(categoryoptioncomboid) values (‘16’) was aborted. Call

getNextException to see the cause. (JDBCExceptionReporter.java [main])

  • WARN 11:42:09,353 SQL Error: 0, SQLState: 23505

(JDBCExceptionReporter.java [main])

  • ERROR 11:42:09,355 ERROR: duplicate key value violates unique

constraint “categoryoptioncombo_pkey” (JDBCExceptionReporter.java

[main])

  • ERROR 11:42:09,358 Could not synchronize database state with session

(AbstractFlushingEventListener.java [main])

org.hibernate.exception.ConstraintViolationException: Could not

execute JDBC batch update

   at org.hibernate.exception.SQLStateConverter.convert(SQLStateConverter.java:71)
   at org.hibernate.exception.JDBCExceptionHelper.convert(JDBCExceptionHelper.java:43)
   at org.hibernate.jdbc.AbstractBatcher.executeBatch(AbstractBatcher.java:253)
   at org.hibernate.jdbc.AbstractBatcher.prepareStatement(AbstractBatcher.java:92)
   at org.hibernate.jdbc.AbstractBatcher.prepareStatement(AbstractBatcher.java:87)
   at org.hibernate.persister.entity.AbstractEntityPersister.insert(AbstractEntityPersister.java:2237)
   at org.hibernate.persister.entity.AbstractEntityPersister.insert(AbstractEntityPersister.java:2665)
   at org.hibernate.action.EntityInsertAction.execute(EntityInsertAction.java:60)
   at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:279)
   at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:263)
   at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:167)
   at org.hibernate.event.def.AbstractFlushingEventListener.performExecutions(AbstractFlushingEventListener.java:298)
   at org.hibernate.event.def.DefaultAutoFlushEventListener.onAutoFlush(DefaultAutoFlushEventListener.java:41)
   at org.hibernate.impl.SessionImpl.autoFlushIfRequired(SessionImpl.java:969)
   at org.hibernate.impl.SessionImpl.list(SessionImpl.java:1562)
   at org.hibernate.impl.CriteriaImpl.list(CriteriaImpl.java:283)
   at org.hibernate.impl.CriteriaImpl.uniqueResult(CriteriaImpl.java:305)
   at org.hisp.dhis.hibernate.HibernateGenericStore.getObject(HibernateGenericStore.java:125)
   at org.hisp.dhis.hibernate.HibernateGenericStore.getByName(HibernateGenericStore.java:178)
   at org.hisp.dhis.dataelement.DefaultDataElementCategoryService.getDataElementCategoryComboByName(DefaultDataElementCategoryService.java:243)
   at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
   at java.lang.reflect.Method.invoke(Method.java:597)
   at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:182)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:149)
   at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:106)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171)
   at org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:89)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171)
   at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
   at $Proxy15.getDataElementCategoryComboByName(Unknown Source)
   at org.hisp.dhis.dataelement.DataElementDefaultDimensionPopulator.execute(DataElementDefaultDimensionPopulator.java:90)
   at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
   at java.lang.reflect.Method.invoke(Method.java:597)
   at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:182)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:149)
   at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:106)
   at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171)
   at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
   at $Proxy34.execute(Unknown Source)
   at org.hisp.dhis.system.startup.DefaultStartupRoutineExecutor.execute(DefaultStartupRoutineExecutor.java:101)
   at org.hisp.dhis.system.startup.DefaultStartupRoutineExecutor.execute(DefaultStartupRoutineExecutor.java:77)
   at org.hisp.dhis.system.startup.StartupListener.contextInitialized(StartupListener.java:57)
   at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:3972)
   at org.apache.catalina.core.StandardContext.start(StandardContext.java:4467)
   at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:791)
   at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:771)
   at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:546)
   at org.apache.catalina.startup.HostConfig.deployWAR(HostConfig.java:905)
   at org.apache.catalina.startup.HostConfig.deployWARs(HostConfig.java:740)
   at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:500)
   at org.apache.catalina.startup.HostConfig.start(HostConfig.java:1277)
   at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:321)
   at org.apache.catalina.util.LifecycleSupport.fireLifecycleEvent(LifecycleSupport.java:119)
   at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1053)
   at org.apache.catalina.core.StandardHost.start(StandardHost.java:785)
   at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1045)
   at org.apache.catalina.core.StandardEngine.start(StandardEngine.java:443)
   at org.apache.catalina.core.StandardService.start(StandardService.java:519)
   at org.apache.catalina.core.StandardServer.start(StandardServer.java:710)
   at org.apache.catalina.startup.Catalina.start(Catalina.java:581)
   at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
   at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
   at java.lang.reflect.Method.invoke(Method.java:597)
   at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:289)
   at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:414)

Caused by: java.sql.BatchUpdateException: Batch entry 0 insert into

categoryoptioncombo (categoryoptioncomboid) values (‘16’) was aborted.

Call getNextException to see the cause.

   at org.postgresql.jdbc2.AbstractJdbc2Statement$BatchResultHandler.handleError(AbstractJdbc2Statement.java:2569)
   at org.postgresql.core.v3.QueryExecutorImpl.processResults(QueryExecutorImpl.java:1796)
   at org.postgresql.core.v3.QueryExecutorImpl.execute(QueryExecutorImpl.java:407)
   at org.postgresql.jdbc2.AbstractJdbc2Statement.executeBatch(AbstractJdbc2Statement.java:2708)
   at com.mchange.v2.c3p0.impl.NewProxyPreparedStatement.executeBatch(NewProxyPreparedStatement.java:1723)
   at org.hibernate.jdbc.BatchingBatcher.doExecuteBatch(BatchingBatcher.java:48)
   at org.hibernate.jdbc.AbstractBatcher.executeBatch(AbstractBatcher.java:246)
   ... 69 more

May 3, 2010 11:42:09 AM org.apache.catalina.core.StandardContext start

SEVERE: Error listenerStart

May 3, 2010 11:42:09 AM org.apache.catalina.core.StandardContext start

SEVERE: Context [/dhis] startup failed due to previous errors

May 3, 2010 11:42:10 AM org.apache.catalina.loader.WebappClassLoader

clearReferencesJdbc

SEVERE: A web application registered the JBDC driver [org.h2.Driver]

but failed to unregister it when the web application was stopped. To

prevent a memory leak, the JDBC Driver has been forcibly unregistered.

May 3, 2010 11:42:10 AM org.apache.catalina.loader.WebappClassLoader

clearReferencesJdbc

SEVERE: A web application registered the JBDC driver

[com.mysql.jdbc.Driver] but failed to unregister it when the web

application was stopped. To prevent a memory leak, the JDBC Driver has

been forcibly unregistered.

May 3, 2010 11:42:10 AM org.apache.catalina.loader.WebappClassLoader

clearReferencesJdbc

SEVERE: A web application registered the JBDC driver

[org.postgresql.Driver] but failed to unregister it when the web

application was stopped. To prevent a memory leak, the JDBC Driver has

been forcibly unregistered.

No protocol specified

May 3, 2010 11:42:10 AM org.apache.catalina.startup.HostConfig deployDirectory

INFO: Deploying web application directory examples

May 3, 2010 11:42:11 AM org.apache.catalina.startup.HostConfig deployDirectory

INFO: Deploying web application directory ROOT

May 3, 2010 11:42:11 AM org.apache.catalina.startup.HostConfig deployDirectory

INFO: Deploying web application directory docs

May 3, 2010 11:42:11 AM org.apache.coyote.http11.Http11AprProtocol start

INFO: Starting Coyote HTTP/1.1 on http-8080

May 3, 2010 11:42:11 AM org.apache.coyote.ajp.AjpAprProtocol start

INFO: Starting Coyote AJP/1.3 on ajp-8009

May 3, 2010 11:42:11 AM org.apache.catalina.startup.Catalina start

INFO: Server startup in 31159 ms

Regards,

Jason


Jason P. Pickering

email: jason.p.pickering@gmail.com

tel:+260968395190

Jason P. Pickering

email: jason.p.pickering@gmail.com

tel:+260968395190


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

Does this imply that I can remove it in a production instance when the
version will not be changed?

Regards,
Jason

···

2010/5/3 Lars Helge Øverland <larshelge@gmail.com>:

On Mon, May 3, 2010 at 12:44 PM, Jason Pickering > <jason.p.pickering@gmail.com> wrote:

Duh, sorry for this. My own mistake, but at least it is documented now..

I was missing

hibernate.hbm2ddl.auto = update

in the hibernate.properties file.

Since this seems to be required, it might be a good idea to be sure it
is there before the app attempts to fire itself up!

Regards,
Jason

Okay. Using hbm2dll in production settings is not really recommended but we
are doing it anyway. The alternative is to distribute sql scripts that
creates the database - and publishing new ones/amendments every time we make
a change.

I guess you were starting up a new database this time..

Lars

On Mon, May 3, 2010 at 11:44 AM, Jason Pickering >> <jason.p.pickering@gmail.com> wrote:
> Hi there. I am having some issues with the latest version. Sorry for
> the lengthy mail, but I have copied and pasted a bunch of log files.
>
> I am using Postgres, Java 1.6.19, Tomcat 6.0.24 on Ubuntu.
>
> During startup on a clean DB, only these are the tables that are
> created.
>
> dhis2_tj=# \dt
> List of relations
> Schema | Name | Type | Owner
> --------+-------------------------------+-------+----------
> public | aggregateddatasetcompleteness | table | postgres
> public | aggregateddatavalue | table | postgres
> public | aggregatedindicatorvalue | table | postgres
> public | categoryoptioncombo | table | postgres
> public | dataelement | table | postgres
> public | datavaluearchive | table | postgres
> public | period | table | postgres
> public | source | table | postgres
> (8 rows)
>
> DHIS2 appears to start correctly the application, I get this..
>
> May 3, 2010 10:48:05 AM org.apache.catalina.startup.HostConfig
> deployDirectory
> INFO: Deploying web application directory ROOT
> May 3, 2010 10:48:05 AM org.apache.catalina.startup.HostConfig
> deployDirectory
> INFO: Deploying web application directory docs
> May 3, 2010 10:48:05 AM org.apache.coyote.http11.Http11AprProtocol start
> INFO: Starting Coyote HTTP/1.1 on http-8080
> May 3, 2010 10:48:05 AM org.apache.coyote.ajp.AjpAprProtocol start
> INFO: Starting Coyote AJP/1.3 on ajp-8009
> May 3, 2010 10:48:05 AM org.apache.catalina.startup.Catalina start
> INFO: Server startup in 36108 ms
> May 3, 2010 11:35:12 AM org.apache.catalina.core.AprLifecycleListener
> init
> INFO: Loaded APR based Apache Tomcat Native library 1.1.20.
> May 3, 2010 11:35:12 AM org.apache.catalina.core.AprLifecycleListener
> init
> INFO: APR capabilities: IPv6 [true], sendfile [true], accept filters
> [false], random [true].
> May 3, 2010 11:35:12 AM org.apache.catalina.core.AprLifecycleListener
> lifecycleEvent
> INFO: Failed to initialize the SSLEngine.
> May 3, 2010 11:35:12 AM org.apache.coyote.http11.Http11AprProtocol init
> INFO: Initializing Coyote HTTP/1.1 on http-8080
> May 3, 2010 11:35:12 AM org.apache.coyote.ajp.AjpAprProtocol init
> INFO: Initializing Coyote AJP/1.3 on ajp-8009
> May 3, 2010 11:35:12 AM org.apache.catalina.startup.Catalina load
> INFO: Initialization processed in 2020 ms
> May 3, 2010 11:35:13 AM org.apache.catalina.core.StandardService start
> INFO: Starting service Catalina
> May 3, 2010 11:35:13 AM org.apache.catalina.core.StandardEngine start
> INFO: Starting Servlet Engine: Apache Tomcat/6.0.26
> May 3, 2010 11:35:13 AM org.apache.catalina.startup.HostConfig
> deployDescriptor
> INFO: Deploying configuration descriptor manager.xml
> May 3, 2010 11:35:13 AM org.apache.catalina.startup.HostConfig
> deployDescriptor
> INFO: Deploying configuration descriptor host-manager.xml
> May 3, 2010 11:35:14 AM org.apache.catalina.startup.HostConfig deployWAR
> INFO: Deploying web application archive dhis.war
> May 3, 2010 11:35:17 AM org.apache.catalina.loader.WebappClassLoader
> validateJarFile
> INFO:
> validateJarFile(/usr/local/apache-tomcat-6.0.26/webapps/dhis/WEB-INF/lib/servlet-api-2.4.jar)
> - jar not loaded. See Servlet Spec 2.3, section 9.7.2. Offending
> class: javax/servlet/Servlet.class
> log4j:WARN No such property [maxFileSize] in
> org.apache.log4j.DailyRollingFileAppender.
> log4j:WARN No such property [maxBackupIndex] in
> org.apache.log4j.DailyRollingFileAppender.
> * INFO 11:35:25,504 System property dhis2.home not set
> (DefaultLocationManager.java [main])
> * INFO 11:35:25,523 Environment variable DHIS2_HOME points to
> /usr/local/apache-tomcat-6.0.26/conf/dhis2/
> (DefaultLocationManager.java [main])
> * INFO 11:35:45,159 Executing startup routine [1 of 13, runlevel 0]:
> DataSetShortNamePopulator (DefaultStartupRoutineExecutor.java [main])
> * INFO 11:35:45,383 Populated DataSet short name and code
> (DataSetShortNamePopulator.java [main])
> * INFO 11:35:45,384 Executing startup routine [2 of 13, runlevel 0]:
> UuidPopulator (DefaultStartupRoutineExecutor.java [main])
> * INFO 11:35:45,392 Checked CategoryOption uuids (UuidPopulator.java
> [main])
> * INFO 11:35:45,400 Checked Category uuids (UuidPopulator.java [main])
> * INFO 11:35:45,413 Checked DataElement uuids (UuidPopulator.java
> [main])
> * INFO 11:35:45,413 Executing startup routine [3 of 13, runlevel 0]:
> OrganisationUnitHierarchyVerifier (DefaultStartupRoutineExecutor.java
> [main])
> * WARN 11:35:45,729 firstResult/maxResults specified with collection
> fetch; applying in memory! (QueryTranslatorImpl.java [main])
> * INFO 11:35:45,821 Added organistion unit hierarchy
> (OrganisationUnitHierarchyVerifier.java [main])
> * INFO 11:35:45,821 Executing startup routine [4 of 13, runlevel 0]:
> OrganisationUnitGroupSetPopulator (DefaultStartupRoutineExecutor.java
> [main])
> * INFO 11:35:45,841 Executing startup routine [5 of 13, runlevel 0]:
> OpenHealthDataSourceWriter (DefaultStartupRoutineExecutor.java [main])
> * INFO 11:35:45,841 Environment variable OPENHEALTH_HOME not set
> (OpenHealthDataSourceWriter.java [main])
> * INFO 11:35:45,842 Executing startup routine [6 of 13, runlevel 0]:
> ReportTableUpgrader (DefaultStartupRoutineExecutor.java [main])
> * INFO 11:35:45,845 Upgraded report tables (ReportTableUpgrader.java
> [main])
> * INFO 11:35:45,846 Executing startup routine [7 of 13, runlevel 1]:
> $Proxy34 (DefaultStartupRoutineExecutor.java [main])
> * INFO 11:35:46,183 Updated Category sort order and primary keys
> (OptionsCategoriesDefaultSortOrderPopulator.java [main])
> * INFO 11:35:46,184 Executing startup routine [8 of 13, runlevel 1]:
> $Proxy34 (DefaultStartupRoutineExecutor.java [main])
> * INFO 11:35:46,202 Executing startup routine [9 of 13, runlevel 1]:
> $Proxy34 (DefaultStartupRoutineExecutor.java [main])
> * INFO 11:35:46,203 Removing datasetid column from dataentryform
> table (DataEntryFormPopulator.java [main])
> * INFO 11:35:46,346 Executing startup routine [10 of 13, runlevel 2]:
> $Proxy34 (DefaultStartupRoutineExecutor.java [main])
> * INFO 11:35:46,383 Added default dataelement dimension
> (DataElementDefaultDimensionPopulator.java [main])
> * INFO 11:35:46,407 Executing startup routine [11 of 13, runlevel 2]:
> $Proxy34 (DefaultStartupRoutineExecutor.java [main])
> * INFO 11:35:46,434 Executing startup routine [12 of 13, runlevel 3]:
> TableCreator (DefaultStartupRoutineExecutor.java [main])
> * INFO 11:35:46,438 Created table aggregateddatavalue
> (TableCreator.java [main])
> * INFO 11:35:46,441 Created table aggregatedindicatorvalue
> (TableCreator.java [main])
> * INFO 11:35:46,457 Created index crosstab on table datavalue
> (TableCreator.java [main])
> * INFO 11:35:46,460 Created table aggregateddatasetcompleteness
> (TableCreator.java [main])
> * INFO 11:35:46,473 Created table aggregateddatasetcompleteness
> (TableCreator.java [main])
> * INFO 11:35:46,473 Executing startup routine [13 of 13, runlevel
> 10]: I18nUpgrader (DefaultStartupRoutineExecutor.java [main])
> * INFO 11:35:46,508 I18n Upgrader running using locale English
> (United Kingdom) (I18nUpgrader.java [main])
> * INFO 11:35:46,527 I18n Upgrader: OrganisationUnitGroupSet (2)
> (I18nUpgrader.java [main])
> * INFO 11:35:46,534 I81n Upgrader: DataElementCategory (1)
> (I18nUpgrader.java [main])
> * INFO 11:35:46,537 I81n Upgrader: DataElementCategoryOption (1)
> (I18nUpgrader.java [main])
> * INFO 11:35:46,540 I81n Upgrader: DataElementCategoryCombo (1)
> (I18nUpgrader.java [main])
> * INFO 11:35:46,541 All startup routines done
> (DefaultStartupRoutineExecutor.java [main])
> May 3, 2010 11:35:49 AM org.apache.catalina.startup.HostConfig
> deployDirectory
> INFO: Deploying web application directory examples
> May 3, 2010 11:35:49 AM org.apache.catalina.startup.HostConfig
> deployDirectory
> INFO: Deploying web application directory ROOT
> May 3, 2010 11:35:49 AM org.apache.catalina.startup.HostConfig
> deployDirectory
> INFO: Deploying web application directory docs
> May 3, 2010 11:35:49 AM org.apache.coyote.http11.Http11AprProtocol start
> INFO: Starting Coyote HTTP/1.1 on http-8080
> May 3, 2010 11:35:49 AM org.apache.coyote.ajp.AjpAprProtocol start
> INFO: Starting Coyote AJP/1.3 on ajp-8009
> May 3, 2010 11:35:49 AM org.apache.catalina.startup.Catalina start
> INFO: Server startup in 36984 ms
> * AUDIT_TRAIL 11:36:11,757 User "null" added User "admin admin"
> (HibernateUserStore.java [http-8080-1])
> * INFO 11:36:17,940 User login success: 'admin'
> (DefaultUserAuditService.java [http-8080-1])
> * INFO 11:36:21,364 User logout: 'admin'
> (DefaultUserAuditService.java [http-8080-2])
>
> I was able to login, which seems very strange, as there is no user
> table. But when I logout I see this..
>
> INFO: Stopping service Catalina
> May 3, 2010 11:36:31 AM org.apache.catalina.loader.WebappClassLoader
> clearReferencesJdbc
> SEVERE: A web application registered the JBDC driver [org.h2.Driver]
> but failed to unregister it when the web application was stopped. To
> prevent a memory leak, the JDBC Driver has been forcibly unregistered.
> May 3, 2010 11:36:31 AM org.apache.catalina.loader.WebappClassLoader
> clearReferencesJdbc
> SEVERE: A web application registered the JBDC driver
> [com.mysql.jdbc.Driver] but failed to unregister it when the web
> application was stopped. To prevent a memory leak, the JDBC Driver has
> been forcibly unregistered.
> May 3, 2010 11:36:31 AM org.apache.catalina.loader.WebappClassLoader
> clearReferencesJdbc
> SEVERE: A web application registered the JBDC driver
> [org.postgresql.Driver] but failed to unregister it when the web
> application was stopped. To prevent a memory leak, the JDBC Driver has
> been forcibly unregistered.
> May 3, 2010 11:36:31 AM org.apache.catalina.loader.WebappClassLoader
> clearThreadLocalMap
> SEVERE: A web application created a ThreadLocal with key of type
> [null] (value [com.opensymphony.xwork2.inject.ContainerImpl$10@ffeba4])
> and a value of type [java.lang.Object] (value
> [[Ljava.lang.Object;@1c78066]) but failed to remove it when the web
> application was stopped. To prevent a memory leak, the ThreadLocal has
> been forcibly removed.
> May 3, 2010 11:36:31 AM org.apache.catalina.loader.WebappClassLoader
> clearThreadLocalMap
> SEVERE: A web application created a ThreadLocal with key of type
> [null] (value [com.opensymphony.xwork2.inject.ContainerImpl$10@becf68])
> and a value of type [java.lang.Object] (value
> [[Ljava.lang.Object;@122c082]) but failed to remove it when the web
> application was stopped. To prevent a memory leak, the ThreadLocal has
> been forcibly removed.
> May 3, 2010 11:36:31 AM org.apache.catalina.loader.WebappClassLoader
> clearThreadLocalMap
> SEVERE: A web application created a ThreadLocal with key of type
> [null] (value [com.opensymphony.xwork2.inject.ContainerImpl$10@becf68])
> and a value of type [java.lang.Object] (value
> [[Ljava.lang.Object;@1fd8905]) but failed to remove it when the web
> application was stopped. To prevent a memory leak, the ThreadLocal has
> been forcibly removed.
> No protocol specified
>
>
>
> Finally, when I try and start the application again, I get this..
>
> ction 9.7.2. Offending class: javax/servlet/Servlet.class
> log4j:WARN No such property [maxFileSize] in
> org.apache.log4j.DailyRollingFileAppender.
> log4j:WARN No such property [maxBackupIndex] in
> org.apache.log4j.DailyRollingFileAppender.
> * INFO 11:41:47,577 System property dhis2.home not set
> (DefaultLocationManager.java [main])
> * INFO 11:41:47,582 Environment variable DHIS2_HOME points to
> /usr/local/apache-tomcat-6.0.26/conf/dhis2/
> (DefaultLocationManager.java [main])
> * ERROR 11:41:56,075 Unsuccessful: create table categoryoptioncombo
> (categoryoptioncomboid int4 not null, primary key
> (categoryoptioncomboid)) (SchemaExport.java [main])
> * ERROR 11:41:56,076 ERROR: relation "categoryoptioncombo" already
> exists (SchemaExport.java [main])
> * ERROR 11:41:56,277 Unsuccessful: create table dataelement
> (dataelementid int4 not null, uuid varchar(40), name varchar(230) not
> null unique, alternativename varchar(230) unique, shortname
> varchar(25) not null unique, code varchar(100), description text,
> active bool, valuetype varchar(16) not null, domaintype varchar(16),
> aggregationtype varchar(16) not null, extendeddataelementid int4
> unique, categorycomboid int4, sortOrder int4, url varchar(255),
> lastUpdated timestamp, zeroIsSignificant bool, primary key
> (dataelementid)) (SchemaExport.java [main])
> * ERROR 11:41:56,279 ERROR: relation "dataelement" already exists
> (SchemaExport.java [main])
> * ERROR 11:41:57,400 Unsuccessful: create table period (periodid int4
> not null, periodtypeid int4, startdate date not null, enddate date not
> null, primary key (periodid), unique (periodtypeid, startdate,
> enddate)) (SchemaExport.java [main])
> * ERROR 11:41:57,402 ERROR: relation "period" already exists
> (SchemaExport.java [main])
> * ERROR 11:41:57,877 Unsuccessful: create table source (sourceid int4
> not null, primary key (sourceid)) (SchemaExport.java [main])
> * ERROR 11:41:57,879 ERROR: relation "source" already exists
> (SchemaExport.java [main])
> * INFO 11:42:08,405 Executing startup routine [1 of 13, runlevel 0]:
> DataSetShortNamePopulator (DefaultStartupRoutineExecutor.java [main])
> * INFO 11:42:08,572 Populated DataSet short name and code
> (DataSetShortNamePopulator.java [main])
> * INFO 11:42:08,572 Executing startup routine [2 of 13, runlevel 0]:
> UuidPopulator (DefaultStartupRoutineExecutor.java [main])
> * INFO 11:42:08,581 Checked CategoryOption uuids (UuidPopulator.java
> [main])
> * INFO 11:42:08,591 Checked Category uuids (UuidPopulator.java [main])
> * INFO 11:42:08,604 Checked DataElement uuids (UuidPopulator.java
> [main])
> * INFO 11:42:08,604 Executing startup routine [3 of 13, runlevel 0]:
> OrganisationUnitHierarchyVerifier (DefaultStartupRoutineExecutor.java
> [main])
> * WARN 11:42:08,901 firstResult/maxResults specified with collection
> fetch; applying in memory! (QueryTranslatorImpl.java [main])
> * INFO 11:42:08,975 Added organistion unit hierarchy
> (OrganisationUnitHierarchyVerifier.java [main])
> * INFO 11:42:08,975 Executing startup routine [4 of 13, runlevel 0]:
> OrganisationUnitGroupSetPopulator (DefaultStartupRoutineExecutor.java
> [main])
> * INFO 11:42:08,993 Executing startup routine [5 of 13, runlevel 0]:
> OpenHealthDataSourceWriter (DefaultStartupRoutineExecutor.java [main])
> * INFO 11:42:08,993 Environment variable OPENHEALTH_HOME not set
> (OpenHealthDataSourceWriter.java [main])
> * INFO 11:42:08,994 Executing startup routine [6 of 13, runlevel 0]:
> ReportTableUpgrader (DefaultStartupRoutineExecutor.java [main])
> * INFO 11:42:08,997 Upgraded report tables (ReportTableUpgrader.java
> [main])
> * INFO 11:42:08,997 Executing startup routine [7 of 13, runlevel 1]:
> $Proxy34 (DefaultStartupRoutineExecutor.java [main])
> * INFO 11:42:09,151 Updated Category sort order and primary keys
> (OptionsCategoriesDefaultSortOrderPopulator.java [main])
> * INFO 11:42:09,152 Executing startup routine [8 of 13, runlevel 1]:
> $Proxy34 (DefaultStartupRoutineExecutor.java [main])
> * INFO 11:42:09,166 Executing startup routine [9 of 13, runlevel 1]:
> $Proxy34 (DefaultStartupRoutineExecutor.java [main])
> * INFO 11:42:09,167 Removing datasetid column from dataentryform
> table (DataEntryFormPopulator.java [main])
> * INFO 11:42:09,286 Executing startup routine [10 of 13, runlevel 2]:
> $Proxy34 (DefaultStartupRoutineExecutor.java [main])
> * INFO 11:42:09,338 Added default dataelement dimension
> (DataElementDefaultDimensionPopulator.java [main])
> * WARN 11:42:09,352 SQL Error: 0, SQLState: 23505
> (JDBCExceptionReporter.java [main])
> * ERROR 11:42:09,353 Batch entry 0 insert into categoryoptioncombo
> (categoryoptioncomboid) values ('16') was aborted. Call
> getNextException to see the cause. (JDBCExceptionReporter.java [main])
> * WARN 11:42:09,353 SQL Error: 0, SQLState: 23505
> (JDBCExceptionReporter.java [main])
> * ERROR 11:42:09,355 ERROR: duplicate key value violates unique
> constraint "categoryoptioncombo_pkey" (JDBCExceptionReporter.java
> [main])
> * ERROR 11:42:09,358 Could not synchronize database state with session
> (AbstractFlushingEventListener.java [main])
> org.hibernate.exception.ConstraintViolationException: Could not
> execute JDBC batch update
> at
> org.hibernate.exception.SQLStateConverter.convert(SQLStateConverter.java:71)
> at
> org.hibernate.exception.JDBCExceptionHelper.convert(JDBCExceptionHelper.java:43)
> at
> org.hibernate.jdbc.AbstractBatcher.executeBatch(AbstractBatcher.java:253)
> at
> org.hibernate.jdbc.AbstractBatcher.prepareStatement(AbstractBatcher.java:92)
> at
> org.hibernate.jdbc.AbstractBatcher.prepareStatement(AbstractBatcher.java:87)
> at
> org.hibernate.persister.entity.AbstractEntityPersister.insert(AbstractEntityPersister.java:2237)
> at
> org.hibernate.persister.entity.AbstractEntityPersister.insert(AbstractEntityPersister.java:2665)
> at
> org.hibernate.action.EntityInsertAction.execute(EntityInsertAction.java:60)
> at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:279)
> at
> org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:263)
> at
> org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:167)
> at
> org.hibernate.event.def.AbstractFlushingEventListener.performExecutions(AbstractFlushingEventListener.java:298)
> at
> org.hibernate.event.def.DefaultAutoFlushEventListener.onAutoFlush(DefaultAutoFlushEventListener.java:41)
> at
> org.hibernate.impl.SessionImpl.autoFlushIfRequired(SessionImpl.java:969)
> at org.hibernate.impl.SessionImpl.list(SessionImpl.java:1562)
> at org.hibernate.impl.CriteriaImpl.list(CriteriaImpl.java:283)
> at
> org.hibernate.impl.CriteriaImpl.uniqueResult(CriteriaImpl.java:305)
> at
> org.hisp.dhis.hibernate.HibernateGenericStore.getObject(HibernateGenericStore.java:125)
> at
> org.hisp.dhis.hibernate.HibernateGenericStore.getByName(HibernateGenericStore.java:178)
> at
> org.hisp.dhis.dataelement.DefaultDataElementCategoryService.getDataElementCategoryComboByName(DefaultDataElementCategoryService.java:243)
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> at
> sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
> at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
> at java.lang.reflect.Method.invoke(Method.java:597)
> at
> org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)
> at
> org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:182)
> at
> org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:149)
> at
> org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:106)
> at
> org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171)
> at
> org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:89)
> at
> org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171)
> at
> org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
> at $Proxy15.getDataElementCategoryComboByName(Unknown Source)
> at
> org.hisp.dhis.dataelement.DataElementDefaultDimensionPopulator.execute(DataElementDefaultDimensionPopulator.java:90)
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> at
> sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
> at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
> at java.lang.reflect.Method.invoke(Method.java:597)
> at
> org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)
> at
> org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:182)
> at
> org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:149)
> at
> org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:106)
> at
> org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171)
> at
> org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
> at $Proxy34.execute(Unknown Source)
> at
> org.hisp.dhis.system.startup.DefaultStartupRoutineExecutor.execute(DefaultStartupRoutineExecutor.java:101)
> at
> org.hisp.dhis.system.startup.DefaultStartupRoutineExecutor.execute(DefaultStartupRoutineExecutor.java:77)
> at
> org.hisp.dhis.system.startup.StartupListener.contextInitialized(StartupListener.java:57)
> at
> org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:3972)
> at
> org.apache.catalina.core.StandardContext.start(StandardContext.java:4467)
> at
> org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:791)
> at
> org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:771)
> at
> org.apache.catalina.core.StandardHost.addChild(StandardHost.java:546)
> at
> org.apache.catalina.startup.HostConfig.deployWAR(HostConfig.java:905)
> at
> org.apache.catalina.startup.HostConfig.deployWARs(HostConfig.java:740)
> at
> org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:500)
> at
> org.apache.catalina.startup.HostConfig.start(HostConfig.java:1277)
> at
> org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:321)
> at
> org.apache.catalina.util.LifecycleSupport.fireLifecycleEvent(LifecycleSupport.java:119)
> at
> org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1053)
> at
> org.apache.catalina.core.StandardHost.start(StandardHost.java:785)
> at
> org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1045)
> at
> org.apache.catalina.core.StandardEngine.start(StandardEngine.java:443)
> at
> org.apache.catalina.core.StandardService.start(StandardService.java:519)
> at
> org.apache.catalina.core.StandardServer.start(StandardServer.java:710)
> at org.apache.catalina.startup.Catalina.start(Catalina.java:581)
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> at
> sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
> at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
> at java.lang.reflect.Method.invoke(Method.java:597)
> at
> org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:289)
> at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:414)
> Caused by: java.sql.BatchUpdateException: Batch entry 0 insert into
> categoryoptioncombo (categoryoptioncomboid) values ('16') was aborted.
> Call getNextException to see the cause.
> at
> org.postgresql.jdbc2.AbstractJdbc2Statement$BatchResultHandler.handleError(AbstractJdbc2Statement.java:2569)
> at
> org.postgresql.core.v3.QueryExecutorImpl.processResults(QueryExecutorImpl.java:1796)
> at
> org.postgresql.core.v3.QueryExecutorImpl.execute(QueryExecutorImpl.java:407)
> at
> org.postgresql.jdbc2.AbstractJdbc2Statement.executeBatch(AbstractJdbc2Statement.java:2708)
> at
> com.mchange.v2.c3p0.impl.NewProxyPreparedStatement.executeBatch(NewProxyPreparedStatement.java:1723)
> at
> org.hibernate.jdbc.BatchingBatcher.doExecuteBatch(BatchingBatcher.java:48)
> at
> org.hibernate.jdbc.AbstractBatcher.executeBatch(AbstractBatcher.java:246)
> ... 69 more
> May 3, 2010 11:42:09 AM org.apache.catalina.core.StandardContext start
> SEVERE: Error listenerStart
> May 3, 2010 11:42:09 AM org.apache.catalina.core.StandardContext start
> SEVERE: Context [/dhis] startup failed due to previous errors
> May 3, 2010 11:42:10 AM org.apache.catalina.loader.WebappClassLoader
> clearReferencesJdbc
> SEVERE: A web application registered the JBDC driver [org.h2.Driver]
> but failed to unregister it when the web application was stopped. To
> prevent a memory leak, the JDBC Driver has been forcibly unregistered.
> May 3, 2010 11:42:10 AM org.apache.catalina.loader.WebappClassLoader
> clearReferencesJdbc
> SEVERE: A web application registered the JBDC driver
> [com.mysql.jdbc.Driver] but failed to unregister it when the web
> application was stopped. To prevent a memory leak, the JDBC Driver has
> been forcibly unregistered.
> May 3, 2010 11:42:10 AM org.apache.catalina.loader.WebappClassLoader
> clearReferencesJdbc
> SEVERE: A web application registered the JBDC driver
> [org.postgresql.Driver] but failed to unregister it when the web
> application was stopped. To prevent a memory leak, the JDBC Driver has
> been forcibly unregistered.
> No protocol specified
> May 3, 2010 11:42:10 AM org.apache.catalina.startup.HostConfig
> deployDirectory
> INFO: Deploying web application directory examples
> May 3, 2010 11:42:11 AM org.apache.catalina.startup.HostConfig
> deployDirectory
> INFO: Deploying web application directory ROOT
> May 3, 2010 11:42:11 AM org.apache.catalina.startup.HostConfig
> deployDirectory
> INFO: Deploying web application directory docs
> May 3, 2010 11:42:11 AM org.apache.coyote.http11.Http11AprProtocol start
> INFO: Starting Coyote HTTP/1.1 on http-8080
> May 3, 2010 11:42:11 AM org.apache.coyote.ajp.AjpAprProtocol start
> INFO: Starting Coyote AJP/1.3 on ajp-8009
> May 3, 2010 11:42:11 AM org.apache.catalina.startup.Catalina start
> INFO: Server startup in 31159 ms
>
>
> Regards,
> Jason
>
>
> ---
> Jason P. Pickering
> email: jason.p.pickering@gmail.com
> tel:+260968395190
>

--
--
Jason P. Pickering
email: jason.p.pickering@gmail.com
tel:+260968395190

_______________________________________________
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

--
--
Jason P. Pickering
email: jason.p.pickering@gmail.com
tel:+260968395190

Does this imply that I can remove it in a production instance when the

version will not be changed?

Yes after the database initially has been created/modified.

···

2010/5/3 Jason Pickering jason.p.pickering@gmail.com

Regards,

Jason

2010/5/3 Lars Helge Øverland larshelge@gmail.com:

On Mon, May 3, 2010 at 12:44 PM, Jason Pickering > > > jason.p.pickering@gmail.com wrote:

Duh, sorry for this. My own mistake, but at least it is documented now…

I was missing

hibernate.hbm2ddl.auto = update

in the hibernate.properties file.

Since this seems to be required, it might be a good idea to be sure it

is there before the app attempts to fire itself up!

Regards,

Jason

Okay. Using hbm2dll in production settings is not really recommended but we

are doing it anyway. The alternative is to distribute sql scripts that

creates the database - and publishing new ones/amendments every time we make

a change.

I guess you were starting up a new database this time…

Lars

On Mon, May 3, 2010 at 11:44 AM, Jason Pickering > > >> jason.p.pickering@gmail.com wrote:

Hi there. I am having some issues with the latest version. Sorry for

the lengthy mail, but I have copied and pasted a bunch of log files.

I am using Postgres, Java 1.6.19, Tomcat 6.0.24 on Ubuntu.

During startup on a clean DB, only these are the tables that are

created.

dhis2_tj=# \dt

                List of relations

Schema | Name | Type | Owner

--------±------------------------------±------±---------

public | aggregateddatasetcompleteness | table | postgres

public | aggregateddatavalue | table | postgres

public | aggregatedindicatorvalue | table | postgres

public | categoryoptioncombo | table | postgres

public | dataelement | table | postgres

public | datavaluearchive | table | postgres

public | period | table | postgres

public | source | table | postgres

(8 rows)

DHIS2 appears to start correctly the application, I get this…

May 3, 2010 10:48:05 AM org.apache.catalina.startup.HostConfig

deployDirectory

INFO: Deploying web application directory ROOT

May 3, 2010 10:48:05 AM org.apache.catalina.startup.HostConfig

deployDirectory

INFO: Deploying web application directory docs

May 3, 2010 10:48:05 AM org.apache.coyote.http11.Http11AprProtocol start

INFO: Starting Coyote HTTP/1.1 on http-8080

May 3, 2010 10:48:05 AM org.apache.coyote.ajp.AjpAprProtocol start

INFO: Starting Coyote AJP/1.3 on ajp-8009

May 3, 2010 10:48:05 AM org.apache.catalina.startup.Catalina start

INFO: Server startup in 36108 ms

May 3, 2010 11:35:12 AM org.apache.catalina.core.AprLifecycleListener

init

INFO: Loaded APR based Apache Tomcat Native library 1.1.20.

May 3, 2010 11:35:12 AM org.apache.catalina.core.AprLifecycleListener

init

INFO: APR capabilities: IPv6 [true], sendfile [true], accept filters

[false], random [true].

May 3, 2010 11:35:12 AM org.apache.catalina.core.AprLifecycleListener

lifecycleEvent

INFO: Failed to initialize the SSLEngine.

May 3, 2010 11:35:12 AM org.apache.coyote.http11.Http11AprProtocol init

INFO: Initializing Coyote HTTP/1.1 on http-8080

May 3, 2010 11:35:12 AM org.apache.coyote.ajp.AjpAprProtocol init

INFO: Initializing Coyote AJP/1.3 on ajp-8009

May 3, 2010 11:35:12 AM org.apache.catalina.startup.Catalina load

INFO: Initialization processed in 2020 ms

May 3, 2010 11:35:13 AM org.apache.catalina.core.StandardService start

INFO: Starting service Catalina

May 3, 2010 11:35:13 AM org.apache.catalina.core.StandardEngine start

INFO: Starting Servlet Engine: Apache Tomcat/6.0.26

May 3, 2010 11:35:13 AM org.apache.catalina.startup.HostConfig

deployDescriptor

INFO: Deploying configuration descriptor manager.xml

May 3, 2010 11:35:13 AM org.apache.catalina.startup.HostConfig

deployDescriptor

INFO: Deploying configuration descriptor host-manager.xml

May 3, 2010 11:35:14 AM org.apache.catalina.startup.HostConfig deployWAR

INFO: Deploying web application archive dhis.war

May 3, 2010 11:35:17 AM org.apache.catalina.loader.WebappClassLoader

validateJarFile

INFO:

validateJarFile(/usr/local/apache-tomcat-6.0.26/webapps/dhis/WEB-INF/lib/servlet-api-2.4.jar)

  • jar not loaded. See Servlet Spec 2.3, section 9.7.2. Offending

class: javax/servlet/Servlet.class

log4j:WARN No such property [maxFileSize] in

org.apache.log4j.DailyRollingFileAppender.

log4j:WARN No such property [maxBackupIndex] in

org.apache.log4j.DailyRollingFileAppender.

  • INFO 11:35:25,504 System property dhis2.home not set

(DefaultLocationManager.java [main])

  • INFO 11:35:25,523 Environment variable DHIS2_HOME points to

/usr/local/apache-tomcat-6.0.26/conf/dhis2/

(DefaultLocationManager.java [main])

  • INFO 11:35:45,159 Executing startup routine [1 of 13, runlevel 0]:

DataSetShortNamePopulator (DefaultStartupRoutineExecutor.java [main])

  • INFO 11:35:45,383 Populated DataSet short name and code

(DataSetShortNamePopulator.java [main])

  • INFO 11:35:45,384 Executing startup routine [2 of 13, runlevel 0]:

UuidPopulator (DefaultStartupRoutineExecutor.java [main])

  • INFO 11:35:45,392 Checked CategoryOption uuids (UuidPopulator.java

[main])

  • INFO 11:35:45,400 Checked Category uuids (UuidPopulator.java [main])
  • INFO 11:35:45,413 Checked DataElement uuids (UuidPopulator.java

[main])

  • INFO 11:35:45,413 Executing startup routine [3 of 13, runlevel 0]:

OrganisationUnitHierarchyVerifier (DefaultStartupRoutineExecutor.java

[main])

  • WARN 11:35:45,729 firstResult/maxResults specified with collection

fetch; applying in memory! (QueryTranslatorImpl.java [main])

  • INFO 11:35:45,821 Added organistion unit hierarchy

(OrganisationUnitHierarchyVerifier.java [main])

  • INFO 11:35:45,821 Executing startup routine [4 of 13, runlevel 0]:

OrganisationUnitGroupSetPopulator (DefaultStartupRoutineExecutor.java

[main])

  • INFO 11:35:45,841 Executing startup routine [5 of 13, runlevel 0]:

OpenHealthDataSourceWriter (DefaultStartupRoutineExecutor.java [main])

  • INFO 11:35:45,841 Environment variable OPENHEALTH_HOME not set

(OpenHealthDataSourceWriter.java [main])

  • INFO 11:35:45,842 Executing startup routine [6 of 13, runlevel 0]:

ReportTableUpgrader (DefaultStartupRoutineExecutor.java [main])

  • INFO 11:35:45,845 Upgraded report tables (ReportTableUpgrader.java

[main])

  • INFO 11:35:45,846 Executing startup routine [7 of 13, runlevel 1]:

$Proxy34 (DefaultStartupRoutineExecutor.java [main])

  • INFO 11:35:46,183 Updated Category sort order and primary keys

(OptionsCategoriesDefaultSortOrderPopulator.java [main])

  • INFO 11:35:46,184 Executing startup routine [8 of 13, runlevel 1]:

$Proxy34 (DefaultStartupRoutineExecutor.java [main])

  • INFO 11:35:46,202 Executing startup routine [9 of 13, runlevel 1]:

$Proxy34 (DefaultStartupRoutineExecutor.java [main])

  • INFO 11:35:46,203 Removing datasetid column from dataentryform

table (DataEntryFormPopulator.java [main])

  • INFO 11:35:46,346 Executing startup routine [10 of 13, runlevel 2]:

$Proxy34 (DefaultStartupRoutineExecutor.java [main])

  • INFO 11:35:46,383 Added default dataelement dimension

(DataElementDefaultDimensionPopulator.java [main])

  • INFO 11:35:46,407 Executing startup routine [11 of 13, runlevel 2]:

$Proxy34 (DefaultStartupRoutineExecutor.java [main])

  • INFO 11:35:46,434 Executing startup routine [12 of 13, runlevel 3]:

TableCreator (DefaultStartupRoutineExecutor.java [main])

  • INFO 11:35:46,438 Created table aggregateddatavalue

(TableCreator.java [main])

  • INFO 11:35:46,441 Created table aggregatedindicatorvalue

(TableCreator.java [main])

  • INFO 11:35:46,457 Created index crosstab on table datavalue

(TableCreator.java [main])

  • INFO 11:35:46,460 Created table aggregateddatasetcompleteness

(TableCreator.java [main])

  • INFO 11:35:46,473 Created table aggregateddatasetcompleteness

(TableCreator.java [main])

  • INFO 11:35:46,473 Executing startup routine [13 of 13, runlevel

10]: I18nUpgrader (DefaultStartupRoutineExecutor.java [main])

  • INFO 11:35:46,508 I18n Upgrader running using locale English

(United Kingdom) (I18nUpgrader.java [main])

  • INFO 11:35:46,527 I18n Upgrader: OrganisationUnitGroupSet (2)

(I18nUpgrader.java [main])

  • INFO 11:35:46,534 I81n Upgrader: DataElementCategory (1)

(I18nUpgrader.java [main])

  • INFO 11:35:46,537 I81n Upgrader: DataElementCategoryOption (1)

(I18nUpgrader.java [main])

  • INFO 11:35:46,540 I81n Upgrader: DataElementCategoryCombo (1)

(I18nUpgrader.java [main])

  • INFO 11:35:46,541 All startup routines done

(DefaultStartupRoutineExecutor.java [main])

May 3, 2010 11:35:49 AM org.apache.catalina.startup.HostConfig

deployDirectory

INFO: Deploying web application directory examples

May 3, 2010 11:35:49 AM org.apache.catalina.startup.HostConfig

deployDirectory

INFO: Deploying web application directory ROOT

May 3, 2010 11:35:49 AM org.apache.catalina.startup.HostConfig

deployDirectory

INFO: Deploying web application directory docs

May 3, 2010 11:35:49 AM org.apache.coyote.http11.Http11AprProtocol start

INFO: Starting Coyote HTTP/1.1 on http-8080

May 3, 2010 11:35:49 AM org.apache.coyote.ajp.AjpAprProtocol start

INFO: Starting Coyote AJP/1.3 on ajp-8009

May 3, 2010 11:35:49 AM org.apache.catalina.startup.Catalina start

INFO: Server startup in 36984 ms

  • AUDIT_TRAIL 11:36:11,757 User “null” added User “admin admin”

(HibernateUserStore.java [http-8080-1])

  • INFO 11:36:17,940 User login success: ‘admin’

(DefaultUserAuditService.java [http-8080-1])

  • INFO 11:36:21,364 User logout: ‘admin’

(DefaultUserAuditService.java [http-8080-2])

I was able to login, which seems very strange, as there is no user

table. But when I logout I see this…

INFO: Stopping service Catalina

May 3, 2010 11:36:31 AM org.apache.catalina.loader.WebappClassLoader

clearReferencesJdbc

SEVERE: A web application registered the JBDC driver [org.h2.Driver]

but failed to unregister it when the web application was stopped. To

prevent a memory leak, the JDBC Driver has been forcibly unregistered.

May 3, 2010 11:36:31 AM org.apache.catalina.loader.WebappClassLoader

clearReferencesJdbc

SEVERE: A web application registered the JBDC driver

[com.mysql.jdbc.Driver] but failed to unregister it when the web

application was stopped. To prevent a memory leak, the JDBC Driver has

been forcibly unregistered.

May 3, 2010 11:36:31 AM org.apache.catalina.loader.WebappClassLoader

clearReferencesJdbc

SEVERE: A web application registered the JBDC driver

[org.postgresql.Driver] but failed to unregister it when the web

application was stopped. To prevent a memory leak, the JDBC Driver has

been forcibly unregistered.

May 3, 2010 11:36:31 AM org.apache.catalina.loader.WebappClassLoader

clearThreadLocalMap

SEVERE: A web application created a ThreadLocal with key of type

[null] (value [com.opensymphony.xwork2.inject.ContainerImpl$10@ffeba4])

and a value of type [java.lang.Object] (value

[[Ljava.lang.Object;@1c78066]) but failed to remove it when the web

application was stopped. To prevent a memory leak, the ThreadLocal has

been forcibly removed.

May 3, 2010 11:36:31 AM org.apache.catalina.loader.WebappClassLoader

clearThreadLocalMap

SEVERE: A web application created a ThreadLocal with key of type

[null] (value [com.opensymphony.xwork2.inject.ContainerImpl$10@becf68])

and a value of type [java.lang.Object] (value

[[Ljava.lang.Object;@122c082]) but failed to remove it when the web

application was stopped. To prevent a memory leak, the ThreadLocal has

been forcibly removed.

May 3, 2010 11:36:31 AM org.apache.catalina.loader.WebappClassLoader

clearThreadLocalMap

SEVERE: A web application created a ThreadLocal with key of type

[null] (value [com.opensymphony.xwork2.inject.ContainerImpl$10@becf68])

and a value of type [java.lang.Object] (value

[[Ljava.lang.Object;@1fd8905]) but failed to remove it when the web

application was stopped. To prevent a memory leak, the ThreadLocal has

been forcibly removed.

No protocol specified

Finally, when I try and start the application again, I get this…

ction 9.7.2. Offending class: javax/servlet/Servlet.class

log4j:WARN No such property [maxFileSize] in

org.apache.log4j.DailyRollingFileAppender.

log4j:WARN No such property [maxBackupIndex] in

org.apache.log4j.DailyRollingFileAppender.

  • INFO 11:41:47,577 System property dhis2.home not set

(DefaultLocationManager.java [main])

  • INFO 11:41:47,582 Environment variable DHIS2_HOME points to

/usr/local/apache-tomcat-6.0.26/conf/dhis2/

(DefaultLocationManager.java [main])

  • ERROR 11:41:56,075 Unsuccessful: create table categoryoptioncombo

(categoryoptioncomboid int4 not null, primary key

(categoryoptioncomboid)) (SchemaExport.java [main])

  • ERROR 11:41:56,076 ERROR: relation “categoryoptioncombo” already

exists (SchemaExport.java [main])

  • ERROR 11:41:56,277 Unsuccessful: create table dataelement

(dataelementid int4 not null, uuid varchar(40), name varchar(230) not

null unique, alternativename varchar(230) unique, shortname

varchar(25) not null unique, code varchar(100), description text,

active bool, valuetype varchar(16) not null, domaintype varchar(16),

aggregationtype varchar(16) not null, extendeddataelementid int4

unique, categorycomboid int4, sortOrder int4, url varchar(255),

lastUpdated timestamp, zeroIsSignificant bool, primary key

(dataelementid)) (SchemaExport.java [main])

  • ERROR 11:41:56,279 ERROR: relation “dataelement” already exists

(SchemaExport.java [main])

  • ERROR 11:41:57,400 Unsuccessful: create table period (periodid int4

not null, periodtypeid int4, startdate date not null, enddate date not

null, primary key (periodid), unique (periodtypeid, startdate,

enddate)) (SchemaExport.java [main])

  • ERROR 11:41:57,402 ERROR: relation “period” already exists

(SchemaExport.java [main])

  • ERROR 11:41:57,877 Unsuccessful: create table source (sourceid int4

not null, primary key (sourceid)) (SchemaExport.java [main])

  • ERROR 11:41:57,879 ERROR: relation “source” already exists

(SchemaExport.java [main])

  • INFO 11:42:08,405 Executing startup routine [1 of 13, runlevel 0]:

DataSetShortNamePopulator (DefaultStartupRoutineExecutor.java [main])

  • INFO 11:42:08,572 Populated DataSet short name and code

(DataSetShortNamePopulator.java [main])

  • INFO 11:42:08,572 Executing startup routine [2 of 13, runlevel 0]:

UuidPopulator (DefaultStartupRoutineExecutor.java [main])

  • INFO 11:42:08,581 Checked CategoryOption uuids (UuidPopulator.java

[main])

  • INFO 11:42:08,591 Checked Category uuids (UuidPopulator.java [main])
  • INFO 11:42:08,604 Checked DataElement uuids (UuidPopulator.java

[main])

  • INFO 11:42:08,604 Executing startup routine [3 of 13, runlevel 0]:

OrganisationUnitHierarchyVerifier (DefaultStartupRoutineExecutor.java

[main])

  • WARN 11:42:08,901 firstResult/maxResults specified with collection

fetch; applying in memory! (QueryTranslatorImpl.java [main])

  • INFO 11:42:08,975 Added organistion unit hierarchy

(OrganisationUnitHierarchyVerifier.java [main])

  • INFO 11:42:08,975 Executing startup routine [4 of 13, runlevel 0]:

OrganisationUnitGroupSetPopulator (DefaultStartupRoutineExecutor.java

[main])

  • INFO 11:42:08,993 Executing startup routine [5 of 13, runlevel 0]:

OpenHealthDataSourceWriter (DefaultStartupRoutineExecutor.java [main])

  • INFO 11:42:08,993 Environment variable OPENHEALTH_HOME not set

(OpenHealthDataSourceWriter.java [main])

  • INFO 11:42:08,994 Executing startup routine [6 of 13, runlevel 0]:

ReportTableUpgrader (DefaultStartupRoutineExecutor.java [main])

  • INFO 11:42:08,997 Upgraded report tables (ReportTableUpgrader.java

[main])

  • INFO 11:42:08,997 Executing startup routine [7 of 13, runlevel 1]:

$Proxy34 (DefaultStartupRoutineExecutor.java [main])

  • INFO 11:42:09,151 Updated Category sort order and primary keys

(OptionsCategoriesDefaultSortOrderPopulator.java [main])

  • INFO 11:42:09,152 Executing startup routine [8 of 13, runlevel 1]:

$Proxy34 (DefaultStartupRoutineExecutor.java [main])

  • INFO 11:42:09,166 Executing startup routine [9 of 13, runlevel 1]:

$Proxy34 (DefaultStartupRoutineExecutor.java [main])

  • INFO 11:42:09,167 Removing datasetid column from dataentryform

table (DataEntryFormPopulator.java [main])

  • INFO 11:42:09,286 Executing startup routine [10 of 13, runlevel 2]:

$Proxy34 (DefaultStartupRoutineExecutor.java [main])

  • INFO 11:42:09,338 Added default dataelement dimension

(DataElementDefaultDimensionPopulator.java [main])

  • WARN 11:42:09,352 SQL Error: 0, SQLState: 23505

(JDBCExceptionReporter.java [main])

  • ERROR 11:42:09,353 Batch entry 0 insert into categoryoptioncombo

(categoryoptioncomboid) values (‘16’) was aborted. Call

getNextException to see the cause. (JDBCExceptionReporter.java [main])

  • WARN 11:42:09,353 SQL Error: 0, SQLState: 23505

(JDBCExceptionReporter.java [main])

  • ERROR 11:42:09,355 ERROR: duplicate key value violates unique

constraint “categoryoptioncombo_pkey” (JDBCExceptionReporter.java

[main])

  • ERROR 11:42:09,358 Could not synchronize database state with session

(AbstractFlushingEventListener.java [main])

org.hibernate.exception.ConstraintViolationException: Could not

execute JDBC batch update

   at

org.hibernate.exception.SQLStateConverter.convert(SQLStateConverter.java:71)

   at

org.hibernate.exception.JDBCExceptionHelper.convert(JDBCExceptionHelper.java:43)

   at

org.hibernate.jdbc.AbstractBatcher.executeBatch(AbstractBatcher.java:253)

   at

org.hibernate.jdbc.AbstractBatcher.prepareStatement(AbstractBatcher.java:92)

   at

org.hibernate.jdbc.AbstractBatcher.prepareStatement(AbstractBatcher.java:87)

   at

org.hibernate.persister.entity.AbstractEntityPersister.insert(AbstractEntityPersister.java:2237)

   at

org.hibernate.persister.entity.AbstractEntityPersister.insert(AbstractEntityPersister.java:2665)

   at

org.hibernate.action.EntityInsertAction.execute(EntityInsertAction.java:60)

   at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:279)
   at

org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:263)

   at

org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:167)

   at

org.hibernate.event.def.AbstractFlushingEventListener.performExecutions(AbstractFlushingEventListener.java:298)

   at

org.hibernate.event.def.DefaultAutoFlushEventListener.onAutoFlush(DefaultAutoFlushEventListener.java:41)

   at

org.hibernate.impl.SessionImpl.autoFlushIfRequired(SessionImpl.java:969)

   at org.hibernate.impl.SessionImpl.list(SessionImpl.java:1562)
   at org.hibernate.impl.CriteriaImpl.list(CriteriaImpl.java:283)
   at

org.hibernate.impl.CriteriaImpl.uniqueResult(CriteriaImpl.java:305)

   at

org.hisp.dhis.hibernate.HibernateGenericStore.getObject(HibernateGenericStore.java:125)

   at

org.hisp.dhis.hibernate.HibernateGenericStore.getByName(HibernateGenericStore.java:178)

   at

org.hisp.dhis.dataelement.DefaultDataElementCategoryService.getDataElementCategoryComboByName(DefaultDataElementCategoryService.java:243)

   at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   at

sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)

   at

sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)

   at java.lang.reflect.Method.invoke(Method.java:597)
   at

org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)

   at

org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:182)

   at

org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:149)

   at

org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:106)

   at

org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171)

   at

org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:89)

   at

org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171)

   at

org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)

   at $Proxy15.getDataElementCategoryComboByName(Unknown Source)
   at

org.hisp.dhis.dataelement.DataElementDefaultDimensionPopulator.execute(DataElementDefaultDimensionPopulator.java:90)

   at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   at

sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)

   at

sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)

   at java.lang.reflect.Method.invoke(Method.java:597)
   at

org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)

   at

org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:182)

   at

org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:149)

   at

org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:106)

   at

org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171)

   at

org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)

   at $Proxy34.execute(Unknown Source)
   at

org.hisp.dhis.system.startup.DefaultStartupRoutineExecutor.execute(DefaultStartupRoutineExecutor.java:101)

   at

org.hisp.dhis.system.startup.DefaultStartupRoutineExecutor.execute(DefaultStartupRoutineExecutor.java:77)

   at

org.hisp.dhis.system.startup.StartupListener.contextInitialized(StartupListener.java:57)

   at

org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:3972)

   at

org.apache.catalina.core.StandardContext.start(StandardContext.java:4467)

   at

org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:791)

   at

org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:771)

   at

org.apache.catalina.core.StandardHost.addChild(StandardHost.java:546)

   at

org.apache.catalina.startup.HostConfig.deployWAR(HostConfig.java:905)

   at

org.apache.catalina.startup.HostConfig.deployWARs(HostConfig.java:740)

   at

org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:500)

   at

org.apache.catalina.startup.HostConfig.start(HostConfig.java:1277)

   at

org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:321)

   at

org.apache.catalina.util.LifecycleSupport.fireLifecycleEvent(LifecycleSupport.java:119)

   at

org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1053)

   at

org.apache.catalina.core.StandardHost.start(StandardHost.java:785)

   at

org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1045)

   at

org.apache.catalina.core.StandardEngine.start(StandardEngine.java:443)

   at

org.apache.catalina.core.StandardService.start(StandardService.java:519)

   at

org.apache.catalina.core.StandardServer.start(StandardServer.java:710)

   at org.apache.catalina.startup.Catalina.start(Catalina.java:581)
   at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   at

sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)

   at

sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)

   at java.lang.reflect.Method.invoke(Method.java:597)
   at

org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:289)

   at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:414)

Caused by: java.sql.BatchUpdateException: Batch entry 0 insert into

categoryoptioncombo (categoryoptioncomboid) values (‘16’) was aborted.

Call getNextException to see the cause.

   at

org.postgresql.jdbc2.AbstractJdbc2Statement$BatchResultHandler.handleError(AbstractJdbc2Statement.java:2569)

   at

org.postgresql.core.v3.QueryExecutorImpl.processResults(QueryExecutorImpl.java:1796)

   at

org.postgresql.core.v3.QueryExecutorImpl.execute(QueryExecutorImpl.java:407)

   at

org.postgresql.jdbc2.AbstractJdbc2Statement.executeBatch(AbstractJdbc2Statement.java:2708)

   at

com.mchange.v2.c3p0.impl.NewProxyPreparedStatement.executeBatch(NewProxyPreparedStatement.java:1723)

   at

org.hibernate.jdbc.BatchingBatcher.doExecuteBatch(BatchingBatcher.java:48)

   at

org.hibernate.jdbc.AbstractBatcher.executeBatch(AbstractBatcher.java:246)

   ... 69 more

May 3, 2010 11:42:09 AM org.apache.catalina.core.StandardContext start

SEVERE: Error listenerStart

May 3, 2010 11:42:09 AM org.apache.catalina.core.StandardContext start

SEVERE: Context [/dhis] startup failed due to previous errors

May 3, 2010 11:42:10 AM org.apache.catalina.loader.WebappClassLoader

clearReferencesJdbc

SEVERE: A web application registered the JBDC driver [org.h2.Driver]

but failed to unregister it when the web application was stopped. To

prevent a memory leak, the JDBC Driver has been forcibly unregistered.

May 3, 2010 11:42:10 AM org.apache.catalina.loader.WebappClassLoader

clearReferencesJdbc

SEVERE: A web application registered the JBDC driver

[com.mysql.jdbc.Driver] but failed to unregister it when the web

application was stopped. To prevent a memory leak, the JDBC Driver has

been forcibly unregistered.

May 3, 2010 11:42:10 AM org.apache.catalina.loader.WebappClassLoader

clearReferencesJdbc

SEVERE: A web application registered the JBDC driver

[org.postgresql.Driver] but failed to unregister it when the web

application was stopped. To prevent a memory leak, the JDBC Driver has

been forcibly unregistered.

No protocol specified

May 3, 2010 11:42:10 AM org.apache.catalina.startup.HostConfig

deployDirectory

INFO: Deploying web application directory examples

May 3, 2010 11:42:11 AM org.apache.catalina.startup.HostConfig

deployDirectory

INFO: Deploying web application directory ROOT

May 3, 2010 11:42:11 AM org.apache.catalina.startup.HostConfig

deployDirectory

INFO: Deploying web application directory docs

May 3, 2010 11:42:11 AM org.apache.coyote.http11.Http11AprProtocol start

INFO: Starting Coyote HTTP/1.1 on http-8080

May 3, 2010 11:42:11 AM org.apache.coyote.ajp.AjpAprProtocol start

INFO: Starting Coyote AJP/1.3 on ajp-8009

May 3, 2010 11:42:11 AM org.apache.catalina.startup.Catalina start

INFO: Server startup in 31159 ms

Regards,

Jason


Jason P. Pickering

email: jason.p.pickering@gmail.com

tel:+260968395190

Jason P. Pickering

email: jason.p.pickering@gmail.com

tel:+260968395190


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

Jason P. Pickering

email: jason.p.pickering@gmail.com

tel:+260968395190