2019-11-05 10:40:56,671 utils.prepare_workspace(109) INFO Writing log messages to H:\SIG\Procesos SIG\Invest_water\test_regiones\maule\Invest_output\InVEST-Seasonal-Water-Yield-log-2019-11-05--10_40_56.txt 2019-11-05 10:40:56,687 model._logged_target(1633) Level 100 Starting model with parameters: Arguments for InVEST natcap.invest.seasonal_water_yield.seasonal_water_yield 3.7.0: alpha_m aoi_path H:/SIG/Procesos SIG/Invest_water/test_regiones/maule/AOI/maule.shp beta_i 1 biophysical_table_path H:/SIG/Procesos SIG/Invest_water/test_regiones/maule/tabla_biof_maule.csv dem_raster_path H:/SIG/Procesos SIG/Invest_water/test_regiones/maule/DEM/maule_v3.tif et0_dir H:\SIG\Procesos SIG\Invest_water\test_regiones\maule\ET0\CGIAIRCSI\CGIAIRCSI_CLIP\buffer gamma 1 lulc_raster_path H:/SIG/Procesos SIG/Invest_water/test_regiones/maule/landuse/LU_maule.tif monthly_alpha True monthly_alpha_path H:/SIG/Procesos SIG/Invest_water/test_regiones/maule/monthly_alpha_maule.csv n_workers -1 precip_dir H:\SIG\Procesos SIG\Invest_water\test_regiones\maule\Precipitation_mensual\pp_buffer rain_events_table_path H:/SIG/Procesos SIG/Invest_water/test_regiones/maule/rain_events_maule.csv results_suffix 5 soil_group_path H:/SIG/Procesos SIG/Invest_water/test_regiones/maule/Soil group/Soil_NASA/soil_maule7.tif threshold_flow_accumulation 1000 user_defined_climate_zones False user_defined_local_recharge False workspace_dir H:\SIG\Procesos SIG\Invest_water\test_regiones\maule\Invest_output 2019-11-05 10:40:56,765 seasonal_water_yield._execute(184) INFO prepare and test inputs for common errors 2019-11-05 10:40:57,092 seasonal_water_yield._execute(246) INFO Building file registry 2019-11-05 10:40:57,092 seasonal_water_yield._execute(252) INFO Checking that the AOI is not the output aggregate vector 2019-11-05 10:40:57,092 seasonal_water_yield._execute(261) INFO Aligning and clipping dataset list 2019-11-05 10:40:57,480 geoprocessing.align_and_resize_raster_stack(729) INFO n_workers > 1 (5) so starting a processes pool. 2019-11-05 10:41:06,822 geoprocessing.align_and_resize_raster_stack(779) INFO 1 of 27 aligned: prcp_a0_5.tif 2019-11-05 10:41:07,441 geoprocessing.align_and_resize_raster_stack(779) INFO 2 of 27 aligned: prcp_a1_5.tif 2019-11-05 10:41:08,306 geoprocessing.align_and_resize_raster_stack(779) INFO 3 of 27 aligned: prcp_a2_5.tif 2019-11-05 10:41:09,141 geoprocessing.align_and_resize_raster_stack(779) INFO 4 of 27 aligned: prcp_a3_5.tif 2019-11-05 10:41:09,543 geoprocessing.align_and_resize_raster_stack(779) INFO 5 of 27 aligned: prcp_a4_5.tif 2019-11-05 10:41:12,171 geoprocessing.align_and_resize_raster_stack(779) INFO 6 of 27 aligned: prcp_a5_5.tif 2019-11-05 10:41:12,834 geoprocessing.align_and_resize_raster_stack(779) INFO 7 of 27 aligned: prcp_a6_5.tif 2019-11-05 10:41:13,697 geoprocessing.align_and_resize_raster_stack(779) INFO 8 of 27 aligned: prcp_a7_5.tif 2019-11-05 10:41:14,513 geoprocessing.align_and_resize_raster_stack(779) INFO 9 of 27 aligned: prcp_a8_5.tif 2019-11-05 10:41:14,811 geoprocessing.align_and_resize_raster_stack(779) INFO 10 of 27 aligned: prcp_a9_5.tif 2019-11-05 10:41:17,374 geoprocessing.align_and_resize_raster_stack(779) INFO 11 of 27 aligned: prcp_a10_5.tif 2019-11-05 10:41:17,882 geoprocessing.align_and_resize_raster_stack(779) INFO 12 of 27 aligned: prcp_a11_5.tif 2019-11-05 10:41:17,882 geoprocessing.align_and_resize_raster_stack(779) INFO 13 of 27 aligned: soil_group_aligned_5.tif 2019-11-05 10:41:19,591 geoprocessing.align_and_resize_raster_stack(779) INFO 14 of 27 aligned: et0_a0_5.tif 2019-11-05 10:41:19,901 geoprocessing.align_and_resize_raster_stack(779) INFO 15 of 27 aligned: et0_a1_5.tif 2019-11-05 10:41:22,049 geoprocessing.align_and_resize_raster_stack(779) INFO 16 of 27 aligned: et0_a2_5.tif 2019-11-05 10:41:22,621 geoprocessing.align_and_resize_raster_stack(779) INFO 17 of 27 aligned: et0_a3_5.tif 2019-11-05 10:41:23,055 geoprocessing.align_and_resize_raster_stack(779) INFO 18 of 27 aligned: et0_a4_5.tif 2019-11-05 10:41:24,815 geoprocessing.align_and_resize_raster_stack(779) INFO 19 of 27 aligned: et0_a5_5.tif 2019-11-05 10:41:25,046 geoprocessing.align_and_resize_raster_stack(779) INFO 20 of 27 aligned: et0_a6_5.tif 2019-11-05 10:41:27,267 geoprocessing.align_and_resize_raster_stack(779) INFO 21 of 27 aligned: et0_a7_5.tif 2019-11-05 10:41:27,779 geoprocessing.align_and_resize_raster_stack(779) INFO 22 of 27 aligned: et0_a8_5.tif 2019-11-05 10:41:28,217 geoprocessing.align_and_resize_raster_stack(779) INFO 23 of 27 aligned: et0_a9_5.tif 2019-11-05 10:41:29,950 geoprocessing.align_and_resize_raster_stack(779) INFO 24 of 27 aligned: et0_a10_5.tif 2019-11-05 10:41:30,108 geoprocessing.align_and_resize_raster_stack(779) INFO 25 of 27 aligned: et0_a11_5.tif 2019-11-05 10:41:31,756 geoprocessing.align_and_resize_raster_stack(779) INFO 26 of 27 aligned: lulc_aligned_5.tif 2019-11-05 10:41:37,647 geoprocessing.align_and_resize_raster_stack(779) INFO 27 of 27 aligned: dem_aligned_5.tif 2019-11-05 10:41:37,710 geoprocessing.align_and_resize_raster_stack(788) INFO aligned all 27 rasters. 2019-11-05 10:41:48,993 Task._call(1068) INFO 0.0% complete 2019-11-05 10:41:54,752 Task._call(1068) INFO 7.7% complete 2019-11-05 10:42:00,109 Task._call(1068) INFO 11.6% complete 2019-11-05 10:42:06,361 Task._call(1068) INFO 15.4% complete 2019-11-05 10:42:12,266 Task._call(1068) INFO 19.3% complete 2019-11-05 10:42:18,033 Task._call(1068) INFO 19.3% complete 2019-11-05 10:42:24,012 Task._call(1068) INFO 23.1% complete 2019-11-05 10:42:30,049 Task._call(1068) INFO 23.1% complete 2019-11-05 10:42:36,371 Task._call(1068) INFO 27.0% complete 2019-11-05 10:42:42,032 Task._call(1068) INFO 27.0% complete 2019-11-05 10:42:48,256 Task._call(1068) INFO 30.9% complete 2019-11-05 10:42:54,196 Task._call(1068) INFO 30.9% complete 2019-11-05 10:43:00,440 Task._call(1068) INFO 34.7% complete 2019-11-05 10:43:06,105 Task._call(1068) INFO 34.7% complete 2019-11-05 10:43:12,539 Task._call(1068) INFO 38.6% complete 2019-11-05 10:43:18,032 Task._call(1068) INFO 38.6% complete 2019-11-05 10:43:24,045 Task._call(1068) INFO 42.4% complete 2019-11-05 10:43:30,523 Task._call(1068) INFO 42.4% complete 2019-11-05 10:43:36,305 Task._call(1068) INFO 42.4% complete 2019-11-05 10:43:42,114 Task._call(1068) INFO 46.3% complete 2019-11-05 10:43:48,125 Task._call(1068) INFO 46.3% complete 2019-11-05 10:43:54,368 Task._call(1068) INFO 50.1% complete 2019-11-05 10:44:00,015 Task._call(1068) INFO 50.1% complete 2019-11-05 10:44:06,030 Task._call(1068) INFO 54.0% complete 2019-11-05 10:44:12,467 Task._call(1068) INFO 54.0% complete 2019-11-05 10:44:18,122 Task._call(1068) INFO 54.0% complete 2019-11-05 10:44:24,203 Task._call(1068) INFO 57.9% complete 2019-11-05 10:44:30,125 Task._call(1068) INFO 57.9% complete 2019-11-05 10:44:36,391 Task._call(1068) INFO 61.7% complete 2019-11-05 10:44:42,161 Task._call(1068) INFO 61.7% complete 2019-11-05 10:44:48,414 Task._call(1068) INFO 65.6% complete 2019-11-05 10:44:55,045 Task._call(1068) INFO 65.6% complete 2019-11-05 10:45:01,028 Task._call(1068) INFO 65.6% complete 2019-11-05 10:45:07,040 Task._call(1068) INFO 69.4% complete 2019-11-05 10:45:13,029 Task._call(1068) INFO 69.4% complete 2019-11-05 10:45:19,321 Task._call(1068) INFO 69.4% complete 2019-11-05 10:45:25,326 Task._call(1068) INFO 73.3% complete 2019-11-05 10:45:31,073 Task._call(1068) INFO 73.3% complete 2019-11-05 10:45:37,558 Task._call(1068) INFO 77.1% complete 2019-11-05 10:45:43,267 Task._call(1068) INFO 77.1% complete 2019-11-05 10:45:49,128 Task._call(1068) INFO 81.0% complete 2019-11-05 10:45:55,114 Task._call(1068) INFO 84.9% complete 2019-11-05 10:45:58,996 Task._call(1068) INFO 100.0% complete 2019-11-05 10:46:09,086 Task._call(1068) INFO 0.0% complete 2019-11-05 10:46:15,240 Task._call(1068) INFO 7.7% complete 2019-11-05 10:46:21,059 Task._call(1068) INFO 11.6% complete 2019-11-05 10:46:27,318 Task._call(1068) INFO 15.4% complete 2019-11-05 10:46:33,051 Task._call(1068) INFO 19.3% complete 2019-11-05 10:46:39,348 Task._call(1068) INFO 19.3% complete 2019-11-05 10:46:45,352 Task._call(1068) INFO 19.3% complete 2019-11-05 10:46:51,653 Task._call(1068) INFO 23.1% complete 2019-11-05 10:46:57,617 Task._call(1068) INFO 23.1% complete 2019-11-05 10:47:03,357 Task._call(1068) INFO 23.2% complete 2019-11-05 10:47:09,153 Task._call(1068) INFO 27.0% complete 2019-11-05 10:47:15,200 Task._call(1068) INFO 27.0% complete 2019-11-05 10:47:21,134 Task._call(1068) INFO 27.0% complete 2019-11-05 10:47:27,111 Task._call(1068) INFO 30.9% complete 2019-11-05 10:47:33,470 Task._call(1068) INFO 30.9% complete 2019-11-05 10:47:39,328 Task._call(1068) INFO 30.9% complete 2019-11-05 10:47:45,401 Task._call(1068) INFO 34.7% complete 2019-11-05 10:47:51,657 Task._call(1068) INFO 34.7% complete 2019-11-05 10:47:57,147 Task._call(1068) INFO 34.7% complete 2019-11-05 10:48:03,082 Task._call(1068) INFO 38.6% complete 2019-11-05 10:48:09,072 Task._call(1068) INFO 38.6% complete 2019-11-05 10:48:15,611 Task._call(1068) INFO 38.6% complete 2019-11-05 10:48:21,280 Task._call(1068) INFO 42.4% complete 2019-11-05 10:48:27,062 Task._call(1068) INFO 42.4% complete 2019-11-05 10:48:33,631 Task._call(1068) INFO 42.4% complete 2019-11-05 10:48:39,198 Task._call(1068) INFO 46.3% complete 2019-11-05 10:48:45,427 Task._call(1068) INFO 46.3% complete 2019-11-05 10:48:51,441 Task._call(1068) INFO 46.3% complete 2019-11-05 10:48:57,585 Task._call(1068) INFO 46.3% complete 2019-11-05 10:49:03,178 Task._call(1068) INFO 50.1% complete 2019-11-05 10:49:09,509 Task._call(1068) INFO 50.1% complete 2019-11-05 10:49:15,575 Task._call(1068) INFO 50.1% complete 2019-11-05 10:49:21,569 Task._call(1068) INFO 54.0% complete 2019-11-05 10:49:27,082 Task._call(1068) INFO 54.0% complete 2019-11-05 10:49:33,023 Task._call(1068) INFO 54.0% complete 2019-11-05 10:49:39,410 Task._call(1068) INFO 57.9% complete 2019-11-05 10:49:45,943 Task._call(1068) INFO 57.9% complete 2019-11-05 10:49:51,573 Task._call(1068) INFO 57.9% complete 2019-11-05 10:49:57,903 Task._call(1068) INFO 61.7% complete 2019-11-05 10:50:03,617 Task._call(1068) INFO 61.7% complete 2019-11-05 10:50:09,561 Task._call(1068) INFO 61.7% complete 2019-11-05 10:50:15,247 Task._call(1068) INFO 61.7% complete 2019-11-05 10:50:21,576 Task._call(1068) INFO 65.6% complete 2019-11-05 10:50:27,562 Task._call(1068) INFO 65.6% complete 2019-11-05 10:50:33,051 Task._call(1068) INFO 65.6% complete 2019-11-05 10:50:39,730 Task._call(1068) INFO 69.4% complete 2019-11-05 10:50:45,358 Task._call(1068) INFO 69.4% complete 2019-11-05 10:50:51,280 Task._call(1068) INFO 69.4% complete 2019-11-05 10:50:57,713 Task._call(1068) INFO 69.4% complete 2019-11-05 10:51:03,482 Task._call(1068) INFO 73.3% complete 2019-11-05 10:51:09,677 Task._call(1068) INFO 73.3% complete 2019-11-05 10:51:15,112 Task._call(1068) INFO 73.3% complete 2019-11-05 10:51:21,711 Task._call(1068) INFO 77.1% complete 2019-11-05 10:51:27,647 Task._call(1068) INFO 77.1% complete 2019-11-05 10:51:33,112 Task._call(1068) INFO 77.1% complete 2019-11-05 10:51:39,073 Task._call(1068) INFO 81.0% complete 2019-11-05 10:51:45,104 Task._call(1068) INFO 88.7% complete 2019-11-05 10:51:52,950 Task._call(1068) INFO 100.0% complete 2019-11-05 10:51:59,006 Task._call(1068) INFO 3.9% complete 2019-11-05 10:52:25,569 Task._call(1068) INFO 11.6% complete 2019-11-05 10:52:31,088 Task._call(1068) INFO 23.1% complete 2019-11-05 10:53:54,221 Task._call(1068) INFO 30.9% complete 2019-11-05 10:54:00,862 Task._call(1068) INFO 50.1% complete 2019-11-05 10:54:06,012 Task._call(1068) INFO 73.3% complete 2019-11-05 10:54:12,098 Task._call(1068) INFO 88.7% complete 2019-11-05 10:54:15,825 Task._call(1068) INFO 100.0% complete 2019-11-05 10:54:31,595 Task._call(1068) INFO 11.6% complete 2019-11-05 10:54:37,029 Task._call(1068) INFO 19.3% complete 2019-11-05 10:54:43,013 Task._call(1068) INFO 27.0% complete 2019-11-05 10:55:13,404 Task._call(1068) INFO 30.9% complete 2019-11-05 10:55:19,013 Task._call(1068) INFO 38.6% complete 2019-11-05 10:55:25,005 Task._call(1068) INFO 46.3% complete 2019-11-05 10:55:31,013 Task._call(1068) INFO 57.9% complete 2019-11-05 10:55:37,005 Task._call(1068) INFO 65.6% complete 2019-11-05 10:55:43,003 Task._call(1068) INFO 77.1% complete 2019-11-05 10:55:49,000 Task._call(1068) INFO 84.9% complete 2019-11-05 10:55:52,779 Task._call(1068) INFO filter out incomplete divergent streams 2019-11-05 10:55:53,940 Task._call(1068) INFO 100.0% complete 2019-11-05 10:55:54,019 seasonal_water_yield._execute(363) INFO quick flow 2019-11-05 10:55:54,036 seasonal_water_yield._execute(377) INFO loading number of monthly events 2019-11-05 10:56:23,596 geoprocessing.raster_calculator(366) INFO starting stats_worker 2019-11-05 10:56:23,599 geoprocessing.raster_calculator(372) INFO started stats_worker 2019-11-05 10:56:28,605 geoprocessing.(442) INFO 84.8% complete 2019-11-05 10:56:29,483 geoprocessing.raster_calculator(445) INFO 100.0%% complete 2019-11-05 10:56:29,486 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate 2019-11-05 10:56:29,489 threading.run(754) DEBUG payload is None, terminating 2019-11-05 10:56:29,489 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result. 2019-11-05 10:56:30,161 geoprocessing.raster_calculator(366) INFO starting stats_worker 2019-11-05 10:56:30,161 geoprocessing.raster_calculator(372) INFO started stats_worker 2019-11-05 10:56:35,148 geoprocessing.raster_calculator(445) INFO 100.0%% complete 2019-11-05 10:56:35,148 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate 2019-11-05 10:56:35,164 threading.run(754) DEBUG payload is None, terminating 2019-11-05 10:56:35,164 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result. 2019-11-05 10:56:35,551 seasonal_water_yield._execute(442) INFO calculate quick flow for month 1 2019-11-05 10:56:35,983 geoprocessing.raster_calculator(366) INFO starting stats_worker 2019-11-05 10:56:35,986 geoprocessing.raster_calculator(372) INFO started stats_worker 2019-11-05 10:56:40,986 geoprocessing.(442) INFO 29.1% complete 2019-11-05 10:56:46,016 geoprocessing.(442) INFO 47.6% complete 2019-11-05 10:56:51,032 geoprocessing.(442) INFO 64.4% complete 2019-11-05 10:56:56,053 geoprocessing.(442) INFO 82.7% complete 2019-11-05 10:56:58,589 geoprocessing.raster_calculator(445) INFO 100.0%% complete 2019-11-05 10:56:58,605 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate 2019-11-05 10:56:58,605 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result. 2019-11-05 10:56:58,605 threading.run(754) DEBUG payload is None, terminating 2019-11-05 10:56:58,877 seasonal_water_yield._execute(442) INFO calculate quick flow for month 2 2019-11-05 10:56:59,296 geoprocessing.raster_calculator(366) INFO starting stats_worker 2019-11-05 10:56:59,312 geoprocessing.raster_calculator(372) INFO started stats_worker 2019-11-05 10:57:04,301 geoprocessing.(442) INFO 31.0% complete 2019-11-05 10:57:09,328 geoprocessing.(442) INFO 51.2% complete 2019-11-05 10:57:14,358 geoprocessing.(442) INFO 70.2% complete 2019-11-05 10:57:19,365 geoprocessing.(442) INFO 95.8% complete 2019-11-05 10:57:19,625 geoprocessing.raster_calculator(445) INFO 100.0%% complete 2019-11-05 10:57:19,625 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate 2019-11-05 10:57:19,625 threading.run(754) DEBUG payload is None, terminating 2019-11-05 10:57:19,625 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result. 2019-11-05 10:57:19,934 seasonal_water_yield._execute(442) INFO calculate quick flow for month 3 2019-11-05 10:57:20,352 geoprocessing.raster_calculator(366) INFO starting stats_worker 2019-11-05 10:57:20,352 geoprocessing.raster_calculator(372) INFO started stats_worker 2019-11-05 10:57:25,408 geoprocessing.(442) INFO 24.7% complete 2019-11-05 10:57:30,469 geoprocessing.(442) INFO 39.1% complete 2019-11-05 10:57:35,499 geoprocessing.(442) INFO 51.4% complete 2019-11-05 10:57:40,509 geoprocessing.(442) INFO 63.8% complete 2019-11-05 10:57:45,510 geoprocessing.(442) INFO 76.2% complete 2019-11-05 10:57:50,542 geoprocessing.(442) INFO 94.9% complete 2019-11-05 10:57:51,015 geoprocessing.raster_calculator(445) INFO 100.0%% complete 2019-11-05 10:57:51,017 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate 2019-11-05 10:57:51,019 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result. 2019-11-05 10:57:51,019 threading.run(754) DEBUG payload is None, terminating 2019-11-05 10:57:51,298 seasonal_water_yield._execute(442) INFO calculate quick flow for month 4 2019-11-05 10:57:51,726 geoprocessing.raster_calculator(366) INFO starting stats_worker 2019-11-05 10:57:51,742 geoprocessing.raster_calculator(372) INFO started stats_worker 2019-11-05 10:57:56,766 geoprocessing.(442) INFO 27.8% complete 2019-11-05 10:58:01,780 geoprocessing.(442) INFO 44.0% complete 2019-11-05 10:58:06,808 geoprocessing.(442) INFO 59.7% complete 2019-11-05 10:58:11,852 geoprocessing.(442) INFO 75.5% complete 2019-11-05 10:58:16,464 geoprocessing.raster_calculator(445) INFO 100.0%% complete 2019-11-05 10:58:16,480 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate 2019-11-05 10:58:16,480 threading.run(754) DEBUG payload is None, terminating 2019-11-05 10:58:16,480 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result. 2019-11-05 10:58:16,753 seasonal_water_yield._execute(442) INFO calculate quick flow for month 5 2019-11-05 10:58:17,190 geoprocessing.raster_calculator(366) INFO starting stats_worker 2019-11-05 10:58:17,207 geoprocessing.raster_calculator(372) INFO started stats_worker 2019-11-05 10:58:22,213 geoprocessing.(442) INFO 30.4% complete 2019-11-05 10:58:27,243 geoprocessing.(442) INFO 51.0% complete 2019-11-05 10:58:32,244 geoprocessing.(442) INFO 70.2% complete 2019-11-05 10:58:37,253 geoprocessing.(442) INFO 97.9% complete 2019-11-05 10:58:37,417 geoprocessing.raster_calculator(445) INFO 100.0%% complete 2019-11-05 10:58:37,421 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate 2019-11-05 10:58:37,421 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result. 2019-11-05 10:58:37,421 threading.run(754) DEBUG payload is None, terminating 2019-11-05 10:58:37,753 seasonal_water_yield._execute(442) INFO calculate quick flow for month 6 2019-11-05 10:58:38,171 geoprocessing.raster_calculator(366) INFO starting stats_worker 2019-11-05 10:58:38,187 geoprocessing.raster_calculator(372) INFO started stats_worker 2019-11-05 10:58:43,186 geoprocessing.(442) INFO 33.1% complete 2019-11-05 10:58:48,200 geoprocessing.(442) INFO 54.9% complete 2019-11-05 10:58:53,223 geoprocessing.(442) INFO 76.3% complete 2019-11-05 10:58:56,454 geoprocessing.raster_calculator(445) INFO 100.0%% complete 2019-11-05 10:58:56,454 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate 2019-11-05 10:58:56,454 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result. 2019-11-05 10:58:56,454 threading.run(754) DEBUG payload is None, terminating 2019-11-05 10:58:56,733 seasonal_water_yield._execute(442) INFO calculate quick flow for month 7 2019-11-05 10:58:57,732 geoprocessing.raster_calculator(366) INFO starting stats_worker 2019-11-05 10:58:57,736 geoprocessing.raster_calculator(372) INFO started stats_worker 2019-11-05 10:59:02,747 geoprocessing.(442) INFO 35.4% complete 2019-11-05 10:59:07,763 geoprocessing.(442) INFO 59.1% complete 2019-11-05 10:59:12,793 geoprocessing.(442) INFO 83.7% complete 2019-11-05 10:59:14,530 geoprocessing.raster_calculator(445) INFO 100.0%% complete 2019-11-05 10:59:14,546 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate 2019-11-05 10:59:14,546 threading.run(754) DEBUG payload is None, terminating 2019-11-05 10:59:14,546 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result. 2019-11-05 10:59:14,823 seasonal_water_yield._execute(442) INFO calculate quick flow for month 8 2019-11-05 10:59:15,246 geoprocessing.raster_calculator(366) INFO starting stats_worker 2019-11-05 10:59:15,246 geoprocessing.raster_calculator(372) INFO started stats_worker 2019-11-05 10:59:20,273 geoprocessing.(442) INFO 37.0% complete 2019-11-05 10:59:25,286 geoprocessing.(442) INFO 63.1% complete 2019-11-05 10:59:30,290 geoprocessing.(442) INFO 94.3% complete 2019-11-05 10:59:30,714 geoprocessing.raster_calculator(445) INFO 100.0%% complete 2019-11-05 10:59:30,717 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate 2019-11-05 10:59:30,720 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result. 2019-11-05 10:59:30,720 threading.run(754) DEBUG payload is None, terminating 2019-11-05 10:59:31,000 seasonal_water_yield._execute(442) INFO calculate quick flow for month 9 2019-11-05 10:59:31,760 geoprocessing.raster_calculator(366) INFO starting stats_worker 2019-11-05 10:59:31,760 geoprocessing.raster_calculator(372) INFO started stats_worker 2019-11-05 10:59:36,775 geoprocessing.(442) INFO 37.7% complete 2019-11-05 10:59:41,789 geoprocessing.(442) INFO 64.6% complete 2019-11-05 10:59:46,803 geoprocessing.(442) INFO 98.3% complete 2019-11-05 10:59:46,913 geoprocessing.raster_calculator(445) INFO 100.0%% complete 2019-11-05 10:59:46,928 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate 2019-11-05 10:59:46,928 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result. 2019-11-05 10:59:46,928 threading.run(754) DEBUG payload is None, terminating 2019-11-05 10:59:47,266 seasonal_water_yield._execute(442) INFO calculate quick flow for month 10 2019-11-05 10:59:47,729 geoprocessing.raster_calculator(366) INFO starting stats_worker 2019-11-05 10:59:47,729 geoprocessing.raster_calculator(372) INFO started stats_worker 2019-11-05 10:59:52,730 geoprocessing.(442) INFO 37.0% complete 2019-11-05 10:59:57,740 geoprocessing.(442) INFO 64.1% complete 2019-11-05 11:00:02,740 geoprocessing.(442) INFO 97.8% complete 2019-11-05 11:00:02,878 geoprocessing.raster_calculator(445) INFO 100.0%% complete 2019-11-05 11:00:02,878 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate 2019-11-05 11:00:02,894 threading.run(754) DEBUG payload is None, terminating 2019-11-05 11:00:02,894 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result. 2019-11-05 11:00:03,203 seasonal_water_yield._execute(442) INFO calculate quick flow for month 11 2019-11-05 11:00:03,743 geoprocessing.raster_calculator(366) INFO starting stats_worker 2019-11-05 11:00:03,743 geoprocessing.raster_calculator(372) INFO started stats_worker 2019-11-05 11:00:08,788 geoprocessing.(442) INFO 35.5% complete 2019-11-05 11:00:13,793 geoprocessing.(442) INFO 59.6% complete 2019-11-05 11:00:18,803 geoprocessing.(442) INFO 83.9% complete 2019-11-05 11:00:20,500 geoprocessing.raster_calculator(445) INFO 100.0%% complete 2019-11-05 11:00:20,500 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate 2019-11-05 11:00:20,500 threading.run(754) DEBUG payload is None, terminating 2019-11-05 11:00:20,500 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result. 2019-11-05 11:00:20,796 seasonal_water_yield._execute(442) INFO calculate quick flow for month 12 2019-11-05 11:00:21,219 geoprocessing.raster_calculator(366) INFO starting stats_worker 2019-11-05 11:00:21,219 geoprocessing.raster_calculator(372) INFO started stats_worker 2019-11-05 11:00:26,229 geoprocessing.(442) INFO 32.4% complete 2019-11-05 11:00:31,247 geoprocessing.(442) INFO 53.0% complete 2019-11-05 11:00:36,263 geoprocessing.(442) INFO 72.2% complete 2019-11-05 11:00:40,815 geoprocessing.raster_calculator(445) INFO 100.0%% complete 2019-11-05 11:00:40,815 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate 2019-11-05 11:00:40,815 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result. 2019-11-05 11:00:57,559 threading.run(754) DEBUG payload is None, terminating 2019-11-05 11:00:58,358 geoprocessing.raster_calculator(366) INFO starting stats_worker 2019-11-05 11:00:58,375 geoprocessing.raster_calculator(372) INFO started stats_worker 2019-11-05 11:01:03,367 geoprocessing.(442) INFO 48.3% complete 2019-11-05 11:01:08,381 geoprocessing.(442) INFO 94.3% complete 2019-11-05 11:01:08,815 geoprocessing.raster_calculator(445) INFO 100.0%% complete 2019-11-05 11:01:08,815 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate 2019-11-05 11:01:08,832 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result. 2019-11-05 11:01:34,887 threading.run(754) DEBUG payload is None, terminating 2019-11-05 11:01:35,071 seasonal_water_yield._execute(470) INFO calculate local recharge 2019-11-05 11:01:35,242 geoprocessing.raster_calculator(366) INFO starting stats_worker 2019-11-05 11:01:35,242 geoprocessing.raster_calculator(372) INFO started stats_worker 2019-11-05 11:01:40,246 geoprocessing.(442) INFO 100.0% complete 2019-11-05 11:01:40,269 geoprocessing.raster_calculator(445) INFO 100.0%% complete 2019-11-05 11:01:40,269 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate 2019-11-05 11:01:40,269 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result. 2019-11-05 11:01:40,269 threading.run(754) DEBUG payload is None, terminating 2019-11-05 11:01:41,105 geoprocessing.raster_calculator(366) INFO starting stats_worker 2019-11-05 11:01:41,105 geoprocessing.raster_calculator(372) INFO started stats_worker 2019-11-05 11:01:45,973 geoprocessing.raster_calculator(445) INFO 100.0%% complete 2019-11-05 11:01:45,973 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate 2019-11-05 11:01:45,973 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result. 2019-11-05 11:01:45,973 threading.run(754) DEBUG payload is None, terminating 2019-11-05 11:01:46,730 geoprocessing.raster_calculator(366) INFO starting stats_worker 2019-11-05 11:01:46,746 geoprocessing.raster_calculator(372) INFO started stats_worker 2019-11-05 11:01:51,739 geoprocessing.(442) INFO 95.2% complete 2019-11-05 11:01:52,010 geoprocessing.raster_calculator(445) INFO 100.0%% complete 2019-11-05 11:01:52,010 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate 2019-11-05 11:01:52,010 threading.run(754) DEBUG payload is None, terminating 2019-11-05 11:01:52,010 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result. 2019-11-05 11:01:52,773 geoprocessing.raster_calculator(366) INFO starting stats_worker 2019-11-05 11:01:52,773 geoprocessing.raster_calculator(372) INFO started stats_worker 2019-11-05 11:01:57,786 geoprocessing.(442) INFO 99.9% complete 2019-11-05 11:01:57,801 geoprocessing.raster_calculator(445) INFO 100.0%% complete 2019-11-05 11:01:57,816 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate 2019-11-05 11:01:57,816 threading.run(754) DEBUG payload is None, terminating 2019-11-05 11:01:57,816 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result. 2019-11-05 11:01:58,588 geoprocessing.raster_calculator(366) INFO starting stats_worker 2019-11-05 11:01:58,588 geoprocessing.raster_calculator(372) INFO started stats_worker 2019-11-05 11:02:03,589 geoprocessing.(442) INFO 98.8% complete 2019-11-05 11:02:03,651 geoprocessing.raster_calculator(445) INFO 100.0%% complete 2019-11-05 11:02:03,651 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate 2019-11-05 11:02:03,667 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result. 2019-11-05 11:02:03,667 threading.run(754) DEBUG payload is None, terminating 2019-11-05 11:02:04,453 geoprocessing.raster_calculator(366) INFO starting stats_worker 2019-11-05 11:02:04,453 geoprocessing.raster_calculator(372) INFO started stats_worker 2019-11-05 11:02:09,444 geoprocessing.raster_calculator(445) INFO 100.0%% complete 2019-11-05 11:02:09,460 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate 2019-11-05 11:02:09,460 threading.run(754) DEBUG payload is None, terminating 2019-11-05 11:02:09,460 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result. 2019-11-05 11:02:10,227 geoprocessing.raster_calculator(366) INFO starting stats_worker 2019-11-05 11:02:10,243 geoprocessing.raster_calculator(372) INFO started stats_worker 2019-11-05 11:02:15,240 geoprocessing.(442) INFO 99.4% complete 2019-11-05 11:02:15,286 geoprocessing.raster_calculator(445) INFO 100.0%% complete 2019-11-05 11:02:15,286 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate 2019-11-05 11:02:15,286 threading.run(754) DEBUG payload is None, terminating 2019-11-05 11:02:15,286 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result. 2019-11-05 11:02:16,063 geoprocessing.raster_calculator(366) INFO starting stats_worker 2019-11-05 11:02:16,066 geoprocessing.raster_calculator(372) INFO started stats_worker 2019-11-05 11:02:21,059 geoprocessing.raster_calculator(445) INFO 100.0%% complete 2019-11-05 11:02:21,075 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate 2019-11-05 11:02:21,075 threading.run(754) DEBUG payload is None, terminating 2019-11-05 11:02:21,075 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result. 2019-11-05 11:02:21,828 geoprocessing.raster_calculator(366) INFO starting stats_worker 2019-11-05 11:02:21,844 geoprocessing.raster_calculator(372) INFO started stats_worker 2019-11-05 11:02:26,838 geoprocessing.raster_calculator(445) INFO 100.0%% complete 2019-11-05 11:02:26,838 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate 2019-11-05 11:02:26,838 threading.run(754) DEBUG payload is None, terminating 2019-11-05 11:02:26,838 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result. 2019-11-05 11:02:27,618 geoprocessing.raster_calculator(366) INFO starting stats_worker 2019-11-05 11:02:27,618 geoprocessing.raster_calculator(372) INFO started stats_worker 2019-11-05 11:02:32,605 geoprocessing.raster_calculator(445) INFO 100.0%% complete 2019-11-05 11:02:32,605 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate 2019-11-05 11:02:32,605 threading.run(754) DEBUG payload is None, terminating 2019-11-05 11:02:32,605 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result. 2019-11-05 11:02:33,387 geoprocessing.raster_calculator(366) INFO starting stats_worker 2019-11-05 11:02:33,401 geoprocessing.raster_calculator(372) INFO started stats_worker 2019-11-05 11:02:38,391 geoprocessing.raster_calculator(445) INFO 100.0%% complete 2019-11-05 11:02:38,391 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate 2019-11-05 11:02:38,391 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result. 2019-11-05 11:02:38,391 threading.run(754) DEBUG payload is None, terminating 2019-11-05 11:02:39,165 geoprocessing.raster_calculator(366) INFO starting stats_worker 2019-11-05 11:02:39,165 geoprocessing.raster_calculator(372) INFO started stats_worker 2019-11-05 11:02:44,153 geoprocessing.raster_calculator(445) INFO 100.0%% complete 2019-11-05 11:02:44,153 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate 2019-11-05 11:02:44,153 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result. 2019-11-05 11:02:44,153 threading.run(754) DEBUG payload is None, terminating 2019-11-05 11:02:54,542 Task._call(1068) INFO peak point detection 0.00% complete 2019-11-05 11:03:00,414 Task._call(1068) INFO peak point detection 3.86% complete 2019-11-05 11:03:06,282 Task._call(1068) INFO peak point detection 3.86% complete 2019-11-05 11:03:12,609 Task._call(1068) INFO peak point detection 3.87% complete 2019-11-05 11:03:19,743 Task._call(1068) INFO peak point detection 3.87% complete 2019-11-05 11:03:27,621 Task._call(1068) INFO peak point detection 7.72% complete 2019-11-05 11:03:35,203 Task._call(1068) INFO peak point detection 7.72% complete 2019-11-05 11:03:42,411 Task._call(1068) INFO peak point detection 7.72% complete 2019-11-05 11:03:51,674 Task._call(1068) INFO peak point detection 7.72% complete 2019-11-05 11:04:00,042 Task._call(1068) INFO peak point detection 7.72% complete 2019-11-05 11:04:07,786 Task._call(1068) INFO peak point detection 7.72% complete 2019-11-05 11:04:13,101 Task._call(1068) INFO peak point detection 7.73% complete 2019-11-05 11:04:21,562 Task._call(1068) INFO peak point detection 11.57% complete 2019-11-05 11:04:28,061 Task._call(1068) INFO peak point detection 11.57% complete 2019-11-05 11:04:40,331 Task._call(1068) INFO peak point detection 11.58% complete 2019-11-05 11:04:46,023 Task._call(1068) INFO peak point detection 11.58% complete 2019-11-05 11:04:52,063 Task._call(1068) INFO peak point detection 11.58% complete 2019-11-05 11:05:00,582 Task._call(1068) INFO peak point detection 11.58% complete 2019-11-05 11:05:06,230 Task._call(1068) INFO peak point detection 11.58% complete 2019-11-05 11:05:12,108 Task._call(1068) INFO peak point detection 11.58% complete 2019-11-05 11:05:18,619 Task._call(1068) INFO peak point detection 11.58% complete 2019-11-05 11:05:24,437 Task._call(1068) INFO peak point detection 11.58% complete 2019-11-05 11:05:30,181 Task._call(1068) INFO peak point detection 11.58% complete 2019-11-05 11:05:36,802 Task._call(1068) INFO peak point detection 15.43% complete 2019-11-05 11:05:42,147 Task._call(1068) INFO peak point detection 15.43% complete 2019-11-05 11:05:50,450 Task._call(1068) INFO peak point detection 15.43% complete 2019-11-05 11:05:56,246 Task._call(1068) INFO peak point detection 15.43% complete 2019-11-05 11:06:02,052 Task._call(1068) INFO peak point detection 15.43% complete 2019-11-05 11:06:09,315 Task._call(1068) INFO peak point detection 15.44% complete 2019-11-05 11:06:16,959 Task._call(1068) INFO peak point detection 15.44% complete 2019-11-05 11:06:23,464 Task._call(1068) INFO peak point detection 15.44% complete 2019-11-05 11:06:31,303 Task._call(1068) INFO peak point detection 15.44% complete 2019-11-05 11:06:38,927 Task._call(1068) INFO peak point detection 15.44% complete 2019-11-05 11:06:45,456 Task._call(1068) INFO peak point detection 15.44% complete 2019-11-05 11:06:51,019 Task._call(1068) INFO peak point detection 19.29% complete 2019-11-05 11:06:59,104 Task._call(1068) INFO peak point detection 19.29% complete 2019-11-05 11:07:06,698 Task._call(1068) INFO peak point detection 19.29% complete 2019-11-05 11:07:12,167 Task._call(1068) INFO peak point detection 19.29% complete 2019-11-05 11:07:18,144 Task._call(1068) INFO peak point detection 19.29% complete 2019-11-05 11:07:26,760 Task._call(1068) INFO peak point detection 19.29% complete 2019-11-05 11:07:33,186 Task._call(1068) INFO peak point detection 19.29% complete 2019-11-05 11:07:40,279 Task._call(1068) INFO peak point detection 19.29% complete 2019-11-05 11:07:47,016 Task._call(1068) INFO peak point detection 19.30% complete 2019-11-05 11:07:54,727 Task._call(1068) INFO peak point detection 19.30% complete 2019-11-05 11:08:02,551 Task._call(1068) INFO peak point detection 19.30% complete 2019-11-05 11:08:08,842 Task._call(1068) INFO peak point detection 23.14% complete 2019-11-05 11:08:16,611 Task._call(1068) INFO peak point detection 23.14% complete 2019-11-05 11:08:22,688 Task._call(1068) INFO peak point detection 23.15% complete 2019-11-05 11:08:30,586 Task._call(1068) INFO peak point detection 23.15% complete 2019-11-05 11:08:36,052 Task._call(1068) INFO peak point detection 23.15% complete 2019-11-05 11:08:43,480 Task._call(1068) INFO peak point detection 23.15% complete 2019-11-05 11:08:49,490 Task._call(1068) INFO peak point detection 23.15% complete 2019-11-05 11:08:55,029 Task._call(1068) INFO peak point detection 23.15% complete 2019-11-05 11:09:03,104 Task._call(1068) INFO peak point detection 23.15% complete 2019-11-05 11:09:19,631 Task._call(1068) INFO peak point detection 23.15% complete 2019-11-05 11:09:27,029 Task._call(1068) INFO peak point detection 23.15% complete 2019-11-05 11:09:33,108 Task._call(1068) INFO peak point detection 23.15% complete 2019-11-05 11:09:41,026 Task._call(1068) INFO peak point detection 27.00% complete 2019-11-05 11:09:49,936 Task._call(1068) INFO peak point detection 27.00% complete 2019-11-05 11:09:56,161 Task._call(1068) INFO peak point detection 27.00% complete 2019-11-05 11:10:07,155 Task._call(1068) INFO peak point detection 27.00% complete 2019-11-05 11:10:18,252 Task._call(1068) INFO peak point detection 27.00% complete 2019-11-05 11:10:26,151 Task._call(1068) INFO peak point detection 27.00% complete 2019-11-05 11:10:35,710 Task._call(1068) INFO peak point detection 27.01% complete 2019-11-05 11:10:41,864 Task._call(1068) INFO peak point detection 27.01% complete 2019-11-05 11:10:49,502 Task._call(1068) INFO peak point detection 27.01% complete 2019-11-05 11:10:56,838 Task._call(1068) INFO peak point detection 27.01% complete 2019-11-05 11:11:03,131 Task._call(1068) INFO peak point detection 27.01% complete 2019-11-05 11:11:09,006 Task._call(1068) INFO peak point detection 27.01% complete 2019-11-05 11:11:17,733 Task._call(1068) INFO peak point detection 30.86% complete 2019-11-05 11:11:24,095 Task._call(1068) INFO peak point detection 30.86% complete 2019-11-05 11:11:32,404 Task._call(1068) INFO peak point detection 30.86% complete 2019-11-05 11:11:38,221 Task._call(1068) INFO peak point detection 30.86% complete 2019-11-05 11:11:46,214 Task._call(1068) INFO peak point detection 30.86% complete 2019-11-05 11:11:52,369 Task._call(1068) INFO peak point detection 30.86% complete 2019-11-05 11:12:01,020 Task._call(1068) INFO peak point detection 30.86% complete 2019-11-05 11:12:10,341 Task._call(1068) INFO peak point detection 30.86% complete 2019-11-05 11:12:17,849 Task._call(1068) INFO peak point detection 30.86% complete 2019-11-05 11:12:43,447 Task._call(1068) INFO peak point detection 30.87% complete 2019-11-05 11:12:53,532 Task._call(1068) INFO peak point detection 34.71% complete 2019-11-05 11:13:01,994 Task._call(1068) INFO peak point detection 34.71% complete 2019-11-05 11:13:09,950 Task._call(1068) INFO peak point detection 34.71% complete 2019-11-05 11:13:15,739 Task._call(1068) INFO peak point detection 34.71% complete 2019-11-05 11:13:23,543 Task._call(1068) INFO peak point detection 34.72% complete 2019-11-05 11:13:29,221 Task._call(1068) INFO peak point detection 34.72% complete 2019-11-05 11:13:38,552 Task._call(1068) INFO peak point detection 34.72% complete 2019-11-05 11:13:44,150 Task._call(1068) INFO peak point detection 34.72% complete 2019-11-05 11:13:50,299 Task._call(1068) INFO peak point detection 34.72% complete 2019-11-05 11:13:56,065 Task._call(1068) INFO peak point detection 34.72% complete 2019-11-05 11:14:05,714 Task._call(1068) INFO peak point detection 34.72% complete 2019-11-05 11:14:14,311 Task._call(1068) INFO peak point detection 34.72% complete 2019-11-05 11:14:22,003 Task._call(1068) INFO peak point detection 38.57% complete 2019-11-05 11:14:28,220 Task._call(1068) INFO peak point detection 38.57% complete 2019-11-05 11:14:35,601 Task._call(1068) INFO peak point detection 38.57% complete 2019-11-05 11:14:41,970 Task._call(1068) INFO peak point detection 38.57% complete 2019-11-05 11:14:51,921 Task._call(1068) INFO peak point detection 38.57% complete 2019-11-05 11:14:57,615 Task._call(1068) INFO peak point detection 38.57% complete 2019-11-05 11:15:04,253 Task._call(1068) INFO peak point detection 38.57% complete 2019-11-05 11:15:12,779 Task._call(1068) INFO peak point detection 38.57% complete 2019-11-05 11:15:20,081 Task._call(1068) INFO peak point detection 38.58% complete 2019-11-05 11:15:27,795 Task._call(1068) INFO peak point detection 38.58% complete 2019-11-05 11:15:33,676 Task._call(1068) INFO peak point detection 38.58% complete 2019-11-05 11:15:39,009 Task._call(1068) INFO peak point detection 38.58% complete 2019-11-05 11:15:45,961 Task._call(1068) INFO peak point detection 38.58% complete 2019-11-05 11:15:51,890 Task._call(1068) INFO peak point detection 38.58% complete 2019-11-05 11:15:57,262 Task._call(1068) INFO peak point detection 42.42% complete 2019-11-05 11:16:07,187 Task._call(1068) INFO peak point detection 42.43% complete 2019-11-05 11:16:15,509 Task._call(1068) INFO peak point detection 42.43% complete 2019-11-05 11:16:21,257 Task._call(1068) INFO peak point detection 42.43% complete 2019-11-05 11:16:27,062 Task._call(1068) INFO peak point detection 42.43% complete 2019-11-05 11:16:35,664 Task._call(1068) INFO peak point detection 42.43% complete 2019-11-05 11:16:41,316 Task._call(1068) INFO peak point detection 42.43% complete 2019-11-05 11:16:48,132 Task._call(1068) INFO peak point detection 42.43% complete 2019-11-05 11:16:57,637 Task._call(1068) INFO peak point detection 42.43% complete 2019-11-05 11:17:03,509 Task._call(1068) INFO peak point detection 42.43% complete 2019-11-05 11:17:24,693 Task._call(1068) INFO peak point detection 42.43% complete 2019-11-05 11:17:30,746 Task._call(1068) INFO peak point detection 42.44% complete 2019-11-05 11:17:40,380 Task._call(1068) INFO peak point detection 42.44% complete 2019-11-05 11:17:48,529 Task._call(1068) INFO peak point detection 46.28% complete 2019-11-05 11:17:56,440 Task._call(1068) INFO peak point detection 46.28% complete 2019-11-05 11:18:02,625 Task._call(1068) INFO peak point detection 46.28% complete 2019-11-05 11:18:10,387 Task._call(1068) INFO peak point detection 46.28% complete 2019-11-05 11:18:18,540 Task._call(1068) INFO peak point detection 46.28% complete 2019-11-05 11:18:26,311 Task._call(1068) INFO peak point detection 46.29% complete 2019-11-05 11:18:33,744 Task._call(1068) INFO peak point detection 46.29% complete 2019-11-05 11:18:40,986 Task._call(1068) INFO peak point detection 46.29% complete 2019-11-05 11:18:49,493 Task._call(1068) INFO peak point detection 46.29% complete 2019-11-05 11:18:57,171 Task._call(1068) INFO peak point detection 46.29% complete 2019-11-05 11:19:04,174 Task._call(1068) INFO peak point detection 46.29% complete 2019-11-05 11:19:12,710 Task._call(1068) INFO peak point detection 46.29% complete 2019-11-05 11:19:18,792 Task._call(1068) INFO peak point detection 46.29% complete 2019-11-05 11:19:25,996 Task._call(1068) INFO peak point detection 46.29% complete 2019-11-05 11:19:33,474 Task._call(1068) INFO peak point detection 50.14% complete 2019-11-05 11:19:41,592 Task._call(1068) INFO peak point detection 50.14% complete 2019-11-05 11:19:47,451 Task._call(1068) INFO peak point detection 50.14% complete 2019-11-05 11:19:54,154 Task._call(1068) INFO peak point detection 50.14% complete 2019-11-05 11:20:00,168 Task._call(1068) INFO peak point detection 50.14% complete 2019-11-05 11:20:07,783 Task._call(1068) INFO peak point detection 50.14% complete 2019-11-05 11:20:13,661 Task._call(1068) INFO peak point detection 50.14% complete 2019-11-05 11:20:22,500 Task._call(1068) INFO peak point detection 50.14% complete 2019-11-05 11:20:28,180 Task._call(1068) INFO peak point detection 50.15% complete 2019-11-05 11:20:35,936 Task._call(1068) INFO peak point detection 50.15% complete 2019-11-05 11:20:51,617 Task._call(1068) INFO peak point detection 50.15% complete 2019-11-05 11:20:58,154 Task._call(1068) INFO peak point detection 50.15% complete 2019-11-05 11:21:04,131 Task._call(1068) INFO peak point detection 50.15% complete 2019-11-05 11:21:11,755 Task._call(1068) INFO peak point detection 50.15% complete 2019-11-05 11:21:54,986 Task._call(1068) INFO peak point detection 50.15% complete 2019-11-05 11:22:00,867 Task._call(1068) INFO peak point detection 53.99% complete 2019-11-05 11:22:06,628 Task._call(1068) INFO peak point detection 53.99% complete 2019-11-05 11:22:12,032 Task._call(1068) INFO peak point detection 54.00% complete 2019-11-05 11:22:21,262 Task._call(1068) INFO peak point detection 54.00% complete 2019-11-05 11:22:29,256 Task._call(1068) INFO peak point detection 54.00% complete 2019-11-05 11:22:35,177 Task._call(1068) INFO peak point detection 54.00% complete 2019-11-05 11:22:42,986 Task._call(1068) INFO peak point detection 54.00% complete 2019-11-05 11:22:48,841 Task._call(1068) INFO peak point detection 54.00% complete 2019-11-05 11:22:58,851 Task._call(1068) INFO peak point detection 54.00% complete 2019-11-05 11:23:06,681 Task._call(1068) INFO peak point detection 54.00% complete 2019-11-05 11:23:12,492 Task._call(1068) INFO peak point detection 54.00% complete 2019-11-05 11:23:19,394 Task._call(1068) INFO peak point detection 54.00% complete 2019-11-05 11:23:27,681 Task._call(1068) INFO peak point detection 54.01% complete 2019-11-05 11:23:33,528 Task._call(1068) INFO peak point detection 57.85% complete 2019-11-05 11:23:41,479 Task._call(1068) INFO peak point detection 57.85% complete 2019-11-05 11:23:47,970 Task._call(1068) INFO peak point detection 57.85% complete 2019-11-05 11:23:53,242 Task._call(1068) INFO peak point detection 57.85% complete 2019-11-05 11:24:02,614 Task._call(1068) INFO peak point detection 57.85% complete 2019-11-05 11:24:09,331 Task._call(1068) INFO peak point detection 57.85% complete 2019-11-05 11:24:15,956 Task._call(1068) INFO peak point detection 57.85% complete 2019-11-05 11:24:21,625 Task._call(1068) INFO peak point detection 57.86% complete 2019-11-05 11:24:29,328 Task._call(1068) INFO peak point detection 57.86% complete 2019-11-05 11:24:37,855 Task._call(1068) INFO peak point detection 57.86% complete 2019-11-05 11:24:43,747 Task._call(1068) INFO peak point detection 57.86% complete 2019-11-05 11:24:51,589 Task._call(1068) INFO peak point detection 57.86% complete 2019-11-05 11:24:57,556 Task._call(1068) INFO peak point detection 57.86% complete 2019-11-05 11:25:03,913 Task._call(1068) INFO peak point detection 57.86% complete 2019-11-05 11:25:15,038 Task._call(1068) INFO peak point detection 57.86% complete 2019-11-05 11:25:21,848 Task._call(1068) INFO peak point detection 61.71% complete 2019-11-05 11:25:27,405 Task._call(1068) INFO peak point detection 61.71% complete 2019-11-05 11:25:35,638 Task._call(1068) INFO peak point detection 61.71% complete 2019-11-05 11:25:43,078 Task._call(1068) INFO peak point detection 61.71% complete 2019-11-05 11:25:49,612 Task._call(1068) INFO peak point detection 61.71% complete 2019-11-05 11:25:55,539 Task._call(1068) INFO peak point detection 61.71% complete 2019-11-05 11:26:01,220 Task._call(1068) INFO peak point detection 61.71% complete 2019-11-05 11:26:09,036 Task._call(1068) INFO peak point detection 61.71% complete 2019-11-05 11:26:15,450 Task._call(1068) INFO peak point detection 61.71% complete 2019-11-05 11:26:21,015 Task._call(1068) INFO peak point detection 61.72% complete 2019-11-05 11:26:28,288 Task._call(1068) INFO peak point detection 61.72% complete 2019-11-05 11:26:36,256 Task._call(1068) INFO peak point detection 61.72% complete 2019-11-05 11:26:44,043 Task._call(1068) INFO peak point detection 61.72% complete 2019-11-05 11:26:50,772 Task._call(1068) INFO peak point detection 65.56% complete 2019-11-05 11:26:56,457 Task._call(1068) INFO peak point detection 65.56% complete 2019-11-05 11:27:05,489 Task._call(1068) INFO peak point detection 65.56% complete 2019-11-05 11:27:12,476 Task._call(1068) INFO peak point detection 65.56% complete 2019-11-05 11:27:19,066 Task._call(1068) INFO peak point detection 65.57% complete 2019-11-05 11:27:26,815 Task._call(1068) INFO peak point detection 65.57% complete 2019-11-05 11:27:32,684 Task._call(1068) INFO peak point detection 65.57% complete 2019-11-05 11:27:38,371 Task._call(1068) INFO peak point detection 65.57% complete 2019-11-05 11:27:44,135 Task._call(1068) INFO peak point detection 65.57% complete 2019-11-05 11:27:52,233 Task._call(1068) INFO peak point detection 65.57% complete 2019-11-05 11:28:00,282 Task._call(1068) INFO peak point detection 65.57% complete 2019-11-05 11:28:07,029 Task._call(1068) INFO peak point detection 65.57% complete 2019-11-05 11:28:15,289 Task._call(1068) INFO peak point detection 65.57% complete 2019-11-05 11:28:23,686 Task._call(1068) INFO peak point detection 65.57% complete 2019-11-05 11:28:31,142 Task._call(1068) INFO peak point detection 65.58% complete 2019-11-05 11:28:38,431 Task._call(1068) INFO peak point detection 69.42% complete 2019-11-05 11:28:46,075 Task._call(1068) INFO peak point detection 69.42% complete 2019-11-05 11:28:53,575 Task._call(1068) INFO peak point detection 69.42% complete 2019-11-05 11:29:01,180 Task._call(1068) INFO peak point detection 69.42% complete 2019-11-05 11:29:09,842 Task._call(1068) INFO peak point detection 69.42% complete 2019-11-05 11:29:15,519 Task._call(1068) INFO peak point detection 69.42% complete 2019-11-05 11:29:22,812 Task._call(1068) INFO peak point detection 69.43% complete 2019-11-05 11:29:31,253 Task._call(1068) INFO peak point detection 69.43% complete 2019-11-05 11:29:39,611 Task._call(1068) INFO peak point detection 69.43% complete 2019-11-05 11:29:52,293 Task._call(1068) INFO peak point detection 69.43% complete 2019-11-05 11:29:58,398 Task._call(1068) INFO peak point detection 69.43% complete 2019-11-05 11:30:05,299 Task._call(1068) INFO peak point detection 69.43% complete 2019-11-05 11:30:14,421 Task._call(1068) INFO peak point detection 69.43% complete 2019-11-05 11:30:21,173 Task._call(1068) INFO peak point detection 69.43% complete 2019-11-05 11:30:29,358 Task._call(1068) INFO peak point detection 73.28% complete 2019-11-05 11:30:37,658 Task._call(1068) INFO peak point detection 73.28% complete 2019-11-05 11:30:43,273 Task._call(1068) INFO peak point detection 73.28% complete 2019-11-05 11:30:51,575 Task._call(1068) INFO peak point detection 73.28% complete 2019-11-05 11:30:59,595 Task._call(1068) INFO peak point detection 73.28% complete 2019-11-05 11:31:05,430 Task._call(1068) INFO peak point detection 73.28% complete 2019-11-05 11:31:11,989 Task._call(1068) INFO peak point detection 73.28% complete 2019-11-05 11:31:17,674 Task._call(1068) INFO peak point detection 73.28% complete 2019-11-05 11:31:24,255 Task._call(1068) INFO peak point detection 73.28% complete 2019-11-05 11:31:31,051 Task._call(1068) INFO peak point detection 73.29% complete 2019-11-05 11:31:39,378 Task._call(1068) INFO peak point detection 73.29% complete 2019-11-05 11:31:47,335 Task._call(1068) INFO peak point detection 73.29% complete 2019-11-05 11:31:54,536 Task._call(1068) INFO peak point detection 73.29% complete 2019-11-05 11:32:05,631 Task._call(1068) INFO peak point detection 77.13% complete 2019-11-05 11:32:11,099 Task._call(1068) INFO peak point detection 77.14% complete 2019-11-05 11:32:20,301 Task._call(1068) INFO peak point detection 77.14% complete 2019-11-05 11:32:30,596 Task._call(1068) INFO peak point detection 77.14% complete 2019-11-05 11:32:39,595 Task._call(1068) INFO peak point detection 77.14% complete 2019-11-05 11:32:49,878 Task._call(1068) INFO peak point detection 77.14% complete 2019-11-05 11:32:56,095 Task._call(1068) INFO peak point detection 77.14% complete 2019-11-05 11:33:02,555 Task._call(1068) INFO peak point detection 77.14% complete 2019-11-05 11:33:08,157 Task._call(1068) INFO peak point detection 77.14% complete 2019-11-05 11:33:15,701 Task._call(1068) INFO peak point detection 77.14% complete 2019-11-05 11:33:21,500 Task._call(1068) INFO peak point detection 77.14% complete 2019-11-05 11:33:27,901 Task._call(1068) INFO peak point detection 77.15% complete 2019-11-05 11:33:41,033 Task._call(1068) INFO peak point detection 77.15% complete 2019-11-05 11:33:54,059 Task._call(1068) INFO peak point detection 80.99% complete 2019-11-05 11:34:00,219 Task._call(1068) INFO peak point detection 80.99% complete 2019-11-05 11:34:09,078 Task._call(1068) INFO peak point detection 80.99% complete 2019-11-05 11:34:25,040 Task._call(1068) INFO peak point detection 81.00% complete 2019-11-05 11:34:31,257 Task._call(1068) INFO peak point detection 81.00% complete 2019-11-05 11:34:49,559 Task._call(1068) INFO peak point detection 81.00% complete 2019-11-05 11:34:58,950 Task._call(1068) INFO peak point detection 81.00% complete 2019-11-05 11:35:04,595 Task._call(1068) INFO peak point detection 81.00% complete 2019-11-05 11:35:10,040 Task._call(1068) INFO peak point detection 81.00% complete 2019-11-05 11:35:30,341 Task._call(1068) INFO peak point detection 81.00% complete 2019-11-05 11:35:37,576 Task._call(1068) INFO peak point detection 81.00% complete 2019-11-05 11:35:43,394 Task._call(1068) INFO peak point detection 81.00% complete 2019-11-05 11:35:50,308 Task._call(1068) INFO peak point detection 84.85% complete 2019-11-05 11:35:57,903 Task._call(1068) INFO peak point detection 84.85% complete 2019-11-05 11:36:04,118 Task._call(1068) INFO peak point detection 84.85% complete 2019-11-05 11:36:11,134 Task._call(1068) INFO peak point detection 84.85% complete 2019-11-05 11:36:17,345 Task._call(1068) INFO peak point detection 84.86% complete 2019-11-05 11:36:24,571 Task._call(1068) INFO peak point detection 84.86% complete 2019-11-05 11:36:32,071 Task._call(1068) INFO peak point detection 84.86% complete 2019-11-05 11:36:38,234 Task._call(1068) INFO peak point detection 88.71% complete 2019-11-05 11:36:51,782 Task._call(1068) INFO peak point detection 88.71% complete 2019-11-05 11:36:57,351 Task._call(1068) INFO peak point detection 88.71% complete 2019-11-05 11:37:05,721 Task._call(1068) INFO peak point detection 88.71% complete 2019-11-05 11:37:12,315 Task._call(1068) INFO peak point detection 88.71% complete 2019-11-05 11:37:19,785 Task._call(1068) INFO peak point detection 88.71% complete 2019-11-05 11:37:25,910 Task._call(1068) INFO peak point detection 92.57% complete 2019-11-05 11:37:31,328 Task._call(1068) INFO peak point detection 92.57% complete 2019-11-05 11:37:50,342 Task._call(1068) INFO peak point detection 92.57% complete 2019-11-05 11:37:57,786 Task._call(1068) INFO peak point detection 92.57% complete 2019-11-05 11:38:04,499 Task._call(1068) INFO peak point detection 92.57% complete 2019-11-05 11:38:10,038 Task._call(1068) INFO peak point detection 96.43% complete 2019-11-05 11:38:45,506 Task._call(1068) INFO peak point detection 96.43% complete 2019-11-05 11:38:47,397 Task.add_task(636) ERROR Something went wrong when adding task calculate vri (45), terminating taskgraph. Traceback (most recent call last): File "Z:\opt\atlassian\pipelines\agent\build\env\lib\site-packages\taskgraph\Task.py", line 602, in add_task File "Z:\opt\atlassian\pipelines\agent\build\env\lib\site-packages\taskgraph\Task.py", line 1068, in _call File "Z:\opt\atlassian\pipelines\agent\build\env\lib\site-packages\natcap\invest\seasonal_water_yield\seasonal_water_yield.py", line 602, in _calculate_vri File "C:\Python27\lib\site-packages\numpy\core\fromnumeric.py", line 2076, in sum File "C:\Python27\lib\site-packages\numpy\core\fromnumeric.py", line 86, in _wrapreduction RuntimeWarning: overflow encountered in reduce 2019-11-05 11:38:47,397 model._logged_target(1639) ERROR Exception while executing Traceback (most recent call last): File "Z:\opt\atlassian\pipelines\agent\build\env\lib\site-packages\natcap\invest\ui\model.py", line 1636, in _logged_target File "Z:\opt\atlassian\pipelines\agent\build\env\lib\site-packages\natcap\invest\seasonal_water_yield\seasonal_water_yield.py", line 169, in execute File "Z:\opt\atlassian\pipelines\agent\build\env\lib\site-packages\natcap\invest\seasonal_water_yield\seasonal_water_yield.py", line 528, in _execute File "Z:\opt\atlassian\pipelines\agent\build\env\lib\site-packages\taskgraph\Task.py", line 602, in add_task File "Z:\opt\atlassian\pipelines\agent\build\env\lib\site-packages\taskgraph\Task.py", line 1068, in _call File "Z:\opt\atlassian\pipelines\agent\build\env\lib\site-packages\natcap\invest\seasonal_water_yield\seasonal_water_yield.py", line 602, in _calculate_vri File "C:\Python27\lib\site-packages\numpy\core\fromnumeric.py", line 2076, in sum File "C:\Python27\lib\site-packages\numpy\core\fromnumeric.py", line 86, in _wrapreduction RuntimeWarning: overflow encountered in reduce 2019-11-05 11:38:47,413 model._logged_target(1642) INFO Execution finished 2019-11-05 11:38:47,413 utils.prepare_workspace(115) INFO Elapsed time: 57m 50.73s