I tried restoring a backup database from another instance to a new dhis2 instance… This is the error am getting
ERROR 2023-07-18T13:45:06,276 org.springframework.dao.DataIntegrityViolationException: could not execute statement; SQL [n/a]; constraint [messageconversation_pkey]; nested exception is org.hibernate.exception.ConstraintViolationException: could not execute statement.
How can i fix this?
Hi @Mark_Banda
Welcome to the community. This indicates that there is an issue with one of the tables and constraints which means that you’d need to check the data in the table if there’s an issue.
What dhis2 versions are you using?
We will need more information such as the complete catalina log (without sensitive info) to help you further.
Thanks!
Thanks so Much the full log is :
– Logs begin at Fri 2023-02-10 12:38:00 CAT. –
Jul 20 09:06:26 stage4 tomcat9[276]: Starting Servlet engine: [Apache Tomcat/9.0.31 (Ubuntu)]
Jul 20 09:06:26 stage4 tomcat9[276]: Deploying deployment descriptor [/etc/tomcat9/Catalina/localhost/host-manager.xml]
Jul 20 09:06:26 stage4 tomcat9[276]: The path attribute with value [/host-manager] in deployment descriptor [/etc/tomcat9/Catalina/localhost/host-manager.xml] has been ignored
Jul 20 09:06:27 stage4 tomcat9[276]: 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.
Jul 20 09:06:27 stage4 tomcat9[276]: Deployment of deployment descriptor [/etc/tomcat9/Catalina/localhost/host-manager.xml] has finished in [1,088] ms
Jul 20 09:06:27 stage4 tomcat9[276]: Deploying deployment descriptor [/etc/tomcat9/Catalina/localhost/manager.xml]
Jul 20 09:06:27 stage4 tomcat9[276]: The path attribute with value [/manager] in deployment descriptor [/etc/tomcat9/Catalina/localhost/manager.xml] has been ignored
Jul 20 09:06:28 stage4 tomcat9[276]: 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.
Jul 20 09:06:28 stage4 tomcat9[276]: Deployment of deployment descriptor [/etc/tomcat9/Catalina/localhost/manager.xml] has finished in [702] ms
Jul 20 09:06:28 stage4 tomcat9[276]: Deploying web application directory [/var/lib/tomcat9/webapps/stage4]
Jul 20 09:06:37 stage4 tomcat9[276]: 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.
Jul 20 09:06:39 stage4 tomcat9[276]: * INFO 2023-07-20T07:06:39,745 Monitoring metric for key monitoring.jvm.enabled is disabled (MetricsEnabler.java [main])
Jul 20 09:06:42 stage4 tomcat9[276]: * INFO 2023-07-20T07:06:42,916 Hibernate configuration loaded: dialect: ‘org.hisp.dhis.hibernate.dialect.DhisPostgresDialect’, region factory: ‘org.hibernate.cache.ehcache.EhCacheRegionFactory’, connection pool max size: null (DefaultHibernateConfigurationProvider.java [main])
Jul 20 09:06:45 stage4 tomcat9[276]: * INFO 2023-07-20T07:06:45,564 Simple Local Cache instance created for region:‘periodCache’ (SimpleCacheBuilder.java [main])
Jul 20 09:06:55 stage4 tomcat9[276]: * INFO 2023-07-20T07:06:55,199 DHIS 2 Version: 2.39.0, Revision: 3009509, Build date: Wed Oct 26 12:01:37 UTC 2022, Database name: stage4, Java version: 11.0.19 (DefaultSystemService.java [main])
Jul 20 09:06:56 stage4 tomcat9[276]: * INFO 2023-07-20T07:06:56,025 File store configured with provider: ‘filesystem’, container: ‘files’ and location: ‘’. (JCloudsFileResourceContentStore.java [main])
Jul 20 09:06:57 stage4 tomcat9[276]: * INFO 2023-07-20T07:06:57,175 File store configured with provider: ‘filesystem’, container: ‘files’ and location: ‘’. (JCloudsAppStorageService.java [main])
Jul 20 09:06:57 stage4 tomcat9[276]: * INFO 2023-07-20T07:06:57,178 Old apps folder does not exist, stopping discovery (LocalAppStorageService.java [main])
Jul 20 09:06:57 stage4 tomcat9[276]: * INFO 2023-07-20T07:06:57,179 Starting JClouds discovery (JCloudsAppStorageService.java [main])
Jul 20 09:06:57 stage4 tomcat9[276]: * INFO 2023-07-20T07:06:57,186 No apps found during JClouds discovery. (JCloudsAppStorageService.java [main])
Jul 20 09:06:57 stage4 tomcat9[276]: * INFO 2023-07-20T07:06:57,301 Following listeners are registered: [org.hisp.dhis.sms.listener.AggregateDataSetSMSListener@118dd791, org.hisp.dhis.sms.listener.SimpleEventSMSListener@22014cfd, org.hisp.dhis.sms.listener.TrackerEventSMSListener@1e342732, org.hisp.dhis.sms.listener.UnregisteredSMSListener@2bec8923, org.hisp.dhis.sms.listener.SingleEventListener@2773bab7, org.hisp.dhis.sms.listener.DeleteEventSMSListener@15365e32, org.hisp.dhis.sms.listener.RelationshipSMSListener@21f1bef7, org.hisp.dhis.sms.listener.DataValueSMSListener@533d5207, org.hisp.dhis.sms.listener.EnrollmentSMSListener@27dcfc2, org.hisp.dhis.sms.listener.ProgramStageDataEntrySMSListener@30280851, org.hisp.dhis.sms.listener.TrackedEntityRegistrationSMSListener@663664a8, org.hisp.dhis.sms.listener.J2MEDataValueSMSListener@7fe7ce7a, org.hisp.dhis.sms.listener.DhisMessageAlertListener@45e626d5] (SmsConsumerThread.java [main])
Jul 20 09:06:57 stage4 tomcat9[276]: * INFO 2023-07-20T07:06:57,331 SMS consumer started (DefaultSmsConsumerService.java [main])
Jul 20 09:06:57 stage4 tomcat9[276]: * INFO 2023-07-20T07:06:57,447 Initialized NoOp leader manager with node UUID: ‘7a21fed4-58d8-4b91-9e3e-83f543760cad’ and node ID: ‘’ (NoOpLeaderManager.java [main])
Jul 20 09:07:00 stage4 tomcat9[276]: * INFO 2023-07-20T07:07:00,068 Starting embedded Artemis ActiveMQ server. (ArtemisManager.java [main])
Jul 20 09:07:00 stage4 tomcat9[276]: * INFO 2023-07-20T07:07:00,387 Initializing Log4j, max file size: ‘100MB’, max file archives: 1 (Log4JLogConfigInitializer.java [main])
Jul 20 09:07:00 stage4 tomcat9[276]: * INFO 2023-07-20T07:07:00,402 Added logger: org.hisp.dhis.resourcetable using file: /opt/dhis2/logs/dhis-analytics-table.log (Log4JLogConfigInitializer.java [main])
Jul 20 09:07:00 stage4 tomcat9[276]: * INFO 2023-07-20T07:07:00,402 Added logger: org.hisp.dhis.analytics.table using file: /opt/dhis2/logs/dhis-analytics-table.log (Log4JLogConfigInitializer.java [main])
Jul 20 09:07:00 stage4 tomcat9[276]: * INFO 2023-07-20T07:07:00,403 Added logger: org.hisp.dhis.dxf2 using file: /opt/dhis2/logs/dhis-data-exchange.log (Log4JLogConfigInitializer.java [main])
Jul 20 09:07:00 stage4 tomcat9[276]: * INFO 2023-07-20T07:07:00,403 Added logger: org.hisp.dhis.dxf2.sync using file: /opt/dhis2/logs/dhis-data-sync.log (Log4JLogConfigInitializer.java [main])
Jul 20 09:07:00 stage4 tomcat9[276]: * INFO 2023-07-20T07:07:00,404 Added logger: org.hisp.dhis.dxf2.metadata using file: /opt/dhis2/logs/dhis-metadata-sync.log (Log4JLogConfigInitializer.java [main])
Jul 20 09:07:00 stage4 tomcat9[276]: * INFO 2023-07-20T07:07:00,404 Added logger: org.hisp.dhis.pushanalysis using file: /opt/dhis2/logs/dhis-push-analysis.log (Log4JLogConfigInitializer.java [main])
Jul 20 09:07:00 stage4 tomcat9[276]: * INFO 2023-07-20T07:07:00,416 Added logger: org.hisp.dhis.audit using file: /opt/dhis2/logs/dhis-audit.log (Log4JLogConfigInitializer.java [main])
Jul 20 09:07:00 stage4 tomcat9[276]: * INFO 2023-07-20T07:07:00,417 Added root logger using file: /opt/dhis2/logs/dhis.log (Log4JLogConfigInitializer.java [main])
Jul 20 09:07:00 stage4 tomcat9[276]: * INFO 2023-07-20T07:07:00,434 Scheduled monitoring service (DefaultMonitoringService.java [main])
Jul 20 09:07:00 stage4 tomcat9[276]: * INFO 2023-07-20T07:07:00,577 Session timeout set to 3600 seconds (DefaultAuthenticationSuccessHandler.java [main])
Jul 20 09:07:00 stage4 tomcat9[276]: * WARN 2023-07-20T07:07:00,944 You are asking Spring Security to ignore Ant [pattern=‘/api/staticContent/‘]. This is not recommended – please use permitAll via HttpSecurity#authorizeHttpRequests instead. (WebSecurity.java [main])
Jul 20 09:07:00 stage4 tomcat9[276]: * WARN 2023-07-20T07:07:00,946 You are asking Spring Security to ignore Ant [pattern=’/dhis-web-commons/oidc/’]. This is not recommended – please use permitAll via HttpSecurity#authorizeHttpRequests instead. (WebSecurity.java [main])
Jul 20 09:07:00 stage4 tomcat9[276]: * WARN 2023-07-20T07:07:00,946 You are asking Spring Security to ignore Ant [pattern=‘/dhis-web-commons/javascripts/‘]. This is not recommended – please use permitAll via HttpSecurity#authorizeHttpRequests instead. (WebSecurity.java [main])
Jul 20 09:07:00 stage4 tomcat9[276]: * WARN 2023-07-20T07:07:00,946 You are asking Spring Security to ignore Ant [pattern=’/dhis-web-commons/css/’]. This is not recommended – please use permitAll via HttpSecurity#authorizeHttpRequests instead. (WebSecurity.java [main])
Jul 20 09:07:00 stage4 tomcat9[276]: * WARN 2023-07-20T07:07:00,946 You are asking Spring Security to ignore Ant [pattern=‘/dhis-web-commons/flags/‘]. This is not recommended – please use permitAll via HttpSecurity#authorizeHttpRequests instead. (WebSecurity.java [main])
Jul 20 09:07:00 stage4 tomcat9[276]: * WARN 2023-07-20T07:07:00,946 You are asking Spring Security to ignore Ant [pattern=’/dhis-web-commons/fonts/’]. This is not recommended – please use permitAll via HttpSecurity#authorizeHttpRequests instead. (WebSecurity.java [main])
Jul 20 09:07:00 stage4 tomcat9[276]: * WARN 2023-07-20T07:07:00,946 You are asking Spring Security to ignore Ant [pattern=‘/api/files/style/external’]. This is not recommended – please use permitAll via HttpSecurity#authorizeHttpRequests instead. (WebSecurity.java [main])
Jul 20 09:07:02 stage4 tomcat9[276]: * INFO 2023-07-20T07:07:02,271 Executing startup routine [2 of 8, runlevel 3]: PeriodTypePopulator (DefaultStartupRoutineExecutor.java [main])
Jul 20 09:07:02 stage4 tomcat9[276]: * INFO 2023-07-20T07:07:02,296 Executing startup routine [3 of 8, runlevel 4]: DataElementDefaultDimensionPopulator (DefaultStartupRoutineExecutor.java [main])
Jul 20 09:07:02 stage4 tomcat9[276]: * INFO 2023-07-20T07:07:02,574 Executing startup routine [4 of 8, runlevel 7]: ModelUpgrader (DefaultStartupRoutineExecutor.java [main])
Jul 20 09:07:02 stage4 tomcat9[276]: * INFO 2023-07-20T07:07:02,585 Executing startup routine [5 of 8, runlevel 12]: ConfigurationPopulator (DefaultStartupRoutineExecutor.java [main])
Jul 20 09:07:02 stage4 tomcat9[276]: * WARN 2023-07-20T07:07:02,585 Encryption not configured: Missing encryption.password in dhis.conf (ConfigurationPopulator.java [main])
Jul 20 09:07:02 stage4 tomcat9[276]: * INFO 2023-07-20T07:07:02,587 Executing startup routine [6 of 8, runlevel 13]: I18nLocalePopulator (DefaultStartupRoutineExecutor.java [main])
Jul 20 09:07:02 stage4 tomcat9[276]: * INFO 2023-07-20T07:07:02,594 Executing startup routine [7 of 8, runlevel 14]: SettingUpgrader (DefaultStartupRoutineExecutor.java [main])
Jul 20 09:07:02 stage4 tomcat9[276]: * INFO 2023-07-20T07:07:02,603 Executing startup routine [8 of 8, runlevel 15]: SchedulerStart (DefaultStartupRoutineExecutor.java [main])
Jul 20 09:07:02 stage4 tomcat9[276]: * INFO 2023-07-20T07:07:02,617 Setting up default jobs. (SchedulerStart.java [main])
Jul 20 09:07:02 stage4 tomcat9[276]: * INFO 2023-07-20T07:07:02,653 Scheduling job: JobConfiguration{uid=‘uwWCT2BMmlq’, name=‘Remove expired or used reserved values’, jobType=REMOVE_USED_OR_EXPIRED_RESERVED_VALUES, cronExpression=‘0 0 2 ? * *’, delay=‘null’, jobParameters=null, enabled=true, inMemoryJob=false, lastRuntimeExecution=‘null’, userUid=‘null’, leaderOnlyJob=true, jobStatus=SCHEDULED, nextExecutionTime=Fri Jul 21 02:00:00 UTC 2023, lastExecutedStatus=NOT_STARTED, lastExecuted=null} (DefaultSchedulingManager.java [main])
Jul 20 09:07:02 stage4 tomcat9[276]: * INFO 2023-07-20T07:07:02,656 Scheduled job: JobConfiguration{uid=‘uwWCT2BMmlq’, name=‘Remove expired or used reserved values’, jobType=REMOVE_USED_OR_EXPIRED_RESERVED_VALUES, cronExpression=‘0 0 2 ? * *’, delay=‘null’, jobParameters=null, enabled=true, inMemoryJob=false, lastRuntimeExecution=‘null’, userUid=‘null’, leaderOnlyJob=true, jobStatus=SCHEDULED, nextExecutionTime=Fri Jul 21 02:00:00 UTC 2023, lastExecutedStatus=NOT_STARTED, lastExecuted=null} (DefaultSchedulingManager.java [main])
Jul 20 09:07:02 stage4 tomcat9[276]: * INFO 2023-07-20T07:07:02,667 Scheduling job: JobConfiguration{uid=‘vt21671bgno’, name=‘System version update check notification’, jobType=SYSTEM_VERSION_UPDATE_CHECK, cronExpression=‘49 55 4 ? * *’, delay=‘null’, jobParameters=null, enabled=true, inMemoryJob=false, lastRuntimeExecution=‘null’, userUid=‘null’, leaderOnlyJob=true, jobStatus=SCHEDULED, nextExecutionTime=Fri Jul 21 04:55:49 UTC 2023, lastExecutedStatus=NOT_STARTED, lastExecuted=null} (DefaultSchedulingManager.java [main])
Jul 20 09:07:02 stage4 tomcat9[276]: * INFO 2023-07-20T07:07:02,667 Scheduled job: JobConfiguration{uid=‘vt21671bgno’, name=‘System version update check notification’, jobType=SYSTEM_VERSION_UPDATE_CHECK, cronExpression=‘49 55 4 ? * *’, delay=‘null’, jobParameters=null, enabled=true, inMemoryJob=false, lastRuntimeExecution=‘null’, userUid=‘null’, leaderOnlyJob=true, jobStatus=SCHEDULED, nextExecutionTime=Fri Jul 21 04:55:49 UTC 2023, lastExecutedStatus=NOT_STARTED, lastExecuted=null} (DefaultSchedulingManager.java [main])
Jul 20 09:07:02 stage4 tomcat9[276]: * INFO 2023-07-20T07:07:02,676 Scheduling job: JobConfiguration{uid=‘Js3vHn2AVuG’, name=‘Validation result notification’, jobType=VALIDATION_RESULTS_NOTIFICATION, cronExpression=‘0 0 7 ? * *’, delay=‘null’, jobParameters=null, enabled=true, inMemoryJob=false, lastRuntimeExecution=‘00:00:00.087’, userUid=‘null’, leaderOnlyJob=true, jobStatus=SCHEDULED, nextExecutionTime=Fri Jul 21 07:00:00 UTC 2023, lastExecutedStatus=COMPLETED, lastExecuted=2023-07-04 07:00:00.087} (DefaultSchedulingManager.java [main])
Jul 20 09:07:02 stage4 tomcat9[276]: * INFO 2023-07-20T07:07:02,695 Scheduling job: JobConfiguration{uid=‘YvAwAmrqAtN’, name=‘Dataset notification’, jobType=DATA_SET_NOTIFICATION, cronExpression=‘0 0 2 ? * *’, delay=‘null’, jobParameters=null, enabled=true, inMemoryJob=false, lastRuntimeExecution=‘00:00:00.067’, userUid=‘null’, leaderOnlyJob=true, jobStatus=SCHEDULED, nextExecutionTime=Fri Jul 21 02:00:00 UTC 2023, lastExecutedStatus=COMPLETED, lastExecuted=2023-07-05 02:00:00.07} (DefaultSchedulingManager.java [main])
Jul 20 09:07:02 stage4 tomcat9[276]: * INFO 2023-07-20T07:07:02,696 Scheduled job: JobConfiguration{uid=‘YvAwAmrqAtN’, name=‘Dataset notification’, jobType=DATA_SET_NOTIFICATION, cronExpression=‘0 0 2 ? * *’, delay=‘null’, jobParameters=null, enabled=true, inMemoryJob=false, lastRuntimeExecution=‘00:00:00.067’, userUid=‘null’, leaderOnlyJob=true, jobStatus=SCHEDULED, nextExecutionTime=Fri Jul 21 02:00:00 UTC 2023, lastExecutedStatus=COMPLETED, lastExecuted=2023-07-05 02:00:00.07} (DefaultSchedulingManager.java [main])
Jul 20 09:07:02 stage4 tomcat9[276]: * INFO 2023-07-20T07:07:02,703 Scheduling job: JobConfiguration{uid=‘BFa3jDsbtdO’, name=‘Data statistics’, jobType=DATA_STATISTICS, cronExpression=‘0 0 2 ? * *’, delay=‘null’, jobParameters=null, enabled=true, inMemoryJob=false, lastRuntimeExecution=‘00:00:00.243’, userUid=‘null’, leaderOnlyJob=true, jobStatus=SCHEDULED, nextExecutionTime=Fri Jul 21 02:00:00 UTC 2023, lastExecutedStatus=COMPLETED, lastExecuted=2023-07-05 02:00:00.243} (DefaultSchedulingManager.java [main])
Jul 20 09:07:02 stage4 tomcat9[276]: * INFO 2023-07-20T07:07:02,728 Scheduled job: JobConfiguration{uid=‘DCeIVkVXMhp’, name=‘Midnight’, jobType=ANALYTICS_TABLE, cronExpression=‘0 0 3 ? * *’, delay=‘null’,
Jul 20 09:07:02 stage4 tomcat9[276]: * INFO 2023-07-20T07:07:02,738 Scheduling job: JobConfiguration{uid=‘A3zYa9sYFW0’, name=‘Every Hour’, jobType=ANALYTICS_TABLE, cronExpression=‘0 0 * ? * *’, delay=‘null’, jobParameters=org.hisp.dhis.scheduling.parameters.jackson.AnalyticsJobParametersDeserializer$CustomJobParameters@684d47ce, enabled=true, inMemoryJob=false, lastRuntimeExecution=‘00:00:00.090’, userUid=‘null’, leaderOnlyJob=false, jobStatus=SCHEDULED, nextExecutionTime=Thu Jul 20 08:00:00 UTC 2023, lastExecutedStatus=COMPLETED, lastExecuted=2023-07-05 04:00:00.091} (DefaultSchedulingManager.java [main])
Jul 20 09:07:02 stage4 tomcat9[276]: * INFO 2023-07-20T07:07:02,738 Scheduled job: JobConfiguration{uid=‘A3zYa9sYFW0’, name=‘Every Hour’, jobType=ANALYTICS_TABLE, cronExpression=‘0 0 * ? * *’, delay=‘null’, jobParameters=org.hisp.dhis.scheduling.parameters.jackson.AnalyticsJobParametersDeserializer$CustomJobParameters@684d47ce, enabled=true, inMemoryJob=false, lastRuntimeExecution=‘00:00:00.090’, userUid=‘null’, leaderOnlyJob=false, jobStatus=SCHEDULED, nextExecutionTime=Thu Jul 20 08:00:00 UTC 2023, lastExecutedStatus=COMPLETED, lastExecuted=2023-07-05 04:00:00.091} (DefaultSchedulingManager.java [main])
Jul 20 09:07:02 stage4 tomcat9[276]: * INFO 2023-07-20T07:07:02,747 Scheduling job: JobConfiguration{uid=‘fUWM1At1TUx’, name=‘User account expiry alert’, jobType=ACCOUNT_EXPIRY_ALERT, cronExpression=‘0 0 2 ? * *’, delay=‘null’, jobParameters=null, enabled=true, inMemoryJob=false, lastRuntimeExecution=‘null’, userUid=‘null’, leaderOnlyJob=true, jobStatus=SCHEDULED, nextExecutionTime=Fri Jul 21 02:00:00 UTC 2023, lastExecutedStatus=NOT_STARTED, lastExecuted=null} (DefaultSchedulingManager.java [main])
Jul 20 09:07:02 stage4 tomcat9[276]: * INFO 2023-07-20T07:07:02,747 Scheduled job: JobConfiguration{uid=‘fUWM1At1TUx’, name=‘User account expiry alert’, jobType=ACCOUNT_EXPIRY_ALERT, cronExpression=‘0 0 2 ? * *’, delay=‘null’, jobParameters=null, enabled=true, inMemoryJob=false, lastRuntimeExecution=‘null’, userUid=‘null’, leaderOnlyJob=true, jobStatus=SCHEDULED, nextExecutionTime=Fri Jul 21 02:00:00 UTC 2023, lastExecutedStatus=NOT_STARTED, lastExecuted=null} (DefaultSchedulingManager.java [main])
Jul 20 09:07:02 stage4 tomcat9[276]: * ERROR 2023-07-20T07:07:02,822 org.springframework.dao.DataIntegrityViolationException: could not execute statement; SQL [n/a]; constraint [messageconversation_pkey]; nested exception is org.hibernate.exception.ConstraintViolationException: could not execute statement
Jul 20 09:07:02 stage4 tomcat9[276]: at org.springframework.orm.hibernate5.SessionFactoryUtils.convertHibernateAccessException(SessionFactoryUtils.java:212)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.springframework.orm.hibernate5.HibernateTransactionManager.convertHibernateAccessException(HibernateTransactionManager.java:785)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.springframework.orm.hibernate5.HibernateTransactionManager.doCommit(HibernateTransactionManager.java:655)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.springframework.transaction.support.AbstractPlatformTransactionManager.processCommit(AbstractPlatformTransactionManager.java:743)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.springframework.transaction.support.AbstractPlatformTransactionManager.commit(AbstractPlatformTransactionManager.java:711)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.springframework.transaction.interceptor.TransactionAspectSupport.commitTransactionAfterReturning(TransactionAspectSupport.java:654)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.springframework.transaction.interceptor.TransactionAspectSupport.invokeWithinTransaction(TransactionAspectSupport.java:407)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:119)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:186)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:215)
Jul 20 09:07:02 stage4 tomcat9[276]: at com.sun.proxy.$Proxy256.sendSystemErrorNotification(Unknown Source)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.hisp.dhis.startup.SchedulerStart.execute(SchedulerStart.java:190)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.hisp.dhis.system.startup.DefaultStartupRoutineExecutor.execute(DefaultStartupRoutineExecutor.java:113)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.hisp.dhis.system.startup.DefaultStartupRoutineExecutor.execute(DefaultStartupRoutineExecutor.java:70)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.hisp.dhis.system.startup.StartupListener.contextInitialized(StartupListener.java:70)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.apache.catalina.core.StandardContext.listenerStart(StandardContext.java:4683)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5146)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:717)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:690)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:705)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.apache.catalina.startup.HostConfig.deployDirectory(HostConfig.java:1132)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.apache.catalina.startup.HostConfig$DeployDirectory.run(HostConfig.java:1866)
Jul 20 09:07:02 stage4 tomcat9[276]: at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
Jul 20 09:07:02 stage4 tomcat9[276]: at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.apache.tomcat.util.threads.InlineExecutorService.execute(InlineExecutorService.java:75)
Jul 20 09:07:02 stage4 tomcat9[276]: at java.base/java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:118)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.apache.catalina.startup.HostConfig.deployDirectories(HostConfig.java:1044)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:429)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.apache.catalina.startup.HostConfig.start(HostConfig.java:1575)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:309)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.apache.catalina.util.LifecycleBase.fireLifecycleEvent(LifecycleBase.java:123)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.apache.catalina.util.LifecycleBase.setStateInternal(LifecycleBase.java:423)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.apache.catalina.util.LifecycleBase.setState(LifecycleBase.java:366)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.apache.catalina.core.ContainerBase.startInternal(ContainerBase.java:936)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.apache.catalina.core.StandardHost.startInternal(StandardHost.java:841)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1384)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1374)
Jul 20 09:07:02 stage4 tomcat9[276]: at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.apache.tomcat.util.threads.InlineExecutorService.execute(InlineExecutorService.java:75)
Jul 20 09:07:02 stage4 tomcat9[276]: at java.base/java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:140)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.apache.catalina.core.ContainerBase.startInternal(ContainerBase.java:909)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.apache.catalina.core.StandardEngine.startInternal(StandardEngine.java:262)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.apache.catalina.core.StandardService.startInternal(StandardService.java:421)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.apache.catalina.core.StandardServer.startInternal(StandardServer.java:930)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.apache.catalina.startup.Catalina.start(Catalina.java:633)
Jul 20 09:07:02 stage4 tomcat9[276]: at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
Jul 20 09:07:02 stage4 tomcat9[276]: at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
Jul 20 09:07:02 stage4 tomcat9[276]: at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
Jul 20 09:07:02 stage4 tomcat9[276]: at java.base/java.lang.reflect.Method.invoke(Method.java:566)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:343)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:478)
Jul 20 09:07:02 stage4 tomcat9[276]: Caused by: org.hibernate.exception.ConstraintViolationException: could not execute statement
Jul 20 09:07:02 stage4 tomcat9[276]: at org.hibernate.exception.internal.SQLStateConversionDelegate.convert(SQLStateConversionDelegate.java:109)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.hibernate.exception.internal.StandardSQLExceptionConverter.convert(StandardSQLExceptionConverter.java:37)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.hibernate.engine.jdbc.spi.SqlExceptionHelper.convert(SqlExceptionHelper.java:113)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.hibernate.engine.jdbc.spi.SqlExceptionHelper.convert(SqlExceptionHelper.java:99)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.hibernate.engine.jdbc.internal.ResultSetReturnImpl.executeUpdate(ResultSetReturnImpl.java:200)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.hibernate.engine.jdbc.batch.internal.NonBatchingBatch.addToBatch(NonBatchingBatch.java:46)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.hibernate.persister.entity.AbstractEntityPersister.insert(AbstractEntityPersister.java:3375)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.hibernate.persister.entity.AbstractEntityPersister.insert(AbstractEntityPersister.java:3908)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.hibernate.action.internal.EntityInsertAction.execute(EntityInsertAction.java:107)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.hibernate.engine.spi.ActionQueue.executeActions(ActionQueue.java:604)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.hibernate.engine.spi.ActionQueue.lambda$executeActions$1(ActionQueue.java:478)
Jul 20 09:07:02 stage4 tomcat9[276]: at java.base/java.util.LinkedHashMap.forEach(LinkedHashMap.java:684)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.hibernate.engine.spi.ActionQueue.executeActions(ActionQueue.java:475)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.hibernate.event.internal.AbstractFlushingEventListener.performExecutions(AbstractFlushingEventListener.java:344)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.hibernate.event.internal.DefaultFlushEventListener.onFlush(DefaultFlushEventListener.java:40)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.hibernate.event.service.internal.EventListenerGroupImpl.fireEventOnEachListener(EventListenerGroupImpl.java:107)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.hibernate.internal.SessionImpl.doFlush(SessionImpl.java:1407)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.hibernate.internal.SessionImpl.managedFlush(SessionImpl.java:489)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.hibernate.internal.SessionImpl.flushBeforeTransactionCompletion(SessionImpl.java:3290)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.hibernate.internal.SessionImpl.beforeTransactionCompletion(SessionImpl.java:2425)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.hibernate.engine.jdbc.internal.JdbcCoordinatorImpl.beforeTransactionCompletion(JdbcCoordinatorImpl.java:449)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.hibernate.resource.transaction.backend.jdbc.internal.JdbcResourceLocalTransactionCoordinatorImpl.beforeCompletionCallback(JdbcResourceLocalTransactionCoordinatorImpl.java:183)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.hibernate.resource.transaction.backend.jdbc.internal.JdbcResourceLocalTransactionCoordinatorImpl.access$300(JdbcResourceLocalTransactionCoordinatorImpl.java:40)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.hibernate.resource.transaction.backend.jdbc.internal.JdbcResourceLocalTransactionCoordinatorImpl$TransactionDriverControlImpl.commit(JdbcResourceLocalTransactionCoordinatorImpl.java:281)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.hibernate.engine.transaction.internal.TransactionImpl.commit(TransactionImpl.java:101)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.springframework.orm.hibernate5.HibernateTransactionManager.doCommit(HibernateTransactionManager.java:643)
Jul 20 09:07:02 stage4 tomcat9[276]: … 53 more
Jul 20 09:07:02 stage4 tomcat9[276]: Caused by: org.postgresql.util.PSQLException: ERROR: duplicate key value violates unique constraint “messageconversation_pkey”
Jul 20 09:07:02 stage4 tomcat9[276]: Detail: Key (messageconversationid)=(36) already exists.
Jul 20 09:07:02 stage4 tomcat9[276]: at org.postgresql.core.v3.QueryExecutorImpl.receiveErrorResponse(QueryExecutorImpl.java:2676)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.postgresql.core.v3.QueryExecutorImpl.processResults(QueryExecutorImpl.java:2366)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.postgresql.core.v3.QueryExecutorImpl.execute(QueryExecutorImpl.java:356)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.postgresql.jdbc.PgStatement.executeInternal(PgStatement.java:496)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.postgresql.jdbc.PgStatement.execute(PgStatement.java:413)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.postgresql.jdbc.PgPreparedStatement.executeWithFlags(PgPreparedStatement.java:190)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.postgresql.jdbc.PgPreparedStatement.executeUpdate(PgPreparedStatement.java:152)
Jul 20 09:07:02 stage4 tomcat9[276]: at com.mchange.v2.c3p0.impl.NewProxyPreparedStatement.executeUpdate(NewProxyPreparedStatement.java:1502)
Jul 20 09:07:02 stage4 tomcat9[276]: at org.hibernate.engine.jdbc.internal.ResultSetReturnImpl.executeUpdate(ResultSetReturnImpl.
Why do you think duplicated data is being inserted? This is an issue with the data. It’s not possible to have the same id for the messageconversationid. Could you double check the database where the messageconversationid is 36 and see what’s being duplicated?
Maybe clearing the application cache from Data Administration app in maintenance and restarting the server then try again to see if it solves the issue.
Thanks!