14 Apr 2021 02:02:09,997 INFO : Starting to run task 'org.labkey.study.pipeline.StudyReloadSourceTask' at location 'webserver'
14 Apr 2021 02:02:10,005 INFO : Starting REDCap export
14 Apr 2021 02:02:10,881 INFO : Creating study archive
14 Apr 2021 02:02:10,945 INFO : Writing dataset metadata
14 Apr 2021 02:02:11,059 INFO : Creating column lookups
14 Apr 2021 02:02:11,800 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
14 Apr 2021 02:02:11,800 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
14 Apr 2021 02:02:11,800 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
14 Apr 2021 02:02:11,800 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
14 Apr 2021 02:02:11,800 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
14 Apr 2021 02:02:11,800 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
14 Apr 2021 02:02:11,800 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
14 Apr 2021 02:02:11,801 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
14 Apr 2021 02:02:11,801 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
14 Apr 2021 02:02:11,801 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
14 Apr 2021 02:02:11,801 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
14 Apr 2021 02:02:11,801 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
14 Apr 2021 02:02:11,801 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
14 Apr 2021 02:02:11,801 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
14 Apr 2021 02:02:11,801 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
14 Apr 2021 02:02:11,801 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
14 Apr 2021 02:02:11,801 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
14 Apr 2021 02:02:11,801 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
14 Apr 2021 02:02:11,801 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
14 Apr 2021 02:02:11,801 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
14 Apr 2021 02:02:11,801 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
14 Apr 2021 02:02:11,801 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
14 Apr 2021 02:02:11,801 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
14 Apr 2021 02:02:11,801 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
14 Apr 2021 02:02:11,801 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
14 Apr 2021 02:02:11,801 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
14 Apr 2021 02:02:11,801 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
14 Apr 2021 02:02:11,801 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
14 Apr 2021 02:02:11,801 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
14 Apr 2021 02:02:11,801 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
14 Apr 2021 02:02:11,802 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
14 Apr 2021 02:02:11,802 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
14 Apr 2021 02:02:11,802 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
14 Apr 2021 02:02:11,802 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
14 Apr 2021 02:02:11,802 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
14 Apr 2021 02:02:11,802 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
14 Apr 2021 02:02:11,802 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
14 Apr 2021 02:02:11,802 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
14 Apr 2021 02:02:11,802 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
14 Apr 2021 02:02:11,802 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
14 Apr 2021 02:02:11,802 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
14 Apr 2021 02:02:11,802 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
14 Apr 2021 02:02:11,802 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
14 Apr 2021 02:02:11,802 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
14 Apr 2021 02:02:11,802 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
14 Apr 2021 02:02:11,802 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
14 Apr 2021 02:02:11,802 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
14 Apr 2021 02:02:11,802 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
14 Apr 2021 02:02:11,802 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
14 Apr 2021 02:02:11,802 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
14 Apr 2021 02:02:11,802 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
14 Apr 2021 02:02:11,802 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
14 Apr 2021 02:02:11,802 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
14 Apr 2021 02:02:11,803 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
14 Apr 2021 02:02:11,803 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
14 Apr 2021 02:02:11,803 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
14 Apr 2021 02:02:11,803 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
14 Apr 2021 02:02:11,803 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
14 Apr 2021 02:02:11,803 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
14 Apr 2021 02:02:11,803 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
14 Apr 2021 02:02:11,803 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
14 Apr 2021 02:02:11,803 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
14 Apr 2021 02:02:11,803 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
14 Apr 2021 02:02:11,803 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
14 Apr 2021 02:02:11,803 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
14 Apr 2021 02:02:11,803 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
14 Apr 2021 02:02:11,803 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
14 Apr 2021 02:02:11,803 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
14 Apr 2021 02:02:11,803 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
14 Apr 2021 02:02:11,803 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
14 Apr 2021 02:02:11,803 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
14 Apr 2021 02:02:11,803 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
14 Apr 2021 02:02:11,803 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
14 Apr 2021 02:02:11,804 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
14 Apr 2021 02:02:11,804 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
14 Apr 2021 02:02:11,804 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
14 Apr 2021 02:02:11,804 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
14 Apr 2021 02:02:11,804 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
14 Apr 2021 02:02:11,804 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
14 Apr 2021 02:02:11,804 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
14 Apr 2021 02:02:11,804 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
14 Apr 2021 02:02:11,804 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
14 Apr 2021 02:02:11,804 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
14 Apr 2021 02:02:11,804 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
14 Apr 2021 02:02:11,804 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
14 Apr 2021 02:02:11,804 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
14 Apr 2021 02:02:11,804 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
14 Apr 2021 02:02:11,804 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
14 Apr 2021 02:02:11,804 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
14 Apr 2021 02:02:11,804 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
14 Apr 2021 02:02:11,804 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
14 Apr 2021 02:02:11,804 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
14 Apr 2021 02:02:11,804 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
14 Apr 2021 02:02:11,804 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
14 Apr 2021 02:02:11,804 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
14 Apr 2021 02:02:11,804 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
14 Apr 2021 02:02:11,804 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
14 Apr 2021 02:02:11,804 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
14 Apr 2021 02:02:11,804 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
14 Apr 2021 02:02:11,805 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
14 Apr 2021 02:02:11,805 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
14 Apr 2021 02:02:11,805 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
14 Apr 2021 02:02:11,805 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
14 Apr 2021 02:02:11,805 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
14 Apr 2021 02:02:11,805 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
14 Apr 2021 02:02:11,805 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
14 Apr 2021 02:02:11,805 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
14 Apr 2021 02:02:11,805 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
14 Apr 2021 02:02:11,805 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
14 Apr 2021 02:02:11,805 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
14 Apr 2021 02:02:11,805 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
14 Apr 2021 02:02:11,805 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
14 Apr 2021 02:02:11,805 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
14 Apr 2021 02:02:11,806 INFO : Finished creating study archive
14 Apr 2021 02:02:11,807 INFO : Successfully completed task 'org.labkey.study.pipeline.StudyReloadSourceTask'
14 Apr 2021 02:02:11,868 INFO : Starting to run task 'org.labkey.study.importer.StudyImportInitialTask' at location 'webserver'
14 Apr 2021 02:02:11,882 INFO : Reloading study from study.xml
14 Apr 2021 02:02:11,891 INFO : Loading top-level study properties (label, start/end date, description, etc.)
14 Apr 2021 02:02:12,287 INFO : Done importing top-level study properties (label, start/end date, description, etc.)
14 Apr 2021 02:02:12,360 INFO : Loading datasets manifest from datasets_manifest.xml
14 Apr 2021 02:02:12,365 INFO : Loading dataset schema from datasets_metadata.xml
14 Apr 2021 02:02:13,440 INFO : Successfully completed task 'org.labkey.study.importer.StudyImportInitialTask'
14 Apr 2021 02:02:13,512 INFO : Starting to run task 'org.labkey.study.pipeline.StudyImportDatasetTask' at location 'webserver'
14 Apr 2021 02:02:13,526 INFO : Start batch RedCap Integration.dataset
14 Apr 2021 02:02:13,591 INFO : demographics: Starting import from dataset001.tsv
14 Apr 2021 02:02:14,058 DEBUG: <next>
14 Apr 2021 02:02:14,059 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@1776f671>
14 Apr 2021 02:02:14,059 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@1776f671>
14 Apr 2021 02:02:14,059 DEBUG: </next>
14 Apr 2021 02:02:14,059 DEBUG: <next>
14 Apr 2021 02:02:14,060 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@1776f671>
14 Apr 2021 02:02:14,060 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@1776f671>
14 Apr 2021 02:02:14,060 DEBUG: </next>
14 Apr 2021 02:02:14,060 DEBUG: <next>
14 Apr 2021 02:02:14,060 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@1776f671>
14 Apr 2021 02:02:14,060 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@1776f671>
14 Apr 2021 02:02:14,060 DEBUG: </next>
14 Apr 2021 02:02:14,060 DEBUG: <next>
14 Apr 2021 02:02:14,061 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@1776f671>
14 Apr 2021 02:02:14,061 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@1776f671>
14 Apr 2021 02:02:14,061 DEBUG: </next>
14 Apr 2021 02:02:14,061 DEBUG: <next>
14 Apr 2021 02:02:14,061 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@1776f671>
14 Apr 2021 02:02:14,061 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@1776f671>
14 Apr 2021 02:02:14,061 DEBUG: </next>
14 Apr 2021 02:02:14,061 DEBUG: <next>
14 Apr 2021 02:02:14,061 DEBUG: <swap() - old: org.labkey.api.data.ParameterMapStatement@1776f671>
14 Apr 2021 02:02:14,066 DEBUG: </swap() - new: org.labkey.api.data.ParameterMapStatement@5a9c63ad>
14 Apr 2021 02:02:14,066 DEBUG: <close() on _queue>
14 Apr 2021 02:02:14,066 DEBUG: <close() on _queue>
14 Apr 2021 02:02:14,066 DEBUG: </close() on _queue>
14 Apr 2021 02:02:14,066 DEBUG: <join() on _asyncThread>
14 Apr 2021 02:02:14,070 DEBUG: <executeBatch() on org.labkey.api.data.ParameterMapStatement@1776f671>
14 Apr 2021 02:02:14,079 DEBUG: </executeBatch() on org.labkey.api.data.ParameterMapStatement@1776f671>
14 Apr 2021 02:02:14,079 DEBUG: exit background thread
14 Apr 2021 02:02:14,080 DEBUG: </join() on _asyncThread>
14 Apr 2021 02:02:14,080 DEBUG: </next>
14 Apr 2021 02:02:14,080 DEBUG: <close()>
14 Apr 2021 02:02:14,080 DEBUG: <close() on _data>
14 Apr 2021 02:02:14,080 DEBUG: </close() on _data>
14 Apr 2021 02:02:14,080 DEBUG: <close() on _queue>
14 Apr 2021 02:02:14,080 DEBUG: </close() on _queue>
14 Apr 2021 02:02:14,080 DEBUG: <join() on _asyncThread>
14 Apr 2021 02:02:14,080 DEBUG: </join() on _asyncThread>
14 Apr 2021 02:02:14,080 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@1776f671>
14 Apr 2021 02:02:14,080 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@1776f671>
14 Apr 2021 02:02:14,080 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@5a9c63ad>
14 Apr 2021 02:02:14,080 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@5a9c63ad>
14 Apr 2021 02:02:14,080 DEBUG: </close()>
14 Apr 2021 02:02:14,142 INFO : demographics: Processed 5 rows from dataset001.tsv
14 Apr 2021 02:02:14,142 INFO : demographics: Deleted 5 rows
14 Apr 2021 02:02:14,142 INFO : demographics: Merged 5 rows
14 Apr 2021 02:02:14,168 INFO : emergency_contact_info: Starting import from dataset002.tsv
14 Apr 2021 02:02:14,510 DEBUG: <next>
14 Apr 2021 02:02:14,511 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@305b04a8>
14 Apr 2021 02:02:14,511 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@305b04a8>
14 Apr 2021 02:02:14,511 DEBUG: </next>
14 Apr 2021 02:02:14,511 DEBUG: <next>
14 Apr 2021 02:02:14,511 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@305b04a8>
14 Apr 2021 02:02:14,511 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@305b04a8>
14 Apr 2021 02:02:14,511 DEBUG: </next>
14 Apr 2021 02:02:14,511 DEBUG: <next>
14 Apr 2021 02:02:14,511 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@305b04a8>
14 Apr 2021 02:02:14,511 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@305b04a8>
14 Apr 2021 02:02:14,511 DEBUG: </next>
14 Apr 2021 02:02:14,511 DEBUG: <next>
14 Apr 2021 02:02:14,512 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@305b04a8>
14 Apr 2021 02:02:14,512 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@305b04a8>
14 Apr 2021 02:02:14,512 DEBUG: </next>
14 Apr 2021 02:02:14,512 DEBUG: <next>
14 Apr 2021 02:02:14,512 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@305b04a8>
14 Apr 2021 02:02:14,512 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@305b04a8>
14 Apr 2021 02:02:14,512 DEBUG: </next>
14 Apr 2021 02:02:14,512 DEBUG: <next>
14 Apr 2021 02:02:14,512 DEBUG: <swap() - old: org.labkey.api.data.ParameterMapStatement@305b04a8>
14 Apr 2021 02:02:14,512 DEBUG: </swap() - new: org.labkey.api.data.ParameterMapStatement@74f4a2a5>
14 Apr 2021 02:02:14,512 DEBUG: <close() on _queue>
14 Apr 2021 02:02:14,512 DEBUG: <close() on _queue>
14 Apr 2021 02:02:14,512 DEBUG: </close() on _queue>
14 Apr 2021 02:02:14,512 DEBUG: <join() on _asyncThread>
14 Apr 2021 02:02:14,512 DEBUG: <executeBatch() on org.labkey.api.data.ParameterMapStatement@305b04a8>
14 Apr 2021 02:02:14,521 DEBUG: </executeBatch() on org.labkey.api.data.ParameterMapStatement@305b04a8>
14 Apr 2021 02:02:14,521 DEBUG: exit background thread
14 Apr 2021 02:02:14,521 DEBUG: </join() on _asyncThread>
14 Apr 2021 02:02:14,521 DEBUG: </next>
14 Apr 2021 02:02:14,521 DEBUG: <close()>
14 Apr 2021 02:02:14,522 DEBUG: <close() on _data>
14 Apr 2021 02:02:14,522 DEBUG: </close() on _data>
14 Apr 2021 02:02:14,522 DEBUG: <close() on _queue>
14 Apr 2021 02:02:14,522 DEBUG: </close() on _queue>
14 Apr 2021 02:02:14,522 DEBUG: <join() on _asyncThread>
14 Apr 2021 02:02:14,522 DEBUG: </join() on _asyncThread>
14 Apr 2021 02:02:14,522 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@305b04a8>
14 Apr 2021 02:02:14,522 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@305b04a8>
14 Apr 2021 02:02:14,522 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@74f4a2a5>
14 Apr 2021 02:02:14,522 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@74f4a2a5>
14 Apr 2021 02:02:14,522 DEBUG: </close()>
14 Apr 2021 02:02:14,542 INFO : emergency_contact_info: Processed 5 rows from dataset002.tsv
14 Apr 2021 02:02:14,542 INFO : emergency_contact_info: Deleted 5 rows
14 Apr 2021 02:02:14,542 INFO : emergency_contact_info: Merged 5 rows
14 Apr 2021 02:02:14,571 INFO : baseline_lab_data: Starting import from dataset003.tsv
14 Apr 2021 02:02:14,830 DEBUG: <next>
14 Apr 2021 02:02:14,831 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@29c93f98>
14 Apr 2021 02:02:14,831 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@29c93f98>
14 Apr 2021 02:02:14,831 DEBUG: </next>
14 Apr 2021 02:02:14,831 DEBUG: <next>
14 Apr 2021 02:02:14,831 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@29c93f98>
14 Apr 2021 02:02:14,831 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@29c93f98>
14 Apr 2021 02:02:14,831 DEBUG: </next>
14 Apr 2021 02:02:14,831 DEBUG: <next>
14 Apr 2021 02:02:14,831 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@29c93f98>
14 Apr 2021 02:02:14,831 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@29c93f98>
14 Apr 2021 02:02:14,832 DEBUG: </next>
14 Apr 2021 02:02:14,832 DEBUG: <next>
14 Apr 2021 02:02:14,832 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@29c93f98>
14 Apr 2021 02:02:14,832 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@29c93f98>
14 Apr 2021 02:02:14,832 DEBUG: </next>
14 Apr 2021 02:02:14,832 DEBUG: <next>
14 Apr 2021 02:02:14,832 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@29c93f98>
14 Apr 2021 02:02:14,832 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@29c93f98>
14 Apr 2021 02:02:14,832 DEBUG: </next>
14 Apr 2021 02:02:14,832 DEBUG: <next>
14 Apr 2021 02:02:14,832 DEBUG: <swap() - old: org.labkey.api.data.ParameterMapStatement@29c93f98>
14 Apr 2021 02:02:14,832 DEBUG: </swap() - new: org.labkey.api.data.ParameterMapStatement@1c68d24e>
14 Apr 2021 02:02:14,832 DEBUG: <close() on _queue>
14 Apr 2021 02:02:14,832 DEBUG: <close() on _queue>
14 Apr 2021 02:02:14,833 DEBUG: </close() on _queue>
14 Apr 2021 02:02:14,833 DEBUG: <join() on _asyncThread>
14 Apr 2021 02:02:14,833 DEBUG: <executeBatch() on org.labkey.api.data.ParameterMapStatement@29c93f98>
14 Apr 2021 02:02:14,841 DEBUG: </executeBatch() on org.labkey.api.data.ParameterMapStatement@29c93f98>
14 Apr 2021 02:02:14,841 DEBUG: exit background thread
14 Apr 2021 02:02:14,841 DEBUG: </join() on _asyncThread>
14 Apr 2021 02:02:14,841 DEBUG: </next>
14 Apr 2021 02:02:14,841 DEBUG: <close()>
14 Apr 2021 02:02:14,841 DEBUG: <close() on _data>
14 Apr 2021 02:02:14,841 DEBUG: </close() on _data>
14 Apr 2021 02:02:14,842 DEBUG: <close() on _queue>
14 Apr 2021 02:02:14,842 DEBUG: </close() on _queue>
14 Apr 2021 02:02:14,842 DEBUG: <join() on _asyncThread>
14 Apr 2021 02:02:14,842 DEBUG: </join() on _asyncThread>
14 Apr 2021 02:02:14,842 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@29c93f98>
14 Apr 2021 02:02:14,842 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@29c93f98>
14 Apr 2021 02:02:14,842 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@1c68d24e>
14 Apr 2021 02:02:14,842 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@1c68d24e>
14 Apr 2021 02:02:14,842 DEBUG: </close()>
14 Apr 2021 02:02:14,861 INFO : baseline_lab_data: Processed 5 rows from dataset003.tsv
14 Apr 2021 02:02:14,861 INFO : baseline_lab_data: Deleted 5 rows
14 Apr 2021 02:02:14,861 INFO : baseline_lab_data: Merged 5 rows
14 Apr 2021 02:02:14,890 INFO : visit_blood_workup: Starting import from dataset004.tsv
14 Apr 2021 02:02:14,938 DEBUG: <next>
14 Apr 2021 02:02:14,938 DEBUG: <close() on _queue>
14 Apr 2021 02:02:14,938 DEBUG: <close() on _queue>
14 Apr 2021 02:02:14,938 DEBUG: </close() on _queue>
14 Apr 2021 02:02:14,938 DEBUG: <join() on _asyncThread>
14 Apr 2021 02:02:14,938 DEBUG: exit background thread
14 Apr 2021 02:02:14,938 DEBUG: </join() on _asyncThread>
14 Apr 2021 02:02:14,939 DEBUG: </next>
14 Apr 2021 02:02:14,939 DEBUG: <close()>
14 Apr 2021 02:02:14,939 DEBUG: <close() on _data>
14 Apr 2021 02:02:14,939 DEBUG: </close() on _data>
14 Apr 2021 02:02:14,939 DEBUG: <close() on _queue>
14 Apr 2021 02:02:14,939 DEBUG: </close() on _queue>
14 Apr 2021 02:02:14,939 DEBUG: <join() on _asyncThread>
14 Apr 2021 02:02:14,939 DEBUG: </join() on _asyncThread>
14 Apr 2021 02:02:14,939 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@1ca9abb6>
14 Apr 2021 02:02:14,939 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@1ca9abb6>
14 Apr 2021 02:02:14,939 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@2689da91>
14 Apr 2021 02:02:14,939 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@2689da91>
14 Apr 2021 02:02:14,939 DEBUG: </close()>
14 Apr 2021 02:02:14,974 INFO : completion_data: Starting import from dataset005.tsv
14 Apr 2021 02:02:15,032 DEBUG: <next>
14 Apr 2021 02:02:15,033 DEBUG: <close() on _queue>
14 Apr 2021 02:02:15,034 DEBUG: <close() on _queue>
14 Apr 2021 02:02:15,035 DEBUG: </close() on _queue>
14 Apr 2021 02:02:15,035 DEBUG: <join() on _asyncThread>
14 Apr 2021 02:02:15,035 DEBUG: exit background thread
14 Apr 2021 02:02:15,035 DEBUG: </join() on _asyncThread>
14 Apr 2021 02:02:15,035 DEBUG: </next>
14 Apr 2021 02:02:15,036 DEBUG: <close()>
14 Apr 2021 02:02:15,036 DEBUG: <close() on _data>
14 Apr 2021 02:02:15,036 DEBUG: </close() on _data>
14 Apr 2021 02:02:15,036 DEBUG: <close() on _queue>
14 Apr 2021 02:02:15,036 DEBUG: </close() on _queue>
14 Apr 2021 02:02:15,036 DEBUG: <join() on _asyncThread>
14 Apr 2021 02:02:15,036 DEBUG: </join() on _asyncThread>
14 Apr 2021 02:02:15,036 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@2bbf7b12>
14 Apr 2021 02:02:15,036 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@2bbf7b12>
14 Apr 2021 02:02:15,036 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@2e0d9897>
14 Apr 2021 02:02:15,036 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@2e0d9897>
14 Apr 2021 02:02:15,036 DEBUG: </close()>
14 Apr 2021 02:02:15,073 INFO : completion_project_questionnaire: Starting import from dataset006.tsv
14 Apr 2021 02:02:15,152 DEBUG: <next>
14 Apr 2021 02:02:15,153 DEBUG: <close() on _queue>
14 Apr 2021 02:02:15,153 DEBUG: <close() on _queue>
14 Apr 2021 02:02:15,153 DEBUG: </close() on _queue>
14 Apr 2021 02:02:15,153 DEBUG: <join() on _asyncThread>
14 Apr 2021 02:02:15,153 DEBUG: exit background thread
14 Apr 2021 02:02:15,153 DEBUG: </join() on _asyncThread>
14 Apr 2021 02:02:15,153 DEBUG: </next>
14 Apr 2021 02:02:15,153 DEBUG: <close()>
14 Apr 2021 02:02:15,153 DEBUG: <close() on _data>
14 Apr 2021 02:02:15,153 DEBUG: </close() on _data>
14 Apr 2021 02:02:15,153 DEBUG: <close() on _queue>
14 Apr 2021 02:02:15,153 DEBUG: </close() on _queue>
14 Apr 2021 02:02:15,153 DEBUG: <join() on _asyncThread>
14 Apr 2021 02:02:15,153 DEBUG: </join() on _asyncThread>
14 Apr 2021 02:02:15,153 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@48c18f14>
14 Apr 2021 02:02:15,153 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@48c18f14>
14 Apr 2021 02:02:15,154 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@1c56e123>
14 Apr 2021 02:02:15,154 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@1c56e123>
14 Apr 2021 02:02:15,154 DEBUG: </close()>
14 Apr 2021 02:02:15,159 INFO : Finish batch RedCap Integration.dataset
14 Apr 2021 02:02:15,164 INFO : Updating participant visits
14 Apr 2021 02:02:15,164 INFO : Updating participants
14 Apr 2021 02:02:15,170 INFO : Updating participant visit table
14 Apr 2021 02:02:15,295 INFO : Updating visit table
14 Apr 2021 02:02:15,302 INFO : Updating cohorts
14 Apr 2021 02:02:15,302 INFO : Clearing participant visit caches
14 Apr 2021 02:02:15,305 INFO : Finished updating participants
14 Apr 2021 02:02:15,307 INFO : Successfully completed task 'org.labkey.study.pipeline.StudyImportDatasetTask'
14 Apr 2021 02:02:15,378 INFO : Starting to run task 'org.labkey.api.specimen.pipeline.StudyImportSpecimenTask' at location 'webserver'
14 Apr 2021 02:02:15,381 INFO : Successfully completed task 'org.labkey.api.specimen.pipeline.StudyImportSpecimenTask'
14 Apr 2021 02:02:15,435 INFO : Starting to run task 'org.labkey.study.importer.StudyImportFinalTask' at location 'webserver'
14 Apr 2021 02:02:15,483 INFO : Loading dataset cohort assignments
14 Apr 2021 02:02:15,483 INFO : Done importing dataset cohort assignments
14 Apr 2021 02:02:15,541 INFO : Loading lists
14 Apr 2021 02:02:15,563 INFO : Truncating list: vbw8
14 Apr 2021 02:24:45,792 INFO : Recreating list: vbw8
14 Apr 2021 02:24:46,079 INFO : Truncating list: cpq13
14 Apr 2021 02:24:46,231 INFO : Recreating list: cpq13
14 Apr 2021 02:24:46,356 INFO : Truncating list: sex
14 Apr 2021 02:24:46,460 INFO : Recreating list: sex
14 Apr 2021 02:24:46,556 INFO : Truncating list: withdraw_reason
14 Apr 2021 02:24:46,657 INFO : Recreating list: withdraw_reason
14 Apr 2021 02:24:46,746 INFO : Truncating list: cpq6
14 Apr 2021 02:24:46,866 INFO : Recreating list: cpq6
14 Apr 2021 02:24:46,965 INFO : Truncating list: vbw9
14 Apr 2021 02:24:47,074 INFO : Recreating list: vbw9
14 Apr 2021 02:24:47,158 INFO : Truncating list: cpq8
14 Apr 2021 02:24:47,247 INFO : Recreating list: cpq8
14 Apr 2021 02:24:47,322 INFO : Truncating list: cpq10
14 Apr 2021 02:24:47,413 INFO : Recreating list: cpq10
14 Apr 2021 02:24:47,496 INFO : Truncating list: vbw6
14 Apr 2021 02:24:47,591 INFO : Recreating list: vbw6
14 Apr 2021 02:24:47,668 INFO : Truncating list: cpq11
14 Apr 2021 02:24:47,756 INFO : Recreating list: cpq11
14 Apr 2021 02:24:47,841 INFO : Truncating list: vbw7
14 Apr 2021 02:24:47,934 INFO : Recreating list: vbw7
14 Apr 2021 02:24:48,078 INFO : Merged 5 row(s) into list: withdraw_reason
14 Apr 2021 02:24:48,131 INFO : Merged 3 row(s) into list: cpq13
14 Apr 2021 02:24:48,190 INFO : Merged 6 row(s) into list: cpq11
14 Apr 2021 02:24:48,258 INFO : Merged 4 row(s) into list: cpq10
14 Apr 2021 02:24:48,309 INFO : Merged 2 row(s) into list: sex
14 Apr 2021 02:24:48,352 INFO : Merged 2 row(s) into list: vbw9
14 Apr 2021 02:24:48,404 INFO : Merged 3 row(s) into list: vbw8
14 Apr 2021 02:24:48,449 INFO : Merged 3 row(s) into list: cpq8
14 Apr 2021 02:24:48,482 INFO : Merged 4 row(s) into list: vbw7
14 Apr 2021 02:24:48,527 INFO : Merged 5 row(s) into list: cpq6
14 Apr 2021 02:24:48,573 INFO : Merged 2 row(s) into list: vbw6
14 Apr 2021 02:24:48,577 INFO : 11 lists imported successfully
14 Apr 2021 02:24:48,577 INFO : Done importing lists
14 Apr 2021 02:24:48,588 INFO : Post-processing queries
14 Apr 2021 02:24:48,589 INFO : Skipping query validation.
14 Apr 2021 02:24:48,589 INFO : Done post-processing queries
14 Apr 2021 02:24:48,605 INFO : Successfully completed task 'org.labkey.study.importer.StudyImportFinalTask'