starting build "4f03d5e0-bbcb-4946-988a-36924586b5ed"

FETCHSOURCE
hint: Using 'master' as the name for the initial branch. This default branch name
hint: is subject to change. To configure the initial branch name to use in all
hint: of your new repositories, which will suppress this warning, call:
hint:
hint: 	git config --global init.defaultBranch <name>
hint:
hint: Names commonly chosen instead of 'master' are 'main', 'trunk' and
hint: 'development'. The just-created branch can be renamed via this command:
hint:
hint: 	git branch -m <name>
Initialized empty Git repository in /workspace/.git/
From https://github.com/datacommonsorg/data
 * branch            cd4865d9cbbf4094d219cad27569b3b416c8320d -> FETCH_HEAD
Updating files:  28% (863/3001)
Updating files:  29% (871/3001)
Updating files:  30% (901/3001)
Updating files:  31% (931/3001)
Updating files:  32% (961/3001)
Updating files:  33% (991/3001)
Updating files:  34% (1021/3001)
Updating files:  35% (1051/3001)
Updating files:  35% (1056/3001)
Updating files:  36% (1081/3001)
Updating files:  37% (1111/3001)
Updating files:  38% (1141/3001)
Updating files:  39% (1171/3001)
Updating files:  40% (1201/3001)
Updating files:  41% (1231/3001)
Updating files:  42% (1261/3001)
Updating files:  43% (1291/3001)
Updating files:  44% (1321/3001)
Updating files:  45% (1351/3001)
Updating files:  46% (1381/3001)
Updating files:  47% (1411/3001)
Updating files:  48% (1441/3001)
Updating files:  49% (1471/3001)
Updating files:  50% (1501/3001)
Updating files:  51% (1531/3001)
Updating files:  52% (1561/3001)
Updating files:  53% (1591/3001)
Updating files:  54% (1621/3001)
Updating files:  55% (1651/3001)
Updating files:  56% (1681/3001)
Updating files:  57% (1711/3001)
Updating files:  58% (1741/3001)
Updating files:  59% (1771/3001)
Updating files:  60% (1801/3001)
Updating files:  61% (1831/3001)
Updating files:  62% (1861/3001)
Updating files:  63% (1891/3001)
Updating files:  64% (1921/3001)
Updating files:  65% (1951/3001)
Updating files:  66% (1981/3001)
Updating files:  67% (2011/3001)
Updating files:  68% (2041/3001)
Updating files:  69% (2071/3001)
Updating files:  70% (2101/3001)
Updating files:  71% (2131/3001)
Updating files:  72% (2161/3001)
Updating files:  73% (2191/3001)
Updating files:  74% (2221/3001)
Updating files:  75% (2251/3001)
Updating files:  76% (2281/3001)
Updating files:  77% (2311/3001)
Updating files:  78% (2341/3001)
Updating files:  79% (2371/3001)
Updating files:  80% (2401/3001)
Updating files:  81% (2431/3001)
Updating files:  82% (2461/3001)
Updating files:  83% (2491/3001)
Updating files:  84% (2521/3001)
Updating files:  85% (2551/3001)
Updating files:  86% (2581/3001)
Updating files:  87% (2611/3001)
Updating files:  88% (2641/3001)
Updating files:  89% (2671/3001)
Updating files:  90% (2701/3001)
Updating files:  91% (2731/3001)
Updating files:  91% (2743/3001)
Updating files:  92% (2761/3001)
Updating files:  93% (2791/3001)
Updating files:  94% (2821/3001)
Updating files:  95% (2851/3001)
Updating files:  96% (2881/3001)
Updating files:  97% (2911/3001)
Updating files:  98% (2941/3001)
Updating files:  99% (2971/3001)
Updating files: 100% (3001/3001)
Updating files: 100% (3001/3001), done.
HEAD is now at cd4865d manifest.json updated for 2 weeks once cron schedule
GitCommit:
cd4865d9cbbf4094d219cad27569b3b416c8320d
BUILD
Starting Step #0 - "python_install"
Step #0 - "python_install": Pulling image: python:3.11
Step #0 - "python_install": 3.11: Pulling from library/python
Step #0 - "python_install": fd0410a2d1ae: Already exists
Step #0 - "python_install": bf571be90f05: Already exists
Step #0 - "python_install": 684a51896c82: Already exists
Step #0 - "python_install": fbf93b646d6b: Already exists
Step #0 - "python_install": 8525658d5eba: Pulling fs layer
Step #0 - "python_install": 1c7e45502a31: Pulling fs layer
Step #0 - "python_install": 07c7344ec2b0: Pulling fs layer
Step #0 - "python_install": 07c7344ec2b0: Verifying Checksum
Step #0 - "python_install": 07c7344ec2b0: Download complete
Step #0 - "python_install": 8525658d5eba: Verifying Checksum
Step #0 - "python_install": 8525658d5eba: Download complete
Step #0 - "python_install": 1c7e45502a31: Verifying Checksum
Step #0 - "python_install": 1c7e45502a31: Download complete
Step #0 - "python_install": 8525658d5eba: Pull complete
Step #0 - "python_install": 1c7e45502a31: Pull complete
Step #0 - "python_install": 07c7344ec2b0: Pull complete
Step #0 - "python_install": Digest: sha256:adb581d8ed80edd03efd4dcad66db115b9ce8de8522b01720b9f3e6146f0884c
Step #0 - "python_install": Status: Downloaded newer image for python:3.11
Step #0 - "python_install": docker.io/library/python:3.11
Step #0 - "python_install": ### Installing Python requirements
Step #0 - "python_install": Installing Python requirements
Step #0 - "python_install": 
Step #0 - "python_install": [notice] A new release of pip is available: 24.0 -> 25.0
Step #0 - "python_install": [notice] To update, run: pip install --upgrade pip
Finished Step #0 - "python_install"
Starting Step #2 - "python_format_check"
Starting Step #1 - "python_test"
Step #1 - "python_test": Already have image (with digest): python:3.11
Step #2 - "python_format_check": Already have image (with digest): python:3.11
Step #1 - "python_test": ### Running all tests
Step #2 - "python_format_check": ### Testing lint
Step #1 - "python_test": Installing Python requirements
Step #2 - "python_format_check": Installing Python requirements
Step #1 - "python_test": 
Step #1 - "python_test": [notice] A new release of pip is available: 24.0 -> 25.0
Step #1 - "python_test": [notice] To update, run: pip install --upgrade pip
Step #2 - "python_format_check": 
Step #2 - "python_format_check": [notice] A new release of pip is available: 24.0 -> 25.0
Step #2 - "python_format_check": [notice] To update, run: pip install --upgrade pip
Step #1 - "python_test": #### Testing Python code in util/
Step #2 - "python_format_check": #### Testing Python lint
Step #1 - "python_test": /workspace/util/file_util.py:680: SyntaxWarning: "is" with a literal. Did you mean "=="?
Step #1 - "python_test":   if key_column_name is '':
Step #1 - "python_test": /workspace/.env/lib/python3.11/site-packages/requests/__init__.py:102: RequestsDependencyWarning: urllib3 (1.26.8) or chardet (5.2.0)/charset_normalizer (2.0.12) doesn't match a supported version!
Step #1 - "python_test":   warnings.warn("urllib3 ({}) or chardet ({})/charset_normalizer ({}) doesn't match a supported "
Step #1 - "python_test": test_aggregate_dict (aggregation_util_test.AggregationUtilTest.test_aggregate_dict) ... ok
Step #1 - "python_test": test_aggregate_value (aggregation_util_test.AggregationUtilTest.test_aggregate_value) ... ok
Step #1 - "python_test": test_config_map_with_override (config_map_test.TestConfigMap.test_config_map_with_override) ... ok
Step #1 - "python_test": test_load_config_file (config_map_test.TestConfigMap.test_load_config_file)
Step #1 - "python_test": Test loading of config dictionary from a file. ... ok
Step #1 - "python_test": test_set_config (config_map_test.TestConfigMap.test_set_config) ... ok
Step #1 - "python_test": test_update_config (config_map_test.TestConfigMap.test_update_config) ... ok
Step #1 - "python_test": test_add_counter (counters_test.TestCounters.test_add_counter)
Step #1 - "python_test": Verify increment and decrement counters. ... Counters:
Step #1 - "python_test":                                        test_inputs =         10
Step #1 - "python_test":                                   test_process-mem =  197414912
Step #1 - "python_test":                               test_process-mem-rss =   90378240
Step #1 - "python_test":                         test_process-time-sys-secs =       0.16
Step #1 - "python_test":                        test_process-time-user-secs =       1.25
Step #1 - "python_test":                          test_process_elapsed_time =       0.00
Step #1 - "python_test":                                     test_processed =          0
Step #1 - "python_test":                                    test_start_time =     238.79
Step #1 - "python_test": ok
Step #1 - "python_test": test_counter_dict (counters_test.TestCounters.test_counter_dict)
Step #1 - "python_test": Verify counter dict is shared across counters. ... Counters:
Step #1 - "python_test":                                        process-mem =  197414912
Step #1 - "python_test":                                    process-mem-rss =   90378240
Step #1 - "python_test":                              process-time-sys-secs =       0.16
Step #1 - "python_test":                             process-time-user-secs =       1.26
Step #1 - "python_test":                               process_elapsed_time =       0.00
Step #1 - "python_test":                                          processed =          0
Step #1 - "python_test":                                         start_time =     238.80
Step #1 - "python_test":                                           test_ctr =          1
Step #1 - "python_test": ok
Step #1 - "python_test": test_debug_counters (counters_test.TestCounters.test_debug_counters)
Step #1 - "python_test": Verify counters with debug string suffixes. ... Counters:
Step #1 - "python_test":                                       test3_inputs =         10
Step #1 - "python_test":                           test3_inputs_test-case-2 =         10
Step #1 - "python_test":                                  test3_process-mem =  197414912
Step #1 - "python_test":                              test3_process-mem-rss =   90378240
Step #1 - "python_test":                        test3_process-time-sys-secs =       0.16
Step #1 - "python_test":                       test3_process-time-user-secs =       1.26
Step #1 - "python_test":                         test3_process_elapsed_time =       0.00
Step #1 - "python_test":                                    test3_processed =          0
Step #1 - "python_test":                                   test3_start_time =     238.80
Step #1 - "python_test": ok
Step #1 - "python_test": test_set_counter (counters_test.TestCounters.test_set_counter)
Step #1 - "python_test": Verify set_counter overrides current value. ... Counters:
Step #1 - "python_test":                                        test2_lines =          1
Step #1 - "python_test":                                  test2_lines_file1 =          1
Step #1 - "python_test":                                  test2_process-mem =  197414912
Step #1 - "python_test":                              test2_process-mem-rss =   90378240
Step #1 - "python_test":                        test2_process-time-sys-secs =       0.16
Step #1 - "python_test":                       test2_process-time-user-secs =       1.26
Step #1 - "python_test":                         test2_process_elapsed_time =       0.00
Step #1 - "python_test":                                    test2_processed =          0
Step #1 - "python_test":                                   test2_start_time =     238.80
Step #1 - "python_test": Counters:
Step #1 - "python_test":                                        test2_lines =         11
Step #1 - "python_test":                                  test2_lines_file1 =         11
Step #1 - "python_test":                                  test2_process-mem =  197414912
Step #1 - "python_test":                              test2_process-mem-rss =   90378240
Step #1 - "python_test":                        test2_process-time-sys-secs =       0.16
Step #1 - "python_test":                       test2_process-time-user-secs =       1.26
Step #1 - "python_test":                         test2_process_elapsed_time =       0.00
Step #1 - "python_test":                                    test2_processed =          0
Step #1 - "python_test":                                   test2_start_time =     238.80
Step #1 - "python_test": ok
Step #1 - "python_test": test_show_counters (counters_test.TestCounters.test_show_counters) ... Counters:
Step #1 - "python_test":                                     test-file-rows =        100
Step #1 - "python_test":                                   test-process-mem =  197414912
Step #1 - "python_test":                               test-process-mem-rss =   90378240
Step #1 - "python_test":                         test-process-time-sys-secs =       0.16
Step #1 - "python_test":                        test-process-time-user-secs =       1.26
Step #1 - "python_test":                          test-process_elapsed_time =       0.00
Step #1 - "python_test":                        test-process_remaining_time = 1000000.00
Step #1 - "python_test":                                     test-read-rows =          0
Step #1 - "python_test":                                    test-start_time =     238.80
Step #1 - "python_test": Counters:
Step #1 - "python_test":                                     test-file-rows =        100
Step #1 - "python_test":                                   test-process-mem =  197414912
Step #1 - "python_test":                               test-process-mem-rss =   90378240
Step #1 - "python_test":                         test-process-time-sys-secs =       0.16
Step #1 - "python_test":                        test-process-time-user-secs =       1.26
Step #1 - "python_test":                          test-process_elapsed_time =       0.00
Step #1 - "python_test":                        test-process_remaining_time =       0.01
Step #1 - "python_test":                               test-processing_rate =   12131.74
Step #1 - "python_test":                                     test-read-rows =         10
Step #1 - "python_test":                                    test-start_time =     238.80
Step #1 - "python_test": ok
Step #1 - "python_test": test_dc_api_batched_wrapper (dc_api_wrapper_test.TestDCAPIWrapper.test_dc_api_batched_wrapper)
Step #1 - "python_test": Test DC API wrapper for batched calls. ... ok
Step #1 - "python_test": test_dc_api_is_defined_dcid (dc_api_wrapper_test.TestDCAPIWrapper.test_dc_api_is_defined_dcid)
Step #1 - "python_test": Test API wrapper for defined DCIDs. ... ok
Step #1 - "python_test": test_dc_api_wrapper (dc_api_wrapper_test.TestDCAPIWrapper.test_dc_api_wrapper)
Step #1 - "python_test": Test the wrapper for DC API. ... ok
Step #1 - "python_test": test_dc_get_node_property_values (dc_api_wrapper_test.TestDCAPIWrapper.test_dc_get_node_property_values)
Step #1 - "python_test": Test API wrapper to get all property:values for a node. ... ok
Step #1 - "python_test": test_download_file (download_util_test.TestCounters.test_download_file) ... ok
Step #1 - "python_test": test_prefilled_url (download_util_test.TestCounters.test_prefilled_url) ... ok
Step #1 - "python_test": test_request_url (download_util_test.TestCounters.test_request_url) ... ok
Step #1 - "python_test": test_read_write (file_util_test.FileIOTest.test_read_write) ... ok
Step #1 - "python_test": test_file_get_estimate_num_rows (file_util_test.FileUtilsTest.test_file_get_estimate_num_rows) ... ok
Step #1 - "python_test": test_file_get_matching (file_util_test.FileUtilsTest.test_file_get_matching) ... ok
Step #1 - "python_test": test_file_load_csv_dict (file_util_test.FileUtilsTest.test_file_load_csv_dict) ... ok
Step #1 - "python_test": test_file_type (file_util_test.FileUtilsTest.test_file_type) ... ok
Step #1 - "python_test": test_file_write_load_py_dict (file_util_test.FileUtilsTest.test_file_write_load_py_dict) ... ok
Step #1 - "python_test": test_aa2 (latlng2place_mapsapi_test.Latlng2PlaceMapsAPITest.test_aa2) ... ok
Step #1 - "python_test": test_country (latlng2place_mapsapi_test.Latlng2PlaceMapsAPITest.test_country) ... ok
Step #1 - "python_test": test_main (latlng_recon_geojson_test.LatlngReconGeojsonTest.test_main) ... ok
Step #1 - "python_test": test_basic (latlng_recon_service_test.LatlngReconServiceTest.test_basic) ... /usr/local/lib/python3.11/concurrent/futures/thread.py:58: ResourceWarning: unclosed <ssl.SSLSocket fd=3, family=2, type=1, proto=6, laddr=('192.168.10.2', 59054), raddr=('34.49.176.91', 443)>
Step #1 - "python_test":   result = self.fn(*self.args, **self.kwargs)
Step #1 - "python_test": ResourceWarning: Enable tracemalloc to get the object allocation traceback
Step #1 - "python_test": ok
Step #1 - "python_test": test_filter (latlng_recon_service_test.LatlngReconServiceTest.test_filter) ... /usr/local/lib/python3.11/concurrent/futures/thread.py:58: ResourceWarning: unclosed <ssl.SSLSocket fd=3, family=2, type=1, proto=6, laddr=('192.168.10.2', 53522), raddr=('34.49.176.91', 443)>
Step #1 - "python_test":   result = self.fn(*self.args, **self.kwargs)
Step #1 - "python_test": ResourceWarning: Enable tracemalloc to get the object allocation traceback
Step #1 - "python_test": ok
Step #1 - "python_test": test_dict_list_to_mcf_str (mcf_dict_util_test.TestMCFDict.test_dict_list_to_mcf_str) ... ok
Step #1 - "python_test": test_drop_nodes (mcf_dict_util_test.TestMCFDict.test_drop_nodes) ... ok
Step #1 - "python_test": test_get_dcid_node (mcf_dict_util_test.TestMCFDict.test_get_dcid_node) ... ok
Step #1 - "python_test": test_mcf_dict_rename_namespace (mcf_dict_util_test.TestMCFDict.test_mcf_dict_rename_namespace) ... ok
Step #1 - "python_test": test_mcf_dict_rename_prop (mcf_dict_util_test.TestMCFDict.test_mcf_dict_rename_prop) ... ok
Step #1 - "python_test": test_mcf_dict_rename_prop_value (mcf_dict_util_test.TestMCFDict.test_mcf_dict_rename_prop_value) ... ok
Step #1 - "python_test": test_mcf_to_dict_list (mcf_dict_util_test.TestMCFDict.test_mcf_to_dict_list) ... ok
Step #1 - "python_test": test_node_list_check_existence_dc (mcf_dict_util_test.TestMCFDict.test_node_list_check_existence_dc) ... ok
Step #1 - "python_test": test_node_list_check_existence_node_list (mcf_dict_util_test.TestMCFDict.test_node_list_check_existence_node_list) ... ok
Step #1 - "python_test": test_example_usage (mcf_template_filler_test.MCFTemplateFillerTest.test_example_usage) ... ok
Step #1 - "python_test": test_pop_and_2_obs_with_all_pv (mcf_template_filler_test.MCFTemplateFillerTest.test_pop_and_2_obs_with_all_pv)
Step #1 - "python_test": Use separate templates for Pop Obs, and use Obs template repeatedly. ... ok
Step #1 - "python_test": test_pop_with_missing_req_pv (mcf_template_filler_test.MCFTemplateFillerTest.test_pop_with_missing_req_pv) ... ok
Step #1 - "python_test": test_require_node_name (mcf_template_filler_test.MCFTemplateFillerTest.test_require_node_name) ... ok
Step #1 - "python_test": test_unified_pop_obs_with_missing_optional_pv (mcf_template_filler_test.MCFTemplateFillerTest.test_unified_pop_obs_with_missing_optional_pv) ... ok
Step #1 - "python_test": test_place_id_resolution_by_name (state_division_to_dcid_test.PlaceMapTest.test_place_id_resolution_by_name) ... ok
Step #1 - "python_test": test_boolean_naming (statvar_dcid_generator_test.TestStatVarDcidGenerator.test_boolean_naming) ... ok
Step #1 - "python_test": test_double_underscore (statvar_dcid_generator_test.TestStatVarDcidGenerator.test_double_underscore) ... ok
Step #1 - "python_test": test_ignore_props (statvar_dcid_generator_test.TestStatVarDcidGenerator.test_ignore_props) ... ok
Step #1 - "python_test": test_legacy_mapping (statvar_dcid_generator_test.TestStatVarDcidGenerator.test_legacy_mapping) ... ok
Step #1 - "python_test": test_measured_property (statvar_dcid_generator_test.TestStatVarDcidGenerator.test_measured_property) ... ok
Step #1 - "python_test": test_measurement_constraint_removal (statvar_dcid_generator_test.TestStatVarDcidGenerator.test_measurement_constraint_removal) ... ok
Step #1 - "python_test": test_measurement_denominator (statvar_dcid_generator_test.TestStatVarDcidGenerator.test_measurement_denominator) ... ok
Step #1 - "python_test": test_measurement_qualifier (statvar_dcid_generator_test.TestStatVarDcidGenerator.test_measurement_qualifier) ... ok
Step #1 - "python_test": test_naics_name_generation (statvar_dcid_generator_test.TestStatVarDcidGenerator.test_naics_name_generation) ... ok
Step #1 - "python_test": test_namespace_removal (statvar_dcid_generator_test.TestStatVarDcidGenerator.test_namespace_removal) ... ok
Step #1 - "python_test": test_prepend_append_replace (statvar_dcid_generator_test.TestStatVarDcidGenerator.test_prepend_append_replace) ... ok
Step #1 - "python_test": test_quantity_name_generation (statvar_dcid_generator_test.TestStatVarDcidGenerator.test_quantity_name_generation) ... ok
Step #1 - "python_test": test_quantity_range_name_generation (statvar_dcid_generator_test.TestStatVarDcidGenerator.test_quantity_range_name_generation) ... ok
Step #1 - "python_test": test_soc_map (statvar_dcid_generator_test.TestStatVarDcidGenerator.test_soc_map) ... ok
Step #1 - "python_test": test_soc_name_generation (statvar_dcid_generator_test.TestStatVarDcidGenerator.test_soc_name_generation) ... ok
Step #1 - "python_test": test_sorted_constraints (statvar_dcid_generator_test.TestStatVarDcidGenerator.test_sorted_constraints) ... ok
Step #1 - "python_test": test_stat_type (statvar_dcid_generator_test.TestStatVarDcidGenerator.test_stat_type) ... ok
Step #1 - "python_test": 
Step #1 - "python_test": ----------------------------------------------------------------------
Step #1 - "python_test": Ran 61 tests in 3.004s
Step #1 - "python_test": 
Step #1 - "python_test": OK
Step #1 - "python_test": #### Testing Python code in scripts/
Step #1 - "python_test": /workspace/.env/lib/python3.11/site-packages/requests/__init__.py:102: RequestsDependencyWarning: urllib3 (1.26.8) or chardet (5.2.0)/charset_normalizer (2.0.12) doesn't match a supported version!
Step #1 - "python_test":   warnings.warn("urllib3 ({}) or chardet ({})/charset_normalizer ({}) doesn't match a supported "
Step #1 - "python_test": test_write_csv (aqicn.aqicn_test.AqicnTest.test_write_csv) ... ok
Step #1 - "python_test": test_main (biomedical.antibodyABCD.parse_abcd_test.TestParseAbcd.test_main)
Step #1 - "python_test": Test in the main function ... ok
Step #1 - "python_test": test_MergeAndProcessData (climate_trace.preprocess_data_test.PreprocessDataTest.test_MergeAndProcessData) ... ok
Step #1 - "python_test": test_create_csv (covid19_india.cases_count_states_data.preprocess_test.TestPreprocessCSVTest.test_create_csv) ... ok
Step #1 - "python_test": test_create_csv_include_dn_dd (covid19_india.cases_count_states_data.preprocess_test.TestPreprocessCSVTest.test_create_csv_include_dn_dd) ... ok
Step #1 - "python_test": test_create_csv_ingore_invalid_state (covid19_india.cases_count_states_data.preprocess_test.TestPreprocessCSVTest.test_create_csv_ingore_invalid_state) ... ok
Step #1 - "python_test": test_create_csv_latest_report_of_the_day (covid19_india.cases_count_states_data.preprocess_test.TestPreprocessCSVTest.test_create_csv_latest_report_of_the_day) ... ok
Step #1 - "python_test": test_create_csv_skip_unassigned (covid19_india.cases_count_states_data.preprocess_test.TestPreprocessCSVTest.test_create_csv_skip_unassigned) ... ok
Step #1 - "python_test": test_create_csv (covid19_india.medical_tests_in_data.preprocess_test.TestPreprocessCSVTest.test_create_csv) ... ok
Step #1 - "python_test": test_create_csv_remove_duplicate_dates (covid19_india.medical_tests_in_data.preprocess_test.TestPreprocessCSVTest.test_create_csv_remove_duplicate_dates) ... ok
Step #1 - "python_test": test_generate_tmcf (covid_tracking_project.historic_state_data.preprocess_csv_test.TestPreprocessCSVTest.test_generate_tmcf) ... ok
Step #1 - "python_test": test_write_csv (deepsolar.deepsolar_test.TestDeepSolar.test_write_csv) ... ok
Step #1 - "python_test": test_geocode_city (fbi.crime.geocode_cities_test.GeocodeCitiesTest.test_geocode_city) ... ok
Step #1 - "python_test": test_manual_geocode_city (fbi.crime.geocode_cities_test.GeocodeCitiesTest.test_manual_geocode_city) ... ok
Step #1 - "python_test": test_update_crime_geocode (fbi.crime.geocode_cities_test.GeocodeCitiesTest.test_update_crime_geocode) ... ok
Step #1 - "python_test": test_calculate_crimes (fbi.crime.preprocess_states_test.CleanCrimeFileTest.test_calculate_crimes) ... ok
Step #1 - "python_test": test_clean_crime_file (fbi.crime.preprocess_states_test.CleanCrimeFileTest.test_clean_crime_file) ... ok
Step #1 - "python_test": test_create_tmcf (fbi.crime.preprocess_states_test.CleanCrimeFileTest.test_create_tmcf) ... ok
Step #1 - "python_test": test_calculate_crimes (fbi.crime.preprocess_test.CleanCrimeFileTest.test_calculate_crimes) ... ok
Step #1 - "python_test": test_clean_crime_file (fbi.crime.preprocess_test.CleanCrimeFileTest.test_clean_crime_file) ... ok
Step #1 - "python_test": test_create_tmcf (fbi.crime.preprocess_test.CleanCrimeFileTest.test_create_tmcf) ... ok
Step #1 - "python_test": test_extract_geojsons (fires.perimeter.wfigs_fire_perimeter_test.PerimeterDataTest.test_extract_geojsons) ... ok
Step #1 - "python_test": test_ProcessDF (fires.wfigs_data_test.PreprocessDataTest.test_ProcessDF) ... /workspace/scripts/fires/wfigs_data_test.py:43: FutureWarning: Mismatched null-like values None and nan found. In a future version, pandas equality-testing functions (e.g. assert_frame_equal) will consider these not-matching and raise.
Step #1 - "python_test":   pd.testing.assert_frame_equal(processed,
Step #1 - "python_test": /workspace/scripts/fires/wfigs_data_test.py:43: FutureWarning: Mismatched null-like values None and nan found. In a future version, pandas equality-testing functions (e.g. assert_frame_equal) will consider these not-matching and raise.
Step #1 - "python_test":   pd.testing.assert_frame_equal(processed,
Step #1 - "python_test": /workspace/scripts/fires/wfigs_data_test.py:43: FutureWarning: Mismatched null-like values None and nan found. In a future version, pandas equality-testing functions (e.g. assert_frame_equal) will consider these not-matching and raise.
Step #1 - "python_test":   pd.testing.assert_frame_equal(processed,
Step #1 - "python_test": /workspace/scripts/fires/wfigs_data_test.py:43: FutureWarning: Mismatched null-like values None and nan found. In a future version, pandas equality-testing functions (e.g. assert_frame_equal) will consider these not-matching and raise.
Step #1 - "python_test":   pd.testing.assert_frame_equal(processed,
Step #1 - "python_test": /workspace/scripts/fires/wfigs_data_test.py:43: FutureWarning: Mismatched null-like values None and nan found. In a future version, pandas equality-testing functions (e.g. assert_frame_equal) will consider these not-matching and raise.
Step #1 - "python_test":   pd.testing.assert_frame_equal(processed,
Step #1 - "python_test": /workspace/scripts/fires/wfigs_data_test.py:43: FutureWarning: Mismatched null-like values None and nan found. In a future version, pandas equality-testing functions (e.g. assert_frame_equal) will consider these not-matching and raise.
Step #1 - "python_test":   pd.testing.assert_frame_equal(processed,
Step #1 - "python_test": /workspace/scripts/fires/wfigs_data_test.py:43: FutureWarning: Mismatched null-like values None and nan found. In a future version, pandas equality-testing functions (e.g. assert_frame_equal) will consider these not-matching and raise.
Step #1 - "python_test":   pd.testing.assert_frame_equal(processed,
Step #1 - "python_test": /workspace/scripts/fires/wfigs_data_test.py:43: FutureWarning: Mismatched null-like values None and nan found. In a future version, pandas equality-testing functions (e.g. assert_frame_equal) will consider these not-matching and raise.
Step #1 - "python_test":   pd.testing.assert_frame_equal(processed,
Step #1 - "python_test": /workspace/scripts/fires/wfigs_data_test.py:43: FutureWarning: Mismatched null-like values None and nan found. In a future version, pandas equality-testing functions (e.g. assert_frame_equal) will consider these not-matching and raise.
Step #1 - "python_test":   pd.testing.assert_frame_equal(processed,
Step #1 - "python_test": /workspace/scripts/fires/wfigs_data_test.py:43: FutureWarning: Mismatched null-like values None and nan found. In a future version, pandas equality-testing functions (e.g. assert_frame_equal) will consider these not-matching and raise.
Step #1 - "python_test":   pd.testing.assert_frame_equal(processed,
Step #1 - "python_test": /workspace/scripts/fires/wfigs_data_test.py:43: FutureWarning: Mismatched null-like values None and nan found. In a future version, pandas equality-testing functions (e.g. assert_frame_equal) will consider these not-matching and raise.
Step #1 - "python_test":   pd.testing.assert_frame_equal(processed,
Step #1 - "python_test": ok
Step #1 - "python_test": test1 (google_datasets.covid_mobility.covidmobility_test.TestCovidMobility.test1)
Step #1 - "python_test": Simple test 1 ... ok
Step #1 - "python_test": test2 (google_datasets.covid_mobility.covidmobility_test.TestCovidMobility.test2)
Step #1 - "python_test": Simple test 2 ... ok
Step #1 - "python_test": test3 (google_datasets.covid_mobility.covidmobility_test.TestCovidMobility.test3)
Step #1 - "python_test": Tests a row with empty data. ... ok
Step #1 - "python_test": test4 (google_datasets.covid_mobility.covidmobility_test.TestCovidMobility.test4)
Step #1 - "python_test": Tests a row with an empty date. ... ok
Step #1 - "python_test": test_format_census2001_district_code (india.formatters_test.TestPreprocess.test_format_census2001_district_code) ... ok
Step #1 - "python_test": test_format_census2011_code (india.formatters_test.TestPreprocess.test_format_census2011_code) ... ok
Step #1 - "python_test": test_format_lgd_district_code (india.formatters_test.TestPreprocess.test_format_lgd_district_code) ... ok
Step #1 - "python_test": test_format_lgd_state_code (india.formatters_test.TestPreprocess.test_format_lgd_state_code) ... ok
Step #1 - "python_test": test_get_district_name_to_lgd_code_mapping (india.geo.districts_test.TestPreprocess.test_get_district_name_to_lgd_code_mapping) ... ok
Step #1 - "python_test": test_get_state_name_to_census2001_code_mapping (india.geo.states_test.TestPreprocess.test_get_state_name_to_census2001_code_mapping) ... ok
Step #1 - "python_test": test_get_state_name_to_iso_code_mapping (india.geo.states_test.TestPreprocess.test_get_state_name_to_iso_code_mapping) ... ok
Step #1 - "python_test": test_create_csv (india_aqi.preprocess_test.TestPreprocess.test_create_csv) ... /workspace/scripts/india_aqi/preprocess.py:89: FutureWarning: errors='ignore' is deprecated and will raise in a future version. Use to_numeric without passing `errors` and catch exceptions explicitly instead
Step #1 - "python_test":   self.clean_df[column] = pd.to_numeric(self.clean_df[column],
Step #1 - "python_test": /workspace/scripts/india_aqi/preprocess.py:89: FutureWarning: errors='ignore' is deprecated and will raise in a future version. Use to_numeric without passing `errors` and catch exceptions explicitly instead
Step #1 - "python_test":   self.clean_df[column] = pd.to_numeric(self.clean_df[column],
Step #1 - "python_test": /workspace/scripts/india_aq
...
[Logs truncated due to log size limitations. For full logs, see https://console.cloud.google.com/cloud-build/builds/4f03d5e0-bbcb-4946-988a-36924586b5ed?project=879489846695.]
...
.TestProcess.test_cleanup_name) ... ok
Step #1 - "python_test": test_process (us_eia.opendata.process.common_test.TestProcess.test_process) ... ok
Step #1 - "python_test": test_write_csv_county (us_epa.airdata.air_quality_aggregate_test.TestCriteriaGasesTest.test_write_csv_county) ... ok
Step #1 - "python_test": test_write_csv_csba (us_epa.airdata.air_quality_aggregate_test.TestCriteriaGasesTest.test_write_csv_csba) ... ok
Step #1 - "python_test": test_write_tmcf (us_epa.airdata.air_quality_aggregate_test.TestCriteriaGasesTest.test_write_tmcf) ... ok
Step #1 - "python_test": test_write_csv (us_epa.airdata.air_quality_test.TestCriteriaGasesTest.test_write_csv) ... ok
Step #1 - "python_test": test_write_tmcf (us_epa.airdata.air_quality_test.TestCriteriaGasesTest.test_write_tmcf) ... ok
Step #1 - "python_test": test_write_csv (us_epa.ejscreen.ejscreen_test.TestEjscreen.test_write_csv) ... ok
Step #1 - "python_test": test_e2e (us_epa.facility.process_facility_test.ProcessTest.test_e2e) ... ok
Step #1 - "python_test": test_name_to_dcid (us_epa.ghgrp.gas_test.GasTest.test_name_to_dcid) ... ok
Step #1 - "python_test": test_process_direct_emitters (us_epa.ghgrp.process_test.ProcessTest.test_process_direct_emitters) ... ok
Step #1 - "python_test": test_name_to_dcid (us_epa.ghgrp.sources_test.SourcesTest.test_name_to_dcid) ... ok
Step #1 - "python_test": test_parent_companies_e2e (us_epa.parent_company.process_parent_company_test.ProcessTest.test_parent_companies_e2e) ... ok
Step #1 - "python_test": test_svobs_e2e (us_epa.parent_company.process_parent_company_test.ProcessTest.test_svobs_e2e) ... ok
Step #1 - "python_test": test_e2e_superfund_funding_status (us_epa.superfund.site_and_funding_status.process_sites_fundingStatus_test.ProcessTest.test_e2e_superfund_funding_status) ... ok
Step #1 - "python_test": test_e2e_superfund_sites (us_epa.superfund.site_and_funding_status.process_sites_test.ProcessTest.test_e2e_superfund_sites) ... ok
Step #1 - "python_test": test_e2e_superfund_site_contamination (us_epa.superfund.site_contamination.process_sites_contamination_test.ProcessTest.test_e2e_superfund_site_contamination) ... ok
Step #1 - "python_test": test_e2e (us_epa.superfund.site_hazards.process_sites_hazards_test.ProcessTest.test_e2e) ... ok
Step #1 - "python_test": test_e2e (us_epa.superfund.sites.measurement_sites.generate_measurement_site_mcf_test.ProcessTest.test_e2e) ... ok
Step #1 - "python_test": test_e2e (us_epa.superfund.sites.tar_creek.process_contaminants_test.ProcessTest.test_e2e) ... /workspace/scripts/us_epa/superfund/sites/tar_creek/process_contaminants.py:232: FutureWarning: Downcasting behavior in `replace` is deprecated and will be removed in a future version. To retain the old behavior, explicitly call `result.infer_objects(copy=False)`. To opt-in to the future behavior, set `pd.set_option('future.no_silent_downcasting', True)`
Step #1 - "python_test":   df.replace(to_replace=r'^<.*$', value=0, regex=True,
Step #1 - "python_test": /workspace/scripts/us_epa/superfund/sites/tar_creek/process_contaminants.py:223: FutureWarning: The behavior of DataFrame concatenation with empty or all-NA entries is deprecated. In a future version, this will no longer exclude empty or all-NA columns when determining the result dtypes. To retain the old behavior, exclude the relevant entries before the concat operation.
Step #1 - "python_test":   _CLEAN_CSV_FRAMES.append(pd.concat(clean_csv))
Step #1 - "python_test": /workspace/scripts/us_epa/superfund/sites/tar_creek/process_contaminants.py:252: FutureWarning: The provided callable <built-in function max> is currently using SeriesGroupBy.max. In a future version of pandas, the provided callable will be used directly. To keep current behavior pass the string "max" instead.
Step #1 - "python_test":   as_index=False)['value'].transform(max)
Step #1 - "python_test": ok
Step #1 - "python_test": test_main (us_fema.national_risk_index.generate_schema_and_tmcf_test.ProcessFemaNriFileTest.test_main) ... ok
Step #1 - "python_test": test_county_missing_trailing_zero (us_fema.national_risk_index.process_data_test.FormatGeoIDTest.test_county_missing_trailing_zero) ... ok
Step #1 - "python_test": test_county_no_change_needed (us_fema.national_risk_index.process_data_test.FormatGeoIDTest.test_county_no_change_needed) ... ok
Step #1 - "python_test": test_tract_missing_trailing_zero (us_fema.national_risk_index.process_data_test.FormatGeoIDTest.test_tract_missing_trailing_zero) ... ok
Step #1 - "python_test": test_tract_no_change_needed (us_fema.national_risk_index.process_data_test.FormatGeoIDTest.test_tract_no_change_needed) ... ok
Step #1 - "python_test": test_process_county_file (us_fema.national_risk_index.process_data_test.ProcessFemaNriFileTest.test_process_county_file) ... ok
Step #1 - "python_test": test_process_tract_file (us_fema.national_risk_index.process_data_test.ProcessFemaNriFileTest.test_process_tract_file) ... ok
Step #1 - "python_test": test_preprocess (us_gs.earthquake.preprocess_test.USGSEarthquakePreprocessTest.test_preprocess) ... ok
Step #1 - "python_test": test_output_mcf (world_bank.boundaries.country_boundaries_mcf_generator_test.CountyBoundariesMcfGeneratorTest.test_output_mcf) ... ok
Step #1 - "python_test": 
Step #1 - "python_test": ----------------------------------------------------------------------
Step #1 - "python_test": Ran 165 tests in 125.686s
Step #1 - "python_test": 
Step #1 - "python_test": OK
Step #1 - "python_test": sys:1: ResourceWarning: unclosed file <_io.TextIOWrapper name='/workspace/scripts/aqicn/cities.csv' mode='r' encoding='UTF-8'>
Step #1 - "python_test": #### Testing Python code in import-automation/executor
Step #1 - "python_test": /workspace/.env/lib/python3.11/site-packages/requests/__init__.py:102: RequestsDependencyWarning: urllib3 (1.26.8) or chardet (5.2.0)/charset_normalizer (2.0.12) doesn't match a supported version!
Step #1 - "python_test":   warnings.warn("urllib3 ({}) or chardet ({})/charset_normalizer ({}) doesn't match a supported "
Step #1 - "python_test": test_appengine_job_request (test.cloud_scheduler_test.CloudSchedulerTest.test_appengine_job_request) ... ok
Step #1 - "python_test": test_cloud_run_job_request (test.cloud_scheduler_test.CloudSchedulerTest.test_cloud_run_job_request) ... ok
Step #1 - "python_test": test_gke_job_request (test.cloud_scheduler_test.CloudSchedulerTest.test_gke_job_request) ... ok
Step #1 - "python_test": test_invalid_string_args (test.file_uploader_test.GCSFileUploaderTest.test_invalid_string_args) ... ok
Step #1 - "python_test": test_upload_file (test.file_uploader_test.GCSFileUploaderTest.test_upload_file) ... ok
Step #1 - "python_test": test_upload_string (test.file_uploader_test.GCSFileUploaderTest.test_upload_string) ... ok
Step #1 - "python_test": test_invalid_string_args (test.file_uploader_test.LocalFileUploaderTest.test_invalid_string_args) ... ok
Step #1 - "python_test": test_upload_file (test.file_uploader_test.LocalFileUploaderTest.test_upload_file) ... ok
Step #1 - "python_test": test_upload_string (test.file_uploader_test.LocalFileUploaderTest.test_upload_string) ... ok
Step #1 - "python_test": test_build_commit_query (test.github_api_test.GitHubAPITest.test_build_commit_query) ... ok
Step #1 - "python_test": test_build_content_query (test.github_api_test.GitHubAPITest.test_build_content_query) ... ok
Step #1 - "python_test": test_dir_exists (test.github_api_test.GitHubAPITest.test_dir_exists) ... ok
Step #1 - "python_test": test_dir_exists_not (test.github_api_test.GitHubAPITest.test_dir_exists_not) ... ok
Step #1 - "python_test": test_dir_exists_other_error (test.github_api_test.GitHubAPITest.test_dir_exists_other_error) ... ok
Step #1 - "python_test": test_download_repo (test.github_api_test.GitHubAPITest.test_download_repo) ... ok
Step #1 - "python_test": test_download_repo_empty (test.github_api_test.GitHubAPITest.test_download_repo_empty) ... ok
Step #1 - "python_test": test_download_repo_http_error (test.github_api_test.GitHubAPITest.test_download_repo_http_error) ... ok
Step #1 - "python_test": test_download_repo_timeout (test.github_api_test.GitHubAPITest.test_download_repo_timeout) ... ok
Step #1 - "python_test": test_find_dirs_in_commit (test.github_api_test.GitHubAPITest.test_find_dirs_in_commit) ... ok
Step #1 - "python_test": test_find_dirs_in_commit_empty (test.github_api_test.GitHubAPITest.test_find_dirs_in_commit_empty) ... ok
Step #1 - "python_test": test_find_dirs_up_path (test.github_api_test.GitHubAPITest.test_find_dirs_up_path) ... ok
Step #1 - "python_test": test_find_dirs_up_path_empty (test.github_api_test.GitHubAPITest.test_find_dirs_up_path_empty) ... ok
Step #1 - "python_test": test_find_dirs_up_path_root (test.github_api_test.GitHubAPITest.test_find_dirs_up_path_root) ... ok
Step #1 - "python_test": test_find_dirs_up_path_skip_call (test.github_api_test.GitHubAPITest.test_find_dirs_up_path_skip_call) ... ok
Step #1 - "python_test": test_get_path_first_component (test.github_api_test.GitHubAPITest.test_get_path_first_component) ... ok
Step #1 - "python_test": test_path_containing_file (test.github_api_test.GitHubAPITest.test_path_containing_file) ... ok
Step #1 - "python_test": test_path_containing_file_not_exist (test.github_api_test.GitHubAPITest.test_path_containing_file_not_exist) ... ok
Step #1 - "python_test": test_query_changed_files_in_commit (test.github_api_test.GitHubAPITest.test_query_changed_files_in_commit) ... ok
Step #1 - "python_test": test_query_changed_files_in_commit_raise (test.github_api_test.GitHubAPITest.test_query_changed_files_in_commit_raise) ... ok
Step #1 - "python_test": test_query_commit (test.github_api_test.GitHubAPITest.test_query_commit) ... ok
Step #1 - "python_test": test_query_files_in_dir (test.github_api_test.GitHubAPITest.test_query_files_in_dir) ... ok
Step #1 - "python_test": test_query_files_in_dir_raise (test.github_api_test.GitHubAPITest.test_query_files_in_dir_raise) ... ok
Step #1 - "python_test": test_clean_date (test.import_executor_test.ImportExecutorTest.test_clean_date) ... ok
Step #1 - "python_test": test_clean_time (test.import_executor_test.ImportExecutorTest.test_clean_time) ... ok
Step #1 - "python_test": test_construct_process_message (test.import_executor_test.ImportExecutorTest.test_construct_process_message) ... ok
Step #1 - "python_test": test_construct_process_message_no_output (test.import_executor_test.ImportExecutorTest.test_construct_process_message_no_output)
Step #1 - "python_test": Tests that _construct_process_message does not append ... ok
Step #1 - "python_test": test_create_venv (test.import_executor_test.ImportExecutorTest.test_create_venv) ... ok
Step #1 - "python_test": test_run_and_handle_exception (test.import_executor_test.ImportExecutorTest.test_run_and_handle_exception) ... ERROR:root:An unexpected exception was thrown
Step #1 - "python_test": Traceback (most recent call last):
Step #1 - "python_test":   File "/workspace/import-automation/executor/app/executor/import_executor.py", line 640, in run_and_handle_exception
Step #1 - "python_test":     return exec_func(*args)
Step #1 - "python_test":            ^^^^^^^^^^^^^^^^
Step #1 - "python_test":   File "/workspace/import-automation/executor/test/import_executor_test.py", line 68, in raise_exception
Step #1 - "python_test":     raise Exception
Step #1 - "python_test": Exception
Step #1 - "python_test": ok
Step #1 - "python_test": test_run_with_timeout (test.import_executor_test.ImportExecutorTest.test_run_with_timeout) ... ERROR:root:An unexpected exception was thrown: Command '['sleep', '5']' timed out after 0.1 seconds when running ['sleep', '5']: Traceback (most recent call last):
Step #1 - "python_test":   File "/workspace/import-automation/executor/app/executor/import_executor.py", line 741, in _run_with_timeout
Step #1 - "python_test":     process = subprocess.run(args,
Step #1 - "python_test":               ^^^^^^^^^^^^^^^^^^^^
Step #1 - "python_test":   File "/usr/local/lib/python3.11/subprocess.py", line 550, in run
Step #1 - "python_test":     stdout, stderr = process.communicate(input, timeout=timeout)
Step #1 - "python_test":                      ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Step #1 - "python_test":   File "/usr/local/lib/python3.11/subprocess.py", line 1209, in communicate
Step #1 - "python_test":     stdout, stderr = self._communicate(input, endtime, timeout)
Step #1 - "python_test":                      ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Step #1 - "python_test":   File "/usr/local/lib/python3.11/subprocess.py", line 2116, in _communicate
Step #1 - "python_test":     self._check_timeout(endtime, orig_timeout, stdout, stderr)
Step #1 - "python_test":   File "/usr/local/lib/python3.11/subprocess.py", line 1253, in _check_timeout
Step #1 - "python_test":     raise TimeoutExpired(
Step #1 - "python_test": subprocess.TimeoutExpired: Command '['sleep', '5']' timed out after 0.1 seconds
Step #1 - "python_test": Traceback (most recent call last):
Step #1 - "python_test":   File "/workspace/import-automation/executor/app/executor/import_executor.py", line 741, in _run_with_timeout
Step #1 - "python_test":     process = subprocess.run(args,
Step #1 - "python_test":               ^^^^^^^^^^^^^^^^^^^^
Step #1 - "python_test":   File "/usr/local/lib/python3.11/subprocess.py", line 550, in run
Step #1 - "python_test":     stdout, stderr = process.communicate(input, timeout=timeout)
Step #1 - "python_test":                      ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Step #1 - "python_test":   File "/usr/local/lib/python3.11/subprocess.py", line 1209, in communicate
Step #1 - "python_test":     stdout, stderr = self._communicate(input, endtime, timeout)
Step #1 - "python_test":                      ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
Step #1 - "python_test":   File "/usr/local/lib/python3.11/subprocess.py", line 2116, in _communicate
Step #1 - "python_test":     self._check_timeout(endtime, orig_timeout, stdout, stderr)
Step #1 - "python_test":   File "/usr/local/lib/python3.11/subprocess.py", line 1253, in _check_timeout
Step #1 - "python_test":     raise TimeoutExpired(
Step #1 - "python_test": subprocess.TimeoutExpired: Command '['sleep', '5']' timed out after 0.1 seconds
Step #1 - "python_test": ok
Step #1 - "python_test": test_are_imports_finished (test.import_service_test.ImportServiceTest.test_are_imports_finished) ... ok
Step #1 - "python_test": test_block_on_import (test.import_service_test.ImportServiceTest.test_block_on_import) ... ok
Step #1 - "python_test": test_fix_input_path (test.import_service_test.ImportServiceTest.test_fix_input_path) ... ok
Step #1 - "python_test": test_format_import_info (test.import_service_test.ImportServiceTest.test_format_import_info) ... ok
Step #1 - "python_test": test_get_fixed_absolute_import_name (test.import_service_test.ImportServiceTest.test_get_fixed_absolute_import_name) ... ok
Step #1 - "python_test": test_get_import_id (test.import_service_test.ImportServiceTest.test_get_import_id) ... ok
Step #1 - "python_test": test_smart_import (test.import_service_test.ImportServiceTest.test_smart_import) ... ok
Step #1 - "python_test": test_absolute_import_name (test.import_target_test.ImportTargetTest.test_absolute_import_name) ... ok
Step #1 - "python_test": test_is_import_targetted_by_commit (test.import_target_test.ImportTargetTest.test_is_import_targetted_by_commit) ... ok
Step #1 - "python_test": test_parse_commit_message_targets (test.import_target_test.ImportTargetTest.test_parse_commit_message_targets) ... ok
Step #1 - "python_test": test_download_file (test.utils_test.AppUtilsTest.test_download_file)
Step #1 - "python_test": Response does not have a Content-Disposition header. ... ok
Step #1 - "python_test": test_download_file_timeout (test.utils_test.AppUtilsTest.test_download_file_timeout)
Step #1 - "python_test": Raises requests.Timeout exception. ... ok
Step #1 - "python_test": test_get_filename (test.utils_test.AppUtilsTest.test_get_filename) ... ok
Step #1 - "python_test": test_get_filename_raise (test.utils_test.AppUtilsTest.test_get_filename_raise) ... ok
Step #1 - "python_test": test_next_utc_date (test.utils_test.AppUtilsTest.test_next_utc_date)
Step #1 - "python_test": Tests next_utc_date. ... ok
Step #1 - "python_test": test_pacific_time_to_datetime (test.utils_test.AppUtilsTest.test_pacific_time_to_datetime)
Step #1 - "python_test": Tests that the string returned by pacific_time can be converted to ... ok
Step #1 - "python_test": test_pacific_time_to_datetime_then_back (test.utils_test.AppUtilsTest.test_pacific_time_to_datetime_then_back)
Step #1 - "python_test": Tests that the string returned by pacific_time can be converted to ... ok
Step #1 - "python_test": test_compare_lines (test.utils_test.TestUtilsTest.test_compare_lines) ... ok
Step #1 - "python_test": test_import_spec_valid (test.validation_test.ValidationTest.test_import_spec_valid) ... ok
Step #1 - "python_test": test_import_spec_valid_fields_absent (test.validation_test.ValidationTest.test_import_spec_valid_fields_absent) ... ok
Step #1 - "python_test": test_import_spec_valid_script_not_exist (test.validation_test.ValidationTest.test_import_spec_valid_script_not_exist) ... ok
Step #1 - "python_test": test_import_targets_valid_absolute_names (test.validation_test.ValidationTest.test_import_targets_valid_absolute_names) ... ok
Step #1 - "python_test": test_import_targets_valid_manifest_not_exist (test.validation_test.ValidationTest.test_import_targets_valid_manifest_not_exist) ... ok
Step #1 - "python_test": test_import_targets_valid_name_not_exist (test.validation_test.ValidationTest.test_import_targets_valid_name_not_exist) ... ok
Step #1 - "python_test": test_import_targets_valid_relative_names (test.validation_test.ValidationTest.test_import_targets_valid_relative_names) ... ok
Step #1 - "python_test": test_import_targets_valid_relative_names_multiple_dirs (test.validation_test.ValidationTest.test_import_targets_valid_relative_names_multiple_dirs) ... ok
Step #1 - "python_test": test_manifest_valid_fields_absent (test.validation_test.ValidationTest.test_manifest_valid_fields_absent) ... ok
Step #1 - "python_test": 
Step #1 - "python_test": ----------------------------------------------------------------------
Step #1 - "python_test": Ran 66 tests in 7.196s
Step #1 - "python_test": 
Step #1 - "python_test": OK
Step #1 - "python_test": #### Testing Python code in tools/
Step #1 - "python_test": /workspace/.env/lib/python3.11/site-packages/requests/__init__.py:102: RequestsDependencyWarning: urllib3 (1.26.8) or chardet (5.2.0)/charset_normalizer (2.0.12) doesn't match a supported version!
Step #1 - "python_test":   warnings.warn("urllib3 ({}) or chardet ({})/charset_normalizer ({}) doesn't match a supported "
Step #1 - "python_test": test_diff_analysis (import_differ.import_differ_test.TestImportDiffer.test_diff_analysis) ... ok
Step #1 - "python_test": test_validation (import_validation.import_validation_test.TestValidation.test_validation) ... ERROR:absl:AssertionError('Validation failed: UNMODIFIED_COUNT')
Step #1 - "python_test": ok
Step #1 - "python_test": test_evaluate_statement (statvar_importer.eval_functions_test.TestEvalFunctions.test_evaluate_statement) ... ok
Step #1 - "python_test": test_format_date (statvar_importer.eval_functions_test.TestEvalFunctions.test_format_date) ... ok
Step #1 - "python_test": test_str_to_camel_case (statvar_importer.eval_functions_test.TestEvalFunctions.test_str_to_camel_case) ... ok
Step #1 - "python_test": test_diff_mcf_files (statvar_importer.mcf_diff_test.TestMCFDiff.test_diff_mcf_files) ... Counters:
Step #1 - "python_test":    input-nodes-in-mcf1:/tmp/tmp2lhlhdei/sample.mcf =          2
Step #1 - "python_test":                                        process-mem =  394846208
Step #1 - "python_test":                                    process-mem-rss =  164331520
Step #1 - "python_test":                              process-time-sys-secs =       0.17
Step #1 - "python_test":                             process-time-user-secs =       0.99
Step #1 - "python_test":                               process_elapsed_time =       0.00
Step #1 - "python_test":                                          processed =          0
Step #1 - "python_test":                                         start_time =     391.79
Step #1 - "python_test": ok
Step #1 - "python_test": test_diff_mcf_node_pvs (statvar_importer.mcf_diff_test.TestMCFDiff.test_diff_mcf_node_pvs)
Step #1 - "python_test": Test diff on MCF node dictionary. ... ok
Step #1 - "python_test": test_add_namespace (statvar_importer.mcf_file_util_test.TestMCFFileUtil.test_add_namespace) ... ok
Step #1 - "python_test": test_load_mcf_file (statvar_importer.mcf_file_util_test.TestMCFFileUtil.test_load_mcf_file) ... Counters:
Step #1 - "python_test":                                        PVs-matched =          1
Step #1 - "python_test":                                        process-mem =  394846208
Step #1 - "python_test":                                    process-mem-rss =  164331520
Step #1 - "python_test":                              process-time-sys-secs =       0.17
Step #1 - "python_test":                             process-time-user-secs =       1.00
Step #1 - "python_test":                               process_elapsed_time =       0.00
Step #1 - "python_test":                                          processed =          0
Step #1 - "python_test":                                         start_time =     391.80
Step #1 - "python_test": ok
Step #1 - "python_test": test_normalize_mcf_node (statvar_importer.mcf_file_util_test.TestMCFFileUtil.test_normalize_mcf_node) ... ok
Step #1 - "python_test": test_normalize_range (statvar_importer.mcf_file_util_test.TestMCFFileUtil.test_normalize_range) ... ok
Step #1 - "python_test": test_strip_namespace (statvar_importer.mcf_file_util_test.TestMCFFileUtil.test_strip_namespace) ... ok
Step #1 - "python_test": test_drop_existing_mcf_nodes (statvar_importer.mcf_filter_test.TestMCFFilter.test_drop_existing_mcf_nodes) ... Counters:
Step #1 - "python_test":                            existing-nodes-from-api =          2
Step #1 - "python_test":                                        process-mem =  394846208
Step #1 - "python_test":                                    process-mem-rss =  165752832
Step #1 - "python_test":                              process-time-sys-secs =       0.17
Step #1 - "python_test":                             process-time-user-secs =       1.00
Step #1 - "python_test":                               process_elapsed_time =       0.16
Step #1 - "python_test":                                          processed =          0
Step #1 - "python_test":                                         start_time =     391.80
Step #1 - "python_test": WARNING:absl:Diff in ignored Node: Count_Person, diff:
Step #1 - "python_test": + Node: dcid:Count_Person
Step #1 - "python_test":   typeOf: dcid:StatisticalVariable
Step #1 - "python_test": + name: "Total Population"
Step #1 - "python_test":   populationType: dcid:Person
Step #1 - "python_test":   measuredProperty: dcid:count
Step #1 - "python_test":   statType: dcid:measuredValue
Step #1 - "python_test": + memberOf: dcid:dc/g/Demographics
Step #1 - "python_test": + provenance: dcid:dc/base/HumanReadableStatVars
Step #1 - "python_test": 
Step #1 - "python_test": WARNING:absl:Diff in ignored Node: dcid:Count_Person_Male, diff:
Step #1 - "python_test": + Node: dcid:Count_Person_Male
Step #1 - "python_test":   typeOf: dcid:StatisticalVariable
Step #1 - "python_test": + name: "Male Population"
Step #1 - "python_test":   populationType: dcid:Person
Step #1 - "python_test":   measuredProperty: dcid:count
Step #1 - "python_test":   statType: dcid:measuredValue
Step #1 - "python_test": + constraintProperties: dcid:gender
Step #1 - "python_test":   gender: dcid:Male
Step #1 - "python_test": + memberOf: dcid:dc/g/Person_Gender-Male
Step #1 - "python_test": + provenance: dcid:dc/base/HumanReadableStatVars
Step #1 - "python_test": 
Step #1 - "python_test": ok
Step #1 - "python_test": test_filter_mcf_file (statvar_importer.mcf_filter_test.TestMCFFilter.test_filter_mcf_file) ... WARNING:absl:Ignoring node without a dcid: OrderedDict([('# comment1', '# Auto generated using command: "python3 -m unittest stat_var_processor_test.py" on 2023-02-21 15:17:19.653404')])
Step #1 - "python_test": WARNING:absl:Ignoring node without a dcid: OrderedDict([('# comment1', '# Auto generated using command: "python3 -m unittest stat_var_processor_test.py" on 2023-02-21 15:17:19.653404')])
Step #1 - "python_test": Counters:
Step #1 - "python_test":                                        input-nodes =       1261
Step #1 - "python_test":                                        process-mem =  399040512
Step #1 - "python_test":                                    process-mem-rss =  169537536
Step #1 - "python_test":                              process-time-sys-secs =       0.17
Step #1 - "python_test":                             process-time-user-secs =       1.16
Step #1 - "python_test":                               process_elapsed_time =       0.07
Step #1 - "python_test":                                          processed =          0
Step #1 - "python_test":                                         start_time =     392.04
Step #1 - "python_test": Counters:
Step #1 - "python_test":                                        PVs-matched =        157
Step #1 - "python_test":                                ignore-nodes-loaded =       1261
Step #1 - "python_test":                                        input-nodes =       1261
Step #1 - "python_test":                                input-nodes-ignored =         23
Step #1 - "python_test":                                      nodes-matched =         23
Step #1 - "python_test":                                       output-nodes =       1238
Step #1 - "python_test":                                        process-mem =  399040512
Step #1 - "python_test":                                    process-mem-rss =  169537536
Step #1 - "python_test":                              process-time-sys-secs =       0.17
Step #1 - "python_test":                             process-time-user-secs =       1.18
Step #1 - "python_test":                               process_elapsed_time =       0.09
Step #1 - "python_test":                                          processed =          0
Step #1 - "python_test":                                         start_time =     392.04
Step #1 - "python_test": ok
Step #1 - "python_test": test_lookup_string (statvar_importer.ngram_matcher_test.NgramMatcherTest.test_lookup_string) ... ok
Step #1 - "python_test": test_add_entry (statvar_importer.property_value_cache_test.PropertyValueCacheTest.test_add_entry) ... Counters:
Step #1 - "python_test":                                        process-mem =  399040512
Step #1 - "python_test":                                    process-mem-rss =  169537536
Step #1 - "python_test":                              process-time-sys-secs =       0.17
Step #1 - "python_test":                             process-time-user-secs =       1.18
Step #1 - "python_test":                               process_elapsed_time =       0.00
Step #1 - "python_test":                                          processed =          0
Step #1 - "python_test":                               pv-cache-misses-dcid =          1
Step #1 - "python_test":                                         start_time =     392.13
Step #1 - "python_test": ERROR:absl:Conflicting name:california old:{'name': ['California', 'CA'], 'dcid': 'geoId/06', 'typeOf': ['AdministrativeArea1', 'State']} new:{'name': ['California', 'CA'], 'dcid': 'geoId/06', 'typeOf': ['AdministrativeArea1', 'State']}
Step #1 - "python_test": ok
Step #1 - "python_test": test_flatten_dict (statvar_importer.property_value_cache_test.PropertyValueCacheTest.test_flatten_dict) ... ok
Step #1 - "python_test": test_load_pvmap (statvar_importer.property_value_mapper_test.PropertyValueMapperTest.test_load_pvmap) ... Counters:
Step #1 - "python_test":                                        process-mem =  399040512
Step #1 - "python_test":                                    process-mem-rss =  169537536
Step #1 - "python_test":                              process-time-sys-secs =       0.17
Step #1 - "python_test":                             process-time-user-secs =       1.18
Step #1 - "python_test":                               process_elapsed_time =       0.00
Step #1 - "python_test":                                          processed =          0
Step #1 - "python_test":                                         start_time =     392.13
Step #1 - "python_test":                       warning-missing-property-key =          1
Step #1 - "python_test": ok
Step #1 - "python_test": test_process_pvs (statvar_importer.property_value_mapper_test.PropertyValueMapperTest.test_process_pvs) ... Counters:
Step #1 - "python_test":                                        process-mem =  399040512
Step #1 - "python_test":                                    process-mem-rss =  169537536
Step #1 - "python_test":                              process-time-sys-secs =       0.17
Step #1 - "python_test":                             process-time-user-secs =       1.18
Step #1 - "python_test":                               process_elapsed_time =       0.00
Step #1 - "python_test":                                          processed =          0
Step #1 - "python_test":                                    processed-regex =          1
Step #1 - "python_test":                                         start_time =     392.14
Step #1 - "python_test": ok
Step #1 - "python_test": test_pvmap_get_all_pvs (statvar_importer.property_value_mapper_test.PropertyValueMapperTest.test_pvmap_get_all_pvs) ... Counters:
Step #1 - "python_test":                                        process-mem =  399040512
Step #1 - "python_test":                                    process-mem-rss =  169537536
Step #1 - "python_test":                              process-time-sys-secs =       0.17
Step #1 - "python_test":                             process-time-user-secs =       1.18
Step #1 - "python_test":                               process_elapsed_time =       0.00
Step #1 - "python_test":                                          processed =          0
Step #1 - "python_test":                                         start_time =     392.14
Step #1 - "python_test":                       warning-missing-property-key =          1
Step #1 - "python_test": ok
Step #1 - "python_test": 
Step #1 - "python_test": ----------------------------------------------------------------------
Step #1 - "python_test": Ran 20 tests in 0.401s
Step #1 - "python_test": 
Step #1 - "python_test": OK
Step #1 - "python_test": #### Testing Python lint
Finished Step #1 - "python_test"
PUSH
DONE