:06 [INFO ] api - In method ReportService.deleteOldReportRequests. Arguments: 21-10-2016 22:16:06 [INFO ] api - Exiting method deleteOldReportRequests 21-10-2016 23:16:06 [INFO ] api - In method ReportService.deleteOldReportRequests. Arguments: 21-10-2016 23:16:06 [INFO ] api - Exiting method deleteOldReportRequests 22-10-2016 00:16:06 [INFO ] api - In method ReportService.deleteOldReportRequests. Arguments: 22-10-2016 00:16:06 [INFO ] api - Exiting method deleteOldReportRequests 22-10-2016 01:16:06 [INFO ] api - In method ReportService.deleteOldReportRequests. Arguments: 22-10-2016 01:16:06 [INFO ] api - Exiting method deleteOldReportRequests 22-10-2016 02:16:06 [INFO ] api - In method ReportService.deleteOldReportRequests. Arguments: 22-10-2016 02:16:06 [INFO ] api - Exiting method deleteOldReportRequests 22-10-2016 03:16:06 [INFO ] api - In method ReportService.deleteOldReportRequests. Arguments: 22-10-2016 03:16:06 [INFO ] api - Exiting method deleteOldReportRequests 22-10-2016 04:16:06 [INFO ] api - In method ReportService.deleteOldReportRequests. Arguments: 22-10-2016 04:16:06 [INFO ] api - Exiting method deleteOldReportRequests 22-10-2016 05:16:06 [INFO ] api - In method ReportService.deleteOldReportRequests. Arguments: 22-10-2016 05:16:06 [INFO ] api - Exiting method deleteOldReportRequests 22-10-2016 06:16:06 [INFO ] api - In method ReportService.deleteOldReportRequests. Arguments: 22-10-2016 06:16:06 [INFO ] api - Exiting method deleteOldReportRequests 22-10-2016 07:16:06 [INFO ] api - In method ReportService.deleteOldReportRequests. Arguments: 22-10-2016 07:16:06 [INFO ] api - Exiting method deleteOldReportRequests 22-10-2016 08:16:06 [INFO ] api - In method ReportService.deleteOldReportRequests. Arguments: 22-10-2016 08:16:06 [INFO ] api - Exiting method deleteOldReportRequests 22-10-2016 09:16:06 [INFO ] api - In method ReportService.deleteOldReportRequests. Arguments: 22-10-2016 09:16:06 [INFO ] api - Exiting method deleteOldReportRequests 22-10-2016 10:16:06 [INFO ] api - In method ReportService.deleteOldReportRequests. Arguments: 22-10-2016 10:16:06 [INFO ] api - Exiting method deleteOldReportRequests 22-10-2016 11:16:06 [INFO ] api - In method ReportService.deleteOldReportRequests. Arguments: 22-10-2016 11:16:06 [INFO ] api - Exiting method deleteOldReportRequests 22-10-2016 12:16:06 [INFO ] api - In method ReportService.deleteOldReportRequests. Arguments: 22-10-2016 12:16:06 [INFO ] api - Exiting method deleteOldReportRequests 22-10-2016 13:16:06 [INFO ] api - In method ReportService.deleteOldReportRequests. Arguments: 22-10-2016 13:16:06 [INFO ] api - Exiting method deleteOldReportRequests 22-10-2016 14:16:06 [INFO ] api - In method ReportService.deleteOldReportRequests. Arguments: 22-10-2016 14:16:06 [INFO ] api - Exiting method deleteOldReportRequests 22-10-2016 15:16:06 [INFO ] api - In method ReportService.deleteOldReportRequests. Arguments: 22-10-2016 15:16:06 [INFO ] api - Exiting method deleteOldReportRequests 22-10-2016 16:16:06 [INFO ] api - In method ReportService.deleteOldReportRequests. Arguments: 22-10-2016 16:16:06 [INFO ] api - Exiting method deleteOldReportRequests 22-10-2016 17:16:06 [INFO ] api - In method ReportService.deleteOldReportRequests. Arguments: 22-10-2016 17:16:06 [INFO ] api - Exiting method deleteOldReportRequests 22-10-2016 18:16:06 [INFO ] api - In method ReportService.deleteOldReportRequests. Arguments: 22-10-2016 18:16:06 [INFO ] api - Exiting method deleteOldReportRequests 22-10-2016 19:16:06 [INFO ] api - In method ReportService.deleteOldReportRequests. Arguments: 22-10-2016 19:16:06 [INFO ] api - Exiting method deleteOldReportRequests 22-10-2016 20:16:06 [INFO ] api - In method ReportService.deleteOldReportRequests. Arguments: 22-10-2016 20:16:06 [INFO ] api - Exiting method deleteOldReportRequests 22-10-2016 21:16:06 [INFO ] api - In method ReportService.deleteOldReportRequests. Arguments: 22-10-2016 21:16:06 [INFO ] api - Exiting method deleteOldReportRequests 22-10-2016 21:50:44 [WARN ] DefaultConnectionTester - SQL State '08007' of Exception tested by statusOnException() implies that the database is invalid, and the pool should refill itself with fresh Connections. com.mysql.jdbc.exceptions.jdbc4.MySQLNonTransientConnectionException: Communications link failure during rollback(). Transaction resolution unknown. at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) at sun.reflect.NativeConstructorAccessorImpl.newInstance(Unknown Source) at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(Unknown Source) at java.lang.reflect.Constructor.newInstance(Unknown Source) at com.mysql.jdbc.Util.handleNewInstance(Util.java:411) at com.mysql.jdbc.Util.getInstance(Util.java:386) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:1015) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:989) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:975) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:920) at com.mysql.jdbc.ConnectionImpl.rollback(ConnectionImpl.java:5084) at com.mchange.v2.c3p0.impl.NewProxyConnection.rollback(NewProxyConnection.java:855) at org.hibernate.transaction.JDBCTransaction.rollbackAndResetAutoCommit(JDBCTransaction.java:213) at org.hibernate.transaction.JDBCTransaction.rollback(JDBCTransaction.java:192) at org.springframework.orm.hibernate3.HibernateTransactionManager.doRollback(HibernateTransactionManager.java:677) at org.springframework.transaction.support.AbstractPlatformTransactionManager.processRollback(AbstractPlatformTransactionManager.java:846) at org.springframework.transaction.support.AbstractPlatformTransactionManager.rollback(AbstractPlatformTransactionManager.java:823) at org.springframework.transaction.support.TransactionTemplate.rollbackOnException(TransactionTemplate.java:162) at org.springframework.transaction.support.TransactionTemplate.execute(TransactionTemplate.java:135) at org.openmrs.module.atomfeed.transaction.support.AtomFeedSpringTransactionManager.executeWithTransaction(AtomFeedSpringTransactionManager.java:37) at org.openmrs.module.atomfeed.scheduler.tasks.EventPublisherTask.execute(EventPublisherTask.java:20) at org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper.execute(TaskThreadedInitializationWrapper.java:68) at org.openmrs.scheduler.timer.TimerSchedulerTask.execute(TimerSchedulerTask.java:94) at org.openmrs.api.context.Daemon$2.run(Daemon.java:132) 22-10-2016 21:50:44 [WARN ] NewPooledConnection - [c3p0] A PooledConnection that has already signalled a Connection error is still in use! 22-10-2016 21:50:44 [WARN ] NewPooledConnection - [c3p0] Another error has occurred [ com.mysql.jdbc.exceptions.jdbc4.MySQLNonTransientConnectionException: Communications link failure during rollback(). Transaction resolution unknown. ] which will not be reported to listeners! com.mysql.jdbc.exceptions.jdbc4.MySQLNonTransientConnectionException: Communications link failure during rollback(). Transaction resolution unknown. at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) at sun.reflect.NativeConstructorAccessorImpl.newInstance(Unknown Source) at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(Unknown Source) at java.lang.reflect.Constructor.newInstance(Unknown Source) at com.mysql.jdbc.Util.handleNewInstance(Util.java:411) at com.mysql.jdbc.Util.getInstance(Util.java:386) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:1015) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:989) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:975) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:920) at com.mysql.jdbc.ConnectionImpl.rollback(ConnectionImpl.java:5084) at com.mchange.v2.c3p0.impl.NewProxyConnection.rollback(NewProxyConnection.java:855) at org.hibernate.transaction.JDBCTransaction.rollbackAndResetAutoCommit(JDBCTransaction.java:213) at org.hibernate.transaction.JDBCTransaction.rollback(JDBCTransaction.java:192) at org.springframework.orm.hibernate3.HibernateTransactionManager.doRollback(HibernateTransactionManager.java:677) at org.springframework.transaction.support.AbstractPlatformTransactionManager.processRollback(AbstractPlatformTransactionManager.java:846) at org.springframework.transaction.support.AbstractPlatformTransactionManager.rollback(AbstractPlatformTransactionManager.java:823) at org.springframework.transaction.support.TransactionTemplate.rollbackOnException(TransactionTemplate.java:162) at org.springframework.transaction.support.TransactionTemplate.execute(TransactionTemplate.java:135) at org.openmrs.module.atomfeed.transaction.support.AtomFeedSpringTransactionManager.executeWithTransaction(AtomFeedSpringTransactionManager.java:37) at org.openmrs.module.atomfeed.scheduler.tasks.EventPublisherTask.execute(EventPublisherTask.java:20) at org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper.execute(TaskThreadedInitializationWrapper.java:68) at org.openmrs.scheduler.timer.TimerSchedulerTask.execute(TimerSchedulerTask.java:94) at org.openmrs.api.context.Daemon$2.run(Daemon.java:132) 22-10-2016 21:50:44 [ERROR] JDBCTransaction - JDBC rollback failed com.mysql.jdbc.exceptions.jdbc4.MySQLNonTransientConnectionException: Communications link failure during rollback(). Transaction resolution unknown. at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) at sun.reflect.NativeConstructorAccessorImpl.newInstance(Unknown Source) at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(Unknown Source) at java.lang.reflect.Constructor.newInstance(Unknown Source) at com.mysql.jdbc.Util.handleNewInstance(Util.java:411) at com.mysql.jdbc.Util.getInstance(Util.java:386) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:1015) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:989) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:975) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:920) at com.mysql.jdbc.ConnectionImpl.rollback(ConnectionImpl.java:5084) at com.mchange.v2.c3p0.impl.NewProxyConnection.rollback(NewProxyConnection.java:855) at org.hibernate.transaction.JDBCTransaction.rollbackAndResetAutoCommit(JDBCTransaction.java:213) at org.hibernate.transaction.JDBCTransaction.rollback(JDBCTransaction.java:192) at org.springframework.orm.hibernate3.HibernateTransactionManager.doRollback(HibernateTransactionManager.java:677) at org.springframework.transaction.support.AbstractPlatformTransactionManager.processRollback(AbstractPlatformTransactionManager.java:846) at org.springframework.transaction.support.AbstractPlatformTransactionManager.rollback(AbstractPlatformTransactionManager.java:823) at org.springframework.transaction.support.TransactionTemplate.rollbackOnException(TransactionTemplate.java:162) at org.springframework.transaction.support.TransactionTemplate.execute(TransactionTemplate.java:135) at org.openmrs.module.atomfeed.transaction.support.AtomFeedSpringTransactionManager.executeWithTransaction(AtomFeedSpringTransactionManager.java:37) at org.openmrs.module.atomfeed.scheduler.tasks.EventPublisherTask.execute(EventPublisherTask.java:20) at org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper.execute(TaskThreadedInitializationWrapper.java:68) at org.openmrs.scheduler.timer.TimerSchedulerTask.execute(TimerSchedulerTask.java:94) at org.openmrs.api.context.Daemon$2.run(Daemon.java:132) 22-10-2016 21:50:44 [WARN ] C3P0PooledConnectionPool - A ConnectionTest has failed, reporting that all previously acquired Connections are likely invalid. The pool will be reset. 22-10-2016 21:50:44 [ERROR] TransactionTemplate - Application exception overridden by rollback exception org.ict4h.atomfeed.server.exceptions.AtomFeedRuntimeException: com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications link failure The last packet successfully received from the server was 3 milliseconds ago. The last packet sent successfully to the server was 2 milliseconds ago. at org.ict4h.atomfeed.server.repository.jdbc.AllEventRecordsQueueJdbcImpl.getAll(AllEventRecordsQueueJdbcImpl.java:103) at org.ict4h.atomfeed.server.service.publisher.EventRecordsPublishingService.publish(EventRecordsPublishingService.java:14) at org.openmrs.module.atomfeed.scheduler.tasks.EventPublisherTask$1.doInTransaction(EventPublisherTask.java:25) at org.ict4h.atomfeed.transaction.AFTransactionWorkWithoutResult.execute(AFTransactionWorkWithoutResult.java:6) at org.openmrs.module.atomfeed.transaction.support.AtomFeedSpringTransactionManager$1.doInTransaction(AtomFeedSpringTransactionManager.java:40) at org.springframework.transaction.support.TransactionTemplate.execute(TransactionTemplate.java:131) at org.openmrs.module.atomfeed.transaction.support.AtomFeedSpringTransactionManager.executeWithTransaction(AtomFeedSpringTransactionManager.java:37) at org.openmrs.module.atomfeed.scheduler.tasks.EventPublisherTask.execute(EventPublisherTask.java:20) at org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper.execute(TaskThreadedInitializationWrapper.java:68) at org.openmrs.scheduler.timer.TimerSchedulerTask.execute(TimerSchedulerTask.java:94) at org.openmrs.api.context.Daemon$2.run(Daemon.java:132) Caused by: com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications link failure The last packet successfully received from the server was 3 milliseconds ago. The last packet sent successfully to the server was 2 milliseconds ago. at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) at sun.reflect.NativeConstructorAccessorImpl.newInstance(Unknown Source) at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(Unknown Source) at java.lang.reflect.Constructor.newInstance(Unknown Source) at com.mysql.jdbc.Util.handleNewInstance(Util.java:411) at com.mysql.jdbc.SQLError.createCommunicationsException(SQLError.java:1129) at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3720) at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3609) at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:4160) at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:2617) at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2778) at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2825) at com.mysql.jdbc.PreparedStatement.executeInternal(PreparedStatement.java:2156) at com.mysql.jdbc.PreparedStatement.executeQuery(PreparedStatement.java:2313) at com.mchange.v2.c3p0.impl.NewProxyPreparedStatement.executeQuery(NewProxyPreparedStatement.java:76) at org.ict4h.atomfeed.server.repository.jdbc.AllEventRecordsQueueJdbcImpl.getAll(AllEventRecordsQueueJdbcImpl.java:100) ... 10 more Caused by: java.io.EOFException: Can not read response from server. Expected to read 4 bytes, read 0 bytes before connection was unexpectedly lost. at com.mysql.jdbc.MysqlIO.readFully(MysqlIO.java:3166) at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3620) ... 19 more 22-10-2016 21:50:44 [ERROR] TimerSchedulerTask - FATAL ERROR: Task [class org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper] failed due to exception [org.springframework.transaction.TransactionSystemException] org.springframework.transaction.TransactionSystemException: Could not roll back Hibernate transaction; nested exception is org.hibernate.TransactionException: JDBC rollback failed at org.springframework.orm.hibernate3.HibernateTransactionManager.doRollback(HibernateTransactionManager.java:680) at org.springframework.transaction.support.AbstractPlatformTransactionManager.processRollback(AbstractPlatformTransactionManager.java:846) at org.springframework.transaction.support.AbstractPlatformTransactionManager.rollback(AbstractPlatformTransactionManager.java:823) at org.springframework.transaction.support.TransactionTemplate.rollbackOnException(TransactionTemplate.java:162) at org.springframework.transaction.support.TransactionTemplate.execute(TransactionTemplate.java:135) at org.openmrs.module.atomfeed.transaction.support.AtomFeedSpringTransactionManager.executeWithTransaction(AtomFeedSpringTransactionManager.java:37) at org.openmrs.module.atomfeed.scheduler.tasks.EventPublisherTask.execute(EventPublisherTask.java:20) at org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper.execute(TaskThreadedInitializationWrapper.java:68) at org.openmrs.scheduler.timer.TimerSchedulerTask.execute(TimerSchedulerTask.java:94) at org.openmrs.api.context.Daemon$2.run(Daemon.java:132) Caused by: org.hibernate.TransactionException: JDBC rollback failed at org.hibernate.transaction.JDBCTransaction.rollback(JDBCTransaction.java:200) at org.springframework.orm.hibernate3.HibernateTransactionManager.doRollback(HibernateTransactionManager.java:677) ... 9 more Caused by: com.mysql.jdbc.exceptions.jdbc4.MySQLNonTransientConnectionException: Communications link failure during rollback(). Transaction resolution unknown. at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) at sun.reflect.NativeConstructorAccessorImpl.newInstance(Unknown Source) at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(Unknown Source) at java.lang.reflect.Constructor.newInstance(Unknown Source) at com.mysql.jdbc.Util.handleNewInstance(Util.java:411) at com.mysql.jdbc.Util.getInstance(Util.java:386) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:1015) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:989) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:975) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:920) at com.mysql.jdbc.ConnectionImpl.rollback(ConnectionImpl.java:5084) at com.mchange.v2.c3p0.impl.NewProxyConnection.rollback(NewProxyConnection.java:855) at org.hibernate.transaction.JDBCTransaction.rollbackAndResetAutoCommit(JDBCTransaction.java:213) at org.hibernate.transaction.JDBCTransaction.rollback(JDBCTransaction.java:192) ... 10 more 22-10-2016 21:50:50 [WARN ] ManagementContext - Failed to stop jmx connector: Cannot bind to URL: javax.naming.CommunicationException [Root exception is java.rmi.ConnectIOException: Exception creating connection to: localhost; nested exception is: java.net.SocketException: Network is unreachable] Oct 22, 2016 9:51:14 PM org.apache.catalina.core.StandardContext setPath WARNING: A context path must either be an empty string or start with a '/'. The path [openmrs] does not meet these criteria and has been changed to [/openmrs] Oct 22, 2016 9:51:16 PM org.apache.coyote.AbstractProtocol init INFO: Initializing ProtocolHandler ["http-nio-8050"] Oct 22, 2016 9:51:16 PM org.apache.tomcat.util.net.NioSelectorPool getSharedSelector INFO: Using a shared selector for servlet write/read Oct 22, 2016 9:51:16 PM org.apache.catalina.core.StandardService startInternal INFO: Starting service Tomcat Oct 22, 2016 9:51:16 PM org.apache.catalina.core.StandardEngine startInternal INFO: Starting Servlet Engine: Apache Tomcat/8.0.12 Oct 22, 2016 9:51:16 PM org.apache.catalina.startup.SetContextPropertiesRule begin WARNING: [SetContextPropertiesRule]{Context} Setting property 'antiJARLocking' to 'true' did not find a matching property. Oct 22, 2016 9:51:18 PM org.apache.catalina.startup.ContextConfig getDefaultWebXmlFragment INFO: No global web.xml found Oct 22, 2016 9:51:59 PM org.apache.catalina.core.ApplicationContext log INFO: No Spring WebApplicationInitializer types detected on classpath Oct 22, 2016 9:52:01 PM org.apache.catalina.core.ApplicationContext log INFO: Set web app root system property: 'webapp.root' = [/opt/openmrs/openmrs] Oct 22, 2016 9:52:01 PM org.apache.catalina.core.ApplicationContext log INFO: Initializing log4j from [classpath:log4j.xml] 22-10-2016 21:52:01 [WARN ] OpenmrsUtil - Unable to find a runtime properties file at /openmrs-runtime.properties INFO 10/22/16 9:52 PM:liquibase: Reading from `liquibasechangelog` WARNING 10/22/16 9:52 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 9:52 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 9:52 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case 22-10-2016 21:52:34 [WARN ] Listener - Bundled module folder doesn't exist: /opt/openmrs/openmrs/WEB-INF/bundledModules 22-10-2016 21:52:34 [WARN ] OpenmrsUtil - Unable to find a runtime properties file at /openmrs-runtime.properties INFO 10/22/16 9:52 PM:liquibase: Reading from `liquibasechangelog` WARNING 10/22/16 9:52 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 9:52 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 9:52 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case INFO 10/22/16 9:52 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 9:52 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 9:52 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 9:52 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 9:52 PM:liquibase: Successfully released change log lock 22-10-2016 21:52:43 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: uiframework.started value: true, 22-10-2016 21:52:43 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:52:43 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: uiframework.mandatory value: false, 22-10-2016 21:52:43 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:52:44 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: htmlwidgets.started value: true, 22-10-2016 21:52:44 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:52:44 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: htmlwidgets.mandatory value: false, 22-10-2016 21:52:44 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 9:52 PM:liquibase: Reading from `liquibasechangelog` WARNING 10/22/16 9:52 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 9:52 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case INFO 10/22/16 9:52 PM:liquibase: Successfully acquired change log lock WARNING 10/22/16 9:52 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 9:52 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case INFO 10/22/16 9:52 PM:liquibase: Successfully released change log lock 22-10-2016 21:52:44 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: openmrs-atomfeed.started value: true, 22-10-2016 21:52:44 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:52:44 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: openmrs-atomfeed.mandatory value: false, 22-10-2016 21:52:44 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 9:52 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 9:52 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 9:52 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 9:52 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 9:52 PM:liquibase: Successfully released change log lock 22-10-2016 21:52:44 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: calculation.started value: true, 22-10-2016 21:52:44 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:52:44 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: calculation.mandatory value: false, 22-10-2016 21:52:44 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:52:45 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: rulesengine.started value: true, 22-10-2016 21:52:45 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:52:45 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: rulesengine.mandatory value: false, 22-10-2016 21:52:45 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 9:52 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 9:52 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 9:52 PM:liquibase: Successfully released change log lock 22-10-2016 21:52:45 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: episodes.started value: true, 22-10-2016 21:52:45 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:52:45 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: episodes.mandatory value: false, 22-10-2016 21:52:45 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:52:45 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: webservices.rest.started value: true, 22-10-2016 21:52:45 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:52:45 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: webservices.rest.mandatory value: false, 22-10-2016 21:52:45 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 9:52 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 9:52 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 9:52 PM:liquibase: Successfully released change log lock 22-10-2016 21:52:46 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: bedmanagement.started value: true, 22-10-2016 21:52:46 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:52:46 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: bedmanagement.mandatory value: false, 22-10-2016 21:52:46 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:52:46 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: idgen.started value: true, 22-10-2016 21:52:46 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:52:46 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: idgen.mandatory value: false, 22-10-2016 21:52:46 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:52:46 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: idgen-webservices.started value: true, 22-10-2016 21:52:46 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:52:46 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: idgen-webservices.mandatory value: false, 22-10-2016 21:52:46 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:52:47 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: addresshierarchy.started value: true, 22-10-2016 21:52:47 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:52:47 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: addresshierarchy.mandatory value: false, 22-10-2016 21:52:47 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:52:47 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: event.started value: true, 22-10-2016 21:52:47 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:52:47 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: event.mandatory value: false, 22-10-2016 21:52:47 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 9:52 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 9:52 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 9:52 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 9:52 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 9:52 PM:liquibase: Successfully released change log lock 22-10-2016 21:52:48 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: metadatamapping.started value: true, 22-10-2016 21:52:48 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:52:48 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: metadatamapping.mandatory value: false, 22-10-2016 21:52:48 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 9:52 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 9:52 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 9:52 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 9:52 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 9:52 PM:liquibase: Successfully released change log lock 22-10-2016 21:52:48 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: metadatasharing.started value: true, 22-10-2016 21:52:48 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:52:48 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: metadatasharing.mandatory value: false, 22-10-2016 21:52:48 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:52:48 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: serialization.xstream.started value: true, 22-10-2016 21:52:48 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:52:48 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: serialization.xstream.mandatory value: false, 22-10-2016 21:52:48 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 9:52 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 9:52 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 9:52 PM:liquibase: Successfully released change log lock 22-10-2016 21:52:49 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: reporting.started value: true, 22-10-2016 21:52:49 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:52:49 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: reporting.mandatory value: false, 22-10-2016 21:52:49 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:52:49 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: uilibrary.started value: true, 22-10-2016 21:52:49 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:52:49 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: uilibrary.mandatory value: false, 22-10-2016 21:52:49 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 9:52 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 9:52 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 9:52 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 9:52 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 9:52 PM:liquibase: Successfully released change log lock 22-10-2016 21:52:50 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: providermanagement.started value: true, 22-10-2016 21:52:50 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:52:50 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: providermanagement.mandatory value: false, 22-10-2016 21:52:50 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 9:52 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 9:52 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 9:52 PM:liquibase: Successfully released change log lock 22-10-2016 21:52:50 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: emrapi.started value: true, 22-10-2016 21:52:50 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:52:50 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: emrapi.mandatory value: false, 22-10-2016 21:52:50 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:52:50 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: reference-data.started value: true, 22-10-2016 21:52:50 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:52:50 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: reference-data.mandatory value: false, 22-10-2016 21:52:50 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 9:52 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 9:52 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 9:52 PM:liquibase: Successfully released change log lock 22-10-2016 21:52:51 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: bacteriology.started value: true, 22-10-2016 21:52:51 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:52:51 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: bacteriology.mandatory value: false, 22-10-2016 21:52:51 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 9:52 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 9:52 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 9:52 PM:liquibase: Successfully released change log lock 22-10-2016 21:52:54 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: bahmnicore.started value: true, 22-10-2016 21:52:54 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:52:54 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: bahmnicore.mandatory value: false, 22-10-2016 21:52:54 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 9:52 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 9:52 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 9:52 PM:liquibase: Successfully released change log lock 22-10-2016 21:52:54 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: elisatomfeedclient.started value: true, 22-10-2016 21:52:54 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:52:54 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: elisatomfeedclient.mandatory value: false, 22-10-2016 21:52:54 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:52:54 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: uicommons.started value: true, 22-10-2016 21:52:54 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:52:54 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: uicommons.mandatory value: false, 22-10-2016 21:52:54 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 9:52 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 9:52 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 9:52 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 9:52 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 9:52 PM:liquibase: Successfully released change log lock 22-10-2016 21:52:55 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: appframework.started value: true, 22-10-2016 21:52:55 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:52:55 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: appframework.mandatory value: false, 22-10-2016 21:52:55 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:52:55 [INFO ] api - In method SchedulerService.saveToMemento. Arguments: 22-10-2016 21:52:55 [INFO ] api - Exiting method saveToMemento 22-10-2016 21:52:56 [INFO ] HibernateSessionFactoryBean - Closing Hibernate SessionFactory 22-10-2016 21:52:56 [INFO ] ServiceContext - Refreshing Context 22-10-2016 21:53:08 [INFO ] HibernateSessionFactoryBean - Building new Hibernate SessionFactory 22-10-2016 21:53:25 [INFO ] ServiceContext - Done refreshing Context 22-10-2016 21:53:26 [WARN ] RefillIdentifierPoolsTask - Not running scheduled task. DaemonToken = null; enabled = false 22-10-2016 21:53:31 [ERROR] OpenElisPatientFailedEventsFeedClientImpl - openelisatomfeedclient:failed feed execution while running failed eventsjava.lang.NullPointerException java.lang.NullPointerException at org.bahmni.module.bahmnicore.properties.BahmniCoreProperties.getProperty(BahmniCoreProperties.java:28) at org.bahmni.module.elisatomfeedclient.api.ElisAtomFeedProperties.getPatientFeedUri(ElisAtomFeedProperties.java:17) at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.getFeedUri(OpenElisPatientFailedEventsFeedClientImpl.java:44) at org.bahmni.module.elisatomfeedclient.api.client.OpenElisFeedClient.getAtomFeedClient(OpenElisFeedClient.java:48) at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.processFailedEvents(OpenElisPatientFailedEventsFeedClientImpl.java:64) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) at java.lang.reflect.Method.invoke(Unknown Source) at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:198) at com.sun.proxy.$Proxy287.processFailedEvents(Unknown Source) at org.bahmni.module.elisatomfeedclient.api.task.OpenElisPatientFeedFailedEventsTask.execute(OpenElisPatientFeedFailedEventsTask.java:13) at org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper.execute(TaskThreadedInitializationWrapper.java:68) at org.openmrs.scheduler.timer.TimerSchedulerTask.execute(TimerSchedulerTask.java:94) at org.openmrs.api.context.Daemon$2.run(Daemon.java:132) 22-10-2016 21:53:31 [ERROR] TimerSchedulerTask - FATAL ERROR: Task [class org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper] failed due to exception [java.lang.RuntimeException] java.lang.RuntimeException: java.lang.NullPointerException at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.processFailedEvents(OpenElisPatientFailedEventsFeedClientImpl.java:75) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) at java.lang.reflect.Method.invoke(Unknown Source) at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:198) at com.sun.proxy.$Proxy287.processFailedEvents(Unknown Source) at org.bahmni.module.elisatomfeedclient.api.task.OpenElisPatientFeedFailedEventsTask.execute(OpenElisPatientFeedFailedEventsTask.java:13) at org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper.execute(TaskThreadedInitializationWrapper.java:68) at org.openmrs.scheduler.timer.TimerSchedulerTask.execute(TimerSchedulerTask.java:94) at org.openmrs.api.context.Daemon$2.run(Daemon.java:132) Caused by: java.lang.NullPointerException at org.bahmni.module.bahmnicore.properties.BahmniCoreProperties.getProperty(BahmniCoreProperties.java:28) at org.bahmni.module.elisatomfeedclient.api.ElisAtomFeedProperties.getPatientFeedUri(ElisAtomFeedProperties.java:17) at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.getFeedUri(OpenElisPatientFailedEventsFeedClientImpl.java:44) at org.bahmni.module.elisatomfeedclient.api.client.OpenElisFeedClient.getAtomFeedClient(OpenElisFeedClient.java:48) at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.processFailedEvents(OpenElisPatientFailedEventsFeedClientImpl.java:64) ... 11 more 22-10-2016 21:53:34 [INFO ] api - In method UserService.saveRole. Arguments: Role=Privilege Level: Full, 22-10-2016 21:53:34 [INFO ] api - Exiting method saveRole 22-10-2016 21:53:34 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: visits.assignmentHandler value: org.openmrs.module.emrapi.adt.EmrApiVisitAssignmentHandler, 22-10-2016 21:53:34 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:53:34 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: provider.unknownProviderUuid value: f9badd80-ab76-11e2-9e96-0800200c9a66, 22-10-2016 21:53:34 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:53:34 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: emr.unknownProvider value: f9badd80-ab76-11e2-9e96-0800200c9a66, 22-10-2016 21:53:34 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:53:34 [INFO ] HibernateContextDAO - Updating the search index... It may take a few minutes. 22-10-2016 21:53:46 [ERROR] OpenElisPatientFailedEventsFeedClientImpl - openelisatomfeedclient:failed feed execution while running failed eventsjava.lang.NullPointerException java.lang.NullPointerException at org.bahmni.module.bahmnicore.properties.BahmniCoreProperties.getProperty(BahmniCoreProperties.java:28) at org.bahmni.module.elisatomfeedclient.api.ElisAtomFeedProperties.getPatientFeedUri(ElisAtomFeedProperties.java:17) at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.getFeedUri(OpenElisPatientFailedEventsFeedClientImpl.java:44) at org.bahmni.module.elisatomfeedclient.api.client.OpenElisFeedClient.getAtomFeedClient(OpenElisFeedClient.java:48) at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.processFailedEvents(OpenElisPatientFailedEventsFeedClientImpl.java:64) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) at java.lang.reflect.Method.invoke(Unknown Source) at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:198) at com.sun.proxy.$Proxy287.processFailedEvents(Unknown Source) at org.bahmni.module.elisatomfeedclient.api.task.OpenElisPatientFeedFailedEventsTask.execute(OpenElisPatientFeedFailedEventsTask.java:13) at org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper.execute(TaskThreadedInitializationWrapper.java:68) at org.openmrs.scheduler.timer.TimerSchedulerTask.execute(TimerSchedulerTask.java:94) at org.openmrs.api.context.Daemon$2.run(Daemon.java:132) 22-10-2016 21:53:46 [ERROR] TimerSchedulerTask - FATAL ERROR: Task [class org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper] failed due to exception [java.lang.RuntimeException] java.lang.RuntimeException: java.lang.NullPointerException at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.processFailedEvents(OpenElisPatientFailedEventsFeedClientImpl.java:75) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) at java.lang.reflect.Method.invoke(Unknown Source) at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:198) at com.sun.proxy.$Proxy287.processFailedEvents(Unknown Source) at org.bahmni.module.elisatomfeedclient.api.task.OpenElisPatientFeedFailedEventsTask.execute(OpenElisPatientFeedFailedEventsTask.java:13) at org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper.execute(TaskThreadedInitializationWrapper.java:68) at org.openmrs.scheduler.timer.TimerSchedulerTask.execute(TimerSchedulerTask.java:94) at org.openmrs.api.context.Daemon$2.run(Daemon.java:132) Caused by: java.lang.NullPointerException at org.bahmni.module.bahmnicore.properties.BahmniCoreProperties.getProperty(BahmniCoreProperties.java:28) at org.bahmni.module.elisatomfeedclient.api.ElisAtomFeedProperties.getPatientFeedUri(ElisAtomFeedProperties.java:17) at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.getFeedUri(OpenElisPatientFailedEventsFeedClientImpl.java:44) at org.bahmni.module.elisatomfeedclient.api.client.OpenElisFeedClient.getAtomFeedClient(OpenElisFeedClient.java:48) at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.processFailedEvents(OpenElisPatientFailedEventsFeedClientImpl.java:64) ... 11 more 22-10-2016 21:53:59 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: search.indexVersion value: 3, 22-10-2016 21:53:59 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 21:53:59 [INFO ] HibernateContextDAO - Finished updating the search index 22-10-2016 21:53:59 [WARN ] OpenmrsUtil - Unable to find a runtime properties file at /openmrs-runtime.properties 22-10-2016 21:54:00 [WARN ] OpenmrsUtil - Unable to find a runtime properties file at /openmrs-runtime.properties INFO 10/22/16 9:54 PM:liquibase: Reading from `liquibasechangelog` 22-10-2016 21:54:01 [ERROR] OpenElisPatientFailedEventsFeedClientImpl - openelisatomfeedclient:failed feed execution while running failed eventsorg.ict4h.atomfeed.client.exceptions.AtomFeedClientException: java.lang.NullPointerException org.ict4h.atomfeed.client.exceptions.AtomFeedClientException: java.lang.NullPointerException at org.ict4h.atomfeed.client.service.AtomFeedClient.processFailedEvents(AtomFeedClient.java:111) at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.processFailedEvents(OpenElisPatientFailedEventsFeedClientImpl.java:64) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) at java.lang.reflect.Method.invoke(Unknown Source) at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:198) at com.sun.proxy.$Proxy287.processFailedEvents(Unknown Source) at org.bahmni.module.elisatomfeedclient.api.task.OpenElisPatientFeedFailedEventsTask.execute(OpenElisPatientFeedFailedEventsTask.java:13) at org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper.execute(TaskThreadedInitializationWrapper.java:68) at org.openmrs.scheduler.timer.TimerSchedulerTask.execute(TimerSchedulerTask.java:94) at org.openmrs.api.context.Daemon$2.run(Daemon.java:132) Caused by: java.lang.NullPointerException at org.ict4h.atomfeed.Configuration.getSchema(Configuration.java:61) at org.ict4h.atomfeed.client.repository.jdbc.AllFailedEventsJdbcImpl.getOldestNFailedEvents(AllFailedEventsJdbcImpl.java:220) at org.ict4h.atomfeed.client.service.AtomFeedClient$FailedEventsFetcher.execute(AtomFeedClient.java:259) at org.ict4h.atomfeed.client.service.AtomFeedClient$FailedEventsFetcher.execute(AtomFeedClient.java:256) at org.openmrs.module.atomfeed.transaction.support.AtomFeedSpringTransactionManager$1.doInTransaction(AtomFeedSpringTransactionManager.java:40) at org.springframework.transaction.support.TransactionTemplate.execute(TransactionTemplate.java:131) at org.openmrs.module.atomfeed.transaction.support.AtomFeedSpringTransactionManager.executeWithTransaction(AtomFeedSpringTransactionManager.java:37) at org.ict4h.atomfeed.client.service.AtomFeedClient.processFailedEvents(AtomFeedClient.java:94) ... 12 more 22-10-2016 21:54:01 [ERROR] TimerSchedulerTask - FATAL ERROR: Task [class org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper] failed due to exception [java.lang.RuntimeException] java.lang.RuntimeException: org.ict4h.atomfeed.client.exceptions.AtomFeedClientException: java.lang.NullPointerException at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.processFailedEvents(OpenElisPatientFailedEventsFeedClientImpl.java:75) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) at java.lang.reflect.Method.invoke(Unknown Source) at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:198) at com.sun.proxy.$Proxy287.processFailedEvents(Unknown Source) at org.bahmni.module.elisatomfeedclient.api.task.OpenElisPatientFeedFailedEventsTask.execute(OpenElisPatientFeedFailedEventsTask.java:13) at org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper.execute(TaskThreadedInitializationWrapper.java:68) at org.openmrs.scheduler.timer.TimerSchedulerTask.execute(TimerSchedulerTask.java:94) at org.openmrs.api.context.Daemon$2.run(Daemon.java:132) Caused by: org.ict4h.atomfeed.client.exceptions.AtomFeedClientException: java.lang.NullPointerException at org.ict4h.atomfeed.client.service.AtomFeedClient.processFailedEvents(AtomFeedClient.java:111) at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.processFailedEvents(OpenElisPatientFailedEventsFeedClientImpl.java:64) ... 11 more Caused by: java.lang.NullPointerException at org.ict4h.atomfeed.Configuration.getSchema(Configuration.java:61) at org.ict4h.atomfeed.client.repository.jdbc.AllFailedEventsJdbcImpl.getOldestNFailedEvents(AllFailedEventsJdbcImpl.java:220) at org.ict4h.atomfeed.client.service.AtomFeedClient$FailedEventsFetcher.execute(AtomFeedClient.java:259) at org.ict4h.atomfeed.client.service.AtomFeedClient$FailedEventsFetcher.execute(AtomFeedClient.java:256) at org.openmrs.module.atomfeed.transaction.support.AtomFeedSpringTransactionManager$1.doInTransaction(AtomFeedSpringTransactionManager.java:40) at org.springframework.transaction.support.TransactionTemplate.execute(TransactionTemplate.java:131) at org.openmrs.module.atomfeed.transaction.support.AtomFeedSpringTransactionManager.executeWithTransaction(AtomFeedSpringTransactionManager.java:37) at org.ict4h.atomfeed.client.service.AtomFeedClient.processFailedEvents(AtomFeedClient.java:94) ... 12 more WARNING 10/22/16 9:54 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 9:54 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 9:54 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case INFO 10/22/16 9:54 PM:liquibase: Reading from `liquibasechangelog` WARNING 10/22/16 9:54 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 9:54 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 9:54 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case Oct 22, 2016 9:54:04 PM org.apache.coyote.AbstractProtocol start INFO: Starting ProtocolHandler ["http-nio-8050"] Oct 22, 2016 9:54:04 PM org.apache.catalina.core.ApplicationContext log INFO: Initializing Spring FrameworkServlet 'openmrs' 22-10-2016 22:14:17 [WARN ] DefaultConnectionTester - SQL State '08007' of Exception tested by statusOnException() implies that the database is invalid, and the pool should refill itself with fresh Connections. com.mysql.jdbc.exceptions.jdbc4.MySQLNonTransientConnectionException: Communications link failure during rollback(). Transaction resolution unknown. at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) at sun.reflect.NativeConstructorAccessorImpl.newInstance(Unknown Source) at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(Unknown Source) at java.lang.reflect.Constructor.newInstance(Unknown Source) at com.mysql.jdbc.Util.handleNewInstance(Util.java:411) at com.mysql.jdbc.Util.getInstance(Util.java:386) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:1015) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:989) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:975) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:920) at com.mysql.jdbc.ConnectionImpl.rollback(ConnectionImpl.java:5084) at com.mchange.v2.c3p0.impl.NewProxyConnection.rollback(NewProxyConnection.java:855) at org.hibernate.transaction.JDBCTransaction.rollbackAndResetAutoCommit(JDBCTransaction.java:213) at org.hibernate.transaction.JDBCTransaction.rollback(JDBCTransaction.java:192) at org.springframework.orm.hibernate3.HibernateTransactionManager.doRollback(HibernateTransactionManager.java:677) at org.springframework.transaction.support.AbstractPlatformTransactionManager.processRollback(AbstractPlatformTransactionManager.java:846) at org.springframework.transaction.support.AbstractPlatformTransactionManager.rollback(AbstractPlatformTransactionManager.java:823) at org.springframework.transaction.support.TransactionTemplate.rollbackOnException(TransactionTemplate.java:162) at org.springframework.transaction.support.TransactionTemplate.execute(TransactionTemplate.java:135) at org.openmrs.module.atomfeed.transaction.support.AtomFeedSpringTransactionManager.executeWithTransaction(AtomFeedSpringTransactionManager.java:37) at org.openmrs.module.atomfeed.scheduler.tasks.EventPublisherTask.execute(EventPublisherTask.java:20) at org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper.execute(TaskThreadedInitializationWrapper.java:68) at org.openmrs.scheduler.timer.TimerSchedulerTask.execute(TimerSchedulerTask.java:94) at org.openmrs.api.context.Daemon$2.run(Daemon.java:132) 22-10-2016 22:14:17 [WARN ] NewPooledConnection - [c3p0] A PooledConnection that has already signalled a Connection error is still in use! 22-10-2016 22:14:17 [WARN ] NewPooledConnection - [c3p0] Another error has occurred [ com.mysql.jdbc.exceptions.jdbc4.MySQLNonTransientConnectionException: Communications link failure during rollback(). Transaction resolution unknown. ] which will not be reported to listeners! com.mysql.jdbc.exceptions.jdbc4.MySQLNonTransientConnectionException: Communications link failure during rollback(). Transaction resolution unknown. at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) at sun.reflect.NativeConstructorAccessorImpl.newInstance(Unknown Source) at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(Unknown Source) at java.lang.reflect.Constructor.newInstance(Unknown Source) at com.mysql.jdbc.Util.handleNewInstance(Util.java:411) at com.mysql.jdbc.Util.getInstance(Util.java:386) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:1015) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:989) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:975) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:920) at com.mysql.jdbc.ConnectionImpl.rollback(ConnectionImpl.java:5084) at com.mchange.v2.c3p0.impl.NewProxyConnection.rollback(NewProxyConnection.java:855) at org.hibernate.transaction.JDBCTransaction.rollbackAndResetAutoCommit(JDBCTransaction.java:213) at org.hibernate.transaction.JDBCTransaction.rollback(JDBCTransaction.java:192) at org.springframework.orm.hibernate3.HibernateTransactionManager.doRollback(HibernateTransactionManager.java:677) at org.springframework.transaction.support.AbstractPlatformTransactionManager.processRollback(AbstractPlatformTransactionManager.java:846) at org.springframework.transaction.support.AbstractPlatformTransactionManager.rollback(AbstractPlatformTransactionManager.java:823) at org.springframework.transaction.support.TransactionTemplate.rollbackOnException(TransactionTemplate.java:162) at org.springframework.transaction.support.TransactionTemplate.execute(TransactionTemplate.java:135) at org.openmrs.module.atomfeed.transaction.support.AtomFeedSpringTransactionManager.executeWithTransaction(AtomFeedSpringTransactionManager.java:37) at org.openmrs.module.atomfeed.scheduler.tasks.EventPublisherTask.execute(EventPublisherTask.java:20) at org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper.execute(TaskThreadedInitializationWrapper.java:68) at org.openmrs.scheduler.timer.TimerSchedulerTask.execute(TimerSchedulerTask.java:94) at org.openmrs.api.context.Daemon$2.run(Daemon.java:132) 22-10-2016 22:14:17 [ERROR] JDBCTransaction - JDBC rollback failed com.mysql.jdbc.exceptions.jdbc4.MySQLNonTransientConnectionException: Communications link failure during rollback(). Transaction resolution unknown. at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) at sun.reflect.NativeConstructorAccessorImpl.newInstance(Unknown Source) at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(Unknown Source) at java.lang.reflect.Constructor.newInstance(Unknown Source) at com.mysql.jdbc.Util.handleNewInstance(Util.java:411) at com.mysql.jdbc.Util.getInstance(Util.java:386) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:1015) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:989) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:975) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:920) at com.mysql.jdbc.ConnectionImpl.rollback(ConnectionImpl.java:5084) at com.mchange.v2.c3p0.impl.NewProxyConnection.rollback(NewProxyConnection.java:855) at org.hibernate.transaction.JDBCTransaction.rollbackAndResetAutoCommit(JDBCTransaction.java:213) at org.hibernate.transaction.JDBCTransaction.rollback(JDBCTransaction.java:192) at org.springframework.orm.hibernate3.HibernateTransactionManager.doRollback(HibernateTransactionManager.java:677) at org.springframework.transaction.support.AbstractPlatformTransactionManager.processRollback(AbstractPlatformTransactionManager.java:846) at org.springframework.transaction.support.AbstractPlatformTransactionManager.rollback(AbstractPlatformTransactionManager.java:823) at org.springframework.transaction.support.TransactionTemplate.rollbackOnException(TransactionTemplate.java:162) at org.springframework.transaction.support.TransactionTemplate.execute(TransactionTemplate.java:135) at org.openmrs.module.atomfeed.transaction.support.AtomFeedSpringTransactionManager.executeWithTransaction(AtomFeedSpringTransactionManager.java:37) at org.openmrs.module.atomfeed.scheduler.tasks.EventPublisherTask.execute(EventPublisherTask.java:20) at org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper.execute(TaskThreadedInitializationWrapper.java:68) at org.openmrs.scheduler.timer.TimerSchedulerTask.execute(TimerSchedulerTask.java:94) at org.openmrs.api.context.Daemon$2.run(Daemon.java:132) 22-10-2016 22:14:17 [WARN ] C3P0PooledConnectionPool - A ConnectionTest has failed, reporting that all previously acquired Connections are likely invalid. The pool will be reset. 22-10-2016 22:14:17 [ERROR] TransactionTemplate - Application exception overridden by rollback exception org.ict4h.atomfeed.server.exceptions.AtomFeedRuntimeException: com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications link failure The last packet successfully received from the server was 2 milliseconds ago. The last packet sent successfully to the server was 2 milliseconds ago. at org.ict4h.atomfeed.server.repository.jdbc.AllEventRecordsQueueJdbcImpl.getAll(AllEventRecordsQueueJdbcImpl.java:103) at org.ict4h.atomfeed.server.service.publisher.EventRecordsPublishingService.publish(EventRecordsPublishingService.java:14) at org.openmrs.module.atomfeed.scheduler.tasks.EventPublisherTask$1.doInTransaction(EventPublisherTask.java:25) at org.ict4h.atomfeed.transaction.AFTransactionWorkWithoutResult.execute(AFTransactionWorkWithoutResult.java:6) at org.openmrs.module.atomfeed.transaction.support.AtomFeedSpringTransactionManager$1.doInTransaction(AtomFeedSpringTransactionManager.java:40) at org.springframework.transaction.support.TransactionTemplate.execute(TransactionTemplate.java:131) at org.openmrs.module.atomfeed.transaction.support.AtomFeedSpringTransactionManager.executeWithTransaction(AtomFeedSpringTransactionManager.java:37) at org.openmrs.module.atomfeed.scheduler.tasks.EventPublisherTask.execute(EventPublisherTask.java:20) at org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper.execute(TaskThreadedInitializationWrapper.java:68) at org.openmrs.scheduler.timer.TimerSchedulerTask.execute(TimerSchedulerTask.java:94) at org.openmrs.api.context.Daemon$2.run(Daemon.java:132) Caused by: com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications link failure The last packet successfully received from the server was 2 milliseconds ago. The last packet sent successfully to the server was 2 milliseconds ago. at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) at sun.reflect.NativeConstructorAccessorImpl.newInstance(Unknown Source) at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(Unknown Source) at java.lang.reflect.Constructor.newInstance(Unknown Source) at com.mysql.jdbc.Util.handleNewInstance(Util.java:411) at com.mysql.jdbc.SQLError.createCommunicationsException(SQLError.java:1129) at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3720) at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3609) at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:4160) at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:2617) at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2778) at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2825) at com.mysql.jdbc.PreparedStatement.executeInternal(PreparedStatement.java:2156) at com.mysql.jdbc.PreparedStatement.executeQuery(PreparedStatement.java:2313) at com.mchange.v2.c3p0.impl.NewProxyPreparedStatement.executeQuery(NewProxyPreparedStatement.java:76) at org.ict4h.atomfeed.server.repository.jdbc.AllEventRecordsQueueJdbcImpl.getAll(AllEventRecordsQueueJdbcImpl.java:100) ... 10 more Caused by: java.io.EOFException: Can not read response from server. Expected to read 4 bytes, read 0 bytes before connection was unexpectedly lost. at com.mysql.jdbc.MysqlIO.readFully(MysqlIO.java:3166) at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3620) ... 19 more 22-10-2016 22:14:17 [ERROR] TimerSchedulerTask - FATAL ERROR: Task [class org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper] failed due to exception [org.springframework.transaction.TransactionSystemException] org.springframework.transaction.TransactionSystemException: Could not roll back Hibernate transaction; nested exception is org.hibernate.TransactionException: JDBC rollback failed at org.springframework.orm.hibernate3.HibernateTransactionManager.doRollback(HibernateTransactionManager.java:680) at org.springframework.transaction.support.AbstractPlatformTransactionManager.processRollback(AbstractPlatformTransactionManager.java:846) at org.springframework.transaction.support.AbstractPlatformTransactionManager.rollback(AbstractPlatformTransactionManager.java:823) at org.springframework.transaction.support.TransactionTemplate.rollbackOnException(TransactionTemplate.java:162) at org.springframework.transaction.support.TransactionTemplate.execute(TransactionTemplate.java:135) at org.openmrs.module.atomfeed.transaction.support.AtomFeedSpringTransactionManager.executeWithTransaction(AtomFeedSpringTransactionManager.java:37) at org.openmrs.module.atomfeed.scheduler.tasks.EventPublisherTask.execute(EventPublisherTask.java:20) at org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper.execute(TaskThreadedInitializationWrapper.java:68) at org.openmrs.scheduler.timer.TimerSchedulerTask.execute(TimerSchedulerTask.java:94) at org.openmrs.api.context.Daemon$2.run(Daemon.java:132) Caused by: org.hibernate.TransactionException: JDBC rollback failed at org.hibernate.transaction.JDBCTransaction.rollback(JDBCTransaction.java:200) at org.springframework.orm.hibernate3.HibernateTransactionManager.doRollback(HibernateTransactionManager.java:677) ... 9 more Caused by: com.mysql.jdbc.exceptions.jdbc4.MySQLNonTransientConnectionException: Communications link failure during rollback(). Transaction resolution unknown. at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) at sun.reflect.NativeConstructorAccessorImpl.newInstance(Unknown Source) at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(Unknown Source) at java.lang.reflect.Constructor.newInstance(Unknown Source) at com.mysql.jdbc.Util.handleNewInstance(Util.java:411) at com.mysql.jdbc.Util.getInstance(Util.java:386) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:1015) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:989) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:975) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:920) at com.mysql.jdbc.ConnectionImpl.rollback(ConnectionImpl.java:5084) at com.mchange.v2.c3p0.impl.NewProxyConnection.rollback(NewProxyConnection.java:855) at org.hibernate.transaction.JDBCTransaction.rollbackAndResetAutoCommit(JDBCTransaction.java:213) at org.hibernate.transaction.JDBCTransaction.rollback(JDBCTransaction.java:192) ... 10 more 22-10-2016 22:14:23 [WARN ] ManagementContext - Failed to stop jmx connector: Cannot bind to URL: javax.naming.CommunicationException [Root exception is java.rmi.ConnectIOException: Exception creating connection to: localhost; nested exception is: java.net.SocketException: Network is unreachable] Oct 22, 2016 10:14:47 PM org.apache.catalina.core.StandardContext setPath WARNING: A context path must either be an empty string or start with a '/'. The path [openmrs] does not meet these criteria and has been changed to [/openmrs] Oct 22, 2016 10:14:49 PM org.apache.coyote.AbstractProtocol init INFO: Initializing ProtocolHandler ["http-nio-8050"] Oct 22, 2016 10:14:49 PM org.apache.tomcat.util.net.NioSelectorPool getSharedSelector INFO: Using a shared selector for servlet write/read Oct 22, 2016 10:14:49 PM org.apache.catalina.core.StandardService startInternal INFO: Starting service Tomcat Oct 22, 2016 10:14:49 PM org.apache.catalina.core.StandardEngine startInternal INFO: Starting Servlet Engine: Apache Tomcat/8.0.12 Oct 22, 2016 10:14:49 PM org.apache.catalina.startup.SetContextPropertiesRule begin WARNING: [SetContextPropertiesRule]{Context} Setting property 'antiJARLocking' to 'true' did not find a matching property. Oct 22, 2016 10:14:50 PM org.apache.catalina.startup.ContextConfig getDefaultWebXmlFragment INFO: No global web.xml found Oct 22, 2016 10:15:33 PM org.apache.catalina.core.ApplicationContext log INFO: No Spring WebApplicationInitializer types detected on classpath Oct 22, 2016 10:15:34 PM org.apache.catalina.core.ApplicationContext log INFO: Set web app root system property: 'webapp.root' = [/opt/openmrs/openmrs] Oct 22, 2016 10:15:34 PM org.apache.catalina.core.ApplicationContext log INFO: Initializing log4j from [classpath:log4j.xml] 22-10-2016 22:15:34 [WARN ] OpenmrsUtil - Unable to find a runtime properties file at /openmrs-runtime.properties INFO 10/22/16 10:15 PM:liquibase: Reading from `liquibasechangelog` WARNING 10/22/16 10:15 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 10:15 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 10:15 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case 22-10-2016 22:16:01 [WARN ] Listener - Bundled module folder doesn't exist: /opt/openmrs/openmrs/WEB-INF/bundledModules 22-10-2016 22:16:01 [WARN ] OpenmrsUtil - Unable to find a runtime properties file at /openmrs-runtime.properties INFO 10/22/16 10:16 PM:liquibase: Reading from `liquibasechangelog` WARNING 10/22/16 10:16 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 10:16 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 10:16 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case INFO 10/22/16 10:16 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 10:16 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 10:16 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 10:16 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 10:16 PM:liquibase: Successfully released change log lock 22-10-2016 22:16:08 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: uiframework.started value: true, 22-10-2016 22:16:08 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:08 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: uiframework.mandatory value: false, 22-10-2016 22:16:08 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:08 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: htmlwidgets.started value: true, 22-10-2016 22:16:08 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:08 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: htmlwidgets.mandatory value: false, 22-10-2016 22:16:08 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 10:16 PM:liquibase: Reading from `liquibasechangelog` WARNING 10/22/16 10:16 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 10:16 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case INFO 10/22/16 10:16 PM:liquibase: Successfully acquired change log lock WARNING 10/22/16 10:16 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 10:16 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case INFO 10/22/16 10:16 PM:liquibase: Successfully released change log lock 22-10-2016 22:16:09 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: openmrs-atomfeed.started value: true, 22-10-2016 22:16:09 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:09 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: openmrs-atomfeed.mandatory value: false, 22-10-2016 22:16:09 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 10:16 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 10:16 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 10:16 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 10:16 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 10:16 PM:liquibase: Successfully released change log lock 22-10-2016 22:16:09 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: calculation.started value: true, 22-10-2016 22:16:09 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:09 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: calculation.mandatory value: false, 22-10-2016 22:16:09 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:09 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: rulesengine.started value: true, 22-10-2016 22:16:09 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:09 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: rulesengine.mandatory value: false, 22-10-2016 22:16:09 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 10:16 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 10:16 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 10:16 PM:liquibase: Successfully released change log lock 22-10-2016 22:16:10 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: episodes.started value: true, 22-10-2016 22:16:10 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:10 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: episodes.mandatory value: false, 22-10-2016 22:16:10 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:10 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: webservices.rest.started value: true, 22-10-2016 22:16:10 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:10 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: webservices.rest.mandatory value: false, 22-10-2016 22:16:10 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 10:16 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 10:16 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 10:16 PM:liquibase: Successfully released change log lock 22-10-2016 22:16:11 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: bedmanagement.started value: true, 22-10-2016 22:16:11 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:11 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: bedmanagement.mandatory value: false, 22-10-2016 22:16:11 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:11 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: idgen.started value: true, 22-10-2016 22:16:11 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:11 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: idgen.mandatory value: false, 22-10-2016 22:16:11 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:11 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: idgen-webservices.started value: true, 22-10-2016 22:16:11 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:11 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: idgen-webservices.mandatory value: false, 22-10-2016 22:16:11 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:12 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: addresshierarchy.started value: true, 22-10-2016 22:16:12 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:12 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: addresshierarchy.mandatory value: false, 22-10-2016 22:16:12 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:12 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: event.started value: true, 22-10-2016 22:16:12 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:12 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: event.mandatory value: false, 22-10-2016 22:16:12 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 10:16 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 10:16 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 10:16 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 10:16 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 10:16 PM:liquibase: Successfully released change log lock 22-10-2016 22:16:12 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: metadatamapping.started value: true, 22-10-2016 22:16:12 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:12 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: metadatamapping.mandatory value: false, 22-10-2016 22:16:12 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 10:16 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 10:16 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 10:16 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 10:16 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 10:16 PM:liquibase: Successfully released change log lock 22-10-2016 22:16:13 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: metadatasharing.started value: true, 22-10-2016 22:16:13 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:13 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: metadatasharing.mandatory value: false, 22-10-2016 22:16:13 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:13 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: serialization.xstream.started value: true, 22-10-2016 22:16:13 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:13 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: serialization.xstream.mandatory value: false, 22-10-2016 22:16:13 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 10:16 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 10:16 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 10:16 PM:liquibase: Successfully released change log lock 22-10-2016 22:16:14 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: reporting.started value: true, 22-10-2016 22:16:14 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:14 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: reporting.mandatory value: false, 22-10-2016 22:16:14 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:14 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: uilibrary.started value: true, 22-10-2016 22:16:14 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:14 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: uilibrary.mandatory value: false, 22-10-2016 22:16:14 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 10:16 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 10:16 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 10:16 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 10:16 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 10:16 PM:liquibase: Successfully released change log lock 22-10-2016 22:16:14 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: providermanagement.started value: true, 22-10-2016 22:16:14 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:14 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: providermanagement.mandatory value: false, 22-10-2016 22:16:14 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 10:16 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 10:16 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 10:16 PM:liquibase: Successfully released change log lock 22-10-2016 22:16:15 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: emrapi.started value: true, 22-10-2016 22:16:15 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:15 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: emrapi.mandatory value: false, 22-10-2016 22:16:15 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:15 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: reference-data.started value: true, 22-10-2016 22:16:15 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:15 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: reference-data.mandatory value: false, 22-10-2016 22:16:15 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 10:16 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 10:16 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 10:16 PM:liquibase: Successfully released change log lock 22-10-2016 22:16:16 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: bacteriology.started value: true, 22-10-2016 22:16:16 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:16 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: bacteriology.mandatory value: false, 22-10-2016 22:16:16 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 10:16 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 10:16 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 10:16 PM:liquibase: Successfully released change log lock 22-10-2016 22:16:18 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: bahmnicore.started value: true, 22-10-2016 22:16:18 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:18 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: bahmnicore.mandatory value: false, 22-10-2016 22:16:18 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 10:16 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 10:16 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 10:16 PM:liquibase: Successfully released change log lock 22-10-2016 22:16:19 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: elisatomfeedclient.started value: true, 22-10-2016 22:16:19 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:19 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: elisatomfeedclient.mandatory value: false, 22-10-2016 22:16:19 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:19 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: uicommons.started value: true, 22-10-2016 22:16:19 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:19 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: uicommons.mandatory value: false, 22-10-2016 22:16:19 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 10:16 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 10:16 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 10:16 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 10:16 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 10:16 PM:liquibase: Successfully released change log lock 22-10-2016 22:16:19 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: appframework.started value: true, 22-10-2016 22:16:19 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:19 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: appframework.mandatory value: false, 22-10-2016 22:16:19 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:20 [INFO ] api - In method SchedulerService.saveToMemento. Arguments: 22-10-2016 22:16:20 [INFO ] api - Exiting method saveToMemento 22-10-2016 22:16:20 [INFO ] HibernateSessionFactoryBean - Closing Hibernate SessionFactory 22-10-2016 22:16:20 [INFO ] ServiceContext - Refreshing Context 22-10-2016 22:16:30 [INFO ] HibernateSessionFactoryBean - Building new Hibernate SessionFactory 22-10-2016 22:16:46 [INFO ] ServiceContext - Done refreshing Context 22-10-2016 22:16:48 [WARN ] RefillIdentifierPoolsTask - Not running scheduled task. DaemonToken = null; enabled = false 22-10-2016 22:16:54 [INFO ] api - In method UserService.saveRole. Arguments: Role=Privilege Level: Full, 22-10-2016 22:16:54 [INFO ] api - Exiting method saveRole 22-10-2016 22:16:54 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: visits.assignmentHandler value: org.openmrs.module.emrapi.adt.EmrApiVisitAssignmentHandler, 22-10-2016 22:16:54 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:54 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: provider.unknownProviderUuid value: f9badd80-ab76-11e2-9e96-0800200c9a66, 22-10-2016 22:16:54 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:54 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: emr.unknownProvider value: f9badd80-ab76-11e2-9e96-0800200c9a66, 22-10-2016 22:16:54 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:16:54 [INFO ] HibernateContextDAO - Updating the search index... It may take a few minutes. 22-10-2016 22:17:01 [ERROR] OpenElisPatientFailedEventsFeedClientImpl - openelisatomfeedclient:failed feed execution while running failed eventsjava.lang.NullPointerException java.lang.NullPointerException at org.bahmni.module.bahmnicore.properties.BahmniCoreProperties.getProperty(BahmniCoreProperties.java:28) at org.bahmni.module.elisatomfeedclient.api.ElisAtomFeedProperties.getPatientFeedUri(ElisAtomFeedProperties.java:17) at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.getFeedUri(OpenElisPatientFailedEventsFeedClientImpl.java:44) at org.bahmni.module.elisatomfeedclient.api.client.OpenElisFeedClient.getAtomFeedClient(OpenElisFeedClient.java:48) at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.processFailedEvents(OpenElisPatientFailedEventsFeedClientImpl.java:64) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) at java.lang.reflect.Method.invoke(Unknown Source) at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:198) at com.sun.proxy.$Proxy280.processFailedEvents(Unknown Source) at org.bahmni.module.elisatomfeedclient.api.task.OpenElisPatientFeedFailedEventsTask.execute(OpenElisPatientFeedFailedEventsTask.java:13) at org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper.execute(TaskThreadedInitializationWrapper.java:68) at org.openmrs.scheduler.timer.TimerSchedulerTask.execute(TimerSchedulerTask.java:94) at org.openmrs.api.context.Daemon$2.run(Daemon.java:132) 22-10-2016 22:17:01 [ERROR] TimerSchedulerTask - FATAL ERROR: Task [class org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper] failed due to exception [java.lang.RuntimeException] java.lang.RuntimeException: java.lang.NullPointerException at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.processFailedEvents(OpenElisPatientFailedEventsFeedClientImpl.java:75) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) at java.lang.reflect.Method.invoke(Unknown Source) at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:198) at com.sun.proxy.$Proxy280.processFailedEvents(Unknown Source) at org.bahmni.module.elisatomfeedclient.api.task.OpenElisPatientFeedFailedEventsTask.execute(OpenElisPatientFeedFailedEventsTask.java:13) at org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper.execute(TaskThreadedInitializationWrapper.java:68) at org.openmrs.scheduler.timer.TimerSchedulerTask.execute(TimerSchedulerTask.java:94) at org.openmrs.api.context.Daemon$2.run(Daemon.java:132) Caused by: java.lang.NullPointerException at org.bahmni.module.bahmnicore.properties.BahmniCoreProperties.getProperty(BahmniCoreProperties.java:28) at org.bahmni.module.elisatomfeedclient.api.ElisAtomFeedProperties.getPatientFeedUri(ElisAtomFeedProperties.java:17) at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.getFeedUri(OpenElisPatientFailedEventsFeedClientImpl.java:44) at org.bahmni.module.elisatomfeedclient.api.client.OpenElisFeedClient.getAtomFeedClient(OpenElisFeedClient.java:48) at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.processFailedEvents(OpenElisPatientFailedEventsFeedClientImpl.java:64) ... 11 more 22-10-2016 22:17:16 [ERROR] OpenElisPatientFailedEventsFeedClientImpl - openelisatomfeedclient:failed feed execution while running failed eventsjava.lang.NullPointerException java.lang.NullPointerException at org.bahmni.module.bahmnicore.properties.BahmniCoreProperties.getProperty(BahmniCoreProperties.java:28) at org.bahmni.module.elisatomfeedclient.api.ElisAtomFeedProperties.getPatientFeedUri(ElisAtomFeedProperties.java:17) at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.getFeedUri(OpenElisPatientFailedEventsFeedClientImpl.java:44) at org.bahmni.module.elisatomfeedclient.api.client.OpenElisFeedClient.getAtomFeedClient(OpenElisFeedClient.java:48) at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.processFailedEvents(OpenElisPatientFailedEventsFeedClientImpl.java:64) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) at java.lang.reflect.Method.invoke(Unknown Source) at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:198) at com.sun.proxy.$Proxy280.processFailedEvents(Unknown Source) at org.bahmni.module.elisatomfeedclient.api.task.OpenElisPatientFeedFailedEventsTask.execute(OpenElisPatientFeedFailedEventsTask.java:13) at org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper.execute(TaskThreadedInitializationWrapper.java:68) at org.openmrs.scheduler.timer.TimerSchedulerTask.execute(TimerSchedulerTask.java:94) at org.openmrs.api.context.Daemon$2.run(Daemon.java:132) 22-10-2016 22:17:16 [ERROR] TimerSchedulerTask - FATAL ERROR: Task [class org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper] failed due to exception [java.lang.RuntimeException] java.lang.RuntimeException: java.lang.NullPointerException at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.processFailedEvents(OpenElisPatientFailedEventsFeedClientImpl.java:75) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) at java.lang.reflect.Method.invoke(Unknown Source) at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:198) at com.sun.proxy.$Proxy280.processFailedEvents(Unknown Source) at org.bahmni.module.elisatomfeedclient.api.task.OpenElisPatientFeedFailedEventsTask.execute(OpenElisPatientFeedFailedEventsTask.java:13) at org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper.execute(TaskThreadedInitializationWrapper.java:68) at org.openmrs.scheduler.timer.TimerSchedulerTask.execute(TimerSchedulerTask.java:94) at org.openmrs.api.context.Daemon$2.run(Daemon.java:132) Caused by: java.lang.NullPointerException at org.bahmni.module.bahmnicore.properties.BahmniCoreProperties.getProperty(BahmniCoreProperties.java:28) at org.bahmni.module.elisatomfeedclient.api.ElisAtomFeedProperties.getPatientFeedUri(ElisAtomFeedProperties.java:17) at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.getFeedUri(OpenElisPatientFailedEventsFeedClientImpl.java:44) at org.bahmni.module.elisatomfeedclient.api.client.OpenElisFeedClient.getAtomFeedClient(OpenElisFeedClient.java:48) at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.processFailedEvents(OpenElisPatientFailedEventsFeedClientImpl.java:64) ... 11 more 22-10-2016 22:17:18 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: search.indexVersion value: 3, 22-10-2016 22:17:18 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:17:18 [INFO ] HibernateContextDAO - Finished updating the search index 22-10-2016 22:17:19 [WARN ] OpenmrsUtil - Unable to find a runtime properties file at /openmrs-runtime.properties 22-10-2016 22:17:19 [WARN ] OpenmrsUtil - Unable to find a runtime properties file at /openmrs-runtime.properties INFO 10/22/16 10:17 PM:liquibase: Reading from `liquibasechangelog` WARNING 10/22/16 10:17 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 10:17 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 10:17 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case INFO 10/22/16 10:17 PM:liquibase: Reading from `liquibasechangelog` WARNING 10/22/16 10:17 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 10:17 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 10:17 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case Oct 22, 2016 10:17:23 PM org.apache.coyote.AbstractProtocol start INFO: Starting ProtocolHandler ["http-nio-8050"] Oct 22, 2016 10:17:23 PM org.apache.catalina.core.ApplicationContext log INFO: Initializing Spring FrameworkServlet 'openmrs' 22-10-2016 22:30:37 [WARN ] DefaultConnectionTester - SQL State '08007' of Exception tested by statusOnException() implies that the database is invalid, and the pool should refill itself with fresh Connections. com.mysql.jdbc.exceptions.jdbc4.MySQLNonTransientConnectionException: Communications link failure during rollback(). Transaction resolution unknown. at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) at sun.reflect.NativeConstructorAccessorImpl.newInstance(Unknown Source) at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(Unknown Source) at java.lang.reflect.Constructor.newInstance(Unknown Source) at com.mysql.jdbc.Util.handleNewInstance(Util.java:411) at com.mysql.jdbc.Util.getInstance(Util.java:386) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:1015) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:989) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:975) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:920) at com.mysql.jdbc.ConnectionImpl.rollback(ConnectionImpl.java:5084) at com.mchange.v2.c3p0.impl.NewProxyConnection.rollback(NewProxyConnection.java:855) at org.hibernate.transaction.JDBCTransaction.rollbackAndResetAutoCommit(JDBCTransaction.java:213) at org.hibernate.transaction.JDBCTransaction.rollback(JDBCTransaction.java:192) at org.springframework.orm.hibernate3.HibernateTransactionManager.doRollback(HibernateTransactionManager.java:677) at org.springframework.transaction.support.AbstractPlatformTransactionManager.processRollback(AbstractPlatformTransactionManager.java:846) at org.springframework.transaction.support.AbstractPlatformTransactionManager.rollback(AbstractPlatformTransactionManager.java:823) at org.springframework.transaction.support.TransactionTemplate.rollbackOnException(TransactionTemplate.java:162) at org.springframework.transaction.support.TransactionTemplate.execute(TransactionTemplate.java:135) at org.openmrs.module.atomfeed.transaction.support.AtomFeedSpringTransactionManager.executeWithTransaction(AtomFeedSpringTransactionManager.java:37) at org.openmrs.module.atomfeed.scheduler.tasks.EventPublisherTask.execute(EventPublisherTask.java:20) at org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper.execute(TaskThreadedInitializationWrapper.java:68) at org.openmrs.scheduler.timer.TimerSchedulerTask.execute(TimerSchedulerTask.java:94) at org.openmrs.api.context.Daemon$2.run(Daemon.java:132) 22-10-2016 22:30:37 [WARN ] NewPooledConnection - [c3p0] A PooledConnection that has already signalled a Connection error is still in use! 22-10-2016 22:30:37 [WARN ] NewPooledConnection - [c3p0] Another error has occurred [ com.mysql.jdbc.exceptions.jdbc4.MySQLNonTransientConnectionException: Communications link failure during rollback(). Transaction resolution unknown. ] which will not be reported to listeners! com.mysql.jdbc.exceptions.jdbc4.MySQLNonTransientConnectionException: Communications link failure during rollback(). Transaction resolution unknown. at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) at sun.reflect.NativeConstructorAccessorImpl.newInstance(Unknown Source) at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(Unknown Source) at java.lang.reflect.Constructor.newInstance(Unknown Source) at com.mysql.jdbc.Util.handleNewInstance(Util.java:411) at com.mysql.jdbc.Util.getInstance(Util.java:386) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:1015) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:989) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:975) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:920) at com.mysql.jdbc.ConnectionImpl.rollback(ConnectionImpl.java:5084) at com.mchange.v2.c3p0.impl.NewProxyConnection.rollback(NewProxyConnection.java:855) at org.hibernate.transaction.JDBCTransaction.rollbackAndResetAutoCommit(JDBCTransaction.java:213) at org.hibernate.transaction.JDBCTransaction.rollback(JDBCTransaction.java:192) at org.springframework.orm.hibernate3.HibernateTransactionManager.doRollback(HibernateTransactionManager.java:677) at org.springframework.transaction.support.AbstractPlatformTransactionManager.processRollback(AbstractPlatformTransactionManager.java:846) at org.springframework.transaction.support.AbstractPlatformTransactionManager.rollback(AbstractPlatformTransactionManager.java:823) at org.springframework.transaction.support.TransactionTemplate.rollbackOnException(TransactionTemplate.java:162) at org.springframework.transaction.support.TransactionTemplate.execute(TransactionTemplate.java:135) at org.openmrs.module.atomfeed.transaction.support.AtomFeedSpringTransactionManager.executeWithTransaction(AtomFeedSpringTransactionManager.java:37) at org.openmrs.module.atomfeed.scheduler.tasks.EventPublisherTask.execute(EventPublisherTask.java:20) at org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper.execute(TaskThreadedInitializationWrapper.java:68) at org.openmrs.scheduler.timer.TimerSchedulerTask.execute(TimerSchedulerTask.java:94) at org.openmrs.api.context.Daemon$2.run(Daemon.java:132) 22-10-2016 22:30:37 [ERROR] JDBCTransaction - JDBC rollback failed com.mysql.jdbc.exceptions.jdbc4.MySQLNonTransientConnectionException: Communications link failure during rollback(). Transaction resolution unknown. at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) at sun.reflect.NativeConstructorAccessorImpl.newInstance(Unknown Source) at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(Unknown Source) at java.lang.reflect.Constructor.newInstance(Unknown Source) at com.mysql.jdbc.Util.handleNewInstance(Util.java:411) at com.mysql.jdbc.Util.getInstance(Util.java:386) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:1015) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:989) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:975) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:920) at com.mysql.jdbc.ConnectionImpl.rollback(ConnectionImpl.java:5084) at com.mchange.v2.c3p0.impl.NewProxyConnection.rollback(NewProxyConnection.java:855) at org.hibernate.transaction.JDBCTransaction.rollbackAndResetAutoCommit(JDBCTransaction.java:213) at org.hibernate.transaction.JDBCTransaction.rollback(JDBCTransaction.java:192) at org.springframework.orm.hibernate3.HibernateTransactionManager.doRollback(HibernateTransactionManager.java:677) at org.springframework.transaction.support.AbstractPlatformTransactionManager.processRollback(AbstractPlatformTransactionManager.java:846) at org.springframework.transaction.support.AbstractPlatformTransactionManager.rollback(AbstractPlatformTransactionManager.java:823) at org.springframework.transaction.support.TransactionTemplate.rollbackOnException(TransactionTemplate.java:162) at org.springframework.transaction.support.TransactionTemplate.execute(TransactionTemplate.java:135) at org.openmrs.module.atomfeed.transaction.support.AtomFeedSpringTransactionManager.executeWithTransaction(AtomFeedSpringTransactionManager.java:37) at org.openmrs.module.atomfeed.scheduler.tasks.EventPublisherTask.execute(EventPublisherTask.java:20) at org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper.execute(TaskThreadedInitializationWrapper.java:68) at org.openmrs.scheduler.timer.TimerSchedulerTask.execute(TimerSchedulerTask.java:94) at org.openmrs.api.context.Daemon$2.run(Daemon.java:132) 22-10-2016 22:30:37 [WARN ] C3P0PooledConnectionPool - A ConnectionTest has failed, reporting that all previously acquired Connections are likely invalid. The pool will be reset. 22-10-2016 22:30:37 [ERROR] TransactionTemplate - Application exception overridden by rollback exception org.ict4h.atomfeed.server.exceptions.AtomFeedRuntimeException: com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications link failure The last packet successfully received from the server was 3 milliseconds ago. The last packet sent successfully to the server was 2 milliseconds ago. at org.ict4h.atomfeed.server.repository.jdbc.AllEventRecordsQueueJdbcImpl.getAll(AllEventRecordsQueueJdbcImpl.java:103) at org.ict4h.atomfeed.server.service.publisher.EventRecordsPublishingService.publish(EventRecordsPublishingService.java:14) at org.openmrs.module.atomfeed.scheduler.tasks.EventPublisherTask$1.doInTransaction(EventPublisherTask.java:25) at org.ict4h.atomfeed.transaction.AFTransactionWorkWithoutResult.execute(AFTransactionWorkWithoutResult.java:6) at org.openmrs.module.atomfeed.transaction.support.AtomFeedSpringTransactionManager$1.doInTransaction(AtomFeedSpringTransactionManager.java:40) at org.springframework.transaction.support.TransactionTemplate.execute(TransactionTemplate.java:131) at org.openmrs.module.atomfeed.transaction.support.AtomFeedSpringTransactionManager.executeWithTransaction(AtomFeedSpringTransactionManager.java:37) at org.openmrs.module.atomfeed.scheduler.tasks.EventPublisherTask.execute(EventPublisherTask.java:20) at org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper.execute(TaskThreadedInitializationWrapper.java:68) at org.openmrs.scheduler.timer.TimerSchedulerTask.execute(TimerSchedulerTask.java:94) at org.openmrs.api.context.Daemon$2.run(Daemon.java:132) Caused by: com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications link failure The last packet successfully received from the server was 3 milliseconds ago. The last packet sent successfully to the server was 2 milliseconds ago. at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) at sun.reflect.NativeConstructorAccessorImpl.newInstance(Unknown Source) at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(Unknown Source) at java.lang.reflect.Constructor.newInstance(Unknown Source) at com.mysql.jdbc.Util.handleNewInstance(Util.java:411) at com.mysql.jdbc.SQLError.createCommunicationsException(SQLError.java:1129) at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3720) at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3609) at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:4160) at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:2617) at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2778) at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2825) at com.mysql.jdbc.PreparedStatement.executeInternal(PreparedStatement.java:2156) at com.mysql.jdbc.PreparedStatement.executeQuery(PreparedStatement.java:2313) at com.mchange.v2.c3p0.impl.NewProxyPreparedStatement.executeQuery(NewProxyPreparedStatement.java:76) at org.ict4h.atomfeed.server.repository.jdbc.AllEventRecordsQueueJdbcImpl.getAll(AllEventRecordsQueueJdbcImpl.java:100) ... 10 more Caused by: java.io.EOFException: Can not read response from server. Expected to read 4 bytes, read 0 bytes before connection was unexpectedly lost. at com.mysql.jdbc.MysqlIO.readFully(MysqlIO.java:3166) at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3620) ... 19 more 22-10-2016 22:30:37 [ERROR] TimerSchedulerTask - FATAL ERROR: Task [class org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper] failed due to exception [org.springframework.transaction.TransactionSystemException] org.springframework.transaction.TransactionSystemException: Could not roll back Hibernate transaction; nested exception is org.hibernate.TransactionException: JDBC rollback failed at org.springframework.orm.hibernate3.HibernateTransactionManager.doRollback(HibernateTransactionManager.java:680) at org.springframework.transaction.support.AbstractPlatformTransactionManager.processRollback(AbstractPlatformTransactionManager.java:846) at org.springframework.transaction.support.AbstractPlatformTransactionManager.rollback(AbstractPlatformTransactionManager.java:823) at org.springframework.transaction.support.TransactionTemplate.rollbackOnException(TransactionTemplate.java:162) at org.springframework.transaction.support.TransactionTemplate.execute(TransactionTemplate.java:135) at org.openmrs.module.atomfeed.transaction.support.AtomFeedSpringTransactionManager.executeWithTransaction(AtomFeedSpringTransactionManager.java:37) at org.openmrs.module.atomfeed.scheduler.tasks.EventPublisherTask.execute(EventPublisherTask.java:20) at org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper.execute(TaskThreadedInitializationWrapper.java:68) at org.openmrs.scheduler.timer.TimerSchedulerTask.execute(TimerSchedulerTask.java:94) at org.openmrs.api.context.Daemon$2.run(Daemon.java:132) Caused by: org.hibernate.TransactionException: JDBC rollback failed at org.hibernate.transaction.JDBCTransaction.rollback(JDBCTransaction.java:200) at org.springframework.orm.hibernate3.HibernateTransactionManager.doRollback(HibernateTransactionManager.java:677) ... 9 more Caused by: com.mysql.jdbc.exceptions.jdbc4.MySQLNonTransientConnectionException: Communications link failure during rollback(). Transaction resolution unknown. at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) at sun.reflect.NativeConstructorAccessorImpl.newInstance(Unknown Source) at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(Unknown Source) at java.lang.reflect.Constructor.newInstance(Unknown Source) at com.mysql.jdbc.Util.handleNewInstance(Util.java:411) at com.mysql.jdbc.Util.getInstance(Util.java:386) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:1015) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:989) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:975) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:920) at com.mysql.jdbc.ConnectionImpl.rollback(ConnectionImpl.java:5084) at com.mchange.v2.c3p0.impl.NewProxyConnection.rollback(NewProxyConnection.java:855) at org.hibernate.transaction.JDBCTransaction.rollbackAndResetAutoCommit(JDBCTransaction.java:213) at org.hibernate.transaction.JDBCTransaction.rollback(JDBCTransaction.java:192) ... 10 more 22-10-2016 22:30:42 [WARN ] ManagementContext - Failed to stop jmx connector: Cannot bind to URL: javax.naming.CommunicationException [Root exception is java.rmi.ConnectIOException: Exception creating connection to: localhost; nested exception is: java.net.SocketException: Network is unreachable] Oct 22, 2016 10:31:06 PM org.apache.catalina.core.StandardContext setPath WARNING: A context path must either be an empty string or start with a '/'. The path [openmrs] does not meet these criteria and has been changed to [/openmrs] Oct 22, 2016 10:31:08 PM org.apache.coyote.AbstractProtocol init INFO: Initializing ProtocolHandler ["http-nio-8050"] Oct 22, 2016 10:31:08 PM org.apache.tomcat.util.net.NioSelectorPool getSharedSelector INFO: Using a shared selector for servlet write/read Oct 22, 2016 10:31:08 PM org.apache.catalina.core.StandardService startInternal INFO: Starting service Tomcat Oct 22, 2016 10:31:08 PM org.apache.catalina.core.StandardEngine startInternal INFO: Starting Servlet Engine: Apache Tomcat/8.0.12 Oct 22, 2016 10:31:08 PM org.apache.catalina.startup.SetContextPropertiesRule begin WARNING: [SetContextPropertiesRule]{Context} Setting property 'antiJARLocking' to 'true' did not find a matching property. Oct 22, 2016 10:31:10 PM org.apache.catalina.startup.ContextConfig getDefaultWebXmlFragment INFO: No global web.xml found Oct 22, 2016 10:31:50 PM org.apache.catalina.core.ApplicationContext log INFO: No Spring WebApplicationInitializer types detected on classpath Oct 22, 2016 10:31:51 PM org.apache.catalina.core.ApplicationContext log INFO: Set web app root system property: 'webapp.root' = [/opt/openmrs/openmrs] Oct 22, 2016 10:31:51 PM org.apache.catalina.core.ApplicationContext log INFO: Initializing log4j from [classpath:log4j.xml] 22-10-2016 22:31:52 [WARN ] OpenmrsUtil - Unable to find a runtime properties file at /openmrs-runtime.properties INFO 10/22/16 10:31 PM:liquibase: Reading from `liquibasechangelog` WARNING 10/22/16 10:31 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 10:31 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 10:31 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case 22-10-2016 22:32:20 [WARN ] Listener - Bundled module folder doesn't exist: /opt/openmrs/openmrs/WEB-INF/bundledModules 22-10-2016 22:32:20 [WARN ] OpenmrsUtil - Unable to find a runtime properties file at /openmrs-runtime.properties INFO 10/22/16 10:32 PM:liquibase: Reading from `liquibasechangelog` WARNING 10/22/16 10:32 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 10:32 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 10:32 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case INFO 10/22/16 10:32 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 10:32 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 10:32 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 10:32 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 10:32 PM:liquibase: Successfully released change log lock 22-10-2016 22:32:28 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: uiframework.started value: true, 22-10-2016 22:32:28 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:32:28 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: uiframework.mandatory value: false, 22-10-2016 22:32:28 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:32:28 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: htmlwidgets.started value: true, 22-10-2016 22:32:28 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:32:28 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: htmlwidgets.mandatory value: false, 22-10-2016 22:32:28 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 10:32 PM:liquibase: Reading from `liquibasechangelog` WARNING 10/22/16 10:32 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 10:32 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case INFO 10/22/16 10:32 PM:liquibase: Successfully acquired change log lock WARNING 10/22/16 10:32 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 10:32 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case INFO 10/22/16 10:32 PM:liquibase: Successfully released change log lock 22-10-2016 22:32:29 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: openmrs-atomfeed.started value: true, 22-10-2016 22:32:29 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:32:29 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: openmrs-atomfeed.mandatory value: false, 22-10-2016 22:32:29 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 10:32 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 10:32 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 10:32 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 10:32 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 10:32 PM:liquibase: Successfully released change log lock 22-10-2016 22:32:29 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: calculation.started value: true, 22-10-2016 22:32:29 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:32:29 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: calculation.mandatory value: false, 22-10-2016 22:32:29 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:32:29 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: rulesengine.started value: true, 22-10-2016 22:32:29 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:32:29 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: rulesengine.mandatory value: false, 22-10-2016 22:32:29 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 10:32 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 10:32 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 10:32 PM:liquibase: Successfully released change log lock 22-10-2016 22:32:30 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: episodes.started value: true, 22-10-2016 22:32:30 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:32:30 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: episodes.mandatory value: false, 22-10-2016 22:32:30 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:32:30 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: webservices.rest.started value: true, 22-10-2016 22:32:30 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:32:30 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: webservices.rest.mandatory value: false, 22-10-2016 22:32:30 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 10:32 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 10:32 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 10:32 PM:liquibase: Successfully released change log lock 22-10-2016 22:32:30 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: bedmanagement.started value: true, 22-10-2016 22:32:30 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:32:30 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: bedmanagement.mandatory value: false, 22-10-2016 22:32:30 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:32:31 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: idgen.started value: true, 22-10-2016 22:32:31 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:32:31 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: idgen.mandatory value: false, 22-10-2016 22:32:31 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:32:31 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: idgen-webservices.started value: true, 22-10-2016 22:32:31 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:32:31 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: idgen-webservices.mandatory value: false, 22-10-2016 22:32:31 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:32:31 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: addresshierarchy.started value: true, 22-10-2016 22:32:31 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:32:31 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: addresshierarchy.mandatory value: false, 22-10-2016 22:32:31 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:32:32 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: event.started value: true, 22-10-2016 22:32:32 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:32:32 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: event.mandatory value: false, 22-10-2016 22:32:32 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 10:32 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 10:32 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 10:32 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 10:32 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 10:32 PM:liquibase: Successfully released change log lock 22-10-2016 22:32:32 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: metadatamapping.started value: true, 22-10-2016 22:32:32 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:32:32 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: metadatamapping.mandatory value: false, 22-10-2016 22:32:32 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 10:32 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 10:32 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 10:32 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 10:32 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 10:32 PM:liquibase: Successfully released change log lock 22-10-2016 22:32:33 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: metadatasharing.started value: true, 22-10-2016 22:32:33 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:32:33 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: metadatasharing.mandatory value: false, 22-10-2016 22:32:33 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:32:33 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: serialization.xstream.started value: true, 22-10-2016 22:32:33 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:32:33 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: serialization.xstream.mandatory value: false, 22-10-2016 22:32:33 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 10:32 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 10:32 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 10:32 PM:liquibase: Successfully released change log lock 22-10-2016 22:32:34 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: reporting.started value: true, 22-10-2016 22:32:34 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:32:34 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: reporting.mandatory value: false, 22-10-2016 22:32:34 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:32:34 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: uilibrary.started value: true, 22-10-2016 22:32:34 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:32:34 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: uilibrary.mandatory value: false, 22-10-2016 22:32:34 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 10:32 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 10:32 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 10:32 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 10:32 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 10:32 PM:liquibase: Successfully released change log lock 22-10-2016 22:32:34 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: providermanagement.started value: true, 22-10-2016 22:32:34 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:32:34 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: providermanagement.mandatory value: false, 22-10-2016 22:32:34 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 10:32 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 10:32 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 10:32 PM:liquibase: Successfully released change log lock 22-10-2016 22:32:35 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: emrapi.started value: true, 22-10-2016 22:32:35 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:32:35 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: emrapi.mandatory value: false, 22-10-2016 22:32:35 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:32:35 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: reference-data.started value: true, 22-10-2016 22:32:35 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:32:35 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: reference-data.mandatory value: false, 22-10-2016 22:32:35 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 10:32 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 10:32 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 10:32 PM:liquibase: Successfully released change log lock 22-10-2016 22:32:35 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: bacteriology.started value: true, 22-10-2016 22:32:35 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:32:35 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: bacteriology.mandatory value: false, 22-10-2016 22:32:35 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 10:32 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 10:32 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 10:32 PM:liquibase: Successfully released change log lock 22-10-2016 22:32:38 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: bahmnicore.started value: true, 22-10-2016 22:32:38 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:32:38 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: bahmnicore.mandatory value: false, 22-10-2016 22:32:38 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 10:32 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 10:32 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 10:32 PM:liquibase: Successfully released change log lock 22-10-2016 22:32:39 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: elisatomfeedclient.started value: true, 22-10-2016 22:32:39 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:32:39 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: elisatomfeedclient.mandatory value: false, 22-10-2016 22:32:39 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:32:39 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: uicommons.started value: true, 22-10-2016 22:32:39 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:32:39 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: uicommons.mandatory value: false, 22-10-2016 22:32:39 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 10:32 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 10:32 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 10:32 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 10:32 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 10:32 PM:liquibase: Successfully released change log lock 22-10-2016 22:32:39 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: appframework.started value: true, 22-10-2016 22:32:39 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:32:39 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: appframework.mandatory value: false, 22-10-2016 22:32:39 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:32:40 [INFO ] api - In method SchedulerService.saveToMemento. Arguments: 22-10-2016 22:32:40 [INFO ] api - Exiting method saveToMemento 22-10-2016 22:32:40 [INFO ] HibernateSessionFactoryBean - Closing Hibernate SessionFactory 22-10-2016 22:32:40 [INFO ] ServiceContext - Refreshing Context 22-10-2016 22:32:50 [INFO ] HibernateSessionFactoryBean - Building new Hibernate SessionFactory 22-10-2016 22:33:05 [WARN ] RefillIdentifierPoolsTask - Not running scheduled task. DaemonToken = null; enabled = false 22-10-2016 22:33:06 [INFO ] ServiceContext - Done refreshing Context 22-10-2016 22:33:14 [INFO ] api - In method UserService.saveRole. Arguments: Role=Privilege Level: Full, 22-10-2016 22:33:14 [INFO ] api - Exiting method saveRole 22-10-2016 22:33:14 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: visits.assignmentHandler value: org.openmrs.module.emrapi.adt.EmrApiVisitAssignmentHandler, 22-10-2016 22:33:14 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:33:14 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: provider.unknownProviderUuid value: f9badd80-ab76-11e2-9e96-0800200c9a66, 22-10-2016 22:33:14 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:33:14 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: emr.unknownProvider value: f9badd80-ab76-11e2-9e96-0800200c9a66, 22-10-2016 22:33:14 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:33:14 [INFO ] HibernateContextDAO - Updating the search index... It may take a few minutes. 22-10-2016 22:33:16 [ERROR] OpenElisPatientFailedEventsFeedClientImpl - openelisatomfeedclient:failed feed execution while running failed eventsjava.lang.NullPointerException java.lang.NullPointerException at org.bahmni.module.bahmnicore.properties.BahmniCoreProperties.getProperty(BahmniCoreProperties.java:28) at org.bahmni.module.elisatomfeedclient.api.ElisAtomFeedProperties.getPatientFeedUri(ElisAtomFeedProperties.java:17) at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.getFeedUri(OpenElisPatientFailedEventsFeedClientImpl.java:44) at org.bahmni.module.elisatomfeedclient.api.client.OpenElisFeedClient.getAtomFeedClient(OpenElisFeedClient.java:48) at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.processFailedEvents(OpenElisPatientFailedEventsFeedClientImpl.java:64) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) at java.lang.reflect.Method.invoke(Unknown Source) at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:198) at com.sun.proxy.$Proxy269.processFailedEvents(Unknown Source) at org.bahmni.module.elisatomfeedclient.api.task.OpenElisPatientFeedFailedEventsTask.execute(OpenElisPatientFeedFailedEventsTask.java:13) at org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper.execute(TaskThreadedInitializationWrapper.java:68) at org.openmrs.scheduler.timer.TimerSchedulerTask.execute(TimerSchedulerTask.java:94) at org.openmrs.api.context.Daemon$2.run(Daemon.java:132) 22-10-2016 22:33:16 [ERROR] TimerSchedulerTask - FATAL ERROR: Task [class org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper] failed due to exception [java.lang.RuntimeException] java.lang.RuntimeException: java.lang.NullPointerException at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.processFailedEvents(OpenElisPatientFailedEventsFeedClientImpl.java:75) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) at java.lang.reflect.Method.invoke(Unknown Source) at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:198) at com.sun.proxy.$Proxy269.processFailedEvents(Unknown Source) at org.bahmni.module.elisatomfeedclient.api.task.OpenElisPatientFeedFailedEventsTask.execute(OpenElisPatientFeedFailedEventsTask.java:13) at org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper.execute(TaskThreadedInitializationWrapper.java:68) at org.openmrs.scheduler.timer.TimerSchedulerTask.execute(TimerSchedulerTask.java:94) at org.openmrs.api.context.Daemon$2.run(Daemon.java:132) Caused by: java.lang.NullPointerException at org.bahmni.module.bahmnicore.properties.BahmniCoreProperties.getProperty(BahmniCoreProperties.java:28) at org.bahmni.module.elisatomfeedclient.api.ElisAtomFeedProperties.getPatientFeedUri(ElisAtomFeedProperties.java:17) at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.getFeedUri(OpenElisPatientFailedEventsFeedClientImpl.java:44) at org.bahmni.module.elisatomfeedclient.api.client.OpenElisFeedClient.getAtomFeedClient(OpenElisFeedClient.java:48) at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.processFailedEvents(OpenElisPatientFailedEventsFeedClientImpl.java:64) ... 11 more 22-10-2016 22:33:31 [ERROR] OpenElisPatientFailedEventsFeedClientImpl - openelisatomfeedclient:failed feed execution while running failed eventsjava.lang.NullPointerException java.lang.NullPointerException at org.bahmni.module.bahmnicore.properties.BahmniCoreProperties.getProperty(BahmniCoreProperties.java:28) at org.bahmni.module.elisatomfeedclient.api.ElisAtomFeedProperties.getPatientFeedUri(ElisAtomFeedProperties.java:17) at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.getFeedUri(OpenElisPatientFailedEventsFeedClientImpl.java:44) at org.bahmni.module.elisatomfeedclient.api.client.OpenElisFeedClient.getAtomFeedClient(OpenElisFeedClient.java:48) at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.processFailedEvents(OpenElisPatientFailedEventsFeedClientImpl.java:64) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) at java.lang.reflect.Method.invoke(Unknown Source) at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:198) at com.sun.proxy.$Proxy269.processFailedEvents(Unknown Source) at org.bahmni.module.elisatomfeedclient.api.task.OpenElisPatientFeedFailedEventsTask.execute(OpenElisPatientFeedFailedEventsTask.java:13) at org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper.execute(TaskThreadedInitializationWrapper.java:68) at org.openmrs.scheduler.timer.TimerSchedulerTask.execute(TimerSchedulerTask.java:94) at org.openmrs.api.context.Daemon$2.run(Daemon.java:132) 22-10-2016 22:33:31 [ERROR] TimerSchedulerTask - FATAL ERROR: Task [class org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper] failed due to exception [java.lang.RuntimeException] java.lang.RuntimeException: java.lang.NullPointerException at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.processFailedEvents(OpenElisPatientFailedEventsFeedClientImpl.java:75) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) at java.lang.reflect.Method.invoke(Unknown Source) at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:198) at com.sun.proxy.$Proxy269.processFailedEvents(Unknown Source) at org.bahmni.module.elisatomfeedclient.api.task.OpenElisPatientFeedFailedEventsTask.execute(OpenElisPatientFeedFailedEventsTask.java:13) at org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper.execute(TaskThreadedInitializationWrapper.java:68) at org.openmrs.scheduler.timer.TimerSchedulerTask.execute(TimerSchedulerTask.java:94) at org.openmrs.api.context.Daemon$2.run(Daemon.java:132) Caused by: java.lang.NullPointerException at org.bahmni.module.bahmnicore.properties.BahmniCoreProperties.getProperty(BahmniCoreProperties.java:28) at org.bahmni.module.elisatomfeedclient.api.ElisAtomFeedProperties.getPatientFeedUri(ElisAtomFeedProperties.java:17) at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.getFeedUri(OpenElisPatientFailedEventsFeedClientImpl.java:44) at org.bahmni.module.elisatomfeedclient.api.client.OpenElisFeedClient.getAtomFeedClient(OpenElisFeedClient.java:48) at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.processFailedEvents(OpenElisPatientFailedEventsFeedClientImpl.java:64) ... 11 more 22-10-2016 22:33:38 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: search.indexVersion value: 3, 22-10-2016 22:33:38 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:33:38 [INFO ] HibernateContextDAO - Finished updating the search index 22-10-2016 22:33:38 [WARN ] OpenmrsUtil - Unable to find a runtime properties file at /openmrs-runtime.properties 22-10-2016 22:33:39 [WARN ] OpenmrsUtil - Unable to find a runtime properties file at /openmrs-runtime.properties INFO 10/22/16 10:33 PM:liquibase: Reading from `liquibasechangelog` WARNING 10/22/16 10:33 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 10:33 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 10:33 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case INFO 10/22/16 10:33 PM:liquibase: Reading from `liquibasechangelog` WARNING 10/22/16 10:33 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 10:33 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 10:33 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case Oct 22, 2016 10:33:43 PM org.apache.coyote.AbstractProtocol start INFO: Starting ProtocolHandler ["http-nio-8050"] Oct 22, 2016 10:33:43 PM org.apache.catalina.core.ApplicationContext log INFO: Initializing Spring FrameworkServlet 'openmrs' 22-10-2016 22:33:48 [WARN ] DefaultConnectionTester - SQL State '08007' of Exception tested by statusOnException() implies that the database is invalid, and the pool should refill itself with fresh Connections. com.mysql.jdbc.exceptions.jdbc4.MySQLNonTransientConnectionException: Communications link failure during rollback(). Transaction resolution unknown. at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) at sun.reflect.NativeConstructorAccessorImpl.newInstance(Unknown Source) at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(Unknown Source) at java.lang.reflect.Constructor.newInstance(Unknown Source) at com.mysql.jdbc.Util.handleNewInstance(Util.java:411) at com.mysql.jdbc.Util.getInstance(Util.java:386) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:1015) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:989) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:975) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:920) at com.mysql.jdbc.ConnectionImpl.rollback(ConnectionImpl.java:5084) at com.mchange.v2.c3p0.impl.NewProxyConnection.rollback(NewProxyConnection.java:855) at org.hibernate.transaction.JDBCTransaction.rollbackAndResetAutoCommit(JDBCTransaction.java:213) at org.hibernate.transaction.JDBCTransaction.rollback(JDBCTransaction.java:192) at org.springframework.orm.hibernate3.HibernateTransactionManager.doRollback(HibernateTransactionManager.java:677) at org.springframework.transaction.support.AbstractPlatformTransactionManager.processRollback(AbstractPlatformTransactionManager.java:846) at org.springframework.transaction.support.AbstractPlatformTransactionManager.rollback(AbstractPlatformTransactionManager.java:823) at org.springframework.transaction.support.TransactionTemplate.rollbackOnException(TransactionTemplate.java:162) at org.springframework.transaction.support.TransactionTemplate.execute(TransactionTemplate.java:135) at org.openmrs.module.atomfeed.transaction.support.AtomFeedSpringTransactionManager.executeWithTransaction(AtomFeedSpringTransactionManager.java:37) at org.openmrs.module.atomfeed.scheduler.tasks.EventPublisherTask.execute(EventPublisherTask.java:20) at org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper.execute(TaskThreadedInitializationWrapper.java:68) at org.openmrs.scheduler.timer.TimerSchedulerTask.execute(TimerSchedulerTask.java:94) at org.openmrs.api.context.Daemon$2.run(Daemon.java:132) 22-10-2016 22:33:48 [WARN ] NewPooledConnection - [c3p0] A PooledConnection that has already signalled a Connection error is still in use! 22-10-2016 22:33:48 [WARN ] NewPooledConnection - [c3p0] Another error has occurred [ com.mysql.jdbc.exceptions.jdbc4.MySQLNonTransientConnectionException: Communications link failure during rollback(). Transaction resolution unknown. ] which will not be reported to listeners! com.mysql.jdbc.exceptions.jdbc4.MySQLNonTransientConnectionException: Communications link failure during rollback(). Transaction resolution unknown. at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) at sun.reflect.NativeConstructorAccessorImpl.newInstance(Unknown Source) at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(Unknown Source) at java.lang.reflect.Constructor.newInstance(Unknown Source) at com.mysql.jdbc.Util.handleNewInstance(Util.java:411) at com.mysql.jdbc.Util.getInstance(Util.java:386) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:1015) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:989) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:975) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:920) at com.mysql.jdbc.ConnectionImpl.rollback(ConnectionImpl.java:5084) at com.mchange.v2.c3p0.impl.NewProxyConnection.rollback(NewProxyConnection.java:855) at org.hibernate.transaction.JDBCTransaction.rollbackAndResetAutoCommit(JDBCTransaction.java:213) at org.hibernate.transaction.JDBCTransaction.rollback(JDBCTransaction.java:192) at org.springframework.orm.hibernate3.HibernateTransactionManager.doRollback(HibernateTransactionManager.java:677) at org.springframework.transaction.support.AbstractPlatformTransactionManager.processRollback(AbstractPlatformTransactionManager.java:846) at org.springframework.transaction.support.AbstractPlatformTransactionManager.rollback(AbstractPlatformTransactionManager.java:823) at org.springframework.transaction.support.TransactionTemplate.rollbackOnException(TransactionTemplate.java:162) at org.springframework.transaction.support.TransactionTemplate.execute(TransactionTemplate.java:135) at org.openmrs.module.atomfeed.transaction.support.AtomFeedSpringTransactionManager.executeWithTransaction(AtomFeedSpringTransactionManager.java:37) at org.openmrs.module.atomfeed.scheduler.tasks.EventPublisherTask.execute(EventPublisherTask.java:20) at org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper.execute(TaskThreadedInitializationWrapper.java:68) at org.openmrs.scheduler.timer.TimerSchedulerTask.execute(TimerSchedulerTask.java:94) at org.openmrs.api.context.Daemon$2.run(Daemon.java:132) 22-10-2016 22:33:48 [ERROR] JDBCTransaction - JDBC rollback failed com.mysql.jdbc.exceptions.jdbc4.MySQLNonTransientConnectionException: Communications link failure during rollback(). Transaction resolution unknown. at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) at sun.reflect.NativeConstructorAccessorImpl.newInstance(Unknown Source) at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(Unknown Source) at java.lang.reflect.Constructor.newInstance(Unknown Source) at com.mysql.jdbc.Util.handleNewInstance(Util.java:411) at com.mysql.jdbc.Util.getInstance(Util.java:386) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:1015) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:989) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:975) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:920) at com.mysql.jdbc.ConnectionImpl.rollback(ConnectionImpl.java:5084) at com.mchange.v2.c3p0.impl.NewProxyConnection.rollback(NewProxyConnection.java:855) at org.hibernate.transaction.JDBCTransaction.rollbackAndResetAutoCommit(JDBCTransaction.java:213) at org.hibernate.transaction.JDBCTransaction.rollback(JDBCTransaction.java:192) at org.springframework.orm.hibernate3.HibernateTransactionManager.doRollback(HibernateTransactionManager.java:677) at org.springframework.transaction.support.AbstractPlatformTransactionManager.processRollback(AbstractPlatformTransactionManager.java:846) at org.springframework.transaction.support.AbstractPlatformTransactionManager.rollback(AbstractPlatformTransactionManager.java:823) at org.springframework.transaction.support.TransactionTemplate.rollbackOnException(TransactionTemplate.java:162) at org.springframework.transaction.support.TransactionTemplate.execute(TransactionTemplate.java:135) at org.openmrs.module.atomfeed.transaction.support.AtomFeedSpringTransactionManager.executeWithTransaction(AtomFeedSpringTransactionManager.java:37) at org.openmrs.module.atomfeed.scheduler.tasks.EventPublisherTask.execute(EventPublisherTask.java:20) at org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper.execute(TaskThreadedInitializationWrapper.java:68) at org.openmrs.scheduler.timer.TimerSchedulerTask.execute(TimerSchedulerTask.java:94) at org.openmrs.api.context.Daemon$2.run(Daemon.java:132) 22-10-2016 22:33:48 [WARN ] C3P0PooledConnectionPool - A ConnectionTest has failed, reporting that all previously acquired Connections are likely invalid. The pool will be reset. 22-10-2016 22:33:48 [ERROR] TransactionTemplate - Application exception overridden by rollback exception org.ict4h.atomfeed.server.exceptions.AtomFeedRuntimeException: com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications link failure The last packet successfully received from the server was 3 milliseconds ago. The last packet sent successfully to the server was 2 milliseconds ago. at org.ict4h.atomfeed.server.repository.jdbc.AllEventRecordsQueueJdbcImpl.getAll(AllEventRecordsQueueJdbcImpl.java:103) at org.ict4h.atomfeed.server.service.publisher.EventRecordsPublishingService.publish(EventRecordsPublishingService.java:14) at org.openmrs.module.atomfeed.scheduler.tasks.EventPublisherTask$1.doInTransaction(EventPublisherTask.java:25) at org.ict4h.atomfeed.transaction.AFTransactionWorkWithoutResult.execute(AFTransactionWorkWithoutResult.java:6) at org.openmrs.module.atomfeed.transaction.support.AtomFeedSpringTransactionManager$1.doInTransaction(AtomFeedSpringTransactionManager.java:40) at org.springframework.transaction.support.TransactionTemplate.execute(TransactionTemplate.java:131) at org.openmrs.module.atomfeed.transaction.support.AtomFeedSpringTransactionManager.executeWithTransaction(AtomFeedSpringTransactionManager.java:37) at org.openmrs.module.atomfeed.scheduler.tasks.EventPublisherTask.execute(EventPublisherTask.java:20) at org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper.execute(TaskThreadedInitializationWrapper.java:68) at org.openmrs.scheduler.timer.TimerSchedulerTask.execute(TimerSchedulerTask.java:94) at org.openmrs.api.context.Daemon$2.run(Daemon.java:132) Caused by: com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications link failure The last packet successfully received from the server was 3 milliseconds ago. The last packet sent successfully to the server was 2 milliseconds ago. at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) at sun.reflect.NativeConstructorAccessorImpl.newInstance(Unknown Source) at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(Unknown Source) at java.lang.reflect.Constructor.newInstance(Unknown Source) at com.mysql.jdbc.Util.handleNewInstance(Util.java:411) at com.mysql.jdbc.SQLError.createCommunicationsException(SQLError.java:1129) at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3720) at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3609) at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:4160) at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:2617) at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2778) at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2825) at com.mysql.jdbc.PreparedStatement.executeInternal(PreparedStatement.java:2156) at com.mysql.jdbc.PreparedStatement.executeQuery(PreparedStatement.java:2313) at com.mchange.v2.c3p0.impl.NewProxyPreparedStatement.executeQuery(NewProxyPreparedStatement.java:76) at org.ict4h.atomfeed.server.repository.jdbc.AllEventRecordsQueueJdbcImpl.getAll(AllEventRecordsQueueJdbcImpl.java:100) ... 10 more Caused by: java.io.EOFException: Can not read response from server. Expected to read 4 bytes, read 0 bytes before connection was unexpectedly lost. at com.mysql.jdbc.MysqlIO.readFully(MysqlIO.java:3166) at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3620) ... 19 more 22-10-2016 22:33:48 [ERROR] TimerSchedulerTask - FATAL ERROR: Task [class org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper] failed due to exception [org.springframework.transaction.TransactionSystemException] org.springframework.transaction.TransactionSystemException: Could not roll back Hibernate transaction; nested exception is org.hibernate.TransactionException: JDBC rollback failed at org.springframework.orm.hibernate3.HibernateTransactionManager.doRollback(HibernateTransactionManager.java:680) at org.springframework.transaction.support.AbstractPlatformTransactionManager.processRollback(AbstractPlatformTransactionManager.java:846) at org.springframework.transaction.support.AbstractPlatformTransactionManager.rollback(AbstractPlatformTransactionManager.java:823) at org.springframework.transaction.support.TransactionTemplate.rollbackOnException(TransactionTemplate.java:162) at org.springframework.transaction.support.TransactionTemplate.execute(TransactionTemplate.java:135) at org.openmrs.module.atomfeed.transaction.support.AtomFeedSpringTransactionManager.executeWithTransaction(AtomFeedSpringTransactionManager.java:37) at org.openmrs.module.atomfeed.scheduler.tasks.EventPublisherTask.execute(EventPublisherTask.java:20) at org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper.execute(TaskThreadedInitializationWrapper.java:68) at org.openmrs.scheduler.timer.TimerSchedulerTask.execute(TimerSchedulerTask.java:94) at org.openmrs.api.context.Daemon$2.run(Daemon.java:132) Caused by: org.hibernate.TransactionException: JDBC rollback failed at org.hibernate.transaction.JDBCTransaction.rollback(JDBCTransaction.java:200) at org.springframework.orm.hibernate3.HibernateTransactionManager.doRollback(HibernateTransactionManager.java:677) ... 9 more Caused by: com.mysql.jdbc.exceptions.jdbc4.MySQLNonTransientConnectionException: Communications link failure during rollback(). Transaction resolution unknown. at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method) at sun.reflect.NativeConstructorAccessorImpl.newInstance(Unknown Source) at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(Unknown Source) at java.lang.reflect.Constructor.newInstance(Unknown Source) at com.mysql.jdbc.Util.handleNewInstance(Util.java:411) at com.mysql.jdbc.Util.getInstance(Util.java:386) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:1015) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:989) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:975) at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:920) at com.mysql.jdbc.ConnectionImpl.rollback(ConnectionImpl.java:5084) at com.mchange.v2.c3p0.impl.NewProxyConnection.rollback(NewProxyConnection.java:855) at org.hibernate.transaction.JDBCTransaction.rollbackAndResetAutoCommit(JDBCTransaction.java:213) at org.hibernate.transaction.JDBCTransaction.rollback(JDBCTransaction.java:192) ... 10 more 22-10-2016 22:33:53 [WARN ] ManagementContext - Failed to stop jmx connector: Cannot bind to URL: javax.naming.CommunicationException [Root exception is java.rmi.ConnectIOException: Exception creating connection to: localhost; nested exception is: java.net.SocketException: Network is unreachable] Oct 22, 2016 10:34:18 PM org.apache.catalina.core.StandardContext setPath WARNING: A context path must either be an empty string or start with a '/'. The path [openmrs] does not meet these criteria and has been changed to [/openmrs] Oct 22, 2016 10:34:20 PM org.apache.coyote.AbstractProtocol init INFO: Initializing ProtocolHandler ["http-nio-8050"] Oct 22, 2016 10:34:20 PM org.apache.tomcat.util.net.NioSelectorPool getSharedSelector INFO: Using a shared selector for servlet write/read Oct 22, 2016 10:34:20 PM org.apache.catalina.core.StandardService startInternal INFO: Starting service Tomcat Oct 22, 2016 10:34:20 PM org.apache.catalina.core.StandardEngine startInternal INFO: Starting Servlet Engine: Apache Tomcat/8.0.12 Oct 22, 2016 10:34:20 PM org.apache.catalina.startup.SetContextPropertiesRule begin WARNING: [SetContextPropertiesRule]{Context} Setting property 'antiJARLocking' to 'true' did not find a matching property. Oct 22, 2016 10:34:21 PM org.apache.catalina.startup.ContextConfig getDefaultWebXmlFragment INFO: No global web.xml found Oct 22, 2016 10:35:06 PM org.apache.catalina.core.ApplicationContext log INFO: No Spring WebApplicationInitializer types detected on classpath Oct 22, 2016 10:35:06 PM org.apache.catalina.core.ApplicationContext log INFO: Set web app root system property: 'webapp.root' = [/opt/openmrs/openmrs] Oct 22, 2016 10:35:06 PM org.apache.catalina.core.ApplicationContext log INFO: Initializing log4j from [classpath:log4j.xml] 22-10-2016 22:35:06 [WARN ] OpenmrsUtil - Unable to find a runtime properties file at /openmrs-runtime.properties INFO 10/22/16 10:35 PM:liquibase: Reading from `liquibasechangelog` WARNING 10/22/16 10:35 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 10:35 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 10:35 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case 22-10-2016 22:35:33 [WARN ] Listener - Bundled module folder doesn't exist: /opt/openmrs/openmrs/WEB-INF/bundledModules 22-10-2016 22:35:33 [WARN ] OpenmrsUtil - Unable to find a runtime properties file at /openmrs-runtime.properties INFO 10/22/16 10:35 PM:liquibase: Reading from `liquibasechangelog` WARNING 10/22/16 10:35 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 10:35 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 10:35 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case INFO 10/22/16 10:35 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 10:35 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 10:35 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 10:35 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 10:35 PM:liquibase: Successfully released change log lock 22-10-2016 22:35:43 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: uiframework.started value: true, 22-10-2016 22:35:43 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:35:43 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: uiframework.mandatory value: false, 22-10-2016 22:35:43 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:35:43 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: htmlwidgets.started value: true, 22-10-2016 22:35:43 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:35:43 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: htmlwidgets.mandatory value: false, 22-10-2016 22:35:43 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 10:35 PM:liquibase: Reading from `liquibasechangelog` WARNING 10/22/16 10:35 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 10:35 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case INFO 10/22/16 10:35 PM:liquibase: Successfully acquired change log lock WARNING 10/22/16 10:35 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 10:35 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case INFO 10/22/16 10:35 PM:liquibase: Successfully released change log lock 22-10-2016 22:35:43 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: openmrs-atomfeed.started value: true, 22-10-2016 22:35:43 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:35:43 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: openmrs-atomfeed.mandatory value: false, 22-10-2016 22:35:43 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 10:35 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 10:35 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 10:35 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 10:35 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 10:35 PM:liquibase: Successfully released change log lock 22-10-2016 22:35:44 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: calculation.started value: true, 22-10-2016 22:35:44 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:35:44 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: calculation.mandatory value: false, 22-10-2016 22:35:44 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:35:44 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: rulesengine.started value: true, 22-10-2016 22:35:44 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:35:44 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: rulesengine.mandatory value: false, 22-10-2016 22:35:44 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 10:35 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 10:35 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 10:35 PM:liquibase: Successfully released change log lock 22-10-2016 22:35:44 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: episodes.started value: true, 22-10-2016 22:35:44 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:35:44 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: episodes.mandatory value: false, 22-10-2016 22:35:44 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:35:44 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: webservices.rest.started value: true, 22-10-2016 22:35:44 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:35:44 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: webservices.rest.mandatory value: false, 22-10-2016 22:35:44 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 10:35 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 10:35 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 10:35 PM:liquibase: Successfully released change log lock 22-10-2016 22:35:45 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: bedmanagement.started value: true, 22-10-2016 22:35:45 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:35:45 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: bedmanagement.mandatory value: false, 22-10-2016 22:35:45 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:35:45 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: idgen.started value: true, 22-10-2016 22:35:45 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:35:45 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: idgen.mandatory value: false, 22-10-2016 22:35:45 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:35:46 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: idgen-webservices.started value: true, 22-10-2016 22:35:46 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:35:46 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: idgen-webservices.mandatory value: false, 22-10-2016 22:35:46 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:35:46 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: addresshierarchy.started value: true, 22-10-2016 22:35:46 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:35:46 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: addresshierarchy.mandatory value: false, 22-10-2016 22:35:46 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:35:47 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: event.started value: true, 22-10-2016 22:35:47 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:35:47 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: event.mandatory value: false, 22-10-2016 22:35:47 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 10:35 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 10:35 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 10:35 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 10:35 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 10:35 PM:liquibase: Successfully released change log lock 22-10-2016 22:35:47 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: metadatamapping.started value: true, 22-10-2016 22:35:47 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:35:47 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: metadatamapping.mandatory value: false, 22-10-2016 22:35:47 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 10:35 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 10:35 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 10:35 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 10:35 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 10:35 PM:liquibase: Successfully released change log lock 22-10-2016 22:35:47 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: metadatasharing.started value: true, 22-10-2016 22:35:47 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:35:47 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: metadatasharing.mandatory value: false, 22-10-2016 22:35:47 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:35:47 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: serialization.xstream.started value: true, 22-10-2016 22:35:47 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:35:47 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: serialization.xstream.mandatory value: false, 22-10-2016 22:35:47 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 10:35 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 10:35 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 10:35 PM:liquibase: Successfully released change log lock 22-10-2016 22:35:48 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: reporting.started value: true, 22-10-2016 22:35:48 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:35:48 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: reporting.mandatory value: false, 22-10-2016 22:35:48 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:35:48 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: uilibrary.started value: true, 22-10-2016 22:35:48 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:35:48 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: uilibrary.mandatory value: false, 22-10-2016 22:35:48 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 10:35 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 10:35 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 10:35 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 10:35 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 10:35 PM:liquibase: Successfully released change log lock 22-10-2016 22:35:49 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: providermanagement.started value: true, 22-10-2016 22:35:49 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:35:49 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: providermanagement.mandatory value: false, 22-10-2016 22:35:49 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 10:35 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 10:35 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 10:35 PM:liquibase: Successfully released change log lock 22-10-2016 22:35:49 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: emrapi.started value: true, 22-10-2016 22:35:49 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:35:49 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: emrapi.mandatory value: false, 22-10-2016 22:35:49 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:35:49 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: reference-data.started value: true, 22-10-2016 22:35:49 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:35:49 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: reference-data.mandatory value: false, 22-10-2016 22:35:49 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 10:35 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 10:35 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 10:35 PM:liquibase: Successfully released change log lock 22-10-2016 22:35:50 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: bacteriology.started value: true, 22-10-2016 22:35:50 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:35:50 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: bacteriology.mandatory value: false, 22-10-2016 22:35:50 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 10:35 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 10:35 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 10:35 PM:liquibase: Successfully released change log lock 22-10-2016 22:35:53 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: bahmnicore.started value: true, 22-10-2016 22:35:53 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:35:53 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: bahmnicore.mandatory value: false, 22-10-2016 22:35:53 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 10:35 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 10:35 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 10:35 PM:liquibase: Successfully released change log lock 22-10-2016 22:35:53 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: elisatomfeedclient.started value: true, 22-10-2016 22:35:53 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:35:53 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: elisatomfeedclient.mandatory value: false, 22-10-2016 22:35:53 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:35:53 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: uicommons.started value: true, 22-10-2016 22:35:53 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:35:53 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: uicommons.mandatory value: false, 22-10-2016 22:35:53 [INFO ] api - Exiting method saveGlobalProperty INFO 10/22/16 10:35 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 10:35 PM:liquibase: Reading from `liquibasechangelog` INFO 10/22/16 10:35 PM:liquibase: Successfully acquired change log lock INFO 10/22/16 10:35 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0 INFO 10/22/16 10:35 PM:liquibase: Successfully released change log lock 22-10-2016 22:35:54 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: appframework.started value: true, 22-10-2016 22:35:54 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:35:54 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: appframework.mandatory value: false, 22-10-2016 22:35:54 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:35:54 [INFO ] api - In method SchedulerService.saveToMemento. Arguments: 22-10-2016 22:35:54 [INFO ] api - Exiting method saveToMemento 22-10-2016 22:35:54 [INFO ] HibernateSessionFactoryBean - Closing Hibernate SessionFactory 22-10-2016 22:35:55 [INFO ] ServiceContext - Refreshing Context 22-10-2016 22:36:07 [INFO ] HibernateSessionFactoryBean - Building new Hibernate SessionFactory 22-10-2016 22:36:23 [INFO ] ServiceContext - Done refreshing Context 22-10-2016 22:36:26 [WARN ] RefillIdentifierPoolsTask - Not running scheduled task. DaemonToken = null; enabled = false 22-10-2016 22:36:31 [ERROR] OpenElisPatientFailedEventsFeedClientImpl - openelisatomfeedclient:failed feed execution while running failed eventsjava.lang.NullPointerException java.lang.NullPointerException at org.bahmni.module.bahmnicore.properties.BahmniCoreProperties.getProperty(BahmniCoreProperties.java:28) at org.bahmni.module.elisatomfeedclient.api.ElisAtomFeedProperties.getPatientFeedUri(ElisAtomFeedProperties.java:17) at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.getFeedUri(OpenElisPatientFailedEventsFeedClientImpl.java:44) at org.bahmni.module.elisatomfeedclient.api.client.OpenElisFeedClient.getAtomFeedClient(OpenElisFeedClient.java:48) at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.processFailedEvents(OpenElisPatientFailedEventsFeedClientImpl.java:64) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) at java.lang.reflect.Method.invoke(Unknown Source) at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:198) at com.sun.proxy.$Proxy282.processFailedEvents(Unknown Source) at org.bahmni.module.elisatomfeedclient.api.task.OpenElisPatientFeedFailedEventsTask.execute(OpenElisPatientFeedFailedEventsTask.java:13) at org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper.execute(TaskThreadedInitializationWrapper.java:68) at org.openmrs.scheduler.timer.TimerSchedulerTask.execute(TimerSchedulerTask.java:94) at org.openmrs.api.context.Daemon$2.run(Daemon.java:132) 22-10-2016 22:36:31 [ERROR] TimerSchedulerTask - FATAL ERROR: Task [class org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper] failed due to exception [java.lang.RuntimeException] java.lang.RuntimeException: java.lang.NullPointerException at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.processFailedEvents(OpenElisPatientFailedEventsFeedClientImpl.java:75) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) at java.lang.reflect.Method.invoke(Unknown Source) at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:198) at com.sun.proxy.$Proxy282.processFailedEvents(Unknown Source) at org.bahmni.module.elisatomfeedclient.api.task.OpenElisPatientFeedFailedEventsTask.execute(OpenElisPatientFeedFailedEventsTask.java:13) at org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper.execute(TaskThreadedInitializationWrapper.java:68) at org.openmrs.scheduler.timer.TimerSchedulerTask.execute(TimerSchedulerTask.java:94) at org.openmrs.api.context.Daemon$2.run(Daemon.java:132) Caused by: java.lang.NullPointerException at org.bahmni.module.bahmnicore.properties.BahmniCoreProperties.getProperty(BahmniCoreProperties.java:28) at org.bahmni.module.elisatomfeedclient.api.ElisAtomFeedProperties.getPatientFeedUri(ElisAtomFeedProperties.java:17) at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.getFeedUri(OpenElisPatientFailedEventsFeedClientImpl.java:44) at org.bahmni.module.elisatomfeedclient.api.client.OpenElisFeedClient.getAtomFeedClient(OpenElisFeedClient.java:48) at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.processFailedEvents(OpenElisPatientFailedEventsFeedClientImpl.java:64) ... 11 more 22-10-2016 22:36:32 [INFO ] api - In method UserService.saveRole. Arguments: Role=Privilege Level: Full, 22-10-2016 22:36:32 [INFO ] api - Exiting method saveRole 22-10-2016 22:36:32 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: visits.assignmentHandler value: org.openmrs.module.emrapi.adt.EmrApiVisitAssignmentHandler, 22-10-2016 22:36:32 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:36:32 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: provider.unknownProviderUuid value: f9badd80-ab76-11e2-9e96-0800200c9a66, 22-10-2016 22:36:32 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:36:32 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: emr.unknownProvider value: f9badd80-ab76-11e2-9e96-0800200c9a66, 22-10-2016 22:36:32 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:36:32 [INFO ] HibernateContextDAO - Updating the search index... It may take a few minutes. 22-10-2016 22:36:46 [ERROR] OpenElisPatientFailedEventsFeedClientImpl - openelisatomfeedclient:failed feed execution while running failed eventsjava.lang.NullPointerException java.lang.NullPointerException at org.bahmni.module.bahmnicore.properties.BahmniCoreProperties.getProperty(BahmniCoreProperties.java:28) at org.bahmni.module.elisatomfeedclient.api.ElisAtomFeedProperties.getPatientFeedUri(ElisAtomFeedProperties.java:17) at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.getFeedUri(OpenElisPatientFailedEventsFeedClientImpl.java:44) at org.bahmni.module.elisatomfeedclient.api.client.OpenElisFeedClient.getAtomFeedClient(OpenElisFeedClient.java:48) at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.processFailedEvents(OpenElisPatientFailedEventsFeedClientImpl.java:64) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) at java.lang.reflect.Method.invoke(Unknown Source) at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:198) at com.sun.proxy.$Proxy282.processFailedEvents(Unknown Source) at org.bahmni.module.elisatomfeedclient.api.task.OpenElisPatientFeedFailedEventsTask.execute(OpenElisPatientFeedFailedEventsTask.java:13) at org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper.execute(TaskThreadedInitializationWrapper.java:68) at org.openmrs.scheduler.timer.TimerSchedulerTask.execute(TimerSchedulerTask.java:94) at org.openmrs.api.context.Daemon$2.run(Daemon.java:132) 22-10-2016 22:36:46 [ERROR] TimerSchedulerTask - FATAL ERROR: Task [class org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper] failed due to exception [java.lang.RuntimeException] java.lang.RuntimeException: java.lang.NullPointerException at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.processFailedEvents(OpenElisPatientFailedEventsFeedClientImpl.java:75) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) at java.lang.reflect.Method.invoke(Unknown Source) at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:198) at com.sun.proxy.$Proxy282.processFailedEvents(Unknown Source) at org.bahmni.module.elisatomfeedclient.api.task.OpenElisPatientFeedFailedEventsTask.execute(OpenElisPatientFeedFailedEventsTask.java:13) at org.openmrs.scheduler.tasks.TaskThreadedInitializationWrapper.execute(TaskThreadedInitializationWrapper.java:68) at org.openmrs.scheduler.timer.TimerSchedulerTask.execute(TimerSchedulerTask.java:94) at org.openmrs.api.context.Daemon$2.run(Daemon.java:132) Caused by: java.lang.NullPointerException at org.bahmni.module.bahmnicore.properties.BahmniCoreProperties.getProperty(BahmniCoreProperties.java:28) at org.bahmni.module.elisatomfeedclient.api.ElisAtomFeedProperties.getPatientFeedUri(ElisAtomFeedProperties.java:17) at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.getFeedUri(OpenElisPatientFailedEventsFeedClientImpl.java:44) at org.bahmni.module.elisatomfeedclient.api.client.OpenElisFeedClient.getAtomFeedClient(OpenElisFeedClient.java:48) at org.bahmni.module.elisatomfeedclient.api.client.impl.OpenElisPatientFailedEventsFeedClientImpl.processFailedEvents(OpenElisPatientFailedEventsFeedClientImpl.java:64) ... 11 more 22-10-2016 22:36:52 [INFO ] api - In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: search.indexVersion value: 3, 22-10-2016 22:36:52 [INFO ] api - Exiting method saveGlobalProperty 22-10-2016 22:36:52 [INFO ] HibernateContextDAO - Finished updating the search index 22-10-2016 22:36:52 [WARN ] OpenmrsUtil - Unable to find a runtime properties file at /openmrs-runtime.properties 22-10-2016 22:36:53 [WARN ] OpenmrsUtil - Unable to find a runtime properties file at /openmrs-runtime.properties INFO 10/22/16 10:36 PM:liquibase: Reading from `liquibasechangelog` WARNING 10/22/16 10:36 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 10:36 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 10:36 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case INFO 10/22/16 10:36 PM:liquibase: Reading from `liquibasechangelog` WARNING 10/22/16 10:36 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 10:36 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case WARNING 10/22/16 10:36 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use and re-specify all configuration if this is the case Oct 22, 2016 10:36:56 PM org.apache.coyote.AbstractProtocol start INFO: Starting ProtocolHandler ["http-nio-8050"] Oct 22, 2016 10:36:57 PM org.apache.catalina.core.ApplicationContext log INFO: Initializing Spring FrameworkServlet 'openmrs' 22-10-2016 22:52:13 [INFO ] api - In method UserService.saveUser. Arguments: User=superman, String=, 22-10-2016 22:52:13 [INFO ] api - Exiting method saveUser 22-10-2016 22:52:24 [WARN ] HibernateConceptDAO - Multiple concepts found for 'REGISTRATION_CONCEPTS' 22-10-2016 22:53:09 [INFO ] api - In method EmrPatientProfileService.save. Arguments: PatientProfile=org.openmrs.module.emrapi.patient.PatientProfile@475615f7, 22-10-2016 22:53:09 [ERROR] api - An error occurred while executing this method. Current user: superman Error message: 'Patient#null' failed to validate with reason: names[0].givenName: GivenName.invalid, names[0].middleName: MiddleName.invalid, names[0].familyName: FamilyName.invalid org.openmrs.api.ValidationException: 'Patient#null' failed to validate with reason: names[0].givenName: GivenName.invalid, names[0].middleName: MiddleName.invalid, names[0].familyName: FamilyName.invalid at org.openmrs.validator.ValidateUtil.validate(ValidateUtil.java:79) at org.openmrs.aop.RequiredDataAdvice.before(RequiredDataAdvice.java:124) at org.springframework.aop.framework.adapter.MethodBeforeAdviceInterceptor.invoke(MethodBeforeAdviceInterceptor.java:50) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) at org.springframework.aop.framework.adapter.MethodBeforeAdviceInterceptor.invoke(MethodBeforeAdviceInterceptor.java:51) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204) at com.sun.proxy.$Proxy172.savePatient(Unknown Source) at org.openmrs.module.emrapi.patient.EmrPatientProfileServiceImpl.save(EmrPatientProfileServiceImpl.java:21) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) at java.lang.reflect.Method.invoke(Unknown Source) at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150) at org.openmrs.aop.LoggingAdvice.invoke(LoggingAdvice.java:121) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) at org.springframework.aop.framework.adapter.MethodBeforeAdviceInterceptor.invoke(MethodBeforeAdviceInterceptor.java:51) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) at org.springframework.aop.framework.adapter.MethodBeforeAdviceInterceptor.invoke(MethodBeforeAdviceInterceptor.java:51) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204) at com.sun.proxy.$Proxy181.save(Unknown Source) at org.bahmni.module.bahmnicore.web.v1_0.controller.BahmniPatientProfileResource.create(BahmniPatientProfileResource.java:123) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) at java.lang.reflect.Method.invoke(Unknown Source) at org.springframework.web.bind.annotation.support.HandlerMethodInvoker.invokeHandlerMethod(HandlerMethodInvoker.java:176) at org.springframework.web.servlet.mvc.annotation.AnnotationMethodHandlerAdapter.invokeHandlerMethod(AnnotationMethodHandlerAdapter.java:440) at org.springframework.web.servlet.mvc.annotation.AnnotationMethodHandlerAdapter.handle(AnnotationMethodHandlerAdapter.java:428) at org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:925) at org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:856) at org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:953) at org.springframework.web.servlet.FrameworkServlet.doPost(FrameworkServlet.java:855) at javax.servlet.http.HttpServlet.service(HttpServlet.java:644) at org.springframework.web.servlet.FrameworkServlet.service(FrameworkServlet.java:829) at javax.servlet.http.HttpServlet.service(HttpServlet.java:725) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:291) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.openmrs.module.web.filter.ForcePasswordChangeFilter.doFilter(ForcePasswordChangeFilter.java:61) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:239) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.openmrs.module.web.filter.ModuleFilterChain.doFilter(ModuleFilterChain.java:72) at org.openmrs.web.filter.GZIPFilter.doFilterInternal(GZIPFilter.java:64) at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107) at org.openmrs.module.web.filter.ModuleFilterChain.doFilter(ModuleFilterChain.java:70) at org.openmrs.module.webservices.rest.web.filter.AuthorizationFilter.doFilter(AuthorizationFilter.java:108) at org.openmrs.module.web.filter.ModuleFilterChain.doFilter(ModuleFilterChain.java:70) at org.springframework.web.filter.ShallowEtagHeaderFilter.doFilterInternal(ShallowEtagHeaderFilter.java:73) at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107) at org.openmrs.module.web.filter.ModuleFilterChain.doFilter(ModuleFilterChain.java:70) at org.openmrs.module.web.filter.ModuleFilter.doFilter(ModuleFilter.java:54) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:239) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.openmrs.web.filter.OpenmrsFilter.doFilterInternal(OpenmrsFilter.java:109) at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:239) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.springframework.orm.hibernate3.support.OpenSessionInViewFilter.doFilterInternal(OpenSessionInViewFilter.java:230) at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:239) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.openmrs.web.filter.StartupFilter.doFilter(StartupFilter.java:105) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:239) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.openmrs.web.filter.StartupFilter.doFilter(StartupFilter.java:105) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:239) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.openmrs.web.filter.StartupFilter.doFilter(StartupFilter.java:105) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:239) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.springframework.web.filter.CharacterEncodingFilter.doFilterInternal(CharacterEncodingFilter.java:88) at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:239) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:219) at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:106) at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:505) at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:142) at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:79) at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:88) at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:534) at org.apache.coyote.http11.AbstractHttp11Processor.process(AbstractHttp11Processor.java:1081) at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:658) at org.apache.coyote.http11.Http11NioProtocol$Http11ConnectionHandler.process(Http11NioProtocol.java:222) at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1566) at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.run(NioEndpoint.java:1523) at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61) at java.lang.Thread.run(Unknown Source) 22-10-2016 22:53:09 [INFO ] api - Exiting method save 22-10-2016 22:53:49 [INFO ] api - In method EmrPatientProfileService.save. Arguments: PatientProfile=org.openmrs.module.emrapi.patient.PatientProfile@b65108f, 22-10-2016 22:53:49 [ERROR] api - An error occurred while executing this method. Current user: superman Error message: 'Patient#null' failed to validate with reason: names[0].givenName: GivenName.invalid, names[0].middleName: MiddleName.invalid, names[0].familyName: FamilyName.invalid org.openmrs.api.ValidationException: 'Patient#null' failed to validate with reason: names[0].givenName: GivenName.invalid, names[0].middleName: MiddleName.invalid, names[0].familyName: FamilyName.invalid at org.openmrs.validator.ValidateUtil.validate(ValidateUtil.java:79) at org.openmrs.aop.RequiredDataAdvice.before(RequiredDataAdvice.java:124) at org.springframework.aop.framework.adapter.MethodBeforeAdviceInterceptor.invoke(MethodBeforeAdviceInterceptor.java:50) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) at org.springframework.aop.framework.adapter.MethodBeforeAdviceInterceptor.invoke(MethodBeforeAdviceInterceptor.java:51) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204) at com.sun.proxy.$Proxy172.savePatient(Unknown Source) at org.openmrs.module.emrapi.patient.EmrPatientProfileServiceImpl.save(EmrPatientProfileServiceImpl.java:21) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) at java.lang.reflect.Method.invoke(Unknown Source) at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150) at org.openmrs.aop.LoggingAdvice.invoke(LoggingAdvice.java:121) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) at org.springframework.aop.framework.adapter.MethodBeforeAdviceInterceptor.invoke(MethodBeforeAdviceInterceptor.java:51) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) at org.springframework.aop.framework.adapter.MethodBeforeAdviceInterceptor.invoke(MethodBeforeAdviceInterceptor.java:51) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204) at com.sun.proxy.$Proxy181.save(Unknown Source) at org.bahmni.module.bahmnicore.web.v1_0.controller.BahmniPatientProfileResource.create(BahmniPatientProfileResource.java:123) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) at java.lang.reflect.Method.invoke(Unknown Source) at org.springframework.web.bind.annotation.support.HandlerMethodInvoker.invokeHandlerMethod(HandlerMethodInvoker.java:176) at org.springframework.web.servlet.mvc.annotation.AnnotationMethodHandlerAdapter.invokeHandlerMethod(AnnotationMethodHandlerAdapter.java:440) at org.springframework.web.servlet.mvc.annotation.AnnotationMethodHandlerAdapter.handle(AnnotationMethodHandlerAdapter.java:428) at org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:925) at org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:856) at org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:953) at org.springframework.web.servlet.FrameworkServlet.doPost(FrameworkServlet.java:855) at javax.servlet.http.HttpServlet.service(HttpServlet.java:644) at org.springframework.web.servlet.FrameworkServlet.service(FrameworkServlet.java:829) at javax.servlet.http.HttpServlet.service(HttpServlet.java:725) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:291) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.openmrs.module.web.filter.ForcePasswordChangeFilter.doFilter(ForcePasswordChangeFilter.java:61) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:239) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.openmrs.module.web.filter.ModuleFilterChain.doFilter(ModuleFilterChain.java:72) at org.openmrs.web.filter.GZIPFilter.doFilterInternal(GZIPFilter.java:64) at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107) at org.openmrs.module.web.filter.ModuleFilterChain.doFilter(ModuleFilterChain.java:70) at org.openmrs.module.webservices.rest.web.filter.AuthorizationFilter.doFilter(AuthorizationFilter.java:108) at org.openmrs.module.web.filter.ModuleFilterChain.doFilter(ModuleFilterChain.java:70) at org.springframework.web.filter.ShallowEtagHeaderFilter.doFilterInternal(ShallowEtagHeaderFilter.java:73) at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107) at org.openmrs.module.web.filter.ModuleFilterChain.doFilter(ModuleFilterChain.java:70) at org.openmrs.module.web.filter.ModuleFilter.doFilter(ModuleFilter.java:54) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:239) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.openmrs.web.filter.OpenmrsFilter.doFilterInternal(OpenmrsFilter.java:109) at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:239) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.springframework.orm.hibernate3.support.OpenSessionInViewFilter.doFilterInternal(OpenSessionInViewFilter.java:230) at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:239) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.openmrs.web.filter.StartupFilter.doFilter(StartupFilter.java:105) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:239) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.openmrs.web.filter.StartupFilter.doFilter(StartupFilter.java:105) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:239) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.openmrs.web.filter.StartupFilter.doFilter(StartupFilter.java:105) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:239) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.springframework.web.filter.CharacterEncodingFilter.doFilterInternal(CharacterEncodingFilter.java:88) at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:239) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:219) at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:106) at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:505) at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:142) at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:79) at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:88) at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:534) at org.apache.coyote.http11.AbstractHttp11Processor.process(AbstractHttp11Processor.java:1081) at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:658) at org.apache.coyote.http11.Http11NioProtocol$Http11ConnectionHandler.process(Http11NioProtocol.java:222) at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1566) at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.run(NioEndpoint.java:1523) at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61) at java.lang.Thread.run(Unknown Source) 22-10-2016 22:53:49 [INFO ] api - Exiting method save 22-10-2016 22:54:10 [INFO ] api - In method EmrPatientProfileService.save. Arguments: PatientProfile=org.openmrs.module.emrapi.patient.PatientProfile@5fca88c3, 22-10-2016 22:54:10 [ERROR] api - An error occurred while executing this method. Current user: superman Error message: 'Patient#null' failed to validate with reason: names[0].givenName: GivenName.invalid, names[0].middleName: MiddleName.invalid, names[0].familyName: FamilyName.invalid org.openmrs.api.ValidationException: 'Patient#null' failed to validate with reason: names[0].givenName: GivenName.invalid, names[0].middleName: MiddleName.invalid, names[0].familyName: FamilyName.invalid at org.openmrs.validator.ValidateUtil.validate(ValidateUtil.java:79) at org.openmrs.aop.RequiredDataAdvice.before(RequiredDataAdvice.java:124) at org.springframework.aop.framework.adapter.MethodBeforeAdviceInterceptor.invoke(MethodBeforeAdviceInterceptor.java:50) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) at org.springframework.aop.framework.adapter.MethodBeforeAdviceInterceptor.invoke(MethodBeforeAdviceInterceptor.java:51) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204) at com.sun.proxy.$Proxy172.savePatient(Unknown Source) at org.openmrs.module.emrapi.patient.EmrPatientProfileServiceImpl.save(EmrPatientProfileServiceImpl.java:21) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) at java.lang.reflect.Method.invoke(Unknown Source) at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317) at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150) at org.openmrs.aop.LoggingAdvice.invoke(LoggingAdvice.java:121) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) at org.springframework.aop.framework.adapter.MethodBeforeAdviceInterceptor.invoke(MethodBeforeAdviceInterceptor.java:51) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) at org.springframework.aop.framework.adapter.MethodBeforeAdviceInterceptor.invoke(MethodBeforeAdviceInterceptor.java:51) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172) at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204) at com.sun.proxy.$Proxy181.save(Unknown Source) at org.bahmni.module.bahmnicore.web.v1_0.controller.BahmniPatientProfileResource.create(BahmniPatientProfileResource.java:123) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source) at java.lang.reflect.Method.invoke(Unknown Source) at org.springframework.web.bind.annotation.support.HandlerMethodInvoker.invokeHandlerMethod(HandlerMethodInvoker.java:176) at org.springframework.web.servlet.mvc.annotation.AnnotationMethodHandlerAdapter.invokeHandlerMethod(AnnotationMethodHandlerAdapter.java:440) at org.springframework.web.servlet.mvc.annotation.AnnotationMethodHandlerAdapter.handle(AnnotationMethodHandlerAdapter.java:428) at org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:925) at org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:856) at org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:953) at org.springframework.web.servlet.FrameworkServlet.doPost(FrameworkServlet.java:855) at javax.servlet.http.HttpServlet.service(HttpServlet.java:644) at org.springframework.web.servlet.FrameworkServlet.service(FrameworkServlet.java:829) at javax.servlet.http.HttpServlet.service(HttpServlet.java:725) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:291) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.openmrs.module.web.filter.ForcePasswordChangeFilter.doFilter(ForcePasswordChangeFilter.java:61) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:239) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.openmrs.module.web.filter.ModuleFilterChain.doFilter(ModuleFilterChain.java:72) at org.openmrs.web.filter.GZIPFilter.doFilterInternal(GZIPFilter.java:64) at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107) at org.openmrs.module.web.filter.ModuleFilterChain.doFilter(ModuleFilterChain.java:70) at org.openmrs.module.webservices.rest.web.filter.AuthorizationFilter.doFilter(AuthorizationFilter.java:108) at org.openmrs.module.web.filter.ModuleFilterChain.doFilter(ModuleFilterChain.java:70) at org.springframework.web.filter.ShallowEtagHeaderFilter.doFilterInternal(ShallowEtagHeaderFilter.java:73) at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107) at org.openmrs.module.web.filter.ModuleFilterChain.doFilter(ModuleFilterChain.java:70) at org.openmrs.module.web.filter.ModuleFilter.doFilter(ModuleFilter.java:54) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:239) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.openmrs.web.filter.OpenmrsFilter.doFilterInternal(OpenmrsFilter.java:109) at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:239) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.springframework.orm.hibernate3.support.OpenSessionInViewFilter.doFilterInternal(OpenSessionInViewFilter.java:230) at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:239) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.openmrs.web.filter.StartupFilter.doFilter(StartupFilter.java:105) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:239) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.openmrs.web.filter.StartupFilter.doFilter(StartupFilter.java:105) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:239) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.openmrs.web.filter.StartupFilter.doFilter(StartupFilter.java:105) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:239) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.springframework.web.filter.CharacterEncodingFilter.doFilterInternal(CharacterEncodingFilter.java:88) at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:107) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:239) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:219) at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:106) at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:505) at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:142) at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:79) at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:88) at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:534) at org.apache.coyote.http11.AbstractHttp11Processor.process(AbstractHttp11Processor.java:1081) at org.apache.coyote.AbstractProtocol$AbstractConnectionHandler.process(AbstractProtocol.java:658) at org.apache.coyote.http11.Http11NioProtocol$Http11ConnectionHandler.process(Http11NioProtocol.java:222) at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1566) at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.run(NioEndpoint.java:1523) at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61) at java.lang.Thread.run(Unknown Source) 22-10-2016 22:54:10 [INFO ] api - Exiting method save Oct 22, 2016 11:22:14 PM org.apache.catalina.core.ApplicationContext log INFO: Initializing Spring FrameworkServlet 'openmrs_static_content' 22-10-2016 23:22:14 [ERROR] SignatureParser - Parameter mismatch parsing signatures section in dwr.xml on line: DWREncounterService.findEncounters(String phrase, Integer patientId, boolean includeVoided) 22-10-2016 23:36:24 [INFO ] api - In method ReportService.deleteOldReportRequests. Arguments: 22-10-2016 23:36:24 [INFO ] api - Exiting method deleteOldReportRequests