28 Apr 2021 02:02:14,663 INFO : Starting to run task 'org.labkey.study.pipeline.StudyReloadSourceTask' at location 'webserver'
28 Apr 2021 02:02:14,671 INFO : Starting REDCap export
28 Apr 2021 02:02:15,711 INFO : Creating study archive
28 Apr 2021 02:02:15,752 INFO : Writing dataset metadata
28 Apr 2021 02:02:15,855 INFO : Creating column lookups
28 Apr 2021 02:02:18,085 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
28 Apr 2021 02:02:18,085 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
28 Apr 2021 02:02:18,085 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
28 Apr 2021 02:02:18,085 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
28 Apr 2021 02:02:18,085 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
28 Apr 2021 02:02:18,085 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
28 Apr 2021 02:02:18,085 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
28 Apr 2021 02:02:18,085 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
28 Apr 2021 02:02:18,086 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
28 Apr 2021 02:02:18,086 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
28 Apr 2021 02:02:18,086 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
28 Apr 2021 02:02:18,086 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
28 Apr 2021 02:02:18,086 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
28 Apr 2021 02:02:18,086 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
28 Apr 2021 02:02:18,086 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
28 Apr 2021 02:02:18,086 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
28 Apr 2021 02:02:18,086 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
28 Apr 2021 02:02:18,086 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
28 Apr 2021 02:02:18,086 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
28 Apr 2021 02:02:18,086 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
28 Apr 2021 02:02:18,086 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
28 Apr 2021 02:02:18,086 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
28 Apr 2021 02:02:18,086 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
28 Apr 2021 02:02:18,086 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
28 Apr 2021 02:02:18,086 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
28 Apr 2021 02:02:18,086 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
28 Apr 2021 02:02:18,086 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
28 Apr 2021 02:02:18,086 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
28 Apr 2021 02:02:18,086 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
28 Apr 2021 02:02:18,086 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
28 Apr 2021 02:02:18,087 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
28 Apr 2021 02:02:18,087 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
28 Apr 2021 02:02:18,087 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
28 Apr 2021 02:02:18,087 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
28 Apr 2021 02:02:18,087 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
28 Apr 2021 02:02:18,087 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
28 Apr 2021 02:02:18,087 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
28 Apr 2021 02:02:18,087 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
28 Apr 2021 02:02:18,087 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
28 Apr 2021 02:02:18,087 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
28 Apr 2021 02:02:18,087 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
28 Apr 2021 02:02:18,087 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
28 Apr 2021 02:02:18,087 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
28 Apr 2021 02:02:18,087 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
28 Apr 2021 02:02:18,087 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
28 Apr 2021 02:02:18,087 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
28 Apr 2021 02:02:18,087 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
28 Apr 2021 02:02:18,087 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
28 Apr 2021 02:02:18,087 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
28 Apr 2021 02:02:18,087 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
28 Apr 2021 02:02:18,087 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
28 Apr 2021 02:02:18,087 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
28 Apr 2021 02:02:18,087 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
28 Apr 2021 02:02:18,087 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
28 Apr 2021 02:02:18,087 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
28 Apr 2021 02:02:18,087 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
28 Apr 2021 02:02:18,087 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
28 Apr 2021 02:02:18,088 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
28 Apr 2021 02:02:18,088 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
28 Apr 2021 02:02:18,088 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
28 Apr 2021 02:02:18,088 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
28 Apr 2021 02:02:18,088 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
28 Apr 2021 02:02:18,088 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
28 Apr 2021 02:02:18,088 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
28 Apr 2021 02:02:18,088 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
28 Apr 2021 02:02:18,088 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
28 Apr 2021 02:02:18,088 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
28 Apr 2021 02:02:18,088 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
28 Apr 2021 02:02:18,088 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
28 Apr 2021 02:02:18,088 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
28 Apr 2021 02:02:18,088 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
28 Apr 2021 02:02:18,088 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
28 Apr 2021 02:02:18,088 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
28 Apr 2021 02:02:18,088 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
28 Apr 2021 02:02:18,088 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
28 Apr 2021 02:02:18,088 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
28 Apr 2021 02:02:18,088 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
28 Apr 2021 02:02:18,088 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
28 Apr 2021 02:02:18,088 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
28 Apr 2021 02:02:18,088 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
28 Apr 2021 02:02:18,088 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
28 Apr 2021 02:02:18,088 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
28 Apr 2021 02:02:18,088 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
28 Apr 2021 02:02:18,088 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
28 Apr 2021 02:02:18,088 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
28 Apr 2021 02:02:18,089 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
28 Apr 2021 02:02:18,089 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
28 Apr 2021 02:02:18,089 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
28 Apr 2021 02:02:18,089 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
28 Apr 2021 02:02:18,089 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
28 Apr 2021 02:02:18,089 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
28 Apr 2021 02:02:18,089 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
28 Apr 2021 02:02:18,089 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
28 Apr 2021 02:02:18,089 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
28 Apr 2021 02:02:18,089 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
28 Apr 2021 02:02:18,089 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
28 Apr 2021 02:02:18,089 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
28 Apr 2021 02:02:18,089 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
28 Apr 2021 02:02:18,089 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
28 Apr 2021 02:02:18,089 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
28 Apr 2021 02:02:18,089 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
28 Apr 2021 02:02:18,089 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
28 Apr 2021 02:02:18,089 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
28 Apr 2021 02:02:18,089 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
28 Apr 2021 02:02:18,089 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
28 Apr 2021 02:02:18,089 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
28 Apr 2021 02:02:18,090 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
28 Apr 2021 02:02:18,090 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
28 Apr 2021 02:02:18,090 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
28 Apr 2021 02:02:18,090 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
28 Apr 2021 02:02:18,090 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
28 Apr 2021 02:02:18,090 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
28 Apr 2021 02:02:18,090 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
28 Apr 2021 02:02:18,092 INFO : Finished creating study archive
28 Apr 2021 02:02:18,092 INFO : Successfully completed task 'org.labkey.study.pipeline.StudyReloadSourceTask'
28 Apr 2021 02:02:18,150 INFO : Starting to run task 'org.labkey.study.importer.StudyImportInitialTask' at location 'webserver'
28 Apr 2021 02:02:18,158 INFO : Reloading study from study.xml
28 Apr 2021 02:02:18,166 INFO : Loading top-level study properties (label, start/end date, description, etc.)
28 Apr 2021 02:02:18,527 INFO : Done importing top-level study properties (label, start/end date, description, etc.)
28 Apr 2021 02:02:18,585 INFO : Loading datasets manifest from datasets_manifest.xml
28 Apr 2021 02:02:18,589 INFO : Loading dataset schema from datasets_metadata.xml
28 Apr 2021 02:02:19,332 INFO : Successfully completed task 'org.labkey.study.importer.StudyImportInitialTask'
28 Apr 2021 02:02:19,362 INFO : Starting to run task 'org.labkey.study.pipeline.StudyImportDatasetTask' at location 'webserver'
28 Apr 2021 02:02:19,370 INFO : Start batch RedCap Integration.dataset
28 Apr 2021 02:02:19,411 INFO : demographics: Starting import from dataset001.tsv
28 Apr 2021 02:02:19,656 DEBUG: <next>
28 Apr 2021 02:02:19,657 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@72ae34c6>
28 Apr 2021 02:02:19,657 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@72ae34c6>
28 Apr 2021 02:02:19,658 DEBUG: </next>
28 Apr 2021 02:02:19,658 DEBUG: <next>
28 Apr 2021 02:02:19,658 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@72ae34c6>
28 Apr 2021 02:02:19,659 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@72ae34c6>
28 Apr 2021 02:02:19,659 DEBUG: </next>
28 Apr 2021 02:02:19,659 DEBUG: <next>
28 Apr 2021 02:02:19,660 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@72ae34c6>
28 Apr 2021 02:02:19,660 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@72ae34c6>
28 Apr 2021 02:02:19,660 DEBUG: </next>
28 Apr 2021 02:02:19,660 DEBUG: <next>
28 Apr 2021 02:02:19,660 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@72ae34c6>
28 Apr 2021 02:02:19,660 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@72ae34c6>
28 Apr 2021 02:02:19,660 DEBUG: </next>
28 Apr 2021 02:02:19,661 DEBUG: <next>
28 Apr 2021 02:02:19,661 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@72ae34c6>
28 Apr 2021 02:02:19,661 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@72ae34c6>
28 Apr 2021 02:02:19,661 DEBUG: </next>
28 Apr 2021 02:02:19,661 DEBUG: <next>
28 Apr 2021 02:02:19,661 DEBUG: <swap() - old: org.labkey.api.data.ParameterMapStatement@72ae34c6>
28 Apr 2021 02:02:19,661 DEBUG: </swap() - new: org.labkey.api.data.ParameterMapStatement@38fb3db1>
28 Apr 2021 02:02:19,661 DEBUG: <executeBatch() on org.labkey.api.data.ParameterMapStatement@72ae34c6>
28 Apr 2021 02:02:19,661 DEBUG: <close() on _queue>
28 Apr 2021 02:02:19,661 DEBUG: <close() on _queue>
28 Apr 2021 02:02:19,661 DEBUG: </close() on _queue>
28 Apr 2021 02:02:19,661 DEBUG: <join() on _asyncThread>
28 Apr 2021 02:02:19,669 DEBUG: </executeBatch() on org.labkey.api.data.ParameterMapStatement@72ae34c6>
28 Apr 2021 02:02:19,669 DEBUG: exit background thread
28 Apr 2021 02:02:19,670 DEBUG: </join() on _asyncThread>
28 Apr 2021 02:02:19,670 DEBUG: </next>
28 Apr 2021 02:02:19,670 DEBUG: <close()>
28 Apr 2021 02:02:19,670 DEBUG: <close() on _data>
28 Apr 2021 02:02:19,670 DEBUG: </close() on _data>
28 Apr 2021 02:02:19,670 DEBUG: <close() on _queue>
28 Apr 2021 02:02:19,670 DEBUG: </close() on _queue>
28 Apr 2021 02:02:19,670 DEBUG: <join() on _asyncThread>
28 Apr 2021 02:02:19,670 DEBUG: </join() on _asyncThread>
28 Apr 2021 02:02:19,670 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@72ae34c6>
28 Apr 2021 02:02:19,670 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@72ae34c6>
28 Apr 2021 02:02:19,670 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@38fb3db1>
28 Apr 2021 02:02:19,671 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@38fb3db1>
28 Apr 2021 02:02:19,671 DEBUG: </close()>
28 Apr 2021 02:02:19,723 INFO : demographics: Processed 5 rows from dataset001.tsv
28 Apr 2021 02:02:19,723 INFO : demographics: Deleted 5 rows
28 Apr 2021 02:02:19,723 INFO : demographics: Merged 5 rows
28 Apr 2021 02:02:19,758 INFO : emergency_contact_info: Starting import from dataset002.tsv
28 Apr 2021 02:02:19,932 DEBUG: <next>
28 Apr 2021 02:02:19,932 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@62da0db0>
28 Apr 2021 02:02:19,932 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@62da0db0>
28 Apr 2021 02:02:19,932 DEBUG: </next>
28 Apr 2021 02:02:19,932 DEBUG: <next>
28 Apr 2021 02:02:19,932 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@62da0db0>
28 Apr 2021 02:02:19,932 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@62da0db0>
28 Apr 2021 02:02:19,932 DEBUG: </next>
28 Apr 2021 02:02:19,932 DEBUG: <next>
28 Apr 2021 02:02:19,933 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@62da0db0>
28 Apr 2021 02:02:19,933 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@62da0db0>
28 Apr 2021 02:02:19,933 DEBUG: </next>
28 Apr 2021 02:02:19,933 DEBUG: <next>
28 Apr 2021 02:02:19,933 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@62da0db0>
28 Apr 2021 02:02:19,933 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@62da0db0>
28 Apr 2021 02:02:19,933 DEBUG: </next>
28 Apr 2021 02:02:19,933 DEBUG: <next>
28 Apr 2021 02:02:19,933 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@62da0db0>
28 Apr 2021 02:02:19,933 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@62da0db0>
28 Apr 2021 02:02:19,933 DEBUG: </next>
28 Apr 2021 02:02:19,933 DEBUG: <next>
28 Apr 2021 02:02:19,933 DEBUG: <swap() - old: org.labkey.api.data.ParameterMapStatement@62da0db0>
28 Apr 2021 02:02:19,933 DEBUG: </swap() - new: org.labkey.api.data.ParameterMapStatement@472770d1>
28 Apr 2021 02:02:19,933 DEBUG: <close() on _queue>
28 Apr 2021 02:02:19,933 DEBUG: <close() on _queue>
28 Apr 2021 02:02:19,933 DEBUG: </close() on _queue>
28 Apr 2021 02:02:19,933 DEBUG: <executeBatch() on org.labkey.api.data.ParameterMapStatement@62da0db0>
28 Apr 2021 02:02:19,933 DEBUG: <join() on _asyncThread>
28 Apr 2021 02:02:19,942 DEBUG: </executeBatch() on org.labkey.api.data.ParameterMapStatement@62da0db0>
28 Apr 2021 02:02:19,942 DEBUG: exit background thread
28 Apr 2021 02:02:19,942 DEBUG: </join() on _asyncThread>
28 Apr 2021 02:02:19,942 DEBUG: </next>
28 Apr 2021 02:02:19,942 DEBUG: <close()>
28 Apr 2021 02:02:19,942 DEBUG: <close() on _data>
28 Apr 2021 02:02:19,942 DEBUG: </close() on _data>
28 Apr 2021 02:02:19,942 DEBUG: <close() on _queue>
28 Apr 2021 02:02:19,942 DEBUG: </close() on _queue>
28 Apr 2021 02:02:19,942 DEBUG: <join() on _asyncThread>
28 Apr 2021 02:02:19,942 DEBUG: </join() on _asyncThread>
28 Apr 2021 02:02:19,942 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@62da0db0>
28 Apr 2021 02:02:19,942 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@62da0db0>
28 Apr 2021 02:02:19,942 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@472770d1>
28 Apr 2021 02:02:19,942 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@472770d1>
28 Apr 2021 02:02:19,943 DEBUG: </close()>
28 Apr 2021 02:02:19,961 INFO : emergency_contact_info: Processed 5 rows from dataset002.tsv
28 Apr 2021 02:02:19,961 INFO : emergency_contact_info: Deleted 5 rows
28 Apr 2021 02:02:19,961 INFO : emergency_contact_info: Merged 5 rows
28 Apr 2021 02:02:19,989 INFO : baseline_lab_data: Starting import from dataset003.tsv
28 Apr 2021 02:02:20,181 DEBUG: <next>
28 Apr 2021 02:02:20,182 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@6dace678>
28 Apr 2021 02:02:20,182 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@6dace678>
28 Apr 2021 02:02:20,182 DEBUG: </next>
28 Apr 2021 02:02:20,182 DEBUG: <next>
28 Apr 2021 02:02:20,182 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@6dace678>
28 Apr 2021 02:02:20,182 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@6dace678>
28 Apr 2021 02:02:20,182 DEBUG: </next>
28 Apr 2021 02:02:20,182 DEBUG: <next>
28 Apr 2021 02:02:20,182 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@6dace678>
28 Apr 2021 02:02:20,182 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@6dace678>
28 Apr 2021 02:02:20,182 DEBUG: </next>
28 Apr 2021 02:02:20,182 DEBUG: <next>
28 Apr 2021 02:02:20,182 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@6dace678>
28 Apr 2021 02:02:20,182 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@6dace678>
28 Apr 2021 02:02:20,182 DEBUG: </next>
28 Apr 2021 02:02:20,182 DEBUG: <next>
28 Apr 2021 02:02:20,182 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@6dace678>
28 Apr 2021 02:02:20,182 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@6dace678>
28 Apr 2021 02:02:20,182 DEBUG: </next>
28 Apr 2021 02:02:20,182 DEBUG: <next>
28 Apr 2021 02:02:20,182 DEBUG: <swap() - old: org.labkey.api.data.ParameterMapStatement@6dace678>
28 Apr 2021 02:02:20,182 DEBUG: </swap() - new: org.labkey.api.data.ParameterMapStatement@3205c109>
28 Apr 2021 02:02:20,183 DEBUG: <close() on _queue>
28 Apr 2021 02:02:20,183 DEBUG: <close() on _queue>
28 Apr 2021 02:02:20,183 DEBUG: <executeBatch() on org.labkey.api.data.ParameterMapStatement@6dace678>
28 Apr 2021 02:02:20,183 DEBUG: </close() on _queue>
28 Apr 2021 02:02:20,183 DEBUG: <join() on _asyncThread>
28 Apr 2021 02:02:20,190 DEBUG: </executeBatch() on org.labkey.api.data.ParameterMapStatement@6dace678>
28 Apr 2021 02:02:20,190 DEBUG: exit background thread
28 Apr 2021 02:02:20,190 DEBUG: </join() on _asyncThread>
28 Apr 2021 02:02:20,190 DEBUG: </next>
28 Apr 2021 02:02:20,191 DEBUG: <close()>
28 Apr 2021 02:02:20,191 DEBUG: <close() on _data>
28 Apr 2021 02:02:20,191 DEBUG: </close() on _data>
28 Apr 2021 02:02:20,191 DEBUG: <close() on _queue>
28 Apr 2021 02:02:20,191 DEBUG: </close() on _queue>
28 Apr 2021 02:02:20,191 DEBUG: <join() on _asyncThread>
28 Apr 2021 02:02:20,191 DEBUG: </join() on _asyncThread>
28 Apr 2021 02:02:20,191 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@6dace678>
28 Apr 2021 02:02:20,191 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@6dace678>
28 Apr 2021 02:02:20,191 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@3205c109>
28 Apr 2021 02:02:20,191 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@3205c109>
28 Apr 2021 02:02:20,191 DEBUG: </close()>
28 Apr 2021 02:02:20,208 INFO : baseline_lab_data: Processed 5 rows from dataset003.tsv
28 Apr 2021 02:02:20,208 INFO : baseline_lab_data: Deleted 5 rows
28 Apr 2021 02:02:20,208 INFO : baseline_lab_data: Merged 5 rows
28 Apr 2021 02:02:20,233 INFO : visit_blood_workup: Starting import from dataset004.tsv
28 Apr 2021 02:02:20,275 DEBUG: <next>
28 Apr 2021 02:02:20,276 DEBUG: <close() on _queue>
28 Apr 2021 02:02:20,276 DEBUG: <close() on _queue>
28 Apr 2021 02:02:20,276 DEBUG: </close() on _queue>
28 Apr 2021 02:02:20,276 DEBUG: <join() on _asyncThread>
28 Apr 2021 02:02:20,276 DEBUG: exit background thread
28 Apr 2021 02:02:20,276 DEBUG: </join() on _asyncThread>
28 Apr 2021 02:02:20,276 DEBUG: </next>
28 Apr 2021 02:02:20,276 DEBUG: <close()>
28 Apr 2021 02:02:20,276 DEBUG: <close() on _data>
28 Apr 2021 02:02:20,276 DEBUG: </close() on _data>
28 Apr 2021 02:02:20,276 DEBUG: <close() on _queue>
28 Apr 2021 02:02:20,276 DEBUG: </close() on _queue>
28 Apr 2021 02:02:20,276 DEBUG: <join() on _asyncThread>
28 Apr 2021 02:02:20,276 DEBUG: </join() on _asyncThread>
28 Apr 2021 02:02:20,276 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@70cd244>
28 Apr 2021 02:02:20,276 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@70cd244>
28 Apr 2021 02:02:20,276 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@674fadeb>
28 Apr 2021 02:02:20,276 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@674fadeb>
28 Apr 2021 02:02:20,276 DEBUG: </close()>
28 Apr 2021 02:02:20,306 INFO : completion_data: Starting import from dataset005.tsv
28 Apr 2021 02:02:20,330 DEBUG: <next>
28 Apr 2021 02:02:20,330 DEBUG: <close() on _queue>
28 Apr 2021 02:02:20,330 DEBUG: <close() on _queue>
28 Apr 2021 02:02:20,330 DEBUG: </close() on _queue>
28 Apr 2021 02:02:20,330 DEBUG: <join() on _asyncThread>
28 Apr 2021 02:02:20,330 DEBUG: exit background thread
28 Apr 2021 02:02:20,330 DEBUG: </join() on _asyncThread>
28 Apr 2021 02:02:20,330 DEBUG: </next>
28 Apr 2021 02:02:20,330 DEBUG: <close()>
28 Apr 2021 02:02:20,330 DEBUG: <close() on _data>
28 Apr 2021 02:02:20,330 DEBUG: </close() on _data>
28 Apr 2021 02:02:20,330 DEBUG: <close() on _queue>
28 Apr 2021 02:02:20,330 DEBUG: </close() on _queue>
28 Apr 2021 02:02:20,330 DEBUG: <join() on _asyncThread>
28 Apr 2021 02:02:20,330 DEBUG: </join() on _asyncThread>
28 Apr 2021 02:02:20,330 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@46871f03>
28 Apr 2021 02:02:20,330 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@46871f03>
28 Apr 2021 02:02:20,330 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@1a5de6db>
28 Apr 2021 02:02:20,330 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@1a5de6db>
28 Apr 2021 02:02:20,330 DEBUG: </close()>
28 Apr 2021 02:02:20,363 INFO : completion_project_questionnaire: Starting import from dataset006.tsv
28 Apr 2021 02:02:20,411 DEBUG: <next>
28 Apr 2021 02:02:20,411 DEBUG: <close() on _queue>
28 Apr 2021 02:02:20,411 DEBUG: <close() on _queue>
28 Apr 2021 02:02:20,411 DEBUG: </close() on _queue>
28 Apr 2021 02:02:20,411 DEBUG: <join() on _asyncThread>
28 Apr 2021 02:02:20,411 DEBUG: exit background thread
28 Apr 2021 02:02:20,411 DEBUG: </join() on _asyncThread>
28 Apr 2021 02:02:20,411 DEBUG: </next>
28 Apr 2021 02:02:20,411 DEBUG: <close()>
28 Apr 2021 02:02:20,411 DEBUG: <close() on _data>
28 Apr 2021 02:02:20,411 DEBUG: </close() on _data>
28 Apr 2021 02:02:20,411 DEBUG: <close() on _queue>
28 Apr 2021 02:02:20,411 DEBUG: </close() on _queue>
28 Apr 2021 02:02:20,411 DEBUG: <join() on _asyncThread>
28 Apr 2021 02:02:20,411 DEBUG: </join() on _asyncThread>
28 Apr 2021 02:02:20,412 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@ec6e349>
28 Apr 2021 02:02:20,412 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@ec6e349>
28 Apr 2021 02:02:20,412 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@1ab40d39>
28 Apr 2021 02:02:20,412 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@1ab40d39>
28 Apr 2021 02:02:20,412 DEBUG: </close()>
28 Apr 2021 02:02:20,416 INFO : Finish batch RedCap Integration.dataset
28 Apr 2021 02:02:20,420 INFO : Updating participant visits
28 Apr 2021 02:02:20,420 INFO : Updating participants
28 Apr 2021 02:02:20,426 INFO : Updating participant visit table
28 Apr 2021 02:02:20,518 INFO : Updating visit table
28 Apr 2021 02:02:20,521 INFO : Updating cohorts
28 Apr 2021 02:02:20,521 INFO : Clearing participant visit caches
28 Apr 2021 02:02:20,525 INFO : Finished updating participants
28 Apr 2021 02:02:20,529 INFO : Successfully completed task 'org.labkey.study.pipeline.StudyImportDatasetTask'
28 Apr 2021 02:02:20,575 INFO : Starting to run task 'org.labkey.api.specimen.pipeline.StudyImportSpecimenTask' at location 'webserver'
28 Apr 2021 02:02:20,577 INFO : Successfully completed task 'org.labkey.api.specimen.pipeline.StudyImportSpecimenTask'
28 Apr 2021 02:02:20,659 INFO : Starting to run task 'org.labkey.study.importer.StudyImportFinalTask' at location 'webserver'
28 Apr 2021 02:02:20,712 INFO : Loading dataset cohort assignments
28 Apr 2021 02:02:20,717 INFO : Done importing dataset cohort assignments
28 Apr 2021 02:02:20,779 INFO : Loading lists
28 Apr 2021 02:02:20,791 INFO : Truncating list: vbw8
28 Apr 2021 02:24:55,301 INFO : Recreating list: vbw8
28 Apr 2021 02:24:55,510 INFO : Truncating list: cpq13
28 Apr 2021 02:24:55,643 INFO : Recreating list: cpq13
28 Apr 2021 02:24:55,734 INFO : Truncating list: sex
28 Apr 2021 02:24:55,843 INFO : Recreating list: sex
28 Apr 2021 02:24:55,935 INFO : Truncating list: withdraw_reason
28 Apr 2021 02:24:56,027 INFO : Recreating list: withdraw_reason
28 Apr 2021 02:24:56,109 INFO : Truncating list: cpq6
28 Apr 2021 02:24:56,201 INFO : Recreating list: cpq6
28 Apr 2021 02:24:56,287 INFO : Truncating list: vbw9
28 Apr 2021 02:24:56,379 INFO : Recreating list: vbw9
28 Apr 2021 02:24:56,467 INFO : Truncating list: cpq8
28 Apr 2021 02:24:56,560 INFO : Recreating list: cpq8
28 Apr 2021 02:24:56,639 INFO : Truncating list: cpq10
28 Apr 2021 02:24:56,732 INFO : Recreating list: cpq10
28 Apr 2021 02:24:56,809 INFO : Truncating list: vbw6
28 Apr 2021 02:24:56,902 INFO : Recreating list: vbw6
28 Apr 2021 02:24:56,976 INFO : Truncating list: cpq11
28 Apr 2021 02:24:57,065 INFO : Recreating list: cpq11
28 Apr 2021 02:24:57,141 INFO : Truncating list: vbw7
28 Apr 2021 02:24:57,250 INFO : Recreating list: vbw7
28 Apr 2021 02:24:57,391 INFO : Merged 5 row(s) into list: withdraw_reason
28 Apr 2021 02:24:57,439 INFO : Merged 3 row(s) into list: cpq13
28 Apr 2021 02:24:57,504 INFO : Merged 6 row(s) into list: cpq11
28 Apr 2021 02:24:57,549 INFO : Merged 4 row(s) into list: cpq10
28 Apr 2021 02:24:57,590 INFO : Merged 2 row(s) into list: sex
28 Apr 2021 02:24:57,634 INFO : Merged 2 row(s) into list: vbw9
28 Apr 2021 02:24:57,673 INFO : Merged 3 row(s) into list: cpq8
28 Apr 2021 02:24:57,711 INFO : Merged 3 row(s) into list: vbw8
28 Apr 2021 02:24:57,751 INFO : Merged 4 row(s) into list: vbw7
28 Apr 2021 02:24:57,790 INFO : Merged 2 row(s) into list: vbw6
28 Apr 2021 02:24:57,828 INFO : Merged 5 row(s) into list: cpq6
28 Apr 2021 02:24:57,832 INFO : 11 lists imported successfully
28 Apr 2021 02:24:57,832 INFO : Done importing lists
28 Apr 2021 02:24:57,846 INFO : Post-processing queries
28 Apr 2021 02:24:57,846 INFO : Skipping query validation.
28 Apr 2021 02:24:57,846 INFO : Done post-processing queries
28 Apr 2021 02:24:57,859 INFO : Successfully completed task 'org.labkey.study.importer.StudyImportFinalTask'