Sep 11, 2014 11:41:14 AM org.apache.catalina.core.AprLifecycleListener init
INFO: Loaded APR based Apache Tomcat Native library 1.1.29 using APR version 1.4
.8.
Sep 11, 2014 11:41:14 AM org.apache.catalina.core.AprLifecycleListener init
INFO: APR capabilities: IPv6 [true], sendfile [true], accept filters [false], ra
ndom [true].
Sep 11, 2014 11:41:16 AM org.apache.catalina.core.AprLifecycleListener initializ
eSSL
INFO: OpenSSL successfully initialized (OpenSSL 1.0.1e 11 Feb 2013)
Sep 11, 2014 11:41:17 AM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["http-apr-8084"]
Sep 11, 2014 11:41:17 AM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["ajp-apr-8009"]
Sep 11, 2014 11:41:17 AM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 3706 ms
Sep 11, 2014 11:41:17 AM org.apache.catalina.core.StandardService startInternal
INFO: Starting service Catalina
Sep 11, 2014 11:41:17 AM org.apache.catalina.core.StandardEngine startInternal
INFO: Starting Servlet Engine: Apache Tomcat/7.0.50
Sep 11, 2014 11:41:17 AM org.apache.catalina.startup.HostConfig deployDescriptor

INFO: Deploying configuration descriptor C:\Apache\Apacheconfig17\conf\Catalina\
localhost\manager.xml
Sep 11, 2014 11:41:18 AM org.apache.catalina.startup.HostConfig deployDescriptor

INFO: Deploying configuration descriptor C:\Apache\Apacheconfig17\conf\Catalina\
localhost\ROOT.xml
Sep 11, 2014 11:41:18 AM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["http-apr-8084"]
Sep 11, 2014 11:41:18 AM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["ajp-apr-8009"]
Sep 11, 2014 11:41:18 AM org.apache.catalina.startup.Catalina start
INFO: Server startup in 1278 ms
Sep 11, 2014 11:42:10 AM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive C:\Apache\Apacheconfig17\webapps\dhis.wa
r
* INFO  2014-09-11 11:43:40,216 System property dhis2.home not set (DefaultLocat
ionManager.java [http-apr-8084-exec-9])
* INFO  2014-09-11 11:43:40,219 Environment variable DHIS2_HOME points to C:\DHI
S17_HOME (DefaultLocationManager.java [http-apr-8084-exec-9])
* INFO  2014-09-11 11:43:41,186 Hibernate configuration loaded, using dialect: o
rg.hibernate.dialect.MySQLDialect (DefaultHibernateConfigurationProvider.java [h
ttp-apr-8084-exec-9])
Sep 11, 2014 11:43:46 AM com.hazelcast.hibernate.HazelcastLocalCacheRegionFactor
y
INFO: Starting up HazelcastLocalCacheRegionFactory
Sep 11, 2014 11:43:46 AM com.hazelcast.config.XmlConfigBuilder
INFO: Looking for hazelcast.xml config file in classpath.
Sep 11, 2014 11:43:46 AM com.hazelcast.config.XmlConfigBuilder
INFO: Using configuration file file:/C:/Apache/Apacheconfig17/webapps/dhis/WEB-I
NF/lib/dhis-support-hibernate-2.16.jar!/hazelcast.xml in the classpath.
Sep 11, 2014 11:43:46 AM com.hazelcast.instance.DefaultAddressPicker
INFO: null [dev] [3.2.1] Interfaces is enabled, trying to pick one address match
ing to one of: [127.0.0.1]
Sep 11, 2014 11:43:46 AM com.hazelcast.instance.DefaultAddressPicker
INFO: null [dev] [3.2.1] Picked Address[127.0.0.1]:5701, using socket ServerSock
et[addr=/127.0.0.1,localport=5701], bind any local is false
Sep 11, 2014 11:43:46 AM com.hazelcast.system
INFO: [127.0.0.1]:5701 [dev] [3.2.1] Hazelcast Community Edition 3.2.1 (20140428
) starting at Address[127.0.0.1]:5701
Sep 11, 2014 11:43:46 AM com.hazelcast.system
INFO: [127.0.0.1]:5701 [dev] [3.2.1] Copyright (C) 2008-2014 Hazelcast.com
Sep 11, 2014 11:43:46 AM com.hazelcast.core.LifecycleService
INFO: [127.0.0.1]:5701 [dev] [3.2.1] Address[127.0.0.1]:5701 is STARTING
Sep 11, 2014 11:43:47 AM com.hazelcast.instance.Node
WARNING: [127.0.0.1]:5701 [dev] [3.2.1] No join method is enabled! Starting stan
dalone.
Sep 11, 2014 11:43:47 AM com.hazelcast.core.LifecycleService
INFO: [127.0.0.1]:5701 [dev] [3.2.1] Address[127.0.0.1]:5701 is STARTED
* ERROR 2014-09-11 11:44:13,572 HHH000388: Unsuccessful: alter table completedat
asetregistration add index fk_completedatasetregistration_attributeoptioncomboid
 (attributeoptioncomboid), add constraint fk_completedatasetregistration_attribu
teoptioncomboid foreign key (attributeoptioncomboid) references categoryoptionco
mbo (categoryoptioncomboid) (SchemaUpdate.java [http-apr-8084-exec-9])
* ERROR 2014-09-11 11:44:13,578 Cannot add or update a child row: a foreign key
constraint fails (`dhisrag4_9`.`#sql-9fc_33c`, CONSTRAINT `fk_completedatasetreg
istration_attributeoptioncomboid` FOREIGN KEY (`attributeoptioncomboid`) REFEREN
CES `categoryoptioncombo` (`categoryoptioncomboid`) (SchemaUpdate.java [http-apr
-8084-exec-9])
* ERROR 2014-09-11 11:44:18,326 HHH000388: Unsuccessful: alter table eventchart
add index fk_report_relativeperiodsid (relativeperiodsid), add constraint fk_rep
ort_relativeperiodsid foreign key (relativeperiodsid) references relativeperiods
 (relativeperiodsid) (SchemaUpdate.java [http-apr-8084-exec-9])
* ERROR 2014-09-11 11:44:18,329 Can't create table 'dhisrag4_9.#sql-9fc_33c' (er
rno: 121) (SchemaUpdate.java [http-apr-8084-exec-9])
* ERROR 2014-09-11 11:44:28,632 HHH000388: Unsuccessful: alter table eventreport
 add index fk_report_relativeperiodsid (relativeperiodsid), add constraint fk_re
port_relativeperiodsid foreign key (relativeperiodsid) references relativeperiod
s (relativeperiodsid) (SchemaUpdate.java [http-apr-8084-exec-9])
* ERROR 2014-09-11 11:44:28,636 Can't create table 'dhisrag4_9.#sql-9fc_33c' (er
rno: 121) (SchemaUpdate.java [http-apr-8084-exec-9])
* ERROR 2014-09-11 11:44:29,473 HHH000388: Unsuccessful: alter table indicator a
dd index fk_indicator_legendset (legendsetid), add constraint fk_indicator_legen
dset foreign key (legendsetid) references maplegendset (maplegendsetid) (SchemaU
pdate.java [http-apr-8084-exec-9])
* ERROR 2014-09-11 11:44:29,478 Can't create table 'dhisrag4_9.#sql-9fc_33c' (er
rno: 121) (SchemaUpdate.java [http-apr-8084-exec-9])
* ERROR 2014-09-11 11:44:29,645 HHH000388: Unsuccessful: alter table interpretat
ioncomment add index fk_interpretation_userid (userid), add constraint fk_interp
retation_userid foreign key (userid) references userinfo (userinfoid) (SchemaUpd
ate.java [http-apr-8084-exec-9])
* ERROR 2014-09-11 11:44:29,647 Can't create table 'dhisrag4_9.#sql-9fc_33c' (er
rno: 121) (SchemaUpdate.java [http-apr-8084-exec-9])
* ERROR 2014-09-11 11:44:29,795 HHH000388: Unsuccessful: alter table orgunitgrou
p add index fk_indicator_userid (userid), add constraint fk_indicator_userid for
eign key (userid) references userinfo (userinfoid) (SchemaUpdate.java [http-apr-
8084-exec-9])
* ERROR 2014-09-11 11:44:29,799 Can't create table 'dhisrag4_9.#sql-9fc_33c' (er
rno: 121) (SchemaUpdate.java [http-apr-8084-exec-9])
* ERROR 2014-09-11 11:44:31,735 HHH000388: Unsuccessful: alter table report add
index fk_report_relativeperiodsid (relativeperiodsid), add constraint fk_report_
relativeperiodsid foreign key (relativeperiodsid) references relativeperiods (re
lativeperiodsid) (SchemaUpdate.java [http-apr-8084-exec-9])
* ERROR 2014-09-11 11:44:31,740 Can't create table 'dhisrag4_9.#sql-9fc_33c' (er
rno: 121) (SchemaUpdate.java [http-apr-8084-exec-9])
* ERROR 2014-09-11 11:44:31,883 HHH000388: Unsuccessful: alter table reporttable
 add index fk_report_relativeperiodsid (relativeperiodsid), add constraint fk_re
port_relativeperiodsid foreign key (relativeperiodsid) references relativeperiod
s (relativeperiodsid) (SchemaUpdate.java [http-apr-8084-exec-9])
* ERROR 2014-09-11 11:44:31,885 Can't create table 'dhisrag4_9.#sql-9fc_33c' (er
rno: 121) (SchemaUpdate.java [http-apr-8084-exec-9])
* ERROR 2014-09-11 11:44:32,062 HHH000388: Unsuccessful: alter table trackedenti
tyattributevalue add index fk_attributevalue_attributeid (trackedentityattribute
id), add constraint fk_attributevalue_attributeid foreign key (trackedentityattr
ibuteid) references trackedentityattribute (trackedentityattributeid) (SchemaUpd
ate.java [http-apr-8084-exec-9])
* ERROR 2014-09-11 11:44:32,066 Can't create table 'dhisrag4_9.#sql-9fc_33c' (er
rno: 121) (SchemaUpdate.java [http-apr-8084-exec-9])
* ERROR 2014-09-11 11:44:32,540 HHH000388: Unsuccessful: alter table usergroupme
mbers add index fk_usergroup_userid (userid), add constraint fk_usergroup_userid
 foreign key (userid) references userinfo (userinfoid) (SchemaUpdate.java [http-
apr-8084-exec-9])
* ERROR 2014-09-11 11:44:32,541 Can't create table 'dhisrag4_9.#sql-9fc_33c' (er
rno: 121) (SchemaUpdate.java [http-apr-8084-exec-9])
* INFO  2014-09-11 11:44:38,165 Found the following message senders: [org.hisp.d
his.message.EmailMessageSender@41ed2, org.hisp.dhis.sms.SmsMessageSender@83794f]
 (DefaultMessageService.java [http-apr-8084-exec-9])
* INFO  2014-09-11 11:44:59,150 Detected apps: [] (DefaultAppManager.java [http-
apr-8084-exec-9])
* INFO  2014-09-11 11:45:01,805 Scheduled task with key: 0 0 0 ? * 0 and cron: 0
 0 0 ? * 0 (SpringScheduler.java [http-apr-8084-exec-9])
* INFO  2014-09-11 11:45:01,807 Scheduled task with key: 0 0 0 ? * 1-6 and cron:
 0 0 0 ? * 1-6 (SpringScheduler.java [http-apr-8084-exec-9])
* WARN  2014-09-11 11:45:03,740 RP discovery / realm validation disabled;  (Real
mVerifier.java [http-apr-8084-exec-9])
* INFO  2014-09-11 11:45:06,487 Executing startup routine [1 of 14, runlevel 1]:
 TableAlteror (DefaultStartupRoutineExecutor.java [http-apr-8084-exec-9])
* INFO  2014-09-11 11:45:06,728 Executing startup routine [2 of 14, runlevel 2]:
 PeriodTypePopulator (DefaultStartupRoutineExecutor.java [http-apr-8084-exec-9])

* INFO  2014-09-11 11:45:06,733 Executing startup routine [3 of 14, runlevel 2]:
 DataElementDefaultDimensionPopulator (DefaultStartupRoutineExecutor.java [http-
apr-8084-exec-9])
* INFO  2014-09-11 11:45:06,922 Linked default category with default concept (Da
taElementDefaultDimensionPopulator.java [http-apr-8084-exec-9])
* INFO  2014-09-11 11:45:07,136 Executing startup routine [4 of 14, runlevel 3]:
 TrackerIdentityPopulator (DefaultStartupRoutineExecutor.java [http-apr-8084-exe
c-9])
* INFO  2014-09-11 11:45:08,906 Executing startup routine [5 of 14, runlevel 3]:
 ReportingIdentityPopulator (DefaultStartupRoutineExecutor.java [http-apr-8084-e
xec-9])
* INFO  2014-09-11 11:45:09,844 Executing startup routine [6 of 14, runlevel 3]:
 TableAlteror (DefaultStartupRoutineExecutor.java [http-apr-8084-exec-9])
* INFO  2014-09-11 11:47:22,383 Data value table upgraded with attributeoptionco
mboid column (TableAlteror.java [http-apr-8084-exec-9])
* INFO  2014-09-11 11:47:44,389 Complete data set registration table upgraded wi
th attributeoptioncomboid column (TableAlteror.java [http-apr-8084-exec-9])
* INFO  2014-09-11 11:48:06,273 Tables updated (TableAlteror.java [http-apr-8084
-exec-9])
* INFO  2014-09-11 11:48:06,277 Executing startup routine [7 of 14, runlevel 4]:
 TrackedEntityTableAlteror (DefaultStartupRoutineExecutor.java [http-apr-8084-ex
ec-9])
* INFO  2014-09-11 11:48:08,752 Executing startup routine [8 of 14, runlevel 4]:
 IdentityPopulator (DefaultStartupRoutineExecutor.java [http-apr-8084-exec-9])
* INFO  2014-09-11 11:48:12,080 Executing startup routine [9 of 14, runlevel 5]:
 TableCreator (DefaultStartupRoutineExecutor.java [http-apr-8084-exec-9])
* INFO  2014-09-11 11:48:12,108 Executing startup routine [10 of 14, runlevel 7]
: MapViewUpgrader (DefaultStartupRoutineExecutor.java [http-apr-8084-exec-9])
* INFO  2014-09-11 11:48:12,178 Executing startup routine [11 of 14, runlevel 7]
: DataEntryFormUpgrader (DefaultStartupRoutineExecutor.java [http-apr-8084-exec-
9])
* INFO  2014-09-11 11:48:12,186 Upgraded custom case entry form identifiers (Dat
aEntryFormUpgrader.java [http-apr-8084-exec-9])
* INFO  2014-09-11 11:48:12,188 Executing startup routine [12 of 14, runlevel 8]
: ExpressionUpgrader (DefaultStartupRoutineExecutor.java [http-apr-8084-exec-9])

* INFO  2014-09-11 11:48:12,216 Executing startup routine [13 of 14, runlevel 9]
: ConfigurationPopulator (DefaultStartupRoutineExecutor.java [http-apr-8084-exec
-9])
* INFO  2014-09-11 11:48:12,222 Executing startup routine [14 of 14, runlevel 9]
: I18nLocalePopulator (DefaultStartupRoutineExecutor.java [http-apr-8084-exec-9]
)
* INFO  2014-09-11 11:48:12,231 All startup routines done (DefaultStartupRoutine
Executor.java [http-apr-8084-exec-9])
* WARN  2014-09-11 11:48:19,628 Did not find any ViewResolvers to delegate to; p
lease configure them using the 'viewResolvers' property on the ContentNegotiatin
gViewResolver (ContentNegotiatingViewResolver.java [http-apr-8084-exec-9])
* WARN  2014-09-11 11:48:20,921 Did not find any ViewResolvers to delegate to; p
lease configure them using the 'viewResolvers' property on the ContentNegotiatin
gViewResolver (ContentNegotiatingViewResolver.java [http-apr-8084-exec-9])
* WARN  2014-09-11 11:53:07,525 Ignoring action package with no actions: struts-
default (DefaultModuleManager.java [http-apr-8084-exec-10])
* WARN  2014-09-11 11:53:07,526 Ignoring action package with no actions: spring-
default (DefaultModuleManager.java [http-apr-8084-exec-10])
* WARN  2014-09-11 11:53:07,526 Ignoring action package with no actions: dhis-we
b-commons (DefaultModuleManager.java [http-apr-8084-exec-10])
* WARN  2014-09-11 11:53:07,527 Ignoring action package with no actions: dhis-we
b-ohie (DefaultModuleManager.java [http-apr-8084-exec-10])
* WARN  2014-09-11 11:53:07,527 Ignoring action package with no actions: dhis-we
b-portal (DefaultModuleManager.java [http-apr-8084-exec-10])
* INFO  2014-09-11 11:53:07,528 Menu modules detected: [[Name: dhis-web-maintena
nce-appmanager, namespace: /dhis-web-maintenance-appmanager, default action: ../
dhis-web-maintenance-appmanager/index.action], [Name: dhis-web-maintenance-dataa
dmin, namespace: /dhis-web-maintenance-dataadmin, default action: ../dhis-web-ma
intenance-dataadmin/index.action], [Name: dhis-web-maintenance-datadictionary, n
amespace: /dhis-web-maintenance-datadictionary, default action: ../dhis-web-main
tenance-datadictionary/index.action], [Name: dhis-web-maintenance-dataset, names
pace: /dhis-web-maintenance-dataset, default action: ../dhis-web-maintenance-dat
aset/index.action], [Name: dhis-web-maintenance-mobile, namespace: /dhis-web-mai
ntenance-mobile, default action: ../dhis-web-maintenance-mobile/index.action], [
Name: dhis-web-maintenance-organisationunit, namespace: /dhis-web-maintenance-or
ganisationunit, default action: ../dhis-web-maintenance-organisationunit/index.a
ction], [Name: dhis-web-maintenance-program, namespace: /dhis-web-maintenance-pr
ogram, default action: ../dhis-web-maintenance-program/index.action], [Name: dhi
s-web-maintenance-settings, namespace: /dhis-web-maintenance-settings, default a
ction: ../dhis-web-maintenance-settings/index.action], [Name: dhis-web-maintenan
ce-user, namespace: /dhis-web-maintenance-user, default action: ../dhis-web-main
tenance-user/index.action], [Name: dhis-web-dashboard-integration, namespace: /d
his-web-dashboard-integration, default action: ../dhis-web-dashboard-integration
/index.action], [Name: dhis-web-dataentry, namespace: /dhis-web-dataentry, defau
lt action: ../dhis-web-dataentry/index.action], [Name: dhis-web-validationrule,
namespace: /dhis-web-validationrule, default action: ../dhis-web-validationrule/
index.action], [Name: dhis-web-visualizer, namespace: /dhis-web-visualizer, defa
ult action: ../dhis-web-visualizer/index.action], [Name: dhis-web-mapping, names
pace: /dhis-web-mapping, default action: ../dhis-web-mapping/index.action], [Nam
e: dhis-web-importexport, namespace: /dhis-web-importexport, default action: ../
dhis-web-importexport/index.action], [Name: dhis-web-caseentry, namespace: /dhis
-web-caseentry, default action: ../dhis-web-caseentry/index.action], [Name: dhis
-web-light, namespace: /light, default action: ../light/index.action], [Name: dh
is-web-mobile, namespace: /mobile, default action: ../mobile/index.action], [Nam
e: dhis-web-pivot, namespace: /dhis-web-pivot, default action: ../dhis-web-pivot
/index.action], [Name: dhis-web-reporting, namespace: /dhis-web-reporting, defau
lt action: ../dhis-web-reporting/index.action], [Name: dhis-web-sms, namespace:
/sms, default action: ../sms/index.action], [Name: dhis-web-event-capture, names
pace: /dhis-web-event-capture, default action: ../dhis-web-event-capture/index.a
ction], [Name: dhis-web-event-reports, namespace: /dhis-web-event-reports, defau
lt action: ../dhis-web-event-reports/index.action], [Name: dhis-web-event-visual
izer, namespace: /dhis-web-event-visualizer, default action: ../dhis-web-event-v
isualizer/index.action], [Name: dhis-web-tracker-capture, namespace: /dhis-web-t
racker-capture, default action: ../dhis-web-tracker-capture/index.action]] (Defa
ultModuleManager.java [http-apr-8084-exec-10])
* INFO  2014-09-11 11:53:10,166 Login success for user: 'admin', ip: '127.0.0.1'
 (DefaultUserAuditService.java [http-apr-8084-exec-2])