2019-05-24 16:03:38,894 utils.prepare_workspace(109) INFO Writing log messages to C:\Users\Diego\Desktop\Process_Chingaza\RESULTADOS\CHINGAZA_SWY_2\InVEST-Seasonal-Water-Yield-log-2019-05-24--16_03_38.txt
2019-05-24 16:03:38,901 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                     0.083
aoi_path                    C:/Users/Diego/Desktop/Process_Chingaza/INPUTS_CHINGAZA/BASIN/Guatiquia.shp
beta_i                      1
biophysical_table_path      C:/Users/Diego/Desktop/Process_Chingaza/INPUTS_CHINGAZA/biophysical_table.csv
dem_raster_path             C:/Users/Diego/Desktop/Process_Chingaza/INPUTS_CHINGAZA/DEM/DEM.tif
et0_dir                     C:\Users\Diego\Desktop\Process_Chingaza\INPUTS_CHINGAZA\eto_dir
gamma                       1
lulc_raster_path            C:/Users/Diego/Desktop/Process_Chingaza/INPUTS_CHINGAZA/LUC/Usos_Chingaza.tif
monthly_alpha               False
n_workers                   -1
precip_dir                  C:\Users\Diego\Desktop\Process_Chingaza\INPUTS_CHINGAZA\precip_dir
rain_events_table_path      C:/Users/Diego/Desktop/Process_Chingaza/INPUTS_CHINGAZA/rain_events_table.csv
results_suffix              1
soil_group_path             C:/Users/Diego/Desktop/Process_Chingaza/INPUTS_CHINGAZA/Soil/Suelos_chingaza.tif
threshold_flow_accumulation 1000
user_defined_climate_zones  False
user_defined_local_recharge False
workspace_dir               C:\Users\Diego\Desktop\Process_Chingaza\RESULTADOS\CHINGAZA_SWY_2

2019-05-24 16:03:38,971 seasonal_water_yield._execute(184) INFO prepare and test inputs for common errors
2019-05-24 16:03:39,204 seasonal_water_yield._execute(246) INFO Building file registry
2019-05-24 16:03:39,206 seasonal_water_yield._execute(252) INFO Checking that the AOI is not the output aggregate vector
2019-05-24 16:03:39,206 seasonal_water_yield._execute(261) INFO Aligning and clipping dataset list
2019-05-24 16:03:39,309 geoprocessing.align_and_resize_raster_stack(729) INFO n_workers > 1 (2) so starting a processes pool.
2019-05-24 16:03:40,846 geoprocessing.align_and_resize_raster_stack(779) INFO 1 of 27 aligned: prcp_a0_1.tif
2019-05-24 16:03:41,671 geoprocessing.align_and_resize_raster_stack(779) INFO 2 of 27 aligned: prcp_a1_1.tif
2019-05-24 16:03:41,671 geoprocessing.align_and_resize_raster_stack(779) INFO 3 of 27 aligned: prcp_a2_1.tif
2019-05-24 16:03:41,936 geoprocessing.align_and_resize_raster_stack(779) INFO 4 of 27 aligned: prcp_a3_1.tif
2019-05-24 16:03:41,948 geoprocessing.align_and_resize_raster_stack(779) INFO 5 of 27 aligned: prcp_a4_1.tif
2019-05-24 16:03:42,210 geoprocessing.align_and_resize_raster_stack(779) INFO 6 of 27 aligned: prcp_a5_1.tif
2019-05-24 16:03:42,216 geoprocessing.align_and_resize_raster_stack(779) INFO 7 of 27 aligned: prcp_a6_1.tif
2019-05-24 16:03:42,486 geoprocessing.align_and_resize_raster_stack(779) INFO 8 of 27 aligned: prcp_a7_1.tif
2019-05-24 16:03:42,489 geoprocessing.align_and_resize_raster_stack(779) INFO 9 of 27 aligned: prcp_a8_1.tif
2019-05-24 16:03:42,858 geoprocessing.align_and_resize_raster_stack(779) INFO 10 of 27 aligned: prcp_a9_1.tif
2019-05-24 16:03:42,864 geoprocessing.align_and_resize_raster_stack(779) INFO 11 of 27 aligned: prcp_a10_1.tif
2019-05-24 16:03:43,516 geoprocessing.align_and_resize_raster_stack(779) INFO 12 of 27 aligned: prcp_a11_1.tif
2019-05-24 16:03:43,517 geoprocessing.align_and_resize_raster_stack(779) INFO 13 of 27 aligned: soil_group_aligned_1.tif
2019-05-24 16:03:43,628 geoprocessing.align_and_resize_raster_stack(779) INFO 14 of 27 aligned: et0_a0_1.tif
2019-05-24 16:03:43,796 geoprocessing.align_and_resize_raster_stack(779) INFO 15 of 27 aligned: et0_a1_1.tif
2019-05-24 16:03:43,865 geoprocessing.align_and_resize_raster_stack(779) INFO 16 of 27 aligned: et0_a2_1.tif
2019-05-24 16:03:44,095 geoprocessing.align_and_resize_raster_stack(779) INFO 17 of 27 aligned: et0_a3_1.tif
2019-05-24 16:03:44,138 geoprocessing.align_and_resize_raster_stack(779) INFO 18 of 27 aligned: et0_a4_1.tif
2019-05-24 16:03:44,339 geoprocessing.align_and_resize_raster_stack(779) INFO 19 of 27 aligned: et0_a5_1.tif
2019-05-24 16:03:44,364 geoprocessing.align_and_resize_raster_stack(779) INFO 20 of 27 aligned: et0_a6_1.tif
2019-05-24 16:03:44,588 geoprocessing.align_and_resize_raster_stack(779) INFO 21 of 27 aligned: et0_a7_1.tif
2019-05-24 16:03:44,605 geoprocessing.align_and_resize_raster_stack(779) INFO 22 of 27 aligned: et0_a8_1.tif
2019-05-24 16:03:44,845 geoprocessing.align_and_resize_raster_stack(779) INFO 23 of 27 aligned: et0_a9_1.tif
2019-05-24 16:03:44,867 geoprocessing.align_and_resize_raster_stack(779) INFO 24 of 27 aligned: et0_a10_1.tif
2019-05-24 16:03:45,078 geoprocessing.align_and_resize_raster_stack(779) INFO 25 of 27 aligned: et0_a11_1.tif
2019-05-24 16:03:45,078 geoprocessing.align_and_resize_raster_stack(779) INFO 26 of 27 aligned: lulc_aligned_1.tif
2019-05-24 16:03:45,167 geoprocessing.align_and_resize_raster_stack(779) INFO 27 of 27 aligned: dem_aligned_1.tif
2019-05-24 16:03:45,257 geoprocessing.align_and_resize_raster_stack(788) INFO aligned all 27 rasters.
2019-05-24 16:03:46,378 Task._call(1068) INFO 100.0% complete
2019-05-24 16:03:48,240 Task._call(1068) INFO 100.0% complete
2019-05-24 16:03:49,388 Task._call(1068) INFO 100.0% complete
2019-05-24 16:03:49,851 Task._call(1068) INFO filter out incomplete divergent streams
2019-05-24 16:03:49,874 Task._call(1068) INFO 100.0% complete
2019-05-24 16:03:49,898 seasonal_water_yield._execute(363) INFO quick flow
2019-05-24 16:03:49,898 seasonal_water_yield._execute(377) INFO loading number of monthly events
2019-05-24 16:03:50,664 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-05-24 16:03:50,667 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-5, started daemon 9320)>
2019-05-24 16:03:50,878 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-05-24 16:03:50,878 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-05-24 16:03:50,880 threading.run(754) DEBUG payload is None, terminating
2019-05-24 16:03:50,878 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-05-24 16:03:51,114 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-05-24 16:03:51,115 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-6, started daemon 11312)>
2019-05-24 16:03:51,167 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-05-24 16:03:51,168 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-05-24 16:03:51,170 threading.run(754) DEBUG payload is None, terminating
2019-05-24 16:03:51,168 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-05-24 16:03:51,262 seasonal_water_yield._execute(442) INFO calculate quick flow for month 1
2019-05-24 16:03:51,355 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-05-24 16:03:51,357 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-7, started daemon 9020)>
2019-05-24 16:03:51,532 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-05-24 16:03:51,532 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-05-24 16:03:51,532 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-05-24 16:03:51,533 threading.run(754) DEBUG payload is None, terminating
2019-05-24 16:03:51,700 seasonal_water_yield._execute(442) INFO calculate quick flow for month 2
2019-05-24 16:03:51,801 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-05-24 16:03:51,802 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-8, started daemon 12260)>
2019-05-24 16:03:51,997 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-05-24 16:03:51,997 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-05-24 16:03:51,999 threading.run(754) DEBUG payload is None, terminating
2019-05-24 16:03:51,999 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-05-24 16:03:52,150 seasonal_water_yield._execute(442) INFO calculate quick flow for month 3
2019-05-24 16:03:52,233 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-05-24 16:03:52,236 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-9, started daemon 1292)>
2019-05-24 16:03:52,397 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-05-24 16:03:52,398 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-05-24 16:03:52,398 threading.run(754) DEBUG payload is None, terminating
2019-05-24 16:03:52,398 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-05-24 16:03:52,536 seasonal_water_yield._execute(442) INFO calculate quick flow for month 4
2019-05-24 16:03:52,615 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-05-24 16:03:52,618 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-10, started daemon 2868)>
2019-05-24 16:03:52,792 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-05-24 16:03:52,793 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-05-24 16:03:52,795 threading.run(754) DEBUG payload is None, terminating
2019-05-24 16:03:52,795 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-05-24 16:03:52,931 seasonal_water_yield._execute(442) INFO calculate quick flow for month 5
2019-05-24 16:03:53,012 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-05-24 16:03:53,013 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-11, started daemon 1736)>
2019-05-24 16:03:53,197 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-05-24 16:03:53,197 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-05-24 16:03:53,197 threading.run(754) DEBUG payload is None, terminating
2019-05-24 16:03:53,197 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-05-24 16:03:53,338 seasonal_water_yield._execute(442) INFO calculate quick flow for month 6
2019-05-24 16:03:53,427 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-05-24 16:03:53,430 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-12, started daemon 4668)>
2019-05-24 16:03:53,812 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-05-24 16:03:53,812 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-05-24 16:03:53,812 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-05-24 16:03:53,813 threading.run(754) DEBUG payload is None, terminating
2019-05-24 16:03:53,979 seasonal_water_yield._execute(442) INFO calculate quick flow for month 7
2019-05-24 16:03:54,061 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-05-24 16:03:54,062 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-13, started daemon 2248)>
2019-05-24 16:03:54,250 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-05-24 16:03:54,250 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-05-24 16:03:54,250 threading.run(754) DEBUG payload is None, terminating
2019-05-24 16:03:54,250 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-05-24 16:03:54,398 seasonal_water_yield._execute(442) INFO calculate quick flow for month 8
2019-05-24 16:03:54,479 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-05-24 16:03:54,480 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-14, started daemon 1764)>
2019-05-24 16:03:54,661 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-05-24 16:03:54,661 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-05-24 16:03:54,663 threading.run(754) DEBUG payload is None, terminating
2019-05-24 16:03:54,663 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-05-24 16:03:54,806 seasonal_water_yield._execute(442) INFO calculate quick flow for month 9
2019-05-24 16:03:54,887 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-05-24 16:03:54,890 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-15, started daemon 10972)>
2019-05-24 16:03:55,061 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-05-24 16:03:55,061 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-05-24 16:03:55,062 threading.run(754) DEBUG payload is None, terminating
2019-05-24 16:03:55,062 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-05-24 16:03:55,207 seasonal_water_yield._execute(442) INFO calculate quick flow for month 10
2019-05-24 16:03:55,292 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-05-24 16:03:55,293 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-16, started daemon 5472)>
2019-05-24 16:03:55,476 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-05-24 16:03:55,476 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-05-24 16:03:55,477 threading.run(754) DEBUG payload is None, terminating
2019-05-24 16:03:55,477 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-05-24 16:03:55,615 seasonal_water_yield._execute(442) INFO calculate quick flow for month 11
2019-05-24 16:03:55,703 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-05-24 16:03:55,704 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-17, started daemon 10976)>
2019-05-24 16:03:55,868 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-05-24 16:03:55,868 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-05-24 16:03:55,868 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-05-24 16:03:55,869 threading.run(754) DEBUG payload is None, terminating
2019-05-24 16:03:56,009 seasonal_water_yield._execute(442) INFO calculate quick flow for month 12
2019-05-24 16:03:56,157 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-05-24 16:03:56,158 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-18, started daemon 2796)>
2019-05-24 16:03:56,601 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-05-24 16:03:56,601 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-05-24 16:03:56,601 threading.run(754) DEBUG payload is None, terminating
2019-05-24 16:03:56,601 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-05-24 16:03:56,765 seasonal_water_yield._execute(470) INFO calculate local recharge
2019-05-24 16:03:56,822 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-05-24 16:03:56,825 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-19, started daemon 2988)>
2019-05-24 16:03:56,877 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-05-24 16:03:56,878 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-05-24 16:03:56,878 threading.run(754) DEBUG payload is None, terminating
2019-05-24 16:03:56,878 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-05-24 16:04:03,072 Task._call(1068) INFO peak point detection 74.52% complete
2019-05-24 16:04:04,448 Task.add_task(636) ERROR Something went wrong when adding task calculate vri (23), 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: invalid value encountered in reduce
2019-05-24 16:04:04,450 model._logged_target(1639) ERROR Exception while executing <function execute at 0x0F6D8AB0>
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: invalid value encountered in reduce
2019-05-24 16:04:04,450 model._logged_target(1642) INFO Execution finished
2019-05-24 16:04:04,453 utils.prepare_workspace(115) INFO Elapsed time: 25.56s