WARN - HL7InQueueProcessor.processHL7InQueue(104) |2018-12-11 18:16:59,000| HL7 processor aborting (another processor already running)
WARN - HL7InQueueProcessor.processHL7InQueue(104) |2018-12-11 18:17:04,001| HL7 processor aborting (another processor already running)
Dec 11, 2018 6:17:08 PM org.apache.coyote.AbstractProtocol stop
INFO: Stopping ProtocolHandler ["http-bio-8081"]
Dec 11, 2018 6:17:08 PM org.apache.catalina.core.ApplicationContext log
INFO: Destroying Spring FrameworkServlet 'openmrs'
Dec 11, 2018 6:17:08 PM org.apache.catalina.core.ApplicationContext log
INFO: Destroying Spring FrameworkServlet 'openmrs_static_content'
WARN - HL7InQueueProcessor.processHL7InQueue(104) |2018-12-11 18:17:09,001| HL7 processor aborting (another processor already running)
WARN - ModuleFactory.stopModule(1094) |2018-12-11 18:17:09,937| Unable to call module's Activator.willStop() method
org.openmrs.api.APIAuthenticationException: Privileges required: Manage Atlas Data
at org.openmrs.aop.AuthorizationAdvice.throwUnauthorized(AuthorizationAdvice.java:116)
at org.openmrs.aop.AuthorizationAdvice.before(AuthorizationAdvice.java:97)
at org.springframework.aop.framework.adapter.MethodBeforeAdviceInterceptor.invoke(MethodBeforeAdviceInterceptor.java:51)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:179)
at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:207)
at com.sun.proxy.$Proxy239.disableAtlasModule(Unknown Source)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)
at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:190)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:157)
at org.springframework.transaction.interceptor.TransactionInterceptor$1.proceedWithInvocation(TransactionInterceptor.java:99)
at org.springframework.transaction.interceptor.TransactionAspectSupport.invokeWithinTransaction(TransactionAspectSupport.java:281)
at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:96)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:179)
at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:207)
at com.sun.proxy.$Proxy239.disableAtlasModule(Unknown Source)
at org.openmrs.module.atlas.AtlasModuleActivator.willStop(AtlasModuleActivator.java:68)
at org.openmrs.module.ModuleFactory.stopModule(ModuleFactory.java:1090)
at org.openmrs.module.ModuleUtil.shutdown(ModuleUtil.java:161)
at org.openmrs.api.context.Context.shutdown(Context.java:900)
at org.openmrs.web.Listener.contextDestroyed(Listener.java:558)
at org.apache.catalina.core.StandardContext.listenerStop(StandardContext.java:5002)
at org.apache.catalina.core.StandardContext.stopInternal(StandardContext.java:5647)
at org.apache.catalina.util.LifecycleBase.stop(LifecycleBase.java:232)
at org.apache.catalina.core.ContainerBase$StopChild.call(ContainerBase.java:1575)
at org.apache.catalina.core.ContainerBase$StopChild.call(ContainerBase.java:1564)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
WARN - DynamicPooledDataSourceManagerMBean.getAttribute(373) |2018-12-11 18:17:14,922| Failed to get requested attribute: coreHashCode
javax.management.AttributeNotFoundException: coreHashCode
at com.mchange.v2.c3p0.management.DynamicPooledDataSourceManagerMBean.getAttribute(DynamicPooledDataSourceManagerMBean.java:353)
at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getAttribute(DefaultMBeanServerInterceptor.java:647)
at com.sun.jmx.mbeanserver.JmxMBeanServer.getAttribute(JmxMBeanServer.java:678)
at javax.management.AttributeValueExp.getAttribute(AttributeValueExp.java:163)
at javax.management.AttributeValueExp.apply(AttributeValueExp.java:105)
at javax.management.BinaryRelQueryExp.apply(BinaryRelQueryExp.java:109)
at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.objectNamesFromFilteredNamedObjects(DefaultMBeanServerInterceptor.java:1521)
at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.queryNamesImpl(DefaultMBeanServerInterceptor.java:564)
at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.queryNames(DefaultMBeanServerInterceptor.java:554)
at com.sun.jmx.mbeanserver.JmxMBeanServer.queryNames(JmxMBeanServer.java:619)
at org.apache.solr.core.JmxMonitoredMap.clear(JmxMonitoredMap.java:116)
at org.apache.solr.core.SolrCore.close(SolrCore.java:1111)
at org.apache.solr.core.SolrCores.close(SolrCores.java:117)
at org.apache.solr.core.CoreContainer.shutdown(CoreContainer.java:347)
at org.apache.solr.client.solrj.embedded.EmbeddedSolrServer.shutdown(EmbeddedSolrServer.java:249)
at org.openmrs.module.chartsearch.solr.SolrManagement.shutdown(SolrManagement.java:33)
at org.openmrs.module.chartsearch.ChartSearchActivator.stopped(ChartSearchActivator.java:72)
at org.openmrs.module.ModuleFactory.stopModule(ModuleFactory.java:1198)
at org.openmrs.module.ModuleFactory.stopModule(ModuleFactory.java:1119)
at org.openmrs.module.ModuleFactory.stopModule(ModuleFactory.java:1119)
at org.openmrs.module.ModuleFactory.stopModule(ModuleFactory.java:1119)
at org.openmrs.module.ModuleUtil.shutdown(ModuleUtil.java:161)
at org.openmrs.api.context.Context.shutdown(Context.java:900)
at org.openmrs.web.Listener.contextDestroyed(Listener.java:558)
at org.apache.catalina.core.StandardContext.listenerStop(StandardContext.java:5002)
at org.apache.catalina.core.StandardContext.stopInternal(StandardContext.java:5647)
at org.apache.catalina.util.LifecycleBase.stop(LifecycleBase.java:232)
at org.apache.catalina.core.ContainerBase$StopChild.call(ContainerBase.java:1575)
at org.apache.catalina.core.ContainerBase$StopChild.call(ContainerBase.java:1564)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
WARN - DynamicPooledDataSourceManagerMBean.getAttribute(373) |2018-12-11 18:17:14,996| Failed to get requested attribute: coreHashCode
javax.management.AttributeNotFoundException: coreHashCode
at com.mchange.v2.c3p0.management.DynamicPooledDataSourceManagerMBean.getAttribute(DynamicPooledDataSourceManagerMBean.java:353)
at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.getAttribute(DefaultMBeanServerInterceptor.java:647)
at com.sun.jmx.mbeanserver.JmxMBeanServer.getAttribute(JmxMBeanServer.java:678)
at javax.management.AttributeValueExp.getAttribute(AttributeValueExp.java:163)
at javax.management.AttributeValueExp.apply(AttributeValueExp.java:105)
at javax.management.BinaryRelQueryExp.apply(BinaryRelQueryExp.java:109)
at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.objectNamesFromFilteredNamedObjects(DefaultMBeanServerInterceptor.java:1521)
at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.queryNamesImpl(DefaultMBeanServerInterceptor.java:564)
at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.queryNames(DefaultMBeanServerInterceptor.java:554)
at com.sun.jmx.mbeanserver.JmxMBeanServer.queryNames(JmxMBeanServer.java:619)
at org.apache.solr.core.JmxMonitoredMap.clear(JmxMonitoredMap.java:116)
at org.apache.solr.core.SolrCore.close(SolrCore.java:1111)
at org.apache.solr.core.SolrCores.close(SolrCores.java:117)
at org.apache.solr.core.CoreContainer.shutdown(CoreContainer.java:347)
at org.apache.solr.client.solrj.embedded.EmbeddedSolrServer.shutdown(EmbeddedSolrServer.java:249)
at org.openmrs.module.chartsearch.solr.SolrManagement.shutdown(SolrManagement.java:33)
at org.openmrs.module.chartsearch.ChartSearchActivator.stopped(ChartSearchActivator.java:72)
at org.openmrs.module.ModuleFactory.stopModule(ModuleFactory.java:1198)
at org.openmrs.module.ModuleFactory.stopModule(ModuleFactory.java:1119)
at org.openmrs.module.ModuleFactory.stopModule(ModuleFactory.java:1119)
at org.openmrs.module.ModuleFactory.stopModule(ModuleFactory.java:1119)
at org.openmrs.module.ModuleUtil.shutdown(ModuleUtil.java:161)
at org.openmrs.api.context.Context.shutdown(Context.java:900)
at org.openmrs.web.Listener.contextDestroyed(Listener.java:558)
at org.apache.catalina.core.StandardContext.listenerStop(StandardContext.java:5002)
at org.apache.catalina.core.StandardContext.stopInternal(StandardContext.java:5647)
at org.apache.catalina.util.LifecycleBase.stop(LifecycleBase.java:232)
at org.apache.catalina.core.ContainerBase$StopChild.call(ContainerBase.java:1575)
at org.apache.catalina.core.ContainerBase$StopChild.call(ContainerBase.java:1564)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
WARN - ModuleFactory.stopModule(1202) |2018-12-11 18:17:17,516| Unable to call module's Activator.shutdown() method
org.openmrs.api.APIAuthenticationException: Basic authentication required
at org.openmrs.aop.AuthorizationAdvice.throwUnauthorized(AuthorizationAdvice.java:145)
at org.openmrs.aop.AuthorizationAdvice.before(AuthorizationAdvice.java:101)
at org.springframework.aop.framework.adapter.MethodBeforeAdviceInterceptor.invoke(MethodBeforeAdviceInterceptor.java:51)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:179)
at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:207)
at com.sun.proxy.$Proxy219.getDefinitionByUuid(Unknown Source)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)
at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:190)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:157)
at org.springframework.transaction.interceptor.TransactionInterceptor$1.proceedWithInvocation(TransactionInterceptor.java:99)
at org.springframework.transaction.interceptor.TransactionAspectSupport.invokeWithinTransaction(TransactionAspectSupport.java:281)
at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:96)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:179)
at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:207)
at com.sun.proxy.$Proxy219.getDefinitionByUuid(Unknown Source)
at org.openmrs.module.reporting.definition.persister.SerializedDefinitionPersister.getDefinitionByUuid(SerializedDefinitionPersister.java:65)
at org.openmrs.module.reporting.definition.service.BaseDefinitionService.getDefinitionByUuid(BaseDefinitionService.java:96)
at org.openmrs.module.reporting.definition.service.BaseDefinitionService.getDefinition(BaseDefinitionService.java:167)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)
at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:190)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:157)
at org.springframework.transaction.interceptor.TransactionInterceptor$1.proceedWithInvocation(TransactionInterceptor.java:99)
at org.springframework.transaction.interceptor.TransactionAspectSupport.invokeWithinTransaction(TransactionAspectSupport.java:281)
at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:96)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:179)
at org.openmrs.aop.LoggingAdvice.invoke(LoggingAdvice.java:121)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:179)
at org.springframework.aop.framework.adapter.MethodBeforeAdviceInterceptor.invoke(MethodBeforeAdviceInterceptor.java:52)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:179)
at org.springframework.aop.framework.adapter.MethodBeforeAdviceInterceptor.invoke(MethodBeforeAdviceInterceptor.java:52)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:179)
at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:207)
at com.sun.proxy.$Proxy116.getDefinition(Unknown Source)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)
at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:190)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:157)
at org.springframework.transaction.interceptor.TransactionInterceptor$1.proceedWithInvocation(TransactionInterceptor.java:99)
at org.springframework.transaction.interceptor.TransactionAspectSupport.invokeWithinTransaction(TransactionAspectSupport.java:281)
at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:96)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:179)
at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:207)
at com.sun.proxy.$Proxy237.getDefinition(Unknown Source)
at org.openmrs.module.appointmentschedulingui.AppointmentSchedulingUIActivator.removeDataSetDefinitions(AppointmentSchedulingUIActivator.java:108)
at org.openmrs.module.appointmentschedulingui.AppointmentSchedulingUIActivator.stopped(AppointmentSchedulingUIActivator.java:95)
at org.openmrs.module.ModuleFactory.stopModule(ModuleFactory.java:1198)
at org.openmrs.module.ModuleFactory.stopModule(ModuleFactory.java:1119)
at org.openmrs.module.ModuleFactory.stopModule(ModuleFactory.java:1119)
at org.openmrs.module.ModuleFactory.stopModule(ModuleFactory.java:1119)
at org.openmrs.module.ModuleUtil.shutdown(ModuleUtil.java:161)
at org.openmrs.api.context.Context.shutdown(Context.java:900)
at org.openmrs.web.Listener.contextDestroyed(Listener.java:558)
at org.apache.catalina.core.StandardContext.listenerStop(StandardContext.java:5002)
at org.apache.catalina.core.StandardContext.stopInternal(StandardContext.java:5647)
at org.apache.catalina.util.LifecycleBase.stop(LifecycleBase.java:232)
at org.apache.catalina.core.ContainerBase$StopChild.call(ContainerBase.java:1575)
at org.apache.catalina.core.ContainerBase$StopChild.call(ContainerBase.java:1564)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,180| QUERY: from LocationTag where uuid = :uuid
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,195| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,196| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,197| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,198| Execution Count : 4
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,199| Average time : 0
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,199| Row Count : 4
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,200| QUERY: [CRITERIA] select this_.appointment_request_id as appointm1_9_0_, this_.appointment_type_id as appointm2_9_0_, this_.patient_id as patient_3_9_0_, this_.provider_id as provider4_9_0_, this_.requested_by as requeste5_9_0_, this_.requested_on as requeste6_9_0_, this_.status as status7_9_0_, this_.min_time_frame_value as min_time8_9_0_, this_.min_time_frame_units as min_time9_9_0_, this_.max_time_frame_value as max_tim10_9_0_, this_.max_time_frame_units as max_tim11_9_0_, this_.notes as notes12_9_0_, this_.uuid as uuid13_9_0_, this_.date_created as date_cr14_9_0_, this_.date_changed as date_ch15_9_0_, this_.date_voided as date_vo16_9_0_, this_.voided as voided17_9_0_, this_.void_reason as void_re18_9_0_, this_.creator as creator19_9_0_, this_.changed_by as changed20_9_0_, this_.voided_by as voided_21_9_0_ from appointmentscheduling_appointment_request this_ where this_.patient_id=? and this_.status=? and this_.voided=?
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,201| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,203| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,204| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,205| Execution Count : 5
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,206| Average time : 7
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,207| Row Count : 5
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,208| QUERY: [CRITERIA] select this_.metadata_term_mapping_id as metadata1_85_0_, this_.metadata_source_id as metadata2_85_0_, this_.code as code3_85_0_, this_.metadata_class as metadata4_85_0_, this_.metadata_uuid as metadata5_85_0_, this_.name as name6_85_0_, this_.description as descript7_85_0_, this_.creator as creator8_85_0_, this_.date_created as date_cre9_85_0_, this_.changed_by as changed10_85_0_, this_.date_changed as date_ch11_85_0_, this_.retired as retired12_85_0_, this_.date_retired as date_re13_85_0_, this_.retired_by as retired14_85_0_, this_.retire_reason as retire_15_85_0_, this_.uuid as uuid16_85_0_ from metadatamapping_metadata_term_mapping this_ where this_.metadata_source_id=? and this_.code=?
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,209| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,210| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,210| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,211| Execution Count : 23
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,212| Average time : 3
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,213| Row Count : 23
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,214| QUERY: from Role r order by r.role
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,214| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,215| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,215| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,216| Execution Count : 1
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,216| Average time : 2
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,217| Row Count : 29
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,217| QUERY: [CRITERIA] select this_.patient_program_id as patient_1_103_0_, this_.uuid as uuid2_103_0_, this_.date_enrolled as date_enr3_103_0_, this_.date_completed as date_com4_103_0_, this_.date_created as date_cre5_103_0_, this_.voided as voided6_103_0_, this_.date_voided as date_voi7_103_0_, this_.void_reason as void_rea8_103_0_, this_.voided_by as voided_b9_103_0_, this_.date_changed as date_ch10_103_0_, this_.outcome_concept_id as outcome11_103_0_, this_.program_id as program12_103_0_, this_.patient_id as patient13_103_0_, this_.creator as creator14_103_0_, this_.changed_by as changed15_103_0_, this_.location_id as locatio16_103_0_ from patient_program this_ where this_.patient_id=? and this_.program_id=? and this_.date_enrolled<=? and (this_.date_completed is null or this_.date_completed>=?) and this_.voided=?
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,217| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,218| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,218| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,219| Execution Count : 2
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,219| Average time : 629
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,220| Row Count : 2
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,224| QUERY: from Visit v where v.uuid = :uuid
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,225| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,226| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,226| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,227| Execution Count : 9
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,228| Average time : 0
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,229| Row Count : 9
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,229| QUERY: from AppointmentType at where at.uuid = :uuid
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,230| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,233| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,236| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,238| Execution Count : 22
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,239| Average time : 4
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,240| Row Count : 22
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,243| QUERY: [CRITERIA] select this_.visit_id as visit_id1_143_0_, this_.date_started as date_sta2_143_0_, this_.date_stopped as date_sto3_143_0_, this_.date_created as date_cre4_143_0_, this_.voided as voided5_143_0_, this_.date_voided as date_voi6_143_0_, this_.void_reason as void_rea7_143_0_, this_.date_changed as date_cha8_143_0_, this_.uuid as uuid9_143_0_, this_.visit_type_id as visit_t10_143_0_, this_.patient_id as patient11_143_0_, this_.indication_concept_id as indicat12_143_0_, this_.location_id as locatio13_143_0_, this_.creator as creator14_143_0_, this_.changed_by as changed15_143_0_, this_.voided_by as voided_16_143_0_ from visit this_ where this_.patient_id in (?) and (this_.date_stopped is null or this_.date_stopped>?) and this_.voided=? order by this_.date_started desc, this_.visit_id desc
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,244| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,245| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,246| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,249| Execution Count : 13
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,250| Average time : 17
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,251| Row Count : 12
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,254| QUERY: [CRITERIA] select this_.id as id1_71_0_, this_.uuid as uuid2_71_0_, this_.name as name3_71_0_, this_.description as descript4_71_0_, this_.identifier_type as identifi5_71_0_, this_.creator as creator6_71_0_, this_.date_created as date_cre7_71_0_, this_.changed_by as changed_8_71_0_, this_.date_changed as date_cha9_71_0_, this_.retired as retired10_71_0_, this_.retired_by as retired11_71_0_, this_.date_retired as date_re12_71_0_, this_.retire_reason as retire_13_71_0_, this_1_.next_sequence_value as next_seq2_76_0_, this_1_.base_character_set as base_cha3_76_0_, this_1_.first_identifier_base as first_id4_76_0_, this_1_.prefix as prefix5_76_0_, this_1_.suffix as suffix6_76_0_, this_1_.min_length as min_leng7_76_0_, this_1_.max_length as max_leng8_76_0_, this_2_.url as url2_74_0_, this_2_.user as user3_74_0_, this_2_.password as password4_74_0_, this_3_.source as source2_70_0_, this_3_.batch_size as batch_si3_70_0_, this_3_.min_pool_size as min_pool4_70_0_, this_3_.refill_with_scheduled_task as refill_w5_70_0_, this_3_.sequential as sequenti6_70_0_, case when this_1_.id is not null then 1 when this_2_.id is not null then 2 when this_3_.id is not null then 3 when this_.id is not null then 0 end as clazz_0_ from idgen_identifier_source this_ left outer join idgen_seq_id_gen this_1_ on this_.id=this_1_.id left outer join idgen_remote_source this_2_ on this_.id=this_2_.id left outer join idgen_id_pool this_3_ on this_.id=this_3_.id where this_.retired like ? order by this_.name asc
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,258| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,261| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,262| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,263| Execution Count : 68
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,266| Average time : 11
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,267| Row Count : 68
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,268| QUERY: [CRITERIA] select this_.provider_id as provider1_115_0_, this_.person_id as person_i2_115_0_, this_.name as name3_115_0_, this_.identifier as identifi4_115_0_, this_.creator as creator5_115_0_, this_.date_created as date_cre6_115_0_, this_.changed_by as changed_7_115_0_, this_.date_changed as date_cha8_115_0_, this_.retired_by as retired_9_115_0_, this_.date_retired as date_re10_115_0_, this_.retire_reason as retire_11_115_0_, this_.retired as retired12_115_0_, this_.uuid as uuid13_115_0_, this_.provider_role_id as provide14_115_0_ from provider this_ where this_.retired=? and this_.person_id=? order by this_.provider_id asc
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,269| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,271| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,271| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,272| Execution Count : 119
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,273| Average time : 3
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,273| Row Count : 119
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,274| QUERY: [CRITERIA] select this_.metadata_uuid as y0_ from metadatamapping_metadata_set_member this_ where this_.retired=? and this_.metadata_set_id=? and this_.metadata_uuid in (select item_.uuid as y0_ from patient_identifier_type item_ where item_.uuid=this_.metadata_uuid and item_.retired=?) order by this_.sort_weight desc
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,510| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,512| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,512| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,513| Execution Count : 36
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,513| Average time : 0
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,513| Row Count : 0
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,514| QUERY: [CRITERIA] select this_.allergy_id as allergy_1_3_0_, this_.patient_id as patient_2_3_0_, this_.allergen_type as allergen3_3_0_, this_.coded_allergen as coded_al4_3_0_, this_.non_coded_allergen as non_code5_3_0_, this_.severity_concept_id as severity6_3_0_, this_.comment as comment7_3_0_, this_.creator as creator8_3_0_, this_.date_created as date_cre9_3_0_, this_.changed_by as changed10_3_0_, this_.date_changed as date_ch11_3_0_, this_.voided as voided12_3_0_, this_.date_voided as date_vo13_3_0_, this_.void_reason as void_re14_3_0_, this_.voided_by as voided_15_3_0_, this_.uuid as uuid16_3_0_ from allergy this_ where this_.patient_id=? and this_.voided=?
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,514| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,515| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,515| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,515| Execution Count : 4
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,515| Average time : 74
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,516| Row Count : 12
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,516| QUERY: from User u where (u.username = ? or u.systemId = ? or u.systemId = ?) and u.retired = '0'
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,516| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,516| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,516| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,517| Execution Count : 3
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,517| Average time : 47
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,518| Row Count : 3
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,518| QUERY: [CRITERIA] select this_.id as id1_68_0_, this_.form_id as form_id2_68_0_, this_.xml_data as xml_data3_68_0_, this_.creator as creator4_68_0_, this_.date_created as date_cre5_68_0_, this_.changed_by as changed_6_68_0_, this_.date_changed as date_cha7_68_0_, this_.retired as retired8_68_0_, this_.name as name9_68_0_, this_.uuid as uuid10_68_0_, this_.description as descrip11_68_0_, this_.retired_by as retired12_68_0_, this_.date_retired as date_re13_68_0_, this_.retire_reason as retire_14_68_0_ from htmlformentry_html_form this_ where this_.form_id=? order by this_.date_created desc
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,518| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,519| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,519| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,520| Execution Count : 13
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,520| Average time : 22
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,521| Row Count : 13
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,521| QUERY: SELECT appointment FROM Appointment AS appointment WHERE appointment.voided = 0 AND appointment.timeSlot.startDate >= :fromDate AND appointment.status IN (:statuses_0_, :statuses_1_, :statuses_2_, :statuses_3_, :statuses_4_, :statuses_5_, :statuses_6_) AND appointment.patient=:patient ORDER BY appointment.timeSlot.startDate
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,521| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,522| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,522| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,523| Execution Count : 4
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,523| Average time : 73
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,523| Row Count : 0
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,524| QUERY: [CRITERIA] select this_.encounter_role_id as encounte1_55_0_, this_.name as name2_55_0_, this_.description as descript3_55_0_, this_.creator as creator4_55_0_, this_.date_created as date_cre5_55_0_, this_.changed_by as changed_6_55_0_, this_.date_changed as date_cha7_55_0_, this_.retired as retired8_55_0_, this_.date_retired as date_ret9_55_0_, this_.retired_by as retired10_55_0_, this_.retire_reason as retire_11_55_0_, this_.uuid as uuid12_55_0_ from encounter_role this_ where this_.uuid=?
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,524| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,525| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,525| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,525| Execution Count : 23
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,526| Average time : 17
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,526| Row Count : 23
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,526| QUERY: [CRITERIA] select this_.relationship_id as relation1_124_0_, this_.uuid as uuid2_124_0_, this_.start_date as start_da3_124_0_, this_.end_date as end_date4_124_0_, this_.voided as voided5_124_0_, this_.date_voided as date_voi6_124_0_, this_.void_reason as void_rea7_124_0_, this_.date_created as date_cre8_124_0_, this_.person_a as person_a9_124_0_, this_.person_b as person_10_124_0_, this_.relationship as relatio11_124_0_, this_.creator as creator12_124_0_, this_.voided_by as voided_13_124_0_, this_.changed_by as changed14_124_0_, this_.date_changed as date_ch15_124_0_ from relationship this_ where this_.person_a=? and this_.voided=?
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,527| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,528| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,528| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,529| Execution Count : 17
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,529| Average time : 22
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,530| Row Count : 17
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,530| QUERY: [CRITERIA] select this_.address_hierarchy_level_id as address_1_2_0_, this_.parent_level_id as parent_l2_2_0_, this_.name as name3_2_0_, this_.address_field as address_4_2_0_, this_.required as required5_2_0_, this_.uuid as uuid6_2_0_ from address_hierarchy_level this_ where this_.parent_level_id is null
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,531| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,531| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,532| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,532| Execution Count : 1
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,532| Average time : 49
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,533| Row Count : 1
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,533| QUERY: [CRITERIA] select this_.relationship_type_id as relation1_125_0_, this_.uuid as uuid2_125_0_, this_.a_is_to_b as a_is_to_3_125_0_, this_.b_is_to_a as b_is_to_4_125_0_, this_.description as descript5_125_0_, this_.preferred as preferre6_125_0_, this_.weight as weight7_125_0_, this_.date_created as date_cre8_125_0_, this_.creator as creator9_125_0_, this_.retired_by as retired10_125_0_, this_.date_retired as date_re11_125_0_, this_.retire_reason as retire_12_125_0_, this_.retired as retired13_125_0_ from relationship_type this_ where this_.retired=? order by this_.weight asc
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,534| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,534| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,535| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,535| Execution Count : 2
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,536| Average time : 8
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,536| Row Count : 10
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,537| QUERY: [CRITERIA] select this_.id as id1_131_0_, this_.uuid as uuid2_131_0_, this_.base_cohort_uuid as base_coh3_131_0_, this_.base_cohort_parameters as base_coh4_131_0_, this_.report_definition_uuid as report_d5_131_0_, this_.report_definition_parameters as report_d6_131_0_, this_.renderer_type as renderer7_131_0_, this_.renderer_argument as renderer8_131_0_, this_.requested_by as requeste9_131_0_, this_.request_datetime as request10_131_0_, this_.priority as priorit11_131_0_, this_.status as status12_131_0_, this_.schedule as schedul13_131_0_, this_.process_automatically as process14_131_0_, this_.evaluation_start_datetime as evaluat15_131_0_, this_.evaluation_complete_datetime as evaluat16_131_0_, this_.render_complete_datetime as render_17_131_0_, this_.minimum_days_to_preserve as minimum18_131_0_, this_.description as descrip19_131_0_ from reporting_report_request this_ where this_.request_datetime<=? and this_.status in (?, ?) order by this_.evaluation_complete_datetime desc, this_.evaluation_start_datetime desc, this_.priority desc, this_.request_datetime desc
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,537| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,537| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,538| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,538| Execution Count : 5
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,538| Average time : 5
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,539| Row Count : 0
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,539| QUERY: [CRITERIA] select this_.visit_type_id as visit_ty1_146_0_, this_.name as name2_146_0_, this_.description as descript3_146_0_, this_.uuid as uuid4_146_0_, this_.date_created as date_cre5_146_0_, this_.date_changed as date_cha6_146_0_, this_.date_retired as date_ret7_146_0_, this_.retired as retired8_146_0_, this_.retire_reason as retire_r9_146_0_, this_.creator as creator10_146_0_, this_.changed_by as changed11_146_0_, this_.retired_by as retired12_146_0_ from visit_type this_ where this_.uuid=?
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,540| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,541| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,541| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,542| Execution Count : 1
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,542| Average time : 0
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,543| Row Count : 1
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,543| QUERY: [CRITERIA] select this_.calculation_registration_id as calculat1_14_0_, this_.uuid as uuid2_14_0_, this_.token as token3_14_0_, this_.provider_class_name as provider4_14_0_, this_.calculation_name as calculat5_14_0_, this_.configuration as configur6_14_0_ from calculation_registration this_ where this_.provider_class_name=?
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,544| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,544| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,545| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,545| Execution Count : 1
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,546| Average time : 183
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,546| Row Count : 0
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,547| QUERY: [CRITERIA] select this_.id as id1_71_0_, this_.uuid as uuid2_71_0_, this_.name as name3_71_0_, this_.description as descript4_71_0_, this_.identifier_type as identifi5_71_0_, this_.creator as creator6_71_0_, this_.date_created as date_cre7_71_0_, this_.changed_by as changed_8_71_0_, this_.date_changed as date_cha9_71_0_, this_.retired as retired10_71_0_, this_.retired_by as retired11_71_0_, this_.date_retired as date_re12_71_0_, this_.retire_reason as retire_13_71_0_, this_1_.next_sequence_value as next_seq2_76_0_, this_1_.base_character_set as base_cha3_76_0_, this_1_.first_identifier_base as first_id4_76_0_, this_1_.prefix as prefix5_76_0_, this_1_.suffix as suffix6_76_0_, this_1_.min_length as min_leng7_76_0_, this_1_.max_length as max_leng8_76_0_, this_2_.url as url2_74_0_, this_2_.user as user3_74_0_, this_2_.password as password4_74_0_, this_3_.source as source2_70_0_, this_3_.batch_size as batch_si3_70_0_, this_3_.min_pool_size as min_pool4_70_0_, this_3_.refill_with_scheduled_task as refill_w5_70_0_, this_3_.sequential as sequenti6_70_0_, case when this_1_.id is not null then 1 when this_2_.id is not null then 2 when this_3_.id is not null then 3 when this_.id is not null then 0 end as clazz_0_ from idgen_identifier_source this_ left outer join idgen_seq_id_gen this_1_ on this_.id=this_1_.id left outer join idgen_remote_source this_2_ on this_.id=this_2_.id left outer join idgen_id_pool this_3_ on this_.id=this_3_.id where this_.uuid=?
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,549| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,551| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,552| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,553| Execution Count : 1
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,554| Average time : 787
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,554| Row Count : 1
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,555| QUERY: from User u where u.retired = '0' and (u.username = ? or u.systemId = ?)
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,555| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,556| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,557| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,557| Execution Count : 1
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,558| Average time : 1
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,558| Row Count : 1
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,559| QUERY: [CRITERIA] select this_.metadata_set_id as metadata1_82_0_, this_.name as name2_82_0_, this_.description as descript3_82_0_, this_.creator as creator4_82_0_, this_.date_created as date_cre5_82_0_, this_.changed_by as changed_6_82_0_, this_.date_changed as date_cha7_82_0_, this_.retired as retired8_82_0_, this_.date_retired as date_ret9_82_0_, this_.retired_by as retired10_82_0_, this_.retire_reason as retire_11_82_0_, this_.uuid as uuid12_82_0_ from metadatamapping_metadata_set this_ where this_.uuid=?
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,560| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,560| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,561| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,561| Execution Count : 36
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,562| Average time : 3
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,563| Row Count : 36
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,563| QUERY: [CRITERIA] select this_.alert_id as alert_id1_89_1_, this_.text as text2_89_1_, this_.satisfied_by_any as satisfie3_89_1_, this_.alert_read as alert_re4_89_1_, this_.date_to_expire as date_to_5_89_1_, this_.creator as creator6_89_1_, this_.date_created as date_cre7_89_1_, this_.changed_by as changed_8_89_1_, this_.date_changed as date_cha9_89_1_, this_.uuid as uuid10_89_1_, recipient1_.alert_id as alert_id1_90_0_, recipient1_.user_id as user_id2_90_0_, recipient1_.alert_read as alert_re3_90_0_, recipient1_.date_changed as date_cha4_90_0_, recipient1_.uuid as uuid5_90_0_ from notification_alert this_ inner join notification_alert_recipient recipient1_ on this_.alert_id=recipient1_.alert_id where recipient1_.user_id=? and (this_.date_to_expire is null or this_.date_to_expire>?) and this_.alert_read=? and recipient1_.alert_read=? order by this_.date_changed desc
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,564| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,565| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,565| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,566| Execution Count : 12
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,566| Average time : 75
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,567| Row Count : 0
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,568| QUERY: [CRITERIA] select this_.address_hierarchy_entry_id as address_1_1_1_, this_.name as name2_1_1_, this_.level_id as level_id3_1_1_, this_.parent_id as parent_i4_1_1_, this_.user_generated_id as user_gen5_1_1_, this_.latitude as latitude6_1_1_, this_.longitude as longitud7_1_1_, this_.elevation as elevatio8_1_1_, this_.uuid as uuid9_1_1_, addresshie1_.address_hierarchy_entry_id as address_1_1_0_, addresshie1_.name as name2_1_0_, addresshie1_.level_id as level_id3_1_0_, addresshie1_.parent_id as parent_i4_1_0_, addresshie1_.user_generated_id as user_gen5_1_0_, addresshie1_.latitude as latitude6_1_0_, addresshie1_.longitude as longitud7_1_0_, addresshie1_.elevation as elevatio8_1_0_, addresshie1_.uuid as uuid9_1_0_ from address_hierarchy_entry this_ inner join address_hierarchy_entry addresshie1_ on this_.parent_id=addresshie1_.address_hierarchy_entry_id where addresshie1_.address_hierarchy_entry_id=?
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,569| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,569| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,570| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,571| Execution Count : 10338
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,571| Average time : 0
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,572| Row Count : 10336
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,573| QUERY: [CRITERIA] select count(*) as y0_ from address_hierarchy_entry this_
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,574| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,574| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,575| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,576| Execution Count : 1
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,576| Average time : 265
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,577| Row Count : 1
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,578| QUERY: [CRITERIA] select this_.metadata_term_mapping_id as metadata1_85_1_, this_.metadata_source_id as metadata2_85_1_, this_.code as code3_85_1_, this_.metadata_class as metadata4_85_1_, this_.metadata_uuid as metadata5_85_1_, this_.name as name6_85_1_, this_.description as descript7_85_1_, this_.creator as creator8_85_1_, this_.date_created as date_cre9_85_1_, this_.changed_by as changed10_85_1_, this_.date_changed as date_ch11_85_1_, this_.retired as retired12_85_1_, this_.date_retired as date_re13_85_1_, this_.retired_by as retired14_85_1_, this_.retire_reason as retire_15_85_1_, this_.uuid as uuid16_85_1_, metadataso1_.metadata_source_id as metadata1_84_0_, metadataso1_.name as name2_84_0_, metadataso1_.description as descript3_84_0_, metadataso1_.creator as creator4_84_0_, metadataso1_.date_created as date_cre5_84_0_, metadataso1_.changed_by as changed_6_84_0_, metadataso1_.date_changed as date_cha7_84_0_, metadataso1_.retired as retired8_84_0_, metadataso1_.date_retired as date_ret9_84_0_, metadataso1_.retired_by as retired10_84_0_, metadataso1_.retire_reason as retire_11_84_0_, metadataso1_.uuid as uuid12_84_0_ from metadatamapping_metadata_term_mapping this_ inner join metadatamapping_metadata_source metadataso1_ on this_.metadata_source_id=metadataso1_.metadata_source_id where this_.retired=? and this_.code=? and metadataso1_.name=?
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,578| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,579| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,580| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,580| Execution Count : 152
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,581| Average time : 0
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,582| Row Count : 152
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,583| QUERY: [CRITERIA] select this_.visit_id as visit_id1_143_0_, this_.date_started as date_sta2_143_0_, this_.date_stopped as date_sto3_143_0_, this_.date_created as date_cre4_143_0_, this_.voided as voided5_143_0_, this_.date_voided as date_voi6_143_0_, this_.void_reason as void_rea7_143_0_, this_.date_changed as date_cha8_143_0_, this_.uuid as uuid9_143_0_, this_.visit_type_id as visit_t10_143_0_, this_.patient_id as patient11_143_0_, this_.indication_concept_id as indicat12_143_0_, this_.location_id as locatio13_143_0_, this_.creator as creator14_143_0_, this_.changed_by as changed15_143_0_, this_.voided_by as voided_16_143_0_ from visit this_ where this_.patient_id in (?) and this_.date_started<=? and this_.voided=? order by this_.date_started desc, this_.visit_id desc
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,583| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,584| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,585| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,585| Execution Count : 1
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,586| Average time : 0
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,587| Row Count : 4
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,588| QUERY: [CRITERIA] select this_.provider_id as provider1_115_0_, this_.person_id as person_i2_115_0_, this_.name as name3_115_0_, this_.identifier as identifi4_115_0_, this_.creator as creator5_115_0_, this_.date_created as date_cre6_115_0_, this_.changed_by as changed_7_115_0_, this_.date_changed as date_cha8_115_0_, this_.retired_by as retired_9_115_0_, this_.date_retired as date_re10_115_0_, this_.retire_reason as retire_11_115_0_, this_.retired as retired12_115_0_, this_.uuid as uuid13_115_0_, this_.provider_role_id as provide14_115_0_ from provider this_ order by this_.retired asc, this_.name asc
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,588| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,589| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,590| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,591| Execution Count : 6
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,591| Average time : 0
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,593| Row Count : 24
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,594| QUERY: select count(*) from HtmlForm where deprecatedName is not null or deprecatedDescription is not null
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,595| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,595| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,599| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,600| Execution Count : 2
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,601| Average time : 109
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,602| Row Count : 2
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,603| QUERY: [CRITERIA] select this_.imported_package_id as imported1_88_0_, this_.uuid as uuid2_88_0_, this_.group_uuid as group_uu3_88_0_, this_.date_created as date_cre4_88_0_, this_.date_imported as date_imp5_88_0_, this_.subscription_url as subscrip6_88_0_, this_.subscription_status as subscrip7_88_0_, this_.name as name8_88_0_, this_.description as descript9_88_0_, this_.import_config as import_10_88_0_, this_.remote_version as remote_11_88_0_, this_.version as version12_88_0_ from metadatasharing_imported_package this_ where this_.group_uuid=?
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,604| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,605| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,606| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,607| Execution Count : 8
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,608| Average time : 7
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,609| Row Count : 8
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,610| QUERY: SELECT appointment FROM Appointment AS appointment WHERE appointment.voided = 0 AND appointment.timeSlot.startDate >= :fromDate AND appointment.patient=:patient ORDER BY appointment.timeSlot.startDate
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,611| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,612| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,613| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,616| Execution Count : 1
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,617| Average time : 0
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,618| Row Count : 0
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,619| QUERY: [CRITERIA] select this_.metadata_source_id as metadata1_84_0_, this_.name as name2_84_0_, this_.description as descript3_84_0_, this_.creator as creator4_84_0_, this_.date_created as date_cre5_84_0_, this_.changed_by as changed_6_84_0_, this_.date_changed as date_cha7_84_0_, this_.retired as retired8_84_0_, this_.date_retired as date_ret9_84_0_, this_.retired_by as retired10_84_0_, this_.retire_reason as retire_11_84_0_, this_.uuid as uuid12_84_0_ from metadatamapping_metadata_source this_ where this_.name=?
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,620| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,621| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,622| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,623| Execution Count : 4
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,624| Average time : 69
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,626| Row Count : 4
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,627| QUERY: [CRITERIA] select this_.encounter_type_id as encounte1_56_0_, this_.name as name2_56_0_, this_.description as descript3_56_0_, this_.date_created as date_cre4_56_0_, this_.creator as creator5_56_0_, this_.edit_privilege as edit_pri6_56_0_, this_.view_privilege as view_pri7_56_0_, this_.uuid as uuid8_56_0_, this_.retired_by as retired_9_56_0_, this_.date_retired as date_re10_56_0_, this_.retire_reason as retire_11_56_0_, this_.retired as retired12_56_0_, this_.changed_by as changed13_56_0_, this_.date_changed as date_ch14_56_0_ from encounter_type this_ where this_.uuid=?
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,628| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,629| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,630| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,633| Execution Count : 110
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,634| Average time : 1
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,636| Row Count : 107
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,637| QUERY: [CRITERIA] select this_.property as property1_63_0_, this_.property_value as property2_63_0_, this_.description as descript3_63_0_, this_.datatype as datatype4_63_0_, this_.datatype_config as datatype5_63_0_, this_.preferred_handler as preferre6_63_0_, this_.handler_config as handler_7_63_0_, this_.uuid as uuid8_63_0_ from global_property this_ where lower(this_.property)=?
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,638| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,639| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,640| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,641| Execution Count : 4475
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,642| Average time : 0
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,643| Row Count : 2203
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,644| QUERY: [CRITERIA] select this_.provider_role_id as provider1_118_0_, this_.uuid as uuid2_118_0_, this_.name as name3_118_0_, this_.description as descript4_118_0_, this_.creator as creator5_118_0_, this_.date_created as date_cre6_118_0_, this_.changed_by as changed_7_118_0_, this_.date_changed as date_cha8_118_0_, this_.retired as retired9_118_0_, this_.retired_by as retired10_118_0_, this_.date_retired as date_re11_118_0_, this_.retire_reason as retire_12_118_0_ from providermanagement_provider_role this_ where this_.uuid=?
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,645| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,646| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,647| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,650| Execution Count : 3
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,651| Average time : 19
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,652| Row Count : 3
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,653| QUERY: [CRITERIA] select this_.encounter_id as encounte1_53_0_, this_.uuid as uuid2_53_0_, this_.encounter_datetime as encounte3_53_0_, this_.date_created as date_cre4_53_0_, this_.voided as voided5_53_0_, this_.date_voided as date_voi6_53_0_, this_.void_reason as void_rea7_53_0_, this_.date_changed as date_cha8_53_0_, this_.changed_by as changed_9_53_0_, this_.patient_id as patient10_53_0_, this_.location_id as locatio11_53_0_, this_.form_id as form_id12_53_0_, this_.encounter_type as encount13_53_0_, this_.creator as creator14_53_0_, this_.voided_by as voided_15_53_0_, this_.visit_id as visit_i16_53_0_ from encounter this_ where this_.visit_id=? and this_.voided=? order by this_.encounter_datetime asc
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,654| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,655| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,656| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,658| Execution Count : 1
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,659| Average time : 0
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,660| Row Count : 1
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,661| QUERY: from HL7InQueue as hiq where hiq.messageState = ? order by HL7InQueueId
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,661| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,662| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,663| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,664| Execution Count : 4451
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,666| Average time : 0
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,667| Row Count : 0
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,668| QUERY: from Patient p where p.uuid = :uuid
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,670| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,671| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,672| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,673| Execution Count : 98
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,674| Average time : 2
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,675| Row Count : 98
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,676| QUERY: [CRITERIA] select this_.provider_id as provider1_115_0_, this_.person_id as person_i2_115_0_, this_.name as name3_115_0_, this_.identifier as identifi4_115_0_, this_.creator as creator5_115_0_, this_.date_created as date_cre6_115_0_, this_.changed_by as changed_7_115_0_, this_.date_changed as date_cha8_115_0_, this_.retired_by as retired_9_115_0_, this_.date_retired as date_re10_115_0_, this_.retire_reason as retire_11_115_0_, this_.retired as retired12_115_0_, this_.uuid as uuid13_115_0_, this_.provider_role_id as provide14_115_0_ from provider this_ where this_.provider_id is not null and this_.person_id=? and this_.retired=? order by this_.provider_id asc
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,678| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,679| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,680| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,681| Execution Count : 3
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,683| Average time : 0
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,684| Row Count : 3
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,685| QUERY: [CRITERIA] select this_.obs_id as obs_id1_92_0_, this_.uuid as uuid2_92_0_, this_.person_id as person_i3_92_0_, this_.obs_datetime as obs_date4_92_0_, this_.value_group_id as value_gr5_92_0_, this_.value_datetime as value_da6_92_0_, this_.value_numeric as value_nu7_92_0_, this_.value_modifier as value_mo8_92_0_, this_.value_text as value_te9_92_0_, this_.value_complex as value_c10_92_0_, this_.comments as comment11_92_0_, this_.accession_number as accessi12_92_0_, this_.date_created as date_cr13_92_0_, this_.voided as voided14_92_0_, this_.date_voided as date_vo15_92_0_, this_.void_reason as void_re16_92_0_, this_.form_namespace_and_path as form_na17_92_0_, this_.concept_id as concept18_92_0_, this_.value_coded as value_c19_92_0_, this_.value_coded_name_id as value_c20_92_0_, this_.value_drug as value_d21_92_0_, this_.order_id as order_i22_92_0_, this_.location_id as locatio23_92_0_, this_.encounter_id as encount24_92_0_, this_.creator as creator25_92_0_, this_.voided_by as voided_26_92_0_, this_.obs_group_id as obs_gro27_92_0_, this_.previous_version as previou28_92_0_ from obs this_ where this_.person_id in (?) and this_.concept_id in (?) and this_.voided=? order by this_.obs_datetime desc
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,686| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,687| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,690| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,691| Execution Count : 200
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,692| Average time : 1
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,693| Row Count : 87
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,694| QUERY: [CRITERIA] select this_.location_id as location1_77_0_, this_.uuid as uuid2_77_0_, this_.name as name3_77_0_, this_.description as descript4_77_0_, this_.address1 as address5_77_0_, this_.address2 as address6_77_0_, this_.city_village as city_vil7_77_0_, this_.state_province as state_pr8_77_0_, this_.country as country9_77_0_, this_.postal_code as postal_10_77_0_, this_.latitude as latitud11_77_0_, this_.longitude as longitu12_77_0_, this_.county_district as county_13_77_0_, this_.address3 as address14_77_0_, this_.address4 as address15_77_0_, this_.address6 as address16_77_0_, this_.address5 as address17_77_0_, this_.address7 as address18_77_0_, this_.address8 as address19_77_0_, this_.address9 as address20_77_0_, this_.address10 as address21_77_0_, this_.address11 as address22_77_0_, this_.address12 as address23_77_0_, this_.address13 as address24_77_0_, this_.address14 as address25_77_0_, this_.address15 as address26_77_0_, this_.date_created as date_cr27_77_0_, this_.date_changed as date_ch28_77_0_, this_.parent_location as parent_29_77_0_, this_.changed_by as changed30_77_0_, this_.creator as creator31_77_0_, this_.retired_by as retired32_77_0_, this_.date_retired as date_re33_77_0_, this_.retire_reason as retire_34_77_0_, this_.retired as retired35_77_0_ from location this_ where this_.retired=? order by this_.name asc
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,696| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,697| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,699| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,700| Execution Count : 63
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,702| Average time : 4
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,703| Row Count : 504
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,704| QUERY: select encounter_datetime from encounter where encounter_id = ?
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,705| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,706| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,707| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,710| Execution Count : 2
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,711| Average time : 22
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,713| Row Count : 2
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,714| QUERY: from Form f where f.uuid = :uuid
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,717| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,718| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,720| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,722| Execution Count : 9
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,723| Average time : 0
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,724| Row Count : 9
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,725| QUERY: [CRITERIA] select this_.encounter_type_id as encounte1_56_0_, this_.name as name2_56_0_, this_.description as descript3_56_0_, this_.date_created as date_cre4_56_0_, this_.creator as creator5_56_0_, this_.edit_privilege as edit_pri6_56_0_, this_.view_privilege as view_pri7_56_0_, this_.uuid as uuid8_56_0_, this_.retired_by as retired_9_56_0_, this_.date_retired as date_re10_56_0_, this_.retire_reason as retire_11_56_0_, this_.retired as retired12_56_0_, this_.changed_by as changed13_56_0_, this_.date_changed as date_ch14_56_0_ from encounter_type this_ where this_.retired=? and this_.name=?
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,727| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,728| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,729| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,731| Execution Count : 3
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,733| Average time : 0
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,736| Row Count : 0
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,737| QUERY: from PersonAttributeType pat where pat.uuid = :uuid
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,738| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,740| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,741| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,743| Execution Count : 1
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,744| Average time : 231
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,746| Row Count : 1
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,747| QUERY: [CRITERIA] select this_.app_id as app_id1_6_0_, this_.json as json2_6_0_ from appframework_user_app this_
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,748| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,750| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,751| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,754| Execution Count : 1
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,756| Average time : 82
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,758| Row Count : 7
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,760| QUERY: from VisitType vt where vt.uuid = :uuid
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,761| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,763| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,764| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,767| Execution Count : 2
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,768| Average time : 59
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,770| Row Count : 1
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,771| QUERY: [CRITERIA] select this_.visit_type_id as visit_ty1_146_0_, this_.name as name2_146_0_, this_.description as descript3_146_0_, this_.uuid as uuid4_146_0_, this_.date_created as date_cre5_146_0_, this_.date_changed as date_cha6_146_0_, this_.date_retired as date_ret7_146_0_, this_.retired as retired8_146_0_, this_.retire_reason as retire_r9_146_0_, this_.creator as creator10_146_0_, this_.changed_by as changed11_146_0_, this_.retired_by as retired12_146_0_ from visit_type this_
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,772| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,773| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,775| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,776| Execution Count : 58
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,776| Average time : 0
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,777| Row Count : 58
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,778| QUERY: [CRITERIA] select this_.appointment_type_id as appointm1_11_0_, this_.name as name2_11_0_, this_.description as descript3_11_0_, this_.duration as duration4_11_0_, this_.confidential as confiden5_11_0_, this_.uuid as uuid6_11_0_, this_.date_created as date_cre7_11_0_, this_.date_changed as date_cha8_11_0_, this_.date_retired as date_ret9_11_0_, this_.retired as retired10_11_0_, this_.retire_reason as retire_11_11_0_, this_.creator as creator12_11_0_, this_.changed_by as changed13_11_0_, this_.retired_by as retired14_11_0_ from appointmentscheduling_appointment_type this_ where lower(this_.name)=? and this_.retired=? and not (this_.uuid=?)
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,779| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,781| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,782| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,783| Execution Count : 44
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,784| Average time : 0
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,786| Row Count : 0
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,787| QUERY: [CRITERIA] select this_.visit_attribute_type_id as visit_at1_145_0_, this_.name as name2_145_0_, this_.description as descript3_145_0_, this_.datatype as datatype4_145_0_, this_.datatype_config as datatype5_145_0_, this_.preferred_handler as preferre6_145_0_, this_.handler_config as handler_7_145_0_, this_.min_occurs as min_occu8_145_0_, this_.max_occurs as max_occu9_145_0_, this_.creator as creator10_145_0_, this_.date_created as date_cr11_145_0_, this_.changed_by as changed12_145_0_, this_.date_changed as date_ch13_145_0_, this_.retired as retired14_145_0_, this_.retired_by as retired15_145_0_, this_.date_retired as date_re16_145_0_, this_.retire_reason as retire_17_145_0_, this_.uuid as uuid18_145_0_ from visit_attribute_type this_
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,789| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,790| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,792| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,793| Execution Count : 60
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,794| Average time : 0
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,796| Row Count : 0
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,797| QUERY: select location_id from encounter where encounter_id = ?
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,798| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,800| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,801| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,803| Execution Count : 2
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,805| Average time : 0
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,806| Row Count : 2
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,808| QUERY: select
o.obsGroup
from
Obs o
where
o.voided = 'false'
and (o.encounter.visit = :visitId)
and o.concept.conceptId = :diagnosisOrderConceptId
and o.valueCoded.conceptId = :primaryOrderConceptId
group by o.encounter, o.obsGroup
order by o.encounter.encounterDatetime desc, o.obsGroup.obsDatetime desc
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,810| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,812| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,813| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,817| Execution Count : 45
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,820| Average time : 27
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,822| Row Count : 18
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,824| QUERY: select salt from users where user_id = ?
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,825| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,827| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,828| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,830| Execution Count : 3
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,832| Average time : 0
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,834| Row Count : 3
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,838| QUERY: [CRITERIA] select this_.provider_id as provider1_115_0_, this_.person_id as person_i2_115_0_, this_.name as name3_115_0_, this_.identifier as identifi4_115_0_, this_.creator as creator5_115_0_, this_.date_created as date_cre6_115_0_, this_.changed_by as changed_7_115_0_, this_.date_changed as date_cha8_115_0_, this_.retired_by as retired_9_115_0_, this_.date_retired as date_re10_115_0_, this_.retire_reason as retire_11_115_0_, this_.retired as retired12_115_0_, this_.uuid as uuid13_115_0_, this_.provider_role_id as provide14_115_0_ from provider this_ where this_.provider_id is not null and this_.person_id=? order by this_.provider_id asc
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,840| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,858| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,861| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,861| Execution Count : 3
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,862| Average time : 0
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,862| Row Count : 3
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,863| QUERY: [CRITERIA] select this_.patient_identifier_type_id as patient_1_102_0_, this_.uuid as uuid2_102_0_, this_.name as name3_102_0_, this_.description as descript4_102_0_, this_.format as format5_102_0_, this_.format_description as format_d6_102_0_, this_.required as required7_102_0_, this_.validator as validato8_102_0_, this_.date_created as date_cre9_102_0_, this_.location_behavior as locatio10_102_0_, this_.uniqueness_behavior as uniquen11_102_0_, this_.creator as creator12_102_0_, this_.retired_by as retired13_102_0_, this_.date_retired as date_re14_102_0_, this_.retire_reason as retire_15_102_0_, this_.retired as retired16_102_0_ from patient_identifier_type this_ where this_.uuid=?
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,864| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,864| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,864| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,865| Execution Count : 21
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,865| Average time : 0
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,866| Row Count : 21
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,866| QUERY: select password from users where user_id = ?
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,867| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,867| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,868| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,868| Execution Count : 3
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,869| Average time : 0
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,869| Row Count : 3
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,870| QUERY: from Concept c where c.uuid = :uuid
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,870| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,871| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,871| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,872| Execution Count : 41
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,872| Average time : 14
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,873| Row Count : 41
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,873| QUERY: [CRITERIA] select this_.provider_id as provider1_115_0_, this_.person_id as person_i2_115_0_, this_.name as name3_115_0_, this_.identifier as identifi4_115_0_, this_.creator as creator5_115_0_, this_.date_created as date_cre6_115_0_, this_.changed_by as changed_7_115_0_, this_.date_changed as date_cha8_115_0_, this_.retired_by as retired_9_115_0_, this_.date_retired as date_re10_115_0_, this_.retire_reason as retire_11_115_0_, this_.retired as retired12_115_0_, this_.uuid as uuid13_115_0_, this_.provider_role_id as provide14_115_0_ from provider this_ where this_.retired=? order by this_.name asc
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,874| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,874| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,875| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,875| Execution Count : 21
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,876| Average time : 0
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,876| Row Count : 84
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,877| QUERY: [CRITERIA] select this_.serialized_object_id as serializ1_137_0_, this_.uuid as uuid2_137_0_, this_.name as name3_137_0_, this_.description as descript4_137_0_, this_.type as type5_137_0_, this_.subtype as subtype6_137_0_, this_.serialization_class as serializ7_137_0_, this_.serialized_data as serializ8_137_0_, this_.date_created as date_cre9_137_0_, this_.date_changed as date_ch10_137_0_, this_.retired as retired11_137_0_, this_.date_retired as date_re12_137_0_, this_.retire_reason as retire_13_137_0_, this_.creator as creator14_137_0_, this_.changed_by as changed15_137_0_, this_.retired_by as retired16_137_0_ from serialized_object this_ where (this_.type=? or this_.subtype=?)
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,877| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,878| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,878| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,879| Execution Count : 5
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,881| Average time : 86
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,881| Row Count : 100
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,882| QUERY: [CRITERIA] select this_.component_state_id as componen1_5_0_, this_.uuid as uuid2_5_0_, this_.component_id as componen3_5_0_, this_.component_type as componen4_5_0_, this_.enabled as enabled5_5_0_ from appframework_component_state this_ where this_.component_id=? and this_.component_type=?
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,882| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,883| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,884| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,884| Execution Count : 9203
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,885| Average time : 0
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,885| Row Count : 2775
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,886| QUERY: [CRITERIA] select this_.task_config_id as task_con1_135_0_, this_.uuid as uuid2_135_0_, this_.name as name3_135_0_, this_.description as descript4_135_0_, this_.schedulable_class as schedula5_135_0_, this_.repeat_interval as repeat_i6_135_0_, this_.start_time as start_ti7_135_0_, this_.start_time_pattern as start_ti8_135_0_, this_.last_execution_time as last_exe9_135_0_, this_.start_on_startup as start_o10_135_0_, this_.started as started11_135_0_, this_.created_by as created12_135_0_, this_.changed_by as changed13_135_0_, this_.date_created as date_cr14_135_0_, this_.date_changed as date_ch15_135_0_ from scheduler_task_config this_
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,886| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,887| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,887| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,888| Execution Count : 3
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,888| Average time : 41
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,889| Row Count : 12
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,889| QUERY: select count(*) from User u where (u.username = :uname1 or u.systemId = :uname2 or u.username = :sysid1 or u.systemId = :sysid2 or u.systemId = :uname3) and u.userId <> :uid
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,890| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,890| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,891| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,891| Execution Count : 17
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,892| Average time : 9
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,893| Row Count : 17
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,893| QUERY: [CRITERIA] select this_.form_resource_id as form_res1_62_0_, this_.form_id as form_id2_62_0_, this_.name as name3_62_0_, this_.value_reference as value_re4_62_0_, this_.datatype as datatype5_62_0_, this_.datatype_config as datatype6_62_0_, this_.preferred_handler as preferre7_62_0_, this_.handler_config as handler_8_62_0_, this_.uuid as uuid9_62_0_ from form_resource this_ where this_.form_id=?
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,894| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,894| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,895| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,895| Execution Count : 33
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,896| Average time : 13
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,896| Row Count : 31
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,897| QUERY: [CRITERIA] select this_.form_id as form_id1_60_0_, this_.uuid as uuid2_60_0_, this_.name as name3_60_0_, this_.version as version4_60_0_, this_.build as build5_60_0_, this_.published as publishe6_60_0_, this_.description as descript7_60_0_, this_.encounter_type as encounte8_60_0_, this_.creator as creator9_60_0_, this_.date_created as date_cr10_60_0_, this_.changed_by as changed11_60_0_, this_.date_changed as date_ch12_60_0_, this_.retired as retired13_60_0_, this_.retired_by as retired14_60_0_, this_.date_retired as date_re15_60_0_, this_.retired_reason as retired16_60_0_ from form this_ order by this_.name asc, this_.form_id asc
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,897| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,898| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,899| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,899| Execution Count : 1
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,900| Average time : 205
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,900| Row Count : 33
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,901| QUERY: [CRITERIA] select this_.obs_id as obs_id1_92_0_, this_.uuid as uuid2_92_0_, this_.person_id as person_i3_92_0_, this_.obs_datetime as obs_date4_92_0_, this_.value_group_id as value_gr5_92_0_, this_.value_datetime as value_da6_92_0_, this_.value_numeric as value_nu7_92_0_, this_.value_modifier as value_mo8_92_0_, this_.value_text as value_te9_92_0_, this_.value_complex as value_c10_92_0_, this_.comments as comment11_92_0_, this_.accession_number as accessi12_92_0_, this_.date_created as date_cr13_92_0_, this_.voided as voided14_92_0_, this_.date_voided as date_vo15_92_0_, this_.void_reason as void_re16_92_0_, this_.form_namespace_and_path as form_na17_92_0_, this_.concept_id as concept18_92_0_, this_.value_coded as value_c19_92_0_, this_.value_coded_name_id as value_c20_92_0_, this_.value_drug as value_d21_92_0_, this_.order_id as order_i22_92_0_, this_.location_id as locatio23_92_0_, this_.encounter_id as encount24_92_0_, this_.creator as creator25_92_0_, this_.voided_by as voided_26_92_0_, this_.obs_group_id as obs_gro27_92_0_, this_.previous_version as previou28_92_0_ from obs this_ where this_.person_id in (?) and this_.concept_id in (?) and this_.obs_datetime>=? and this_.voided=? order by this_.obs_datetime desc
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,901| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,903| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,904| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,904| Execution Count : 4
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,905| Average time : 17
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,906| Row Count : 12
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,907| QUERY: [CRITERIA] select this_.provider_id as provider1_115_0_, this_.person_id as person_i2_115_0_, this_.name as name3_115_0_, this_.identifier as identifi4_115_0_, this_.creator as creator5_115_0_, this_.date_created as date_cre6_115_0_, this_.changed_by as changed_7_115_0_, this_.date_changed as date_cha8_115_0_, this_.retired_by as retired_9_115_0_, this_.date_retired as date_re10_115_0_, this_.retire_reason as retire_11_115_0_, this_.retired as retired12_115_0_, this_.uuid as uuid13_115_0_, this_.provider_role_id as provide14_115_0_ from provider this_ where this_.uuid=?
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,907| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,908| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,909| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,909| Execution Count : 1
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,910| Average time : 43
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,911| Row Count : 1
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,911| QUERY: [CRITERIA] select this_.visit_id as visit_id1_143_0_, this_.date_started as date_sta2_143_0_, this_.date_stopped as date_sto3_143_0_, this_.date_created as date_cre4_143_0_, this_.voided as voided5_143_0_, this_.date_voided as date_voi6_143_0_, this_.void_reason as void_rea7_143_0_, this_.date_changed as date_cha8_143_0_, this_.uuid as uuid9_143_0_, this_.visit_type_id as visit_t10_143_0_, this_.patient_id as patient11_143_0_, this_.indication_concept_id as indicat12_143_0_, this_.location_id as locatio13_143_0_, this_.creator as creator14_143_0_, this_.changed_by as changed15_143_0_, this_.voided_by as voided_16_143_0_ from visit this_ where this_.patient_id in (?) and this_.voided=? order by this_.date_started desc, this_.visit_id desc
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,912| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,913| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,914| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,915| Execution Count : 66
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,916| Average time : 2
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,917| Row Count : 318
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,918| QUERY: [CRITERIA] select this_.concept_source_id as concept_1_42_0_, this_.uuid as uuid2_42_0_, this_.name as name3_42_0_, this_.description as descript4_42_0_, this_.hl7_code as hl5_42_0_, this_.date_created as date_cre6_42_0_, this_.retired as retired7_42_0_, this_.date_retired as date_ret8_42_0_, this_.retire_reason as retire_r9_42_0_, this_.creator as creator10_42_0_, this_.retired_by as retired11_42_0_ from concept_reference_source this_ where this_.name=?
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,919| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,920| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,920| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,922| Execution Count : 51
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,923| Average time : 20
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,925| Row Count : 51
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,926| QUERY: [CRITERIA] select this_.relationship_id as relation1_124_0_, this_.uuid as uuid2_124_0_, this_.start_date as start_da3_124_0_, this_.end_date as end_date4_124_0_, this_.voided as voided5_124_0_, this_.date_voided as date_voi6_124_0_, this_.void_reason as void_rea7_124_0_, this_.date_created as date_cre8_124_0_, this_.person_a as person_a9_124_0_, this_.person_b as person_10_124_0_, this_.relationship as relatio11_124_0_, this_.creator as creator12_124_0_, this_.voided_by as voided_13_124_0_, this_.changed_by as changed14_124_0_, this_.date_changed as date_ch15_124_0_ from relationship this_ where this_.person_b=? and this_.voided=?
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,926| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,927| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,928| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,929| Execution Count : 17
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,929| Average time : 14
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,930| Row Count : 17
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,932| QUERY: [CRITERIA] select this_.location_tag_id as location1_80_0_, this_.name as name2_80_0_, this_.description as descript3_80_0_, this_.date_created as date_cre4_80_0_, this_.creator as creator5_80_0_, this_.retired_by as retired_6_80_0_, this_.date_retired as date_ret7_80_0_, this_.retire_reason as retire_r8_80_0_, this_.retired as retired9_80_0_, this_.uuid as uuid10_80_0_, this_.date_changed as date_ch11_80_0_, this_.changed_by as changed12_80_0_ from location_tag this_ where this_.name=?
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,933| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,934| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,935| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,935| Execution Count : 33
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,936| Average time : 28
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,937| Row Count : 33
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,938| QUERY: [CRITERIA] select this_.serialized_object_id as serializ1_137_0_, this_.uuid as uuid2_137_0_, this_.name as name3_137_0_, this_.description as descript4_137_0_, this_.type as type5_137_0_, this_.subtype as subtype6_137_0_, this_.serialization_class as serializ7_137_0_, this_.serialized_data as serializ8_137_0_, this_.date_created as date_cre9_137_0_, this_.date_changed as date_ch10_137_0_, this_.retired as retired11_137_0_, this_.date_retired as date_re12_137_0_, this_.retire_reason as retire_13_137_0_, this_.creator as creator14_137_0_, this_.changed_by as changed15_137_0_, this_.retired_by as retired16_137_0_ from serialized_object this_ where this_.uuid=?
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,938| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,939| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,940| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,941| Execution Count : 1
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,942| Average time : 1157
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,943| Row Count : 1
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,944| QUERY: [CRITERIA] select this_.encounter_id as encounte1_53_0_, this_.uuid as uuid2_53_0_, this_.encounter_datetime as encounte3_53_0_, this_.date_created as date_cre4_53_0_, this_.voided as voided5_53_0_, this_.date_voided as date_voi6_53_0_, this_.void_reason as void_rea7_53_0_, this_.date_changed as date_cha8_53_0_, this_.changed_by as changed_9_53_0_, this_.patient_id as patient10_53_0_, this_.location_id as locatio11_53_0_, this_.form_id as form_id12_53_0_, this_.encounter_type as encount13_53_0_, this_.creator as creator14_53_0_, this_.voided_by as voided_15_53_0_, this_.visit_id as visit_i16_53_0_ from encounter this_ where this_.patient_id=? and this_.encounter_type in (?) and this_.voided=? order by this_.encounter_datetime asc
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,946| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,947| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,948| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,949| Execution Count : 4
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,950| Average time : 0
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,951| Row Count : 4
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,952| QUERY: [CRITERIA] select this_.id as id1_131_0_, this_.uuid as uuid2_131_0_, this_.base_cohort_uuid as base_coh3_131_0_, this_.base_cohort_parameters as base_coh4_131_0_, this_.report_definition_uuid as report_d5_131_0_, this_.report_definition_parameters as report_d6_131_0_, this_.renderer_type as renderer7_131_0_, this_.renderer_argument as renderer8_131_0_, this_.requested_by as requeste9_131_0_, this_.request_datetime as request10_131_0_, this_.priority as priorit11_131_0_, this_.status as status12_131_0_, this_.schedule as schedul13_131_0_, this_.process_automatically as process14_131_0_, this_.evaluation_start_datetime as evaluat15_131_0_, this_.evaluation_complete_datetime as evaluat16_131_0_, this_.render_complete_datetime as render_17_131_0_, this_.minimum_days_to_preserve as minimum18_131_0_, this_.description as descrip19_131_0_ from reporting_report_request this_ where this_.status in (?) order by this_.evaluation_complete_datetime desc, this_.evaluation_start_datetime desc, this_.priority desc, this_.request_datetime desc
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,953| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,954| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,955| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,956| Execution Count : 688
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,957| Average time : 0
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,958| Row Count : 0
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,960| QUERY: [CRITERIA] select this_.encounter_id as encounte1_53_0_, this_.uuid as uuid2_53_0_, this_.encounter_datetime as encounte3_53_0_, this_.date_created as date_cre4_53_0_, this_.voided as voided5_53_0_, this_.date_voided as date_voi6_53_0_, this_.void_reason as void_rea7_53_0_, this_.date_changed as date_cha8_53_0_, this_.changed_by as changed_9_53_0_, this_.patient_id as patient10_53_0_, this_.location_id as locatio11_53_0_, this_.form_id as form_id12_53_0_, this_.encounter_type as encount13_53_0_, this_.creator as creator14_53_0_, this_.voided_by as voided_15_53_0_, this_.visit_id as visit_i16_53_0_ from encounter this_ where this_.patient_id=? and this_.encounter_datetime>=? and this_.voided=? order by this_.encounter_datetime asc
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,961| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,962| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,963| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,964| Execution Count : 3
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,965| Average time : 2
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,966| Row Count : 36
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,967| QUERY: [CRITERIA] select this_.id as id1_69_0_, this_.identifier_type as identifi2_69_0_, this_.location as location3_69_0_, this_.source as source4_69_0_, this_.manual_entry_enabled as manual_e5_69_0_, this_.automatic_generation_enabled as automati6_69_0_ from idgen_auto_generation_option this_ where this_.identifier_type=?
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,968| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,969| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,969| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,970| Execution Count : 2
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,972| Average time : 108
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,973| Row Count : 2
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,975| QUERY: [CRITERIA] select this_.address_hierarchy_entry_id as address_1_1_1_, this_.name as name2_1_1_, this_.level_id as level_id3_1_1_, this_.parent_id as parent_i4_1_1_, this_.user_generated_id as user_gen5_1_1_, this_.latitude as latitude6_1_1_, this_.longitude as longitud7_1_1_, this_.elevation as elevatio8_1_1_, this_.uuid as uuid9_1_1_, addresshie1_.address_hierarchy_level_id as address_1_2_0_, addresshie1_.parent_level_id as parent_l2_2_0_, addresshie1_.name as name3_2_0_, addresshie1_.address_field as address_4_2_0_, addresshie1_.required as required5_2_0_, addresshie1_.uuid as uuid6_2_0_ from address_hierarchy_entry this_ inner join address_hierarchy_level addresshie1_ on this_.level_id=addresshie1_.address_hierarchy_level_id where addresshie1_.address_hierarchy_level_id=?
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,976| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,977| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,978| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,979| Execution Count : 1
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,980| Average time : 1
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,981| Row Count : 2
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,982| QUERY: [CRITERIA] select this_.concept_id as y0_ from concept_reference_map this_ inner join concept concept3_ on this_.concept_id=concept3_.concept_id left outer join concept_numeric concept3_1_ on concept3_.concept_id=concept3_1_.concept_id left outer join concept_complex concept3_2_ on concept3_.concept_id=concept3_2_.concept_id inner join concept_reference_term term1_ on this_.concept_reference_term_id=term1_.concept_reference_term_id inner join concept_reference_source source2_ on term1_.concept_source_id=source2_.concept_source_id where lower(term1_.code)=? and (lower(source2_.name)=? or lower(source2_.hl7_code)=?) order by concept3_.retired asc
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,983| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,984| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,985| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,986| Execution Count : 335
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,986| Average time : 697
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,987| Row Count : 334
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,988| QUERY: from PatientIdentifierType pit where pit.uuid = :uuid
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,989| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,992| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:25,993| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:25,994| Execution Count : 2
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:25,995| Average time : 1172
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:25,996| Row Count : 2
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:25,997| QUERY: from Privilege p order by p.privilege
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:25,998| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:25,999| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:26,000| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:26,003| Execution Count : 1
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:26,006| Average time : 6
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:26,012| Row Count : 293
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:26,015| QUERY: from Person p where p.uuid = :uuid
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:26,018| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:26,022| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:26,025| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:26,027| Execution Count : 6
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:26,028| Average time : 2
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:26,029| Row Count : 6
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:26,030| QUERY: [CRITERIA] select this_.person_attribute_type_id as person_a1_108_0_, this_.uuid as uuid2_108_0_, this_.name as name3_108_0_, this_.description as descript4_108_0_, this_.format as format5_108_0_, this_.foreign_key as foreign_6_108_0_, this_.sort_weight as sort_wei7_108_0_, this_.searchable as searchab8_108_0_, this_.creator as creator9_108_0_, this_.date_created as date_cr10_108_0_, this_.changed_by as changed11_108_0_, this_.date_changed as date_ch12_108_0_, this_.retired_by as retired13_108_0_, this_.date_retired as date_re14_108_0_, this_.retire_reason as retire_15_108_0_, this_.retired as retired16_108_0_, this_.edit_privilege as edit_pr17_108_0_ from person_attribute_type this_ where this_.name=?
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:26,031| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:26,032| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:26,033| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:26,034| Execution Count : 38
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:26,035| Average time : 0
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:26,036| Row Count : 38
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:26,038| QUERY: [CRITERIA] select this_.provider_id as provider1_115_0_, this_.person_id as person_i2_115_0_, this_.name as name3_115_0_, this_.identifier as identifi4_115_0_, this_.creator as creator5_115_0_, this_.date_created as date_cre6_115_0_, this_.changed_by as changed_7_115_0_, this_.date_changed as date_cha8_115_0_, this_.retired_by as retired_9_115_0_, this_.date_retired as date_re10_115_0_, this_.retire_reason as retire_11_115_0_, this_.retired as retired12_115_0_, this_.uuid as uuid13_115_0_, this_.provider_role_id as provide14_115_0_ from provider this_ where this_.person_id=? order by this_.retired asc, this_.provider_id asc
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:26,039| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:26,041| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:26,042| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:26,045| Execution Count : 20
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:26,046| Average time : 0
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:26,048| Row Count : 20
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:26,050| QUERY: from User u where u.uuid = :uuid
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:26,051| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:26,052| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:26,054| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:26,055| Execution Count : 8303
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:26,058| Average time : 4
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:26,059| Row Count : 8303
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:26,061| QUERY: select count(*) from PatientIdentifier pi, Patient p where pi.patient.patientId = p.patient.patientId and p.voided = false and pi.voided = false and pi.identifier = :identifier and pi.identifierType = :idType and p.patientId != :ptId
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:26,062| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:26,063| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:26,064| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:26,066| Execution Count : 4
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:26,067| Average time : 7
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:26,071| Row Count : 4
INFO - HibernateContextDAO.showUsageStatistics(372) |2018-12-11 18:17:26,072| QUERY: from Role r where r.uuid = :uuid
INFO - HibernateContextDAO.showUsageStatistics(374) |2018-12-11 18:17:26,075| Cache Hit Count : 0
INFO - HibernateContextDAO.showUsageStatistics(375) |2018-12-11 18:17:26,076| Cache Miss Count: 0
INFO - HibernateContextDAO.showUsageStatistics(376) |2018-12-11 18:17:26,078| Cache Put Count : 0
INFO - HibernateContextDAO.showUsageStatistics(377) |2018-12-11 18:17:26,080| Execution Count : 1
INFO - HibernateContextDAO.showUsageStatistics(378) |2018-12-11 18:17:26,082| Average time : 0
INFO - HibernateContextDAO.showUsageStatistics(379) |2018-12-11 18:17:26,083| Row Count : 1
Didn't remove driver class: class com.mysql.jdbc.Driver with classloader of: sun.misc.Launcher$AppClassLoader@70dea4e
Dec 11, 2018 6:17:26 PM org.apache.catalina.core.StandardContext listenerStop
SEVERE: Exception sending context destroyed event to listener instance of class org.openmrs.web.Listener
java.lang.NullPointerException
at sun.reflect.UnsafeFieldAccessorImpl.ensureObj(UnsafeFieldAccessorImpl.java:57)
at sun.reflect.UnsafeObjectFieldAccessorImpl.get(UnsafeObjectFieldAccessorImpl.java:36)
at java.lang.reflect.Field.get(Field.java:393)
at org.openmrs.util.MemoryLeakUtil.shutdownMysqlCancellationTimer(MemoryLeakUtil.java:42)
at org.openmrs.web.Listener.contextDestroyed(Listener.java:604)
at org.apache.catalina.core.StandardContext.listenerStop(StandardContext.java:5002)
at org.apache.catalina.core.StandardContext.stopInternal(StandardContext.java:5647)
at org.apache.catalina.util.LifecycleBase.stop(LifecycleBase.java:232)
at org.apache.catalina.core.ContainerBase$StopChild.call(ContainerBase.java:1575)
at org.apache.catalina.core.ContainerBase$StopChild.call(ContainerBase.java:1564)
at java.util.concurrent.FutureTask.run(FutureTask.java:266)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
at java.lang.Thread.run(Thread.java:748)

Dec 11, 2018 6:17:26 PM org.apache.catalina.core.ApplicationContext log
INFO: Shutting down log4j
Exception in thread "Thread-9727" Exception in thread "Thread-9725" org.hibernate.service.UnknownServiceException: Unknown service requested [org.hibernate.engine.jdbc.connections.spi.ConnectionProvider]
at org.hibernate.service.internal.AbstractServiceRegistryImpl.getService(AbstractServiceRegistryImpl.java:201)
at org.hibernate.internal.AbstractSessionImpl.getJdbcConnectionAccess(AbstractSessionImpl.java:341)
at org.hibernate.engine.jdbc.internal.JdbcCoordinatorImpl.<init>(JdbcCoordinatorImpl.java:114)
at org.hibernate.engine.transaction.internal.TransactionCoordinatorImpl.<init>(TransactionCoordinatorImpl.java:89)
at org.hibernate.internal.SessionImpl.<init>(SessionImpl.java:258)
at org.hibernate.internal.SessionFactoryImpl$SessionBuilderImpl.openSession(SessionFactoryImpl.java:1589)
at org.hibernate.internal.SessionFactoryImpl.openSession(SessionFactoryImpl.java:999)
at org.openmrs.api.db.hibernate.HibernateContextDAO.openSession(HibernateContextDAO.java:270)
at sun.reflect.GeneratedMethodAccessor202.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)
at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:201)
at com.sun.proxy.$Proxy317.openSession(Unknown Source)
at org.openmrs.api.context.Context.openSession(Context.java:717)
at org.openmrs.api.context.Daemon$5.run(Daemon.java:283)
org.hibernate.service.UnknownServiceException: Unknown service requested [org.hibernate.engine.jdbc.connections.spi.ConnectionProvider]
at org.hibernate.service.internal.AbstractServiceRegistryImpl.getService(AbstractServiceRegistryImpl.java:201)
at org.hibernate.internal.AbstractSessionImpl.getJdbcConnectionAccess(AbstractSessionImpl.java:341)
at org.hibernate.engine.jdbc.internal.JdbcCoordinatorImpl.<init>(JdbcCoordinatorImpl.java:114)
at org.hibernate.engine.transaction.internal.TransactionCoordinatorImpl.<init>(TransactionCoordinatorImpl.java:89)
at org.hibernate.internal.SessionImpl.<init>(SessionImpl.java:258)
at org.hibernate.internal.SessionFactoryImpl$SessionBuilderImpl.openSession(SessionFactoryImpl.java:1589)
at org.hibernate.internal.SessionFactoryImpl.openSession(SessionFactoryImpl.java:999)
at org.openmrs.api.db.hibernate.HibernateContextDAO.openSession(HibernateContextDAO.java:270)
at sun.reflect.GeneratedMethodAccessor202.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)
at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:201)
at com.sun.proxy.$Proxy317.openSession(Unknown Source)
at org.openmrs.api.context.Context.openSession(Context.java:717)
at org.openmrs.api.context.Daemon$5.run(Daemon.java:283)
Exception in thread "Thread-9726" org.hibernate.service.UnknownServiceException: Unknown service requested [org.hibernate.engine.jdbc.connections.spi.ConnectionProvider]
at org.hibernate.service.internal.AbstractServiceRegistryImpl.getService(AbstractServiceRegistryImpl.java:201)
at org.hibernate.internal.AbstractSessionImpl.getJdbcConnectionAccess(AbstractSessionImpl.java:341)
at org.hibernate.engine.jdbc.internal.JdbcCoordinatorImpl.<init>(JdbcCoordinatorImpl.java:114)
at org.hibernate.engine.transaction.internal.TransactionCoordinatorImpl.<init>(TransactionCoordinatorImpl.java:89)
at org.hibernate.internal.SessionImpl.<init>(SessionImpl.java:258)
at org.hibernate.internal.SessionFactoryImpl$SessionBuilderImpl.openSession(SessionFactoryImpl.java:1589)
at org.hibernate.internal.SessionFactoryImpl.openSession(SessionFactoryImpl.java:999)
at org.openmrs.api.db.hibernate.HibernateContextDAO.openSession(HibernateContextDAO.java:270)
at sun.reflect.GeneratedMethodAccessor202.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)
at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:201)
at com.sun.proxy.$Proxy317.openSession(Unknown Source)
at org.openmrs.api.context.Context.openSession(Context.java:717)
at org.openmrs.api.context.Daemon$5.run(Daemon.java:283)
Dec 11, 2018 6:17:31 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/openmrs-standalone] appears to have started a thread named [Thread-4] but has failed to stop it. This is very likely to create a memory leak.
Dec 11, 2018 6:17:31 PM org.apache.catalina.loader.WebappClassLoader checkThreadLocalMapForLeaks
SEVERE: The web application [/openmrs-standalone] created a ThreadLocal with key of type [org.apache.log4j.helpers.ThreadLocalMap] (value [org.apache.log4j.helpers.ThreadLocalMap@2141af10]) and a value of type [java.util.Hashtable] (value [{}]) but failed to remove it when the web application was stopped. Threads are going to be renewed over time to try and avoid a probable memory leak.
Dec 11, 2018 6:17:31 PM org.apache.catalina.loader.WebappClassLoader checkThreadLocalMapForLeaks
SEVERE: The web application [/openmrs-standalone] created a ThreadLocal with key of type [org.springframework.core.NamedThreadLocal] (value [Transactional resources]) and a value of type [java.util.HashMap] (value [{org.hibernate.internal.SessionFactoryImpl@3265bd5d=org.springframework.orm.hibernate4.SessionHolder@2c0073ac}]) but failed to remove it when the web application was stopped. Threads are going to be renewed over time to try and avoid a probable memory leak.
Dec 11, 2018 6:17:31 PM org.apache.catalina.loader.WebappClassLoader checkThreadLocalMapForLeaks
SEVERE: The web application [/openmrs-standalone] created a ThreadLocal with key of type [org.apache.log4j.helpers.ThreadLocalMap] (value [org.apache.log4j.helpers.ThreadLocalMap@2141af10]) and a value of type [java.util.Hashtable] (value [{}]) but failed to remove it when the web application was stopped. Threads are going to be renewed over time to try and avoid a probable memory leak.
Dec 11, 2018 6:17:31 PM org.apache.catalina.loader.WebappClassLoader checkThreadLocalMapForLeaks
SEVERE: The web application [/openmrs-standalone] created a ThreadLocal with key of type [org.apache.log4j.helpers.ThreadLocalMap] (value [org.apache.log4j.helpers.ThreadLocalMap@2141af10]) and a value of type [java.util.Hashtable] (value [{}]) but failed to remove it when the web application was stopped. Threads are going to be renewed over time to try and avoid a probable memory leak.
Dec 11, 2018 6:17:31 PM org.apache.catalina.loader.WebappClassLoader checkThreadLocalMapForLeaks
SEVERE: The web application [/openmrs-standalone] created a ThreadLocal with key of type [org.apache.log4j.helpers.ThreadLocalMap] (value [org.apache.log4j.helpers.ThreadLocalMap@2141af10]) and a value of type [java.util.Hashtable] (value [{}]) but failed to remove it when the web application was stopped. Threads are going to be renewed over time to try and avoid a probable memory leak.
Dec 11, 2018 6:17:31 PM org.apache.catalina.loader.WebappClassLoader checkThreadLocalMapForLeaks
SEVERE: The web application [/openmrs-standalone] created a ThreadLocal with key of type [org.apache.log4j.helpers.ThreadLocalMap] (value [org.apache.log4j.helpers.ThreadLocalMap@2141af10]) and a value of type [java.util.Hashtable] (value [{}]) but failed to remove it when the web application was stopped. Threads are going to be renewed over time to try and avoid a probable memory leak.
Dec 11, 2018 6:17:31 PM org.apache.catalina.loader.WebappClassLoader checkThreadLocalMapForLeaks
SEVERE: The web application [/openmrs-standalone] created a ThreadLocal with key of type [org.apache.log4j.helpers.ThreadLocalMap] (value [org.apache.log4j.helpers.ThreadLocalMap@2141af10]) and a value of type [java.util.Hashtable] (value [{}]) but failed to remove it when the web application was stopped. Threads are going to be renewed over time to try and avoid a probable memory leak.
Dec 11, 2018 6:17:31 PM org.apache.catalina.loader.WebappClassLoader checkThreadLocalMapForLeaks
SEVERE: The web application [/openmrs-standalone] created a ThreadLocal with key of type [org.springframework.core.NamedThreadLocal] (value [Transactional resources]) and a value of type [java.util.HashMap] (value [{org.hibernate.internal.SessionFactoryImpl@3265bd5d=org.springframework.orm.hibernate4.SessionHolder@358d43ae}]) but failed to remove it when the web application was stopped. Threads are going to be renewed over time to try and avoid a probable memory leak.
Dec 11, 2018 6:17:31 PM org.apache.catalina.loader.WebappClassLoader checkThreadLocalMapForLeaks
SEVERE: The web application [/openmrs-standalone] created a ThreadLocal with key of type [org.apache.log4j.helpers.ThreadLocalMap] (value [org.apache.log4j.helpers.ThreadLocalMap@2141af10]) and a value of type [java.util.Hashtable] (value [{}]) but failed to remove it when the web application was stopped. Threads are going to be renewed over time to try and avoid a probable memory leak.
Dec 11, 2018 6:17:31 PM org.apache.catalina.loader.WebappClassLoader checkThreadLocalMapForLeaks
SEVERE: The web application [/openmrs-standalone] created a ThreadLocal with key of type [org.apache.log4j.helpers.ThreadLocalMap] (value [org.apache.log4j.helpers.ThreadLocalMap@2141af10]) and a value of type [java.util.Hashtable] (value [{}]) but failed to remove it when the web application was stopped. Threads are going to be renewed over time to try and avoid a probable memory leak.
Dec 11, 2018 6:17:31 PM org.apache.catalina.util.LifecycleBase stop
INFO: The stop() method was called on component [StandardService[null]] after stop() had already been called. The second call will be ignored.
Dec 11, 2018 6:17:31 PM org.apache.catalina.util.LifecycleBase stop
INFO: The stop() method was called on component [StandardService[null]] after stop() had already been called. The second call will be ignored.
[MysqldResource] stopping mysqld (process: 20154)
[MysqldResource] shutdown complete
[MysqldResource] Mysqld not running. No file: /home/heisenberg/NigeriaMRS/NigeriaMRS/database/data/MysqldResource.pid
[MysqldResource] Mysqld not running. No file: /home/heisenberg/NigeriaMRS/NigeriaMRS/database/data/MysqldResource.pid
Attempting to load properties file in current directory: openmrs-standalone-runtime.properties
Using runtime properties file: openmrs-standalone-runtime.properties
Dec 11, 2018 6:18:06 PM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["http-bio-8081"]
Dec 11, 2018 6:18:06 PM org.apache.catalina.startup.Embedded startInternal
INFO: Starting tomcat server
Dec 11, 2018 6:18:06 PM org.apache.catalina.core.StandardEngine startInternal
INFO: Starting Servlet Engine: Apache Tomcat/7.0.50
Dec 11, 2018 6:18:14 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://www.springframework.org/tags/form is already defined
Dec 11, 2018 6:18:15 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://www.springframework.org/tags/form is already defined
Dec 11, 2018 6:18:15 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/functions is already defined
Dec 11, 2018 6:18:15 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://www.springframework.org/tags is already defined
Dec 11, 2018 6:18:15 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://jakarta.apache.org/taglibs/response-1.0 is already defined
Dec 11, 2018 6:18:15 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/core_rt is already defined
Dec 11, 2018 6:18:15 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://jakarta.apache.org/taglibs/page-1.0 is already defined
Dec 11, 2018 6:18:15 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://jakarta.apache.org/taglibs/request-1.0 is already defined
Dec 11, 2018 6:18:15 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://jakarta.apache.org/taglibs/request-1.0 is already defined
Dec 11, 2018 6:18:15 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://www.springframework.org/tags is already defined
Dec 11, 2018 6:18:15 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://www.springframework.org/tags/form is already defined
Dec 11, 2018 6:18:15 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://jakarta.apache.org/taglibs/response-1.0 is already defined
Dec 11, 2018 6:18:15 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jstl/core_rt is already defined
Dec 11, 2018 6:18:15 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://java.sun.com/jsp/jstl/functions is already defined
Dec 11, 2018 6:18:15 PM org.apache.catalina.startup.TaglibUriRule body
INFO: TLD skipped. URI: http://jakarta.apache.org/taglibs/page-1.0 is already defined
Dec 11, 2018 6:18:15 PM org.apache.catalina.core.ApplicationContext log
INFO: No Spring WebApplicationInitializer types detected on classpath
Dec 11, 2018 6:18:15 PM org.apache.catalina.core.ApplicationContext log
INFO: Initializing log4j from [classpath:log4j.xml]
INFO - Listener.contextInitialized(174) |2018-12-11 18:18:16,067| Using runtime properties file: openmrs-standalone-runtime.properties
[/home/heisenberg/NigeriaMRS/NigeriaMRS/database/bin/mysqld][--no-defaults][--basedir=/home/heisenberg/NigeriaMRS/NigeriaMRS/database][--max_allowed_packet=96M][--port=3316][--datadir=/home/heisenberg/NigeriaMRS/NigeriaMRS/database/data][--character-set-server=utf8][--socket=mysql.sock][--collation-server=utf8_general_ci][--pid-file=/home/heisenberg/NigeriaMRS/NigeriaMRS/database/data/MysqldResource.pid]
[MysqldResource] launching mysqld (driver_launched_mysqld_1)
[MysqldResource] mysqld running as process: 26078
INFO 12/11/18 6:18 PM:liquibase: Reading from `liquibasechangelog`
WARNING 12/11/18 6:18 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use <sql> and re-specify all configuration if this is the case
WARNING 12/11/18 6:18 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use <sql> and re-specify all configuration if this is the case
WARNING 12/11/18 6:18 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use <sql> and re-specify all configuration if this is the case
WARN - SimpleUrlHandlerMapping.registerHandlers(115) |2018-12-11 18:18:32,891| Neither 'urlMap' nor 'mappings' set on SimpleUrlHandlerMapping
WARN - Listener.loadBundledModules(523) |2018-12-11 18:18:32,980| Bundled module folder doesn't exist: /home/heisenberg/NigeriaMRS/NigeriaMRS/tomcat/webapps/openmrs-standalone/WEB-INF/bundledModules
INFO 12/11/18 6:18 PM:liquibase: Reading from `liquibasechangelog`
WARNING 12/11/18 6:18 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use <sql> and re-specify all configuration if this is the case
WARNING 12/11/18 6:18 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use <sql> and re-specify all configuration if this is the case
WARNING 12/11/18 6:18 PM:liquibase: modifyDataType will lose primary key/autoincrement/not null settings for mysql. Use <sql> and re-specify all configuration if this is the case
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:35,076| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: layout.address.format value: <org.openmrs.layout.address.AddressTemplate>
<nameMappings class="properties">
<property name="postalCode" value="Location.postalCode"/>
<property name="address2" value="Location.address2"/>
<property name="address1" value="Location.address1"/>
<property name="country" value="Location.country"/>
<property name="stateProvince" value="Location.stateProvince"/>
<property name="cityVillage" value="Location.cityVillage"/>
</nameMappings>
<sizeMappings class="properties">
<property name="postalCode" value="10"/>
<property name="address2" value="40"/>
<property name="address1" value="40"/>
<property name="country" value="10"/>
<property name="stateProvince" value="10"/>
<property name="cityVillage" value="10"/>
</sizeMappings>
<lineByLineFormat>
<string>address1</string>
<string>address2</string>
<string>cityVillage stateProvince country postalCode</string>
</lineByLineFormat>
</org.openmrs.layout.address.AddressTemplate>,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:35,225| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:36,427| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: event.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:36,447| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:36,456| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: event.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:36,469| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:36,577| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: legacyui.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:36,589| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:36,594| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: legacyui.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:36,602| Exiting method saveGlobalProperty
INFO 12/11/18 6:18 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0
INFO 12/11/18 6:18 PM:liquibase: Reading from `liquibasechangelog`
INFO 12/11/18 6:18 PM:liquibase: Successfully acquired change log lock
INFO 12/11/18 6:18 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0
INFO 12/11/18 6:18 PM:liquibase: Successfully released change log lock
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:38,080| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: fhir.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:38,087| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:38,093| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: fhir.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:38,102| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:38,191| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: dataexchange.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:38,203| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:38,210| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: dataexchange.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:38,220| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:38,274| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: reportingcompatibility.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:38,281| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:38,285| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: reportingcompatibility.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:38,295| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:38,494| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: webservices.rest.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:38,500| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:38,507| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: webservices.rest.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:38,517| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:38,599| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: serialization.xstream.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:38,607| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:38,614| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: serialization.xstream.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:38,623| Exiting method saveGlobalProperty
INFO 12/11/18 6:18 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0
INFO 12/11/18 6:18 PM:liquibase: Reading from `liquibasechangelog`
INFO 12/11/18 6:18 PM:liquibase: Successfully acquired change log lock
INFO 12/11/18 6:18 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0
INFO 12/11/18 6:18 PM:liquibase: Successfully released change log lock
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:39,155| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: uiframework.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:39,163| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:39,169| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: uiframework.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:39,176| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:39,851| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: uicommons.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:39,862| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:39,872| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: uicommons.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:39,885| Exiting method saveGlobalProperty
INFO 12/11/18 6:18 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0
INFO 12/11/18 6:18 PM:liquibase: Reading from `liquibasechangelog`
INFO 12/11/18 6:18 PM:liquibase: Successfully acquired change log lock
INFO 12/11/18 6:18 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0
INFO 12/11/18 6:18 PM:liquibase: Successfully released change log lock
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:40,309| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: atlas.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:40,317| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:40,322| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: atlas.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:40,334| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:40,448| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: uilibrary.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:40,461| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:40,466| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: uilibrary.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:40,473| Exiting method saveGlobalProperty
INFO 12/11/18 6:18 PM:liquibase: Reading from `liquibasechangelog`
INFO 12/11/18 6:18 PM:liquibase: Successfully acquired change log lock
INFO 12/11/18 6:18 PM:liquibase: Successfully released change log lock
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:40,831| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: providermanagement.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:40,842| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:40,850| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: providermanagement.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:40,861| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:41,000| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: addresshierarchy.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:41,009| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:41,019| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: addresshierarchy.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:41,030| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:41,091| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: htmlwidgets.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:41,097| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:41,101| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: htmlwidgets.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:41,106| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:41,160| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: owa.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:41,167| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:41,170| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: owa.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:41,177| Exiting method saveGlobalProperty
INFO 12/11/18 6:18 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0
INFO 12/11/18 6:18 PM:liquibase: Reading from `liquibasechangelog`
INFO 12/11/18 6:18 PM:liquibase: Successfully acquired change log lock
INFO 12/11/18 6:18 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0
INFO 12/11/18 6:18 PM:liquibase: Successfully released change log lock
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:41,609| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: metadatamapping.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:41,617| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:41,624| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: metadatamapping.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:41,630| Exiting method saveGlobalProperty
INFO 12/11/18 6:18 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0
INFO 12/11/18 6:18 PM:liquibase: Reading from `liquibasechangelog`
INFO 12/11/18 6:18 PM:liquibase: Successfully acquired change log lock
INFO 12/11/18 6:18 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0
INFO 12/11/18 6:18 PM:liquibase: Successfully released change log lock
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:41,956| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: metadatasharing.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:41,961| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:41,972| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: metadatasharing.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:41,977| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:42,024| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: metadatadeploy.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:42,032| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:42,037| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: metadatadeploy.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:42,042| Exiting method saveGlobalProperty
INFO 12/11/18 6:18 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0
INFO 12/11/18 6:18 PM:liquibase: Reading from `liquibasechangelog`
INFO 12/11/18 6:18 PM:liquibase: Successfully acquired change log lock
INFO 12/11/18 6:18 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0
INFO 12/11/18 6:18 PM:liquibase: Successfully released change log lock
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:42,480| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: htmlformentry.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:42,486| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:42,500| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: htmlformentry.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:42,506| Exiting method saveGlobalProperty
INFO 12/11/18 6:18 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0
INFO 12/11/18 6:18 PM:liquibase: Reading from `liquibasechangelog`
INFO 12/11/18 6:18 PM:liquibase: Successfully acquired change log lock
INFO 12/11/18 6:18 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0
INFO 12/11/18 6:18 PM:liquibase: Successfully released change log lock
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:42,739| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: calculation.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:42,745| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:42,750| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: calculation.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:42,756| Exiting method saveGlobalProperty
INFO 12/11/18 6:18 PM:liquibase: Reading from `liquibasechangelog`
INFO 12/11/18 6:18 PM:liquibase: Successfully acquired change log lock
INFO 12/11/18 6:18 PM:liquibase: Successfully released change log lock
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:45,178| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: reporting.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:45,186| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:45,190| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: reporting.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:45,195| Exiting method saveGlobalProperty
INFO 12/11/18 6:18 PM:liquibase: Reading from `liquibasechangelog`
INFO 12/11/18 6:18 PM:liquibase: Successfully acquired change log lock
INFO 12/11/18 6:18 PM:liquibase: Successfully released change log lock
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:45,537| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: emrapi.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:45,543| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:45,547| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: emrapi.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:45,553| Exiting method saveGlobalProperty
INFO 12/11/18 6:18 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0
INFO 12/11/18 6:18 PM:liquibase: Reading from `liquibasechangelog`
INFO 12/11/18 6:18 PM:liquibase: Successfully acquired change log lock
INFO 12/11/18 6:18 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0
INFO 12/11/18 6:18 PM:liquibase: Successfully released change log lock
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:45,974| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: appointmentscheduling.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:45,978| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:45,984| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: appointmentscheduling.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:45,990| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:46,175| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: reportingrest.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:46,184| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:46,193| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: reportingrest.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:46,201| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:46,428| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: idgen.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:46,436| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:46,445| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: idgen.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:46,450| Exiting method saveGlobalProperty
INFO 12/11/18 6:18 PM:liquibase: Reading from `liquibasechangelog`
INFO 12/11/18 6:18 PM:liquibase: Successfully acquired change log lock
INFO 12/11/18 6:18 PM:liquibase: Successfully released change log lock
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:46,750| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: referencemetadata.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:46,758| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:46,762| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: referencemetadata.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:46,767| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:46,805| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: referencedemodata.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:46,810| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:46,814| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: referencedemodata.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:46,819| Exiting method saveGlobalProperty
INFO 12/11/18 6:18 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0
INFO 12/11/18 6:18 PM:liquibase: Reading from `liquibasechangelog`
INFO 12/11/18 6:18 PM:liquibase: Successfully acquired change log lock
INFO 12/11/18 6:18 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0
INFO 12/11/18 6:18 PM:liquibase: Successfully released change log lock
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:47,077| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: registrationcore.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:47,082| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:47,086| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: registrationcore.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:47,091| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:47,109| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: registrationcore.patientNameSearch value: registrationcore.BasicPatientNameSearch,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:47,178| Exiting method saveGlobalProperty
INFO 12/11/18 6:18 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0
INFO 12/11/18 6:18 PM:liquibase: Reading from `liquibasechangelog`
INFO 12/11/18 6:18 PM:liquibase: Successfully acquired change log lock
INFO 12/11/18 6:18 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0
INFO 12/11/18 6:18 PM:liquibase: Successfully released change log lock
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:47,512| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: appframework.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:47,518| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:47,527| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: appframework.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:47,532| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:47,545| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: formentryapp.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:47,550| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:47,555| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: formentryapp.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:47,565| Exiting method saveGlobalProperty
INFO 12/11/18 6:18 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0
INFO 12/11/18 6:18 PM:liquibase: Reading from `liquibasechangelog`
INFO 12/11/18 6:18 PM:liquibase: Successfully acquired change log lock
INFO 12/11/18 6:18 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0
INFO 12/11/18 6:18 PM:liquibase: Successfully released change log lock
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:47,811| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: allergyui.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:47,818| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:47,822| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: allergyui.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:47,829| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:47,872| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: appui.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:47,880| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:47,884| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: appui.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:47,888| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:48,010| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: adminui.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:48,017| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:48,021| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: adminui.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:48,025| Exiting method saveGlobalProperty
INFO 12/11/18 6:18 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0
INFO 12/11/18 6:18 PM:liquibase: Reading from `liquibasechangelog`
INFO 12/11/18 6:18 PM:liquibase: Successfully acquired change log lock
INFO 12/11/18 6:18 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0
INFO 12/11/18 6:18 PM:liquibase: Successfully released change log lock
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:48,460| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: coreapps.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:48,467| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:48,475| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: coreapps.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:48,480| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:48,526| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: registrationapp.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:48,531| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:48,535| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: registrationapp.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:48,540| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:48,572| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: reportingui.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:48,576| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:48,579| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: reportingui.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:48,584| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:48,651| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: appointmentschedulingui.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:48,655| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:48,660| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: appointmentschedulingui.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:48,664| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:48,694| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: htmlformentryui.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:48,698| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:48,702| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: htmlformentryui.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:48,706| Exiting method saveGlobalProperty
INFO 12/11/18 6:18 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0
INFO 12/11/18 6:18 PM:liquibase: Reading from `liquibasechangelog`
INFO 12/11/18 6:18 PM:liquibase: Successfully acquired change log lock
INFO 12/11/18 6:18 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0
INFO 12/11/18 6:18 PM:liquibase: Successfully released change log lock
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:50,397| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: chartsearch.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:50,405| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:50,411| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: chartsearch.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:50,419| Exiting method saveGlobalProperty
INFO 12/11/18 6:18 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0
INFO 12/11/18 6:18 PM:liquibase: Reading from `liquibasechangelog`
INFO 12/11/18 6:18 PM:liquibase: Successfully acquired change log lock
INFO 12/11/18 6:18 PM:liquibase: liquibase.xml is using schema version 1.9 rather than version 2.0
INFO 12/11/18 6:18 PM:liquibase: Successfully released change log lock
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:50,704| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: referenceapplication.started value: true,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:50,708| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:50,711| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: referenceapplication.mandatory value: false,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:50,715| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:18:52,149| In method SchedulerService.saveToMemento. Arguments:
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:18:52,154| Exiting method saveToMemento
INFO - ServiceContext.startRefreshingContext(823) |2018-12-11 18:18:52,333| Refreshing Context
WARN - IdgenTask.run(32) |2018-12-11 18:19:31,380| Not running scheduled task. DaemonToken = null; enabled = false
WARN - SimpleUrlHandlerMapping.registerHandlers(115) |2018-12-11 18:19:33,042| Neither 'urlMap' nor 'mappings' set on SimpleUrlHandlerMapping
WARN - SimpleUrlHandlerMapping.registerHandlers(115) |2018-12-11 18:19:34,899| Neither 'urlMap' nor 'mappings' set on SimpleUrlHandlerMapping
INFO - ServiceContext.doneRefreshingContext(834) |2018-12-11 18:19:34,907| Done refreshing Context
WARN - HL7InQueueProcessor.processHL7InQueue(104) |2018-12-11 18:19:39,002| HL7 processor aborting (another processor already running)
WARN - AddressConfigurationLoader.loadAddressConfiguration(64) |2018-12-11 18:19:43,814| Address hierarchy configuration file appears invalid, skipping the loading process: addressConfiguration.xml
WARN - HL7InQueueProcessor.processHL7InQueue(104) |2018-12-11 18:19:44,000| HL7 processor aborting (another processor already running)
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:44,974| In method UserService.saveRole. Arguments: Role=Privilege Level: Full,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:44,995| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:45,342| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: visits.assignmentHandler value: org.openmrs.module.emrapi.adt.EmrApiVisitAssignmentHandler,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:45,358| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:45,588| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: referencemetadata.installedVersion value: 13,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:45,618| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:45,633| In method UserService.savePrivilege. Arguments: Privilege=App: coreapps.systemAdministration,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:45,636| Exiting method savePrivilege
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:45,645| In method UserService.savePrivilege. Arguments: Privilege=App: referenceapplication.legacyAdmin,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:45,648| Exiting method savePrivilege
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:45,655| In method UserService.savePrivilege. Arguments: Privilege=App: coreapps.activeVisits,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:45,656| Exiting method savePrivilege
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:45,661| In method UserService.savePrivilege. Arguments: Privilege=App: atlas.manage,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:45,663| Exiting method savePrivilege
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:45,668| In method UserService.savePrivilege. Arguments: Privilege=App: coreapps.configuremetadata,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:45,669| Exiting method savePrivilege
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:45,674| In method UserService.savePrivilege. Arguments: Privilege=App: coreapps.findPatient,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:45,675| Exiting method savePrivilege
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:45,681| In method UserService.savePrivilege. Arguments: Privilege=App: coreapps.patientDashboard,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:45,682| Exiting method savePrivilege
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:45,687| In method UserService.savePrivilege. Arguments: Privilege=App: coreapps.patientVisits,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:45,689| Exiting method savePrivilege
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:45,694| In method UserService.savePrivilege. Arguments: Privilege=App: coreapps.systemAdministration,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:45,695| Exiting method savePrivilege
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:45,700| In method UserService.savePrivilege. Arguments: Privilege=App: formentryapp.forms,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:45,701| Exiting method savePrivilege
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:45,706| In method UserService.savePrivilege. Arguments: Privilege=App: referenceapplication.vitals,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:45,707| Exiting method savePrivilege
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:45,712| In method UserService.savePrivilege. Arguments: Privilege=App: registrationapp.registerPatient,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:45,713| Exiting method savePrivilege
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:45,717| In method UserService.savePrivilege. Arguments: Privilege=Task: coreapps.createRetrospectiveVisit,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:45,719| Exiting method savePrivilege
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:45,724| In method UserService.savePrivilege. Arguments: Privilege=Task: coreapps.createVisit,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:45,725| Exiting method savePrivilege
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:45,730| In method UserService.savePrivilege. Arguments: Privilege=Task: coreapps.endVisit,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:45,731| Exiting method savePrivilege
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:45,737| In method UserService.savePrivilege. Arguments: Privilege=Task: coreapps.mergeVisits,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:45,738| Exiting method savePrivilege
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:45,743| In method UserService.savePrivilege. Arguments: Privilege=Task: referenceapplication.simpleAdmission,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:45,744| Exiting method savePrivilege
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:45,748| In method UserService.savePrivilege. Arguments: Privilege=Task: referenceapplication.simpleDischarge,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:45,749| Exiting method savePrivilege
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:45,753| In method UserService.savePrivilege. Arguments: Privilege=Task: referenceapplication.simpleTransfer,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:45,754| Exiting method savePrivilege
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:45,759| In method UserService.savePrivilege. Arguments: Privilege=Task: referenceapplication.simpleVisitNote,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:45,761| Exiting method savePrivilege
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:45,765| In method UserService.savePrivilege. Arguments: Privilege=Task: referenceapplication.vitals,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:45,766| Exiting method savePrivilege
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:45,771| In method UserService.savePrivilege. Arguments: Privilege=Task: Modify Allergies,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:45,772| Exiting method savePrivilege
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:45,777| In method UserService.savePrivilege. Arguments: Privilege=Task: emr.patient.encounter.delete,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:45,778| Exiting method savePrivilege
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:45,784| In method UserService.savePrivilege. Arguments: Privilege=Task: emr.patient.encounter.edit,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:45,785| Exiting method savePrivilege
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:45,845| In method UserService.saveRole. Arguments: Role=Application: Administers System,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:45,846| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:45,868| In method UserService.saveRole. Arguments: Role=Application: Configures Metadata,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:45,869| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:45,893| In method UserService.saveRole. Arguments: Role=Application: Configures Forms,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:45,894| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:45,919| In method UserService.saveRole. Arguments: Role=Application: Manages Atlas,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:45,920| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:45,943| In method UserService.saveRole. Arguments: Role=Application: Registers Patients,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:45,944| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:45,973| In method UserService.saveRole. Arguments: Role=Application: Enters Vitals,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:45,974| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:45,997| In method UserService.saveRole. Arguments: Role=Application: Uses Capture Vitals App,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:45,998| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:46,023| In method UserService.saveRole. Arguments: Role=Application: Uses Patient Summary,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:46,024| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:46,049| In method UserService.saveRole. Arguments: Role=Application: Writes Clinical Notes,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:46,050| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:46,100| In method UserService.saveRole. Arguments: Role=Application: Enters ADT Events,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:46,101| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:46,133| In method UserService.saveRole. Arguments: Role=Application: Records Allergies,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:46,136| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:46,146| In method UserService.saveRole. Arguments: Role=Application: Has Super User Privileges,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:46,150| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:46,183| In method UserService.saveRole. Arguments: Role=Application: Sees Appointment Schedule,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:46,186| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:46,200| In method UserService.saveRole. Arguments: Role=Application: Edits Existing Encounters,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:46,202| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:46,233| In method UserService.saveRole. Arguments: Role=Application: Requests Appointments,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:46,235| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:46,271| In method UserService.saveRole. Arguments: Role=Application: Schedules Appointments,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:46,273| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:46,307| In method UserService.saveRole. Arguments: Role=Application: Schedules And Overbooks Appointments,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:46,310| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:46,344| In method UserService.saveRole. Arguments: Role=Application: Manages Provider Schedules,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:46,347| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:46,364| In method UserService.saveRole. Arguments: Role=Application: Configures Appointment Scheduling,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:46,368| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:46,398| In method UserService.saveRole. Arguments: Role=Organizational: Doctor,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:46,402| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:46,432| In method UserService.saveRole. Arguments: Role=Organizational: Nurse,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:46,436| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:46,456| In method UserService.saveRole. Arguments: Role=Organizational: Registration Clerk,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:46,460| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:46,491| In method UserService.saveRole. Arguments: Role=Organizational: System Administrator,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:46,492| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:46,520| In method UserService.saveRole. Arguments: Role=Organizational: Hospital Administrator,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:46,522| Exiting method saveRole
INFO - SerializationServiceImpl.getDefaultSerializer(71) |2018-12-11 18:19:46,679| No default serializer specified - using builtin SimpleXStreamSerializer.
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:46,836| In method UserService.saveRole. Arguments: Role=Application: Administers System,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:46,902| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:46,919| In method UserService.saveRole. Arguments: Role=Application: Configures Appointment Scheduling,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:46,980| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:46,993| In method UserService.saveRole. Arguments: Role=Application: Configures Forms,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:47,058| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:47,078| In method UserService.saveRole. Arguments: Role=Application: Configures Metadata,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:47,158| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:47,171| In method UserService.saveRole. Arguments: Role=Application: Edits Existing Encounters,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:47,247| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:47,265| In method UserService.saveRole. Arguments: Role=Application: Enters ADT Events,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:47,369| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:47,388| In method UserService.saveRole. Arguments: Role=Application: Enters Vitals,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:47,469| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:47,481| In method UserService.saveRole. Arguments: Role=Application: Has Super User Privileges,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:47,570| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:47,596| In method UserService.saveRole. Arguments: Role=Application: Manages Atlas,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:47,678| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:47,688| In method UserService.saveRole. Arguments: Role=Application: Manages Provider Schedules,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:47,848| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:47,856| In method UserService.saveRole. Arguments: Role=Application: Records Allergies,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:47,948| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:47,961| In method UserService.saveRole. Arguments: Role=Application: Registers Patients,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:48,103| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:48,116| In method UserService.saveRole. Arguments: Role=Application: Requests Appointments,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:48,192| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:48,204| In method UserService.saveRole. Arguments: Role=Application: Schedules And Overbooks Appointments,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:48,281| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:48,294| In method UserService.saveRole. Arguments: Role=Application: Schedules Appointments,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:48,493| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:48,503| In method UserService.saveRole. Arguments: Role=Application: Sees Appointment Schedule,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:48,572| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:48,583| In method UserService.saveRole. Arguments: Role=Application: Uses Capture Vitals App,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:48,727| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:48,741| In method UserService.saveRole. Arguments: Role=Application: Uses Patient Summary,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:48,795| Exiting method saveRole
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:48,808| In method UserService.saveRole. Arguments: Role=Application: Writes Clinical Notes,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:48,872| Exiting method saveRole
INFO - SerializationServiceImpl.getDefaultSerializer(71) |2018-12-11 18:19:48,899| No default serializer specified - using builtin SimpleXStreamSerializer.
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:48,918| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: layout.address.format value: <org.openmrs.layout.address.AddressTemplate>
<nameMappings class="properties">
<property name="postalCode" value="Location.postalCode"/>
<property name="address2" value="Location.address2"/>
<property name="address1" value="Location.address1"/>
<property name="country" value="Location.country"/>
<property name="stateProvince" value="Location.stateProvince"/>
<property name="cityVillage" value="Location.cityVillage"/>
</nameMappings>
<sizeMappings class="properties">
<property name="postalCode" value="10"/>
<property name="address2" value="40"/>
<property name="address1" value="40"/>
<property name="country" value="10"/>
<property name="stateProvince" value="10"/>
<property name="cityVillage" value="10"/>
</sizeMappings>
<lineByLineFormat>
<string>address1</string>
<string>address2</string>
<string>cityVillage stateProvince country postalCode</string>
</lineByLineFormat>
</org.openmrs.layout.address.AddressTemplate>,
WARN - HL7InQueueProcessor.processHL7InQueue(104) |2018-12-11 18:19:49,000| HL7 processor aborting (another processor already running)
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:49,008| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:49,114| In method UserService.saveUser. Arguments: User=clerk,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:49,166| Exiting method saveUser
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:49,201| In method UserService.saveUser. Arguments: User=nurse,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:49,230| Exiting method saveUser
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:49,263| In method UserService.saveUser. Arguments: User=doctor,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:49,291| Exiting method saveUser
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:49,336| In method UserService.saveUser. Arguments: User=sysadmin,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:49,369| Exiting method saveUser
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:49,645| In method AppointmentService.saveAppointmentType. Arguments: AppointmentType=AppointmentType[hashCode=7fba1e5,uuid=4da187c6-c436-11e4-a470-82b0ea87e2d8],
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:49,648| Exiting method saveAppointmentType
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:49,668| In method AppointmentService.saveAppointmentType. Arguments: AppointmentType=AppointmentType[hashCode=c8813f7e,uuid=5ab6d8a8-c436-11e4-a470-82b0ea87e2d8],
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:49,670| Exiting method saveAppointmentType
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:49,692| In method AppointmentService.saveAppointmentType. Arguments: AppointmentType=AppointmentType[hashCode=eb3d3d44,uuid=7dd9ac8e-c436-11e4-a470-82b0ea87e2d8],
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:49,694| Exiting method saveAppointmentType
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:49,714| In method AppointmentService.saveAppointmentType. Arguments: AppointmentType=AppointmentType[hashCode=ce2c34c4,uuid=7e7d3e26-c436-11e4-a470-82b0ea87e2d8],
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:49,717| Exiting method saveAppointmentType
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:49,739| In method AppointmentService.saveAppointmentType. Arguments: AppointmentType=AppointmentType[hashCode=b4b49efa,uuid=7efeaa60-c436-11e4-a470-82b0ea87e2d8],
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:49,741| Exiting method saveAppointmentType
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:49,760| In method AppointmentService.saveAppointmentType. Arguments: AppointmentType=AppointmentType[hashCode=abc150ca,uuid=95636ce6-c436-11e4-a470-82b0ea87e2d8],
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:49,763| Exiting method saveAppointmentType
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:49,784| In method AppointmentService.saveAppointmentType. Arguments: AppointmentType=AppointmentType[hashCode=b519c52f,uuid=9ebdc232-c436-11e4-a470-82b0ea87e2d8],
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:49,786| Exiting method saveAppointmentType
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:49,807| In method AppointmentService.saveAppointmentType. Arguments: AppointmentType=AppointmentType[hashCode=b21fd75e,uuid=a62a40e0-c436-11e4-a470-82b0ea87e2d8],
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:49,810| Exiting method saveAppointmentType
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:49,830| In method AppointmentService.saveAppointmentType. Arguments: AppointmentType=AppointmentType[hashCode=f6a1c0a2,uuid=ac71c996-c436-11e4-a470-82b0ea87e2d8],
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:49,832| Exiting method saveAppointmentType
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:49,852| In method AppointmentService.saveAppointmentType. Arguments: AppointmentType=AppointmentType[hashCode=44b3d280,uuid=b29be856-c436-11e4-a470-82b0ea87e2d8],
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:49,854| Exiting method saveAppointmentType
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:49,875| In method AppointmentService.saveAppointmentType. Arguments: AppointmentType=AppointmentType[hashCode=da393751,uuid=cba5a260-c436-11e4-a470-82b0ea87e2d8],
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:49,877| Exiting method saveAppointmentType
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:49,896| In method AppointmentService.saveAppointmentType. Arguments: AppointmentType=AppointmentType[hashCode=e86393c9,uuid=d248c6c4-c436-11e4-a470-82b0ea87e2d8],
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:49,898| Exiting method saveAppointmentType
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:49,918| In method AppointmentService.saveAppointmentType. Arguments: AppointmentType=AppointmentType[hashCode=ea148419,uuid=0c617770-c437-11e4-a470-82b0ea87e2d8],
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:49,920| Exiting method saveAppointmentType
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:49,939| In method AppointmentService.saveAppointmentType. Arguments: AppointmentType=AppointmentType[hashCode=81627d4b,uuid=136ed9a4-c437-11e4-a470-82b0ea87e2d8],
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:49,941| Exiting method saveAppointmentType
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:49,962| In method AppointmentService.saveAppointmentType. Arguments: AppointmentType=AppointmentType[hashCode=fd2cb9c5,uuid=1b2d98c4-c437-11e4-a470-82b0ea87e2d8],
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:49,964| Exiting method saveAppointmentType
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:49,984| In method AppointmentService.saveAppointmentType. Arguments: AppointmentType=AppointmentType[hashCode=cb138210,uuid=25873c9e-c437-11e4-a470-82b0ea87e2d8],
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:49,986| Exiting method saveAppointmentType
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:50,007| In method AppointmentService.saveAppointmentType. Arguments: AppointmentType=AppointmentType[hashCode=20d32feb,uuid=2febe6a8-c437-11e4-a470-82b0ea87e2d8],
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:50,010| Exiting method saveAppointmentType
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:50,028| In method AppointmentService.saveAppointmentType. Arguments: AppointmentType=AppointmentType[hashCode=479f1d6a,uuid=38081afa-c437-11e4-a470-82b0ea87e2d8],
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:50,030| Exiting method saveAppointmentType
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:50,055| In method AppointmentService.saveAppointmentType. Arguments: AppointmentType=AppointmentType[hashCode=65d684ad,uuid=3f5a8ca2-c437-11e4-a470-82b0ea87e2d8],
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:50,058| Exiting method saveAppointmentType
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:50,082| In method AppointmentService.saveAppointmentType. Arguments: AppointmentType=AppointmentType[hashCode=33718375,uuid=452c596c-c437-11e4-a470-82b0ea87e2d8],
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:50,084| Exiting method saveAppointmentType
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:50,107| In method AppointmentService.saveAppointmentType. Arguments: AppointmentType=AppointmentType[hashCode=9e3b268b,uuid=4d3b6396-c437-11e4-a470-82b0ea87e2d8],
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:50,109| Exiting method saveAppointmentType
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:50,129| In method AppointmentService.saveAppointmentType. Arguments: AppointmentType=AppointmentType[hashCode=5dd5e553,uuid=4d85dda4-c437-11e4-a470-82b0ea87e2d8e],
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:50,132| Exiting method saveAppointmentType
ERROR - AppFrameworkActivator.registerAppsAndExtensions(101) |2018-12-11 18:19:50,442| Error loading app framework. Some apps might not work.org.openmrs.module.appframework.factory.UserAppFactory@d3886c7
java.lang.IllegalStateException: App 'referenceapplication.registrationapp.myRegisterPat' says its an instanceOf 'registrationapp.registerPatient' but there is no AppTemplate with that id
at org.openmrs.module.appframework.repository.AllAppDescriptors.setAppTemplatesOnInstances(AllAppDescriptors.java:74)
at org.openmrs.module.appframework.AppFrameworkActivator.registerAppsAndExtensions(AppFrameworkActivator.java:97)
at org.openmrs.module.appframework.AppFrameworkActivator.contextRefreshed(AppFrameworkActivator.java:69)
at org.openmrs.module.ModuleUtil.refreshApplicationContext(ModuleUtil.java:877)
at org.openmrs.module.web.WebModuleUtil.refreshWAC(WebModuleUtil.java:866)
at org.openmrs.web.Listener.performWebStartOfModules(Listener.java:658)
at org.openmrs.web.Listener.performWebStartOfModules(Listener.java:637)
at org.openmrs.web.Listener.startOpenmrs(Listener.java:268)
at org.openmrs.web.WebDaemon$1.run(WebDaemon.java:42)
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:51,092| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: registrationcore.identifierSourceId value: 1,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:51,108| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:51,334| In method DefinitionService.saveDefinition. Arguments: Definition=appointmentschedulingui.appointmentDataSetDefinition.dailyAppointments,
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:51,337| In method SerializedDefinitionService.saveDefinition. Arguments: Definition=appointmentschedulingui.appointmentDataSetDefinition.dailyAppointments,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:51,357| Exiting method saveDefinition
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:51,358| Exiting method saveDefinition
WARN - HL7InQueueProcessor.processHL7InQueue(104) |2018-12-11 18:19:54,000| HL7 processor aborting (another processor already running)
WARN - RequestHandlers.initHandlersFromConfig(163) |2018-12-11 18:19:54,230| Multiple requestHandler registered to the same name: /update ignoring: org.apache.solr.handler.UpdateRequestHandler
WARN - ManagedResource.reloadFromStorage(182) |2018-12-11 18:19:54,859| No stored data found for /rest/managed
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:55,176| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: registrationcore.patientNameSearch value: registrationcore.ExistingPatientNameSearch,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:55,244| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:55,516| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: emr.concept.diagnosisSetOfSets value: 160167AAAAAAAAAAAAAAAAAAAAAAAAAAAAAA,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:55,535| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:55,540| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: namephonetics.givenNameStringEncoder value: Soundex,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:55,560| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:55,567| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: namephonetics.middleNameStringEncoder value: Soundex,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:55,586| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:55,590| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: namephonetics.familyNameStringEncoder value: Soundex,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:55,609| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:55,615| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: namephonetics.familyName2StringEncoder value: Soundex,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:55,633| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:55,638| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: registrationcore.patientNameSearch value: registrationcore.BasicPatientNameSearch,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:55,889| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:55,895| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: registrationcore.fastSimilarPatientSearchAlgorithm value: registrationcore.BasicSimilarPatientSearchAlgorithm,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:55,922| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:55,927| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: registrationcore.preciseSimilarPatientSearchAlgorithm value: registrationcore.BasicExactPatientSearchAlgorithm,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:55,955| Exiting method saveGlobalProperty
INFO - LoggingAdvice.invoke(115) |2018-12-11 18:19:55,960| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: conceptmanagementapps.snomedCtConceptSource value: 1ADDDDDDDDDDDDDDDDDDDDDDDDDDDDDDDDDD,
INFO - LoggingAdvice.invoke(155) |2018-12-11 18:19:55,986| Exiting method saveGlobalProperty
ERROR - CommonsLoggingOutput.error(75) |2018-12-11 18:19:58,207| Line=380 The content of element type "dwr" must match "(init?,allow?,signatures?)".
ERROR - CommonsLoggingOutput.error(75) |2018-12-11 18:19:58,213| Parameter mismatch parsing signatures section in dwr.xml on line: DWRAtlasService.disableAtlasModule()
ERROR - CommonsLoggingOutput.error(75) |2018-12-11 18:19:58,217| Parameter mismatch parsing signatures section in dwr.xml on line: DWRHtmlFormEntryService.checkIfLoggedIn()
Dec 11, 2018 6:19:58 PM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["http-bio-8081"]
WARN - HL7InQueueProcessor.processHL7InQueue(104) |2018-12-11 18:19:59,001| HL7 processor aborting (another processor already running)
WARN - HL7InQueueProcessor.processHL7InQueue(104) |2018-12-11 18:20:04,000| HL7 processor aborting (another processor already running)
Dec 11, 2018 6:20:05 PM org.apache.catalina.core.ApplicationContext log
INFO: Initializing Spring FrameworkServlet 'openmrs'
WARN - HL7InQueueProcessor.processHL7InQueue(104) |2018-12-11 18:20:09,005| HL7 processor aborting (another processor already running)
Dec 11, 2018 6:20:09 PM org.apache.jasper.compiler.TldLocationsCache tldScanJar
INFO: At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.
WARN - HL7InQueueProcessor.processHL7InQueue(104) |2018-12-11 18:20:14,007| HL7 processor aborting (another processor already running)
WARN - HL7InQueueProcessor.processHL7InQueue(104) |2018-12-11 18:20:19,001| HL7 processor aborting (another processor already running)
WARN - HL7InQueueProcessor.processHL7InQueue(104) |2018-12-11 18:20:24,002| HL7 processor aborting (another processor already running)
WARN - HL7InQueueProcessor.processHL7InQueue(104) |2018-12-11 18:20:29,001| HL7 processor aborting (another processor already running)
WARN - HL7InQueueProcessor.processHL7InQueue(104) |2018-12-11 18:20:34,001| HL7 processor aborting (another processor already running)
Dec 11, 2018 6:20:40 PM org.apache.catalina.core.ApplicationContext log
INFO: Initializing Spring FrameworkServlet 'openmrs_static_content'
WARN - HL7InQueueProcessor.processHL7InQueue(104) |2018-12-11 18:20:44,006| HL7 processor aborting (another processor already running)
WARN - HL7InQueueProcessor.processHL7InQueue(104) |2018-12-11 18:20:54,001| HL7 processor aborting (another processor already running)
WARN - HL7InQueueProcessor.processHL7InQueue(104) |2018-12-11 18:20:59,001| HL7 processor aborting (another processor already running)
WARN - HL7InQueueProcessor.processHL7InQueue(104) |2018-12-11 18:21:04,001| HL7 processor aborting (another processor already running)
WARN - HL7InQueueProcessor.processHL7InQueue(104) |2018-12-11 18:21:09,001| HL7 processor aborting (another processor already running)
WARN - HL7InQueueProcessor.processHL7InQueue(104) |2018-12-11 18:21:14,000| HL7 processor aborting (another processor already running)
WARN - HL7InQueueProcessor.processHL7InQueue(104) |2018-12-11 18:21:19,001| HL7 processor aborting (another processor already running)
Exception in thread "Thread-367" java.lang.LinkageError: loader constraint violation: loader (instance of org/openmrs/module/ModuleClassLoader) previously initiated loading for a different type with name "org/openmrs/module/Extension$MEDIA_TYPE"
at java.lang.ClassLoader.defineClass1(Native Method)
at java.lang.ClassLoader.defineClass(ClassLoader.java:763)
at java.security.SecureClassLoader.defineClass(SecureClassLoader.java:142)
at java.net.URLClassLoader.defineClass(URLClassLoader.java:467)
at java.net.URLClassLoader.access$100(URLClassLoader.java:73)
at java.net.URLClassLoader$1.run(URLClassLoader.java:368)
at java.net.URLClassLoader$1.run(URLClassLoader.java:362)
at java.security.AccessController.doPrivileged(Native Method)
at java.net.URLClassLoader.findClass(URLClassLoader.java:361)
at org.openmrs.module.ModuleClassLoader.loadClass(ModuleClassLoader.java:645)
at org.openmrs.module.ModuleClassLoader.loadClass(ModuleClassLoader.java:564)
at java.lang.ClassLoader.loadClass(ClassLoader.java:357)
at org.openmrs.module.nigeriaemr.extension.html.AdminList.getMediaType(AdminList.java:29)
at org.openmrs.module.Extension.getExtensionId(Extension.java:123)
at org.openmrs.module.Module.expandExtensionNames(Module.java:543)
at org.openmrs.module.Module.getExtensions(Module.java:490)
at org.openmrs.module.ModuleFactory.startModuleInternal(ModuleFactory.java:664)
at org.openmrs.api.context.Daemon$1.run(Daemon.java:74)
WARN - HL7InQueueProcessor.processHL7InQueue(104) |2018-12-11 18:21:24,000| HL7 processor aborting (another processor already running)
Exception in thread "Thread-374" java.lang.LinkageError: org/openmrs/module/Extension$MEDIA_TYPE
at org.openmrs.module.nigeriaemr.extension.html.AdminList.getMediaType(AdminList.java:29)
at org.openmrs.module.Extension.getExtensionId(Extension.java:123)
at org.openmrs.module.ModuleFactory.startModuleInternal(ModuleFactory.java:666)
at org.openmrs.api.context.Daemon$1.run(Daemon.java:74)
WARN - HL7InQueueProcessor.processHL7InQueue(104) |2018-12-11 18:21:34,001| HL7 processor aborting (another processor already running)