Build log

The build generated 12,121 lines of output. The output is too long and has been truncated to the last 1,000 lines. Download or view full build log.

17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,488\| Mapping class: org.openmrs.ConceptProposal -> concept_proposal
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,488\| Mapping class: org.openmrs.ConceptStateConversion -> concept_state_conversion
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,488\| Mapping class: org.openmrs.ConceptSet -> concept_set
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,488\| Mapping class: org.openmrs.ConceptMap -> concept_reference_map
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,489\| Mapping class: org.openmrs.ConceptWord -> concept_word
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,489\| Mapping class: org.openmrs.ConceptStopWord -> concept_stop_word
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,489\| Mapping class: org.openmrs.ConceptSource -> concept_reference_source
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,489\| Mapping class: org.openmrs.ConceptSetDerived -> concept_set_derived
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,490\| Mapping class: org.openmrs.ConceptReferenceTerm -> concept_reference_term
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,490\| Mapping class: org.openmrs.ConceptMapType -> concept_map_type
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,490\| Mapping class: org.openmrs.ConceptReferenceTermMap -> concept_reference_term_map
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,490\| Mapping class: org.openmrs.Drug -> drug
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,490\| Mapping class: org.openmrs.DrugIngredient -> drug_ingredient
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,491\| Mapping class: org.openmrs.DrugReferenceMap -> drug_reference_map
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,491\| Mapping class: org.openmrs.Field -> field
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,491\| Mapping class: org.openmrs.FieldAnswer -> field_answer
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,491\| Mapping class: org.openmrs.FieldType -> field_type
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,491\| Mapping class: org.openmrs.Form -> form
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,492\| Mapping class: org.openmrs.FormField -> form_field
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,492\| Mapping class: org.openmrs.FormResource -> form_resource
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,492\| Mapping class: org.openmrs.GlobalProperty -> global_property
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,492\| Mapping class: org.openmrs.Obs -> obs
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,492\| Mapping class: org.openmrs.Person -> person
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,493\| Mapping class: org.openmrs.PersonAttribute -> person_attribute
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,493\| Mapping class: org.openmrs.PersonAttributeType -> person_attribute_type
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,493\| Mapping class: org.openmrs.PersonAddress -> person_address
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,493\| Mapping class: org.openmrs.person.PersonMergeLog -> person_merge_log
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,494\| Mapping class: org.openmrs.PersonName -> person_name
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,494\| Mapping class: org.openmrs.User -> users
17-Jun-2025 22:11:18	INFO - HbmBinder.bindCollection(1522) \|2025-06-17 22:11:18,494\| Mapping collection: org.openmrs.User.roles -> user_role
17-Jun-2025 22:11:18	INFO - HbmBinder.bindCollection(1522) \|2025-06-17 22:11:18,494\| Mapping collection: org.openmrs.User.userProperties -> user_property
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,494\| Mapping class: org.openmrs.api.db.LoginCredential -> users
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,494\| Mapping class: org.openmrs.Privilege -> privilege
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,495\| Mapping class: org.openmrs.Role -> role
17-Jun-2025 22:11:18	INFO - HbmBinder.bindCollection(1522) \|2025-06-17 22:11:18,495\| Mapping collection: org.openmrs.Role.inheritedRoles -> role_role
17-Jun-2025 22:11:18	INFO - HbmBinder.bindCollection(1522) \|2025-06-17 22:11:18,495\| Mapping collection: org.openmrs.Role.childRoles -> role_role
17-Jun-2025 22:11:18	INFO - HbmBinder.bindCollection(1522) \|2025-06-17 22:11:18,495\| Mapping collection: org.openmrs.Role.privileges -> role_privilege
17-Jun-2025 22:11:18	INFO - HbmBinder.bindJoinedSubclass(945) \|2025-06-17 22:11:18,495\| Mapping joined-subclass: org.openmrs.Patient -> patient
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,495\| Mapping class: org.openmrs.PatientIdentifier -> patient_identifier
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,496\| Mapping class: org.openmrs.PatientIdentifierType -> patient_identifier_type
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,496\| Mapping class: org.openmrs.Relationship -> relationship
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,496\| Mapping class: org.openmrs.RelationshipType -> relationship_type
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,496\| Mapping class: org.openmrs.Order -> orders
17-Jun-2025 22:11:18	INFO - HbmBinder.bindJoinedSubclass(945) \|2025-06-17 22:11:18,497\| Mapping joined-subclass: org.openmrs.DrugOrder -> drug_order
17-Jun-2025 22:11:18	INFO - HbmBinder.bindJoinedSubclass(945) \|2025-06-17 22:11:18,497\| Mapping joined-subclass: org.openmrs.TestOrder -> test_order
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,497\| Mapping class: org.openmrs.CareSetting -> care_setting
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,497\| Mapping class: org.openmrs.OrderType -> order_type
17-Jun-2025 22:11:18	INFO - HbmBinder.bindCollection(1522) \|2025-06-17 22:11:18,497\| Mapping collection: org.openmrs.OrderType.conceptClasses -> order_type_class_map
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,498\| Mapping class: org.openmrs.Location -> location
17-Jun-2025 22:11:18	INFO - HbmBinder.bindCollection(1522) \|2025-06-17 22:11:18,498\| Mapping collection: org.openmrs.Location.tags -> location_tag_map
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,498\| Mapping class: org.openmrs.LocationTag -> location_tag
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,498\| Mapping class: org.openmrs.LocationAttributeType -> location_attribute_type
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,499\| Mapping class: org.openmrs.LocationAttribute -> location_attribute
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,499\| Mapping class: org.openmrs.EncounterType -> encounter_type
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,499\| Mapping class: org.openmrs.Encounter -> encounter
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,499\| Mapping class: org.openmrs.EncounterProvider -> encounter_provider
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,499\| Mapping class: org.openmrs.EncounterRole -> encounter_role
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,500\| Mapping class: org.openmrs.Program -> program
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,500\| Mapping class: org.openmrs.ProgramWorkflow -> program_workflow
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,500\| Mapping class: org.openmrs.ProgramWorkflowState -> program_workflow_state
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,500\| Mapping class: org.openmrs.PatientProgram -> patient_program
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,501\| Mapping class: org.openmrs.PatientState -> patient_state
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,501\| Mapping class: org.openmrs.Cohort -> cohort
17-Jun-2025 22:11:18	INFO - HbmBinder.bindCollection(1522) \|2025-06-17 22:11:18,501\| Mapping collection: org.openmrs.Cohort.memberIds -> cohort_member
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,501\| Mapping class: org.openmrs.api.db.SerializedObject -> serialized_object
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,501\| Mapping class: org.openmrs.OrderFrequency -> order_frequency
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,501\| Mapping class: org.openmrs.hl7.HL7Source -> hl7_source
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,502\| Mapping class: org.openmrs.hl7.HL7InQueue -> hl7_in_queue
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,502\| Mapping class: org.openmrs.hl7.HL7InArchive -> hl7_in_archive
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,502\| Mapping class: org.openmrs.hl7.HL7InError -> hl7_in_error
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,502\| Mapping class: org.openmrs.reporting.ReportObjectWrapper -> report_object
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,502\| Mapping class: org.openmrs.report.ReportSchemaXml -> report_schema_xml
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,503\| Mapping class: org.openmrs.notification.Template -> notification_template
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,503\| Mapping class: org.openmrs.notification.Alert -> notification_alert
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,503\| Mapping class: org.openmrs.notification.AlertRecipient -> notification_alert_recipient
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,503\| Mapping class: org.openmrs.scheduler.TaskDefinition -> scheduler_task_config
17-Jun-2025 22:11:18	INFO - HbmBinder.bindCollection(1522) \|2025-06-17 22:11:18,503\| Mapping collection: org.openmrs.scheduler.TaskDefinition.properties -> scheduler_task_config_property
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,504\| Mapping class: org.openmrs.VisitType -> visit_type
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,504\| Mapping class: org.openmrs.VisitAttributeType -> visit_attribute_type
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,504\| Mapping class: org.openmrs.VisitAttribute -> visit_attribute
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,504\| Mapping class: org.openmrs.Visit -> visit
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,504\| Mapping class: org.openmrs.Provider -> provider
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,505\| Mapping class: org.openmrs.ProviderAttribute -> provider_attribute
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,505\| Mapping class: org.openmrs.ProviderAttributeType -> provider_attribute_type
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,505\| Mapping class: org.openmrs.api.db.ClobDatatypeStorage -> clob_datatype_storage
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,505\| Mapping class: org.openmrs.module.addresshierarchy.AddressHierarchyEntry -> address_hierarchy_entry
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,505\| Mapping class: org.openmrs.module.addresshierarchy.AddressHierarchyLevel -> address_hierarchy_level
17-Jun-2025 22:11:18	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:18,506\| Mapping class: org.openmrs.module.addresshierarchy.AddressToEntryMap -> address_hierarchy_address_to_entry_map
17-Jun-2025 22:11:18	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:18,506\| Mapping collection: org.openmrs.Concept.names -> concept_name
17-Jun-2025 22:11:18	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:18,506\| Mapping collection: org.openmrs.Concept.descriptions -> concept_description
17-Jun-2025 22:11:18	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:18,506\| Mapping collection: org.openmrs.Concept.answers -> concept_answer
17-Jun-2025 22:11:18	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:18,506\| Mapping collection: org.openmrs.Concept.conceptSets -> concept_set
17-Jun-2025 22:11:18	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:18,506\| Mapping collection: org.openmrs.Concept.conceptMappings -> concept_reference_map
17-Jun-2025 22:11:18	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:18,507\| Mapping collection: org.openmrs.ConceptReferenceTerm.conceptReferenceTermMaps -> concept_reference_term_map
17-Jun-2025 22:11:18	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:18,507\| Mapping collection: org.openmrs.Drug.drugReferenceMaps -> drug_reference_map
17-Jun-2025 22:11:18	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:18,507\| Mapping collection: org.openmrs.Form.formFields -> form_field
17-Jun-2025 22:11:18	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:18,507\| Mapping collection: org.openmrs.Obs.groupMembers -> obs
17-Jun-2025 22:11:18	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:18,507\| Mapping collection: org.openmrs.Person.addresses -> person_address
17-Jun-2025 22:11:18	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:18,507\| Mapping collection: org.openmrs.Person.names -> person_name
17-Jun-2025 22:11:18	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:18,508\| Mapping collection: org.openmrs.Person.attributes -> person_attribute
17-Jun-2025 22:11:18	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:18,508\| Mapping collection: org.openmrs.Patient.identifiers -> patient_identifier
17-Jun-2025 22:11:18	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:18,508\| Mapping collection: org.openmrs.Location.childLocations -> location
17-Jun-2025 22:11:18	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:18,508\| Mapping collection: org.openmrs.Location.attributes -> location_attribute
17-Jun-2025 22:11:18	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:18,508\| Mapping collection: org.openmrs.Encounter.orders -> orders
17-Jun-2025 22:11:18	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:18,508\| Mapping collection: org.openmrs.Encounter.obs -> obs
17-Jun-2025 22:11:18	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:18,509\| Mapping collection: org.openmrs.Encounter.encounterProviders -> encounter_provider
17-Jun-2025 22:11:18	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:18,509\| Mapping collection: org.openmrs.Program.allWorkflows -> program_workflow
17-Jun-2025 22:11:18	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:18,509\| Mapping collection: org.openmrs.ProgramWorkflow.states -> program_workflow_state
17-Jun-2025 22:11:18	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:18,509\| Mapping collection: org.openmrs.PatientProgram.states -> patient_state
17-Jun-2025 22:11:18	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:18,509\| Mapping collection: org.openmrs.notification.Alert.recipients -> notification_alert_recipient
17-Jun-2025 22:11:18	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:18,509\| Mapping collection: org.openmrs.Visit.attributes -> visit_attribute
17-Jun-2025 22:11:18	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:18,509\| Mapping collection: org.openmrs.Visit.encounters -> encounter
17-Jun-2025 22:11:18	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:18,510\| Mapping collection: org.openmrs.Provider.attributes -> provider_attribute
17-Jun-2025 22:11:18	INFO - Configuration.applyHibernateValidatorLegacyConstraintsOnDDL(1676) \|2025-06-17 22:11:18,510\| Hibernate Validator not found: ignoring
17-Jun-2025 22:11:18	INFO - LocalSessionFactoryBean.buildSessionFactory(777) \|2025-06-17 22:11:18,510\| Building new Hibernate SessionFactory
17-Jun-2025 22:11:18	INFO - HibernateSearchEventListenerRegister.enableHibernateSearch(75) \|2025-06-17 22:11:18,511\| Unable to find org.hibernate.search.event.FullTextIndexEventListener on the classpath. Hibernate Search is not enabled.
17-Jun-2025 22:11:18	INFO - ConnectionProviderFactory.initializeConnectionProviderFromConfig(173) \|2025-06-17 22:11:18,512\| Initializing connection provider: org.hibernate.connection.C3P0ConnectionProvider
17-Jun-2025 22:11:18	INFO - C3P0ConnectionProvider.configure(103) \|2025-06-17 22:11:18,512\| C3P0 using driver: org.h2.Driver at URL: jdbc:h2:mem:openmrs;DB_CLOSE_DELAY=30
17-Jun-2025 22:11:18	INFO - C3P0ConnectionProvider.configure(104) \|2025-06-17 22:11:18,513\| Connection properties: {user=sa, password=****, release_mode=after_transaction}
17-Jun-2025 22:11:18	INFO - C3P0ConnectionProvider.configure(107) \|2025-06-17 22:11:18,513\| autocommit mode: false
17-Jun-2025 22:11:18	INFO - AbstractPoolBackedDataSource.getPoolManager(462) \|2025-06-17 22:11:18,527\| Initializing c3p0 pool... com.mchange.v2.c3p0.PoolBackedDataSource@f2f2595a [ connectionPoolDataSource -> com.mchange.v2.c3p0.WrapperConnectionPoolDataSource@d03c3f01 [ acquireIncrement -> 1, acquireRetryAttempts -> 5, acquireRetryDelay -> 1000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 0, connectionCustomizerClassName -> null, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, debugUnreturnedConnectionStackTraces -> false, factoryClassLocation -> null, forceIgnoreUnresolvedTransactions -> false, identityToken -> z8kfltbbzi07q5ssu6u0\|519c2def, idleConnectionTestPeriod -> 3000, initialPoolSize -> 0, maxAdministrativeTaskTime -> 0, maxConnectionAge -> 0, maxIdleTime -> 100, maxIdleTimeExcessConnections -> 0, maxPoolSize -> 50, maxStatements -> 0, maxStatementsPerConnection -> 0, minPoolSize -> 0, nestedDataSource -> com.mchange.v2.c3p0.DriverManagerDataSource@9ba35c21 [ description -> null, driverClass -> null, factoryClassLocation -> null, identityToken -> z8kfltbbzi07q5ssu6u0\|781ec10f, jdbcUrl -> jdbc:h2:mem:openmrs;DB_CLOSE_DELAY=30, properties -> {user=****, password=****, release_mode=after_transaction} ], preferredTestQuery -> null, propertyCycle -> 0, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, unreturnedConnectionTimeout -> 0, usesTraditionalReflectiveProxies -> false; userOverrides: {} ], dataSourceName -> null, factoryClassLocation -> null, identityToken -> z8kfltbbzi07q5ssu6u0\|41c4bfe3, numHelperThreads -> 3 ]
17-Jun-2025 22:11:18	INFO - Dialect.<init>(135) \|2025-06-17 22:11:18,528\| Using dialect: org.hibernate.dialect.H2Dialect
17-Jun-2025 22:11:18	INFO - JdbcSupportLoader.useContextualLobCreation(108) \|2025-06-17 22:11:18,529\| Disabling contextual LOB creation as createClob() method threw error : java.lang.reflect.InvocationTargetException
17-Jun-2025 22:11:18	INFO - SettingsFactory.buildSettings(126) \|2025-06-17 22:11:18,529\| Database ->
17-Jun-2025 22:11:18	name : H2
17-Jun-2025 22:11:18	version : 1.4.187 (2015-04-10)
17-Jun-2025 22:11:18	major : 1
17-Jun-2025 22:11:18	minor : 4
17-Jun-2025 22:11:18	INFO - SettingsFactory.buildSettings(132) \|2025-06-17 22:11:18,529\| Driver ->
17-Jun-2025 22:11:18	name : H2 JDBC Driver
17-Jun-2025 22:11:18	version : 1.4.187 (2015-04-10)
17-Jun-2025 22:11:18	major : 1
17-Jun-2025 22:11:18	minor : 4
17-Jun-2025 22:11:18	INFO - TransactionFactoryFactory.buildTransactionFactory(65) \|2025-06-17 22:11:18,529\| Transaction strategy: org.springframework.orm.hibernate3.SpringTransactionFactory
17-Jun-2025 22:11:18	INFO - TransactionManagerLookupFactory.getTransactionManagerLookup(83) \|2025-06-17 22:11:18,529\| No TransactionManagerLookup configured (in JTA environment, use of read-write or transactional second-level cache is not recommended)
17-Jun-2025 22:11:18	INFO - SettingsFactory.buildSettings(179) \|2025-06-17 22:11:18,530\| Automatic flush during beforeCompletion(): disabled
17-Jun-2025 22:11:18	INFO - SettingsFactory.buildSettings(183) \|2025-06-17 22:11:18,530\| Automatic session close at end of transaction: disabled
17-Jun-2025 22:11:18	INFO - SettingsFactory.buildSettings(190) \|2025-06-17 22:11:18,530\| JDBC batch size: 15
17-Jun-2025 22:11:18	INFO - SettingsFactory.buildSettings(193) \|2025-06-17 22:11:18,530\| JDBC batch updates for versioned data: disabled
17-Jun-2025 22:11:18	INFO - SettingsFactory.buildSettings(198) \|2025-06-17 22:11:18,530\| Scrollable result sets: enabled
17-Jun-2025 22:11:18	INFO - SettingsFactory.buildSettings(206) \|2025-06-17 22:11:18,530\| JDBC3 getGeneratedKeys(): enabled
17-Jun-2025 22:11:18	INFO - SettingsFactory.buildSettings(214) \|2025-06-17 22:11:18,530\| Connection release mode: after_transaction
17-Jun-2025 22:11:18	INFO - SettingsFactory.buildSettings(241) \|2025-06-17 22:11:18,530\| Default batch fetch size: 1
17-Jun-2025 22:11:18	INFO - SettingsFactory.buildSettings(245) \|2025-06-17 22:11:18,530\| Generate SQL with comments: disabled
17-Jun-2025 22:11:18	INFO - SettingsFactory.buildSettings(249) \|2025-06-17 22:11:18,531\| Order SQL updates by primary key: disabled
17-Jun-2025 22:11:18	INFO - SettingsFactory.buildSettings(253) \|2025-06-17 22:11:18,531\| Order SQL inserts for batching: disabled
17-Jun-2025 22:11:18	INFO - SettingsFactory.createQueryTranslatorFactory(431) \|2025-06-17 22:11:18,531\| Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory
17-Jun-2025 22:11:18	INFO - ASTQueryTranslatorFactory.<init>(47) \|2025-06-17 22:11:18,531\| Using ASTQueryTranslatorFactory
17-Jun-2025 22:11:18	INFO - SettingsFactory.buildSettings(261) \|2025-06-17 22:11:18,531\| Query language substitutions: {}
17-Jun-2025 22:11:18	INFO - SettingsFactory.buildSettings(266) \|2025-06-17 22:11:18,531\| JPA-QL strict compliance: disabled
17-Jun-2025 22:11:18	INFO - SettingsFactory.buildSettings(271) \|2025-06-17 22:11:18,531\| Second-level cache: enabled
17-Jun-2025 22:11:18	INFO - SettingsFactory.buildSettings(275) \|2025-06-17 22:11:18,531\| Query cache: disabled
17-Jun-2025 22:11:18	INFO - SettingsFactory.createRegionFactory(406) \|2025-06-17 22:11:18,531\| Cache region factory : org.hibernate.cache.impl.bridge.RegionFactoryCacheProviderBridge
17-Jun-2025 22:11:18	INFO - RegionFactoryCacheProviderBridge.<init>(61) \|2025-06-17 22:11:18,532\| Cache provider: org.hibernate.cache.EhCacheProvider
17-Jun-2025 22:11:18	INFO - SettingsFactory.buildSettings(285) \|2025-06-17 22:11:18,532\| Optimize cache for minimal puts: disabled
17-Jun-2025 22:11:18	INFO - SettingsFactory.buildSettings(294) \|2025-06-17 22:11:18,532\| Structured second-level cache entries: disabled
17-Jun-2025 22:11:18	INFO - SettingsFactory.buildSettings(323) \|2025-06-17 22:11:18,532\| Statistics: enabled
17-Jun-2025 22:11:18	INFO - SettingsFactory.buildSettings(327) \|2025-06-17 22:11:18,532\| Deleted entity synthetic identifier rollback: disabled
17-Jun-2025 22:11:18	INFO - SettingsFactory.buildSettings(343) \|2025-06-17 22:11:18,532\| Default entity-mode: pojo
17-Jun-2025 22:11:18	INFO - SettingsFactory.buildSettings(347) \|2025-06-17 22:11:18,532\| Named query checking : enabled
17-Jun-2025 22:11:18	INFO - SettingsFactory.buildSettings(351) \|2025-06-17 22:11:18,532\| Check Nullability in Core (should be disabled when Bean Validation is on): disabled
17-Jun-2025 22:11:18	INFO - SessionFactoryImpl.<init>(202) \|2025-06-17 22:11:18,536\| building session factory
17-Jun-2025 22:11:18	INFO - BasicTypeRegistry.register(150) \|2025-06-17 22:11:18,537\| Type registration [clob] overrides previous : org.hibernate.type.ClobType@21390938
17-Jun-2025 22:11:18	INFO - BasicTypeRegistry.register(150) \|2025-06-17 22:11:18,537\| Type registration [java.sql.Clob] overrides previous : org.hibernate.type.ClobType@21390938
17-Jun-2025 22:11:18	INFO - BasicTypeRegistry.register(150) \|2025-06-17 22:11:18,537\| Type registration [wrapper_characters_clob] overrides previous : org.hibernate.type.CharacterArrayClobType@7a388990
17-Jun-2025 22:11:18	INFO - BasicTypeRegistry.register(150) \|2025-06-17 22:11:18,537\| Type registration [materialized_blob] overrides previous : org.hibernate.type.MaterializedBlobType@4e4162bc
17-Jun-2025 22:11:18	INFO - BasicTypeRegistry.register(150) \|2025-06-17 22:11:18,537\| Type registration [characters_clob] overrides previous : org.hibernate.type.PrimitiveCharacterArrayClobType@72fbf94d
17-Jun-2025 22:11:18	INFO - BasicTypeRegistry.register(150) \|2025-06-17 22:11:18,538\| Type registration [materialized_clob] overrides previous : org.hibernate.type.MaterializedClobType@2d28fb02
17-Jun-2025 22:11:18	INFO - BasicTypeRegistry.register(150) \|2025-06-17 22:11:18,538\| Type registration [blob] overrides previous : org.hibernate.type.BlobType@402f8592
17-Jun-2025 22:11:18	INFO - BasicTypeRegistry.register(150) \|2025-06-17 22:11:18,538\| Type registration [java.sql.Blob] overrides previous : org.hibernate.type.BlobType@402f8592
17-Jun-2025 22:11:18	INFO - BasicTypeRegistry.register(150) \|2025-06-17 22:11:18,538\| Type registration [wrapper_materialized_blob] overrides previous : org.hibernate.type.WrappedMaterializedBlobType@7f2c57fe
17-Jun-2025 22:11:18	WARN - EhCacheProvider.buildCache(93) \|2025-06-17 22:11:18,548\| Could not find configuration [org.openmrs.ConceptStopWord]; using defaults.
17-Jun-2025 22:11:18	WARN - EhCacheProvider.buildCache(93) \|2025-06-17 22:11:18,559\| Could not find configuration [org.openmrs.Person]; using defaults.
17-Jun-2025 22:11:18	WARN - EhCacheProvider.buildCache(93) \|2025-06-17 22:11:18,562\| Could not find configuration [org.openmrs.FormResource]; using defaults.
17-Jun-2025 22:11:18	WARN - EhCacheProvider.buildCache(93) \|2025-06-17 22:11:18,585\| Could not find configuration [org.openmrs.Role.childRoles]; using defaults.
17-Jun-2025 22:11:18	WARN - EhCacheProvider.buildCache(93) \|2025-06-17 22:11:18,586\| Could not find configuration [org.openmrs.Role.inheritedRoles]; using defaults.
17-Jun-2025 22:11:18	WARN - EhCacheProvider.buildCache(93) \|2025-06-17 22:11:18,587\| Could not find configuration [org.openmrs.Role.privileges]; using defaults.
17-Jun-2025 22:11:18	INFO - SessionFactoryObjectFactory.addInstance(105) \|2025-06-17 22:11:18,630\| Not binding factory to JNDI, no JNDI name configured
17-Jun-2025 22:11:18	INFO - SchemaExport.execute(234) \|2025-06-17 22:11:18,633\| Running hbm2ddl schema export
17-Jun-2025 22:11:18	INFO - SchemaExport.execute(262) \|2025-06-17 22:11:18,634\| exporting generated schema to database
17-Jun-2025 22:11:18	INFO - SchemaExport.execute(281) \|2025-06-17 22:11:18,684\| schema export complete
17-Jun-2025 22:11:18	INFO - HapiLogImpl.info(78) \|2025-06-17 22:11:18,694\| org.openmrs.hl7.handler.ORUR01Handler registered to handle ORU^R01 messages
17-Jun-2025 22:11:18	INFO - HapiLogImpl.info(78) \|2025-06-17 22:11:18,695\| org.openmrs.hl7.handler.ADTA28Handler registered to handle ADT^A28 messages
17-Jun-2025 22:11:18	INFO - ExecutorConfigurationSupport.initialize(115) \|2025-06-17 22:11:18,715\| Initializing ExecutorService 'addressHierarchyTimerFactory'
17-Jun-2025 22:11:18	INFO - TransactionalTestExecutionListener.startNewTransaction(272) \|2025-06-17 22:11:18,719\| Began transaction (1): transaction manager [org.springframework.orm.hibernate3.HibernateTransactionManager@666c5e35]; rollback [true]
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,774\| HQL: from User u where (u.username = ? or u.systemId = ? or u.systemId = ?) and u.retired = '0', time: 1ms, rows: 1
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,774\| HQL: select password from users where user_id = ?, time: 0ms, rows: 1
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,775\| HQL: select salt from users where user_id = ?, time: 0ms, rows: 1
17-Jun-2025 22:11:18	INFO - LoggingAdvice.invoke(113) \|2025-06-17 22:11:18,776\| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: exti18n.reverseI18nSupport value: true,
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,777\| HQL: null, time: 0ms, rows: 0
17-Jun-2025 22:11:18	INFO - LoggingAdvice.invoke(150) \|2025-06-17 22:11:18,777\| Exiting method saveGlobalProperty
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,783\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,783\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,783\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,784\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,784\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,784\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,784\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,785\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,785\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,785\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,786\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,786\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,786\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,786\| HQL: null, time: 0ms, rows: 0
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,787\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,787\| HQL: null, time: 0ms, rows: 2
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,788\| HQL: null, time: 0ms, rows: 8
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,788\| HQL: null, time: 0ms, rows: 3
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,789\| HQL: null, time: 0ms, rows: 3
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,789\| HQL: null, time: 0ms, rows: 2
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,790\| HQL: null, time: 0ms, rows: 2
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,790\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:18	INFO - LoggingAdvice.invoke(113) \|2025-06-17 22:11:18,791\| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: exti18n.reverseI18nSupport value: false,
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,791\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:18	INFO - LoggingAdvice.invoke(150) \|2025-06-17 22:11:18,791\| Exiting method saveGlobalProperty
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,798\| HQL: 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, time: 0ms, rows: 1
17-Jun-2025 22:11:18	INFO - LoggingAdvice.invoke(113) \|2025-06-17 22:11:18,800\| In method PatientService.savePatient. Arguments: Patient=Patient#null,
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,801\| HQL: null, time: 0ms, rows: 0
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,801\| HQL: 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, time: 0ms, rows: 1
17-Jun-2025 22:11:18	INFO - LoggingAdvice.invoke(150) \|2025-06-17 22:11:18,802\| Exiting method savePatient
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,822\| HQL: 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, time: 0ms, rows: 1
17-Jun-2025 22:11:18	INFO - LoggingAdvice.invoke(113) \|2025-06-17 22:11:18,826\| In method PatientService.savePatient. Arguments: Patient=Patient#1000,
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,827\| HQL: null, time: 0ms, rows: 0
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,828\| HQL: 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, time: 0ms, rows: 1
17-Jun-2025 22:11:18	INFO - LoggingAdvice.invoke(150) \|2025-06-17 22:11:18,838\| Exiting method savePatient
17-Jun-2025 22:11:18	INFO - LoggingAdvice.invoke(113) \|2025-06-17 22:11:18,840\| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: exti18n.reverseI18nSupport value: true,
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,840\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:18	INFO - LoggingAdvice.invoke(150) \|2025-06-17 22:11:18,841\| Exiting method saveGlobalProperty
17-Jun-2025 22:11:18	INFO - LoggingAdvice.invoke(113) \|2025-06-17 22:11:18,841\| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: exti18n.reverseI18nSupport value: false,
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,842\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:18	INFO - LoggingAdvice.invoke(150) \|2025-06-17 22:11:18,842\| Exiting method saveGlobalProperty
17-Jun-2025 22:11:18	INFO - TransactionalTestExecutionListener.endTransaction(292) \|2025-06-17 22:11:18,865\| Rolled back transaction after test execution for test context [[TestContext@47bb82e7 testClass = AddressValuesAOPInterceptorTest, locations = array<String>['classpath:applicationContext-service.xml', 'classpath:TestingApplicationContext.xml', 'classpath:moduleApplicationContext.xml'], testInstance = org.openmrs.module.addresshierarchy.i18n.AddressValuesAOPInterceptorTest@38ed70fd, testMethod = invoke_shouldLeaveAddressesUntouchedOutsideAddressHierarchy@AddressValuesAOPInterceptorTest, testException = [null]]]
17-Jun-2025 22:11:18	INFO - TransactionalTestExecutionListener.startNewTransaction(272) \|2025-06-17 22:11:18,871\| Began transaction (2): transaction manager [org.springframework.orm.hibernate3.HibernateTransactionManager@666c5e35]; rollback [true]
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,981\| HQL: from User u where (u.username = ? or u.systemId = ? or u.systemId = ?) and u.retired = '0', time: 1ms, rows: 1
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,981\| HQL: select password from users where user_id = ?, time: 0ms, rows: 1
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,981\| HQL: select salt from users where user_id = ?, time: 0ms, rows: 1
17-Jun-2025 22:11:18	INFO - LoggingAdvice.invoke(113) \|2025-06-17 22:11:18,983\| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: exti18n.reverseI18nSupport value: true,
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,983\| HQL: null, time: 0ms, rows: 0
17-Jun-2025 22:11:18	INFO - LoggingAdvice.invoke(150) \|2025-06-17 22:11:18,983\| Exiting method saveGlobalProperty
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,992\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,992\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,992\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,993\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,993\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,993\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,993\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,993\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,993\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,994\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,994\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,994\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,994\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,994\| HQL: null, time: 0ms, rows: 0
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,995\| HQL: null, time: 0ms, rows: 2
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,995\| HQL: null, time: 0ms, rows: 8
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,995\| HQL: null, time: 0ms, rows: 3
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,996\| HQL: null, time: 0ms, rows: 3
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,996\| HQL: null, time: 0ms, rows: 2
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,996\| HQL: null, time: 0ms, rows: 2
17-Jun-2025 22:11:18	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:18,997\| HQL: null, time: 0ms, rows: 0
17-Jun-2025 22:11:19	INFO - LoggingAdvice.invoke(113) \|2025-06-17 22:11:19,005\| In method LocationService.saveLocation. Arguments: Location=My Location,
17-Jun-2025 22:11:19	INFO - LoggingAdvice.invoke(150) \|2025-06-17 22:11:19,015\| Exiting method saveLocation
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,017\| HQL: null, time: 0ms, rows: 0
17-Jun-2025 22:11:19	INFO - LoggingAdvice.invoke(113) \|2025-06-17 22:11:19,017\| In method LocationService.saveLocation. Arguments: Location=My Location,
17-Jun-2025 22:11:19	INFO - LoggingAdvice.invoke(150) \|2025-06-17 22:11:19,017\| Exiting method saveLocation
17-Jun-2025 22:11:19	INFO - LoggingAdvice.invoke(113) \|2025-06-17 22:11:19,017\| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: exti18n.reverseI18nSupport value: false,
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,018\| HQL: null, time: 1ms, rows: 1
17-Jun-2025 22:11:19	INFO - LoggingAdvice.invoke(150) \|2025-06-17 22:11:19,018\| Exiting method saveGlobalProperty
17-Jun-2025 22:11:19	INFO - TransactionalTestExecutionListener.endTransaction(292) \|2025-06-17 22:11:19,040\| Rolled back transaction after test execution for test context [[TestContext@47bb82e7 testClass = AddressValuesAOPInterceptorTest, locations = array<String>['classpath:applicationContext-service.xml', 'classpath:TestingApplicationContext.xml', 'classpath:moduleApplicationContext.xml'], testInstance = org.openmrs.module.addresshierarchy.i18n.AddressValuesAOPInterceptorTest@72f8c052, testMethod = invoke_shouldReturnL10nLocation@AddressValuesAOPInterceptorTest, testException = [null]]]
17-Jun-2025 22:11:19	INFO - TransactionalTestExecutionListener.startNewTransaction(272) \|2025-06-17 22:11:19,040\| Began transaction (3): transaction manager [org.springframework.orm.hibernate3.HibernateTransactionManager@666c5e35]; rollback [true]
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,143\| HQL: from User u where (u.username = ? or u.systemId = ? or u.systemId = ?) and u.retired = '0', time: 1ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,144\| HQL: select password from users where user_id = ?, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,144\| HQL: select salt from users where user_id = ?, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - LoggingAdvice.invoke(113) \|2025-06-17 22:11:19,144\| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: exti18n.reverseI18nSupport value: true,
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,145\| HQL: null, time: 0ms, rows: 0
17-Jun-2025 22:11:19	INFO - LoggingAdvice.invoke(150) \|2025-06-17 22:11:19,145\| Exiting method saveGlobalProperty
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,156\| HQL: null, time: 1ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,156\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,162\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,162\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,162\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,164\| HQL: null, time: 1ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,164\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,164\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,164\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,165\| HQL: null, time: 1ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,165\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,165\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,165\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,165\| HQL: null, time: 0ms, rows: 0
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,166\| HQL: null, time: 1ms, rows: 2
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,170\| HQL: null, time: 0ms, rows: 8
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,170\| HQL: null, time: 0ms, rows: 3
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,171\| HQL: null, time: 0ms, rows: 3
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,171\| HQL: null, time: 0ms, rows: 2
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,172\| HQL: null, time: 1ms, rows: 2
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,172\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,172\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,172\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,173\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,173\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,173\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,173\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,173\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,174\| HQL: null, time: 1ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,174\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,174\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,174\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,174\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,174\| HQL: null, time: 0ms, rows: 0
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,175\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,175\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,175\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,175\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,175\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,175\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,176\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,176\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,176\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,176\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,176\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,177\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,177\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,177\| HQL: null, time: 0ms, rows: 0
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,177\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,177\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,178\| HQL: null, time: 0ms, rows: 2
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,189\| HQL: null, time: 11ms, rows: 2
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,190\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,192\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,192\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,192\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,192\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,193\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,193\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,193\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,193\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,193\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,194\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,194\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,194\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,194\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,194\| HQL: null, time: 0ms, rows: 0
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,194\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,195\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,195\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,196\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,196\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,196\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,196\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,196\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,197\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,197\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,197\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,197\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,197\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,197\| HQL: null, time: 0ms, rows: 0
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,198\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,198\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,198\| HQL: null, time: 0ms, rows: 2
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,199\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,202\| HQL: null, time: 0ms, rows: 0
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,202\| HQL: null, time: 0ms, rows: 2
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,202\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,203\| HQL: null, time: 1ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,203\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,203\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,203\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,203\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,203\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,204\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,204\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,204\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,204\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,204\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,204\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,210\| HQL: null, time: 0ms, rows: 0
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,210\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,210\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,211\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,211\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,211\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,211\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,211\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,212\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,212\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,212\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,212\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,212\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,212\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,213\| HQL: null, time: 0ms, rows: 0
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,213\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,213\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,213\| HQL: null, time: 0ms, rows: 2
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,214\| HQL: null, time: 1ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,214\| HQL: null, time: 0ms, rows: 0
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,214\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,214\| HQL: null, time: 0ms, rows: 2
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,215\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,215\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,215\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,215\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,215\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,215\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,216\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,216\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,216\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,216\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,216\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,217\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,217\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,217\| HQL: null, time: 0ms, rows: 0
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,217\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,217\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,217\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,218\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,218\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,226\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,226\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,226\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,227\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,227\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,227\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,227\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,227\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,228\| HQL: null, time: 0ms, rows: 0
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,228\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,228\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,228\| HQL: null, time: 0ms, rows: 2
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,228\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,229\| HQL: null, time: 0ms, rows: 0
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,229\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,229\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,229\| HQL: null, time: 0ms, rows: 2
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,231\| HQL: 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, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - LoggingAdvice.invoke(113) \|2025-06-17 22:11:19,232\| In method PatientService.savePatient. Arguments: Patient=Patient#null,
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,232\| HQL: null, time: 0ms, rows: 0
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,232\| HQL: 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, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - LoggingAdvice.invoke(150) \|2025-06-17 22:11:19,243\| Exiting method savePatient
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,245\| HQL: 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, time: 2ms, rows: 1
17-Jun-2025 22:11:19	INFO - LoggingAdvice.invoke(113) \|2025-06-17 22:11:19,246\| In method PatientService.savePatient. Arguments: Patient=Patient#1001,
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,247\| HQL: null, time: 0ms, rows: 0
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,248\| HQL: 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, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - LoggingAdvice.invoke(150) \|2025-06-17 22:11:19,248\| Exiting method savePatient
17-Jun-2025 22:11:19	INFO - LoggingAdvice.invoke(113) \|2025-06-17 22:11:19,249\| In method AdministrationService.saveGlobalProperty. Arguments: GlobalProperty=property: exti18n.reverseI18nSupport value: false,
17-Jun-2025 22:11:19	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:19,249\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:19	INFO - LoggingAdvice.invoke(150) \|2025-06-17 22:11:19,249\| Exiting method saveGlobalProperty
17-Jun-2025 22:11:19	INFO - TransactionalTestExecutionListener.endTransaction(292) \|2025-06-17 22:11:19,281\| Rolled back transaction after test execution for test context [[TestContext@47bb82e7 testClass = AddressValuesAOPInterceptorTest, locations = array<String>['classpath:applicationContext-service.xml', 'classpath:TestingApplicationContext.xml', 'classpath:moduleApplicationContext.xml'], testInstance = org.openmrs.module.addresshierarchy.i18n.AddressValuesAOPInterceptorTest@63e5d27, testMethod = invoke_shouldReturnL10nPersonAddress@AddressValuesAOPInterceptorTest, testException = [null]]]
17-Jun-2025 22:11:19	INFO - AbstractApplicationContext.doClose(1002) \|2025-06-17 22:11:19,282\| Closing org.springframework.context.support.GenericApplicationContext@463c4ed4: startup date [Tue Jun 17 22:11:18 UTC 2025]; root of context hierarchy
17-Jun-2025 22:11:19	INFO - DefaultSingletonBeanRegistry.destroySingletons(422) \|2025-06-17 22:11:19,282\| Destroying singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@741b3354: defining beans [org.springframework.aop.framework.autoproxy.DefaultAdvisorAutoProxyCreator#0,org.springframework.transaction.interceptor.TransactionAttributeSourceAdvisor#0,transactionInterceptor,globalLocaleList,clearOpenmrsEventListeners,openmrsEventListeners,serviceContext,context,contextDAO,patientDAO,personDAO,conceptDAO,userDAO,obsDAO,encounterDAO,locationDAO,orderDAO,formDAO,adminDAO,noteDAO,programWorkflowDAO,templateDAO,patientSetDAO,cohortDAO,serializedObjectDAO,datatypeDAO,schedulerDAO,alertDAO,reportDAO,reportObjectDAO,hL7DAO,activeListDAO,visitDAO,providerDAO,patientServiceTarget,personServiceTarget,conceptServiceTarget,userServiceTarget,obsServiceTarget,encounterServiceTarget,locationServiceTarget,orderServiceTarget,formServiceTarget,adminServiceTarget,datatypeServiceTarget,programWorkflowServiceTarget,patientSetServiceTarget,activeListServiceTarget,visitServiceTarget,providerServiceTarget,cohortServiceTarget,schedulerServiceTarget,alertServiceTarget,messageServiceTarget,reportObjectServiceTarget,reportServiceTarget,serializationServiceTarget,dataSetServiceTarget,hL7ServiceTarget,ardenServiceTarget,messageSourceServiceTarget,transactionAttributeSource,mutableResourceBundleMessageSource,patientService,personService,conceptService,userService,obsService,encounterService,locationService,orderService,formService,adminService,datatypeService,programWorkflowService,messageService,patientSetService,cohortService,schedulerService,alertService,reportService,reportObjectService,serializationService,dataSetService,hL7Service,ardenService,messageSourceService,activeListService,visitService,providerService,authorizationInterceptor,loggingInterceptor,requiredDataInterceptor,serviceInterceptors,moduleTestingMappingJarLocations,mappingJarResources,auditableInterceptor,sessionFactory,dbSessionFactory,transactionManager,patientCharacteristicFilterModule,numericObsPatientFilterModule,shortDescriptionProducerModule,personValidator,patientValidator,locationValidator,personNameValidator,patientIdentifierValidator,patientIdentifierTypeValidator,personAttributeTypeValidator,userValidator,roleValidator,privilegeValidator,encounterTypeValidator,conceptClassValidator,conceptDatatypeValidator,formEditValidator,fieldTypeValidator,programValidator,stateConversionValidator,taskValidator,obsValidator,orderValidator,drugOrderValidator,requireNameValidator,conceptDrugValidator,hl7SourceValidator,personAddressValidator,visitTypeValidator,visitAttributeTypeValidator,providerAttributeTypeValidator,encounterValidator,locationAttributeTypeValidator,providerValidator,patientProgramValidator,conceptValidator,personMergeLogValidator,conceptReferenceTermValidator,conceptMapTypeValidator,implementationIdHttpClient,hl7InErrorListController,hl7InArchiveMigrationController,hl7InQueueListController,hl7InArchiveListController,webSchedulerFormValidator,longFreeTextTextareaHandler,booleanFieldGenDatatypeHandler,longFreeTextFileUploadHandler,dateFieldGenDatatypeHandler,formResourceController,locationAttributeTypeFormController,locationAttributeTypeListController,providerFormController,patientDashboardController,shortPatientFormController,patientDashboardGraphController,coreLoginController,conceptStopWordListController,conceptMapTypeFormController,conceptStopWordFormController,conceptReferenceTermFormController,visitListController,configureVisitsFormController,visitFormController,uncaughtExceptionController,personAttributeTypeListController,encounterRoleFormController,addressTemplateController,hierarchyController,locationTagController,databaseChangesInfoController,settingsController,systemInformationController,currentUsersController,localesAndThemesFormController,userFormController,userListController,changePasswordFormController,localizationController,locationQueryController,customValueController,exti18n.ExtI18nConfig,addressHierarchyLevelValidator,i18nCacheImpl,webTestHelper,handlerUtil,schedulerFormValidator,alertValidator,formValidator,orderTypeValidator,baseAttributeValidator,relationshipTypeValidator,testOrderValidator,orderFrequencyValidator,locationTagValidator,drugValidator,HL7SourceValidator,visitValidator,encounterRoleValidator,immutableOrderInterceptor,noVisitAssignmentHandler,requireVoidReasonVoidHandler,patientDataUnvoidHandler,encounterSaveHandler,conceptNameSaveHandler,voidSaveHandler,existingOrNewVisitAssignmentHandler,existingVisitAssignmentHandler,userSaveHandler,patientSaveHandler,baseVoidHandler,conceptReferenceTermSaveHandler,conceptProposalHandler,visitUnvoidHandler,visitVoidHandler,personSaveHandler,conceptSaveHandler,personUnvoidHandler,baseRetireHandler,personVoidHandler,orderSaveHandler,patientDataVoidHandler,baseUnvoidHandler,openmrsObjectSaveHandler,requireVoidReasonSaveHandler,personNameSaveHandler,retireSaveHandler,baseUnretireHandler,freeTextDatatype,longFreeTextDatatype,dateDatatype,regexValidatedTextDatatype,booleanDatatype,orderEntry.OrderNumberGenerator,openmrsComponent1_8,openmrsProfile1_10,openmrsProfileWithoutTest1Module,listener2,listener1,org.springframework.context.annotation.internalConfigurationAnnotationProcessor,org.springframework.context.annotation.internalAutowiredAnnotationProcessor,org.springframework.context.annotation.internalRequiredAnnotationProcessor,org.springframework.context.annotation.internalCommonAnnotationProcessor,org.springframework.context.annotation.internalPersistenceAnnotationProcessor,serviceContext$child#0,proj001MappingResources,exti18n.TestsMessageSource,exti18n.addressValuesHibernateInterceptor,exti18n.addressValuesAOPInterceptor,addressHierarchyActivator,exti18n.ExtI18nService,serviceContext$child#1,exti18n.reverseI18nCache,exti18n.addressHierarchyI18nCache,exti18n.metadataHibernateInterceptor,exti18n.metadataAOPInterceptor,exti18n.metadataLocalizer,exti18n.addressValuesLocalizer,exti18n.globalPropertyListener,serviceContext$child#2,AddressCacheResetSupport,initializeFullAddressCacheTask,initializeFullAddressCacheTimerTask,addressHierarchyTimerFactory]; root of factory hierarchy
17-Jun-2025 22:11:19	INFO - ExecutorConfigurationSupport.shutdown(150) \|2025-06-17 22:11:19,287\| Shutting down ExecutorService 'addressHierarchyTimerFactory'
17-Jun-2025 22:11:19	INFO - AbstractSessionFactoryBean.destroy(246) \|2025-06-17 22:11:19,292\| Closing Hibernate SessionFactory
17-Jun-2025 22:11:19	INFO - SessionFactoryImpl.close(927) \|2025-06-17 22:11:19,292\| closing
17-Jun-2025 22:11:19	INFO - SchemaExport.execute(234) \|2025-06-17 22:11:19,292\| Running hbm2ddl schema export
17-Jun-2025 22:11:19	INFO - SchemaExport.execute(262) \|2025-06-17 22:11:19,293\| exporting generated schema to database
17-Jun-2025 22:11:19	INFO - SchemaExport.execute(281) \|2025-06-17 22:11:19,319\| schema export complete
17-Jun-2025 22:11:19	[INFO] Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.263 s - in org.openmrs.module.addresshierarchy.i18n.AddressValuesAOPInterceptorTest
17-Jun-2025 22:11:19	[INFO]
17-Jun-2025 22:11:19	[INFO] Results:
17-Jun-2025 22:11:19	[INFO]
17-Jun-2025 22:11:19	[WARNING] Tests run: 87, Failures: 0, Errors: 0, Skipped: 2
17-Jun-2025 22:11:19	[INFO]
17-Jun-2025 22:11:19	[INFO]
17-Jun-2025 22:11:19	[INFO] --- maven-jar-plugin:2.4:jar (default-jar) @ addresshierarchy-api ---
17-Jun-2025 22:11:19	[INFO] Building jar: /home/bamboo-agent/bamboo-agent/xml-data/build-dir/ADDRHIER-ADDRHIER-JOB1/api/target/addresshierarchy-api-2.22.0-SNAPSHOT.jar
17-Jun-2025 22:11:19	[INFO]
17-Jun-2025 22:11:19	[INFO] --- maven-install-plugin:2.4:install (default-install) @ addresshierarchy-api ---
17-Jun-2025 22:11:19	[INFO] Installing /home/bamboo-agent/bamboo-agent/xml-data/build-dir/ADDRHIER-ADDRHIER-JOB1/api/target/addresshierarchy-api-2.22.0-SNAPSHOT.jar to /home/bamboo-agent/.m2/repository/org/openmrs/module/addresshierarchy-api/2.22.0-SNAPSHOT/addresshierarchy-api-2.22.0-SNAPSHOT.jar
17-Jun-2025 22:11:19	[INFO] Installing /home/bamboo-agent/bamboo-agent/xml-data/build-dir/ADDRHIER-ADDRHIER-JOB1/api/pom.xml to /home/bamboo-agent/.m2/repository/org/openmrs/module/addresshierarchy-api/2.22.0-SNAPSHOT/addresshierarchy-api-2.22.0-SNAPSHOT.pom
17-Jun-2025 22:11:19	[INFO]
17-Jun-2025 22:11:19	[INFO] --- maven-deploy-plugin:2.7:deploy (default-deploy) @ addresshierarchy-api ---
17-Jun-2025 22:11:19	[INFO] Downloading from openmrs-repo-snapshots: https://mavenrepo.openmrs.org/snapshots/org/openmrs/module/addresshierarchy-api/2.22.0-SNAPSHOT/maven-metadata.xml
17-Jun-2025 22:11:20	[INFO] Downloaded from openmrs-repo-snapshots: https://mavenrepo.openmrs.org/snapshots/org/openmrs/module/addresshierarchy-api/2.22.0-SNAPSHOT/maven-metadata.xml (791 B at 3.2 kB/s)
17-Jun-2025 22:11:20	[INFO] Uploading to openmrs-repo-snapshots: https://mavenrepo.openmrs.org/snapshots/org/openmrs/module/addresshierarchy-api/2.22.0-SNAPSHOT/addresshierarchy-api-2.22.0-20250617.221031-2.jar
17-Jun-2025 22:11:22	[INFO] Uploaded to openmrs-repo-snapshots: https://mavenrepo.openmrs.org/snapshots/org/openmrs/module/addresshierarchy-api/2.22.0-SNAPSHOT/addresshierarchy-api-2.22.0-20250617.221031-2.jar (76 kB at 38 kB/s)
17-Jun-2025 22:11:22	[INFO] Uploading to openmrs-repo-snapshots: https://mavenrepo.openmrs.org/snapshots/org/openmrs/module/addresshierarchy-api/2.22.0-SNAPSHOT/addresshierarchy-api-2.22.0-20250617.221031-2.pom
17-Jun-2025 22:11:23	[INFO] Uploaded to openmrs-repo-snapshots: https://mavenrepo.openmrs.org/snapshots/org/openmrs/module/addresshierarchy-api/2.22.0-SNAPSHOT/addresshierarchy-api-2.22.0-20250617.221031-2.pom (5.2 kB at 3.1 kB/s)
17-Jun-2025 22:11:23	[INFO] Downloading from openmrs-repo-snapshots: https://mavenrepo.openmrs.org/snapshots/org/openmrs/module/addresshierarchy-api/maven-metadata.xml
17-Jun-2025 22:11:24	[INFO] Downloaded from openmrs-repo-snapshots: https://mavenrepo.openmrs.org/snapshots/org/openmrs/module/addresshierarchy-api/maven-metadata.xml (1.0 kB at 4.1 kB/s)
17-Jun-2025 22:11:24	[INFO] Uploading to openmrs-repo-snapshots: https://mavenrepo.openmrs.org/snapshots/org/openmrs/module/addresshierarchy-api/2.22.0-SNAPSHOT/maven-metadata.xml
17-Jun-2025 22:11:25	[INFO] Uploaded to openmrs-repo-snapshots: https://mavenrepo.openmrs.org/snapshots/org/openmrs/module/addresshierarchy-api/2.22.0-SNAPSHOT/maven-metadata.xml (791 B at 631 B/s)
17-Jun-2025 22:11:25	[INFO] Uploading to openmrs-repo-snapshots: https://mavenrepo.openmrs.org/snapshots/org/openmrs/module/addresshierarchy-api/maven-metadata.xml
17-Jun-2025 22:11:26	[INFO] Uploaded to openmrs-repo-snapshots: https://mavenrepo.openmrs.org/snapshots/org/openmrs/module/addresshierarchy-api/maven-metadata.xml (947 B at 676 B/s)
17-Jun-2025 22:11:26	[INFO]
17-Jun-2025 22:11:26	[INFO] --------------< org.openmrs.module:addresshierarchy-omod >--------------
17-Jun-2025 22:11:26	[INFO] Building Address Hierarchy OMOD 2.22.0-SNAPSHOT [3/3]
17-Jun-2025 22:11:26	[INFO] --------------------------------[ jar ]---------------------------------
17-Jun-2025 22:11:26	[INFO]
17-Jun-2025 22:11:26	[INFO] --- maven-clean-plugin:2.5:clean (default-clean) @ addresshierarchy-omod ---
17-Jun-2025 22:11:26	[INFO]
17-Jun-2025 22:11:26	[INFO] --- maven-openmrs-plugin:1.0.1:initialize-module (init) @ addresshierarchy-omod ---
17-Jun-2025 22:11:26	[INFO]
17-Jun-2025 22:11:26	[INFO] --- maven-dependency-plugin:2.8:unpack-dependencies (Expand moduleApplicationContext and messages) @ addresshierarchy-omod ---
17-Jun-2025 22:11:27	[INFO] Unpacking /home/bamboo-agent/bamboo-agent/xml-data/build-dir/ADDRHIER-ADDRHIER-JOB1/api/target/addresshierarchy-api-2.22.0-SNAPSHOT.jar to /home/bamboo-agent/bamboo-agent/xml-data/build-dir/ADDRHIER-ADDRHIER-JOB1/omod/target/classes with includes "*\/.xml,*\/.properties" and excludes ""
17-Jun-2025 22:11:27	[INFO]
17-Jun-2025 22:11:27	[INFO] --- maven-resources-plugin:2.6:resources (default-resources) @ addresshierarchy-omod ---
17-Jun-2025 22:11:27	[INFO] Using 'UTF-8' encoding to copy filtered resources.
17-Jun-2025 22:11:27	[INFO] Copying 4 resources
17-Jun-2025 22:11:27	[INFO] Copying 6 resources to web/module
17-Jun-2025 22:11:27	[INFO] Copying 0 resource to web/module
17-Jun-2025 22:11:27	[INFO] Copying 6 resources to web/module
17-Jun-2025 22:11:27	[INFO]
17-Jun-2025 22:11:27	[INFO] --- maven-compiler-plugin:3.1:compile (default-compile) @ addresshierarchy-omod ---
17-Jun-2025 22:11:27	[INFO] Changes detected - recompiling the module!
17-Jun-2025 22:11:27	[INFO] Compiling 5 source files to /home/bamboo-agent/bamboo-agent/xml-data/build-dir/ADDRHIER-ADDRHIER-JOB1/omod/target/classes
17-Jun-2025 22:11:27	[WARNING] /home/bamboo-agent/bamboo-agent/xml-data/build-dir/ADDRHIER-ADDRHIER-JOB1/omod/src/main/java/org/openmrs/module/addresshierarchy/web/controller/ManageAddressHierarchyController.java: /home/bamboo-agent/bamboo-agent/xml-data/build-dir/ADDRHIER-ADDRHIER-JOB1/omod/src/main/java/org/openmrs/module/addresshierarchy/web/controller/ManageAddressHierarchyController.java uses unchecked or unsafe operations.
17-Jun-2025 22:11:27	[WARNING] /home/bamboo-agent/bamboo-agent/xml-data/build-dir/ADDRHIER-ADDRHIER-JOB1/omod/src/main/java/org/openmrs/module/addresshierarchy/web/controller/ManageAddressHierarchyController.java: Recompile with -Xlint:unchecked for details.
17-Jun-2025 22:11:27	[INFO]
17-Jun-2025 22:11:27	[INFO] --- maven-resources-plugin:2.6:testResources (default-testResources) @ addresshierarchy-omod ---
17-Jun-2025 22:11:27	[INFO] Using 'UTF-8' encoding to copy filtered resources.
17-Jun-2025 22:11:27	[INFO] Copying 4 resources
17-Jun-2025 22:11:27	[INFO] Copying 0 resource
17-Jun-2025 22:11:27	[INFO]
17-Jun-2025 22:11:27	[INFO] --- maven-compiler-plugin:3.1:testCompile (default-testCompile) @ addresshierarchy-omod ---
17-Jun-2025 22:11:27	[INFO] Changes detected - recompiling the module!
17-Jun-2025 22:11:27	[INFO] Compiling 1 source file to /home/bamboo-agent/bamboo-agent/xml-data/build-dir/ADDRHIER-ADDRHIER-JOB1/omod/target/test-classes
17-Jun-2025 22:11:27	[INFO]
17-Jun-2025 22:11:27	[INFO] --- maven-surefire-plugin:2.22.1:test (default-test) @ addresshierarchy-omod ---
17-Jun-2025 22:11:28	[INFO]
17-Jun-2025 22:11:28	[INFO] -------------------------------------------------------
17-Jun-2025 22:11:28	[INFO] T E S T S
17-Jun-2025 22:11:28	[INFO] -------------------------------------------------------
17-Jun-2025 22:11:28	[INFO] Running org.openmrs.module.addresshierarchy.web.controller.ajax.AddressHierarchyAjaxControllerTest
17-Jun-2025 22:11:28	INFO - XmlBeanDefinitionReader.loadBeanDefinitions(315) \|2025-06-17 22:11:28,684\| Loading XML bean definitions from class path resource [applicationContext-service.xml]
17-Jun-2025 22:11:29	INFO - XmlBeanDefinitionReader.loadBeanDefinitions(315) \|2025-06-17 22:11:29,426\| Loading XML bean definitions from URL [file:/home/bamboo-agent/bamboo-agent/xml-data/build-dir/ADDRHIER-ADDRHIER-JOB1/omod/target/test-classes/TestingApplicationContext.xml]
17-Jun-2025 22:11:29	INFO - DefaultListableBeanFactory.registerBeanDefinition(618) \|2025-06-17 22:11:29,446\| Overriding bean definition for bean 'sessionFactory': replacing [Generic bean: class [org.openmrs.api.db.hibernate.HibernateSessionFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null; defined in class path resource [applicationContext-service.xml]] with [Generic bean: class [org.openmrs.api.db.hibernate.HibernateSessionFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null; defined in URL [file:/home/bamboo-agent/bamboo-agent/xml-data/build-dir/ADDRHIER-ADDRHIER-JOB1/omod/target/test-classes/TestingApplicationContext.xml]]
17-Jun-2025 22:11:29	INFO - XmlBeanDefinitionReader.loadBeanDefinitions(315) \|2025-06-17 22:11:29,449\| Loading XML bean definitions from URL [jar:file:/home/bamboo-agent/.m2/repository/org/openmrs/api/openmrs-api/1.10.6/openmrs-api-1.10.6-tests.jar!/TestingApplicationContext.xml]
17-Jun-2025 22:11:29	INFO - DefaultListableBeanFactory.registerBeanDefinition(618) \|2025-06-17 22:11:29,468\| Overriding bean definition for bean 'mappingJarResources': replacing [Generic bean: class [org.springframework.beans.factory.config.ListFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null; defined in class path resource [applicationContext-service.xml]] with [Generic bean: class [org.springframework.beans.factory.config.ListFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null; defined in URL [jar:file:/home/bamboo-agent/.m2/repository/org/openmrs/api/openmrs-api/1.10.6/openmrs-api-1.10.6-tests.jar!/TestingApplicationContext.xml]]
17-Jun-2025 22:11:29	INFO - XmlBeanDefinitionReader.loadBeanDefinitions(315) \|2025-06-17 22:11:29,472\| Loading XML bean definitions from URL [file:/home/bamboo-agent/bamboo-agent/xml-data/build-dir/ADDRHIER-ADDRHIER-JOB1/omod/target/classes/moduleApplicationContext.xml]
17-Jun-2025 22:11:29	INFO - XmlBeanDefinitionReader.loadBeanDefinitions(315) \|2025-06-17 22:11:29,481\| Loading XML bean definitions from URL [jar:file:/home/bamboo-agent/bamboo-agent/xml-data/build-dir/ADDRHIER-ADDRHIER-JOB1/api/target/addresshierarchy-api-2.22.0-SNAPSHOT.jar!/moduleApplicationContext.xml]
17-Jun-2025 22:11:29	INFO - DefaultListableBeanFactory.registerBeanDefinition(618) \|2025-06-17 22:11:29,503\| Overriding bean definition for bean 'AddressCacheResetSupport': replacing [Generic bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null; defined in URL [file:/home/bamboo-agent/bamboo-agent/xml-data/build-dir/ADDRHIER-ADDRHIER-JOB1/omod/target/classes/moduleApplicationContext.xml]] with [Generic bean: class [null]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null; defined in URL [jar:file:/home/bamboo-agent/bamboo-agent/xml-data/build-dir/ADDRHIER-ADDRHIER-JOB1/api/target/addresshierarchy-api-2.22.0-SNAPSHOT.jar!/moduleApplicationContext.xml]]
17-Jun-2025 22:11:29	INFO - DefaultListableBeanFactory.registerBeanDefinition(618) \|2025-06-17 22:11:29,504\| Overriding bean definition for bean 'initializeFullAddressCacheTask': replacing [Generic bean: class [org.openmrs.module.addresshierarchy.scheduler.InitializeFullAddressCacheTask]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null; defined in URL [file:/home/bamboo-agent/bamboo-agent/xml-data/build-dir/ADDRHIER-ADDRHIER-JOB1/omod/target/classes/moduleApplicationContext.xml]] with [Generic bean: class [org.openmrs.module.addresshierarchy.scheduler.InitializeFullAddressCacheTask]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null; defined in URL [jar:file:/home/bamboo-agent/bamboo-agent/xml-data/build-dir/ADDRHIER-ADDRHIER-JOB1/api/target/addresshierarchy-api-2.22.0-SNAPSHOT.jar!/moduleApplicationContext.xml]]
17-Jun-2025 22:11:29	INFO - DefaultListableBeanFactory.registerBeanDefinition(618) \|2025-06-17 22:11:29,506\| Overriding bean definition for bean 'initializeFullAddressCacheTimerTask': replacing [Generic bean: class [org.springframework.scheduling.concurrent.ScheduledExecutorTask]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null; defined in URL [file:/home/bamboo-agent/bamboo-agent/xml-data/build-dir/ADDRHIER-ADDRHIER-JOB1/omod/target/classes/moduleApplicationContext.xml]] with [Generic bean: class [org.springframework.scheduling.concurrent.ScheduledExecutorTask]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null; defined in URL [jar:file:/home/bamboo-agent/bamboo-agent/xml-data/build-dir/ADDRHIER-ADDRHIER-JOB1/api/target/addresshierarchy-api-2.22.0-SNAPSHOT.jar!/moduleApplicationContext.xml]]
17-Jun-2025 22:11:29	INFO - DefaultListableBeanFactory.registerBeanDefinition(618) \|2025-06-17 22:11:29,508\| Overriding bean definition for bean 'addressHierarchyTimerFactory': replacing [Generic bean: class [org.springframework.scheduling.concurrent.ScheduledExecutorFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null; defined in URL [file:/home/bamboo-agent/bamboo-agent/xml-data/build-dir/ADDRHIER-ADDRHIER-JOB1/omod/target/classes/moduleApplicationContext.xml]] with [Generic bean: class [org.springframework.scheduling.concurrent.ScheduledExecutorFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null; defined in URL [jar:file:/home/bamboo-agent/bamboo-agent/xml-data/build-dir/ADDRHIER-ADDRHIER-JOB1/api/target/addresshierarchy-api-2.22.0-SNAPSHOT.jar!/moduleApplicationContext.xml]]
17-Jun-2025 22:11:29	INFO - AbstractApplicationContext.prepareRefresh(456) \|2025-06-17 22:11:29,511\| Refreshing org.springframework.context.support.GenericApplicationContext@6b1274d2: startup date [Tue Jun 17 22:11:29 UTC 2025]; root of context hierarchy
17-Jun-2025 22:11:29	INFO - DefaultListableBeanFactory.preInstantiateSingletons(555) \|2025-06-17 22:11:29,757\| Pre-instantiating singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@7ca0863b: defining beans [org.springframework.aop.framework.autoproxy.DefaultAdvisorAutoProxyCreator#0,org.springframework.transaction.interceptor.TransactionAttributeSourceAdvisor#0,transactionInterceptor,globalLocaleList,clearOpenmrsEventListeners,openmrsEventListeners,serviceContext,context,contextDAO,patientDAO,personDAO,conceptDAO,userDAO,obsDAO,encounterDAO,locationDAO,orderDAO,formDAO,adminDAO,noteDAO,programWorkflowDAO,templateDAO,patientSetDAO,cohortDAO,serializedObjectDAO,datatypeDAO,schedulerDAO,alertDAO,reportDAO,reportObjectDAO,hL7DAO,activeListDAO,visitDAO,providerDAO,patientServiceTarget,personServiceTarget,conceptServiceTarget,userServiceTarget,obsServiceTarget,encounterServiceTarget,locationServiceTarget,orderServiceTarget,formServiceTarget,adminServiceTarget,datatypeServiceTarget,programWorkflowServiceTarget,patientSetServiceTarget,activeListServiceTarget,visitServiceTarget,providerServiceTarget,cohortServiceTarget,schedulerServiceTarget,alertServiceTarget,messageServiceTarget,reportObjectServiceTarget,reportServiceTarget,serializationServiceTarget,dataSetServiceTarget,hL7ServiceTarget,ardenServiceTarget,messageSourceServiceTarget,transactionAttributeSource,mutableResourceBundleMessageSource,patientService,personService,conceptService,userService,obsService,encounterService,locationService,orderService,formService,adminService,datatypeService,programWorkflowService,messageService,patientSetService,cohortService,schedulerService,alertService,reportService,reportObjectService,serializationService,dataSetService,hL7Service,ardenService,messageSourceService,activeListService,visitService,providerService,authorizationInterceptor,loggingInterceptor,requiredDataInterceptor,serviceInterceptors,moduleTestingMappingJarLocations,mappingJarResources,auditableInterceptor,sessionFactory,dbSessionFactory,transactionManager,patientCharacteristicFilterModule,numericObsPatientFilterModule,shortDescriptionProducerModule,personValidator,patientValidator,locationValidator,personNameValidator,patientIdentifierValidator,patientIdentifierTypeValidator,personAttributeTypeValidator,userValidator,roleValidator,privilegeValidator,encounterTypeValidator,conceptClassValidator,conceptDatatypeValidator,formEditValidator,fieldTypeValidator,programValidator,stateConversionValidator,taskValidator,obsValidator,orderValidator,drugOrderValidator,requireNameValidator,conceptDrugValidator,hl7SourceValidator,personAddressValidator,visitTypeValidator,visitAttributeTypeValidator,providerAttributeTypeValidator,encounterValidator,locationAttributeTypeValidator,providerValidator,patientProgramValidator,conceptValidator,personMergeLogValidator,conceptReferenceTermValidator,conceptMapTypeValidator,implementationIdHttpClient,hl7InErrorListController,hl7InArchiveMigrationController,hl7InQueueListController,hl7InArchiveListController,webSchedulerFormValidator,longFreeTextTextareaHandler,booleanFieldGenDatatypeHandler,longFreeTextFileUploadHandler,dateFieldGenDatatypeHandler,formResourceController,locationAttributeTypeFormController,locationAttributeTypeListController,providerFormController,patientDashboardController,shortPatientFormController,patientDashboardGraphController,coreLoginController,conceptStopWordListController,conceptMapTypeFormController,conceptStopWordFormController,conceptReferenceTermFormController,visitListController,configureVisitsFormController,visitFormController,uncaughtExceptionController,personAttributeTypeListController,encounterRoleFormController,addressTemplateController,hierarchyController,locationTagController,databaseChangesInfoController,settingsController,systemInformationController,currentUsersController,localesAndThemesFormController,userFormController,userListController,changePasswordFormController,localizationController,locationQueryController,customValueController,advancedFeaturesController,addressHierarchyAjaxController,manageAddressHierarchyController,webTestHelper,handlerUtil,schedulerFormValidator,alertValidator,formValidator,orderTypeValidator,baseAttributeValidator,relationshipTypeValidator,testOrderValidator,orderFrequencyValidator,locationTagValidator,drugValidator,HL7SourceValidator,visitValidator,encounterRoleValidator,immutableOrderInterceptor,noVisitAssignmentHandler,requireVoidReasonVoidHandler,patientDataUnvoidHandler,encounterSaveHandler,conceptNameSaveHandler,voidSaveHandler,existingOrNewVisitAssignmentHandler,existingVisitAssignmentHandler,userSaveHandler,patientSaveHandler,baseVoidHandler,conceptReferenceTermSaveHandler,conceptProposalHandler,visitUnvoidHandler,visitVoidHandler,personSaveHandler,conceptSaveHandler,personUnvoidHandler,baseRetireHandler,personVoidHandler,orderSaveHandler,patientDataVoidHandler,baseUnvoidHandler,openmrsObjectSaveHandler,requireVoidReasonSaveHandler,personNameSaveHandler,retireSaveHandler,baseUnretireHandler,freeTextDatatype,longFreeTextDatatype,dateDatatype,regexValidatedTextDatatype,booleanDatatype,addressHierarchyLevelValidator,disabledI18nCache,orderEntry.OrderNumberGenerator,openmrsComponent1_8,openmrsProfile1_10,openmrsProfileWithoutTest1Module,listener2,listener1,org.springframework.context.annotation.internalConfigurationAnnotationProcessor,org.springframework.context.annotation.internalAutowiredAnnotationProcessor,org.springframework.context.annotation.internalRequiredAnnotationProcessor,org.springframework.context.annotation.internalCommonAnnotationProcessor,org.springframework.context.annotation.internalPersistenceAnnotationProcessor,serviceContext$child#0,proj001MappingResources,serviceContext$child#1,AddressCacheResetSupport,initializeFullAddressCacheTask,initializeFullAddressCacheTimerTask,addressHierarchyTimerFactory,serviceContext$child#2]; root of factory hierarchy
17-Jun-2025 22:11:30	INFO - Version.<clinit>(37) \|2025-06-17 22:11:30,219\| Hibernate Commons Annotations 3.2.0.Final
17-Jun-2025 22:11:30	INFO - Environment.<clinit>(603) \|2025-06-17 22:11:30,222\| Hibernate 3.6.5.Final-mod
17-Jun-2025 22:11:30	INFO - Environment.<clinit>(636) \|2025-06-17 22:11:30,223\| hibernate.properties not found
17-Jun-2025 22:11:30	INFO - Environment.buildBytecodeProvider(814) \|2025-06-17 22:11:30,225\| Bytecode provider name : javassist
17-Jun-2025 22:11:30	INFO - Environment.<clinit>(695) \|2025-06-17 22:11:30,290\| using JDK 1.4 java.sql.Timestamp handling
17-Jun-2025 22:11:30	INFO - Configuration.configure(2192) \|2025-06-17 22:11:30,316\| configuring from url: jar:file:/home/bamboo-agent/.m2/repository/org/openmrs/api/openmrs-api/1.10.6/openmrs-api-1.10.6.jar!/hibernate.cfg.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,346\| Reading mappings from resource : org/openmrs/api/db/hibernate/ActiveListItem.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,373\| Reading mappings from resource : org/openmrs/api/db/hibernate/ActiveListType.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,380\| Reading mappings from resource : org/openmrs/api/db/hibernate/ActiveListAllergy.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,398\| Reading mappings from resource : org/openmrs/api/db/hibernate/ActiveListProblem.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,409\| Reading mappings from resource : org/openmrs/api/db/hibernate/Concept.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,422\| Reading mappings from resource : org/openmrs/api/db/hibernate/ConceptAnswer.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,430\| Reading mappings from resource : org/openmrs/api/db/hibernate/ConceptDescription.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,445\| Reading mappings from resource : org/openmrs/api/db/hibernate/ConceptName.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,458\| Reading mappings from resource : org/openmrs/api/db/hibernate/ConceptNameTag.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,465\| Reading mappings from resource : org/openmrs/api/db/hibernate/ConceptClass.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,470\| Reading mappings from resource : org/openmrs/api/db/hibernate/ConceptDatatype.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,474\| Reading mappings from resource : org/openmrs/api/db/hibernate/ConceptProposal.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,479\| Reading mappings from resource : org/openmrs/api/db/hibernate/ConceptStateConversion.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,483\| Reading mappings from resource : org/openmrs/api/db/hibernate/ConceptSet.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,487\| Reading mappings from resource : org/openmrs/api/db/hibernate/ConceptMap.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,491\| Reading mappings from resource : org/openmrs/api/db/hibernate/ConceptWord.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,495\| Reading mappings from resource : org/openmrs/api/db/hibernate/ConceptStopWord.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,499\| Reading mappings from resource : org/openmrs/api/db/hibernate/ConceptSource.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,503\| Reading mappings from resource : org/openmrs/api/db/hibernate/ConceptSetDerived.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,507\| Reading mappings from resource : org/openmrs/api/db/hibernate/ConceptReferenceTerm.hbm.xml
17-Jun-2025 22:11:30	WARN - DTDEntityResolver.resolveEntity(73) \|2025-06-17 22:11:30,509\| recognized obsolete hibernate namespace http://hibernate.sourceforge.net/. Use namespace http://www.hibernate.org/dtd/ instead. Refer to Hibernate 3.6 Migration Guide!
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,512\| Reading mappings from resource : org/openmrs/api/db/hibernate/ConceptMapType.hbm.xml
17-Jun-2025 22:11:30	WARN - DTDEntityResolver.resolveEntity(73) \|2025-06-17 22:11:30,514\| recognized obsolete hibernate namespace http://hibernate.sourceforge.net/. Use namespace http://www.hibernate.org/dtd/ instead. Refer to Hibernate 3.6 Migration Guide!
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,517\| Reading mappings from resource : org/openmrs/api/db/hibernate/ConceptReferenceTermMap.hbm.xml
17-Jun-2025 22:11:30	WARN - DTDEntityResolver.resolveEntity(73) \|2025-06-17 22:11:30,518\| recognized obsolete hibernate namespace http://hibernate.sourceforge.net/. Use namespace http://www.hibernate.org/dtd/ instead. Refer to Hibernate 3.6 Migration Guide!
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,521\| Reading mappings from resource : org/openmrs/api/db/hibernate/Drug.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,525\| Reading mappings from resource : org/openmrs/api/db/hibernate/DrugIngredient.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,529\| Reading mappings from resource : org/openmrs/api/db/hibernate/DrugReferenceMap.hbm.xml
17-Jun-2025 22:11:30	WARN - DTDEntityResolver.resolveEntity(73) \|2025-06-17 22:11:30,530\| recognized obsolete hibernate namespace http://hibernate.sourceforge.net/. Use namespace http://www.hibernate.org/dtd/ instead. Refer to Hibernate 3.6 Migration Guide!
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,533\| Reading mappings from resource : org/openmrs/api/db/hibernate/Field.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,537\| Reading mappings from resource : org/openmrs/api/db/hibernate/FieldAnswer.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,540\| Reading mappings from resource : org/openmrs/api/db/hibernate/FieldType.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,544\| Reading mappings from resource : org/openmrs/api/db/hibernate/Form.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,548\| Reading mappings from resource : org/openmrs/api/db/hibernate/FormField.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,552\| Reading mappings from resource : org/openmrs/api/db/hibernate/FormResource.hbm.xml
17-Jun-2025 22:11:30	WARN - DTDEntityResolver.resolveEntity(73) \|2025-06-17 22:11:30,553\| recognized obsolete hibernate namespace http://hibernate.sourceforge.net/. Use namespace http://www.hibernate.org/dtd/ instead. Refer to Hibernate 3.6 Migration Guide!
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,556\| Reading mappings from resource : org/openmrs/api/db/hibernate/GlobalProperty.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,559\| Reading mappings from resource : org/openmrs/api/db/hibernate/Obs.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,566\| Reading mappings from resource : org/openmrs/api/db/hibernate/Person.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,570\| Reading mappings from resource : org/openmrs/api/db/hibernate/PersonAttribute.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,573\| Reading mappings from resource : org/openmrs/api/db/hibernate/PersonAttributeType.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,577\| Reading mappings from resource : org/openmrs/api/db/hibernate/PersonAddress.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,581\| Reading mappings from resource : org/openmrs/api/db/hibernate/PersonMergeLog.hbm.xml
17-Jun-2025 22:11:30	WARN - DTDEntityResolver.resolveEntity(73) \|2025-06-17 22:11:30,582\| recognized obsolete hibernate namespace http://hibernate.sourceforge.net/. Use namespace http://www.hibernate.org/dtd/ instead. Refer to Hibernate 3.6 Migration Guide!
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,585\| Reading mappings from resource : org/openmrs/api/db/hibernate/PersonName.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,589\| Reading mappings from resource : org/openmrs/api/db/hibernate/User.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,593\| Reading mappings from resource : org/openmrs/api/db/hibernate/LoginCredential.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,597\| Reading mappings from resource : org/openmrs/api/db/hibernate/Privilege.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,600\| Reading mappings from resource : org/openmrs/api/db/hibernate/Role.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,604\| Reading mappings from resource : org/openmrs/api/db/hibernate/Patient.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,607\| Reading mappings from resource : org/openmrs/api/db/hibernate/PatientIdentifier.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,611\| Reading mappings from resource : org/openmrs/api/db/hibernate/PatientIdentifierType.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,615\| Reading mappings from resource : org/openmrs/api/db/hibernate/Relationship.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,618\| Reading mappings from resource : org/openmrs/api/db/hibernate/RelationshipType.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,622\| Reading mappings from resource : org/openmrs/api/db/hibernate/Order.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,626\| Reading mappings from resource : org/openmrs/api/db/hibernate/CareSetting.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,630\| Reading mappings from resource : org/openmrs/api/db/hibernate/OrderType.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,633\| Reading mappings from resource : org/openmrs/api/db/hibernate/Location.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,637\| Reading mappings from resource : org/openmrs/api/db/hibernate/LocationTag.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,641\| Reading mappings from resource : org/openmrs/api/db/hibernate/LocationAttributeType.hbm.xml
17-Jun-2025 22:11:30	WARN - DTDEntityResolver.resolveEntity(73) \|2025-06-17 22:11:30,642\| recognized obsolete hibernate namespace http://hibernate.sourceforge.net/. Use namespace http://www.hibernate.org/dtd/ instead. Refer to Hibernate 3.6 Migration Guide!
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,645\| Reading mappings from resource : org/openmrs/api/db/hibernate/LocationAttribute.hbm.xml
17-Jun-2025 22:11:30	WARN - DTDEntityResolver.resolveEntity(73) \|2025-06-17 22:11:30,646\| recognized obsolete hibernate namespace http://hibernate.sourceforge.net/. Use namespace http://www.hibernate.org/dtd/ instead. Refer to Hibernate 3.6 Migration Guide!
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,648\| Reading mappings from resource : org/openmrs/api/db/hibernate/EncounterType.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,652\| Reading mappings from resource : org/openmrs/api/db/hibernate/Encounter.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,656\| Reading mappings from resource : org/openmrs/api/db/hibernate/EncounterProvider.hbm.xml
17-Jun-2025 22:11:30	WARN - DTDEntityResolver.resolveEntity(73) \|2025-06-17 22:11:30,657\| recognized obsolete hibernate namespace http://hibernate.sourceforge.net/. Use namespace http://www.hibernate.org/dtd/ instead. Refer to Hibernate 3.6 Migration Guide!
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,660\| Reading mappings from resource : org/openmrs/api/db/hibernate/EncounterRole.hbm.xml
17-Jun-2025 22:11:30	WARN - DTDEntityResolver.resolveEntity(73) \|2025-06-17 22:11:30,661\| recognized obsolete hibernate namespace http://hibernate.sourceforge.net/. Use namespace http://www.hibernate.org/dtd/ instead. Refer to Hibernate 3.6 Migration Guide!
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,663\| Reading mappings from resource : org/openmrs/api/db/hibernate/Program.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,667\| Reading mappings from resource : org/openmrs/api/db/hibernate/ProgramWorkflow.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,670\| Reading mappings from resource : org/openmrs/api/db/hibernate/ProgramWorkflowState.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,674\| Reading mappings from resource : org/openmrs/api/db/hibernate/PatientProgram.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,678\| Reading mappings from resource : org/openmrs/api/db/hibernate/PatientState.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,681\| Reading mappings from resource : org/openmrs/api/db/hibernate/Cohort.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,685\| Reading mappings from resource : org/openmrs/api/db/hibernate/SerializedObject.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,688\| Reading mappings from resource : org/openmrs/api/db/hibernate/OrderFrequency.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,691\| Reading mappings from resource : org/openmrs/hl7/db/hibernate/HL7Source.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,695\| Reading mappings from resource : org/openmrs/hl7/db/hibernate/HL7InQueue.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,698\| Reading mappings from resource : org/openmrs/hl7/db/hibernate/HL7InArchive.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,701\| Reading mappings from resource : org/openmrs/hl7/db/hibernate/HL7InError.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,705\| Reading mappings from resource : org/openmrs/reporting/db/hibernate/ReportObject.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,708\| Reading mappings from resource : org/openmrs/reporting/db/hibernate/ReportSchemaXml.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,712\| Reading mappings from resource : org/openmrs/notification/db/hibernate/Template.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,715\| Reading mappings from resource : org/openmrs/notification/db/hibernate/Alert.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,720\| Reading mappings from resource : org/openmrs/notification/db/hibernate/AlertRecipient.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,723\| Reading mappings from resource : org/openmrs/scheduler/db/hibernate/TaskDefinition.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,727\| Reading mappings from resource : org/openmrs/api/db/hibernate/VisitType.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,730\| Reading mappings from resource : org/openmrs/api/db/hibernate/VisitAttributeType.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,734\| Reading mappings from resource : org/openmrs/api/db/hibernate/VisitAttribute.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,737\| Reading mappings from resource : org/openmrs/api/db/hibernate/Visit.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,741\| Reading mappings from resource : org/openmrs/api/db/hibernate/Provider.hbm.xml
17-Jun-2025 22:11:30	WARN - DTDEntityResolver.resolveEntity(73) \|2025-06-17 22:11:30,742\| recognized obsolete hibernate namespace http://hibernate.sourceforge.net/. Use namespace http://www.hibernate.org/dtd/ instead. Refer to Hibernate 3.6 Migration Guide!
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,744\| Reading mappings from resource : org/openmrs/api/db/hibernate/ProviderAttribute.hbm.xml
17-Jun-2025 22:11:30	WARN - DTDEntityResolver.resolveEntity(73) \|2025-06-17 22:11:30,745\| recognized obsolete hibernate namespace http://hibernate.sourceforge.net/. Use namespace http://www.hibernate.org/dtd/ instead. Refer to Hibernate 3.6 Migration Guide!
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,748\| Reading mappings from resource : org/openmrs/api/db/hibernate/ProviderAttributeType.hbm.xml
17-Jun-2025 22:11:30	WARN - DTDEntityResolver.resolveEntity(73) \|2025-06-17 22:11:30,749\| recognized obsolete hibernate namespace http://hibernate.sourceforge.net/. Use namespace http://www.hibernate.org/dtd/ instead. Refer to Hibernate 3.6 Migration Guide!
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,751\| Reading mappings from resource : org/openmrs/api/db/hibernate/ClobDatatypeStorage.hbm.xml
17-Jun-2025 22:11:30	INFO - Configuration.doConfigure(2297) \|2025-06-17 22:11:30,754\| Configured SessionFactory: null
17-Jun-2025 22:11:30	INFO - Configuration.configure(2192) \|2025-06-17 22:11:30,755\| configuring from url: file:/home/bamboo-agent/bamboo-agent/xml-data/build-dir/ADDRHIER-ADDRHIER-JOB1/omod/target/test-classes/test-hibernate.cfg.xml
17-Jun-2025 22:11:30	WARN - DTDEntityResolver.resolveEntity(73) \|2025-06-17 22:11:30,755\| recognized obsolete hibernate namespace http://hibernate.sourceforge.net/. Use namespace http://www.hibernate.org/dtd/ instead. Refer to Hibernate 3.6 Migration Guide!
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,756\| Reading mappings from resource : AddressHierarchyEntry.hbm.xml
17-Jun-2025 22:11:30	WARN - DTDEntityResolver.resolveEntity(73) \|2025-06-17 22:11:30,757\| recognized obsolete hibernate namespace http://hibernate.sourceforge.net/. Use namespace http://www.hibernate.org/dtd/ instead. Refer to Hibernate 3.6 Migration Guide!
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,759\| Reading mappings from resource : AddressHierarchyLevel.hbm.xml
17-Jun-2025 22:11:30	WARN - DTDEntityResolver.resolveEntity(73) \|2025-06-17 22:11:30,760\| recognized obsolete hibernate namespace http://hibernate.sourceforge.net/. Use namespace http://www.hibernate.org/dtd/ instead. Refer to Hibernate 3.6 Migration Guide!
17-Jun-2025 22:11:30	INFO - Configuration.addResource(789) \|2025-06-17 22:11:30,763\| Reading mappings from resource : AddressToEntryMap.hbm.xml
17-Jun-2025 22:11:30	WARN - DTDEntityResolver.resolveEntity(73) \|2025-06-17 22:11:30,764\| recognized obsolete hibernate namespace http://hibernate.sourceforge.net/. Use namespace http://www.hibernate.org/dtd/ instead. Refer to Hibernate 3.6 Migration Guide!
17-Jun-2025 22:11:30	INFO - Configuration.doConfigure(2297) \|2025-06-17 22:11:30,766\| Configured SessionFactory: null
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,789\| Mapping class: org.openmrs.activelist.ActiveListItem -> active_list
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,801\| Mapping class: org.openmrs.activelist.ActiveListType -> active_list_type
17-Jun-2025 22:11:30	INFO - HbmBinder.bindJoinedSubclass(945) \|2025-06-17 22:11:30,807\| Mapping joined-subclass: org.openmrs.activelist.Allergy -> active_list_allergy
17-Jun-2025 22:11:30	INFO - HbmBinder.bindJoinedSubclass(945) \|2025-06-17 22:11:30,808\| Mapping joined-subclass: org.openmrs.activelist.Problem -> active_list_problem
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,808\| Mapping class: org.openmrs.Concept -> concept
17-Jun-2025 22:11:30	INFO - HbmBinder.bindJoinedSubclass(945) \|2025-06-17 22:11:30,810\| Mapping joined-subclass: org.openmrs.ConceptNumeric -> concept_numeric
17-Jun-2025 22:11:30	INFO - HbmBinder.bindJoinedSubclass(945) \|2025-06-17 22:11:30,811\| Mapping joined-subclass: org.openmrs.ConceptComplex -> concept_complex
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,812\| Mapping class: org.openmrs.ConceptAnswer -> concept_answer
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,812\| Mapping class: org.openmrs.ConceptDescription -> concept_description
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,813\| Mapping class: org.openmrs.ConceptName -> concept_name
17-Jun-2025 22:11:30	INFO - HbmBinder.bindCollection(1522) \|2025-06-17 22:11:30,814\| Mapping collection: org.openmrs.ConceptName.tags -> concept_name_tag_map
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,814\| Mapping class: org.openmrs.ConceptNameTag -> concept_name_tag
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,815\| Mapping class: org.openmrs.ConceptClass -> concept_class
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,816\| Mapping class: org.openmrs.ConceptDatatype -> concept_datatype
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,816\| Mapping class: org.openmrs.ConceptProposal -> concept_proposal
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,817\| Mapping class: org.openmrs.ConceptStateConversion -> concept_state_conversion
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,818\| Mapping class: org.openmrs.ConceptSet -> concept_set
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,818\| Mapping class: org.openmrs.ConceptMap -> concept_reference_map
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,819\| Mapping class: org.openmrs.ConceptWord -> concept_word
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,820\| Mapping class: org.openmrs.ConceptStopWord -> concept_stop_word
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,820\| Mapping class: org.openmrs.ConceptSource -> concept_reference_source
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,821\| Mapping class: org.openmrs.ConceptSetDerived -> concept_set_derived
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,823\| Mapping class: org.openmrs.ConceptReferenceTerm -> concept_reference_term
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,824\| Mapping class: org.openmrs.ConceptMapType -> concept_map_type
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,824\| Mapping class: org.openmrs.ConceptReferenceTermMap -> concept_reference_term_map
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,825\| Mapping class: org.openmrs.Drug -> drug
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,826\| Mapping class: org.openmrs.DrugIngredient -> drug_ingredient
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,827\| Mapping class: org.openmrs.DrugReferenceMap -> drug_reference_map
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,828\| Mapping class: org.openmrs.Field -> field
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,828\| Mapping class: org.openmrs.FieldAnswer -> field_answer
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,829\| Mapping class: org.openmrs.FieldType -> field_type
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,830\| Mapping class: org.openmrs.Form -> form
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,831\| Mapping class: org.openmrs.FormField -> form_field
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,831\| Mapping class: org.openmrs.FormResource -> form_resource
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,832\| Mapping class: org.openmrs.GlobalProperty -> global_property
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,833\| Mapping class: org.openmrs.Obs -> obs
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,834\| Mapping class: org.openmrs.Person -> person
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,835\| Mapping class: org.openmrs.PersonAttribute -> person_attribute
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,835\| Mapping class: org.openmrs.PersonAttributeType -> person_attribute_type
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,836\| Mapping class: org.openmrs.PersonAddress -> person_address
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,837\| Mapping class: org.openmrs.person.PersonMergeLog -> person_merge_log
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,838\| Mapping class: org.openmrs.PersonName -> person_name
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,839\| Mapping class: org.openmrs.User -> users
17-Jun-2025 22:11:30	INFO - HbmBinder.bindCollection(1522) \|2025-06-17 22:11:30,839\| Mapping collection: org.openmrs.User.roles -> user_role
17-Jun-2025 22:11:30	INFO - HbmBinder.bindCollection(1522) \|2025-06-17 22:11:30,840\| Mapping collection: org.openmrs.User.userProperties -> user_property
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,841\| Mapping class: org.openmrs.api.db.LoginCredential -> users
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,841\| Mapping class: org.openmrs.Privilege -> privilege
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,842\| Mapping class: org.openmrs.Role -> role
17-Jun-2025 22:11:30	INFO - HbmBinder.bindCollection(1522) \|2025-06-17 22:11:30,842\| Mapping collection: org.openmrs.Role.inheritedRoles -> role_role
17-Jun-2025 22:11:30	INFO - HbmBinder.bindCollection(1522) \|2025-06-17 22:11:30,843\| Mapping collection: org.openmrs.Role.childRoles -> role_role
17-Jun-2025 22:11:30	INFO - HbmBinder.bindCollection(1522) \|2025-06-17 22:11:30,843\| Mapping collection: org.openmrs.Role.privileges -> role_privilege
17-Jun-2025 22:11:30	INFO - HbmBinder.bindJoinedSubclass(945) \|2025-06-17 22:11:30,843\| Mapping joined-subclass: org.openmrs.Patient -> patient
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,844\| Mapping class: org.openmrs.PatientIdentifier -> patient_identifier
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,845\| Mapping class: org.openmrs.PatientIdentifierType -> patient_identifier_type
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,845\| Mapping class: org.openmrs.Relationship -> relationship
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,846\| Mapping class: org.openmrs.RelationshipType -> relationship_type
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,847\| Mapping class: org.openmrs.Order -> orders
17-Jun-2025 22:11:30	INFO - HbmBinder.bindJoinedSubclass(945) \|2025-06-17 22:11:30,848\| Mapping joined-subclass: org.openmrs.DrugOrder -> drug_order
17-Jun-2025 22:11:30	INFO - HbmBinder.bindJoinedSubclass(945) \|2025-06-17 22:11:30,848\| Mapping joined-subclass: org.openmrs.TestOrder -> test_order
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,849\| Mapping class: org.openmrs.CareSetting -> care_setting
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,849\| Mapping class: org.openmrs.OrderType -> order_type
17-Jun-2025 22:11:30	INFO - HbmBinder.bindCollection(1522) \|2025-06-17 22:11:30,850\| Mapping collection: org.openmrs.OrderType.conceptClasses -> order_type_class_map
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,851\| Mapping class: org.openmrs.Location -> location
17-Jun-2025 22:11:30	INFO - HbmBinder.bindCollection(1522) \|2025-06-17 22:11:30,851\| Mapping collection: org.openmrs.Location.tags -> location_tag_map
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,852\| Mapping class: org.openmrs.LocationTag -> location_tag
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,852\| Mapping class: org.openmrs.LocationAttributeType -> location_attribute_type
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,853\| Mapping class: org.openmrs.LocationAttribute -> location_attribute
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,853\| Mapping class: org.openmrs.EncounterType -> encounter_type
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,854\| Mapping class: org.openmrs.Encounter -> encounter
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,855\| Mapping class: org.openmrs.EncounterProvider -> encounter_provider
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,856\| Mapping class: org.openmrs.EncounterRole -> encounter_role
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,857\| Mapping class: org.openmrs.Program -> program
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,857\| Mapping class: org.openmrs.ProgramWorkflow -> program_workflow
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,858\| Mapping class: org.openmrs.ProgramWorkflowState -> program_workflow_state
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,859\| Mapping class: org.openmrs.PatientProgram -> patient_program
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,859\| Mapping class: org.openmrs.PatientState -> patient_state
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,860\| Mapping class: org.openmrs.Cohort -> cohort
17-Jun-2025 22:11:30	INFO - HbmBinder.bindCollection(1522) \|2025-06-17 22:11:30,860\| Mapping collection: org.openmrs.Cohort.memberIds -> cohort_member
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,861\| Mapping class: org.openmrs.api.db.SerializedObject -> serialized_object
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,861\| Mapping class: org.openmrs.OrderFrequency -> order_frequency
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,862\| Mapping class: org.openmrs.hl7.HL7Source -> hl7_source
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,863\| Mapping class: org.openmrs.hl7.HL7InQueue -> hl7_in_queue
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,863\| Mapping class: org.openmrs.hl7.HL7InArchive -> hl7_in_archive
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,864\| Mapping class: org.openmrs.hl7.HL7InError -> hl7_in_error
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,864\| Mapping class: org.openmrs.reporting.ReportObjectWrapper -> report_object
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,865\| Mapping class: org.openmrs.report.ReportSchemaXml -> report_schema_xml
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,865\| Mapping class: org.openmrs.notification.Template -> notification_template
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,866\| Mapping class: org.openmrs.notification.Alert -> notification_alert
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,866\| Mapping class: org.openmrs.notification.AlertRecipient -> notification_alert_recipient
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,867\| Mapping class: org.openmrs.scheduler.TaskDefinition -> scheduler_task_config
17-Jun-2025 22:11:30	INFO - HbmBinder.bindCollection(1522) \|2025-06-17 22:11:30,867\| Mapping collection: org.openmrs.scheduler.TaskDefinition.properties -> scheduler_task_config_property
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,868\| Mapping class: org.openmrs.VisitType -> visit_type
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,868\| Mapping class: org.openmrs.VisitAttributeType -> visit_attribute_type
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,869\| Mapping class: org.openmrs.VisitAttribute -> visit_attribute
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,869\| Mapping class: org.openmrs.Visit -> visit
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,870\| Mapping class: org.openmrs.Provider -> provider
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,870\| Mapping class: org.openmrs.ProviderAttribute -> provider_attribute
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,871\| Mapping class: org.openmrs.ProviderAttributeType -> provider_attribute_type
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,872\| Mapping class: org.openmrs.api.db.ClobDatatypeStorage -> clob_datatype_storage
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,872\| Mapping class: org.openmrs.module.addresshierarchy.AddressHierarchyEntry -> address_hierarchy_entry
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,873\| Mapping class: org.openmrs.module.addresshierarchy.AddressHierarchyLevel -> address_hierarchy_level
17-Jun-2025 22:11:30	INFO - HbmBinder.bindRootPersistentClassCommonValues(353) \|2025-06-17 22:11:30,873\| Mapping class: org.openmrs.module.addresshierarchy.AddressToEntryMap -> address_hierarchy_address_to_entry_map
17-Jun-2025 22:11:30	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:30,875\| Mapping collection: org.openmrs.Concept.names -> concept_name
17-Jun-2025 22:11:30	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:30,875\| Mapping collection: org.openmrs.Concept.descriptions -> concept_description
17-Jun-2025 22:11:30	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:30,875\| Mapping collection: org.openmrs.Concept.answers -> concept_answer
17-Jun-2025 22:11:30	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:30,876\| Mapping collection: org.openmrs.Concept.conceptSets -> concept_set
17-Jun-2025 22:11:30	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:30,876\| Mapping collection: org.openmrs.Concept.conceptMappings -> concept_reference_map
17-Jun-2025 22:11:30	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:30,877\| Mapping collection: org.openmrs.ConceptReferenceTerm.conceptReferenceTermMaps -> concept_reference_term_map
17-Jun-2025 22:11:30	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:30,877\| Mapping collection: org.openmrs.Drug.drugReferenceMaps -> drug_reference_map
17-Jun-2025 22:11:30	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:30,877\| Mapping collection: org.openmrs.Form.formFields -> form_field
17-Jun-2025 22:11:30	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:30,878\| Mapping collection: org.openmrs.Obs.groupMembers -> obs
17-Jun-2025 22:11:30	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:30,878\| Mapping collection: org.openmrs.Person.addresses -> person_address
17-Jun-2025 22:11:30	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:30,879\| Mapping collection: org.openmrs.Person.names -> person_name
17-Jun-2025 22:11:30	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:30,879\| Mapping collection: org.openmrs.Person.attributes -> person_attribute
17-Jun-2025 22:11:30	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:30,879\| Mapping collection: org.openmrs.Patient.identifiers -> patient_identifier
17-Jun-2025 22:11:30	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:30,880\| Mapping collection: org.openmrs.Location.childLocations -> location
17-Jun-2025 22:11:30	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:30,880\| Mapping collection: org.openmrs.Location.attributes -> location_attribute
17-Jun-2025 22:11:30	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:30,881\| Mapping collection: org.openmrs.Encounter.orders -> orders
17-Jun-2025 22:11:30	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:30,881\| Mapping collection: org.openmrs.Encounter.obs -> obs
17-Jun-2025 22:11:30	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:30,881\| Mapping collection: org.openmrs.Encounter.encounterProviders -> encounter_provider
17-Jun-2025 22:11:30	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:30,882\| Mapping collection: org.openmrs.Program.allWorkflows -> program_workflow
17-Jun-2025 22:11:30	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:30,882\| Mapping collection: org.openmrs.ProgramWorkflow.states -> program_workflow_state
17-Jun-2025 22:11:30	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:30,882\| Mapping collection: org.openmrs.PatientProgram.states -> patient_state
17-Jun-2025 22:11:30	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:30,883\| Mapping collection: org.openmrs.notification.Alert.recipients -> notification_alert_recipient
17-Jun-2025 22:11:30	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:30,883\| Mapping collection: org.openmrs.Visit.attributes -> visit_attribute
17-Jun-2025 22:11:30	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:30,884\| Mapping collection: org.openmrs.Visit.encounters -> encounter
17-Jun-2025 22:11:30	INFO - HbmBinder.bindCollectionSecondPass(2508) \|2025-06-17 22:11:30,884\| Mapping collection: org.openmrs.Provider.attributes -> provider_attribute
17-Jun-2025 22:11:30	INFO - Configuration.applyHibernateValidatorLegacyConstraintsOnDDL(1676) \|2025-06-17 22:11:30,888\| Hibernate Validator not found: ignoring
17-Jun-2025 22:11:30	INFO - LocalSessionFactoryBean.buildSessionFactory(777) \|2025-06-17 22:11:30,889\| Building new Hibernate SessionFactory
17-Jun-2025 22:11:30	INFO - HibernateSearchEventListenerRegister.enableHibernateSearch(75) \|2025-06-17 22:11:30,892\| Unable to find org.hibernate.search.event.FullTextIndexEventListener on the classpath. Hibernate Search is not enabled.
17-Jun-2025 22:11:30	INFO - ConnectionProviderFactory.initializeConnectionProviderFromConfig(173) \|2025-06-17 22:11:30,914\| Initializing connection provider: org.hibernate.connection.C3P0ConnectionProvider
17-Jun-2025 22:11:30	INFO - C3P0ConnectionProvider.configure(103) \|2025-06-17 22:11:30,915\| C3P0 using driver: org.h2.Driver at URL: jdbc:h2:mem:openmrs;DB_CLOSE_DELAY=30
17-Jun-2025 22:11:30	INFO - C3P0ConnectionProvider.configure(104) \|2025-06-17 22:11:30,915\| Connection properties: {user=sa, password=****, release_mode=after_transaction}
17-Jun-2025 22:11:30	INFO - C3P0ConnectionProvider.configure(107) \|2025-06-17 22:11:30,916\| autocommit mode: false
17-Jun-2025 22:11:30	INFO - MLog.<clinit>(80) \|2025-06-17 22:11:30,931\| MLog clients using log4j logging.
17-Jun-2025 22:11:31	INFO - C3P0Registry.banner(204) \|2025-06-17 22:11:31,005\| Initializing c3p0-0.9.1 [built 16-January-2007 14:46:42; debug? true; trace: 10]
17-Jun-2025 22:11:31	INFO - AbstractPoolBackedDataSource.getPoolManager(462) \|2025-06-17 22:11:31,043\| Initializing c3p0 pool... com.mchange.v2.c3p0.PoolBackedDataSource@1c631185 [ connectionPoolDataSource -> com.mchange.v2.c3p0.WrapperConnectionPoolDataSource@767cd5d3 [ acquireIncrement -> 1, acquireRetryAttempts -> 5, acquireRetryDelay -> 1000, autoCommitOnClose -> false, automaticTestTable -> null, breakAfterAcquireFailure -> false, checkoutTimeout -> 0, connectionCustomizerClassName -> null, connectionTesterClassName -> com.mchange.v2.c3p0.impl.DefaultConnectionTester, debugUnreturnedConnectionStackTraces -> false, factoryClassLocation -> null, forceIgnoreUnresolvedTransactions -> false, identityToken -> z8kfltbbzi14ash0r4ks\|1cb9ef52, idleConnectionTestPeriod -> 3000, initialPoolSize -> 0, maxAdministrativeTaskTime -> 0, maxConnectionAge -> 0, maxIdleTime -> 100, maxIdleTimeExcessConnections -> 0, maxPoolSize -> 50, maxStatements -> 0, maxStatementsPerConnection -> 0, minPoolSize -> 0, nestedDataSource -> com.mchange.v2.c3p0.DriverManagerDataSource@5b151f1b [ description -> null, driverClass -> null, factoryClassLocation -> null, identityToken -> z8kfltbbzi14ash0r4ks\|6e02721d, jdbcUrl -> jdbc:h2:mem:openmrs;DB_CLOSE_DELAY=30, properties -> {user=****, password=****, release_mode=after_transaction} ], preferredTestQuery -> null, propertyCycle -> 0, testConnectionOnCheckin -> false, testConnectionOnCheckout -> false, unreturnedConnectionTimeout -> 0, usesTraditionalReflectiveProxies -> false; userOverrides: {} ], dataSourceName -> null, factoryClassLocation -> null, identityToken -> z8kfltbbzi14ash0r4ks\|93501be, numHelperThreads -> 3 ]
17-Jun-2025 22:11:31	INFO - Dialect.<init>(135) \|2025-06-17 22:11:31,180\| Using dialect: org.hibernate.dialect.H2Dialect
17-Jun-2025 22:11:31	INFO - JdbcSupportLoader.useContextualLobCreation(108) \|2025-06-17 22:11:31,187\| Disabling contextual LOB creation as createClob() method threw error : java.lang.reflect.InvocationTargetException
17-Jun-2025 22:11:31	INFO - SettingsFactory.buildSettings(126) \|2025-06-17 22:11:31,187\| Database ->
17-Jun-2025 22:11:31	name : H2
17-Jun-2025 22:11:31	version : 1.4.187 (2015-04-10)
17-Jun-2025 22:11:31	major : 1
17-Jun-2025 22:11:31	minor : 4
17-Jun-2025 22:11:31	INFO - SettingsFactory.buildSettings(132) \|2025-06-17 22:11:31,187\| Driver ->
17-Jun-2025 22:11:31	name : H2 JDBC Driver
17-Jun-2025 22:11:31	version : 1.4.187 (2015-04-10)
17-Jun-2025 22:11:31	major : 1
17-Jun-2025 22:11:31	minor : 4
17-Jun-2025 22:11:31	INFO - TransactionFactoryFactory.buildTransactionFactory(65) \|2025-06-17 22:11:31,189\| Transaction strategy: org.springframework.orm.hibernate3.SpringTransactionFactory
17-Jun-2025 22:11:31	INFO - TransactionManagerLookupFactory.getTransactionManagerLookup(83) \|2025-06-17 22:11:31,190\| No TransactionManagerLookup configured (in JTA environment, use of read-write or transactional second-level cache is not recommended)
17-Jun-2025 22:11:31	INFO - SettingsFactory.buildSettings(179) \|2025-06-17 22:11:31,190\| Automatic flush during beforeCompletion(): disabled
17-Jun-2025 22:11:31	INFO - SettingsFactory.buildSettings(183) \|2025-06-17 22:11:31,191\| Automatic session close at end of transaction: disabled
17-Jun-2025 22:11:31	INFO - SettingsFactory.buildSettings(190) \|2025-06-17 22:11:31,191\| JDBC batch size: 15
17-Jun-2025 22:11:31	INFO - SettingsFactory.buildSettings(193) \|2025-06-17 22:11:31,192\| JDBC batch updates for versioned data: disabled
17-Jun-2025 22:11:31	INFO - SettingsFactory.buildSettings(198) \|2025-06-17 22:11:31,192\| Scrollable result sets: enabled
17-Jun-2025 22:11:31	INFO - SettingsFactory.buildSettings(206) \|2025-06-17 22:11:31,193\| JDBC3 getGeneratedKeys(): enabled
17-Jun-2025 22:11:31	INFO - SettingsFactory.buildSettings(214) \|2025-06-17 22:11:31,193\| Connection release mode: after_transaction
17-Jun-2025 22:11:31	INFO - SettingsFactory.buildSettings(241) \|2025-06-17 22:11:31,194\| Default batch fetch size: 1
17-Jun-2025 22:11:31	INFO - SettingsFactory.buildSettings(245) \|2025-06-17 22:11:31,194\| Generate SQL with comments: disabled
17-Jun-2025 22:11:31	INFO - SettingsFactory.buildSettings(249) \|2025-06-17 22:11:31,194\| Order SQL updates by primary key: disabled
17-Jun-2025 22:11:31	INFO - SettingsFactory.buildSettings(253) \|2025-06-17 22:11:31,195\| Order SQL inserts for batching: disabled
17-Jun-2025 22:11:31	INFO - SettingsFactory.createQueryTranslatorFactory(431) \|2025-06-17 22:11:31,195\| Query translator: org.hibernate.hql.ast.ASTQueryTranslatorFactory
17-Jun-2025 22:11:31	INFO - ASTQueryTranslatorFactory.<init>(47) \|2025-06-17 22:11:31,196\| Using ASTQueryTranslatorFactory
17-Jun-2025 22:11:31	INFO - SettingsFactory.buildSettings(261) \|2025-06-17 22:11:31,196\| Query language substitutions: {}
17-Jun-2025 22:11:31	INFO - SettingsFactory.buildSettings(266) \|2025-06-17 22:11:31,197\| JPA-QL strict compliance: disabled
17-Jun-2025 22:11:31	INFO - SettingsFactory.buildSettings(271) \|2025-06-17 22:11:31,197\| Second-level cache: enabled
17-Jun-2025 22:11:31	INFO - SettingsFactory.buildSettings(275) \|2025-06-17 22:11:31,198\| Query cache: disabled
17-Jun-2025 22:11:31	INFO - SettingsFactory.createRegionFactory(406) \|2025-06-17 22:11:31,198\| Cache region factory : org.hibernate.cache.impl.bridge.RegionFactoryCacheProviderBridge
17-Jun-2025 22:11:31	INFO - RegionFactoryCacheProviderBridge.<init>(61) \|2025-06-17 22:11:31,200\| Cache provider: org.hibernate.cache.EhCacheProvider
17-Jun-2025 22:11:31	INFO - SettingsFactory.buildSettings(285) \|2025-06-17 22:11:31,201\| Optimize cache for minimal puts: disabled
17-Jun-2025 22:11:31	INFO - SettingsFactory.buildSettings(294) \|2025-06-17 22:11:31,202\| Structured second-level cache entries: disabled
17-Jun-2025 22:11:31	INFO - SettingsFactory.buildSettings(323) \|2025-06-17 22:11:31,204\| Statistics: enabled
17-Jun-2025 22:11:31	INFO - SettingsFactory.buildSettings(327) \|2025-06-17 22:11:31,204\| Deleted entity synthetic identifier rollback: disabled
17-Jun-2025 22:11:31	INFO - SettingsFactory.buildSettings(343) \|2025-06-17 22:11:31,205\| Default entity-mode: pojo
17-Jun-2025 22:11:31	INFO - SettingsFactory.buildSettings(347) \|2025-06-17 22:11:31,205\| Named query checking : enabled
17-Jun-2025 22:11:31	INFO - SettingsFactory.buildSettings(351) \|2025-06-17 22:11:31,206\| Check Nullability in Core (should be disabled when Bean Validation is on): disabled
17-Jun-2025 22:11:31	INFO - SessionFactoryImpl.<init>(202) \|2025-06-17 22:11:31,214\| building session factory
17-Jun-2025 22:11:31	INFO - BasicTypeRegistry.register(150) \|2025-06-17 22:11:31,217\| Type registration [materialized_blob] overrides previous : org.hibernate.type.MaterializedBlobType@1e545821
17-Jun-2025 22:11:31	INFO - BasicTypeRegistry.register(150) \|2025-06-17 22:11:31,217\| Type registration [characters_clob] overrides previous : org.hibernate.type.PrimitiveCharacterArrayClobType@76a7fcbd
17-Jun-2025 22:11:31	INFO - BasicTypeRegistry.register(150) \|2025-06-17 22:11:31,217\| Type registration [wrapper_materialized_blob] overrides previous : org.hibernate.type.WrappedMaterializedBlobType@7f977fba
17-Jun-2025 22:11:31	INFO - BasicTypeRegistry.register(150) \|2025-06-17 22:11:31,218\| Type registration [wrapper_characters_clob] overrides previous : org.hibernate.type.CharacterArrayClobType@d653e41
17-Jun-2025 22:11:31	INFO - BasicTypeRegistry.register(150) \|2025-06-17 22:11:31,218\| Type registration [blob] overrides previous : org.hibernate.type.BlobType@7f5614f9
17-Jun-2025 22:11:31	INFO - BasicTypeRegistry.register(150) \|2025-06-17 22:11:31,219\| Type registration [java.sql.Blob] overrides previous : org.hibernate.type.BlobType@7f5614f9
17-Jun-2025 22:11:31	INFO - BasicTypeRegistry.register(150) \|2025-06-17 22:11:31,219\| Type registration [clob] overrides previous : org.hibernate.type.ClobType@110e9982
17-Jun-2025 22:11:31	INFO - BasicTypeRegistry.register(150) \|2025-06-17 22:11:31,219\| Type registration [java.sql.Clob] overrides previous : org.hibernate.type.ClobType@110e9982
17-Jun-2025 22:11:31	INFO - BasicTypeRegistry.register(150) \|2025-06-17 22:11:31,220\| Type registration [materialized_clob] overrides previous : org.hibernate.type.MaterializedClobType@67f9cb52
17-Jun-2025 22:11:31	WARN - EhCacheProvider.buildCache(93) \|2025-06-17 22:11:31,462\| Could not find configuration [org.openmrs.ConceptStopWord]; using defaults.
17-Jun-2025 22:11:31	WARN - EhCacheProvider.buildCache(93) \|2025-06-17 22:11:31,568\| Could not find configuration [org.openmrs.Person]; using defaults.
17-Jun-2025 22:11:31	WARN - EhCacheProvider.buildCache(93) \|2025-06-17 22:11:31,582\| Could not find configuration [org.openmrs.FormResource]; using defaults.
17-Jun-2025 22:11:31	WARN - EhCacheProvider.buildCache(93) \|2025-06-17 22:11:31,744\| Could not find configuration [org.openmrs.Role.childRoles]; using defaults.
17-Jun-2025 22:11:31	WARN - EhCacheProvider.buildCache(93) \|2025-06-17 22:11:31,754\| Could not find configuration [org.openmrs.Role.inheritedRoles]; using defaults.
17-Jun-2025 22:11:31	WARN - EhCacheProvider.buildCache(93) \|2025-06-17 22:11:31,762\| Could not find configuration [org.openmrs.Role.privileges]; using defaults.
17-Jun-2025 22:11:31	INFO - SessionFactoryObjectFactory.addInstance(105) \|2025-06-17 22:11:31,970\| Not binding factory to JNDI, no JNDI name configured
17-Jun-2025 22:11:31	INFO - SchemaExport.execute(234) \|2025-06-17 22:11:31,991\| Running hbm2ddl schema export
17-Jun-2025 22:11:31	INFO - SchemaExport.execute(262) \|2025-06-17 22:11:31,991\| exporting generated schema to database
17-Jun-2025 22:11:32	INFO - SchemaExport.execute(281) \|2025-06-17 22:11:32,587\| schema export complete
17-Jun-2025 22:11:32	INFO - HapiLogImpl.info(78) \|2025-06-17 22:11:32,830\| org.openmrs.hl7.handler.ORUR01Handler registered to handle ORU^R01 messages
17-Jun-2025 22:11:32	INFO - HapiLogImpl.info(78) \|2025-06-17 22:11:32,833\| org.openmrs.hl7.handler.ADTA28Handler registered to handle ADT^A28 messages
17-Jun-2025 22:11:33	INFO - ExecutorConfigurationSupport.initialize(115) \|2025-06-17 22:11:33,119\| Initializing ExecutorService 'addressHierarchyTimerFactory'
17-Jun-2025 22:11:33	INFO - TransactionalTestExecutionListener.startNewTransaction(272) \|2025-06-17 22:11:33,149\| Began transaction (1): transaction manager [org.springframework.orm.hibernate3.HibernateTransactionManager@699e0bf0]; rollback [true]
17-Jun-2025 22:11:33	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:33,611\| HQL: from User u where (u.username = ? or u.systemId = ? or u.systemId = ?) and u.retired = '0', time: 34ms, rows: 1
17-Jun-2025 22:11:33	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:33,626\| HQL: select password from users where user_id = ?, time: 0ms, rows: 1
17-Jun-2025 22:11:33	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:33,628\| HQL: select salt from users where user_id = ?, time: 0ms, rows: 1
17-Jun-2025 22:11:33	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:33,668\| HQL: null, time: 1ms, rows: 7
17-Jun-2025 22:11:33	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:33,678\| HQL: from AddressHierarchyEntry where uuid = :uuid, time: 1ms, rows: 0
17-Jun-2025 22:11:33	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:33,681\| HQL: null, time: 0ms, rows: 0
17-Jun-2025 22:11:33	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:33,688\| HQL: null, time: 3ms, rows: 2
17-Jun-2025 22:11:33	INFO - TransactionalTestExecutionListener.endTransaction(292) \|2025-06-17 22:11:33,762\| Rolled back transaction after test execution for test context [[TestContext@4a453f8d testClass = AddressHierarchyAjaxControllerTest, locations = array<String>['classpath:applicationContext-service.xml', 'classpath:TestingApplicationContext.xml', 'classpath:moduleApplicationContext.xml'], testInstance = org.openmrs.module.addresshierarchy.web.controller.ajax.AddressHierarchyAjaxControllerTest@60a6840c, testMethod = getPossibleAddressHierarchyEntries_shouldReturnAllEntriesWhenParentUuidIsWrong@AddressHierarchyAjaxControllerTest, testException = [null]]]
17-Jun-2025 22:11:33	INFO - TransactionalTestExecutionListener.startNewTransaction(272) \|2025-06-17 22:11:33,766\| Began transaction (2): transaction manager [org.springframework.orm.hibernate3.HibernateTransactionManager@699e0bf0]; rollback [true]
17-Jun-2025 22:11:33	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:33,990\| HQL: from User u where (u.username = ? or u.systemId = ? or u.systemId = ?) and u.retired = '0', time: 7ms, rows: 1
17-Jun-2025 22:11:34	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:33,991\| HQL: select password from users where user_id = ?, time: 0ms, rows: 1
17-Jun-2025 22:11:34	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:33,993\| HQL: select salt from users where user_id = ?, time: 0ms, rows: 1
17-Jun-2025 22:11:34	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:34,032\| HQL: null, time: 3ms, rows: 7
17-Jun-2025 22:11:34	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:34,033\| HQL: from AddressHierarchyEntry where uuid = :uuid, time: 0ms, rows: 0
17-Jun-2025 22:11:34	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:34,034\| HQL: null, time: 0ms, rows: 0
17-Jun-2025 22:11:34	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:34,041\| HQL: null, time: 2ms, rows: 2
17-Jun-2025 22:11:34	INFO - TransactionalTestExecutionListener.endTransaction(292) \|2025-06-17 22:11:34,134\| Rolled back transaction after test execution for test context [[TestContext@4a453f8d testClass = AddressHierarchyAjaxControllerTest, locations = array<String>['classpath:applicationContext-service.xml', 'classpath:TestingApplicationContext.xml', 'classpath:moduleApplicationContext.xml'], testInstance = org.openmrs.module.addresshierarchy.web.controller.ajax.AddressHierarchyAjaxControllerTest@ca10069, testMethod = getPossibleAddressHierarchyEntries_shouldRetrieveAddressHierarchyEntriesWhenParentNotSpecified@AddressHierarchyAjaxControllerTest, testException = [null]]]
17-Jun-2025 22:11:34	INFO - TransactionalTestExecutionListener.startNewTransaction(272) \|2025-06-17 22:11:34,136\| Began transaction (3): transaction manager [org.springframework.orm.hibernate3.HibernateTransactionManager@699e0bf0]; rollback [true]
17-Jun-2025 22:11:34	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:34,399\| HQL: from User u where (u.username = ? or u.systemId = ? or u.systemId = ?) and u.retired = '0', time: 14ms, rows: 1
17-Jun-2025 22:11:34	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:34,405\| HQL: select password from users where user_id = ?, time: 0ms, rows: 1
17-Jun-2025 22:11:34	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:34,407\| HQL: select salt from users where user_id = ?, time: 0ms, rows: 1
17-Jun-2025 22:11:34	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:34,435\| HQL: null, time: 3ms, rows: 7
17-Jun-2025 22:11:34	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:34,436\| HQL: from AddressHierarchyEntry where uuid = :uuid, time: 0ms, rows: 1
17-Jun-2025 22:11:34	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:34,438\| HQL: null, time: 1ms, rows: 1
17-Jun-2025 22:11:34	INFO - TransactionalTestExecutionListener.endTransaction(292) \|2025-06-17 22:11:34,487\| Rolled back transaction after test execution for test context [[TestContext@4a453f8d testClass = AddressHierarchyAjaxControllerTest, locations = array<String>['classpath:applicationContext-service.xml', 'classpath:TestingApplicationContext.xml', 'classpath:moduleApplicationContext.xml'], testInstance = org.openmrs.module.addresshierarchy.web.controller.ajax.AddressHierarchyAjaxControllerTest@2c2e5e72, testMethod = getPossibleAddressHierarchyEntries_shouldRetrieveAddressHierarchyEntriesWhenParentSpecified@AddressHierarchyAjaxControllerTest, testException = [null]]]
17-Jun-2025 22:11:34	INFO - TransactionalTestExecutionListener.startNewTransaction(272) \|2025-06-17 22:11:34,488\| Began transaction (4): transaction manager [org.springframework.orm.hibernate3.HibernateTransactionManager@699e0bf0]; rollback [true]
17-Jun-2025 22:11:34	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:34,686\| HQL: from User u where (u.username = ? or u.systemId = ? or u.systemId = ?) and u.retired = '0', time: 12ms, rows: 1
17-Jun-2025 22:11:34	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:34,688\| HQL: select password from users where user_id = ?, time: 0ms, rows: 1
17-Jun-2025 22:11:34	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:34,690\| HQL: select salt from users where user_id = ?, time: 0ms, rows: 1
17-Jun-2025 22:11:34	ERROR - AddressHierarchyAjaxController.getPossibleAddressHierarchyEntriesWithParents(147) \|2025-06-17 22:11:34,705\| Invalid address field or address field has no associated address hierarchy level
17-Jun-2025 22:11:34	INFO - TransactionalTestExecutionListener.endTransaction(292) \|2025-06-17 22:11:34,745\| Rolled back transaction after test execution for test context [[TestContext@4a453f8d testClass = AddressHierarchyAjaxControllerTest, locations = array<String>['classpath:applicationContext-service.xml', 'classpath:TestingApplicationContext.xml', 'classpath:moduleApplicationContext.xml'], testInstance = org.openmrs.module.addresshierarchy.web.controller.ajax.AddressHierarchyAjaxControllerTest@19ce19b7, testMethod = getPossibleAddressHierarchyEntries_shouldReturnEmptyMapWhenAddressFieldNameIsWrong@AddressHierarchyAjaxControllerTest, testException = [null]]]
17-Jun-2025 22:11:34	INFO - TransactionalTestExecutionListener.startNewTransaction(272) \|2025-06-17 22:11:34,754\| Began transaction (5): transaction manager [org.springframework.orm.hibernate3.HibernateTransactionManager@699e0bf0]; rollback [true]
17-Jun-2025 22:11:34	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:34,885\| HQL: from User u where (u.username = ? or u.systemId = ? or u.systemId = ?) and u.retired = '0', time: 2ms, rows: 1
17-Jun-2025 22:11:34	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:34,895\| HQL: select password from users where user_id = ?, time: 0ms, rows: 1
17-Jun-2025 22:11:34	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:34,895\| HQL: select salt from users where user_id = ?, time: 0ms, rows: 1
17-Jun-2025 22:11:34	ERROR - AddressHierarchyAjaxController.getPossibleAddressHierarchyEntriesWithParents(137) \|2025-06-17 22:11:34,914\| Limit should be greater than zero
17-Jun-2025 22:11:34	INFO - TransactionalTestExecutionListener.endTransaction(292) \|2025-06-17 22:11:34,932\| Rolled back transaction after test execution for test context [[TestContext@4a453f8d testClass = AddressHierarchyAjaxControllerTest, locations = array<String>['classpath:applicationContext-service.xml', 'classpath:TestingApplicationContext.xml', 'classpath:moduleApplicationContext.xml'], testInstance = org.openmrs.module.addresshierarchy.web.controller.ajax.AddressHierarchyAjaxControllerTest@15ce261, testMethod = getPossibleAddressHierarchyEntries_shouldReturnEmptyLimitForWrongLimit@AddressHierarchyAjaxControllerTest, testException = [null]]]
17-Jun-2025 22:11:34	INFO - TransactionalTestExecutionListener.startNewTransaction(272) \|2025-06-17 22:11:34,933\| Began transaction (6): transaction manager [org.springframework.orm.hibernate3.HibernateTransactionManager@699e0bf0]; rollback [true]
17-Jun-2025 22:11:35	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:35,034\| HQL: from User u where (u.username = ? or u.systemId = ? or u.systemId = ?) and u.retired = '0', time: 8ms, rows: 1
17-Jun-2025 22:11:35	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:35,035\| HQL: select password from users where user_id = ?, time: 0ms, rows: 1
17-Jun-2025 22:11:35	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:35,036\| HQL: select salt from users where user_id = ?, time: 0ms, rows: 1
17-Jun-2025 22:11:35	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:35,057\| HQL: null, time: 1ms, rows: 7
17-Jun-2025 22:11:35	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:35,062\| HQL: from AddressHierarchyEntry where uuid = :uuid, time: 0ms, rows: 0
17-Jun-2025 22:11:35	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:35,063\| HQL: null, time: 0ms, rows: 0
17-Jun-2025 22:11:35	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:35,064\| HQL: null, time: 0ms, rows: 1
17-Jun-2025 22:11:35	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:35,074\| HQL: null, time: 0ms, rows: 7
17-Jun-2025 22:11:35	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:35,075\| HQL: from AddressHierarchyEntry where uuid = :uuid, time: 0ms, rows: 1
17-Jun-2025 22:11:35	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:35,076\| HQL: null, time: 1ms, rows: 1
17-Jun-2025 22:11:35	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:35,077\| HQL: null, time: 0ms, rows: 7
17-Jun-2025 22:11:35	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:35,083\| HQL: from AddressHierarchyEntry where uuid = :uuid, time: 1ms, rows: 1
17-Jun-2025 22:11:35	INFO - ConcurrentStatisticsImpl.queryExecuted(319) \|2025-06-17 22:11:35,084\| HQL: null, time: 1ms, rows: 0
17-Jun-2025 22:11:35	INFO - TransactionalTestExecutionListener.endTransaction(292) \|2025-06-17 22:11:35,121\| Rolled back transaction after test execution for test context [[TestContext@4a453f8d testClass = AddressHierarchyAjaxControllerTest, locations = array<String>['classpath:applicationContext-service.xml', 'classpath:TestingApplicationContext.xml', 'classpath:moduleApplicationContext.xml'], testInstance = org.openmrs.module.addresshierarchy.web.controller.ajax.AddressHierarchyAjaxControllerTest@6ed7c178, testMethod = getPossibleAddressHierarchyEntries_shouldHonourLimit@AddressHierarchyAjaxControllerTest, testException = [null]]]
17-Jun-2025 22:11:35	[INFO] Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 6.666 s - in org.openmrs.module.addresshierarchy.web.controller.ajax.AddressHierarchyAjaxControllerTest
17-Jun-2025 22:11:35	INFO - AbstractApplicationContext.doClose(1002) \|2025-06-17 22:11:35,148\| Closing org.springframework.context.support.GenericApplicationContext@6b1274d2: startup date [Tue Jun 17 22:11:29 UTC 2025]; root of context hierarchy
17-Jun-2025 22:11:35	INFO - DefaultSingletonBeanRegistry.destroySingletons(422) \|2025-06-17 22:11:35,172\| Destroying singletons in org.springframework.beans.factory.support.DefaultListableBeanFactory@7ca0863b: defining beans [org.springframework.aop.framework.autoproxy.DefaultAdvisorAutoProxyCreator#0,org.springframework.transaction.interceptor.TransactionAttributeSourceAdvisor#0,transactionInterceptor,globalLocaleList,clearOpenmrsEventListeners,openmrsEventListeners,serviceContext,context,contextDAO,patientDAO,personDAO,conceptDAO,userDAO,obsDAO,encounterDAO,locationDAO,orderDAO,formDAO,adminDAO,noteDAO,programWorkflowDAO,templateDAO,patientSetDAO,cohortDAO,serializedObjectDAO,datatypeDAO,schedulerDAO,alertDAO,reportDAO,reportObjectDAO,hL7DAO,activeListDAO,visitDAO,providerDAO,patientServiceTarget,personServiceTarget,conceptServiceTarget,userServiceTarget,obsServiceTarget,encounterServiceTarget,locationServiceTarget,orderServiceTarget,formServiceTarget,adminServiceTarget,datatypeServiceTarget,programWorkflowServiceTarget,patientSetServiceTarget,activeListServiceTarget,visitServiceTarget,providerServiceTarget,cohortServiceTarget,schedulerServiceTarget,alertServiceTarget,messageServiceTarget,reportObjectServiceTarget,reportServiceTarget,serializationServiceTarget,dataSetServiceTarget,hL7ServiceTarget,ardenServiceTarget,messageSourceServiceTarget,transactionAttributeSource,mutableResourceBundleMessageSource,patientService,personService,conceptService,userService,obsService,encounterService,locationService,orderService,formService,adminService,datatypeService,programWorkflowService,messageService,patientSetService,cohortService,schedulerService,alertService,reportService,reportObjectService,serializationService,dataSetService,hL7Service,ardenService,messageSourceService,activeListService,visitService,providerService,authorizationInterceptor,loggingInterceptor,requiredDataInterceptor,serviceInterceptors,moduleTestingMappingJarLocations,mappingJarResources,auditableInterceptor,sessionFactory,dbSessionFactory,transactionManager,patientCharacteristicFilterModule,numericObsPatientFilterModule,shortDescriptionProducerModule,personValidator,patientValidator,locationValidator,personNameValidator,patientIdentifierValidator,patientIdentifierTypeValidator,personAttributeTypeValidator,userValidator,roleValidator,privilegeValidator,encounterTypeValidator,conceptClassValidator,conceptDatatypeValidator,formEditValidator,fieldTypeValidator,programValidator,stateConversionValidator,taskValidator,obsValidator,orderValidator,drugOrderValidator,requireNameValidator,conceptDrugValidator,hl7SourceValidator,personAddressValidator,visitTypeValidator,visitAttributeTypeValidator,providerAttributeTypeValidator,encounterValidator,locationAttributeTypeValidator,providerValidator,patientProgramValidator,conceptValidator,personMergeLogValidator,conceptReferenceTermValidator,conceptMapTypeValidator,implementationIdHttpClient,hl7InErrorListController,hl7InArchiveMigrationController,hl7InQueueListController,hl7InArchiveListController,webSchedulerFormValidator,longFreeTextTextareaHandler,booleanFieldGenDatatypeHandler,longFreeTextFileUploadHandler,dateFieldGenDatatypeHandler,formResourceController,locationAttributeTypeFormController,locationAttributeTypeListController,providerFormController,patientDashboardController,shortPatientFormController,patientDashboardGraphController,coreLoginController,conceptStopWordListController,conceptMapTypeFormController,conceptStopWordFormController,conceptReferenceTermFormController,visitListController,configureVisitsFormController,visitFormController,uncaughtExceptionController,personAttributeTypeListController,encounterRoleFormController,addressTemplateController,hierarchyController,locationTagController,databaseChangesInfoController,settingsController,systemInformationController,currentUsersController,localesAndThemesFormController,userFormController,userListController,changePasswordFormController,localizationController,locationQueryController,customValueController,advancedFeaturesController,addressHierarchyAjaxController,manageAddressHierarchyController,webTestHelper,handlerUtil,schedulerFormValidator,alertValidator,formValidator,orderTypeValidator,baseAttributeValidator,relationshipTypeValidator,testOrderValidator,orderFrequencyValidator,locationTagValidator,drugValidator,HL7SourceValidator,visitValidator,encounterRoleValidator,immutableOrderInterceptor,noVisitAssignmentHandler,requireVoidReasonVoidHandler,patientDataUnvoidHandler,encounterSaveHandler,conceptNameSaveHandler,voidSaveHandler,existingOrNewVisitAssignmentHandler,existingVisitAssignmentHandler,userSaveHandler,patientSaveHandler,baseVoidHandler,conceptReferenceTermSaveHandler,conceptProposalHandler,visitUnvoidHandler,visitVoidHandler,personSaveHandler,conceptSaveHandler,personUnvoidHandler,baseRetireHandler,personVoidHandler,orderSaveHandler,patientDataVoidHandler,baseUnvoidHandler,openmrsObjectSaveHandler,requireVoidReasonSaveHandler,personNameSaveHandler,retireSaveHandler,baseUnretireHandler,freeTextDatatype,longFreeTextDatatype,dateDatatype,regexValidatedTextDatatype,booleanDatatype,addressHierarchyLevelValidator,disabledI18nCache,orderEntry.OrderNumberGenerator,openmrsComponent1_8,openmrsProfile1_10,openmrsProfileWithoutTest1Module,listener2,listener1,org.springframework.context.annotation.internalConfigurationAnnotationProcessor,org.springframework.context.annotation.internalAutowiredAnnotationProcessor,org.springframework.context.annotation.internalRequiredAnnotationProcessor,org.springframework.context.annotation.internalCommonAnnotationProcessor,org.springframework.context.annotation.internalPersistenceAnnotationProcessor,serviceContext$child#0,proj001MappingResources,serviceContext$child#1,AddressCacheResetSupport,initializeFullAddressCacheTask,initializeFullAddressCacheTimerTask,addressHierarchyTimerFactory,serviceContext$child#2]; root of factory hierarchy
17-Jun-2025 22:11:35	INFO - ExecutorConfigurationSupport.shutdown(150) \|2025-06-17 22:11:35,176\| Shutting down ExecutorService 'addressHierarchyTimerFactory'
17-Jun-2025 22:11:35	INFO - AbstractSessionFactoryBean.destroy(246) \|2025-06-17 22:11:35,189\| Closing Hibernate SessionFactory
17-Jun-2025 22:11:35	INFO - SessionFactoryImpl.close(927) \|2025-06-17 22:11:35,190\| closing
17-Jun-2025 22:11:35	INFO - SchemaExport.execute(234) \|2025-06-17 22:11:35,192\| Running hbm2ddl schema export
17-Jun-2025 22:11:35	INFO - SchemaExport.execute(262) \|2025-06-17 22:11:35,192\| exporting generated schema to database
17-Jun-2025 22:11:35	ERROR - SchemaExport.execute(287) \|2025-06-17 22:11:35,193\| schema export unsuccessful
17-Jun-2025 22:11:35	org.h2.jdbc.JdbcSQLException: Database is already closed (to disable automatic closing at VM shutdown, add ";DB_CLOSE_ON_EXIT=FALSE" to the db URL) [90121-187]
17-Jun-2025 22:11:35	at org.h2.message.DbException.getJdbcSQLException(DbException.java:345)
17-Jun-2025 22:11:35	at org.h2.message.DbException.get(DbException.java:179)
17-Jun-2025 22:11:35	at org.h2.message.DbException.get(DbException.java:155)
17-Jun-2025 22:11:35	at org.h2.message.DbException.get(DbException.java:144)
17-Jun-2025 22:11:35	at org.h2.jdbc.JdbcConnection.checkClosed(JdbcConnection.java:1469)
17-Jun-2025 22:11:35	at org.h2.jdbc.JdbcConnection.checkClosed(JdbcConnection.java:1444)
17-Jun-2025 22:11:35	at org.h2.jdbc.JdbcConnection.getAutoCommit(JdbcConnection.java:447)
17-Jun-2025 22:11:35	at com.mchange.v2.c3p0.impl.NewProxyConnection.getAutoCommit(NewProxyConnection.java:985)
17-Jun-2025 22:11:35	at org.hibernate.connection.C3P0ConnectionProvider.getConnection(C3P0ConnectionProvider.java:82)
17-Jun-2025 22:11:35	at org.hibernate.tool.hbm2ddl.SuppliedConnectionProviderConnectionHelper.prepare(SuppliedConnectionProviderConnectionHelper.java:51)
17-Jun-2025 22:11:35	at org.hibernate.tool.hbm2ddl.SchemaExport.execute(SchemaExport.java:263)
17-Jun-2025 22:11:35	at org.hibernate.tool.hbm2ddl.SchemaExport.drop(SchemaExport.java:229)
17-Jun-2025 22:11:35	at org.hibernate.impl.SessionFactoryImpl.close(SessionFactoryImpl.java:961)
17-Jun-2025 22:11:35	at org.springframework.orm.hibernate3.AbstractSessionFactoryBean.destroy(AbstractSessionFactoryBean.java:251)
17-Jun-2025 22:11:35	at org.springframework.orm.hibernate3.LocalSessionFactoryBean.destroy(LocalSessionFactoryBean.java:899)
17-Jun-2025 22:11:35	at org.openmrs.api.db.hibernate.HibernateSessionFactoryBean.destroy(HibernateSessionFactoryBean.java:192)
17-Jun-2025 22:11:35	at org.springframework.beans.factory.support.DisposableBeanAdapter.destroy(DisposableBeanAdapter.java:184)
17-Jun-2025 22:11:35	at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.destroyBean(DefaultSingletonBeanRegistry.java:487)
17-Jun-2025 22:11:35	at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.destroySingleton(DefaultSingletonBeanRegistry.java:463)
17-Jun-2025 22:11:35	at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.destroySingletons(DefaultSingletonBeanRegistry.java:431)
17-Jun-2025 22:11:35	at org.springframework.context.support.AbstractApplicationContext.destroyBeans(AbstractApplicationContext.java:1048)
17-Jun-2025 22:11:35	at org.springframework.context.support.AbstractApplicationContext.doClose(AbstractApplicationContext.java:1022)
17-Jun-2025 22:11:35	at org.springframework.context.support.AbstractApplicationContext$3.run(AbstractApplicationContext.java:940)
17-Jun-2025 22:11:35	[INFO]
17-Jun-2025 22:11:35	[INFO] Results:
17-Jun-2025 22:11:35	[INFO]
17-Jun-2025 22:11:35	[INFO] Tests run: 6, Failures: 0, Errors: 0, Skipped: 0
17-Jun-2025 22:11:35	[INFO]
17-Jun-2025 22:11:35	[INFO]
17-Jun-2025 22:11:35	[INFO] --- maven-jar-plugin:2.4:jar (default-jar) @ addresshierarchy-omod ---
17-Jun-2025 22:11:35	[INFO] Building jar: /home/bamboo-agent/bamboo-agent/xml-data/build-dir/ADDRHIER-ADDRHIER-JOB1/omod/target/addresshierarchy-2.22.0-SNAPSHOT.jar
17-Jun-2025 22:11:35	[INFO]
17-Jun-2025 22:11:35	[INFO] --- maven-openmrs-plugin:1.0.1:package-module (pack) @ addresshierarchy-omod ---
17-Jun-2025 22:11:35	[INFO] Packaging OpenMRS module
17-Jun-2025 22:11:35	[INFO] Building jar: /home/bamboo-agent/bamboo-agent/xml-data/build-dir/ADDRHIER-ADDRHIER-JOB1/omod/target/addresshierarchy-2.22.0-SNAPSHOT.omod
17-Jun-2025 22:11:35	[INFO]
17-Jun-2025 22:11:35	[INFO] --- maven-install-plugin:2.4:install (default-install) @ addresshierarchy-omod ---
17-Jun-2025 22:11:35	[INFO] Installing /home/bamboo-agent/bamboo-agent/xml-data/build-dir/ADDRHIER-ADDRHIER-JOB1/omod/target/addresshierarchy-2.22.0-SNAPSHOT.omod to /home/bamboo-agent/.m2/repository/org/openmrs/module/addresshierarchy-omod/2.22.0-SNAPSHOT/addresshierarchy-omod-2.22.0-SNAPSHOT.jar
17-Jun-2025 22:11:35	[INFO] Installing /home/bamboo-agent/bamboo-agent/xml-data/build-dir/ADDRHIER-ADDRHIER-JOB1/omod/pom.xml to /home/bamboo-agent/.m2/repository/org/openmrs/module/addresshierarchy-omod/2.22.0-SNAPSHOT/addresshierarchy-omod-2.22.0-SNAPSHOT.pom
17-Jun-2025 22:11:35	[INFO]
17-Jun-2025 22:11:35	[INFO] --- maven-deploy-plugin:2.7:deploy (default-deploy) @ addresshierarchy-omod ---
17-Jun-2025 22:11:35	[INFO] Downloading from openmrs-repo-snapshots: https://mavenrepo.openmrs.org/snapshots/org/openmrs/module/addresshierarchy-omod/2.22.0-SNAPSHOT/maven-metadata.xml
17-Jun-2025 22:11:36	[INFO] Downloaded from openmrs-repo-snapshots: https://mavenrepo.openmrs.org/snapshots/org/openmrs/module/addresshierarchy-omod/2.22.0-SNAPSHOT/maven-metadata.xml (792 B at 3.4 kB/s)
17-Jun-2025 22:11:36	[INFO] Uploading to openmrs-repo-snapshots: https://mavenrepo.openmrs.org/snapshots/org/openmrs/module/addresshierarchy-omod/2.22.0-SNAPSHOT/addresshierarchy-omod-2.22.0-20250617.221031-2.jar
17-Jun-2025 22:11:38	[INFO] Uploaded to openmrs-repo-snapshots: https://mavenrepo.openmrs.org/snapshots/org/openmrs/module/addresshierarchy-omod/2.22.0-SNAPSHOT/addresshierarchy-omod-2.22.0-20250617.221031-2.jar (325 kB at 146 kB/s)
17-Jun-2025 22:11:38	[INFO] Uploading to openmrs-repo-snapshots: https://mavenrepo.openmrs.org/snapshots/org/openmrs/module/addresshierarchy-omod/2.22.0-SNAPSHOT/addresshierarchy-omod-2.22.0-20250617.221031-2.pom
17-Jun-2025 22:11:39	[INFO] Uploaded to openmrs-repo-snapshots: https://mavenrepo.openmrs.org/snapshots/org/openmrs/module/addresshierarchy-omod/2.22.0-SNAPSHOT/addresshierarchy-omod-2.22.0-20250617.221031-2.pom (6.9 kB at 4.6 kB/s)
17-Jun-2025 22:11:39	[INFO] Downloading from openmrs-repo-snapshots: https://mavenrepo.openmrs.org/snapshots/org/openmrs/module/addresshierarchy-omod/maven-metadata.xml
17-Jun-2025 22:11:40	[INFO] Downloaded from openmrs-repo-snapshots: https://mavenrepo.openmrs.org/snapshots/org/openmrs/module/addresshierarchy-omod/maven-metadata.xml (1.0 kB at 3.3 kB/s)
17-Jun-2025 22:11:40	[INFO] Uploading to openmrs-repo-snapshots: https://mavenrepo.openmrs.org/snapshots/org/openmrs/module/addresshierarchy-omod/2.22.0-SNAPSHOT/maven-metadata.xml
17-Jun-2025 22:11:41	[INFO] Uploaded to openmrs-repo-snapshots: https://mavenrepo.openmrs.org/snapshots/org/openmrs/module/addresshierarchy-omod/2.22.0-SNAPSHOT/maven-metadata.xml (792 B at 640 B/s)
17-Jun-2025 22:11:41	[INFO] Uploading to openmrs-repo-snapshots: https://mavenrepo.openmrs.org/snapshots/org/openmrs/module/addresshierarchy-omod/maven-metadata.xml
17-Jun-2025 22:11:42	[INFO] Uploaded to openmrs-repo-snapshots: https://mavenrepo.openmrs.org/snapshots/org/openmrs/module/addresshierarchy-omod/maven-metadata.xml (948 B at 625 B/s)
17-Jun-2025 22:11:42	[INFO] ------------------------------------------------------------------------
17-Jun-2025 22:11:42	[INFO] Reactor Summary for Address Hierarchy 2.22.0-SNAPSHOT:
17-Jun-2025 22:11:42	[INFO]
17-Jun-2025 22:11:42	[INFO] Address Hierarchy .................................. SUCCESS [ 6.834 s]
17-Jun-2025 22:11:42	[INFO] Address Hierarchy API .............................. SUCCESS [ 48.385 s]
17-Jun-2025 22:11:42	[INFO] Address Hierarchy OMOD ............................. SUCCESS [ 16.019 s]
17-Jun-2025 22:11:42	[INFO] ------------------------------------------------------------------------
17-Jun-2025 22:11:42	[INFO] BUILD SUCCESS
17-Jun-2025 22:11:42	[INFO] ------------------------------------------------------------------------
17-Jun-2025 22:11:42	[INFO] Total time: 01:11 min
17-Jun-2025 22:11:42	[INFO] Finished at: 2025-06-17T22:11:42Z
17-Jun-2025 22:11:42	[INFO] ------------------------------------------------------------------------
17-Jun-2025 22:11:42	Parsing test results under /home/bamboo-agent/bamboo-agent/xml-data/build-dir/ADDRHIER-ADDRHIER-JOB1...
17-Jun-2025 22:11:42	Finished task 'Test and deploy' with result: Success
17-Jun-2025 22:11:42	Running post build plugin 'Docker Container Cleanup'
17-Jun-2025 22:11:42	Running post build plugin 'NCover Results Collector'
17-Jun-2025 22:11:42	Running post build plugin 'Build Results Label Collector'
17-Jun-2025 22:11:42	Running post build plugin 'Clover Results Collector'
17-Jun-2025 22:11:42	Running post build plugin 'npm Cache Cleanup'
17-Jun-2025 22:11:42	Running post build plugin 'Artifact Copier'
17-Jun-2025 22:11:42	Finalising the build...
17-Jun-2025 22:11:42	Stopping timer.
17-Jun-2025 22:11:42	Build ADDRHIER-ADDRHIER-JOB1-137 completed.
17-Jun-2025 22:11:42	Running on server: post build plugin 'Build Hanging Detection Configuration'
17-Jun-2025 22:11:42	Running on server: post build plugin 'NCover Results Collector'
17-Jun-2025 22:11:42	Running on server: post build plugin 'Build Labeller'
17-Jun-2025 22:11:42	Running on server: post build plugin 'Clover Delta Calculator'
17-Jun-2025 22:11:42	Running on server: post build plugin 'Maven Dependencies Postprocessor'
17-Jun-2025 22:11:42	All post build plugins have finished
17-Jun-2025 22:11:42	Generating build results summary...
17-Jun-2025 22:11:43	Saving build results to disk...
17-Jun-2025 22:11:43	Store variable context...
17-Jun-2025 22:11:43	Finished building ADDRHIER-ADDRHIER-JOB1-137.

Build: #137 was successful

Job: Default Job was successful

Stages & jobs

Default Stage

Release

Build log

Build #137

Build: #137 was successful

Job: Default Job was successful

Stages & jobs

Default Stage

Release

Build log