Hello,
I just installed DHI2 version 2.32 following the official documentation.
Catalina.log displays no ERRORS, or SEVER messages. All seems ok, but tomcat doesn’t serve any page.
Localhost logs shows 404 Error messages. So no webpage is served.
Postgree and Tomcat are running as expected, and ROOR.war is also deployed.
Could somebody help me? I don’t see any way to solve this problem, even after checking all the main sources: dhis community, goolge, tomcat sites, …
Thanks a lot, and congratulations for the project. It is awesome!
Here are the main logs (I can’t upload them as a new user):
ubuntu@ip-172-31-43-98:/home/dhis/tomcat-dhis/logs$ cat catalina.out
Jul 03, 2019 10:19:29 PM org.apache.catalina.startup.ClassLoaderFactory validateFile
WARNING: Problem with directory [/usr/share/tomcat8/common/classes], exists: [false], isDirectory: [false], canRead: [false]
Jul 03, 2019 10:19:29 PM org.apache.catalina.startup.ClassLoaderFactory validateFile
WARNING: Problem with directory [/usr/share/tomcat8/common], exists: [false], isDirectory: [false], canRead: [false]
Jul 03, 2019 10:19:29 PM org.apache.catalina.startup.ClassLoaderFactory validateFile
WARNING: Problem with directory [/usr/share/tomcat8/server/classes], exists: [false], isDirectory: [false], canRead: [false]
Jul 03, 2019 10:19:29 PM org.apache.catalina.startup.ClassLoaderFactory validateFile
WARNING: Problem with directory [/usr/share/tomcat8/server], exists: [false], isDirectory: [false], canRead: [false]
Jul 03, 2019 10:19:29 PM org.apache.catalina.startup.ClassLoaderFactory validateFile
WARNING: Problem with directory [/usr/share/tomcat8/shared/classes], exists: [false], isDirectory: [false], canRead: [false]
Jul 03, 2019 10:19:29 PM org.apache.catalina.startup.ClassLoaderFactory validateFile
WARNING: Problem with directory [/usr/share/tomcat8/shared], exists: [false], isDirectory: [false], canRead: [false]
Jul 03, 2019 10:19:29 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Server version: Apache Tomcat/8.0.32 (Ubuntu)
Jul 03, 2019 10:19:29 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Server built: Dec 10 2018 14:08:07 UTC
Jul 03, 2019 10:19:29 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Server number: 8.0.32.0
Jul 03, 2019 10:19:29 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: OS Name: Linux
Jul 03, 2019 10:19:29 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: OS Version: 4.4.0-1087-aws
Jul 03, 2019 10:19:29 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Architecture: amd64
Jul 03, 2019 10:19:29 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Java Home: /usr/java/jre
Jul 03, 2019 10:19:29 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: JVM Version: 1.8.0_212-b10
Jul 03, 2019 10:19:29 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: JVM Vendor: Oracle Corporation
Jul 03, 2019 10:19:29 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: CATALINA_BASE: /home/dhis/tomcat-dhis
Jul 03, 2019 10:19:29 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: CATALINA_HOME: /usr/share/tomcat8
Jul 03, 2019 10:19:29 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Djava.util.logging.config.file=/home/dhis/tomcat-dhis/conf/logging.properties
Jul 03, 2019 10:19:29 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Djava.util.logging.manager=org.apache.juli.ClassLoaderLogManager
Jul 03, 2019 10:19:29 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Djava.awt.headless=true
Jul 03, 2019 10:19:29 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Xmx128M
Jul 03, 2019 10:19:29 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Xmx600m
Jul 03, 2019 10:19:29 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Xms400m
Jul 03, 2019 10:19:29 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Djava.net.preferIPv4Stack=true
Jul 03, 2019 10:19:29 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Djava.net.preferIPv4Addresses=true
Jul 03, 2019 10:19:29 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Djava.endorsed.dirs=/usr/share/tomcat8/endorsed
Jul 03, 2019 10:19:29 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Dcatalina.base=/home/dhis/tomcat-dhis
Jul 03, 2019 10:19:29 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Dcatalina.home=/usr/share/tomcat8
Jul 03, 2019 10:19:29 PM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Djava.io.tmpdir=/home/dhis/tomcat-dhis/temp
Jul 03, 2019 10:19:30 PM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["http-nio-8080"]
Jul 03, 2019 10:19:30 PM org.apache.tomcat.util.net.NioSelectorPool getSharedSelector
INFO: Using a shared selector for servlet write/read
Jul 03, 2019 10:19:30 PM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 1140 ms
Jul 03, 2019 10:19:30 PM org.apache.catalina.core.StandardService startInternal
INFO: Starting service Catalina
Jul 03, 2019 10:19:30 PM org.apache.catalina.core.StandardEngine startInternal
INFO: Starting Servlet Engine: Apache Tomcat/8.0.32 (Ubuntu)
Jul 03, 2019 10:19:30 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive /home/dhis/tomcat-dhis/webapps/ROOt.war
Jul 03, 2019 10:19:42 PM org.apache.jasper.servlet.TldScanner scanJars
INFO: At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.
* INFO 2019-07-03 22:19:46,186 System property dhis2.home not set (DefaultLocationManager.java [localhost-startStop-1])
* INFO 2019-07-03 22:19:46,188 Environment variable DHIS2_HOME points to /home/dhis/config (DefaultLocationManager.java [localhost-startStop-1])
* INFO 2019-07-03 22:19:46,360 File /home/dhis/config/dhis-google-auth.json does not exist (DefaultLocationManager.java [localhost-startStop-1])
* INFO 2019-07-03 22:19:46,360 Could not find dhis-google-auth.json (DefaultDhisConfigurationProvider.java [localhost-startStop-1])
* INFO 2019-07-03 22:19:46,360 File /home/dhis/config/dhis-google-auth.json does not exist (DefaultLocationManager.java [localhost-startStop-1])
* INFO 2019-07-03 22:19:46,360 Could not find dhis-google-auth.json (DefaultDhisConfigurationProvider.java [localhost-startStop-1])
* INFO 2019-07-03 22:19:50,932 Hibernate configuration loaded, using dialect: org.hisp.dhis.hibernate.dialect.DhisPostgresDialect, region factory: org.hibernate.cache.ehcache.EhCacheRegionFactory (DefaultHibernateConfigurationProvider.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:12,256 Local Cache instance created for region:'systemSetting' (CacheBuilder.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:13,917 File store configured with provider: 'filesystem', container: 'files' and location: ''. (JCloudsAppStorageService.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:14,196 Local Cache instance created for region:'appCache' (CacheBuilder.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:14,205 Old apps folder does not exist, stopping discovery (LocalAppStorageService.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:14,212 Starting JClouds discovery (JCloudsAppStorageService.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:14,223 No apps found during JClouds discovery. (JCloudsAppStorageService.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:14,511 Local Cache instance created for region:'userSetting' (CacheBuilder.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:17,253 Read only configuration initialized, read replicas found: 0 (DefaultDataSourceManager.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:17,690 Version: 2.30, Revision: b58337c, Build date: Tue Jul 02 02:06:12 CEST 2019, Database name: dhis2, Java version: 1.8.0_212 (DefaultSystemService.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:18,672 Local Cache instance created for region:'userFailedLoginAttempt' (CacheBuilder.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:19,798 Session timeout set to 3600 seconds (DefaultAuthenticationSuccessHandler.java [localhost-startStop-1])
* WARN 2019-07-03 22:20:20,223 RP discovery / realm validation disabled; (RealmVerifier.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:21,477 Found the following message senders: [org.hisp.dhis.message.EmailMessageSender@17bf252c, org.hisp.dhis.sms.config.SmsMessageSender@6e8f1bce] (DefaultMessageService.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:21,887 File store configured with provider: 'filesystem', container: 'files' and location: ''. (JCloudsFileResourceContentStore.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:21,917 Setting up noop leader manager using dummy NodeId:bfd1fb15-a9c9-4392-aa5f-02b8c7fed0e1 (NoOpLeaderManager.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:22,360 NoOp Cache instance created for region:'eventAnalyticsQueryResponse' (CacheBuilder.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:22,365 Event analytics server-side cache is enabled: false with expiration: 0 s (DefaultEventAnalyticsService.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:22,367 NoOp Cache instance created for region:'analyticsQueryResponse' (CacheBuilder.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:22,367 Analytics server-side cache is enabled: false with expiration: 0 s (DefaultAnalyticsService.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:24,280 Local Cache instance created for region:'tempTrackerOwnership' (CacheBuilder.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:24,965 Initializing Log4j, max file size: '100MB', max file archives: 0 (Log4JLogConfigInitializer.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:24,974 Added logger: org.hisp.dhis.resourcetable using file: /home/dhis/config/logs/dhis-analytics-table.log (Log4JLogConfigInitializer.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:24,974 Added logger: org.hisp.dhis.analytics.table using file: /home/dhis/config/logs/dhis-analytics-table.log (Log4JLogConfigInitializer.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:24,977 Added logger: org.hisp.dhis.dxf2 using file: /home/dhis/config/logs/dhis-data-exchange.log (Log4JLogConfigInitializer.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:24,977 Added logger: org.hisp.dhis.dxf2.synch using file: /home/dhis/config/logs/dhis-data-sync.log (Log4JLogConfigInitializer.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:24,977 Added logger: org.hisp.dhis.dxf2.metadata using file: /home/dhis/config/logs/dhis-metadata-sync.log (Log4JLogConfigInitializer.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:24,978 Added logger: org.hisp.dhis.pushanalysis using file: /home/dhis/config/logs/dhis-push-analysis.log (Log4JLogConfigInitializer.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:24,978 Added root logger using file: /home/dhis/config/logs/dhis.log (Log4JLogConfigInitializer.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:26,402 Following listners are registered: [org.hisp.dhis.sms.listener.DataValueSMSListener@63a1b2d4, org.hisp.dhis.sms.listener.J2MEDataValueSMSListener@a3a0c1d, org.hisp.dhis.sms.listener.UnregisteredSMSListener@45537739, org.hisp.dhis.sms.listener.DhisMessageAlertListener@40a3051e, org.hisp.dhis.sms.listener.TrackedEntityRegistrationSMSListener@2b93541c, org.hisp.dhis.sms.listener.ProgramStageDataEntrySMSListener@5be5afda, org.hisp.dhis.sms.listener.SingleEventListener@f663474] (SmsConsumerThread.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:26,433 SMS consumer started (DefaultSmsConsumerService.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:27,693 Scheduled monitoring service (DefaultMonitoringService.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:28,416 SMS configuration not found (DefaultGatewayAdministrationService.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:32,527 Executing startup routine [1 of 18, runlevel 1]: InitTableAlteror (DefaultStartupRoutineExecutor.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:34,040 Executing startup routine [2 of 18, runlevel 2]: PeriodTypePopulator (DefaultStartupRoutineExecutor.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:34,053 Executing startup routine [3 of 18, runlevel 2]: TwoFAPopulator (DefaultStartupRoutineExecutor.java [localhost-startStop-1])
* WARN 2019-07-03 22:20:34,059 Cannot get users with disjoint roles as user does not have any user roles (DefaultUserService.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:34,062 Executing startup routine [4 of 18, runlevel 3]: DataElementDefaultDimensionPopulator (DefaultStartupRoutineExecutor.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:34,766 'system-process' update org.hisp.dhis.category.Category, name: default, uid: GLevLNI9wkl (AuditLogUtil.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:34,852 Executing startup routine [5 of 18, runlevel 4]: ReportingIdentityPopulator (DefaultStartupRoutineExecutor.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:35,049 Executing startup routine [6 of 18, runlevel 4]: IdentityPopulator (DefaultStartupRoutineExecutor.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:35,534 Executing startup routine [7 of 18, runlevel 5]: SchedulerUpgrade (DefaultStartupRoutineExecutor.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:35,536 Executing startup routine [8 of 18, runlevel 5]: TableCreator (DefaultStartupRoutineExecutor.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:35,547 Executing startup routine [9 of 18, runlevel 6]: TableAlteror (DefaultStartupRoutineExecutor.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:42,103 Tables updated (TableAlteror.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:42,104 Executing startup routine [10 of 18, runlevel 7]: TrackedEntityTableAlteror (DefaultStartupRoutineExecutor.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:42,883 Executing startup routine [11 of 18, runlevel 7]: ModelUpgrader (DefaultStartupRoutineExecutor.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:43,452 Executing startup routine [12 of 18, runlevel 9]: FavoriteDataItemUpgrader (DefaultStartupRoutineExecutor.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:43,456 Executing startup routine [13 of 18, runlevel 9]: AnalyticalObjectEmbeddedDimensionUpgrader (DefaultStartupRoutineExecutor.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:43,466 Executing startup routine [14 of 18, runlevel 11]: ExpressionUpgrader (DefaultStartupRoutineExecutor.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:43,478 Executing startup routine [15 of 18, runlevel 12]: ConfigurationPopulator (DefaultStartupRoutineExecutor.java [localhost-startStop-1])
* WARN 2019-07-03 22:20:43,479 Encryption not configured: Missing encryption.password in dhis.conf (ConfigurationPopulator.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:43,488 Executing startup routine [16 of 18, runlevel 13]: I18nLocalePopulator (DefaultStartupRoutineExecutor.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:43,500 Executing startup routine [17 of 18, runlevel 14]: SchedulerStart (DefaultStartupRoutineExecutor.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:43,559 Setting up default jobs. (SchedulerStart.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:43,597 Scheduled job: uwWCT2BMmlq, Remove expired reserved values, REMOVE_EXPIRED_RESERVED_VALUES, 0 0 * ? * * (DefaultSchedulingManager.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:43,609 Scheduled job: sHMedQF7VYa, Credentials expiry alert, CREDENTIALS_EXPIRY_ALERT, 0 0 2 ? * * (DefaultSchedulingManager.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:43,625 Kafka is not enabled, disabling scheduled Kafka job. (SchedulerStart.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:43,633 Scheduled job: pd6O228pqr0, File resource clean up, FILE_RESOURCE_CLEANUP, 0 0 2 ? * * (DefaultSchedulingManager.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:43,643 Scheduled job: YvAwAmrqAtN, Dataset notification, DATA_SET_NOTIFICATION, 0 0 2 ? * * (DefaultSchedulingManager.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:43,648 Scheduled job: BFa3jDsbtdO, Data statistics, DATA_STATISTICS, 0 0 2 ? * * (DefaultSchedulingManager.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:43,653 Scheduled job: Js3vHn2AVuG, Validation result notification, VALIDATION_RESULTS_NOTIFICATION, 0 0 7 ? * * (DefaultSchedulingManager.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:43,653 Executing startup routine [18 of 18, runlevel 14]: SettingUpgrader (DefaultStartupRoutineExecutor.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:43,655 All startup routines done (DefaultStartupRoutineExecutor.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:45,741 SMS configuration not found (DefaultGatewayAdministrationService.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:45,800 SMS configuration not found (DefaultGatewayAdministrationService.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:51,759 SMS configuration not found (DefaultGatewayAdministrationService.java [localhost-startStop-1])
* INFO 2019-07-03 22:20:52,995 SMS configuration not found (DefaultGatewayAdministrationService.java [localhost-startStop-1])
Jul 03, 2019 10:20:53 PM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deployment of web application archive /home/dhis/tomcat-dhis/webapps/ROOt.war has finished in 82,582 ms
Jul 03, 2019 10:20:53 PM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["http-nio-8080"]
Jul 03, 2019 10:20:53 PM org.apache.catalina.startup.Catalina start
INFO: Server startup in 82719 ms
#######################################
ubuntu@ip-172-31-43-98:/home/dhis/tomcat-dhis/logs$ cat localhost.2019-07-03.log
03-Jul-2019 22:19:42.629 INFO [localhost-startStop-1] org.apache.catalina.core.ApplicationContext.log No Spring WebApplicationInitializer types detected on classpath
03-Jul-2019 22:19:42.753 INFO [localhost-startStop-1] org.apache.catalina.core.ApplicationContext.log Initializing Spring root WebApplicationContext
03-Jul-2019 22:20:45.601 INFO [localhost-startStop-1] org.apache.catalina.core.ApplicationContext.log Initializing Spring FrameworkServlet 'ohieServlet'
03-Jul-2019 22:20:45.771 INFO [localhost-startStop-1] org.apache.catalina.core.ApplicationContext.log Initializing Spring FrameworkServlet 'mobile'
03-Jul-2019 22:20:45.822 INFO [localhost-startStop-1] org.apache.catalina.core.ApplicationContext.log Initializing Spring FrameworkServlet 'webapi'
03-Jul-2019 22:20:51.781 INFO [localhost-startStop-1] org.apache.catalina.core.ApplicationContext.log Initializing Spring FrameworkServlet 'uaaServlet'
ubuntu@ip-172-31-43-98:/home/dhis/tomcat-dhis/logs$ cat localhost_access_log.2019-07-03.txt
158.109.94.211 - - [03/Jul/2019:22:26:37 +0200] "GET / HTTP/1.1" 404 -
158.109.94.211 - - [03/Jul/2019:22:26:52 +0200] "GET / HTTP/1.1" 404 -
158.109.94.211 - - [03/Jul/2019:22:26:55 +0200] "GET / HTTP/1.1" 404 -
158.109.94.211 - - [03/Jul/2019:22:26:55 +0200] "GET / HTTP/1.1" 404 -
158.109.94.211 - - [03/Jul/2019:22:26:55 +0200] "GET / HTTP/1.1" 404 -