12 May 2021 02:20:49,242 INFO : Starting to run task 'org.labkey.study.pipeline.StudyReloadSourceTask' at location 'webserver'

12 May 2021 02:20:49,249 INFO : Starting REDCap export

12 May 2021 02:20:51,911 INFO : Creating study archive

12 May 2021 02:20:51,954 INFO : Writing dataset metadata

12 May 2021 02:20:52,104 INFO : Creating column lookups

12 May 2021 02:20:52,855 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.

12 May 2021 02:20:52,855 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.

12 May 2021 02:20:52,855 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.

12 May 2021 02:20:52,855 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.

12 May 2021 02:20:52,855 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.

12 May 2021 02:20:52,855 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.

12 May 2021 02:20:52,855 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.

12 May 2021 02:20:52,855 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.

12 May 2021 02:20:52,855 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.

12 May 2021 02:20:52,855 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.

12 May 2021 02:20:52,855 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.

12 May 2021 02:20:52,855 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.

12 May 2021 02:20:52,855 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.

12 May 2021 02:20:52,855 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.

12 May 2021 02:20:52,856 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.

12 May 2021 02:20:52,856 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.

12 May 2021 02:20:52,856 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.

12 May 2021 02:20:52,856 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.

12 May 2021 02:20:52,856 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.

12 May 2021 02:20:52,856 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.

12 May 2021 02:20:52,856 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.

12 May 2021 02:20:52,856 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.

12 May 2021 02:20:52,856 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.

12 May 2021 02:20:52,856 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.

12 May 2021 02:20:52,856 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.

12 May 2021 02:20:52,856 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.

12 May 2021 02:20:52,856 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.

12 May 2021 02:20:52,856 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.

12 May 2021 02:20:52,856 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.

12 May 2021 02:20:52,856 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.

12 May 2021 02:20:52,856 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.

12 May 2021 02:20:52,856 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.

12 May 2021 02:20:52,856 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.

12 May 2021 02:20:52,856 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.

12 May 2021 02:20:52,856 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.

12 May 2021 02:20:52,856 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.

12 May 2021 02:20:52,856 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.

12 May 2021 02:20:52,856 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.

12 May 2021 02:20:52,856 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.

12 May 2021 02:20:52,856 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.

12 May 2021 02:20:52,856 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.

12 May 2021 02:20:52,856 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.

12 May 2021 02:20:52,857 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.

12 May 2021 02:20:52,857 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.

12 May 2021 02:20:52,857 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.

12 May 2021 02:20:52,857 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.

12 May 2021 02:20:52,857 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.

12 May 2021 02:20:52,857 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.

12 May 2021 02:20:52,857 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.

12 May 2021 02:20:52,857 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.

12 May 2021 02:20:52,857 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.

12 May 2021 02:20:52,857 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.

12 May 2021 02:20:52,857 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.

12 May 2021 02:20:52,857 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.

12 May 2021 02:20:52,857 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.

12 May 2021 02:20:52,857 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.

12 May 2021 02:20:52,857 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.

12 May 2021 02:20:52,857 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.

12 May 2021 02:20:52,857 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.

12 May 2021 02:20:52,857 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.

12 May 2021 02:20:52,857 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.

12 May 2021 02:20:52,857 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.

12 May 2021 02:20:52,857 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.

12 May 2021 02:20:52,857 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.

12 May 2021 02:20:52,857 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.

12 May 2021 02:20:52,857 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.

12 May 2021 02:20:52,857 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.

12 May 2021 02:20:52,857 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.

12 May 2021 02:20:52,857 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.

12 May 2021 02:20:52,858 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.

12 May 2021 02:20:52,858 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.

12 May 2021 02:20:52,858 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.

12 May 2021 02:20:52,858 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.

12 May 2021 02:20:52,858 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.

12 May 2021 02:20:52,858 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.

12 May 2021 02:20:52,858 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.

12 May 2021 02:20:52,858 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.

12 May 2021 02:20:52,858 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.

12 May 2021 02:20:52,858 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.

12 May 2021 02:20:52,858 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.

12 May 2021 02:20:52,858 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.

12 May 2021 02:20:52,858 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.

12 May 2021 02:20:52,858 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.

12 May 2021 02:20:52,858 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.

12 May 2021 02:20:52,858 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.

12 May 2021 02:20:52,858 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.

12 May 2021 02:20:52,858 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.

12 May 2021 02:20:52,858 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.

12 May 2021 02:20:52,858 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.

12 May 2021 02:20:52,858 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.

12 May 2021 02:20:52,858 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.

12 May 2021 02:20:52,858 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.

12 May 2021 02:20:52,858 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.

12 May 2021 02:20:52,858 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.

12 May 2021 02:20:52,859 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.

12 May 2021 02:20:52,859 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.

12 May 2021 02:20:52,859 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.

12 May 2021 02:20:52,859 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.

12 May 2021 02:20:52,859 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.

12 May 2021 02:20:52,859 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.

12 May 2021 02:20:52,859 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.

12 May 2021 02:20:52,859 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.

12 May 2021 02:20:52,859 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.

12 May 2021 02:20:52,859 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.

12 May 2021 02:20:52,859 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.

12 May 2021 02:20:52,859 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.

12 May 2021 02:20:52,859 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.

12 May 2021 02:20:52,859 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.

12 May 2021 02:20:52,859 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.

12 May 2021 02:20:52,859 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.

12 May 2021 02:20:52,859 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.

12 May 2021 02:20:52,859 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.

12 May 2021 02:20:52,859 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.

12 May 2021 02:20:52,862 INFO : Finished creating study archive

12 May 2021 02:20:52,862 INFO : Successfully completed task 'org.labkey.study.pipeline.StudyReloadSourceTask'

12 May 2021 02:20:52,923 INFO : Starting to run task 'org.labkey.study.importer.StudyImportInitialTask' at location 'webserver'

12 May 2021 02:20:52,928 INFO : Reloading study from study.xml

12 May 2021 02:20:53,051 INFO : Loading top-level study properties (label, start/end date, description, etc.)

12 May 2021 02:20:53,510 INFO : Done importing top-level study properties (label, start/end date, description, etc.)

12 May 2021 02:20:53,568 INFO : Loading datasets manifest from datasets_manifest.xml

12 May 2021 02:20:53,573 INFO : Loading dataset schema from datasets_metadata.xml

12 May 2021 02:20:54,763 INFO : Successfully completed task 'org.labkey.study.importer.StudyImportInitialTask'

12 May 2021 02:20:54,806 INFO : Starting to run task 'org.labkey.study.pipeline.StudyImportDatasetTask' at location 'webserver'

12 May 2021 02:20:54,818 INFO : Start batch RedCap Integration.dataset

12 May 2021 02:20:54,878 INFO : demographics: Starting import from dataset001.tsv

12 May 2021 02:20:58,136 INFO : demographics: Processed 5 rows from dataset001.tsv

12 May 2021 02:20:58,136 INFO : demographics: Deleted 5 rows

12 May 2021 02:20:58,136 INFO : demographics: Merged 5 rows

12 May 2021 02:20:58,191 INFO : emergency_contact_info: Starting import from dataset002.tsv

12 May 2021 02:20:59,315 INFO : emergency_contact_info: Processed 5 rows from dataset002.tsv

12 May 2021 02:20:59,315 INFO : emergency_contact_info: Deleted 5 rows

12 May 2021 02:20:59,315 INFO : emergency_contact_info: Merged 5 rows

12 May 2021 02:20:59,344 INFO : baseline_lab_data: Starting import from dataset003.tsv

12 May 2021 02:20:59,887 INFO : baseline_lab_data: Processed 5 rows from dataset003.tsv

12 May 2021 02:20:59,888 INFO : baseline_lab_data: Deleted 5 rows

12 May 2021 02:20:59,888 INFO : baseline_lab_data: Merged 5 rows

12 May 2021 02:20:59,924 INFO : visit_blood_workup: Starting import from dataset004.tsv

12 May 2021 02:21:00,000 INFO : completion_data: Starting import from dataset005.tsv

12 May 2021 02:21:00,085 INFO : completion_project_questionnaire: Starting import from dataset006.tsv

12 May 2021 02:21:00,158 INFO : Finish batch RedCap Integration.dataset

12 May 2021 02:21:00,163 INFO : Updating participant visits

12 May 2021 02:21:00,163 INFO : Updating participants

12 May 2021 02:21:00,169 INFO : Updating participant visit table

12 May 2021 02:21:00,342 INFO : Updating visit table

12 May 2021 02:21:00,346 INFO : Updating cohorts

12 May 2021 02:21:00,346 INFO : Clearing participant visit caches

12 May 2021 02:21:00,348 INFO : Finished updating participants

12 May 2021 02:21:00,350 INFO : Successfully completed task 'org.labkey.study.pipeline.StudyImportDatasetTask'

12 May 2021 02:21:00,463 INFO : Starting to run task 'org.labkey.api.specimen.pipeline.StudyImportSpecimenTask' at location 'webserver'

12 May 2021 02:21:00,465 INFO : Successfully completed task 'org.labkey.api.specimen.pipeline.StudyImportSpecimenTask'

12 May 2021 02:21:00,585 INFO : Starting to run task 'org.labkey.study.importer.StudyImportFinalTask' at location 'webserver'

12 May 2021 02:21:00,642 INFO : Loading dataset cohort assignments

12 May 2021 02:21:00,643 INFO : Done importing dataset cohort assignments

12 May 2021 02:21:00,714 INFO : Loading lists

12 May 2021 02:21:00,730 INFO : Truncating list: vbw8

12 May 2021 02:50:41,906 INFO : Recreating list: vbw8

12 May 2021 02:50:42,327 INFO : Truncating list: cpq13

12 May 2021 02:50:42,447 INFO : Recreating list: cpq13

12 May 2021 02:50:42,539 INFO : Truncating list: sex

12 May 2021 02:50:42,633 INFO : Recreating list: sex

12 May 2021 02:50:42,730 INFO : Truncating list: withdraw_reason

12 May 2021 02:50:42,815 INFO : Recreating list: withdraw_reason

12 May 2021 02:50:42,953 INFO : Truncating list: cpq6

12 May 2021 02:50:43,065 INFO : Recreating list: cpq6

12 May 2021 02:50:43,194 INFO : Truncating list: vbw9

12 May 2021 02:50:43,405 INFO : Recreating list: vbw9

12 May 2021 02:50:43,540 INFO : Truncating list: cpq8

12 May 2021 02:50:43,716 INFO : Recreating list: cpq8

12 May 2021 02:50:44,062 INFO : Truncating list: cpq10

12 May 2021 02:50:44,157 INFO : Recreating list: cpq10

12 May 2021 02:50:44,237 INFO : Truncating list: vbw6

12 May 2021 02:50:44,323 INFO : Recreating list: vbw6

12 May 2021 02:50:44,402 INFO : Truncating list: cpq11

12 May 2021 02:50:44,496 INFO : Recreating list: cpq11

12 May 2021 02:50:44,598 INFO : Truncating list: vbw7

12 May 2021 02:50:44,701 INFO : Recreating list: vbw7

12 May 2021 02:50:44,899 INFO : Merged 5 row(s) into list: withdraw_reason

12 May 2021 02:50:44,945 INFO : Merged 3 row(s) into list: cpq13

12 May 2021 02:50:44,987 INFO : Merged 6 row(s) into list: cpq11

12 May 2021 02:50:45,078 INFO : Merged 4 row(s) into list: cpq10

12 May 2021 02:50:45,126 INFO : Merged 2 row(s) into list: sex

12 May 2021 02:50:45,162 INFO : Merged 2 row(s) into list: vbw9

12 May 2021 02:50:45,210 INFO : Merged 3 row(s) into list: vbw8

12 May 2021 02:50:45,248 INFO : Merged 3 row(s) into list: cpq8

12 May 2021 02:50:45,266 INFO : Merged 4 row(s) into list: vbw7

12 May 2021 02:50:45,309 INFO : Merged 5 row(s) into list: cpq6

12 May 2021 02:50:45,348 INFO : Merged 2 row(s) into list: vbw6

12 May 2021 02:50:45,352 INFO : 11 lists imported successfully

12 May 2021 02:50:45,352 INFO : Done importing lists

12 May 2021 02:50:45,358 INFO : Post-processing queries

12 May 2021 02:50:45,358 INFO : Skipping query validation.

12 May 2021 02:50:45,358 INFO : Done post-processing queries

12 May 2021 02:50:45,369 INFO : Successfully completed task 'org.labkey.study.importer.StudyImportFinalTask'