DHIS version 2.33.4

jay.shukla · 18 June 2020 18:55

We have DHIS version 2.33.4 and we are not getting any data in tracker capture.

We have tried to downgrade and upgrade the DHIS version but we facing the same issue.

For your reference, please below screenshot.

Please find the below logs also.

INFO 2020-06-18 18:52:00,000 ‘system-process’ update org.hisp.dhis.scheduling.JobConfiguration, name: Data synchronization, uid: wCf4n9KBYad (AuditLogUtil.java [taskScheduler-18])
INFO 2020-06-18 18:52:00,003 Remote server URL not set (SyncUtils.java [taskScheduler-18])
INFO 2020-06-18 18:52:00,003 Aborting data values push, server not available (DefaultSynchronizationManager.java [taskScheduler-18])
INFO 2020-06-18 18:52:00,003 Remote server URL not set (SyncUtils.java [taskScheduler-18])
INFO 2020-06-18 18:52:00,003 Aborting complete data set registration push, server not available (DefaultSynchronizationManager.java [taskScheduler-18])
INFO 2020-06-18 18:52:00,003 [Level: INFO, category: DATA_SYNC, time: Thu Jun 18 18:52:00 GMT 2020, message: Data value and Complete data set registration sync successful] (InMemoryNotifier.java [taskScheduler-18])
INFO 2020-06-18 18:52:00,005 ‘system-process’ update org.hisp.dhis.scheduling.JobConfiguration, name: Data synchronization, uid: wCf4n9KBYad (AuditLogUtil.java [taskScheduler-18])
INFO 2020-06-18 18:53:00,007 ‘system-process’ update org.hisp.dhis.scheduling.JobConfiguration, name: Data synchronization, uid: wCf4n9KBYad (AuditLogUtil.java [taskScheduler-2])
INFO 2020-06-18 18:53:00,010 Remote server URL not set (SyncUtils.java [taskScheduler-2])
INFO 2020-06-18 18:53:00,010 Aborting data values push, server not available (DefaultSynchronizationManager.java [taskScheduler-2])
INFO 2020-06-18 18:53:00,010 Remote server URL not set (SyncUtils.java [taskScheduler-2])
INFO 2020-06-18 18:53:00,010 Aborting complete data set registration push, server not available (DefaultSynchronizationManager.java [taskScheduler-2])
INFO 2020-06-18 18:53:00,010 [Level: INFO, category: DATA_SYNC, time: Thu Jun 18 18:53:00 GMT 2020, message: Data value and Complete data set registration sync successful] (InMemoryNotifier.java [taskScheduler-2])
INFO 2020-06-18 18:53:00,018 ‘system-process’ update org.hisp.dhis.scheduling.JobConfiguration, name: Data synchronization, uid: wCf4n9KBYad (AuditLogUtil.java [taskScheduler-2])
INFO 2020-06-18 18:54:00,000 ‘system-process’ update org.hisp.dhis.scheduling.JobConfiguration, name: Data synchronization, uid: wCf4n9KBYad (AuditLogUtil.java [taskScheduler-12])
INFO 2020-06-18 18:54:00,009 Remote server URL not set (SyncUtils.java [taskScheduler-12])
INFO 2020-06-18 18:54:00,010 Aborting data values push, server not available (DefaultSynchronizationManager.java [taskScheduler-12])
INFO 2020-06-18 18:54:00,010 Remote server URL not set (SyncUtils.java [taskScheduler-12])
INFO 2020-06-18 18:54:00,010 Aborting complete data set registration push, server not available (DefaultSynchronizationManager.java [taskScheduler-12])
INFO 2020-06-18 18:54:00,010 [Level: INFO, category: DATA_SYNC, time: Thu Jun 18 18:54:00 GMT 2020, message: Data value and Complete data set registration sync successful] (InMemoryNotifier.java [taskScheduler-12])
INFO 2020-06-18 18:54:00,012 ‘system-process’ update org.hisp.dhis.scheduling.JobConfiguration, name: Data synchronization, uid: wCf4n9KBYad (AuditLogUtil.java [taskScheduler-12])

jthomas · 19 June 2020 05:26

Seems like error is not captured in the log snippet you shared ? Also you have already added relaxedQueryChars="[]" to ``tomcat-dhis/conf/server.xml``` ?

  <Connector port="8080" protocol="HTTP/1.1"
             connectionTimeout="20000"
             redirectPort="8443"
             relaxedQueryChars="[]"  />```

jay.shukla · 19 June 2020 05:54

Thanks, for the update.

We have tried to downgrade and upgrade DHIS instance for the resolve this issue but we are facing the same issue in all the versions.

jthomas · 19 June 2020 06:08

So you have already changed relaxedQueryChars ?
Can you search for error or exception [case insensitive] in catalina.out and let us know?

jay.shukla · 19 June 2020 07:26

Yes, I have already changed relaxedQueryChars in tomcat-dhis/conf/server.xml.

 <Connector port="8080" protocol="HTTP/1.1"
        connectionTimeout="20000"
        redirectPort="8443"
        relaxedQueryChars="[]"/>```

Please, find below full logs of catalina.out.

catlina_log.doc (141.5 KB)

jthomas · 19 June 2020 07:56

Shall we do following steps.

Shut down tomcat using shutdown.sh
Check any process running by using ps -aef | grep tomcat
Kill the process if running still kill -15 <pid of the process running >
Check again process running by using ps -aef | grep tomcat
If no process running, start tomcat again.
Wait for few mins, and if problem still happening, can you share the log directory completely as zip here or personal message

jthomas · 19 June 2020 07:57

Reason for asking this step is the below logs which was seen in your catalina log file

io.netty.channel.unix.Errors$NativeIoException: bind(..) failed: Address already in use
	at io.netty.channel.unix.Errors.newIOException(Errors.java:122)
	at io.netty.channel.unix.Socket.bind(Socket.java:287)
	at io.netty.channel.epoll.AbstractEpollChannel.doBind(AbstractEpollChannel.java:688)
	at io.netty.channel.epoll.EpollServerSocketChannel.doBind(EpollServerSocketChannel.java:70)
	at io.netty.channel.AbstractChannel$AbstractUnsafe.bind(AbstractChannel.java:558)
	at

jay.shukla · 19 June 2020 08:46

Thanks, for the quick response.

Sure, we will do and update you.

jay.shukla · 19 June 2020 09:46

@jthomas

We have followed the steps which you mention above.but still, we are facing the same issue.
I am not able to upload a zip file, so I have uploaded the docx file.
Note:- select images or files from your device (jpg, png, gif, pdf, doc, docx, xls, xlsx, ppt, pptx, jpeg)

Catalina_log.docx (18.0 KB)

jay.shukla · 20 June 2020 14:15

@jthomas

Greetings of the day!

I need your help to resolve the below issue. Please, find below the error.

INFO 2020-06-20 14:04:16,179 Executing startup routine [2 of 18, runlevel 2]: PeriodTypePopulator (DefaultStartupRoutineExecutor.java [localhost-startStop-1])
ERROR 2020-06-20 14:04:16,191 org.hibernate.WrongClassException: Object [id=514741] was not of the specified subclass [org.hisp.dhis.period.PeriodType] : Discriminator: SixMonthlyNov
at org.hibernate.loader.Loader.getInstanceClass(Loader.java:1822)
at org.hibernate.loader.Loader.instanceNotYetLoaded(Loader.java:1633)
at org.hibernate.loader.Loader.getRow(Loader.java:1562)
at org.hibernate.loader.Loader.getRowFromResultSet(Loader.java:732)
at org.hibernate.loader.Loader.processResultSet(Loader.java:991)
at org.hibernate.loader.Loader.doQuery(Loader.java:949)
at org.hibernate.loader.Loader.doQueryAndInitializeNonLazyCollections(Loader.java:341)
at org.hibernate.loader.Loader.doList(Loader.java:2692)
at org.hibernate.loader.Loader.listUsingQueryCache(Loader.java:2537)
at org.hibernate.loader.Loader.list(Loader.java:2499)
at org.hibernate.loader.criteria.CriteriaLoader.list(CriteriaLoader.java:109)
at org.hibernate.internal.SessionImpl.list(SessionImpl.java:1936)
at org.hibernate.internal.CriteriaImpl.list(CriteriaImpl.java:370)
at org.hisp.dhis.period.hibernate.HibernatePeriodStore.getAllPeriodTypes(HibernatePeriodStore.java:228)
at org.hisp.dhis.period.PeriodTypePopulator.executeInTransaction(PeriodTypePopulator.java:66)
at org.hisp.dhis.system.startup.TransactionContextStartupRoutine$1.doInTransaction(TransactionContextStartupRoutine.java:57)
at org.springframework.transaction.support.TransactionTemplate.execute(TransactionTemplate.java:133)
at org.hisp.dhis.system.startup.TransactionContextStartupRoutine.execute(TransactionContextStartupRoutine.java:52)
at org.hisp.dhis.system.startup.DefaultStartupRoutineExecutor.execute(DefaultStartupRoutineExecutor.java:113)
at org.hisp.dhis.system.startup.DefaultStartupRoutineExecutor.execute(DefaultStartupRoutineExecutor.java:70)
at org.hisp.dhis.system.startup.StartupListener.contextInitialized(StartupListener.java:71)
at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:4689)
at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5155)
at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:743)
at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:719)
at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:705)
at org.apache.catalina.startup.HostConfig.deployWAR(HostConfig.java:970)
at org.apache.catalina.startup.HostConfig$DeployWar.run(HostConfig.java:1840)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at java.lang.Thread.run(Thread.java:745)
(StartupListener.java [localhost-startStop-1])
20-Jun-2020 14:04:16.193 SEVERE [localhost-startStop-1] org.apache.catalina.core.StandardContext.startInternal One or more listeners failed to start. Full details will be found in the appropriate container log file
20-Jun-2020 14:04:16.195 SEVERE [localhost-startStop-1] org.apache.catalina.core.StandardContext.startInternal Context [] startup failed due to previous errors
INFO 2020-06-20 14:04:16,202 De-registering jdbc driver: org.postgresql.Driver@47b2c72d (StartupListener.java [localhost-startStop-1])
20-Jun-2020 14:04:16.336 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] appears to have started a thread named [com.google.inject.internal.util.$Finalizer] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:
java.lang.Object.wait(Native Method)
java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:143)
java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:164)
com.google.inject.internal.util.$Finalizer.run(Finalizer.java:114)
20-Jun-2020 14:04:16.336 WARNING [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The web application [ROOT] appears to have started a thread named [Resource Destroyer in BasicResourcePool.close()] but has failed to stop it. This is very likely to create a memory leak. Stack trace of thread:
com.mchange.v2.resourcepool.BasicResourcePool$5.run(BasicResourcePool.java:1309)
20-Jun-2020 14:04:16.337 SEVERE [localhost-startStop-1] org.apache.catalina.loader.WebappClassLoaderBase.checkThreadLocalMapForLeaks The web application [ROOT] created a ThreadLocal with key of type [java.lang.ThreadLocal] (value [java.lang.ThreadLocal@34479a67]) and a value of type [org.hibernate.boot.internal.MetadataImpl] (value [org.hibernate.boot.internal.MetadataImpl@626e15c1]) but failed to remove it when the web application was stopped. Threads are going to be renewed over time to try and avoid a probable memory leak.
20-Jun-2020 14:04:16.360 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployWAR Deployment of web application archive [/opt/nascents/webapps/ROOT.war] has finished in [53,057] ms
20-Jun-2020 14:04:16.362 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler [“http-nio-8080”]
20-Jun-2020 14:04:16.380 INFO [main] org.apache.catalina.startup.Catalina.start Server startup in 53159 ms

jthomas · 21 June 2020 04:32

Hello @jay.shukla This looks like a different problem on a different server?

please check the memory you have allocated using setup for tomcat and make sure your configured system support that.

jay.shukla · 21 June 2020 07:17

@jthomas

Thanks, for information.The memory related issue is resolved.

Do you have any idea related below error? if this error is resolved then i think my issue get resolved,

ERROR 2020-06-20 14:04:16,191 org.hibernate.WrongClassException: Object [id=514741] was not of the specified subclass [org.hisp.dhis.period.PeriodType] : Discriminator: SixMonthlyNov

jthomas · 22 June 2020 06:18

Can you run once more Analytics => Start Export and see if this error comes again? This is more likely due to your downgrade attempts?

jay.shukla · 22 June 2020 12:06

We have downgraded version to 2.30.

taufiqhs · 14 October 2020 06:04

Hi @jay.shukla

What are the step to solved the problem? I have the same issue.

was not of the specified subclass [org.hisp.dhis.period.PeriodType] : Discriminator: SixMonthlyNov…