17 Mar 2021 02:55:55,796 INFO : Starting to run task 'org.labkey.study.pipeline.StudyReloadSourceTask' at location 'webserver'
17 Mar 2021 02:55:55,814 INFO : Starting REDCap export
17 Mar 2021 02:55:57,029 INFO : Creating study archive
17 Mar 2021 02:55:57,083 INFO : Writing dataset metadata
17 Mar 2021 02:55:57,176 INFO : Creating column lookups
17 Mar 2021 02:55:59,347 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
17 Mar 2021 02:55:59,347 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
17 Mar 2021 02:55:59,347 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
17 Mar 2021 02:55:59,347 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
17 Mar 2021 02:55:59,347 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
17 Mar 2021 02:55:59,347 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
17 Mar 2021 02:55:59,347 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
17 Mar 2021 02:55:59,347 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
17 Mar 2021 02:55:59,347 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
17 Mar 2021 02:55:59,347 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
17 Mar 2021 02:55:59,347 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
17 Mar 2021 02:55:59,347 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
17 Mar 2021 02:55:59,347 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
17 Mar 2021 02:55:59,348 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
17 Mar 2021 02:55:59,348 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
17 Mar 2021 02:55:59,348 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
17 Mar 2021 02:55:59,348 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
17 Mar 2021 02:55:59,348 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
17 Mar 2021 02:55:59,348 WARN : Unable to locate subject id field: first_name for dataset: demographics row ignored.
17 Mar 2021 02:55:59,348 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
17 Mar 2021 02:55:59,348 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
17 Mar 2021 02:55:59,348 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
17 Mar 2021 02:55:59,348 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
17 Mar 2021 02:55:59,348 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
17 Mar 2021 02:55:59,348 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
17 Mar 2021 02:55:59,348 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
17 Mar 2021 02:55:59,348 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
17 Mar 2021 02:55:59,348 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
17 Mar 2021 02:55:59,348 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
17 Mar 2021 02:55:59,348 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
17 Mar 2021 02:55:59,349 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
17 Mar 2021 02:55:59,349 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
17 Mar 2021 02:55:59,349 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
17 Mar 2021 02:55:59,349 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
17 Mar 2021 02:55:59,349 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
17 Mar 2021 02:55:59,349 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
17 Mar 2021 02:55:59,349 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
17 Mar 2021 02:55:59,349 WARN : Unable to locate subject id field: first_name for dataset: emergency_contact_info row ignored.
17 Mar 2021 02:55:59,349 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
17 Mar 2021 02:55:59,349 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
17 Mar 2021 02:55:59,349 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
17 Mar 2021 02:55:59,349 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
17 Mar 2021 02:55:59,349 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
17 Mar 2021 02:55:59,349 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
17 Mar 2021 02:55:59,349 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
17 Mar 2021 02:55:59,349 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
17 Mar 2021 02:55:59,349 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
17 Mar 2021 02:55:59,349 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
17 Mar 2021 02:55:59,349 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
17 Mar 2021 02:55:59,349 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
17 Mar 2021 02:55:59,349 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
17 Mar 2021 02:55:59,349 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
17 Mar 2021 02:55:59,350 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
17 Mar 2021 02:55:59,350 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
17 Mar 2021 02:55:59,350 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
17 Mar 2021 02:55:59,350 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
17 Mar 2021 02:55:59,350 WARN : Unable to locate subject id field: first_name for dataset: baseline_lab_data row ignored.
17 Mar 2021 02:55:59,350 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
17 Mar 2021 02:55:59,350 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
17 Mar 2021 02:55:59,350 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
17 Mar 2021 02:55:59,350 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
17 Mar 2021 02:55:59,350 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
17 Mar 2021 02:55:59,350 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
17 Mar 2021 02:55:59,350 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
17 Mar 2021 02:55:59,350 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
17 Mar 2021 02:55:59,350 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
17 Mar 2021 02:55:59,350 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
17 Mar 2021 02:55:59,350 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
17 Mar 2021 02:55:59,350 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
17 Mar 2021 02:55:59,350 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
17 Mar 2021 02:55:59,350 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
17 Mar 2021 02:55:59,350 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
17 Mar 2021 02:55:59,350 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
17 Mar 2021 02:55:59,350 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
17 Mar 2021 02:55:59,350 WARN : Unable to locate subject id field: first_name for dataset: visit_blood_workup row ignored.
17 Mar 2021 02:55:59,351 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
17 Mar 2021 02:55:59,351 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
17 Mar 2021 02:55:59,351 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
17 Mar 2021 02:55:59,351 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
17 Mar 2021 02:55:59,351 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
17 Mar 2021 02:55:59,351 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
17 Mar 2021 02:55:59,351 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
17 Mar 2021 02:55:59,351 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
17 Mar 2021 02:55:59,351 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
17 Mar 2021 02:55:59,351 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
17 Mar 2021 02:55:59,351 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
17 Mar 2021 02:55:59,351 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
17 Mar 2021 02:55:59,351 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
17 Mar 2021 02:55:59,351 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
17 Mar 2021 02:55:59,351 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
17 Mar 2021 02:55:59,351 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
17 Mar 2021 02:55:59,351 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
17 Mar 2021 02:55:59,351 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
17 Mar 2021 02:55:59,351 WARN : Unable to locate subject id field: first_name for dataset: completion_data row ignored.
17 Mar 2021 02:55:59,351 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
17 Mar 2021 02:55:59,351 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
17 Mar 2021 02:55:59,351 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
17 Mar 2021 02:55:59,351 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
17 Mar 2021 02:55:59,352 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
17 Mar 2021 02:55:59,352 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
17 Mar 2021 02:55:59,352 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
17 Mar 2021 02:55:59,352 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
17 Mar 2021 02:55:59,352 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
17 Mar 2021 02:55:59,352 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
17 Mar 2021 02:55:59,352 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
17 Mar 2021 02:55:59,352 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
17 Mar 2021 02:55:59,352 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
17 Mar 2021 02:55:59,352 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
17 Mar 2021 02:55:59,352 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
17 Mar 2021 02:55:59,352 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
17 Mar 2021 02:55:59,352 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
17 Mar 2021 02:55:59,352 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
17 Mar 2021 02:55:59,352 WARN : Unable to locate subject id field: first_name for dataset: completion_project_questionnaire row ignored.
17 Mar 2021 02:55:59,355 INFO : Finished creating study archive
17 Mar 2021 02:55:59,355 INFO : Successfully completed task 'org.labkey.study.pipeline.StudyReloadSourceTask'
17 Mar 2021 02:55:59,403 INFO : Starting to run task 'org.labkey.study.importer.StudyImportInitialTask' at location 'webserver'
17 Mar 2021 02:55:59,412 INFO : Reloading study from study.xml
17 Mar 2021 02:55:59,418 INFO : Loading top-level study properties (label, start/end date, description, etc.)
17 Mar 2021 02:56:01,824 INFO : Done importing top-level study properties (label, start/end date, description, etc.)
17 Mar 2021 02:56:01,867 INFO : Loading datasets manifest from datasets_manifest.xml
17 Mar 2021 02:56:01,873 INFO : Loading dataset schema from datasets_metadata.xml
17 Mar 2021 02:56:02,924 INFO : Successfully completed task 'org.labkey.study.importer.StudyImportInitialTask'
17 Mar 2021 02:56:02,968 INFO : Starting to run task 'org.labkey.study.pipeline.StudyImportDatasetTask' at location 'webserver'
17 Mar 2021 02:56:02,979 INFO : Start batch RedCap Integration.dataset
17 Mar 2021 02:56:03,035 INFO : demographics: Starting import from dataset001.tsv
17 Mar 2021 02:56:04,960 DEBUG: <next>
17 Mar 2021 02:56:04,960 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@f5fbaf4>
17 Mar 2021 02:56:04,961 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@f5fbaf4>
17 Mar 2021 02:56:04,961 DEBUG: </next>
17 Mar 2021 02:56:04,961 DEBUG: <next>
17 Mar 2021 02:56:04,961 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@f5fbaf4>
17 Mar 2021 02:56:04,961 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@f5fbaf4>
17 Mar 2021 02:56:04,962 DEBUG: </next>
17 Mar 2021 02:56:04,962 DEBUG: <next>
17 Mar 2021 02:56:04,962 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@f5fbaf4>
17 Mar 2021 02:56:04,962 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@f5fbaf4>
17 Mar 2021 02:56:04,962 DEBUG: </next>
17 Mar 2021 02:56:04,962 DEBUG: <next>
17 Mar 2021 02:56:04,963 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@f5fbaf4>
17 Mar 2021 02:56:04,963 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@f5fbaf4>
17 Mar 2021 02:56:04,963 DEBUG: </next>
17 Mar 2021 02:56:04,963 DEBUG: <next>
17 Mar 2021 02:56:04,963 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@f5fbaf4>
17 Mar 2021 02:56:04,963 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@f5fbaf4>
17 Mar 2021 02:56:04,963 DEBUG: </next>
17 Mar 2021 02:56:04,963 DEBUG: <next>
17 Mar 2021 02:56:04,964 DEBUG: <swap() - old: org.labkey.api.data.ParameterMapStatement@f5fbaf4>
17 Mar 2021 02:56:04,964 DEBUG: </swap() - new: org.labkey.api.data.ParameterMapStatement@3d2b673d>
17 Mar 2021 02:56:04,964 DEBUG: <close() on _queue>
17 Mar 2021 02:56:04,964 DEBUG: <executeBatch() on org.labkey.api.data.ParameterMapStatement@f5fbaf4>
17 Mar 2021 02:56:04,964 DEBUG: <close() on _queue>
17 Mar 2021 02:56:04,964 DEBUG: </close() on _queue>
17 Mar 2021 02:56:04,964 DEBUG: <join() on _asyncThread>
17 Mar 2021 02:56:05,640 DEBUG: </executeBatch() on org.labkey.api.data.ParameterMapStatement@f5fbaf4>
17 Mar 2021 02:56:05,641 DEBUG: exit background thread
17 Mar 2021 02:56:05,641 DEBUG: </join() on _asyncThread>
17 Mar 2021 02:56:05,641 DEBUG: </next>
17 Mar 2021 02:56:05,641 DEBUG: <close()>
17 Mar 2021 02:56:05,641 DEBUG: <close() on _data>
17 Mar 2021 02:56:05,641 DEBUG: </close() on _data>
17 Mar 2021 02:56:05,641 DEBUG: <close() on _queue>
17 Mar 2021 02:56:05,641 DEBUG: </close() on _queue>
17 Mar 2021 02:56:05,641 DEBUG: <join() on _asyncThread>
17 Mar 2021 02:56:05,641 DEBUG: </join() on _asyncThread>
17 Mar 2021 02:56:05,641 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@f5fbaf4>
17 Mar 2021 02:56:05,642 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@f5fbaf4>
17 Mar 2021 02:56:05,642 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@3d2b673d>
17 Mar 2021 02:56:05,642 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@3d2b673d>
17 Mar 2021 02:56:05,642 DEBUG: </close()>
17 Mar 2021 02:56:05,675 INFO : demographics: Processed 5 rows from dataset001.tsv
17 Mar 2021 02:56:05,675 INFO : demographics: Deleted 5 rows
17 Mar 2021 02:56:05,675 INFO : demographics: Merged 5 rows
17 Mar 2021 02:56:05,708 INFO : emergency_contact_info: Starting import from dataset002.tsv
17 Mar 2021 02:56:05,938 DEBUG: <next>
17 Mar 2021 02:56:05,938 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@69926868>
17 Mar 2021 02:56:05,939 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@69926868>
17 Mar 2021 02:56:05,939 DEBUG: </next>
17 Mar 2021 02:56:05,939 DEBUG: <next>
17 Mar 2021 02:56:05,939 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@69926868>
17 Mar 2021 02:56:05,939 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@69926868>
17 Mar 2021 02:56:05,939 DEBUG: </next>
17 Mar 2021 02:56:05,939 DEBUG: <next>
17 Mar 2021 02:56:05,939 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@69926868>
17 Mar 2021 02:56:05,939 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@69926868>
17 Mar 2021 02:56:05,939 DEBUG: </next>
17 Mar 2021 02:56:05,939 DEBUG: <next>
17 Mar 2021 02:56:05,940 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@69926868>
17 Mar 2021 02:56:05,940 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@69926868>
17 Mar 2021 02:56:05,940 DEBUG: </next>
17 Mar 2021 02:56:05,940 DEBUG: <next>
17 Mar 2021 02:56:05,940 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@69926868>
17 Mar 2021 02:56:05,940 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@69926868>
17 Mar 2021 02:56:05,940 DEBUG: </next>
17 Mar 2021 02:56:05,940 DEBUG: <next>
17 Mar 2021 02:56:05,940 DEBUG: <swap() - old: org.labkey.api.data.ParameterMapStatement@69926868>
17 Mar 2021 02:56:05,940 DEBUG: </swap() - new: org.labkey.api.data.ParameterMapStatement@6dc378e3>
17 Mar 2021 02:56:05,940 DEBUG: <executeBatch() on org.labkey.api.data.ParameterMapStatement@69926868>
17 Mar 2021 02:56:05,941 DEBUG: <close() on _queue>
17 Mar 2021 02:56:05,941 DEBUG: <close() on _queue>
17 Mar 2021 02:56:05,941 DEBUG: </close() on _queue>
17 Mar 2021 02:56:05,941 DEBUG: <join() on _asyncThread>
17 Mar 2021 02:56:06,524 DEBUG: </executeBatch() on org.labkey.api.data.ParameterMapStatement@69926868>
17 Mar 2021 02:56:06,524 DEBUG: exit background thread
17 Mar 2021 02:56:06,524 DEBUG: </join() on _asyncThread>
17 Mar 2021 02:56:06,524 DEBUG: </next>
17 Mar 2021 02:56:06,524 DEBUG: <close()>
17 Mar 2021 02:56:06,524 DEBUG: <close() on _data>
17 Mar 2021 02:56:06,525 DEBUG: </close() on _data>
17 Mar 2021 02:56:06,525 DEBUG: <close() on _queue>
17 Mar 2021 02:56:06,525 DEBUG: </close() on _queue>
17 Mar 2021 02:56:06,525 DEBUG: <join() on _asyncThread>
17 Mar 2021 02:56:06,525 DEBUG: </join() on _asyncThread>
17 Mar 2021 02:56:06,525 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@69926868>
17 Mar 2021 02:56:06,525 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@69926868>
17 Mar 2021 02:56:06,525 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@6dc378e3>
17 Mar 2021 02:56:06,525 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@6dc378e3>
17 Mar 2021 02:56:06,525 DEBUG: </close()>
17 Mar 2021 02:56:06,542 INFO : emergency_contact_info: Processed 5 rows from dataset002.tsv
17 Mar 2021 02:56:06,542 INFO : emergency_contact_info: Deleted 5 rows
17 Mar 2021 02:56:06,542 INFO : emergency_contact_info: Merged 5 rows
17 Mar 2021 02:56:06,571 INFO : baseline_lab_data: Starting import from dataset003.tsv
17 Mar 2021 02:56:07,090 DEBUG: <next>
17 Mar 2021 02:56:07,090 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@35a1e41>
17 Mar 2021 02:56:07,090 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@35a1e41>
17 Mar 2021 02:56:07,090 DEBUG: </next>
17 Mar 2021 02:56:07,090 DEBUG: <next>
17 Mar 2021 02:56:07,091 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@35a1e41>
17 Mar 2021 02:56:07,091 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@35a1e41>
17 Mar 2021 02:56:07,091 DEBUG: </next>
17 Mar 2021 02:56:07,091 DEBUG: <next>
17 Mar 2021 02:56:07,091 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@35a1e41>
17 Mar 2021 02:56:07,091 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@35a1e41>
17 Mar 2021 02:56:07,091 DEBUG: </next>
17 Mar 2021 02:56:07,091 DEBUG: <next>
17 Mar 2021 02:56:07,092 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@35a1e41>
17 Mar 2021 02:56:07,092 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@35a1e41>
17 Mar 2021 02:56:07,092 DEBUG: </next>
17 Mar 2021 02:56:07,092 DEBUG: <next>
17 Mar 2021 02:56:07,092 DEBUG: <clear and set parameters on org.labkey.api.data.ParameterMapStatement@35a1e41>
17 Mar 2021 02:56:07,092 DEBUG: </clear and set parameters on org.labkey.api.data.ParameterMapStatement@35a1e41>
17 Mar 2021 02:56:07,093 DEBUG: </next>
17 Mar 2021 02:56:07,093 DEBUG: <next>
17 Mar 2021 02:56:07,093 DEBUG: <swap() - old: org.labkey.api.data.ParameterMapStatement@35a1e41>
17 Mar 2021 02:56:07,093 DEBUG: </swap() - new: org.labkey.api.data.ParameterMapStatement@5dc1cf4e>
17 Mar 2021 02:56:07,093 DEBUG: <close() on _queue>
17 Mar 2021 02:56:07,093 DEBUG: <close() on _queue>
17 Mar 2021 02:56:07,093 DEBUG: </close() on _queue>
17 Mar 2021 02:56:07,093 DEBUG: <join() on _asyncThread>
17 Mar 2021 02:56:07,093 DEBUG: <executeBatch() on org.labkey.api.data.ParameterMapStatement@35a1e41>
17 Mar 2021 02:56:07,398 DEBUG: </executeBatch() on org.labkey.api.data.ParameterMapStatement@35a1e41>
17 Mar 2021 02:56:07,398 DEBUG: exit background thread
17 Mar 2021 02:56:07,398 DEBUG: </join() on _asyncThread>
17 Mar 2021 02:56:07,398 DEBUG: </next>
17 Mar 2021 02:56:07,399 DEBUG: <close()>
17 Mar 2021 02:56:07,399 DEBUG: <close() on _data>
17 Mar 2021 02:56:07,399 DEBUG: </close() on _data>
17 Mar 2021 02:56:07,399 DEBUG: <close() on _queue>
17 Mar 2021 02:56:07,399 DEBUG: </close() on _queue>
17 Mar 2021 02:56:07,399 DEBUG: <join() on _asyncThread>
17 Mar 2021 02:56:07,399 DEBUG: </join() on _asyncThread>
17 Mar 2021 02:56:07,399 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@35a1e41>
17 Mar 2021 02:56:07,399 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@35a1e41>
17 Mar 2021 02:56:07,399 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@5dc1cf4e>
17 Mar 2021 02:56:07,399 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@5dc1cf4e>
17 Mar 2021 02:56:07,399 DEBUG: </close()>
17 Mar 2021 02:56:07,418 INFO : baseline_lab_data: Processed 5 rows from dataset003.tsv
17 Mar 2021 02:56:07,418 INFO : baseline_lab_data: Deleted 5 rows
17 Mar 2021 02:56:07,418 INFO : baseline_lab_data: Merged 5 rows
17 Mar 2021 02:56:07,449 INFO : visit_blood_workup: Starting import from dataset004.tsv
17 Mar 2021 02:56:07,481 DEBUG: <next>
17 Mar 2021 02:56:07,481 DEBUG: <close() on _queue>
17 Mar 2021 02:56:07,481 DEBUG: <close() on _queue>
17 Mar 2021 02:56:07,482 DEBUG: exit background thread
17 Mar 2021 02:56:07,482 DEBUG: </close() on _queue>
17 Mar 2021 02:56:07,482 DEBUG: <join() on _asyncThread>
17 Mar 2021 02:56:07,482 DEBUG: </join() on _asyncThread>
17 Mar 2021 02:56:07,482 DEBUG: </next>
17 Mar 2021 02:56:07,482 DEBUG: <close()>
17 Mar 2021 02:56:07,482 DEBUG: <close() on _data>
17 Mar 2021 02:56:07,482 DEBUG: </close() on _data>
17 Mar 2021 02:56:07,482 DEBUG: <close() on _queue>
17 Mar 2021 02:56:07,482 DEBUG: </close() on _queue>
17 Mar 2021 02:56:07,482 DEBUG: <join() on _asyncThread>
17 Mar 2021 02:56:07,482 DEBUG: </join() on _asyncThread>
17 Mar 2021 02:56:07,482 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@243aa15c>
17 Mar 2021 02:56:07,483 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@243aa15c>
17 Mar 2021 02:56:07,483 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@21d22fe8>
17 Mar 2021 02:56:07,483 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@21d22fe8>
17 Mar 2021 02:56:07,483 DEBUG: </close()>
17 Mar 2021 02:56:07,513 INFO : completion_data: Starting import from dataset005.tsv
17 Mar 2021 02:56:07,534 DEBUG: <next>
17 Mar 2021 02:56:07,534 DEBUG: <close() on _queue>
17 Mar 2021 02:56:07,534 DEBUG: <close() on _queue>
17 Mar 2021 02:56:07,534 DEBUG: </close() on _queue>
17 Mar 2021 02:56:07,534 DEBUG: <join() on _asyncThread>
17 Mar 2021 02:56:07,534 DEBUG: exit background thread
17 Mar 2021 02:56:07,534 DEBUG: </join() on _asyncThread>
17 Mar 2021 02:56:07,534 DEBUG: </next>
17 Mar 2021 02:56:07,534 DEBUG: <close()>
17 Mar 2021 02:56:07,534 DEBUG: <close() on _data>
17 Mar 2021 02:56:07,534 DEBUG: </close() on _data>
17 Mar 2021 02:56:07,535 DEBUG: <close() on _queue>
17 Mar 2021 02:56:07,535 DEBUG: </close() on _queue>
17 Mar 2021 02:56:07,535 DEBUG: <join() on _asyncThread>
17 Mar 2021 02:56:07,535 DEBUG: </join() on _asyncThread>
17 Mar 2021 02:56:07,535 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@6f7cc2c4>
17 Mar 2021 02:56:07,535 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@6f7cc2c4>
17 Mar 2021 02:56:07,535 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@78b88abb>
17 Mar 2021 02:56:07,535 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@78b88abb>
17 Mar 2021 02:56:07,535 DEBUG: </close()>
17 Mar 2021 02:56:07,574 INFO : completion_project_questionnaire: Starting import from dataset006.tsv
17 Mar 2021 02:56:07,618 DEBUG: <next>
17 Mar 2021 02:56:07,618 DEBUG: <close() on _queue>
17 Mar 2021 02:56:07,618 DEBUG: <close() on _queue>
17 Mar 2021 02:56:07,618 DEBUG: </close() on _queue>
17 Mar 2021 02:56:07,618 DEBUG: <join() on _asyncThread>
17 Mar 2021 02:56:07,618 DEBUG: exit background thread
17 Mar 2021 02:56:07,618 DEBUG: </join() on _asyncThread>
17 Mar 2021 02:56:07,618 DEBUG: </next>
17 Mar 2021 02:56:07,618 DEBUG: <close()>
17 Mar 2021 02:56:07,618 DEBUG: <close() on _data>
17 Mar 2021 02:56:07,618 DEBUG: </close() on _data>
17 Mar 2021 02:56:07,619 DEBUG: <close() on _queue>
17 Mar 2021 02:56:07,619 DEBUG: </close() on _queue>
17 Mar 2021 02:56:07,619 DEBUG: <join() on _asyncThread>
17 Mar 2021 02:56:07,619 DEBUG: </join() on _asyncThread>
17 Mar 2021 02:56:07,619 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@486f47cd>
17 Mar 2021 02:56:07,619 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@486f47cd>
17 Mar 2021 02:56:07,619 DEBUG: <close() on org.labkey.api.data.ParameterMapStatement@7b8be99e>
17 Mar 2021 02:56:07,619 DEBUG: </close() on org.labkey.api.data.ParameterMapStatement@7b8be99e>
17 Mar 2021 02:56:07,619 DEBUG: </close()>
17 Mar 2021 02:56:07,623 INFO : Finish batch RedCap Integration.dataset
17 Mar 2021 02:56:07,627 INFO : Updating participant visits
17 Mar 2021 02:56:07,627 INFO : Updating participants
17 Mar 2021 02:56:07,633 INFO : Updating participant visit table
17 Mar 2021 02:56:07,815 INFO : Updating visit table
17 Mar 2021 02:56:07,818 INFO : Updating cohorts
17 Mar 2021 02:56:07,818 INFO : Clearing participant visit caches
17 Mar 2021 02:56:07,820 INFO : Finished updating participants
17 Mar 2021 02:56:07,824 INFO : Successfully completed task 'org.labkey.study.pipeline.StudyImportDatasetTask'
17 Mar 2021 02:56:07,868 INFO : Starting to run task 'org.labkey.api.specimen.pipeline.StudyImportSpecimenTask' at location 'webserver'
17 Mar 2021 02:56:07,870 INFO : Successfully completed task 'org.labkey.api.specimen.pipeline.StudyImportSpecimenTask'
17 Mar 2021 02:56:07,907 INFO : Starting to run task 'org.labkey.study.importer.StudyImportFinalTask' at location 'webserver'
17 Mar 2021 02:56:07,931 INFO : Loading dataset cohort assignments
17 Mar 2021 02:56:07,932 INFO : Done importing dataset cohort assignments
17 Mar 2021 02:56:07,979 INFO : Loading lists
17 Mar 2021 02:56:07,988 INFO : Truncating list: vbw8
17 Mar 2021 02:56:08,367 INFO : Recreating list: vbw8
17 Mar 2021 02:56:08,825 INFO : Truncating list: cpq13
17 Mar 2021 02:56:08,935 INFO : Recreating list: cpq13
17 Mar 2021 02:56:09,008 INFO : Truncating list: sex
17 Mar 2021 02:56:09,120 INFO : Recreating list: sex
17 Mar 2021 02:56:09,185 INFO : Truncating list: withdraw_reason
17 Mar 2021 02:56:09,274 INFO : Recreating list: withdraw_reason
17 Mar 2021 02:56:09,336 INFO : Truncating list: cpq6
17 Mar 2021 02:56:09,428 INFO : Recreating list: cpq6
17 Mar 2021 02:56:09,503 INFO : Truncating list: vbw9
17 Mar 2021 02:56:09,637 INFO : Recreating list: vbw9
17 Mar 2021 02:56:09,696 INFO : Truncating list: cpq8
17 Mar 2021 02:56:09,786 INFO : Recreating list: cpq8
17 Mar 2021 02:56:09,844 INFO : Truncating list: cpq10
17 Mar 2021 02:56:09,933 INFO : Recreating list: cpq10
17 Mar 2021 02:56:09,995 INFO : Truncating list: vbw6
17 Mar 2021 02:56:10,109 INFO : Recreating list: vbw6
17 Mar 2021 02:56:10,168 INFO : Truncating list: cpq11
17 Mar 2021 02:56:10,261 INFO : Recreating list: cpq11
17 Mar 2021 02:56:10,326 INFO : Truncating list: vbw7
17 Mar 2021 02:56:10,413 INFO : Recreating list: vbw7
17 Mar 2021 02:56:10,531 INFO : Merged 5 row(s) into list: withdraw_reason
17 Mar 2021 02:56:10,569 INFO : Merged 3 row(s) into list: cpq13
17 Mar 2021 02:56:10,607 INFO : Merged 6 row(s) into list: cpq11
17 Mar 2021 02:56:10,647 INFO : Merged 4 row(s) into list: cpq10
17 Mar 2021 02:56:10,690 INFO : Merged 2 row(s) into list: sex
17 Mar 2021 02:56:10,726 INFO : Merged 2 row(s) into list: vbw9
17 Mar 2021 02:56:10,762 INFO : Merged 3 row(s) into list: vbw8
17 Mar 2021 02:56:10,801 INFO : Merged 3 row(s) into list: cpq8
17 Mar 2021 02:56:10,818 INFO : Merged 4 row(s) into list: vbw7
17 Mar 2021 02:56:10,858 INFO : Merged 5 row(s) into list: cpq6
17 Mar 2021 02:56:10,901 INFO : Merged 2 row(s) into list: vbw6
17 Mar 2021 02:56:10,905 INFO : 11 lists imported successfully
17 Mar 2021 02:56:10,905 INFO : Done importing lists
17 Mar 2021 02:56:10,910 INFO : Post-processing queries
17 Mar 2021 02:56:10,910 INFO : Skipping query validation.
17 Mar 2021 02:56:10,910 INFO : Done post-processing queries
17 Mar 2021 02:56:10,920 INFO : Successfully completed task 'org.labkey.study.importer.StudyImportFinalTask'