DHIS2 version 40.0.1 is now available - HOTFIX

Dear all,

DHIS2 version 40.0.1 is out as a HOTFIX release to address critical functional and performance issues in 40.0.0.

  • DHIS2-15500: Form name for TEA is not displaying on List of TEI page for a Tracker Capture Program

  • DHIS2-15471: Repeatable events validation should be skipped for event program

  • DHIS2-15468: Event Report and Event Visualizer Take default relative period for analytics

  • DHIS2-15464: d2:right and d2:left on numerical strings

  • DHIS2-15440: Event data item shows wrong result

  • DHIS2-15364: Missing options (of option set) in Analytics events/aggregate API

This is the latest stable release for version 40, and supersedes release 40.0.0

These hotfixes are provided to make it easy for you to keep your system secure and reliable; provided you keep up to date with the latest patch versions.

The release note for this patch can be found here: Patch 40.0.1 Release Note.

Thanks!

DHIS2 Release Team

Release Information Links
Release Note Patch 40.0.1 Release Note
Upgrade notes v40 Upgrade notes
Download release and sample database Downloads - DHIS2
Documentation and Javadocs Home - DHIS2 Documentation
Source code on Github tag/2.40.0.1
Demo instance DHIS 2 Demo - Sierra Leone
Docker docker pull dhis2/core:2.40.0.1
for more docker image variants see dockerhub
2 Likes

Come at the right time

2 Likes

Good progress with improved user friendly; yet the adjustment would always create continued learning.

Dear @phil Thank you for your hard work in upgrading the DHIS2 versions. I upgraded from 2.35.1 to 40.0.0. My problem was with the analytics tables failing, and I have noticed the following error in the catalina log. yesterday after I saw HOTFIX of 40.0.1 I upgraded it from 40.0.0 to 40.0.1 and the same error I found,

**Catalina log:** 
INFO  2023-07-03T00:07:33,137 Scheduler initiated execution of job: JobConfiguration{uid='BO6J9oyMfMQ', name='inMemoryAnalyticsJob', jobType=ANALYTICS_TABLE, cronExpression='', delay='null', jobParameters=org.hisp.dhis.scheduling.parameters.AnalyticsJobParameters@1a31b039, enabled=true, inMemoryJob=true, lastRuntimeExecution='null', userUid='Ij0E4tY1keN', leaderOnlyJob=false, jobStatus=SCHEDULED, nextExecutionTime=null, lastExecutedStatus=NOT_STARTED, lastExecuted=null} (DefaultSchedulingManager.java [http-nio-8081-exec-8]) IDrlsducXX5OUxr/vjkVTNaB1AVV12SXvpfLWQlLvXZ8A= 
* INFO  2023-07-03T00:07:33,138 Found 8 analytics table types: [ORG_UNIT_TARGET, COMPLETENESS, COMPLETENESS_TARGET, OWNERSHIP, DATA_VALUE, VALIDATION_RESULT, ENROLLMENT, EVENT] (DefaultAnalyticsTableGenerator.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:33,138 Analytics table update: AnalyticsTableUpdateParams{last years=null, skip resource tables=false, skip table types=[], skip programs=[], start time=2023-07-03T00:07:33} (DefaultAnalyticsTableGenerator.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:33,138 Last successful analytics table update: '2023-07-02T23:52:55' (DefaultAnalyticsTableGenerator.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:33,139 Analytics table update process (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:33,139 [ANALYTICS_TABLE BO6J9oyMfMQ] Process started: Analytics table update process (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:33,142 Drop SQL views (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:33,142 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Drop SQL views (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:33,161 13 successful and 0 failed items (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:33,162 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.02s: 13 successful and 0 failed items (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:33,162 Generating resource tables (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:33,162 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Generating resource tables (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:33,162 Generating resource table: '_orgunitstructure' (JdbcResourceTableStore.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:33,530 Resource table '_orgunitstructure' update done: '00:00:00.367' (JdbcResourceTableStore.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:33,606 Generating resource table: '_datasetorganisationunitcategory' (JdbcResourceTableStore.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:39,060 Resource table '_datasetorganisationunitcategory' update done: '00:00:05.453' (JdbcResourceTableStore.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:39,119 Generating resource table: '_categoryoptioncomboname' (JdbcResourceTableStore.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:39,138 Resource table '_categoryoptioncomboname' update done: '00:00:00.019' (JdbcResourceTableStore.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:39,143 Generating resource table: '_dataelementgroupsetstructure' (JdbcResourceTableStore.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:39,162 Resource table '_dataelementgroupsetstructure' update done: '00:00:00.018' (JdbcResourceTableStore.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:39,167 Generating resource table: '_indicatorgroupsetstructure' (JdbcResourceTableStore.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:39,175 Resource table '_indicatorgroupsetstructure' update done: '00:00:00.007' (JdbcResourceTableStore.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:39,180 Generating resource table: '_organisationunitgroupsetstructure' (JdbcResourceTableStore.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:40,403 Resource table '_organisationunitgroupsetstructure' update done: '00:00:01.223' (JdbcResourceTableStore.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:40,411 Generating resource table: '_categorystructure' (JdbcResourceTableStore.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:40,447 Resource table '_categorystructure' update done: '00:00:00.036' (JdbcResourceTableStore.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:40,521 Generating resource table: '_dataelementstructure' (JdbcResourceTableStore.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:40,903 Resource table '_dataelementstructure' update done: '00:00:00.381' (JdbcResourceTableStore.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:40,946 Generating resource table: '_periodstructure' (JdbcResourceTableStore.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:41,006 Resource table '_periodstructure' update done: '00:00:00.059' (JdbcResourceTableStore.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:41,015 Generating resource table: '_dateperiodstructure' (JdbcResourceTableStore.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:41,955 Resource table '_dateperiodstructure' update done: '00:00:00.939' (JdbcResourceTableStore.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:41,973 Generating resource table: '_dataelementcategoryoptioncombo' (JdbcResourceTableStore.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:41,992 Resource table '_dataelementcategoryoptioncombo' update done: '00:00:00.018' (JdbcResourceTableStore.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:41,995 11 successful and 0 failed items (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:41,995 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 8.833s: 11 successful and 0 failed items (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:41,996 Create SQL views (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:41,996 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Create SQL views (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* WARN  2023-07-03T00:07:42,015 Validation failed for SQL view 'HnZAdad7N37' with code: 'E4301' and message: 'SQL query must be a select query' (DefaultSqlViewService.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* WARN  2023-07-03T00:07:42,015 Ignoring SQL view which failed validation: HnZAdad7N37, users, message: SQL query must be a select query (DefaultResourceTableService.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:42,019 13 successful and 0 failed items (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:42,019 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.023s: 13 successful and 0 failed items (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:42,020 Analytics table update parameters: AnalyticsTableUpdateParams{last years=null, skip resource tables=false, skip table types=[], skip programs=[], start time=2023-07-03T00:07:33} (DefaultAnalyticsTableService.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:42,020 Starting update of type: DATA_VALUE, table name: 'analytics', processes: 7: 00:00:00.000 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:42,020 Validating Analytics Table DATA_VALUE (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:42,020 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Validating Analytics Table DATA_VALUE (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:42,022 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.002s (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:42,138 Table update start: analytics, earliest: null, parameters: AnalyticsTableUpdateParams{last years=null, skip resource tables=false, skip table types=[], skip programs=[], start time=2023-07-03T00:07:33}: 00:00:00.117 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:42,138 Performing pre-create table work (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:42,138 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Performing pre-create table work (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:42,139 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.001s (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:42,139 Performed pre-create table work DATA_VALUE: 00:00:00.118 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:42,139 Dropping temp tables (if any) DATA_VALUE (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:42,139 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Dropping temp tables (if any) DATA_VALUE (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:42,139 1 successful and 0 failed items (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:42,139 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0s: 1 successful and 0 failed items (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:42,140 Dropped temp tables: 00:00:00.119 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:42,140 Creating analytics tables DATA_VALUE (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:42,140 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Creating analytics tables DATA_VALUE (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:42,140 Creating table: 'analytics_temp', columns: '54' (AbstractJdbcTableManager.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:42,143 Creating partition table: 'analytics_temp_2008' (AbstractJdbcTableManager.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:42,146 Creating partition table: 'analytics_temp_2022' (AbstractJdbcTableManager.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:42,149 Creating partition table: 'analytics_temp_2023' (AbstractJdbcTableManager.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:42,153 1 successful and 0 failed items (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:42,153 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.013s: 1 successful and 0 failed items (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:42,153 Created analytics tables: 00:00:00.132 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:42,153 Populating analytics tables DATA_VALUE (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:42,153 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Populating analytics tables DATA_VALUE (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:42,153 Populate table task number: 3 (DefaultAnalyticsTableService.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:42,211 Populate analytics_temp_2008 [INTEGER, NUMBER, INTEGER_NEGATIVE, INTEGER_POSITIVE, PERCENTAGE, UNIT_INTERVAL, INTEGER_ZERO_OR_POSITIVE] in: 0.053726 sec. (AbstractJdbcTableManager.java [ForkJoinPool-16-worker-2])  
* INFO  2023-07-03T00:07:42,235 Populate analytics_temp_2008 [BOOLEAN, TRUE_ONLY] in: 0.018961 sec. (AbstractJdbcTableManager.java [ForkJoinPool-16-worker-2])  
* INFO  2023-07-03T00:07:42,257 Populate analytics_temp_2008 [BOOLEAN] in: 0.018928 sec. (AbstractJdbcTableManager.java [ForkJoinPool-16-worker-2])  
* INFO  2023-07-03T00:07:42,291 Populate analytics_temp_2008 [LONG_TEXT, TEXT, TIME, LETTER, URL, PHONE_NUMBER, EMAIL, USERNAME, DATETIME, AGE, DATE] in: 0.030803 sec. (AbstractJdbcTableManager.java [ForkJoinPool-16-worker-2])  
* INFO  2023-07-03T00:07:42,357 Populate analytics_temp_2022 [INTEGER, NUMBER, INTEGER_NEGATIVE, INTEGER_POSITIVE, PERCENTAGE, UNIT_INTERVAL, INTEGER_ZERO_OR_POSITIVE] in: 0.199533 sec. (AbstractJdbcTableManager.java [ForkJoinPool-16-worker-1])  
* INFO  2023-07-03T00:07:42,381 Populate analytics_temp_2022 [BOOLEAN, TRUE_ONLY] in: 0.019967 sec. (AbstractJdbcTableManager.java [ForkJoinPool-16-worker-1])  
* INFO  2023-07-03T00:07:42,409 Populate analytics_temp_2022 [BOOLEAN] in: 0.025330 sec. (AbstractJdbcTableManager.java [ForkJoinPool-16-worker-1])  
* INFO  2023-07-03T00:07:42,484 Populate analytics_temp_2022 [LONG_TEXT, TEXT, TIME, LETTER, URL, PHONE_NUMBER, EMAIL, USERNAME, DATETIME, AGE, DATE] in: 0.070950 sec. (AbstractJdbcTableManager.java [ForkJoinPool-16-worker-1])  
* INFO  2023-07-03T00:07:44,489 Populate analytics_temp_2023 [INTEGER, NUMBER, INTEGER_NEGATIVE, INTEGER_POSITIVE, PERCENTAGE, UNIT_INTERVAL, INTEGER_ZERO_OR_POSITIVE] in: 2.331961 sec. (AbstractJdbcTableManager.java [ForkJoinPool-16-worker-3])  
* INFO  2023-07-03T00:07:44,529 Populate analytics_temp_2023 [BOOLEAN, TRUE_ONLY] in: 0.034583 sec. (AbstractJdbcTableManager.java [ForkJoinPool-16-worker-3])  
* INFO  2023-07-03T00:07:44,547 Populate analytics_temp_2023 [BOOLEAN] in: 0.014667 sec. (AbstractJdbcTableManager.java [ForkJoinPool-16-worker-3])  
* INFO  2023-07-03T00:07:44,662 Populate analytics_temp_2023 [LONG_TEXT, TEXT, TIME, LETTER, URL, PHONE_NUMBER, EMAIL, USERNAME, DATETIME, AGE, DATE] in: 0.109901 sec. (AbstractJdbcTableManager.java [ForkJoinPool-16-worker-3])  
* INFO  2023-07-03T00:07:44,663 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 2.51s (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:44,663 Populated analytics tables: 00:00:02.642 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:44,663 Invoking analytics table hooks DATA_VALUE (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:44,663 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Invoking analytics table hooks DATA_VALUE (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:44,664 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.001s (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:44,664 Invoked analytics table hooks: 00:00:02.643 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:44,665 Applying aggregation level 4 DATA_VALUE (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:44,665 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Applying aggregation level 4 DATA_VALUE (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:44,761 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.096s (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:44,764 Applied aggregation levels: 00:00:02.743 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:44,764 Vacuuming tables DATA_VALUE (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:44,764 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Vacuuming tables DATA_VALUE (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:44,817 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.053s (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:44,817 Tables vacuumed: 00:00:02.796 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:44,817 Creating indexes DATA_VALUE (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:44,817 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Creating indexes DATA_VALUE (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:48,795 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 3.978s (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:48,795 Created indexes: 00:00:06.774 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:48,795 Analyzing analytics tables DATA_VALUE (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:48,795 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Analyzing analytics tables DATA_VALUE (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,282 3 successful and 0 failed items (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,282 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.487s: 3 successful and 0 failed items (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,282 Analyzed tables: 00:00:07.261 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,283 Drop SQL views (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,283 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Drop SQL views (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,298 13 successful and 0 failed items (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,298 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.015s: 13 successful and 0 failed items (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,298 Swapping analytics tables DATA_VALUE (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,299 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Swapping analytics tables DATA_VALUE (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,300 Swapping table, master table exists: 'true', skip master table: 'false' (AbstractJdbcTableManager.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,373 1 successful and 0 failed items (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,373 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.074s: 1 successful and 0 failed items (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,374 Create SQL views (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,374 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Create SQL views (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* WARN  2023-07-03T00:07:49,391 Validation failed for SQL view 'HnZAdad7N37' with code: 'E4301' and message: 'SQL query must be a select query' (DefaultSqlViewService.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* WARN  2023-07-03T00:07:49,392 Ignoring SQL view which failed validation: HnZAdad7N37, users, message: SQL query must be a select query (DefaultResourceTableService.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,395 13 successful and 0 failed items (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,396 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.022s: 13 successful and 0 failed items (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,396 Table update done: analytics: 00:00:07.375 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,397 Analytics table update parameters: AnalyticsTableUpdateParams{last years=null, skip resource tables=false, skip table types=[], skip programs=[], start time=2023-07-03T00:07:33} (DefaultAnalyticsTableService.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,397 Starting update of type: COMPLETENESS, table name: 'analytics_completeness', processes: 7: 00:00:00.000 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,397 Validating Analytics Table COMPLETENESS (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,397 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Validating Analytics Table COMPLETENESS (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,398 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.001s (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,405 Table update start: analytics_completeness, earliest: null, parameters: AnalyticsTableUpdateParams{last years=null, skip resource tables=false, skip table types=[], skip programs=[], start time=2023-07-03T00:07:33}: 00:00:00.008 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,405 Performing pre-create table work (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,405 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Performing pre-create table work (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,405 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0s (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,405 Performed pre-create table work COMPLETENESS: 00:00:00.008 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,405 Dropping temp tables (if any) COMPLETENESS (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,405 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Dropping temp tables (if any) COMPLETENESS (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,406 1 successful and 0 failed items (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,406 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.001s: 1 successful and 0 failed items (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,406 Dropped temp tables: 00:00:00.008 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,406 Creating analytics tables COMPLETENESS (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,406 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Creating analytics tables COMPLETENESS (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,406 Creating table: 'analytics_completeness_temp', columns: '38' (AbstractJdbcTableManager.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,409 Creating partition table: 'analytics_completeness_temp_2008' (AbstractJdbcTableManager.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,412 Creating partition table: 'analytics_completeness_temp_2018' (AbstractJdbcTableManager.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,415 Creating partition table: 'analytics_completeness_temp_2022' (AbstractJdbcTableManager.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,418 Creating partition table: 'analytics_completeness_temp_2023' (AbstractJdbcTableManager.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,420 1 successful and 0 failed items (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,421 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.015s: 1 successful and 0 failed items (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,421 Created analytics tables: 00:00:00.023 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,421 Populating analytics tables COMPLETENESS (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,421 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Populating analytics tables COMPLETENESS (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,421 Populate table task number: 4 (DefaultAnalyticsTableService.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,432 Populate analytics_completeness_temp_2018 in: 0.010379 sec. (AbstractJdbcTableManager.java [ForkJoinPool-20-worker-2])  
* INFO  2023-07-03T00:07:49,432 Populate analytics_completeness_temp_2008 in: 0.010862 sec. (AbstractJdbcTableManager.java [ForkJoinPool-20-worker-3])  
* INFO  2023-07-03T00:07:49,433 Populate analytics_completeness_temp_2022 in: 0.011776 sec. (AbstractJdbcTableManager.java [ForkJoinPool-20-worker-1])  
* INFO  2023-07-03T00:07:49,487 Populate analytics_completeness_temp_2023 in: 0.065133 sec. (AbstractJdbcTableManager.java [ForkJoinPool-20-worker-4])  
* INFO  2023-07-03T00:07:49,487 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.066s (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,488 Populated analytics tables: 00:00:00.090 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,488 Invoking analytics table hooks COMPLETENESS (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,488 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Invoking analytics table hooks COMPLETENESS (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ```
* INFO  2023-07-03T00:07:49,489 Invoked analytics table hooks: 00:00:00.092 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,491 Applying aggregation level 4 COMPLETENESS (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,491 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Applying aggregation level 4 COMPLETENESS (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,492 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.001s (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,495 Applied aggregation levels: 00:00:00.098 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,495 Vacuuming tables COMPLETENESS (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,495 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Vacuuming tables COMPLETENESS (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,496 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.001s (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,496 Tables vacuumed: 00:00:00.099 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,497 Creating indexes COMPLETENESS (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,497 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Creating indexes COMPLETENESS (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,694 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.197s (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,694 Created indexes: 00:00:00.297 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,694 Analyzing analytics tables COMPLETENESS (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,695 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Analyzing analytics tables COMPLETENESS (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,825 4 successful and 0 failed items (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,825 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.13s: 4 successful and 0 failed items (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,825 Analyzed tables: 00:00:00.428 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,827 Drop SQL views (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,827 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Drop SQL views (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,836 13 successful and 0 failed items (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,836 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.009s: 13 successful and 0 failed items (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,836 Swapping analytics tables COMPLETENESS (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,836 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Swapping analytics tables COMPLETENESS (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,837 Swapping table, master table exists: 'true', skip master table: 'false' (AbstractJdbcTableManager.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,870 1 successful and 0 failed items (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,870 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.034s: 1 successful and 0 failed items (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,871 Create SQL views (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,871 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Create SQL views (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* WARN  2023-07-03T00:07:49,890 Validation failed for SQL view 'HnZAdad7N37' with code: 'E4301' and message: 'SQL query must be a select query' (DefaultSqlViewService.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* WARN  2023-07-03T00:07:49,890 Ignoring SQL view which failed validation: HnZAdad7N37, users, message: SQL query must be a select query (DefaultResourceTableService.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,894 13 successful and 0 failed items (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,894 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.023s: 13 successful and 0 failed items (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,894 Table update done: analytics_completeness: 00:00:00.497 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,894 Analytics table update parameters: AnalyticsTableUpdateParams{last years=null, skip resource tables=false, skip table types=[], skip programs=[], start time=2023-07-03T00:07:33} (DefaultAnalyticsTableService.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,894 Starting update of type: COMPLETENESS_TARGET, table name: 'analytics_completenesstarget', processes: 7: 00:00:00.000 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,894 Validating Analytics Table COMPLETENESS_TARGET (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,894 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Validating Analytics Table COMPLETENESS_TARGET (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,894 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0s (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,897 Table update start: analytics_completenesstarget, earliest: null, parameters: AnalyticsTableUpdateParams{last years=null, skip resource tables=false, skip table types=[], skip programs=[], start time=2023-07-03T00:07:33}: 00:00:00.002 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,897 Performing pre-create table work (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,897 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Performing pre-create table work (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,897 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0s (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,897 Performed pre-create table work COMPLETENESS_TARGET: 00:00:00.002 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,897 Dropping temp tables (if any) COMPLETENESS_TARGET (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,897 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Dropping temp tables (if any) COMPLETENESS_TARGET (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,898 1 successful and 0 failed items (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,898 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.001s: 1 successful and 0 failed items (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,898 Dropped temp tables: 00:00:00.003 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,898 Creating analytics tables COMPLETENESS_TARGET (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,898 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Creating analytics tables COMPLETENESS_TARGET (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,898 Creating table: 'analytics_completenesstarget_temp', columns: '22' (AbstractJdbcTableManager.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,899 1 successful and 0 failed items (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,899 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.001s: 1 successful and 0 failed items (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,899 Created analytics tables: 00:00:00.004 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,899 Populating analytics tables COMPLETENESS_TARGET (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,899 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Populating analytics tables COMPLETENESS_TARGET (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:49,899 Populate table task number: 1 (DefaultAnalyticsTableService.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,067 Populate analytics_completenesstarget_temp in: 0.167822 sec. (AbstractJdbcTableManager.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,067 1 successful and 0 failed items (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,067 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.168s: 1 successful and 0 failed items (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,068 Populated analytics tables: 00:00:00.173 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,068 Invoking analytics table hooks COMPLETENESS_TARGET (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,068 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Invoking analytics table hooks COMPLETENESS_TARGET (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,069 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.001s (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,069 Invoked analytics table hooks: 00:00:00.174 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,070 Applying aggregation level 4 COMPLETENESS_TARGET (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,071 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Applying aggregation level 4 COMPLETENESS_TARGET (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,071 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0s (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,073 Applied aggregation levels: 00:00:00.178 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,073 Vacuuming tables COMPLETENESS_TARGET (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,073 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Vacuuming tables COMPLETENESS_TARGET (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,074 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.001s (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,074 Tables vacuumed: 00:00:00.179 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,074 Creating indexes COMPLETENESS_TARGET (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,074 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Creating indexes COMPLETENESS_TARGET (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,402 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.328s (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,402 Created indexes: 00:00:00.507 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,402 Analyzing analytics tables COMPLETENESS_TARGET (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,402 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Analyzing analytics tables COMPLETENESS_TARGET (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,530 1 successful and 0 failed items (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,530 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.128s: 1 successful and 0 failed items (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,530 Analyzed tables: 00:00:00.635 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,531 Drop SQL views (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,531 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Drop SQL views (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,541 13 successful and 0 failed items (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,541 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.01s: 13 successful and 0 failed items (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,541 Swapping analytics tables COMPLETENESS_TARGET (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,541 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Swapping analytics tables COMPLETENESS_TARGET (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,542 Swapping table, master table exists: 'true', skip master table: 'false' (AbstractJdbcTableManager.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,552 1 successful and 0 failed items (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,553 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.012s: 1 successful and 0 failed items (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,554 Create SQL views (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,554 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Create SQL views (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* WARN  2023-07-03T00:07:50,573 Validation failed for SQL view 'HnZAdad7N37' with code: 'E4301' and message: 'SQL query must be a select query' (DefaultSqlViewService.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* WARN  2023-07-03T00:07:50,573 Ignoring SQL view which failed validation: HnZAdad7N37, users, message: SQL query must be a select query (DefaultResourceTableService.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,576 13 successful and 0 failed items (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,577 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.023s: 13 successful and 0 failed items (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,577 Table update done: analytics_completenesstarget: 00:00:00.682 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,577 Analytics table update parameters: AnalyticsTableUpdateParams{last years=null, skip resource tables=false, skip table types=[], skip programs=[], start time=2023-07-03T00:07:33} (DefaultAnalyticsTableService.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,577 Starting update of type: ORG_UNIT_TARGET, table name: 'analytics_orgunittarget', processes: 7: 00:00:00.000 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,577 Validating Analytics Table ORG_UNIT_TARGET (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,577 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Validating Analytics Table ORG_UNIT_TARGET (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,577 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0s (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,578 Table update start: analytics_orgunittarget, earliest: null, parameters: AnalyticsTableUpdateParams{last years=null, skip resource tables=false, skip table types=[], skip programs=[], start time=2023-07-03T00:07:33}: 00:00:00.000 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,578 Performing pre-create table work (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,578 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Performing pre-create table work (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,578 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0s (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,578 Performed pre-create table work ORG_UNIT_TARGET: 00:00:00.000 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,578 Dropping temp tables (if any) ORG_UNIT_TARGET (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,578 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Dropping temp tables (if any) ORG_UNIT_TARGET (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,578 1 successful and 0 failed items (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,579 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.001s: 1 successful and 0 failed items (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,579 Dropped temp tables: 00:00:00.001 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,579 Creating analytics tables ORG_UNIT_TARGET (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,579 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Creating analytics tables ORG_UNIT_TARGET (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ```
* INFO  2023-07-03T00:07:50,579 1 successful and 0 failed items (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,580 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0s: 1 successful and 0 failed items (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,580 Created analytics tables: 00:00:00.002 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,580 Populating analytics tables ORG_UNIT_TARGET (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,580 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Populating analytics tables ORG_UNIT_TARGET (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,580 Populate table task number: 1 (DefaultAnalyticsTableService.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,611 analytics_orgunittarget_temp in: 0.030969 sec. (AbstractJdbcTableManager.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,611 1 successful and 0 failed items (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,611 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.031s: 1 successful and 0 failed items (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,611 Populated analytics tables: 00:00:00.034 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,612 Invoking analytics table hooks ORG_UNIT_TARGET (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,612 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Invoking analytics table hooks ORG_UNIT_TARGET (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,612 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0s (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,612 Invoked analytics table hooks: 00:00:00.035 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,613 Applying aggregation level 4 ORG_UNIT_TARGET (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,613 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Applying aggregation level 4 ORG_UNIT_TARGET (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,614 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.001s (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,615 Applied aggregation levels: 00:00:00.037 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,615 Vacuuming tables ORG_UNIT_TARGET (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,615 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Vacuuming tables ORG_UNIT_TARGET (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,615 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0s (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,615 Tables vacuumed: 00:00:00.038 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,615 Creating indexes ORG_UNIT_TARGET (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,616 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Creating indexes ORG_UNIT_TARGET (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,658 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.042s (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,658 Created indexes: 00:00:00.080 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,658 Analyzing analytics tables ORG_UNIT_TARGET (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,658 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Analyzing analytics tables ORG_UNIT_TARGET (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,715 1 successful and 0 failed items (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,715 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.057s: 1 successful and 0 failed items (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,715 Analyzed tables: 00:00:00.138 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,717 Drop SQL views (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,717 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Drop SQL views (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,725 13 successful and 0 failed items (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,725 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.008s: 13 successful and 0 failed items (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,725 Swapping analytics tables ORG_UNIT_TARGET (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,725 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Swapping analytics tables ORG_UNIT_TARGET (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,726 Swapping table, master table exists: 'true', skip master table: 'false' (AbstractJdbcTableManager.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,731 1 successful and 0 failed items (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,732 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.007s: 1 successful and 0 failed items (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,733 Create SQL views (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,733 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Create SQL views (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* WARN  2023-07-03T00:07:50,749 Validation failed for SQL view 'HnZAdad7N37' with code: 'E4301' and message: 'SQL query must be a select query' (DefaultSqlViewService.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* WARN  2023-07-03T00:07:50,749 Ignoring SQL view which failed validation: HnZAdad7N37, users, message: SQL query must be a select query (DefaultResourceTableService.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,752 13 successful and 0 failed items (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,752 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.019s: 13 successful and 0 failed items (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,752 Table update done: analytics_orgunittarget: 00:00:00.174 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,752 Analytics table update parameters: AnalyticsTableUpdateParams{last years=null, skip resource tables=false, skip table types=[], skip programs=[], start time=2023-07-03T00:07:33} (DefaultAnalyticsTableService.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,752 Starting update of type: OWNERSHIP, table name: 'analytics_ownership', processes: 7: 00:00:00.000 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,752 Validating Analytics Table OWNERSHIP (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,752 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Validating Analytics Table OWNERSHIP (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,754 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.002s (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,763 Table update start: analytics_ownership, earliest: null, parameters: AnalyticsTableUpdateParams{last years=null, skip resource tables=false, skip table types=[], skip programs=[], start time=2023-07-03T00:07:33}: 00:00:00.010 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,763 Performing pre-create table work (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,763 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Performing pre-create table work (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,763 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0s (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,763 Performed pre-create table work OWNERSHIP: 00:00:00.010 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,763 Dropping temp tables (if any) OWNERSHIP (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,763 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Dropping temp tables (if any) OWNERSHIP (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,796 12 successful and 0 failed items (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,796 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.033s: 12 successful and 0 failed items (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,796 Dropped temp tables: 00:00:00.043 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,796 Creating analytics tables OWNERSHIP (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,796 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Creating analytics tables OWNERSHIP (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,796 Creating table: 'analytics_ownership_temp_nlknxlelwb7', columns: '13' (AbstractJdbcTableManager.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,797 Creating table: 'analytics_ownership_temp_rd4lkpqj54h', columns: '13' (AbstractJdbcTableManager.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,797 Creating table: 'analytics_ownership_temp_gr1seiiiv0e', columns: '13' (AbstractJdbcTableManager.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,798 Creating table: 'analytics_ownership_temp_aq0ddhbuego', columns: '13' (AbstractJdbcTableManager.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,798 Creating table: 'analytics_ownership_temp_oq6qcyyjpf9', columns: '13' (AbstractJdbcTableManager.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,799 Creating table: 'analytics_ownership_temp_v73kfoswkxf', columns: '13' (AbstractJdbcTableManager.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,799 Creating table: 'analytics_ownership_temp_l2qe5jxnen0', columns: '13' (AbstractJdbcTableManager.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,800 Creating table: 'analytics_ownership_temp_w6woocord9e', columns: '13' (AbstractJdbcTableManager.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,800 Creating table: 'analytics_ownership_temp_xzvwv7zjzm2', columns: '13' (AbstractJdbcTableManager.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,800 Creating table: 'analytics_ownership_temp_gmgchoioksn', columns: '13' (AbstractJdbcTableManager.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,801 Creating table: 'analytics_ownership_temp_xmu2sb8oetv', columns: '13' (AbstractJdbcTableManager.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,802 Creating table: 'analytics_ownership_temp_n5dqvirkajs', columns: '13' (AbstractJdbcTableManager.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,802 12 successful and 0 failed items (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,802 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage completed after 0.006s: 12 successful and 0 failed items (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,802 Created analytics tables: 00:00:00.049 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,802 Populating analytics tables OWNERSHIP (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,802 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage started: Populating analytics tables OWNERSHIP (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,802 Populate table task number: 7 (DefaultAnalyticsTableService.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* INFO  2023-07-03T00:07:50,806 OwnershipAnalytics query row count was 0 for analytics_ownership_temp_xmu2sb8oetv (JdbcOwnershipAnalyticsTableManager.java [ForkJoinPool-30-worker-3])  
* INFO  2023-07-03T00:07:50,806 Populate analytics_ownership_temp_xmu2sb8oetv in: 0.002050 sec. (JdbcOwnershipAnalyticsTableManager.java [ForkJoinPool-30-worker-3])  
* INFO  2023-07-03T00:07:50,806 OwnershipAnalytics query row count was 0 for analytics_ownership_temp_xzvwv7zjzm2 (JdbcOwnershipAnalyticsTableManager.java [ForkJoinPool-30-worker-1])  
* INFO  2023-07-03T00:07:50,806 Populate analytics_ownership_temp_xzvwv7zjzm2 in: 0.002378 sec. (JdbcOwnershipAnalyticsTableManager.java [ForkJoinPool-30-worker-1])  
* ERROR 2023-07-03T00:07:50,807 Cannot invoke "Object.equals(Object)" because the return value of "java.util.Map.get(Object)" is null (NotificationLoggerUtil.java [ForkJoinPool-30-worker-2])  
* WARN  2023-07-03T00:07:50,807 [ANALYTICS_TABLE BO6J9oyMfMQ] Process aborted after 17.668s: aborted after error: Cannot invoke "Object.equals(Object)" because the return value of "java.util.Map.get(Object)" is null (ControlledJobProgress.java [ForkJoinPool-30-worker-2])  
* ERROR 2023-07-03T00:07:50,814 [ANALYTICS_TABLE BO6J9oyMfMQ] Item failed after 0.004s: Cannot invoke "Object.equals(Object)" because the return value of "java.util.Map.get(Object)" is null (ControlledJobProgress.java [ForkJoinPool-30-worker-2])  
java.lang.NullPointerException: Cannot invoke "Object.equals(Object)" because the return value of "java.util.Map.get(Object)" is null
	at org.hisp.dhis.analytics.table.JdbcOwnershipWriter.sameValue(JdbcOwnershipWriter.java:209) ~[dhis-service-analytics-2.40.0.jar:?]
	at org.hisp.dhis.analytics.table.JdbcOwnershipWriter.write(JdbcOwnershipWriter.java:110) ~[dhis-service-analytics-2.40.0.jar:?]
	at org.hisp.dhis.analytics.table.JdbcOwnershipAnalyticsTableManager.lambda$populateTableInternal$1(JdbcOwnershipAnalyticsTableManager.java:182) ~[dhis-service-analytics-2.40.0.jar:?]
	at org.springframework.jdbc.core.JdbcTemplate$RowCallbackHandlerResultSetExtractor.extractData(JdbcTemplate.java:1696) ~[spring-jdbc-5.3.25.jar:5.3.25]
	at org.springframework.jdbc.core.JdbcTemplate$1QueryStatementCallback.doInStatement(JdbcTemplate.java:453) ~[spring-jdbc-5.3.25.jar:5.3.25]
	at org.springframework.jdbc.core.JdbcTemplate.execute(JdbcTemplate.java:381) ~[spring-jdbc-5.3.25.jar:5.3.25]
	at org.springframework.jdbc.core.JdbcTemplate.query(JdbcTemplate.java:465) ~[spring-jdbc-5.3.25.jar:5.3.25]
	at org.springframework.jdbc.core.JdbcTemplate.query(JdbcTemplate.java:470) ~[spring-jdbc-5.3.25.jar:5.3.25]
	at org.hisp.dhis.analytics.table.JdbcOwnershipAnalyticsTableManager.populateTableInternal(JdbcOwnershipAnalyticsTableManager.java:181) ~[dhis-service-analytics-2.40.0.jar:?]
	at org.hisp.dhis.analytics.table.JdbcOwnershipAnalyticsTableManager.populateTable(JdbcOwnershipAnalyticsTableManager.java:160) ~[dhis-service-analytics-2.40.0.jar:?]
	at org.hisp.dhis.analytics.table.AbstractJdbcTableManager.populateTablePartition(AbstractJdbcTableManager.java:255) ~[dhis-service-analytics-2.40.0.jar:?]
	at jdk.internal.reflect.GeneratedMethodAccessor892.invoke(Unknown Source) ~[?:?]
	at jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:?]
	at java.lang.reflect.Method.invoke(Method.java:568) ~[?:?]
	at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:344) ~[spring-aop-5.3.25.jar:5.3.25]
	at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:208) ~[spring-aop-5.3.25.jar:5.3.25]
	at jdk.proxy3.$Proxy372.populateTablePartition(Unknown Source) ~[?:?]
	at org.hisp.dhis.analytics.table.DefaultAnalyticsTableService.lambda$populateTables$2(DefaultAnalyticsTableService.java:226) ~[dhis-service-analytics-2.40.0.jar:?]
	at org.hisp.dhis.scheduling.JobProgress.lambda$runStageInParallel$5(JobProgress.java:498) ~[dhis-api-2.40.0.jar:?]
	at java.util.stream.ReferencePipeline$3$1.accept(ReferencePipeline.java:197) [?:?]
	at java.util.ArrayList$ArrayListSpliterator.forEachRemaining(ArrayList.java:1625) [?:?]
	at java.util.stream.AbstractPipeline.copyInto(AbstractPipeline.java:509) [?:?]
	at java.util.stream.AbstractPipeline.wrapAndCopyInto(AbstractPipeline.java:499) [?:?]
	at java.util.stream.ReduceOps$ReduceTask.doLeaf(ReduceOps.java:960) [?:?]
	at java.util.stream.ReduceOps$ReduceTask.doLeaf(ReduceOps.java:934) [?:?]
	at java.util.stream.AbstractTask.compute(AbstractTask.java:327) [?:?]
	at java.util.concurrent.CountedCompleter.exec(CountedCompleter.java:754) [?:?]
	at java.util.concurrent.ForkJoinTask.doExec(ForkJoinTask.java:373) [?:?]
	at java.util.concurrent.ForkJoinPool$WorkQueue.topLevelExec(ForkJoinPool.java:1182) [?:?]
	at java.util.concurrent.ForkJoinPool.scan(ForkJoinPool.java:1655) [?:?]
	at java.util.concurrent.ForkJoinPool.runWorker(ForkJoinPool.java:1622) [?:?]
	at java.util.concurrent.ForkJoinWorkerThread.run(ForkJoinWorkerThread.java:165) [?:?]
* ERROR 2023-07-03T00:07:50,837 processing aborted: skipped stage, failing item caused abort. parallel processing aborted after 11 successful and 1 failed items (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* ERROR 2023-07-03T00:07:50,844 [ANALYTICS_TABLE BO6J9oyMfMQ] Stage failed after 0.036s: processing aborted: skipped stage, failing item caused abort. parallel processing aborted after 11 successful and 1 failed items (ControlledJobProgress.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
java.lang.RuntimeException: processing aborted: skipped stage, failing item caused abort. parallel processing aborted after 11 successful and 1 failed items
	at org.hisp.dhis.scheduling.ControlledJobProgress.cancellationAsAbort(ControlledJobProgress.java:390) ~[dhis-service-core-2.40.0.jar:?]
	at org.hisp.dhis.scheduling.ControlledJobProgress.failedStage(ControlledJobProgress.java:235) ~[dhis-service-core-2.40.0.jar:?]
	at org.hisp.dhis.scheduling.JobProgress.autoSkipStage(JobProgress.java:382) ~[dhis-api-2.40.0.jar:?]
	at org.hisp.dhis.scheduling.JobProgress.runStageInParallel(JobProgress.java:523) ~[dhis-api-2.40.0.jar:?]
	at org.hisp.dhis.analytics.table.DefaultAnalyticsTableService.populateTables(DefaultAnalyticsTableService.java:225) ~[dhis-service-analytics-2.40.0.jar:?]
	at org.hisp.dhis.analytics.table.DefaultAnalyticsTableService.update(DefaultAnalyticsTableService.java:137) ~[dhis-service-analytics-2.40.0.jar:?]
	at org.hisp.dhis.analytics.table.DefaultAnalyticsTableGenerator.generateTables(DefaultAnalyticsTableGenerator.java:111) ~[dhis-service-analytics-2.40.0.jar:?]
	at org.hisp.dhis.analytics.table.scheduling.AnalyticsTableJob.execute(AnalyticsTableJob.java:72) ~[dhis-service-analytics-2.40.0.jar:?]
	at org.hisp.dhis.scheduling.AbstractSchedulingManager.execute(AbstractSchedulingManager.java:325) ~[dhis-service-core-2.40.0.jar:?]
	at org.hisp.dhis.scheduling.DefaultSchedulingManager.lambda$executeNow$6(DefaultSchedulingManager.java:200) ~[dhis-service-core-2.40.0.jar:?]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) [?:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
	at org.springframework.scheduling.support.DelegatingErrorHandlingRunnable.run(DelegatingErrorHandlingRunnable.java:54) [spring-context-5.3.25.jar:5.3.25]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) [?:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) [?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?]
	at java.lang.Thread.run(Thread.java:833) [?:?]
* INFO  2023-07-03T00:07:50,844 Populated analytics tables: 00:00:00.091 (Clock.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* ERROR 2023-07-03T00:07:50,844 Process failed: java.util.concurrent.CancellationException (NotificationLoggerUtil.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
* ERROR 2023-07-03T00:07:50,844 Job failed: 'inMemoryAnalyticsJob' (AbstractSchedulingManager.java [taskScheduler-22]) UID:BO6J9oyMfMQ 
java.util.concurrent.CancellationException: null
	at org.hisp.dhis.scheduling.ControlledJobProgress.startingStage(ControlledJobProgress.java:202) ~[dhis-service-core-2.40.0.jar:?]
	at org.hisp.dhis.scheduling.JobProgress.startingStage(JobProgress.java:186) ~[dhis-api-2.40.0.jar:?]
	at org.hisp.dhis.scheduling.JobProgress.startingStage(JobProgress.java:191) ~[dhis-api-2.40.0.jar:?]
	at org.hisp.dhis.analytics.table.DefaultAnalyticsTableService.update(DefaultAnalyticsTableService.java:140) ~[dhis-service-analytics-2.40.0.jar:?]
	at org.hisp.dhis.analytics.table.DefaultAnalyticsTableGenerator.generateTables(DefaultAnalyticsTableGenerator.java:111) ~[dhis-service-analytics-2.40.0.jar:?]
	at org.hisp.dhis.analytics.table.scheduling.AnalyticsTableJob.execute(AnalyticsTableJob.java:72) ~[dhis-service-analytics-2.40.0.jar:?]
	at org.hisp.dhis.scheduling.AbstractSchedulingManager.execute(AbstractSchedulingManager.java:325) ~[dhis-service-core-2.40.0.jar:?]
	at org.hisp.dhis.scheduling.DefaultSchedulingManager.lambda$executeNow$6(DefaultSchedulingManager.java:200) ~[dhis-service-core-2.40.0.jar:?]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) [?:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
	at org.springframework.scheduling.support.DelegatingErrorHandlingRunnable.run(DelegatingErrorHandlingRunnable.java:54) [spring-context-5.3.25.jar:5.3.25]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539) [?:?]
	at java.util.concurrent.FutureTask.run(FutureTask.java:264) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304) [?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635) [?:?]
	at java.lang.Thread.run(Thread.java:833) [?:?]
* ERROR 2023-07-03T00:07:50,845 java.util.concurrent.CancellationException
	at org.hisp.dhis.scheduling.ControlledJobProgress.startingStage(ControlledJobProgress.java:202)
	at org.hisp.dhis.scheduling.JobProgress.startingStage(JobProgress.java:186)
	at org.hisp.dhis.scheduling.JobProgress.startingStage(JobProgress.java:191)
	at org.hisp.dhis.analytics.table.DefaultAnalyticsTableService.update(DefaultAnalyticsTableService.java:140)
	at org.hisp.dhis.analytics.table.DefaultAnalyticsTableGenerator.generateTables(DefaultAnalyticsTableGenerator.java:111)
	at org.hisp.dhis.analytics.table.scheduling.AnalyticsTableJob.execute(AnalyticsTableJob.java:72)
	at org.hisp.dhis.scheduling.AbstractSchedulingManager.execute(AbstractSchedulingManager.java:325)
	at org.hisp.dhis.scheduling.DefaultSchedulingManager.lambda$executeNow$6(DefaultSchedulingManager.java:200)
	at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539)
	at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
	at org.springframework.scheduling.support.DelegatingErrorHandlingRunnable.run(DelegatingErrorHandlingRunnable.java:54)
	at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:539)
	at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
	at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:304)
	at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1136)
	at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:635)
	at java.base/java.lang.Thread.run(Thread.java:833)
 (AbstractSchedulingManager.java [taskScheduler-22]) UID:BO6J9oyMfMQ```

I am Upgrading from 2.38.4 to 2.39 but I am having these errors:

2.38.4-2.39_Error-log.json (32.1 KB)

Please find the attached file showing the tomcat error log.

Finally got it fixed and this solution of on this link

Hi, I’m wondering why in the Maintenace, the icons for “Add” “List” are not showing.
How to fix it?

Hi @kateshih
From the screenshot, you have added. I noticed in the below message that no one organization. thus could u please check if you configured the organization unit and if one OU exists?

Hi @sami.oracle10g, thanks for checking this! I have configured one root OU now, but the icons are still not showing :sweat_smile:
Any advice? The icons in the USER app are working fine!

Did you clear the cash from the browser by using the browser cash clear app?
did you try to use another browser? if not, try.
if the problem still exists., share the Catalina log.

Hi, I have entirely cleared the site data with Developer Tool.
However, the problem still exist…
The log is attached. Thanks a lot for your kind advice!
catalina_2023-07-13.docx (21.3 KB)

I meant clear browser cashes using this app as attatched

Hi, that has been done as well before using the Developer tool. Not sure if there’s any error in my installation…

Hi @sami.oracle10g , did you manage to resolve this specific errors leading analytics not running? if so, how.

We recently also upgraded our test instances to 2.40.0.1 BUT analytics seems not to run because of the same.

Regards

Yes, there is temporary fixing until they provide with permanent fixing. The temporary fixing is when you click on analytic tables you will see five check boxes, the last is skip generate period or as it is name however ensure to choose it.

Hi @stephocay ,

Can you please share you errors and one of our @dhis2-backend team may be able to help.
It would be useful for us to know if this is a bug in DHIS2, or an inconsistency in your database.

Kind regards,
Phil

To all on this thread - I’m sorry that we didn’t see some of the issues being raised here earlier.

If you are still having issues with DHIS2 v40.0 please let us know.

Thanks,
Phil

1 Like

Hi @sami.oracle10g and @stephocay,

I believe the issue you’re facing is the one described in the Jira DHIS2-15467.
You can find a workaround here: [DHIS2-15467] - Jira

Also, you can upgrade to DHIS2 40.1, as this has been fixed in that patch version.

I hope that helps.

Cheers,
Maikel