24 Mar 2021 02:01:53,765 INFO : Starting to run task 'org.labkey.study.pipeline.StudyReloadSourceTask' at location 'webserver'
24 Mar 2021 02:01:53,775 INFO : Starting REDCap export
24 Mar 2021 02:01:54,563 INFO : Creating study archive
24 Mar 2021 02:01:54,602 INFO : Writing dataset metadata
24 Mar 2021 02:01:54,753 INFO : Creating column lookups
24 Mar 2021 02:01:56,279 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
24 Mar 2021 02:01:56,279 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
24 Mar 2021 02:01:56,279 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
24 Mar 2021 02:01:56,279 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
24 Mar 2021 02:01:56,279 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
24 Mar 2021 02:01:56,279 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
24 Mar 2021 02:01:56,279 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
24 Mar 2021 02:01:56,280 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
24 Mar 2021 02:01:56,280 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
24 Mar 2021 02:01:56,280 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
24 Mar 2021 02:01:56,280 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
24 Mar 2021 02:01:56,280 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
24 Mar 2021 02:01:56,280 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
24 Mar 2021 02:01:56,280 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
24 Mar 2021 02:01:56,280 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
24 Mar 2021 02:01:56,280 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
24 Mar 2021 02:01:56,280 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
24 Mar 2021 02:01:56,282 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
24 Mar 2021 02:01:56,282 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
24 Mar 2021 02:01:56,282 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
24 Mar 2021 02:01:56,283 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
24 Mar 2021 02:01:56,283 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
24 Mar 2021 02:01:56,283 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
24 Mar 2021 02:01:56,283 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
24 Mar 2021 02:01:56,283 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
24 Mar 2021 02:01:56,283 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
24 Mar 2021 02:01:56,283 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
24 Mar 2021 02:01:56,283 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
24 Mar 2021 02:01:56,283 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
24 Mar 2021 02:01:56,283 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
24 Mar 2021 02:01:56,283 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
24 Mar 2021 02:01:56,283 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
24 Mar 2021 02:01:56,283 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
24 Mar 2021 02:01:56,283 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
24 Mar 2021 02:01:56,283 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
24 Mar 2021 02:01:56,283 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
24 Mar 2021 02:01:56,283 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
24 Mar 2021 02:01:56,283 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
24 Mar 2021 02:01:56,283 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
24 Mar 2021 02:01:56,284 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
24 Mar 2021 02:01:56,284 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
24 Mar 2021 02:01:56,284 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
24 Mar 2021 02:01:56,284 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
24 Mar 2021 02:01:56,284 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
24 Mar 2021 02:01:56,284 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
24 Mar 2021 02:01:56,284 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
24 Mar 2021 02:01:56,284 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
24 Mar 2021 02:01:56,284 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
24 Mar 2021 02:01:56,284 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
24 Mar 2021 02:01:56,284 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
24 Mar 2021 02:01:56,284 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
24 Mar 2021 02:01:56,284 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
24 Mar 2021 02:01:56,284 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
24 Mar 2021 02:01:56,284 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
24 Mar 2021 02:01:56,284 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
24 Mar 2021 02:01:56,284 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
24 Mar 2021 02:01:56,284 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
24 Mar 2021 02:01:56,284 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
24 Mar 2021 02:01:56,285 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
24 Mar 2021 02:01:56,285 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
24 Mar 2021 02:01:56,285 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
24 Mar 2021 02:01:56,285 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
24 Mar 2021 02:01:56,285 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
24 Mar 2021 02:01:56,285 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
24 Mar 2021 02:01:56,285 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
24 Mar 2021 02:01:56,285 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
24 Mar 2021 02:01:56,285 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
24 Mar 2021 02:01:56,285 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
24 Mar 2021 02:01:56,285 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
24 Mar 2021 02:01:56,285 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
24 Mar 2021 02:01:56,285 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
24 Mar 2021 02:01:56,285 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
24 Mar 2021 02:01:56,285 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
24 Mar 2021 02:01:56,285 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
24 Mar 2021 02:01:56,285 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
24 Mar 2021 02:01:56,285 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
24 Mar 2021 02:01:56,286 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
24 Mar 2021 02:01:56,286 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
24 Mar 2021 02:01:56,286 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
24 Mar 2021 02:01:56,286 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
24 Mar 2021 02:01:56,286 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
24 Mar 2021 02:01:56,286 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
24 Mar 2021 02:01:56,286 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
24 Mar 2021 02:01:56,286 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
24 Mar 2021 02:01:56,286 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
24 Mar 2021 02:01:56,286 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
24 Mar 2021 02:01:56,286 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
24 Mar 2021 02:01:56,286 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
24 Mar 2021 02:01:56,286 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
24 Mar 2021 02:01:56,286 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
24 Mar 2021 02:01:56,286 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
24 Mar 2021 02:01:56,286 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
24 Mar 2021 02:01:56,286 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
24 Mar 2021 02:01:56,287 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
24 Mar 2021 02:01:56,287 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
24 Mar 2021 02:01:56,287 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
24 Mar 2021 02:01:56,287 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
24 Mar 2021 02:01:56,287 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
24 Mar 2021 02:01:56,287 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
24 Mar 2021 02:01:56,287 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
24 Mar 2021 02:01:56,287 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
24 Mar 2021 02:01:56,287 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
24 Mar 2021 02:01:56,287 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
24 Mar 2021 02:01:56,287 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
24 Mar 2021 02:01:56,287 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
24 Mar 2021 02:01:56,287 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
24 Mar 2021 02:01:56,287 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
24 Mar 2021 02:01:56,287 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
24 Mar 2021 02:01:56,287 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
24 Mar 2021 02:01:56,287 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
24 Mar 2021 02:01:56,288 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
24 Mar 2021 02:01:56,288 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
24 Mar 2021 02:01:56,288 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
24 Mar 2021 02:01:56,290 INFO : Finished creating study archive
24 Mar 2021 02:01:56,290 INFO : Successfully completed task 'org.labkey.study.pipeline.StudyReloadSourceTask'
24 Mar 2021 02:01:56,345 INFO : Starting to run task 'org.labkey.study.importer.StudyImportInitialTask' at location 'webserver'
24 Mar 2021 02:01:56,362 INFO : Reloading study from study.xml
24 Mar 2021 02:01:56,377 INFO : Loading top-level study properties (label, start/end date, description, etc.)
24 Mar 2021 02:01:56,674 INFO : Done importing top-level study properties (label, start/end date, description, etc.)
24 Mar 2021 02:01:56,735 INFO : Loading datasets manifest from datasets_manifest.xml
24 Mar 2021 02:01:56,739 INFO : Loading dataset schema from datasets_metadata.xml
24 Mar 2021 02:01:57,494 INFO : Successfully completed task 'org.labkey.study.importer.StudyImportInitialTask'
24 Mar 2021 02:01:57,528 INFO : Starting to run task 'org.labkey.study.pipeline.StudyImportDatasetTask' at location 'webserver'
24 Mar 2021 02:01:57,530 INFO : Start batch RedCap Integration.dataset
24 Mar 2021 02:01:57,569 INFO : demographics: Starting import from dataset001.tsv
24 Mar 2021 02:01:58,582 DEBUG: <next>
24 Mar 2021 02:01:58,583 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@5ce96c62>
24 Mar 2021 02:01:58,584 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@5ce96c62>
24 Mar 2021 02:01:58,584 DEBUG: </next>
24 Mar 2021 02:01:58,584 DEBUG: <next>
24 Mar 2021 02:01:58,584 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@5ce96c62>
24 Mar 2021 02:01:58,585 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@5ce96c62>
24 Mar 2021 02:01:58,585 DEBUG: </next>
24 Mar 2021 02:01:58,585 DEBUG: <next>
24 Mar 2021 02:01:58,585 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@5ce96c62>
24 Mar 2021 02:01:58,585 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@5ce96c62>
24 Mar 2021 02:01:58,586 DEBUG: </next>
24 Mar 2021 02:01:58,586 DEBUG: <next>
24 Mar 2021 02:01:58,586 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@5ce96c62>
24 Mar 2021 02:01:58,586 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@5ce96c62>
24 Mar 2021 02:01:58,586 DEBUG: </next>
24 Mar 2021 02:01:58,586 DEBUG: <next>
24 Mar 2021 02:01:58,587 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@5ce96c62>
24 Mar 2021 02:01:58,587 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@5ce96c62>
24 Mar 2021 02:01:58,587 DEBUG: </next>
24 Mar 2021 02:01:58,587 DEBUG: <next>
24 Mar 2021 02:01:58,587 DEBUG: <swap() - old: org.labkey.api.data.ParameterMapStatement@5ce96c62>
24 Mar 2021 02:01:58,587 DEBUG: </swap() - new: org.labkey.api.data.ParameterMapStatement@5215b2fe>
24 Mar 2021 02:01:58,587 DEBUG: <close() on _queue>
24 Mar 2021 02:01:58,587 DEBUG: <close() on _queue>
24 Mar 2021 02:01:58,587 DEBUG: </close() on _queue>
24 Mar 2021 02:01:58,587 DEBUG: <join() on _asyncThread>
24 Mar 2021 02:01:58,588 DEBUG: <executeBatch() on org.labkey.api.data.ParameterMapStatement@5ce96c62>
24 Mar 2021 02:01:58,598 DEBUG: </executeBatch() on org.labkey.api.data.ParameterMapStatement@5ce96c62>
24 Mar 2021 02:01:58,598 DEBUG: exit background thread
24 Mar 2021 02:01:58,598 DEBUG: </join() on _asyncThread>
24 Mar 2021 02:01:58,598 DEBUG: </next>
24 Mar 2021 02:01:58,598 DEBUG: <close()>
24 Mar 2021 02:01:58,598 DEBUG: <close() on _data>
24 Mar 2021 02:01:58,598 DEBUG: </close() on _data>
24 Mar 2021 02:01:58,598 DEBUG: <close() on _queue>
24 Mar 2021 02:01:58,598 DEBUG: </close() on _queue>
24 Mar 2021 02:01:58,598 DEBUG: <join() on _asyncThread>
24 Mar 2021 02:01:58,598 DEBUG: </join() on _asyncThread>
24 Mar 2021 02:01:58,598 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@5ce96c62>
24 Mar 2021 02:01:58,599 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@5ce96c62>
24 Mar 2021 02:01:58,599 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@5215b2fe>
24 Mar 2021 02:01:58,599 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@5215b2fe>
24 Mar 2021 02:01:58,599 DEBUG: </close()>
24 Mar 2021 02:01:58,645 INFO : demographics: Processed 5 rows from dataset001.tsv
24 Mar 2021 02:01:58,646 INFO : demographics: Deleted 5 rows
24 Mar 2021 02:01:58,646 INFO : demographics: Merged 5 rows
24 Mar 2021 02:01:58,675 INFO : emergency_contact_info: Starting import from dataset002.tsv
24 Mar 2021 02:01:58,942 DEBUG: <next>
24 Mar 2021 02:01:58,942 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@7bced208>
24 Mar 2021 02:01:58,942 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@7bced208>
24 Mar 2021 02:01:58,942 DEBUG: </next>
24 Mar 2021 02:01:58,942 DEBUG: <next>
24 Mar 2021 02:01:58,942 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@7bced208>
24 Mar 2021 02:01:58,943 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@7bced208>
24 Mar 2021 02:01:58,943 DEBUG: </next>
24 Mar 2021 02:01:58,943 DEBUG: <next>
24 Mar 2021 02:01:58,943 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@7bced208>
24 Mar 2021 02:01:58,943 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@7bced208>
24 Mar 2021 02:01:58,943 DEBUG: </next>
24 Mar 2021 02:01:58,943 DEBUG: <next>
24 Mar 2021 02:01:58,943 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@7bced208>
24 Mar 2021 02:01:58,943 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@7bced208>
24 Mar 2021 02:01:58,943 DEBUG: </next>
24 Mar 2021 02:01:58,943 DEBUG: <next>
24 Mar 2021 02:01:58,944 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@7bced208>
24 Mar 2021 02:01:58,944 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@7bced208>
24 Mar 2021 02:01:58,944 DEBUG: </next>
24 Mar 2021 02:01:58,944 DEBUG: <next>
24 Mar 2021 02:01:58,944 DEBUG: <swap() - old: org.labkey.api.data.ParameterMapStatement@7bced208>
24 Mar 2021 02:01:58,945 DEBUG: </swap() - new: org.labkey.api.data.ParameterMapStatement@3cd64d18>
24 Mar 2021 02:01:58,945 DEBUG: <executeBatch() on org.labkey.api.data.ParameterMapStatement@7bced208>
24 Mar 2021 02:01:58,945 DEBUG: <close() on _queue>
24 Mar 2021 02:01:58,945 DEBUG: <close() on _queue>
24 Mar 2021 02:01:58,945 DEBUG: </close() on _queue>
24 Mar 2021 02:01:58,945 DEBUG: <join() on _asyncThread>
24 Mar 2021 02:01:58,954 DEBUG: </executeBatch() on org.labkey.api.data.ParameterMapStatement@7bced208>
24 Mar 2021 02:01:58,954 DEBUG: exit background thread
24 Mar 2021 02:01:58,955 DEBUG: </join() on _asyncThread>
24 Mar 2021 02:01:58,955 DEBUG: </next>
24 Mar 2021 02:01:58,955 DEBUG: <close()>
24 Mar 2021 02:01:58,955 DEBUG: <close() on _data>
24 Mar 2021 02:01:58,955 DEBUG: </close() on _data>
24 Mar 2021 02:01:58,955 DEBUG: <close() on _queue>
24 Mar 2021 02:01:58,955 DEBUG: </close() on _queue>
24 Mar 2021 02:01:58,955 DEBUG: <join() on _asyncThread>
24 Mar 2021 02:01:58,955 DEBUG: </join() on _asyncThread>
24 Mar 2021 02:01:58,955 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@7bced208>
24 Mar 2021 02:01:58,955 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@7bced208>
24 Mar 2021 02:01:58,955 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@3cd64d18>
24 Mar 2021 02:01:58,955 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@3cd64d18>
24 Mar 2021 02:01:58,955 DEBUG: </close()>
24 Mar 2021 02:01:58,977 INFO : emergency_contact_info: Processed 5 rows from dataset002.tsv
24 Mar 2021 02:01:58,977 INFO : emergency_contact_info: Deleted 5 rows
24 Mar 2021 02:01:58,977 INFO : emergency_contact_info: Merged 5 rows
24 Mar 2021 02:01:59,013 INFO : baseline_lab_data: Starting import from dataset003.tsv
24 Mar 2021 02:01:59,207 DEBUG: <next>
24 Mar 2021 02:01:59,207 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@3a49db9d>
24 Mar 2021 02:01:59,207 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@3a49db9d>
24 Mar 2021 02:01:59,207 DEBUG: </next>
24 Mar 2021 02:01:59,207 DEBUG: <next>
24 Mar 2021 02:01:59,207 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@3a49db9d>
24 Mar 2021 02:01:59,208 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@3a49db9d>
24 Mar 2021 02:01:59,208 DEBUG: </next>
24 Mar 2021 02:01:59,208 DEBUG: <next>
24 Mar 2021 02:01:59,208 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@3a49db9d>
24 Mar 2021 02:01:59,208 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@3a49db9d>
24 Mar 2021 02:01:59,208 DEBUG: </next>
24 Mar 2021 02:01:59,208 DEBUG: <next>
24 Mar 2021 02:01:59,208 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@3a49db9d>
24 Mar 2021 02:01:59,208 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@3a49db9d>
24 Mar 2021 02:01:59,208 DEBUG: </next>
24 Mar 2021 02:01:59,208 DEBUG: <next>
24 Mar 2021 02:01:59,209 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@3a49db9d>
24 Mar 2021 02:01:59,209 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@3a49db9d>
24 Mar 2021 02:01:59,209 DEBUG: </next>
24 Mar 2021 02:01:59,209 DEBUG: <next>
24 Mar 2021 02:01:59,209 DEBUG: <swap() - old: org.labkey.api.data.ParameterMapStatement@3a49db9d>
24 Mar 2021 02:01:59,209 DEBUG: </swap() - new: org.labkey.api.data.ParameterMapStatement@27be10b1>
24 Mar 2021 02:01:59,209 DEBUG: <close() on _queue>
24 Mar 2021 02:01:59,209 DEBUG: <close() on _queue>
24 Mar 2021 02:01:59,209 DEBUG: </close() on _queue>
24 Mar 2021 02:01:59,209 DEBUG: <join() on _asyncThread>
24 Mar 2021 02:01:59,209 DEBUG: <executeBatch() on org.labkey.api.data.ParameterMapStatement@3a49db9d>
24 Mar 2021 02:01:59,217 DEBUG: </executeBatch() on org.labkey.api.data.ParameterMapStatement@3a49db9d>
24 Mar 2021 02:01:59,217 DEBUG: exit background thread
24 Mar 2021 02:01:59,217 DEBUG: </join() on _asyncThread>
24 Mar 2021 02:01:59,217 DEBUG: </next>
24 Mar 2021 02:01:59,217 DEBUG: <close()>
24 Mar 2021 02:01:59,217 DEBUG: <close() on _data>
24 Mar 2021 02:01:59,218 DEBUG: </close() on _data>
24 Mar 2021 02:01:59,218 DEBUG: <close() on _queue>
24 Mar 2021 02:01:59,218 DEBUG: </close() on _queue>
24 Mar 2021 02:01:59,218 DEBUG: <join() on _asyncThread>
24 Mar 2021 02:01:59,218 DEBUG: </join() on _asyncThread>
24 Mar 2021 02:01:59,218 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@3a49db9d>
24 Mar 2021 02:01:59,218 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@3a49db9d>
24 Mar 2021 02:01:59,218 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@27be10b1>
24 Mar 2021 02:01:59,218 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@27be10b1>
24 Mar 2021 02:01:59,218 DEBUG: </close()>
24 Mar 2021 02:01:59,237 INFO : baseline_lab_data: Processed 5 rows from dataset003.tsv
24 Mar 2021 02:01:59,237 INFO : baseline_lab_data: Deleted 5 rows
24 Mar 2021 02:01:59,237 INFO : baseline_lab_data: Merged 5 rows
24 Mar 2021 02:01:59,268 INFO : visit_blood_workup: Starting import from dataset004.tsv
24 Mar 2021 02:01:59,315 DEBUG: <next>
24 Mar 2021 02:01:59,315 DEBUG: <close() on _queue>
24 Mar 2021 02:01:59,315 DEBUG: <close() on _queue>
24 Mar 2021 02:01:59,315 DEBUG: </close() on _queue>
24 Mar 2021 02:01:59,315 DEBUG: <join() on _asyncThread>
24 Mar 2021 02:01:59,315 DEBUG: exit background thread
24 Mar 2021 02:01:59,316 DEBUG: </join() on _asyncThread>
24 Mar 2021 02:01:59,316 DEBUG: </next>
24 Mar 2021 02:01:59,316 DEBUG: <close()>
24 Mar 2021 02:01:59,316 DEBUG: <close() on _data>
24 Mar 2021 02:01:59,316 DEBUG: </close() on _data>
24 Mar 2021 02:01:59,316 DEBUG: <close() on _queue>
24 Mar 2021 02:01:59,316 DEBUG: </close() on _queue>
24 Mar 2021 02:01:59,316 DEBUG: <join() on _asyncThread>
24 Mar 2021 02:01:59,316 DEBUG: </join() on _asyncThread>
24 Mar 2021 02:01:59,316 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@398cf906>
24 Mar 2021 02:01:59,316 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@398cf906>
24 Mar 2021 02:01:59,316 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@34ac2002>
24 Mar 2021 02:01:59,316 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@34ac2002>
24 Mar 2021 02:01:59,316 DEBUG: </close()>
24 Mar 2021 02:01:59,349 INFO : completion_data: Starting import from dataset005.tsv
24 Mar 2021 02:01:59,376 DEBUG: <next>
24 Mar 2021 02:01:59,376 DEBUG: <close() on _queue>
24 Mar 2021 02:01:59,376 DEBUG: <close() on _queue>
24 Mar 2021 02:01:59,377 DEBUG: </close() on _queue>
24 Mar 2021 02:01:59,377 DEBUG: <join() on _asyncThread>
24 Mar 2021 02:01:59,386 DEBUG: exit background thread
24 Mar 2021 02:01:59,386 DEBUG: </join() on _asyncThread>
24 Mar 2021 02:01:59,387 DEBUG: </next>
24 Mar 2021 02:01:59,387 DEBUG: <close()>
24 Mar 2021 02:01:59,387 DEBUG: <close() on _data>
24 Mar 2021 02:01:59,387 DEBUG: </close() on _data>
24 Mar 2021 02:01:59,387 DEBUG: <close() on _queue>
24 Mar 2021 02:01:59,387 DEBUG: </close() on _queue>
24 Mar 2021 02:01:59,387 DEBUG: <join() on _asyncThread>
24 Mar 2021 02:01:59,387 DEBUG: </join() on _asyncThread>
24 Mar 2021 02:01:59,387 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@29974533>
24 Mar 2021 02:01:59,387 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@29974533>
24 Mar 2021 02:01:59,387 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@3b33c31>
24 Mar 2021 02:01:59,387 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@3b33c31>
24 Mar 2021 02:01:59,387 DEBUG: </close()>
24 Mar 2021 02:01:59,424 INFO : completion_project_questionnaire: Starting import from dataset006.tsv
24 Mar 2021 02:01:59,487 DEBUG: <next>
24 Mar 2021 02:01:59,487 DEBUG: <close() on _queue>
24 Mar 2021 02:01:59,487 DEBUG: <close() on _queue>
24 Mar 2021 02:01:59,487 DEBUG: </close() on _queue>
24 Mar 2021 02:01:59,487 DEBUG: <join() on _asyncThread>
24 Mar 2021 02:01:59,487 DEBUG: exit background thread
24 Mar 2021 02:01:59,487 DEBUG: </join() on _asyncThread>
24 Mar 2021 02:01:59,487 DEBUG: </next>
24 Mar 2021 02:01:59,487 DEBUG: <close()>
24 Mar 2021 02:01:59,487 DEBUG: <close() on _data>
24 Mar 2021 02:01:59,487 DEBUG: </close() on _data>
24 Mar 2021 02:01:59,487 DEBUG: <close() on _queue>
24 Mar 2021 02:01:59,488 DEBUG: </close() on _queue>
24 Mar 2021 02:01:59,488 DEBUG: <join() on _asyncThread>
24 Mar 2021 02:01:59,488 DEBUG: </join() on _asyncThread>
24 Mar 2021 02:01:59,488 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@2d0d0310>
24 Mar 2021 02:01:59,488 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@2d0d0310>
24 Mar 2021 02:01:59,488 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@3965116a>
24 Mar 2021 02:01:59,488 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@3965116a>
24 Mar 2021 02:01:59,488 DEBUG: </close()>
24 Mar 2021 02:01:59,492 INFO : Finish batch RedCap Integration.dataset
24 Mar 2021 02:01:59,496 INFO : Updating participant visits
24 Mar 2021 02:01:59,496 INFO : Updating participants
24 Mar 2021 02:01:59,504 INFO : Updating participant visit table
24 Mar 2021 02:01:59,577 INFO : Updating visit table
24 Mar 2021 02:01:59,582 INFO : Updating cohorts
24 Mar 2021 02:01:59,582 INFO : Clearing participant visit caches
24 Mar 2021 02:01:59,584 INFO : Finished updating participants
24 Mar 2021 02:01:59,584 INFO : Successfully completed task 'org.labkey.study.pipeline.StudyImportDatasetTask'
24 Mar 2021 02:01:59,626 INFO : Starting to run task 'org.labkey.api.specimen.pipeline.StudyImportSpecimenTask' at location 'webserver'
24 Mar 2021 02:01:59,629 INFO : Successfully completed task 'org.labkey.api.specimen.pipeline.StudyImportSpecimenTask'
24 Mar 2021 02:01:59,674 INFO : Starting to run task 'org.labkey.study.importer.StudyImportFinalTask' at location 'webserver'
24 Mar 2021 02:01:59,717 INFO : Loading dataset cohort assignments
24 Mar 2021 02:01:59,718 INFO : Done importing dataset cohort assignments
24 Mar 2021 02:01:59,775 INFO : Loading lists
24 Mar 2021 02:01:59,786 INFO : Truncating list: vbw8
24 Mar 2021 02:25:29,113 INFO : Recreating list: vbw8
24 Mar 2021 02:25:29,256 INFO : Truncating list: cpq13
24 Mar 2021 02:25:29,401 INFO : Recreating list: cpq13
24 Mar 2021 02:25:29,477 INFO : Truncating list: sex
24 Mar 2021 02:25:29,578 INFO : Recreating list: sex
24 Mar 2021 02:25:29,637 INFO : Truncating list: withdraw_reason
24 Mar 2021 02:25:29,720 INFO : Recreating list: withdraw_reason
24 Mar 2021 02:25:29,777 INFO : Truncating list: cpq6
24 Mar 2021 02:25:29,860 INFO : Recreating list: cpq6
24 Mar 2021 02:25:29,917 INFO : Truncating list: vbw9
24 Mar 2021 02:25:30,002 INFO : Recreating list: vbw9
24 Mar 2021 02:25:30,072 INFO : Truncating list: cpq8
24 Mar 2021 02:25:30,162 INFO : Recreating list: cpq8
24 Mar 2021 02:25:30,223 INFO : Truncating list: cpq10
24 Mar 2021 02:25:30,313 INFO : Recreating list: cpq10
24 Mar 2021 02:25:30,371 INFO : Truncating list: vbw6
24 Mar 2021 02:25:30,456 INFO : Recreating list: vbw6
24 Mar 2021 02:25:30,516 INFO : Truncating list: cpq11
24 Mar 2021 02:25:30,601 INFO : Recreating list: cpq11
24 Mar 2021 02:25:30,662 INFO : Truncating list: vbw7
24 Mar 2021 02:25:30,746 INFO : Recreating list: vbw7
24 Mar 2021 02:25:30,861 INFO : Merged 5 row(s) into list: withdraw_reason
24 Mar 2021 02:25:30,899 INFO : Merged 3 row(s) into list: cpq13
24 Mar 2021 02:25:30,947 INFO : Merged 6 row(s) into list: cpq11
24 Mar 2021 02:25:30,987 INFO : Merged 4 row(s) into list: cpq10
24 Mar 2021 02:25:31,024 INFO : Merged 2 row(s) into list: sex
24 Mar 2021 02:25:31,061 INFO : Merged 2 row(s) into list: vbw9
24 Mar 2021 02:25:31,098 INFO : Merged 3 row(s) into list: vbw8
24 Mar 2021 02:25:31,135 INFO : Merged 3 row(s) into list: cpq8
24 Mar 2021 02:25:31,152 INFO : Merged 4 row(s) into list: vbw7
24 Mar 2021 02:25:31,189 INFO : Merged 5 row(s) into list: cpq6
24 Mar 2021 02:25:31,224 INFO : Merged 2 row(s) into list: vbw6
24 Mar 2021 02:25:31,228 INFO : 11 lists imported successfully
24 Mar 2021 02:25:31,228 INFO : Done importing lists
24 Mar 2021 02:25:31,239 INFO : Post-processing queries
24 Mar 2021 02:25:31,239 INFO : Skipping query validation.
24 Mar 2021 02:25:31,240 INFO : Done post-processing queries
24 Mar 2021 02:25:31,250 INFO : Successfully completed task 'org.labkey.study.importer.StudyImportFinalTask'