← Back to team overview

dhis2-users team mailing list archive

Re: [Dhis2-devs] FW: Problem with Tracker program after updating to 2.20 stable

 

Hi Halvdan,

 

No the analytics is still failing. Here is the log.

 

I don’t quite understand what is wrong. What I can see in the database is because analytics is failing the table Analytics_2015_temp remains after the analytics fails.

 

Regards,

 

Elmarie Claasen

Hisp logo

Project Manager

Health Information Systems Program

Tel:      041-367 1027

Cell:     082 374 2209

E-mail:  <mailto:elmarie@xxxxxxxx> elmarie@xxxxxxxx

Skype:  elmarie.claasen52 

 

 

This message and any attachments are subject to a disclaimer published at  <http://www.hisp.org/policies.html#comms_disclaimer> http://www.hisp.org/policies.html#comms_disclaimer .   Please read the disclaimer before opening any attachment or taking any other action in terms of this electronic transmission. 
If you cannot access the disclaimer, kindly send an email to  <mailto:disclaimer@xxxxxxxx> disclaimer@xxxxxxxx and a copy will be provided to you. By replying to this e-mail or opening any attachment you agree to be bound by the provisions of the disclaimer. 

 

From: Halvdan Grelland [mailto:halvdanhg@xxxxxxxxx] 
Sent: 30 July 2015 03:32 PM
To: Elmarie Claasen
Cc: Abyot Gizaw; DHIS 2 Developers list; dhis2-users@xxxxxxxxxxxxxxxxxxx
Subject: Re: [Dhis2-devs] [Dhis2-users] FW: Problem with Tracker program after updating to 2.20 stable

 

Hi,

 

Yes, the first issue seems to be fixed (and there is no indication of faults in the log as you say). Why it didn't run properly before I don't know.

 

As for the analytics: yes, analytics tables (named analytics_2014, analytics_2015 etc) er generated tables. If they do not exist, you might experience expections like the one posted. Analytics can be scheduled (recommended) to run regularly or triggered manually at https://your-dhis-server/dhis-web-reporting/displayDataMartForm.action (the process will take a lot of time to finish, depending on the server and the DB contents).

 

Try to run an analytics update and try again. It will probably solve the issue.

 

2015-07-30 15:17 GMT+02:00 Elmarie Claasen <elmarie@xxxxxxxx>:

Hi Halvdan,

 

I don’t see anything failing in the startup routine – here is the log of the first startup after the replaced the war file. Maybe the log should give an error if the tables was not successfully updated?

 

Can you assist in how we fix the problem with the analytics table which still has an incorrect fieldname – “de” instead of “dx”.  The issue with the Program was resolved after running the script Abyot suggested but the analytics table is a temp table created during the analytics process or something since our db does not contain that table?

 

015-07-29 08:31:56,705 Executing startup routine [1 of 15, runlevel 1]: InitTableAlteror (DefaultStartupRoutineExecutor.java [localhost-startStop-1])

* INFO  2015-07-29 08:31:56,906 Executing startup routine [2 of 15, runlevel 2]: PeriodTypePopulator (DefaultStartupRoutineExecutor.java [localhost-startStop-1])

* INFO  2015-07-29 08:31:56,911 Executing startup routine [3 of 15, runlevel 2]: DataElementDefaultDimensionPopulator (DefaultStartupRoutineExecutor.java [localhost-startStop-1])

* INFO  2015-07-29 08:31:56,961 Linked default category with default concept (DataElementDefaultDimensionPopulator.java [localhost-startStop-1])

* INFO  2015-07-29 08:31:57,171 Executing startup routine [4 of 15, runlevel 3]: TableAlteror (DefaultStartupRoutineExecutor.java [localhost-startStop-1])

* INFO  2015-07-29 08:32:01,337 Tables updated (TableAlteror.java [localhost-startStop-1])

* INFO  2015-07-29 08:32:01,337 Executing startup routine [5 of 15, runlevel 3]: TrackerIdentityPopulator (DefaultStartupRoutineExecutor.java [localhost-startStop-1])

* INFO  2015-07-29 08:32:01,704 Executing startup routine [6 of 15, runlevel 3]: ReportingIdentityPopulator (DefaultStartupRoutineExecutor.java [localhost-startStop-1])

* INFO  2015-07-29 08:32:01,798 Executing startup routine [7 of 15, runlevel 4]: TrackedEntityTableAlteror (DefaultStartupRoutineExecutor.java [localhost-startStop-1])

* INFO  2015-07-29 08:32:05,640 Executing startup routine [8 of 15, runlevel 4]: IdentityPopulator (DefaultStartupRoutineExecutor.java [localhost-startStop-1])

* INFO  2015-07-29 08:32:05,816 Executing startup routine [9 of 15, runlevel 5]: TableCreator (DefaultStartupRoutineExecutor.java [localhost-startStop-1])

* INFO  2015-07-29 08:32:05,880 Executing startup routine [10 of 15, runlevel 7]: MapViewUpgrader (DefaultStartupRoutineExecutor.java [localhost-startStop-1])

* INFO  2015-07-29 08:32:05,973 Executing startup routine [11 of 15, runlevel 7]: FavoriteDataItemUpgrader (DefaultStartupRoutineExecutor.java [localhost-startStop-1])

* INFO  2015-07-29 08:32:06,032 Update done, dropped table reporttable_indicators (FavoriteDataItemUpgrader.java [localhost-startStop-1])

* INFO  2015-07-29 08:32:06,041 Update done, dropped table reporttable_dataelements (FavoriteDataItemUpgrader.java [localhost-startStop-1])

* INFO  2015-07-29 08:32:06,045 Update done, dropped table reporttable_dataelementoperands (FavoriteDataItemUpgrader.java [localhost-startStop-1])

* INFO  2015-07-29 08:32:06,049 Update done, dropped table reporttable_datasets (FavoriteDataItemUpgrader.java [localhost-startStop-1])

* INFO  2015-07-29 08:32:06,057 Update done, dropped table chart_indicators (FavoriteDataItemUpgrader.java [localhost-startStop-1])

* INFO  2015-07-29 08:32:06,061 Update done, dropped table chart_dataelements (FavoriteDataItemUpgrader.java [localhost-startStop-1])

* INFO  2015-07-29 08:32:06,065 Update done, dropped table chart_dataelementoperands (FavoriteDataItemUpgrader.java [localhost-startStop-1])

* INFO  2015-07-29 08:32:06,069 Update done, dropped table chart_datasets (FavoriteDataItemUpgrader.java [localhost-startStop-1])

* INFO  2015-07-29 08:32:06,073 Update done, dropped table mapview_indicators (FavoriteDataItemUpgrader.java [localhost-startStop-1])

* INFO  2015-07-29 08:32:06,076 Update done, dropped table mapview_dataelements (FavoriteDataItemUpgrader.java [localhost-startStop-1])

* INFO  2015-07-29 08:32:06,080 Update done, dropped table mapview_dataelementoperands (FavoriteDataItemUpgrader.java [localhost-startStop-1])

* INFO  2015-07-29 08:32:06,083 Update done, dropped table mapview_datasets (FavoriteDataItemUpgrader.java [localhost-startStop-1])

* INFO  2015-07-29 08:32:06,189 Executing startup routine [12 of 15, runlevel 7]: FavoriteDataItemUpgrader (DefaultStartupRoutineExecutor.java [localhost-startStop-1])

* INFO  2015-07-29 08:32:06,191 Upgraded custom case entry form identifiers (DataEntryFormUpgrader.java [localhost-startStop-1])

* INFO  2015-07-29 08:32:06,193 Executing startup routine [13 of 15, runlevel 8]: ExpressionUpgrader (DefaultStartupRoutineExecutor.java [localhost-startStop-1])

* INFO  2015-07-29 08:32:06,214 Executing startup routine [14 of 15, runlevel 9]: ConfigurationPopulator (DefaultStartupRoutineExecutor.java [localhost-startStop-1])

* INFO  2015-07-29 08:32:06,218 Executing startup routine [15 of 15, runlevel 9]: I18nLocalePopulator (DefaultStartupRoutineExecutor.java [localhost-startStop-1])

* INFO  2015-07-29 08:32:06,228 All startup routines done (DefaultStartupRoutineExecutor.java [localhost-startStop-1])

Jul 29, 2015 8:32:12 AM org.apache.catalina.startup.HostConfig deployWAR

INFO: Deployment of web application archive /home/hisp/tomcat8-dhis2-2/webapps/staging.war has finished in 53,298 ms

Jul 29, 2015 8:32:12 AM org.apache.coyote.AbstractProtocol start

INFO: Starting ProtocolHandler ["http-nio-8081"]

Jul 29, 2015 8:32:12 AM org.apache.catalina.startup.Catalina start

INFO: Server startup in 53351 ms

 

Regards,

 

Elmarie Claasen

Hisp logo

Project Manager

Health Information Systems Program

Tel:      041-367 1027

Cell:     082 374 2209

E-mail:  <mailto:elmarie@xxxxxxxx> elmarie@xxxxxxxx

Skype:  elmarie.claasen52 

 

 

This message and any attachments are subject to a disclaimer published at  <http://www.hisp.org/policies.html#comms_disclaimer> http://www.hisp.org/policies.html#comms_disclaimer .   Please read the disclaimer before opening any attachment or taking any other action in terms of this electronic transmission. 
If you cannot access the disclaimer, kindly send an email to  <mailto:disclaimer@xxxxxxxx> disclaimer@xxxxxxxx and a copy will be provided to you. By replying to this e-mail or opening any attachment you agree to be bound by the provisions of the disclaimer. 

 

From: Halvdan Grelland [mailto:halvdanhg@xxxxxxxxx] 
Sent: 30 July 2015 02:33 PM
To: Abyot Gizaw
Cc: Elmarie Claasen; DHIS 2 Developers list; dhis2-users@xxxxxxxxxxxxxxxxxxx
Subject: Re: [Dhis2-devs] [Dhis2-users] FW: Problem with Tracker program after updating to 2.20 stable

 

In addition; please look at the startup log of dhis2 (specifically under InitTableAlterator) and see if there's any queries failing.

 

2015-07-30 12:59 GMT+02:00 Abyot Gizaw <abyota@xxxxxxxxx>:

 

On Thu, Jul 30, 2015 at 11:30 AM Elmarie Claasen <elmarie@xxxxxxxx> wrote:

Hi Devs,

 

I have an urgent database issue which I have to resolve as this project is implementing by Monday and my database is not running on 2.20 stable which I need for the tracker capture app to load. Can someone please assist.

 

We have a database called ISHP which Erling has been helping us to set up the Tracker capture app for implementation. 

Since the app could only connect to 2.20 we upgraded our training database some time ago to 2.20 snapshot build revision 19527 and also kept the program changes we made updated in 2.19 live version since we couldn’t risk updating a live database when we did not know what the implications would be. 

 

We now wanting to update the 2.19 Live database to 2.20 which we did on our development instance but get the following errors which does not allow us to 

 

1.      access the program in the UI or via the tracker capture app (see open program error log) The problem seems to be that the programtype in our database is Integer while in other 2.20 instances it is char varying. The update script from 2.19>2.20 does not contain anything changing this so how do I deal with this please…

 

This was supposed to be handled internally in the code.

 

try to alter manually and see what happens

 

ALTER TABLE program ALTER COLUMN "type" TYPE varchar(255);

 

 

2.      analytics is failing due to fieldname error   (see detailed analytics error log attached but here is a short version) The problem is that the temp table has a fieldname called de instead of dx – again something I don’t know how it happens or how to fix

 

Caused by: org.springframework.jdbc.BadSqlGrammarException: StatementCallback; bad SQL grammar [update analytics_temp_2015 set "uidlevel1" = null,"uidlevel2" = null,"uidlevel3" = null,"uidlevel4" = null,"uidlevel5" = null where level > 5 and de in ('jx6lCxe8gYy', 'cjA6JH0kC9B')]; nested exception is org.postgresql.util.PSQLException: ERROR: column "de" does not exist

We checked our other 2.20 databases and the column name is dx but we don’t know how to fix this – please help!

 

The program we are accessing on 2.19, 2.20 stable and 2.20 snapshot Version: Build revision: 19527 Build date: 2015-07-01 08:47 is all set up exactly the same way and I don’t see why one would be able to open the program, open tracker capture in the UI and log in on tracker capture app only on the 2.20 snapshot version and not on 2.20 stable. Can you please assist us with what is wrong?

 

 

Regards,

 

Elmarie Claasen

Hisp logo

Project Manager

Health Information Systems Program

Tel:      041-367 1027

Cell:     082 374 2209

E-mail:  <mailto:elmarie@xxxxxxxx> elmarie@xxxxxxxx

Skype:  elmarie.claasen52 

 

 

This message and any attachments are subject to a disclaimer published at  <http://www.hisp.org/policies.html#comms_disclaimer> http://www.hisp.org/policies.html#comms_disclaimer .   Please read the disclaimer before opening any attachment or taking any other action in terms of this electronic transmission. 
If you cannot access the disclaimer, kindly send an email to  <mailto:disclaimer@xxxxxxxx> disclaimer@xxxxxxxx and a copy will be provided to you. By replying to this e-mail or opening any attachment you agree to be bound by the provisions of the disclaimer. 

 

 

This message and any attachments are subject to a disclaimer published at http://www.hisp.org/policies.html#comms_disclaimer.  Please read the disclaimer before opening any attachment or taking any other action in terms of this electronic transmission.  If you cannot access the disclaimer, kindly send an email to disclaimer@xxxxxxxx and a copy will be provided to you. By replying to this e-mail or opening any attachment you agree to be bound by the provisions of the disclaimer.

_______________________________________________
Mailing list: https://launchpad.net/~dhis2-users
Post to     : dhis2-users@xxxxxxxxxxxxxxxxxxx
Unsubscribe : https://launchpad.net/~dhis2-users
More help   : https://help.launchpad.net/ListHelp

-- 

----

Thank you,

Abyot


_______________________________________________
Mailing list: https://launchpad.net/~dhis2-devs
Post to     : dhis2-devs@xxxxxxxxxxxxxxxxxxx
Unsubscribe : https://launchpad.net/~dhis2-devs
More help   : https://help.launchpad.net/ListHelp

 

 

This message and any attachments are subject to a disclaimer published at http://www.hisp.org/policies.html#comms_disclaimer.  Please read the disclaimer before opening any attachment or taking any other action in terms of this electronic transmission.  If you cannot access the disclaimer, kindly send an email to disclaimer@xxxxxxxx and a copy will be provided to you. By replying to this e-mail or opening any attachment you agree to be bound by the provisions of the disclaimer.

 


-- 


*This message and any attachments are subject to a disclaimer published at 
http://www.hisp.org/policies.html#comms_disclaimer 
<http://www.hisp.org/policies.html#comms_disclaimer>.  Please read the 
disclaimer before opening any attachment or taking any other action in 
terms of this electronic transmission.  If you cannot access the 
disclaimer, kindly send an email to disclaimer@xxxxxxxx 
<disclaimer@xxxxxxxx> and a copy will be provided to you. By replying to 
this e-mail or opening any attachment you agree to be bound by the 
provisions of the disclaimer.*

PNG image

INFO  2015-07-30 15:41:55,257 'Elmarie_Claasen': Cleared analytics tables (PerformMaintenanceAction.java [http-nio-8081-exec-9])
* INFO  2015-07-30 15:42:22,578 [Level: INFO, category: DATAMART, time: Thu Jul 30 15:42:22 SAST 2015, message: Analytics table update process started] (InMemoryNotifier.java [taskScheduler-1])
* INFO  2015-07-30 15:42:22,579 [Level: INFO, category: DATAMART, time: Thu Jul 30 15:42:22 SAST 2015, message: Updating resource tables] (InMemoryNotifier.java [taskScheduler-1])
* INFO  2015-07-30 15:42:22,626 Using 5 organisation unit levels for org unit structure table (DefaultResourceTableService.java [taskScheduler-1])
* INFO  2015-07-30 15:42:22,641 Create organisation unit structure table SQL: CREATE TABLE _orgunitstructure ( organisationunitid INTEGER NOT NULL PRIMARY KEY, organisationunituid CHARACTER(11), level INTEGER, "idlevel1" INTEGER, "uidlevel1" CHARACTER(11), "idlevel2" INTEGER, "uidlevel2" CHARACTER(11), "idlevel3" INTEGER, "uidlevel3" CHARACTER(11), "idlevel4" INTEGER, "uidlevel4" CHARACTER(11), "idlevel5" INTEGER, "uidlevel5" CHARACTER(11)); (JdbcResourceTableStore.java [taskScheduler-1])
* INFO  2015-07-30 15:42:24,723 Organisation unit structure table generated (DefaultResourceTableService.java [taskScheduler-1])
* INFO  2015-07-30 15:42:24,857 Create category option combo name table SQL: CREATE TABLE _categoryoptioncomboname ( categoryoptioncomboid INTEGER NOT NULL PRIMARY KEY, categoryoptioncomboname VARCHAR(250), approvallevel INTEGER ) (JdbcResourceTableStore.java [taskScheduler-1])
* INFO  2015-07-30 15:42:24,917 Category option combo name table generated (DefaultResourceTableService.java [taskScheduler-1])
* INFO  2015-07-30 15:42:24,965 Create category option group set table SQL: CREATE TABLE _categoryoptiongroupsetstructure ( categoryoptioncomboid INTEGER NOT NULL, PRIMARY KEY ( categoryoptioncomboid ) ) (JdbcResourceTableStore.java [taskScheduler-1])
* INFO  2015-07-30 15:42:24,968 Category option group set table generated (DefaultResourceTableService.java [taskScheduler-1])
* INFO  2015-07-30 15:42:24,977 Create data element group set table SQL: CREATE TABLE _dataelementgroupsetstructure ( dataelementid INTEGER NOT NULL, dataelementname VARCHAR (250), PRIMARY KEY ( dataelementid ) ) (JdbcResourceTableStore.java [taskScheduler-1])
* INFO  2015-07-30 15:42:24,981 Populate data element group set structure SQL: insert into _dataelementgroupsetstructure select d.dataelementid as dataelementid, d.name as dataelementname from dataelement d (JdbcResourceTableStore.java [taskScheduler-1])
* INFO  2015-07-30 15:42:24,982 Data element group set table generated (DefaultResourceTableService.java [taskScheduler-1])
* INFO  2015-07-30 15:42:24,995 Create indicator group set table SQL: CREATE TABLE _indicatorgroupsetstructure ( indicatorid INTEGER NOT NULL, indicatorname VARCHAR (250), PRIMARY KEY ( indicatorid ) ) (JdbcResourceTableStore.java [taskScheduler-1])
* INFO  2015-07-30 15:42:24,997 Populate indicator group set structure SQL: insert into _indicatorgroupsetstructure select i.indicatorid as indicatorid, i.name as indicatorname from indicator i (JdbcResourceTableStore.java [taskScheduler-1])
* INFO  2015-07-30 15:42:24,999 Indicator group set table generated (DefaultResourceTableService.java [taskScheduler-1])
* INFO  2015-07-30 15:42:25,015 Create organisation unit group set table SQL: CREATE TABLE _organisationunitgroupsetstructure ( organisationunitid INTEGER NOT NULL, organisationunitname VARCHAR (250), "OrgUnitOwnership" VARCHAR (250), "CkIy9PyAAsM" CHARACTER(11), "OrgUnitRuralUrban" VARCHAR (250), "nQoWiIYO3nt" CHARACTER(11), "OrgUnitType" VARCHAR (250), "BmSB4vqoDfI" CHARACTER(11), "QuintileClassification" VARCHAR (250), "IiDqF9APXUm" CHARACTER(11), PRIMARY KEY ( organisationunitid ) ) (JdbcResourceTableStore.java [taskScheduler-1])
* INFO  2015-07-30 15:42:25,018 Populate organisation unit group set structure SQL: insert into _organisationunitgroupsetstructure select ou.organisationunitid as organisationunitid, ou.name as organisationunitname, (select oug.name from orgunitgroup oug inner join orgunitgroupmembers ougm on ougm.orgunitgroupid = oug.orgunitgroupid inner join orgunitgroupsetmembers ougsm on ougsm.orgunitgroupid = ougm.orgunitgroupid and ougsm.orgunitgroupsetid = 23755 where ougm.organisationunitid = ou.organisationunitid limit 1) as "OrgUnitOwnership", (select oug.uid from orgunitgroup oug inner join orgunitgroupmembers ougm on ougm.orgunitgroupid = oug.orgunitgroupid inner join orgunitgroupsetmembers ougsm on ougsm.orgunitgroupid = ougm.orgunitgroupid and ougsm.orgunitgroupsetid = 23755 where ougm.organisationunitid = ou.organisationunitid limit 1) as "CkIy9PyAAsM", (select oug.name from orgunitgroup oug inner join orgunitgroupmembers ougm on ougm.orgunitgroupid = oug.orgunitgroupid inner join orgunitgroupsetmembers ougsm on ougsm.orgunitgroupid = ougm.orgunitgroupid and ougsm.orgunitgroupsetid = 23756 where ougm.organisationunitid = ou.organisationunitid limit 1) as "OrgUnitRuralUrban", (select oug.uid from orgunitgroup oug inner join orgunitgroupmembers ougm on ougm.orgunitgroupid = oug.orgunitgroupid inner join orgunitgroupsetmembers ougsm on ougsm.orgunitgroupid = ougm.orgunitgroupid and ougsm.orgunitgroupsetid = 23756 where ougm.organisationunitid = ou.organisationunitid limit 1) as "nQoWiIYO3nt", (select oug.name from orgunitgroup oug inner join orgunitgroupmembers ougm on ougm.orgunitgroupid = oug.orgunitgroupid inner join orgunitgroupsetmembers ougsm on ougsm.orgunitgroupid = ougm.orgunitgroupid and ougsm.orgunitgroupsetid = 23754 where ougm.organisationunitid = ou.organisationunitid limit 1) as "OrgUnitType", (select oug.uid from orgunitgroup oug inner join orgunitgroupmembers ougm on ougm.orgunitgroupid = oug.orgunitgroupid inner join orgunitgroupsetmembers ougsm on ougsm.orgunitgroupid = ougm.orgunitgroupid and ougsm.orgunitgroupsetid = 23754 where ougm.organisationunitid = ou.organisationunitid limit 1) as "BmSB4vqoDfI", (select oug.name from orgunitgroup oug inner join orgunitgroupmembers ougm on ougm.orgunitgroupid = oug.orgunitgroupid inner join orgunitgroupsetmembers ougsm on ougsm.orgunitgroupid = ougm.orgunitgroupid and ougsm.orgunitgroupsetid = 23757 where ougm.organisationunitid = ou.organisationunitid limit 1) as "QuintileClassification", (select oug.uid from orgunitgroup oug inner join orgunitgroupmembers ougm on ougm.orgunitgroupid = oug.orgunitgroupid inner join orgunitgroupsetmembers ougsm on ougsm.orgunitgroupid = ougm.orgunitgroupid and ougsm.orgunitgroupsetid = 23757 where ougm.organisationunitid = ou.organisationunitid limit 1) as "IiDqF9APXUm" from organisationunit ou (JdbcResourceTableStore.java [taskScheduler-1])
* INFO  2015-07-30 15:42:26,418 Organisation unit group set table generated (DefaultResourceTableService.java [taskScheduler-1])
* INFO  2015-07-30 15:42:26,434 Create category structure table SQL: CREATE TABLE _categorystructure ( categoryoptioncomboid INTEGER NOT NULL, categoryoptioncomboname VARCHAR (250), "Age" VARCHAR (250), "KNqu95ktPhC" CHARACTER(11), PRIMARY KEY ( categoryoptioncomboid ) ) (JdbcResourceTableStore.java [taskScheduler-1])
* INFO  2015-07-30 15:42:26,439 Populate category structure SQL: insert into _categorystructure select coc.categoryoptioncomboid as cocid, con.categoryoptioncomboname as cocname, (select co.name from categoryoptioncombos_categoryoptions cocco inner join dataelementcategoryoption co on cocco.categoryoptionid = co.categoryoptionid inner join categories_categoryoptions cco on co.categoryoptionid = cco.categoryoptionid where coc.categoryoptioncomboid = cocco.categoryoptioncomboid and cco.categoryid = 71780 limit 1) as "Age", (select co.uid from categoryoptioncombos_categoryoptions cocco inner join dataelementcategoryoption co on cocco.categoryoptionid = co.categoryoptionid inner join categories_categoryoptions cco on co.categoryoptionid = cco.categoryoptionid where coc.categoryoptioncomboid = cocco.categoryoptioncomboid and cco.categoryid = 71780 limit 1) as "KNqu95ktPhC" from categoryoptioncombo coc inner join _categoryoptioncomboname con on coc.categoryoptioncomboid = con.categoryoptioncomboid (JdbcResourceTableStore.java [taskScheduler-1])
* INFO  2015-07-30 15:42:26,441 Category table generated (DefaultResourceTableService.java [taskScheduler-1])
* INFO  2015-07-30 15:42:26,490 Create data element structure SQL: CREATE TABLE _dataelementstructure ( dataelementid INTEGER NOT NULL PRIMARY KEY, dataelementuid CHARACTER(11), dataelementname VARCHAR(250), datasetid INTEGER, datasetuid CHARACTER(11), datasetname VARCHAR(250), datasetapprovallevel INTEGER, periodtypeid INTEGER, periodtypename VARCHAR(250) ) (JdbcResourceTableStore.java [taskScheduler-1])
* INFO  2015-07-30 15:42:26,565 Data element table generated (DefaultResourceTableService.java [taskScheduler-1])
* INFO  2015-07-30 15:42:26,591 Create period structure SQL: CREATE TABLE _periodstructure (periodid INTEGER NOT NULL PRIMARY KEY, iso VARCHAR(15) NOT NULL, daysno INTEGER NOT NULL, "daily" VARCHAR(15), "weekly" VARCHAR(15), "monthly" VARCHAR(15), "bimonthly" VARCHAR(15), "quarterly" VARCHAR(15), "sixmonthly" VARCHAR(15), "sixmonthlyapril" VARCHAR(15), "yearly" VARCHAR(15), "financialapril" VARCHAR(15), "financialjuly" VARCHAR(15), "financialoct" VARCHAR(15)) (JdbcResourceTableStore.java [taskScheduler-1])
* INFO  2015-07-30 15:42:26,792 Date period table generated (DefaultResourceTableService.java [taskScheduler-1])
* INFO  2015-07-30 15:42:26,807 Create date period structure SQL: CREATE TABLE _dateperiodstructure (dateperiod DATE NOT NULL PRIMARY KEY, "daily" VARCHAR(15), "weekly" VARCHAR(15), "monthly" VARCHAR(15), "bimonthly" VARCHAR(15), "quarterly" VARCHAR(15), "sixmonthly" VARCHAR(15), "sixmonthlyapril" VARCHAR(15), "yearly" VARCHAR(15), "financialapril" VARCHAR(15), "financialjuly" VARCHAR(15), "financialoct" VARCHAR(15)) (JdbcResourceTableStore.java [taskScheduler-1])
* INFO  2015-07-30 15:42:31,095 Period table generated (DefaultResourceTableService.java [taskScheduler-1])
* INFO  2015-07-30 15:42:31,097 Create data element category option combo SQL: CREATE TABLE _dataelementcategoryoptioncombo (dataelementid INTEGER NOT NULL, dataelementuid VARCHAR(11) NOT NULL, categoryoptioncomboid INTEGER NOT NULL, categoryoptioncombouid VARCHAR(11) NOT NULL) (JdbcResourceTableStore.java [taskScheduler-1])
* INFO  2015-07-30 15:42:31,099 Insert data element category option combo SQL: insert into _dataelementcategoryoptioncombo (dataelementid, dataelementuid, categoryoptioncomboid, categoryoptioncombouid) select de.dataelementid as dataelementid, de.uid as dataelementuid, coc.categoryoptioncomboid as categoryoptioncomboid, coc.uid as categoryoptioncombouid from dataelement de join categorycombos_optioncombos cc on de.categorycomboid = cc.categorycomboid join categoryoptioncombo coc on cc.categoryoptioncomboid = coc.categoryoptioncomboid (JdbcResourceTableStore.java [taskScheduler-1])
* INFO  2015-07-30 15:42:31,100 Create data element category option combo index SQL: CREATE INDEX dataelement_categoryoptioncombo ON _dataelementcategoryoptioncombo (dataelementuid, categoryoptioncombouid) (JdbcResourceTableStore.java [taskScheduler-1])
* INFO  2015-07-30 15:42:31,101 Data element category option combo table generated (DefaultResourceTableService.java [taskScheduler-1])
* INFO  2015-07-30 15:42:31,174 [Level: INFO, category: DATAMART, time: Thu Jul 30 15:42:31 SAST 2015, message: Updating analytics tables] (InMemoryNotifier.java [taskScheduler-1])
* INFO  2015-07-30 15:42:31,199 Starting update, processes: 3, org unit levels: 5: 00:00:00.000 (Clock.java [taskScheduler-1])
* INFO  2015-07-30 15:42:31,247 Approval enabled: false (JdbcAnalyticsTableManager.java [taskScheduler-1])
* INFO  2015-07-30 15:42:31,248 Get tables using earliest: null (AbstractJdbcTableManager.java [taskScheduler-1])
* INFO  2015-07-30 15:42:31,352 Table update start: analytics, processes: 3, partitions: [analytics_2015], last years: null, earliest: null: 00:00:00.174 (Clock.java [taskScheduler-1])
* INFO  2015-07-30 15:42:31,352 [Level: INFO, category: DATAMART, time: Thu Jul 30 15:42:31 SAST 2015, message: Performing pre-create table work, processes: 3, org unit levels: 5] (InMemoryNotifier.java [taskScheduler-1])
* INFO  2015-07-30 15:42:31,354 Performed pre-create table work: 00:00:00.177 (Clock.java [taskScheduler-1])
* INFO  2015-07-30 15:42:31,354 [Level: INFO, category: DATAMART, time: Thu Jul 30 15:42:31 SAST 2015, message: Creating analytics tables] (InMemoryNotifier.java [taskScheduler-1])
* INFO  2015-07-30 15:42:31,360 Creating table: analytics_temp_2015, columns: 26 (JdbcAnalyticsTableManager.java [taskScheduler-1])
* INFO  2015-07-30 15:42:31,363 Created analytics tables: 00:00:00.186 (Clock.java [taskScheduler-1])
* INFO  2015-07-30 15:42:31,363 [Level: INFO, category: DATAMART, time: Thu Jul 30 15:42:31 SAST 2015, message: Populating analytics tables] (InMemoryNotifier.java [taskScheduler-1])
* INFO  2015-07-30 15:42:31,364 Populate table task number: 1 (DefaultAnalyticsTableService.java [taskScheduler-1])
* INFO  2015-07-30 15:42:33,522 Populated analytics_temp_2015, int: 1.95 seconds (AbstractJdbcTableManager.java [SimpleAsyncTaskExecutor-1])
* INFO  2015-07-30 15:42:33,542 Populated analytics_temp_2015, bool: 0.01 seconds (AbstractJdbcTableManager.java [SimpleAsyncTaskExecutor-1])
* INFO  2015-07-30 15:42:33,564 Populated analytics_temp_2015, bool: 0.02 seconds (AbstractJdbcTableManager.java [SimpleAsyncTaskExecutor-1])
* INFO  2015-07-30 15:42:33,579 Populated analytics_temp_2015, trueOnly: 0.01 seconds (AbstractJdbcTableManager.java [SimpleAsyncTaskExecutor-1])
* INFO  2015-07-30 15:42:33,592 Populated analytics_temp_2015, string: 0.01 seconds (AbstractJdbcTableManager.java [SimpleAsyncTaskExecutor-1])
* INFO  2015-07-30 15:42:33,593 Populated analytics tables: 00:00:02.415 (Clock.java [taskScheduler-1])
* INFO  2015-07-30 15:42:33,593 [Level: INFO, category: DATAMART, time: Thu Jul 30 15:42:33 SAST 2015, message: Applying aggregation levels] (InMemoryNotifier.java [taskScheduler-1])
* INFO  2015-07-30 15:42:33,614 [Level: ERROR, category: DATAMART, time: Thu Jul 30 15:42:33 SAST 2015, message: Process failed: Exception during execution] (InMemoryNotifier.java [taskScheduler-1])
* ERROR 2015-07-30 15:42:33,870 Unexpected error occurred in scheduled task. (TaskUtils.java [taskScheduler-1])
java.lang.RuntimeException: Exception during execution
	at org.hisp.dhis.commons.util.ConcurrentUtils.waitForCompletion(ConcurrentUtils.java:57)
	at org.hisp.dhis.analytics.table.DefaultAnalyticsTableService.applyAggregationLevels(DefaultAnalyticsTableService.java:244)
	at org.hisp.dhis.analytics.table.DefaultAnalyticsTableService.update(DefaultAnalyticsTableService.java:133)
	at org.hisp.dhis.analytics.table.scheduling.AnalyticsTableTask.run(AnalyticsTableTask.java:140)
	at org.hisp.dhis.scheduling.ScheduledTasks.run(ScheduledTasks.java:60)
	at org.springframework.scheduling.support.DelegatingErrorHandlingRunnable.run(DelegatingErrorHandlingRunnable.java:54)
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.access$201(ScheduledThreadPoolExecutor.java:180)
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:293)
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
	at java.lang.Thread.run(Thread.java:745)
Caused by: java.util.concurrent.ExecutionException: org.springframework.jdbc.BadSqlGrammarException: StatementCallback; bad SQL grammar [update analytics_temp_2015 set "uidlevel1" = null,"uidlevel2" = null,"uidlevel3" = null,"uidlevel4" = null,"uidlevel5" = null where level > 5 and de in ('jx6lCxe8gYy', 'cjA6JH0kC9B')]; nested exception is org.postgresql.util.PSQLException: ERROR: column "de" does not exist
  Position: 147
	at java.util.concurrent.FutureTask.report(FutureTask.java:122)
	at java.util.concurrent.FutureTask.get(FutureTask.java:192)
	at org.hisp.dhis.commons.util.ConcurrentUtils.waitForCompletion(ConcurrentUtils.java:53)
	... 12 more
Caused by: org.springframework.jdbc.BadSqlGrammarException: StatementCallback; bad SQL grammar [update analytics_temp_2015 set "uidlevel1" = null,"uidlevel2" = null,"uidlevel3" = null,"uidlevel4" = null,"uidlevel5" = null where level > 5 and de in ('jx6lCxe8gYy', 'cjA6JH0kC9B')]; nested exception is org.postgresql.util.PSQLException: ERROR: column "de" does not exist
  Position: 147
	at org.springframework.jdbc.support.SQLStateSQLExceptionTranslator.doTranslate(SQLStateSQLExceptionTranslator.java:99)
	at org.springframework.jdbc.support.AbstractFallbackSQLExceptionTranslator.translate(AbstractFallbackSQLExceptionTranslator.java:73)
	at org.springframework.jdbc.support.AbstractFallbackSQLExceptionTranslator.translate(AbstractFallbackSQLExceptionTranslator.java:81)
	at org.springframework.jdbc.support.AbstractFallbackSQLExceptionTranslator.translate(AbstractFallbackSQLExceptionTranslator.java:81)
	at org.springframework.jdbc.core.JdbcTemplate.execute(JdbcTemplate.java:416)
	at org.springframework.jdbc.core.JdbcTemplate.execute(JdbcTemplate.java:440)
	at org.hisp.dhis.analytics.table.JdbcAnalyticsTableManager.applyAggregationLevels(JdbcAnalyticsTableManager.java:420)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:497)
	at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)
	at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:190)
	at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:157)
	at org.springframework.aop.interceptor.AsyncExecutionInterceptor$1.call(AsyncExecutionInterceptor.java:110)
	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
	... 1 more
Caused by: org.postgresql.util.PSQLException: ERROR: column "de" does not exist
  Position: 147
	at org.postgresql.core.v3.QueryExecutorImpl.receiveErrorResponse(QueryExecutorImpl.java:2198)
	at org.postgresql.core.v3.QueryExecutorImpl.processResults(QueryExecutorImpl.java:1927)
	at org.postgresql.core.v3.QueryExecutorImpl.execute(QueryExecutorImpl.java:255)
	at org.postgresql.jdbc2.AbstractJdbc2Statement.execute(AbstractJdbc2Statement.java:561)
	at org.postgresql.jdbc2.AbstractJdbc2Statement.executeWithFlags(AbstractJdbc2Statement.java:405)
	at org.postgresql.jdbc2.AbstractJdbc2Statement.execute(AbstractJdbc2Statement.java:397)
	at com.mchange.v2.c3p0.impl.NewProxyStatement.execute(NewProxyStatement.java:1006)
	at org.springframework.jdbc.core.JdbcTemplate$1ExecuteStatementCallback.doInStatement(JdbcTemplate.java:432)
	at org.springframework.jdbc.core.JdbcTemplate.execute(JdbcTemplate.java:405)
	... 12 more

Follow ups

References