2019-10-11 11:36:19,002 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-10-11--11_36_18.txt
2019-10-11 11:36:19,006 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
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_clip
rain_events_table_path      H:/SIG/Procesos SIG/Invest_water/test_regiones/maule/rain_events_maule.csv
results_suffix              4
soil_group_path             H:/SIG/Procesos SIG/Invest_water/test_regiones/maule/Soil group/soil_maule.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-10-11 11:36:19,178 seasonal_water_yield._execute(184) INFO prepare and test inputs for common errors
2019-10-11 11:36:19,473 seasonal_water_yield._execute(246) INFO Building file registry
2019-10-11 11:36:19,487 seasonal_water_yield._execute(252) INFO Checking that the AOI is not the output aggregate vector
2019-10-11 11:36:19,487 seasonal_water_yield._execute(261) INFO Aligning and clipping dataset list
2019-10-11 11:36:19,934 geoprocessing.align_and_resize_raster_stack(729) INFO n_workers > 1 (5) so starting a processes pool.
2019-10-11 11:36:25,740 geoprocessing.align_and_resize_raster_stack(779) INFO 1 of 27 aligned: prcp_a0_4.tif
2019-10-11 11:36:26,509 geoprocessing.align_and_resize_raster_stack(779) INFO 2 of 27 aligned: prcp_a1_4.tif
2019-10-11 11:36:27,279 geoprocessing.align_and_resize_raster_stack(779) INFO 3 of 27 aligned: prcp_a2_4.tif
2019-10-11 11:36:27,891 geoprocessing.align_and_resize_raster_stack(779) INFO 4 of 27 aligned: prcp_a3_4.tif
2019-10-11 11:36:28,654 geoprocessing.align_and_resize_raster_stack(779) INFO 5 of 27 aligned: prcp_a4_4.tif
2019-10-11 11:36:30,920 geoprocessing.align_and_resize_raster_stack(779) INFO 6 of 27 aligned: prcp_a5_4.tif
2019-10-11 11:36:31,619 geoprocessing.align_and_resize_raster_stack(779) INFO 7 of 27 aligned: prcp_a6_4.tif
2019-10-11 11:36:32,398 geoprocessing.align_and_resize_raster_stack(779) INFO 8 of 27 aligned: prcp_a7_4.tif
2019-10-11 11:36:33,109 geoprocessing.align_and_resize_raster_stack(779) INFO 9 of 27 aligned: prcp_a8_4.tif
2019-10-11 11:36:33,918 geoprocessing.align_and_resize_raster_stack(779) INFO 10 of 27 aligned: prcp_a9_4.tif
2019-10-11 11:36:36,193 geoprocessing.align_and_resize_raster_stack(779) INFO 11 of 27 aligned: prcp_a10_4.tif
2019-10-11 11:36:36,960 geoprocessing.align_and_resize_raster_stack(779) INFO 12 of 27 aligned: prcp_a11_4.tif
2019-10-11 11:36:36,964 geoprocessing.align_and_resize_raster_stack(779) INFO 13 of 27 aligned: soil_group_aligned_4.tif
2019-10-11 11:36:38,545 geoprocessing.align_and_resize_raster_stack(779) INFO 14 of 27 aligned: et0_a0_4.tif
2019-10-11 11:36:39,480 geoprocessing.align_and_resize_raster_stack(779) INFO 15 of 27 aligned: et0_a1_4.tif
2019-10-11 11:36:41,221 geoprocessing.align_and_resize_raster_stack(779) INFO 16 of 27 aligned: et0_a2_4.tif
2019-10-11 11:36:41,703 geoprocessing.align_and_resize_raster_stack(779) INFO 17 of 27 aligned: et0_a3_4.tif
2019-10-11 11:36:42,546 geoprocessing.align_and_resize_raster_stack(779) INFO 18 of 27 aligned: et0_a4_4.tif
2019-10-11 11:36:43,911 geoprocessing.align_and_resize_raster_stack(779) INFO 19 of 27 aligned: et0_a5_4.tif
2019-10-11 11:36:44,983 geoprocessing.align_and_resize_raster_stack(779) INFO 20 of 27 aligned: et0_a6_4.tif
2019-10-11 11:36:46,642 geoprocessing.align_and_resize_raster_stack(779) INFO 21 of 27 aligned: et0_a7_4.tif
2019-10-11 11:36:47,338 geoprocessing.align_and_resize_raster_stack(779) INFO 22 of 27 aligned: et0_a8_4.tif
2019-10-11 11:36:48,114 geoprocessing.align_and_resize_raster_stack(779) INFO 23 of 27 aligned: et0_a9_4.tif
2019-10-11 11:36:49,378 geoprocessing.align_and_resize_raster_stack(779) INFO 24 of 27 aligned: et0_a10_4.tif
2019-10-11 11:36:50,391 geoprocessing.align_and_resize_raster_stack(779) INFO 25 of 27 aligned: et0_a11_4.tif
2019-10-11 11:36:51,526 geoprocessing.align_and_resize_raster_stack(779) INFO 26 of 27 aligned: lulc_aligned_4.tif
2019-10-11 11:36:58,094 geoprocessing.align_and_resize_raster_stack(779) INFO 27 of 27 aligned: dem_aligned_4.tif
2019-10-11 11:36:58,234 geoprocessing.align_and_resize_raster_stack(788) INFO aligned all 27 rasters.
2019-10-11 11:37:09,655 Task._call(1068) INFO 0.0% complete
2019-10-11 11:37:15,642 Task._call(1068) INFO 7.7% complete
2019-10-11 11:37:21,127 Task._call(1068) INFO 11.6% complete
2019-10-11 11:37:27,371 Task._call(1068) INFO 15.4% complete
2019-10-11 11:37:33,273 Task._call(1068) INFO 19.2% complete
2019-10-11 11:37:39,026 Task._call(1068) INFO 19.3% complete
2019-10-11 11:37:45,013 Task._call(1068) INFO 23.1% complete
2019-10-11 11:37:51,374 Task._call(1068) INFO 23.1% complete
2019-10-11 11:37:57,053 Task._call(1068) INFO 23.1% complete
2019-10-11 11:38:03,186 Task._call(1068) INFO 26.9% complete
2019-10-11 11:38:09,096 Task._call(1068) INFO 27.0% complete
2019-10-11 11:38:15,661 Task._call(1068) INFO 30.8% complete
2019-10-11 11:38:21,194 Task._call(1068) INFO 30.8% complete
2019-10-11 11:38:27,055 Task._call(1068) INFO 34.6% complete
2019-10-11 11:38:33,072 Task._call(1068) INFO 34.6% complete
2019-10-11 11:38:39,354 Task._call(1068) INFO 38.5% complete
2019-10-11 11:38:45,400 Task._call(1068) INFO 38.5% complete
2019-10-11 11:38:51,134 Task._call(1068) INFO 38.5% complete
2019-10-11 11:38:57,289 Task._call(1068) INFO 42.3% complete
2019-10-11 11:39:03,279 Task._call(1068) INFO 42.3% complete
2019-10-11 11:39:09,384 Task._call(1068) INFO 42.3% complete
2019-10-11 11:39:15,487 Task._call(1068) INFO 46.2% complete
2019-10-11 11:39:21,494 Task._call(1068) INFO 46.2% complete
2019-10-11 11:39:27,336 Task._call(1068) INFO 50.0% complete
2019-10-11 11:39:33,153 Task._call(1068) INFO 50.0% complete
2019-10-11 11:39:39,227 Task._call(1068) INFO 50.0% complete
2019-10-11 11:39:45,565 Task._call(1068) INFO 53.9% complete
2019-10-11 11:39:51,039 Task._call(1068) INFO 53.9% complete
2019-10-11 11:39:57,094 Task._call(1068) INFO 53.9% complete
2019-10-11 11:40:03,165 Task._call(1068) INFO 57.7% complete
2019-10-11 11:40:09,112 Task._call(1068) INFO 57.7% complete
2019-10-11 11:40:15,157 Task._call(1068) INFO 57.7% complete
2019-10-11 11:40:21,384 Task._call(1068) INFO 61.6% complete
2019-10-11 11:40:27,385 Task._call(1068) INFO 61.6% complete
2019-10-11 11:40:33,157 Task._call(1068) INFO 61.6% complete
2019-10-11 11:40:39,684 Task._call(1068) INFO 65.4% complete
2019-10-11 11:40:45,293 Task._call(1068) INFO 65.4% complete
2019-10-11 11:40:51,259 Task._call(1068) INFO 65.4% complete
2019-10-11 11:40:57,539 Task._call(1068) INFO 69.3% complete
2019-10-11 11:41:03,155 Task._call(1068) INFO 69.3% complete
2019-10-11 11:41:09,305 Task._call(1068) INFO 69.3% complete
2019-10-11 11:41:15,078 Task._call(1068) INFO 73.1% complete
2019-10-11 11:41:21,450 Task._call(1068) INFO 73.1% complete
2019-10-11 11:41:27,072 Task._call(1068) INFO 73.1% complete
2019-10-11 11:41:33,576 Task._call(1068) INFO 77.0% complete
2019-10-11 11:41:39,055 Task._call(1068) INFO 77.0% complete
2019-10-11 11:41:45,273 Task._call(1068) INFO 80.8% complete
2019-10-11 11:41:51,068 Task._call(1068) INFO 84.7% complete
2019-10-11 11:41:56,582 Task._call(1068) INFO 100.0% complete
2019-10-11 11:42:06,901 Task._call(1068) INFO 0.0% complete
2019-10-11 11:42:12,155 Task._call(1068) INFO 7.7% complete
2019-10-11 11:42:18,427 Task._call(1068) INFO 11.6% complete
2019-10-11 11:42:24,194 Task._call(1068) INFO 11.6% complete
2019-10-11 11:42:30,612 Task._call(1068) INFO 15.4% complete
2019-10-11 11:42:36,316 Task._call(1068) INFO 19.2% complete
2019-10-11 11:42:42,947 Task._call(1068) INFO 19.3% complete
2019-10-11 11:42:48,247 Task._call(1068) INFO 19.3% complete
2019-10-11 11:42:54,348 Task._call(1068) INFO 23.1% complete
2019-10-11 11:43:00,530 Task._call(1068) INFO 23.1% complete
2019-10-11 11:43:06,276 Task._call(1068) INFO 23.1% complete
2019-10-11 11:43:12,529 Task._call(1068) INFO 26.9% complete
2019-10-11 11:43:18,664 Task._call(1068) INFO 26.9% complete
2019-10-11 11:43:24,759 Task._call(1068) INFO 26.9% complete
2019-10-11 11:43:30,602 Task._call(1068) INFO 30.8% complete
2019-10-11 11:43:36,897 Task._call(1068) INFO 30.8% complete
2019-10-11 11:43:42,779 Task._call(1068) INFO 30.8% complete
2019-10-11 11:43:48,009 Task._call(1068) INFO 30.8% complete
2019-10-11 11:43:54,345 Task._call(1068) INFO 34.6% complete
2019-10-11 11:44:00,552 Task._call(1068) INFO 34.6% complete
2019-10-11 11:44:06,062 Task._call(1068) INFO 34.6% complete
2019-10-11 11:44:12,132 Task._call(1068) INFO 38.5% complete
2019-10-11 11:44:18,677 Task._call(1068) INFO 38.5% complete
2019-10-11 11:44:24,467 Task._call(1068) INFO 38.5% complete
2019-10-11 11:44:30,581 Task._call(1068) INFO 42.3% complete
2019-10-11 11:44:36,558 Task._call(1068) INFO 42.3% complete
2019-10-11 11:44:42,549 Task._call(1068) INFO 42.3% complete
2019-10-11 11:44:48,453 Task._call(1068) INFO 42.3% complete
2019-10-11 11:44:54,190 Task._call(1068) INFO 46.2% complete
2019-10-11 11:45:00,543 Task._call(1068) INFO 46.2% complete
2019-10-11 11:45:06,453 Task._call(1068) INFO 46.2% complete
2019-10-11 11:45:12,015 Task._call(1068) INFO 46.2% complete
2019-10-11 11:45:18,085 Task._call(1068) INFO 50.0% complete
2019-10-11 11:45:24,407 Task._call(1068) INFO 50.0% complete
2019-10-11 11:45:30,348 Task._call(1068) INFO 50.0% complete
2019-10-11 11:45:36,332 Task._call(1068) INFO 50.0% complete
2019-10-11 11:45:42,005 Task._call(1068) INFO 53.9% complete
2019-10-11 11:45:48,653 Task._call(1068) INFO 53.9% complete
2019-10-11 11:45:54,296 Task._call(1068) INFO 53.9% complete
2019-10-11 11:46:00,079 Task._call(1068) INFO 53.9% complete
2019-10-11 11:46:06,345 Task._call(1068) INFO 57.7% complete
2019-10-11 11:46:12,163 Task._call(1068) INFO 57.7% complete
2019-10-11 11:46:18,006 Task._call(1068) INFO 57.7% complete
2019-10-11 11:46:24,892 Task._call(1068) INFO 61.6% complete
2019-10-11 11:46:30,302 Task._call(1068) INFO 61.6% complete
2019-10-11 11:46:36,637 Task._call(1068) INFO 61.6% complete
2019-10-11 11:46:42,450 Task._call(1068) INFO 61.6% complete
2019-10-11 11:46:48,644 Task._call(1068) INFO 65.4% complete
2019-10-11 11:46:54,671 Task._call(1068) INFO 65.4% complete
2019-10-11 11:47:00,242 Task._call(1068) INFO 65.4% complete
2019-10-11 11:47:06,092 Task._call(1068) INFO 69.3% complete
2019-10-11 11:47:12,786 Task._call(1068) INFO 69.3% complete
2019-10-11 11:47:18,071 Task._call(1068) INFO 69.3% complete
2019-10-11 11:47:24,391 Task._call(1068) INFO 69.3% complete
2019-10-11 11:47:30,071 Task._call(1068) INFO 73.1% complete
2019-10-11 11:47:36,635 Task._call(1068) INFO 73.1% complete
2019-10-11 11:47:42,224 Task._call(1068) INFO 73.1% complete
2019-10-11 11:47:48,348 Task._call(1068) INFO 73.1% complete
2019-10-11 11:47:54,229 Task._call(1068) INFO 77.0% complete
2019-10-11 11:48:00,012 Task._call(1068) INFO 77.0% complete
2019-10-11 11:48:06,098 Task._call(1068) INFO 77.0% complete
2019-10-11 11:48:12,301 Task._call(1068) INFO 80.8% complete
2019-10-11 11:48:18,286 Task._call(1068) INFO 80.8% complete
2019-10-11 11:48:24,204 Task._call(1068) INFO 84.7% complete
2019-10-11 11:48:30,072 Task._call(1068) INFO 92.4% complete
2019-10-11 11:48:34,035 Task._call(1068) INFO 100.0% complete
2019-10-11 11:48:40,002 Task._call(1068) INFO 3.9% complete
2019-10-11 11:49:10,351 Task._call(1068) INFO 11.5% complete
2019-10-11 11:49:16,526 Task._call(1068) INFO 26.9% complete
2019-10-11 11:50:47,298 Task._call(1068) INFO 30.8% complete
2019-10-11 11:50:54,130 Task._call(1068) INFO 50.0% complete
2019-10-11 11:51:00,418 Task._call(1068) INFO 77.0% complete
2019-10-11 11:51:06,029 Task._call(1068) INFO 88.5% complete
2019-10-11 11:51:09,892 Task._call(1068) INFO 100.0% complete
2019-10-11 11:51:27,085 Task._call(1068) INFO 11.5% complete
2019-10-11 11:51:33,414 Task._call(1068) INFO 19.2% complete
2019-10-11 11:51:39,168 Task._call(1068) INFO 26.9% complete
2019-10-11 11:52:15,341 Task._call(1068) INFO 30.8% complete
2019-10-11 11:52:21,000 Task._call(1068) INFO 38.5% complete
2019-10-11 11:52:27,051 Task._call(1068) INFO 46.2% complete
2019-10-11 11:52:33,003 Task._call(1068) INFO 53.9% complete
2019-10-11 11:52:39,019 Task._call(1068) INFO 61.6% complete
2019-10-11 11:52:45,069 Task._call(1068) INFO 69.3% complete
2019-10-11 11:52:51,028 Task._call(1068) INFO 77.0% complete
2019-10-11 11:52:57,003 Task._call(1068) INFO 88.5% complete
2019-10-11 11:53:00,178 Task._call(1068) INFO filter out incomplete divergent streams
2019-10-11 11:53:01,378 Task._call(1068) INFO 100.0% complete
2019-10-11 11:53:01,457 seasonal_water_yield._execute(363) INFO quick flow
2019-10-11 11:53:01,457 seasonal_water_yield._execute(377) INFO loading number of monthly events
2019-10-11 11:53:32,779 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-10-11 11:53:32,779 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-5, started daemon 4592)>
2019-10-11 11:53:37,779 geoprocessing.<lambda>(442) INFO 83.9% complete
2019-10-11 11:53:38,792 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-10-11 11:53:38,796 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-10-11 11:53:38,799 threading.run(754) DEBUG payload is None, terminating
2019-10-11 11:53:38,799 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-10-11 11:53:39,528 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-10-11 11:53:39,543 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-6, started daemon 14956)>
2019-10-11 11:53:44,529 geoprocessing.<lambda>(442) INFO 90.1% complete
2019-10-11 11:53:45,072 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-10-11 11:53:45,076 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-10-11 11:53:45,078 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-10-11 11:53:45,079 threading.run(754) DEBUG payload is None, terminating
2019-10-11 11:53:45,464 seasonal_water_yield._execute(442) INFO calculate quick flow for month 1
2019-10-11 11:53:46,253 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-10-11 11:53:46,253 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-7, started daemon 5452)>
2019-10-11 11:53:51,255 geoprocessing.<lambda>(442) INFO 29.8% complete
2019-10-11 11:53:56,260 geoprocessing.<lambda>(442) INFO 50.0% complete
2019-10-11 11:54:01,280 geoprocessing.<lambda>(442) INFO 68.1% complete
2019-10-11 11:54:06,301 geoprocessing.<lambda>(442) INFO 90.7% complete
2019-10-11 11:54:07,200 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-10-11 11:54:07,200 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-10-11 11:54:07,217 threading.run(754) DEBUG payload is None, terminating
2019-10-11 11:54:07,217 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-10-11 11:54:07,556 seasonal_water_yield._execute(442) INFO calculate quick flow for month 2
2019-10-11 11:54:08,026 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-10-11 11:54:08,026 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-8, started daemon 12856)>
2019-10-11 11:54:13,056 geoprocessing.<lambda>(442) INFO 32.1% complete
2019-10-11 11:54:18,066 geoprocessing.<lambda>(442) INFO 53.9% complete
2019-10-11 11:54:23,075 geoprocessing.<lambda>(442) INFO 74.1% complete
2019-10-11 11:54:27,099 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-10-11 11:54:27,102 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-10-11 11:54:27,105 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-10-11 11:54:27,105 threading.run(754) DEBUG payload is None, terminating
2019-10-11 11:54:27,417 seasonal_water_yield._execute(442) INFO calculate quick flow for month 3
2019-10-11 11:54:27,880 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-10-11 11:54:27,895 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-9, started daemon 7568)>
2019-10-11 11:54:32,882 geoprocessing.<lambda>(442) INFO 25.2% complete
2019-10-11 11:54:37,891 geoprocessing.<lambda>(442) INFO 39.7% complete
2019-10-11 11:54:42,918 geoprocessing.<lambda>(442) INFO 53.5% complete
2019-10-11 11:54:47,943 geoprocessing.<lambda>(442) INFO 66.7% complete
2019-10-11 11:54:52,970 geoprocessing.<lambda>(442) INFO 81.6% complete
2019-10-11 11:54:56,334 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-10-11 11:54:56,349 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-10-11 11:54:56,349 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-10-11 11:54:56,349 threading.run(754) DEBUG payload is None, terminating
2019-10-11 11:54:56,642 seasonal_water_yield._execute(442) INFO calculate quick flow for month 4
2019-10-11 11:54:57,187 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-10-11 11:54:57,187 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-10, started daemon 3432)>
2019-10-11 11:55:02,233 geoprocessing.<lambda>(442) INFO 28.5% complete
2019-10-11 11:55:07,283 geoprocessing.<lambda>(442) INFO 47.0% complete
2019-10-11 11:55:12,303 geoprocessing.<lambda>(442) INFO 64.0% complete
2019-10-11 11:55:17,344 geoprocessing.<lambda>(442) INFO 82.7% complete
2019-10-11 11:55:19,826 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-10-11 11:55:19,829 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-10-11 11:55:19,832 threading.run(754) DEBUG payload is None, terminating
2019-10-11 11:55:19,832 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-10-11 11:55:20,154 seasonal_water_yield._execute(442) INFO calculate quick flow for month 5
2019-10-11 11:55:20,665 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-10-11 11:55:20,665 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-11, started daemon 14796)>
2019-10-11 11:55:25,694 geoprocessing.<lambda>(442) INFO 32.4% complete
2019-10-11 11:55:30,698 geoprocessing.<lambda>(442) INFO 54.7% complete
2019-10-11 11:55:35,743 geoprocessing.<lambda>(442) INFO 75.4% complete
2019-10-11 11:55:39,486 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-10-11 11:55:39,490 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-10-11 11:55:39,493 threading.run(754) DEBUG payload is None, terminating
2019-10-11 11:55:39,493 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-10-11 11:55:39,832 seasonal_water_yield._execute(442) INFO calculate quick flow for month 6
2019-10-11 11:55:40,746 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-10-11 11:55:40,762 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-12, started daemon 18572)>
2019-10-11 11:55:45,757 geoprocessing.<lambda>(442) INFO 33.5% complete
2019-10-11 11:55:50,770 geoprocessing.<lambda>(442) INFO 58.2% complete
2019-10-11 11:55:55,782 geoprocessing.<lambda>(442) INFO 82.4% complete
2019-10-11 11:55:57,776 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-10-11 11:55:57,792 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-10-11 11:55:57,792 threading.run(754) DEBUG payload is None, terminating
2019-10-11 11:55:57,792 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-10-11 11:55:58,101 seasonal_water_yield._execute(442) INFO calculate quick flow for month 7
2019-10-11 11:55:58,607 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-10-11 11:55:58,609 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-13, started daemon 13948)>
2019-10-11 11:56:03,611 geoprocessing.<lambda>(442) INFO 36.2% complete
2019-10-11 11:56:08,618 geoprocessing.<lambda>(442) INFO 62.5% complete
2019-10-11 11:56:13,645 geoprocessing.<lambda>(442) INFO 91.3% complete
2019-10-11 11:56:14,289 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-10-11 11:56:14,292 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-10-11 11:56:14,296 threading.run(754) DEBUG payload is None, terminating
2019-10-11 11:56:14,295 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-10-11 11:56:14,655 seasonal_water_yield._execute(442) INFO calculate quick flow for month 8
2019-10-11 11:56:15,375 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-10-11 11:56:15,375 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-14, started daemon 7964)>
2019-10-11 11:56:20,378 geoprocessing.<lambda>(442) INFO 37.7% complete
2019-10-11 11:56:25,385 geoprocessing.<lambda>(442) INFO 66.2% complete
2019-10-11 11:56:29,924 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-10-11 11:56:29,924 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-10-11 11:56:29,924 threading.run(754) DEBUG payload is None, terminating
2019-10-11 11:56:29,924 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-10-11 11:56:30,279 seasonal_water_yield._execute(442) INFO calculate quick flow for month 9
2019-10-11 11:56:30,795 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-10-11 11:56:30,795 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-15, started daemon 1212)>
2019-10-11 11:56:35,809 geoprocessing.<lambda>(442) INFO 38.6% complete
2019-10-11 11:56:40,811 geoprocessing.<lambda>(442) INFO 65.4% complete
2019-10-11 11:56:45,569 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-10-11 11:56:45,569 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-10-11 11:56:45,569 threading.run(754) DEBUG payload is None, terminating
2019-10-11 11:56:45,569 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-10-11 11:56:45,941 seasonal_water_yield._execute(442) INFO calculate quick flow for month 10
2019-10-11 11:56:46,966 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-10-11 11:56:46,966 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-16, started daemon 18204)>
2019-10-11 11:56:51,983 geoprocessing.<lambda>(442) INFO 39.0% complete
2019-10-11 11:56:56,993 geoprocessing.<lambda>(442) INFO 67.8% complete
2019-10-11 11:57:01,078 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-10-11 11:57:01,078 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-10-11 11:57:01,078 threading.run(754) DEBUG payload is None, terminating
2019-10-11 11:57:01,078 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-10-11 11:57:01,384 seasonal_water_yield._execute(442) INFO calculate quick flow for month 11
2019-10-11 11:57:01,806 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-10-11 11:57:01,822 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-17, started daemon 580)>
2019-10-11 11:57:06,808 geoprocessing.<lambda>(442) INFO 37.0% complete
2019-10-11 11:57:11,809 geoprocessing.<lambda>(442) INFO 63.4% complete
2019-10-11 11:57:16,816 geoprocessing.<lambda>(442) INFO 91.5% complete
2019-10-11 11:57:17,430 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-10-11 11:57:17,433 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-10-11 11:57:17,436 threading.run(754) DEBUG payload is None, terminating
2019-10-11 11:57:17,434 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-10-11 11:57:17,786 seasonal_water_yield._execute(442) INFO calculate quick flow for month 12
2019-10-11 11:57:18,266 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-10-11 11:57:18,266 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-18, started daemon 18428)>
2019-10-11 11:57:23,282 geoprocessing.<lambda>(442) INFO 32.1% complete
2019-10-11 11:57:28,286 geoprocessing.<lambda>(442) INFO 54.4% complete
2019-10-11 11:57:33,311 geoprocessing.<lambda>(442) INFO 74.7% complete
2019-10-11 11:57:37,499 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-10-11 11:57:37,502 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-10-11 11:57:37,505 threading.run(754) DEBUG payload is None, terminating
2019-10-11 11:57:37,505 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-10-11 11:57:38,473 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-10-11 11:57:38,473 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-19, started daemon 9128)>
2019-10-11 11:57:43,476 geoprocessing.<lambda>(442) INFO 42.3% complete
2019-10-11 11:57:48,480 geoprocessing.<lambda>(442) INFO 78.3% complete
2019-10-11 11:57:50,604 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-10-11 11:57:50,604 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-10-11 11:57:50,619 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-10-11 11:57:50,619 threading.run(754) DEBUG payload is None, terminating
2019-10-11 11:57:50,803 seasonal_water_yield._execute(470) INFO calculate local recharge
2019-10-11 11:57:50,960 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-10-11 11:57:50,960 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-20, started daemon 15432)>
2019-10-11 11:57:55,963 geoprocessing.<lambda>(442) INFO 99.0% complete
2019-10-11 11:57:56,049 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-10-11 11:57:56,052 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-10-11 11:57:56,055 threading.run(754) DEBUG payload is None, terminating
2019-10-11 11:57:56,055 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-10-11 11:57:56,878 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-10-11 11:57:56,878 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-21, started daemon 4124)>
2019-10-11 11:58:01,664 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-10-11 11:58:01,664 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-10-11 11:58:01,664 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-10-11 11:58:01,664 threading.run(754) DEBUG payload is None, terminating
2019-10-11 11:58:02,447 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-10-11 11:58:02,450 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-22, started daemon 14388)>
2019-10-11 11:58:07,407 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-10-11 11:58:07,423 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-10-11 11:58:07,423 threading.run(754) DEBUG payload is None, terminating
2019-10-11 11:58:07,423 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-10-11 11:58:08,296 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-10-11 11:58:08,296 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-23, started daemon 19440)>
2019-10-11 11:58:13,299 geoprocessing.<lambda>(442) INFO 99.0% complete
2019-10-11 11:58:13,361 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-10-11 11:58:13,377 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-10-11 11:58:13,377 threading.run(754) DEBUG payload is None, terminating
2019-10-11 11:58:13,377 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-10-11 11:58:14,198 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-10-11 11:58:14,198 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-24, started daemon 9916)>
2019-10-11 11:58:19,211 geoprocessing.<lambda>(442) INFO 99.5% complete
2019-10-11 11:58:19,265 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-10-11 11:58:19,267 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-10-11 11:58:19,269 threading.run(754) DEBUG payload is None, terminating
2019-10-11 11:58:19,269 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-10-11 11:58:20,180 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-10-11 11:58:20,183 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-25, started daemon 12536)>
2019-10-11 11:58:25,131 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-10-11 11:58:25,131 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-10-11 11:58:25,131 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-10-11 11:58:25,131 threading.run(754) DEBUG payload is None, terminating
2019-10-11 11:58:25,969 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-10-11 11:58:25,969 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-26, started daemon 7036)>
2019-10-11 11:58:30,938 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-10-11 11:58:30,938 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-10-11 11:58:30,954 threading.run(754) DEBUG payload is None, terminating
2019-10-11 11:58:30,954 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-10-11 11:58:31,743 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-10-11 11:58:31,743 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-27, started daemon 5868)>
2019-10-11 11:58:36,752 geoprocessing.<lambda>(442) INFO 98.1% complete
2019-10-11 11:58:36,878 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-10-11 11:58:36,881 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-10-11 11:58:36,884 threading.run(754) DEBUG payload is None, terminating
2019-10-11 11:58:36,884 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-10-11 11:58:37,743 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-10-11 11:58:37,743 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-28, started daemon 13756)>
2019-10-11 11:58:42,746 geoprocessing.<lambda>(442) INFO 94.8% complete
2019-10-11 11:58:43,040 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-10-11 11:58:43,045 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-10-11 11:58:43,048 threading.run(754) DEBUG payload is None, terminating
2019-10-11 11:58:43,048 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-10-11 11:58:43,907 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-10-11 11:58:43,910 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-29, started daemon 11948)>
2019-10-11 11:58:48,920 geoprocessing.<lambda>(442) INFO 97.9% complete
2019-10-11 11:58:49,039 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-10-11 11:58:49,039 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-10-11 11:58:49,039 threading.run(754) DEBUG payload is None, terminating
2019-10-11 11:58:49,039 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-10-11 11:58:49,875 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-10-11 11:58:49,880 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-30, started daemon 6824)>
2019-10-11 11:58:54,851 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-10-11 11:58:54,868 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-10-11 11:58:54,868 threading.run(754) DEBUG payload is None, terminating
2019-10-11 11:58:54,868 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-10-11 11:58:55,667 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-10-11 11:58:55,671 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-31, started daemon 10740)>
2019-10-11 11:59:00,635 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-10-11 11:59:00,638 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-10-11 11:59:00,641 threading.run(754) DEBUG payload is None, terminating
2019-10-11 11:59:00,641 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-10-11 11:59:10,677 Task._call(1068) INFO peak point detection 0.00% complete
2019-10-11 11:59:17,953 Task._call(1068) INFO peak point detection 3.85% complete
2019-10-11 11:59:23,559 Task._call(1068) INFO peak point detection 3.85% complete
2019-10-11 11:59:30,023 Task._call(1068) INFO peak point detection 3.86% complete
2019-10-11 11:59:36,954 Task._call(1068) INFO peak point detection 3.86% complete
2019-10-11 11:59:46,359 Task._call(1068) INFO peak point detection 7.70% complete
2019-10-11 11:59:52,339 Task._call(1068) INFO peak point detection 7.70% complete
2019-10-11 11:59:58,349 Task._call(1068) INFO peak point detection 7.70% complete
2019-10-11 12:00:05,026 Task._call(1068) INFO peak point detection 7.70% complete
2019-10-11 12:00:12,111 Task._call(1068) INFO peak point detection 7.71% complete
2019-10-11 12:00:18,592 Task._call(1068) INFO peak point detection 7.71% complete
2019-10-11 12:00:24,714 Task._call(1068) INFO peak point detection 7.71% complete
2019-10-11 12:00:30,888 Task._call(1068) INFO peak point detection 7.71% complete
2019-10-11 12:00:36,832 Task._call(1068) INFO peak point detection 7.71% complete
2019-10-11 12:00:43,210 Task._call(1068) INFO peak point detection 11.55% complete
2019-10-11 12:00:50,994 Task._call(1068) INFO peak point detection 11.55% complete
2019-10-11 12:00:57,183 Task._call(1068) INFO peak point detection 11.55% complete
2019-10-11 12:01:06,726 Task._call(1068) INFO peak point detection 11.55% complete
2019-10-11 12:01:13,019 Task._call(1068) INFO peak point detection 11.55% complete
2019-10-11 12:01:20,885 Task._call(1068) INFO peak point detection 11.55% complete
2019-10-11 12:01:27,967 Task._call(1068) INFO peak point detection 11.56% complete
2019-10-11 12:01:33,394 Task._call(1068) INFO peak point detection 11.56% complete
2019-10-11 12:01:41,802 Task._call(1068) INFO peak point detection 11.56% complete
2019-10-11 12:01:48,778 Task._call(1068) INFO peak point detection 11.56% complete
2019-10-11 12:01:55,197 Task._call(1068) INFO peak point detection 11.56% complete
2019-10-11 12:02:01,628 Task._call(1068) INFO peak point detection 11.56% complete
2019-10-11 12:02:07,319 Task._call(1068) INFO peak point detection 15.40% complete
2019-10-11 12:02:14,043 Task._call(1068) INFO peak point detection 15.40% complete
2019-10-11 12:02:22,193 Task._call(1068) INFO peak point detection 15.40% complete
2019-10-11 12:02:29,351 Task._call(1068) INFO peak point detection 15.40% complete
2019-10-11 12:02:36,065 Task._call(1068) INFO peak point detection 15.40% complete
2019-10-11 12:02:43,085 Task._call(1068) INFO peak point detection 15.40% complete
2019-10-11 12:02:49,923 Task._call(1068) INFO peak point detection 15.40% complete
2019-10-11 12:02:56,285 Task._call(1068) INFO peak point detection 15.40% complete
2019-10-11 12:03:02,706 Task._call(1068) INFO peak point detection 15.41% complete
2019-10-11 12:03:08,963 Task._call(1068) INFO peak point detection 15.41% complete
2019-10-11 12:03:15,572 Task._call(1068) INFO peak point detection 15.41% complete
2019-10-11 12:03:23,253 Task._call(1068) INFO peak point detection 15.41% complete
2019-10-11 12:03:30,236 Task._call(1068) INFO peak point detection 19.25% complete
2019-10-11 12:03:38,907 Task._call(1068) INFO peak point detection 19.25% complete
2019-10-11 12:03:46,766 Task._call(1068) INFO peak point detection 19.25% complete
2019-10-11 12:03:53,243 Task._call(1068) INFO peak point detection 19.25% complete
2019-10-11 12:03:59,773 Task._call(1068) INFO peak point detection 19.25% complete
2019-10-11 12:04:07,835 Task._call(1068) INFO peak point detection 19.25% complete
2019-10-11 12:04:15,608 Task._call(1068) INFO peak point detection 19.25% complete
2019-10-11 12:04:21,901 Task._call(1068) INFO peak point detection 19.25% complete
2019-10-11 12:04:27,503 Task._call(1068) INFO peak point detection 19.25% complete
2019-10-11 12:04:35,915 Task._call(1068) INFO peak point detection 19.25% complete
2019-10-11 12:04:43,951 Task._call(1068) INFO peak point detection 19.26% complete
2019-10-11 12:04:52,119 Task._call(1068) INFO peak point detection 19.26% complete
2019-10-11 12:05:00,897 Task._call(1068) INFO peak point detection 23.09% complete
2019-10-11 12:05:07,142 Task._call(1068) INFO peak point detection 23.10% complete
2019-10-11 12:05:13,118 Task._call(1068) INFO peak point detection 23.10% complete
2019-10-11 12:05:20,094 Task._call(1068) INFO peak point detection 23.10% complete
2019-10-11 12:05:27,644 Task._call(1068) INFO peak point detection 23.10% complete
2019-10-11 12:05:36,831 Task._call(1068) INFO peak point detection 23.10% complete
2019-10-11 12:05:44,311 Task._call(1068) INFO peak point detection 23.10% complete
2019-10-11 12:05:51,068 Task._call(1068) INFO peak point detection 23.10% complete
2019-10-11 12:05:59,983 Task._call(1068) INFO peak point detection 23.10% complete
2019-10-11 12:06:06,414 Task._call(1068) INFO peak point detection 23.10% complete
2019-10-11 12:06:16,959 Task._call(1068) INFO peak point detection 23.10% complete
2019-10-11 12:06:25,066 Task._call(1068) INFO peak point detection 23.10% complete
2019-10-11 12:06:31,710 Task._call(1068) INFO peak point detection 23.10% complete
2019-10-11 12:06:37,483 Task._call(1068) INFO peak point detection 23.11% complete
2019-10-11 12:06:45,910 Task._call(1068) INFO peak point detection 26.94% complete
2019-10-11 12:06:52,375 Task._call(1068) INFO peak point detection 26.94% complete
2019-10-11 12:06:58,423 Task._call(1068) INFO peak point detection 26.94% complete
2019-10-11 12:07:04,733 Task._call(1068) INFO peak point detection 26.94% complete
2019-10-11 12:07:11,707 Task._call(1068) INFO peak point detection 26.95% complete
2019-10-11 12:07:20,319 Task._call(1068) INFO peak point detection 26.95% complete
2019-10-11 12:07:32,216 Task._call(1068) INFO peak point detection 26.95% complete
2019-10-11 12:07:38,569 Task._call(1068) INFO peak point detection 26.95% complete
2019-10-11 12:07:45,936 Task._call(1068) INFO peak point detection 26.95% complete
2019-10-11 12:07:54,312 Task._call(1068) INFO peak point detection 26.95% complete
2019-10-11 12:08:03,269 Task._call(1068) INFO peak point detection 26.95% complete
2019-10-11 12:08:10,332 Task._call(1068) INFO peak point detection 26.95% complete
2019-10-11 12:08:18,250 Task._call(1068) INFO peak point detection 26.95% complete
2019-10-11 12:08:28,275 Task._call(1068) INFO peak point detection 26.95% complete
2019-10-11 12:08:38,436 Task._call(1068) INFO peak point detection 26.95% complete
2019-10-11 12:08:45,028 Task._call(1068) INFO peak point detection 30.79% complete
2019-10-11 12:08:51,516 Task._call(1068) INFO peak point detection 30.79% complete
2019-10-11 12:08:59,641 Task._call(1068) INFO peak point detection 30.79% complete
2019-10-11 12:09:05,188 Task._call(1068) INFO peak point detection 30.79% complete
2019-10-11 12:09:11,901 Task._call(1068) INFO peak point detection 30.79% complete
2019-10-11 12:09:18,437 Task._call(1068) INFO peak point detection 30.79% complete
2019-10-11 12:09:24,338 Task._call(1068) INFO peak point detection 30.80% complete
2019-10-11 12:09:30,677 Task._call(1068) INFO peak point detection 30.80% complete
2019-10-11 12:09:37,233 Task._call(1068) INFO peak point detection 30.80% complete
2019-10-11 12:09:43,732 Task._call(1068) INFO peak point detection 30.80% complete
2019-10-11 12:09:49,683 Task._call(1068) INFO peak point detection 30.80% complete
2019-10-11 12:09:55,428 Task._call(1068) INFO peak point detection 30.80% complete
2019-10-11 12:10:02,572 Task._call(1068) INFO peak point detection 30.80% complete
2019-10-11 12:10:28,161 Task._call(1068) INFO peak point detection 30.80% complete
2019-10-11 12:10:36,243 Task._call(1068) INFO peak point detection 34.64% complete
2019-10-11 12:10:42,243 Task._call(1068) INFO peak point detection 34.64% complete
2019-10-11 12:10:51,359 Task._call(1068) INFO peak point detection 34.64% complete
2019-10-11 12:11:00,904 Task._call(1068) INFO peak point detection 34.64% complete
2019-10-11 12:11:07,263 Task._call(1068) INFO peak point detection 34.64% complete
2019-10-11 12:11:13,930 Task._call(1068) INFO peak point detection 34.64% complete
2019-10-11 12:11:19,362 Task._call(1068) INFO peak point detection 34.64% complete
2019-10-11 12:11:25,503 Task._call(1068) INFO peak point detection 34.64% complete
2019-10-11 12:11:32,740 Task._call(1068) INFO peak point detection 34.65% complete
2019-10-11 12:11:39,351 Task._call(1068) INFO peak point detection 34.65% complete
2019-10-11 12:11:46,308 Task._call(1068) INFO peak point detection 34.65% complete
2019-10-11 12:11:53,627 Task._call(1068) INFO peak point detection 34.65% complete
2019-10-11 12:11:59,132 Task._call(1068) INFO peak point detection 34.65% complete
2019-10-11 12:12:05,130 Task._call(1068) INFO peak point detection 34.65% complete
2019-10-11 12:12:11,440 Task._call(1068) INFO peak point detection 38.49% complete
2019-10-11 12:12:18,440 Task._call(1068) INFO peak point detection 38.49% complete
2019-10-11 12:12:26,941 Task._call(1068) INFO peak point detection 38.49% complete
2019-10-11 12:12:33,588 Task._call(1068) INFO peak point detection 38.49% complete
2019-10-11 12:12:52,180 Task._call(1068) INFO peak point detection 38.49% complete
2019-10-11 12:12:58,658 Task._call(1068) INFO peak point detection 38.49% complete
2019-10-11 12:13:05,053 Task._call(1068) INFO peak point detection 38.49% complete
2019-10-11 12:13:13,483 Task._call(1068) INFO peak point detection 38.49% complete
2019-10-11 12:13:20,526 Task._call(1068) INFO peak point detection 38.49% complete
2019-10-11 12:13:26,595 Task._call(1068) INFO peak point detection 38.50% complete
2019-10-11 12:13:32,076 Task._call(1068) INFO peak point detection 38.50% complete
2019-10-11 12:13:39,825 Task._call(1068) INFO peak point detection 38.50% complete
2019-10-11 12:13:45,286 Task._call(1068) INFO peak point detection 38.50% complete
2019-10-11 12:13:52,492 Task._call(1068) INFO peak point detection 38.50% complete
2019-10-11 12:13:58,921 Task._call(1068) INFO peak point detection 42.33% complete
2019-10-11 12:14:04,805 Task._call(1068) INFO peak point detection 42.34% complete
2019-10-11 12:14:13,345 Task._call(1068) INFO peak point detection 42.34% complete
2019-10-11 12:14:22,640 Task._call(1068) INFO peak point detection 42.34% complete
2019-10-11 12:14:29,049 Task._call(1068) INFO peak point detection 42.34% complete
2019-10-11 12:14:35,789 Task._call(1068) INFO peak point detection 42.34% complete
2019-10-11 12:14:42,803 Task._call(1068) INFO peak point detection 42.34% complete
2019-10-11 12:14:48,973 Task._call(1068) INFO peak point detection 42.34% complete
2019-10-11 12:14:55,306 Task._call(1068) INFO peak point detection 42.34% complete
2019-10-11 12:15:02,490 Task._call(1068) INFO peak point detection 42.34% complete
2019-10-11 12:15:08,042 Task._call(1068) INFO peak point detection 42.34% complete
2019-10-11 12:15:15,654 Task._call(1068) INFO peak point detection 42.34% complete
2019-10-11 12:15:40,039 Task._call(1068) INFO peak point detection 42.35% complete
2019-10-11 12:15:46,698 Task._call(1068) INFO peak point detection 42.35% complete
2019-10-11 12:15:53,736 Task._call(1068) INFO peak point detection 42.35% complete
2019-10-11 12:16:00,642 Task._call(1068) INFO peak point detection 42.35% complete
2019-10-11 12:16:07,599 Task._call(1068) INFO peak point detection 46.18% complete
2019-10-11 12:16:15,822 Task._call(1068) INFO peak point detection 46.18% complete
2019-10-11 12:16:22,107 Task._call(1068) INFO peak point detection 46.19% complete
2019-10-11 12:16:28,334 Task._call(1068) INFO peak point detection 46.19% complete
2019-10-11 12:16:36,967 Task._call(1068) INFO peak point detection 46.19% complete
2019-10-11 12:16:44,536 Task._call(1068) INFO peak point detection 46.19% complete
2019-10-11 12:16:51,115 Task._call(1068) INFO peak point detection 46.19% complete
2019-10-11 12:16:58,869 Task._call(1068) INFO peak point detection 46.19% complete
2019-10-11 12:17:07,937 Task._call(1068) INFO peak point detection 46.19% complete
2019-10-11 12:17:15,270 Task._call(1068) INFO peak point detection 46.19% complete
2019-10-11 12:17:23,832 Task._call(1068) INFO peak point detection 46.19% complete
2019-10-11 12:17:29,306 Task._call(1068) INFO peak point detection 46.19% complete
2019-10-11 12:17:45,517 Task._call(1068) INFO peak point detection 46.19% complete
2019-10-11 12:17:54,000 Task._call(1068) INFO peak point detection 46.20% complete
2019-10-11 12:18:03,473 Task._call(1068) INFO peak point detection 50.03% complete
2019-10-11 12:18:10,391 Task._call(1068) INFO peak point detection 50.03% complete
2019-10-11 12:18:19,851 Task._call(1068) INFO peak point detection 50.03% complete
2019-10-11 12:18:26,325 Task._call(1068) INFO peak point detection 50.03% complete
2019-10-11 12:18:35,203 Task._call(1068) INFO peak point detection 50.04% complete
2019-10-11 12:18:42,619 Task._call(1068) INFO peak point detection 50.04% complete
2019-10-11 12:18:49,410 Task._call(1068) INFO peak point detection 50.04% complete
2019-10-11 12:18:56,007 Task._call(1068) INFO peak point detection 50.04% complete
2019-10-11 12:19:02,137 Task._call(1068) INFO peak point detection 50.04% complete
2019-10-11 12:19:14,440 Task._call(1068) INFO peak point detection 50.04% complete
2019-10-11 12:19:30,763 Task._call(1068) INFO peak point detection 50.04% complete
2019-10-11 12:19:37,512 Task._call(1068) INFO peak point detection 50.04% complete
2019-10-11 12:19:45,253 Task._call(1068) INFO peak point detection 50.04% complete
2019-10-11 12:19:52,680 Task._call(1068) INFO peak point detection 50.04% complete
2019-10-11 12:19:58,065 Task._call(1068) INFO peak point detection 50.04% complete
2019-10-11 12:20:40,831 Task._call(1068) INFO peak point detection 50.04% complete
2019-10-11 12:20:47,437 Task._call(1068) INFO peak point detection 53.88% complete
2019-10-11 12:20:54,003 Task._call(1068) INFO peak point detection 53.88% complete
2019-10-11 12:21:00,223 Task._call(1068) INFO peak point detection 53.88% complete
2019-10-11 12:21:07,515 Task._call(1068) INFO peak point detection 53.88% complete
2019-10-11 12:21:19,961 Task._call(1068) INFO peak point detection 53.88% complete
2019-10-11 12:21:27,223 Task._call(1068) INFO peak point detection 53.89% complete
2019-10-11 12:21:36,104 Task._call(1068) INFO peak point detection 53.89% complete
2019-10-11 12:21:42,142 Task._call(1068) INFO peak point detection 53.89% complete
2019-10-11 12:21:55,170 Task._call(1068) INFO peak point detection 53.89% complete
2019-10-11 12:22:01,599 Task._call(1068) INFO peak point detection 53.89% complete
2019-10-11 12:22:09,084 Task._call(1068) INFO peak point detection 53.89% complete
2019-10-11 12:22:17,147 Task._call(1068) INFO peak point detection 53.89% complete
2019-10-11 12:22:24,476 Task._call(1068) INFO peak point detection 53.89% complete
2019-10-11 12:22:30,736 Task._call(1068) INFO peak point detection 53.89% complete
2019-10-11 12:22:39,368 Task._call(1068) INFO peak point detection 57.73% complete
2019-10-11 12:22:47,204 Task._call(1068) INFO peak point detection 57.73% complete
2019-10-11 12:22:56,839 Task._call(1068) INFO peak point detection 57.73% complete
2019-10-11 12:23:05,631 Task._call(1068) INFO peak point detection 57.73% complete
2019-10-11 12:23:11,470 Task._call(1068) INFO peak point detection 57.73% complete
2019-10-11 12:23:19,135 Task._call(1068) INFO peak point detection 57.73% complete
2019-10-11 12:23:28,069 Task._call(1068) INFO peak point detection 57.73% complete
2019-10-11 12:23:34,625 Task._call(1068) INFO peak point detection 57.74% complete
2019-10-11 12:23:41,591 Task._call(1068) INFO peak point detection 57.74% complete
2019-10-11 12:23:48,773 Task._call(1068) INFO peak point detection 57.74% complete
2019-10-11 12:23:55,025 Task._call(1068) INFO peak point detection 57.74% complete
2019-10-11 12:24:01,108 Task._call(1068) INFO peak point detection 57.74% complete
2019-10-11 12:24:07,865 Task._call(1068) INFO peak point detection 57.74% complete
2019-10-11 12:24:15,259 Task._call(1068) INFO peak point detection 57.74% complete
2019-10-11 12:24:28,058 Task._call(1068) INFO peak point detection 57.74% complete
2019-10-11 12:24:36,061 Task._call(1068) INFO peak point detection 61.58% complete
2019-10-11 12:24:42,569 Task._call(1068) INFO peak point detection 61.58% complete
2019-10-11 12:24:50,111 Task._call(1068) INFO peak point detection 61.58% complete
2019-10-11 12:24:57,516 Task._call(1068) INFO peak point detection 61.58% complete
2019-10-11 12:25:04,331 Task._call(1068) INFO peak point detection 61.58% complete
2019-10-11 12:25:12,976 Task._call(1068) INFO peak point detection 61.58% complete
2019-10-11 12:25:19,285 Task._call(1068) INFO peak point detection 61.58% complete
2019-10-11 12:25:25,700 Task._call(1068) INFO peak point detection 61.58% complete
2019-10-11 12:25:31,384 Task._call(1068) INFO peak point detection 61.58% complete
2019-10-11 12:25:41,355 Task._call(1068) INFO peak point detection 61.59% complete
2019-10-11 12:25:47,142 Task._call(1068) INFO peak point detection 61.59% complete
2019-10-11 12:25:56,292 Task._call(1068) INFO peak point detection 61.59% complete
2019-10-11 12:26:03,413 Task._call(1068) INFO peak point detection 61.59% complete
2019-10-11 12:26:11,145 Task._call(1068) INFO peak point detection 61.59% complete
2019-10-11 12:26:17,976 Task._call(1068) INFO peak point detection 65.42% complete
2019-10-11 12:26:24,138 Task._call(1068) INFO peak point detection 65.43% complete
2019-10-11 12:26:32,190 Task._call(1068) INFO peak point detection 65.43% complete
2019-10-11 12:26:38,979 Task._call(1068) INFO peak point detection 65.43% complete
2019-10-11 12:26:47,658 Task._call(1068) INFO peak point detection 65.43% complete
2019-10-11 12:26:53,420 Task._call(1068) INFO peak point detection 65.43% complete
2019-10-11 12:27:00,743 Task._call(1068) INFO peak point detection 65.43% complete
2019-10-11 12:27:06,492 Task._call(1068) INFO peak point detection 65.43% complete
2019-10-11 12:27:12,759 Task._call(1068) INFO peak point detection 65.43% complete
2019-10-11 12:27:23,734 Task._call(1068) INFO peak point detection 65.43% complete
2019-10-11 12:27:30,161 Task._call(1068) INFO peak point detection 65.43% complete
2019-10-11 12:27:36,713 Task._call(1068) INFO peak point detection 65.43% complete
2019-10-11 12:27:44,055 Task._call(1068) INFO peak point detection 65.43% complete
2019-10-11 12:27:50,588 Task._call(1068) INFO peak point detection 65.44% complete
2019-10-11 12:27:59,683 Task._call(1068) INFO peak point detection 65.44% complete
2019-10-11 12:28:05,125 Task._call(1068) INFO peak point detection 65.44% complete
2019-10-11 12:28:11,203 Task._call(1068) INFO peak point detection 65.44% complete
2019-10-11 12:28:17,801 Task._call(1068) INFO peak point detection 69.27% complete
2019-10-11 12:28:24,032 Task._call(1068) INFO peak point detection 69.27% complete
2019-10-11 12:28:30,148 Task._call(1068) INFO peak point detection 69.27% complete
2019-10-11 12:28:38,012 Task._call(1068) INFO peak point detection 69.28% complete
2019-10-11 12:28:45,309 Task._call(1068) INFO peak point detection 69.28% complete
2019-10-11 12:28:52,072 Task._call(1068) INFO peak point detection 69.28% complete
2019-10-11 12:28:58,576 Task._call(1068) INFO peak point detection 69.28% complete
2019-10-11 12:29:06,868 Task._call(1068) INFO peak point detection 69.28% complete
2019-10-11 12:29:12,365 Task._call(1068) INFO peak point detection 69.28% complete
2019-10-11 12:29:20,940 Task._call(1068) INFO peak point detection 69.28% complete
2019-10-11 12:29:26,289 Task._call(1068) INFO peak point detection 69.28% complete
2019-10-11 12:29:40,559 Task._call(1068) INFO peak point detection 69.28% complete
2019-10-11 12:29:48,703 Task._call(1068) INFO peak point detection 69.28% complete
2019-10-11 12:29:55,562 Task._call(1068) INFO peak point detection 69.28% complete
2019-10-11 12:30:01,793 Task._call(1068) INFO peak point detection 69.29% complete
2019-10-11 12:30:07,042 Task._call(1068) INFO peak point detection 69.29% complete
2019-10-11 12:30:13,217 Task._call(1068) INFO peak point detection 73.12% complete
2019-10-11 12:30:21,542 Task._call(1068) INFO peak point detection 73.12% complete
2019-10-11 12:30:27,641 Task._call(1068) INFO peak point detection 73.12% complete
2019-10-11 12:30:33,174 Task._call(1068) INFO peak point detection 73.12% complete
2019-10-11 12:30:41,065 Task._call(1068) INFO peak point detection 73.13% complete
2019-10-11 12:30:48,076 Task._call(1068) INFO peak point detection 73.13% complete
2019-10-11 12:30:54,094 Task._call(1068) INFO peak point detection 73.13% complete
2019-10-11 12:31:01,473 Task._call(1068) INFO peak point detection 73.13% complete
2019-10-11 12:31:08,621 Task._call(1068) INFO peak point detection 73.13% complete
2019-10-11 12:31:15,036 Task._call(1068) INFO peak point detection 73.13% complete
2019-10-11 12:31:24,609 Task._call(1068) INFO peak point detection 73.13% complete
2019-10-11 12:31:32,355 Task._call(1068) INFO peak point detection 73.13% complete
2019-10-11 12:31:38,244 Task._call(1068) INFO peak point detection 73.13% complete
2019-10-11 12:31:45,163 Task._call(1068) INFO peak point detection 73.13% complete
2019-10-11 12:31:53,440 Task._call(1068) INFO peak point detection 73.13% complete
2019-10-11 12:32:00,000 Task._call(1068) INFO peak point detection 73.14% complete
2019-10-11 12:32:10,598 Task._call(1068) INFO peak point detection 76.97% complete
2019-10-11 12:32:19,190 Task._call(1068) INFO peak point detection 76.97% complete
2019-10-11 12:32:25,414 Task._call(1068) INFO peak point detection 76.97% complete
2019-10-11 12:32:33,059 Task._call(1068) INFO peak point detection 76.98% complete
2019-10-11 12:32:42,667 Task._call(1068) INFO peak point detection 76.98% complete
2019-10-11 12:32:49,019 Task._call(1068) INFO peak point detection 76.98% complete
2019-10-11 12:32:58,171 Task._call(1068) INFO peak point detection 76.98% complete
2019-10-11 12:33:07,085 Task._call(1068) INFO peak point detection 76.98% complete
2019-10-11 12:33:16,453 Task._call(1068) INFO peak point detection 76.98% complete
2019-10-11 12:33:22,668 Task._call(1068) INFO peak point detection 76.98% complete
2019-10-11 12:33:31,849 Task._call(1068) INFO peak point detection 76.98% complete
2019-10-11 12:33:38,053 Task._call(1068) INFO peak point detection 76.98% complete
2019-10-11 12:33:46,196 Task._call(1068) INFO peak point detection 76.98% complete
2019-10-11 12:34:00,838 Task._call(1068) INFO peak point detection 76.98% complete
2019-10-11 12:34:06,865 Task._call(1068) INFO peak point detection 80.82% complete
2019-10-11 12:34:15,302 Task._call(1068) INFO peak point detection 80.82% complete
2019-10-11 12:34:21,306 Task._call(1068) INFO peak point detection 80.82% complete
2019-10-11 12:34:27,664 Task._call(1068) INFO peak point detection 80.82% complete
2019-10-11 12:34:35,299 Task._call(1068) INFO peak point detection 80.82% complete
2019-10-11 12:34:54,012 Task._call(1068) INFO peak point detection 80.82% complete
2019-10-11 12:35:01,096 Task._call(1068) INFO peak point detection 80.83% complete
2019-10-11 12:35:18,160 Task._call(1068) INFO peak point detection 80.83% complete
2019-10-11 12:35:28,259 Task._call(1068) INFO peak point detection 80.83% complete
2019-10-11 12:35:35,826 Task._call(1068) INFO peak point detection 80.83% complete
2019-10-11 12:35:41,292 Task._call(1068) INFO peak point detection 80.83% complete
2019-10-11 12:35:47,565 Task._call(1068) INFO peak point detection 80.83% complete
2019-10-11 12:36:07,744 Task._call(1068) INFO peak point detection 80.83% complete
2019-10-11 12:36:14,869 Task._call(1068) INFO peak point detection 80.83% complete
2019-10-11 12:36:22,285 Task._call(1068) INFO peak point detection 80.83% complete
2019-10-11 12:36:29,631 Task._call(1068) INFO peak point detection 84.67% complete
2019-10-11 12:36:37,816 Task._call(1068) INFO peak point detection 84.67% complete
2019-10-11 12:36:46,335 Task._call(1068) INFO peak point detection 84.67% complete
2019-10-11 12:36:54,144 Task._call(1068) INFO peak point detection 84.67% complete
2019-10-11 12:37:00,539 Task._call(1068) INFO peak point detection 84.68% complete
2019-10-11 12:37:06,178 Task._call(1068) INFO peak point detection 84.68% complete
2019-10-11 12:37:14,256 Task._call(1068) INFO peak point detection 84.68% complete
2019-10-11 12:37:21,736 Task._call(1068) INFO peak point detection 84.68% complete
2019-10-11 12:37:27,767 Task._call(1068) INFO peak point detection 88.52% complete
2019-10-11 12:37:35,464 Task._call(1068) INFO peak point detection 88.52% complete
2019-10-11 12:37:48,375 Task._call(1068) INFO peak point detection 88.52% complete
2019-10-11 12:37:56,667 Task._call(1068) INFO peak point detection 88.52% complete
2019-10-11 12:38:02,213 Task._call(1068) INFO peak point detection 88.53% complete
2019-10-11 12:38:12,249 Task._call(1068) INFO peak point detection 88.53% complete
2019-10-11 12:38:20,203 Task._call(1068) INFO peak point detection 88.53% complete
2019-10-11 12:38:26,081 Task._call(1068) INFO peak point detection 92.37% complete
2019-10-11 12:38:35,269 Task._call(1068) INFO peak point detection 92.37% complete
2019-10-11 12:38:58,898 Task._call(1068) INFO peak point detection 92.37% complete
2019-10-11 12:39:05,197 Task._call(1068) INFO peak point detection 92.37% complete
2019-10-11 12:39:12,851 Task._call(1068) INFO peak point detection 92.38% complete
2019-10-11 12:39:18,017 Task._call(1068) INFO peak point detection 96.22% complete
2019-10-11 12:40:00,721 Task._call(1068) INFO peak point detection 96.22% complete
2019-10-11 12:40:02,456 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-10-11 12:40:02,471 model._logged_target(1639) ERROR Exception while executing <function execute at 0x17B92AB0>
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-10-11 12:40:02,471 model._logged_target(1642) INFO Execution finished
2019-10-11 12:40:02,471 utils.prepare_workspace(115) INFO Elapsed time: 1h 3m 43.47s