2019-09-30 13:28:26,691 utils.prepare_workspace(109) INFO Writing log messages to C:\InVEST_3.5.0_x86\test\InVEST-Seasonal-Water-Yield-log-2019-09-30--13_28_26.txt
2019-09-30 13:28:26,691 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_losrios/AOI/Losrios.shp
beta_i                      1
biophysical_table_path      H:/SIG/Procesos SIG/Invest_water/Test_losrios/tabla_biof_losrios.csv
dem_raster_path             H:/SIG/Procesos SIG/Invest_water/Test_losrios/DEM/DEM_losrios.tif
et0_dir                     H:\SIG\Procesos SIG\Invest_water\Test_losrios\ET0\ET
gamma                       1
lulc_raster_path            H:/SIG/Procesos SIG/Invest_water/Test_losrios/landuse/LU_losrios4.tif
monthly_alpha               True
monthly_alpha_path          H:/SIG/Procesos SIG/Invest_water/Test_losrios/monthly_alpha_losrios.csv
n_workers                   -1
precip_dir                  H:\SIG\Procesos SIG\Invest_water\Test_losrios\Precipitation_mensual\pp
rain_events_table_path      H:/SIG/Procesos SIG/Invest_water/Test_losrios/rain_events_losrios.csv
results_suffix              1
soil_group_path             H:/SIG/Procesos SIG/Invest_water/Test_losrios/Soil group/gh_losrios.tif
threshold_flow_accumulation 100
user_defined_climate_zones  False
user_defined_local_recharge False
workspace_dir               C:\InVEST_3.5.0_x86\test

2019-09-30 13:28:26,736 seasonal_water_yield._execute(184) INFO prepare and test inputs for common errors
2019-09-30 13:28:27,184 seasonal_water_yield._execute(246) INFO Building file registry
2019-09-30 13:28:27,184 seasonal_water_yield._execute(252) INFO Checking that the AOI is not the output aggregate vector
2019-09-30 13:28:27,184 seasonal_water_yield._execute(261) INFO Aligning and clipping dataset list
2019-09-30 13:28:27,565 geoprocessing.align_and_resize_raster_stack(729) INFO n_workers > 1 (2) so starting a processes pool.
2019-09-30 13:28:32,999 geoprocessing.align_and_resize_raster_stack(779) INFO 1 of 27 aligned: prcp_a0_1.tif
2019-09-30 13:28:33,759 geoprocessing.align_and_resize_raster_stack(779) INFO 2 of 27 aligned: prcp_a1_1.tif
2019-09-30 13:28:37,007 geoprocessing.align_and_resize_raster_stack(779) INFO 3 of 27 aligned: prcp_a2_1.tif
2019-09-30 13:28:37,782 geoprocessing.align_and_resize_raster_stack(779) INFO 4 of 27 aligned: prcp_a3_1.tif
2019-09-30 13:28:41,046 geoprocessing.align_and_resize_raster_stack(779) INFO 5 of 27 aligned: prcp_a4_1.tif
2019-09-30 13:28:41,805 geoprocessing.align_and_resize_raster_stack(779) INFO 6 of 27 aligned: prcp_a5_1.tif
2019-09-30 13:28:45,229 geoprocessing.align_and_resize_raster_stack(779) INFO 7 of 27 aligned: prcp_a6_1.tif
2019-09-30 13:28:46,052 geoprocessing.align_and_resize_raster_stack(779) INFO 8 of 27 aligned: prcp_a7_1.tif
2019-09-30 13:28:49,460 geoprocessing.align_and_resize_raster_stack(779) INFO 9 of 27 aligned: prcp_a8_1.tif
2019-09-30 13:28:50,229 geoprocessing.align_and_resize_raster_stack(779) INFO 10 of 27 aligned: prcp_a9_1.tif
2019-09-30 13:28:53,627 geoprocessing.align_and_resize_raster_stack(779) INFO 11 of 27 aligned: prcp_a10_1.tif
2019-09-30 13:28:54,423 geoprocessing.align_and_resize_raster_stack(779) INFO 12 of 27 aligned: prcp_a11_1.tif
2019-09-30 13:28:56,105 geoprocessing.align_and_resize_raster_stack(779) INFO 13 of 27 aligned: soil_group_aligned_1.tif
2019-09-30 13:28:58,239 geoprocessing.align_and_resize_raster_stack(779) INFO 14 of 27 aligned: et0_a0_1.tif
2019-09-30 13:29:00,005 geoprocessing.align_and_resize_raster_stack(779) INFO 15 of 27 aligned: et0_a1_1.tif
2019-09-30 13:29:02,227 geoprocessing.align_and_resize_raster_stack(779) INFO 16 of 27 aligned: et0_a2_1.tif
2019-09-30 13:29:03,956 geoprocessing.align_and_resize_raster_stack(779) INFO 17 of 27 aligned: et0_a3_1.tif
2019-09-30 13:29:06,217 geoprocessing.align_and_resize_raster_stack(779) INFO 18 of 27 aligned: et0_a4_1.tif
2019-09-30 13:29:07,969 geoprocessing.align_and_resize_raster_stack(779) INFO 19 of 27 aligned: et0_a5_1.tif
2019-09-30 13:29:10,246 geoprocessing.align_and_resize_raster_stack(779) INFO 20 of 27 aligned: et0_a6_1.tif
2019-09-30 13:29:11,890 geoprocessing.align_and_resize_raster_stack(779) INFO 21 of 27 aligned: et0_a7_1.tif
2019-09-30 13:29:14,177 geoprocessing.align_and_resize_raster_stack(779) INFO 22 of 27 aligned: et0_a8_1.tif
2019-09-30 13:29:15,831 geoprocessing.align_and_resize_raster_stack(779) INFO 23 of 27 aligned: et0_a9_1.tif
2019-09-30 13:29:18,177 geoprocessing.align_and_resize_raster_stack(779) INFO 24 of 27 aligned: et0_a10_1.tif
2019-09-30 13:29:19,763 geoprocessing.align_and_resize_raster_stack(779) INFO 25 of 27 aligned: et0_a11_1.tif
2019-09-30 13:29:24,869 geoprocessing.align_and_resize_raster_stack(779) INFO 26 of 27 aligned: lulc_aligned_1.tif
2019-09-30 13:29:28,174 geoprocessing.align_and_resize_raster_stack(779) INFO 27 of 27 aligned: dem_aligned_1.tif
2019-09-30 13:29:28,221 geoprocessing.align_and_resize_raster_stack(788) INFO aligned all 27 rasters.
2019-09-30 13:29:35,520 Task._call(1068) INFO 0.0% complete
2019-09-30 13:29:41,115 Task._call(1068) INFO 15.3% complete
2019-09-30 13:29:47,007 Task._call(1068) INFO 20.4% complete
2019-09-30 13:29:53,615 Task._call(1068) INFO 25.6% complete
2019-09-30 13:29:59,026 Task._call(1068) INFO 25.6% complete
2019-09-30 13:30:05,020 Task._call(1068) INFO 30.7% complete
2019-09-30 13:30:11,276 Task._call(1068) INFO 35.8% complete
2019-09-30 13:30:18,809 Task._call(1068) INFO 35.8% complete
2019-09-30 13:30:24,141 Task._call(1068) INFO 40.9% complete
2019-09-30 13:30:30,259 Task._call(1068) INFO 40.9% complete
2019-09-30 13:30:36,069 Task._call(1068) INFO 46.0% complete
2019-09-30 13:30:42,267 Task._call(1068) INFO 51.1% complete
2019-09-30 13:30:48,226 Task._call(1068) INFO 56.2% complete
2019-09-30 13:30:54,388 Task._call(1068) INFO 56.2% complete
2019-09-30 13:31:00,217 Task._call(1068) INFO 61.3% complete
2019-09-30 13:31:06,878 Task._call(1068) INFO 61.3% complete
2019-09-30 13:31:12,161 Task._call(1068) INFO 66.4% complete
2019-09-30 13:31:18,249 Task._call(1068) INFO 66.4% complete
2019-09-30 13:31:24,385 Task._call(1068) INFO 71.5% complete
2019-09-30 13:31:32,089 Task._call(1068) INFO 71.5% complete
2019-09-30 13:31:58,737 Task._call(1068) INFO 71.6% complete
2019-09-30 13:32:04,055 Task._call(1068) INFO 76.7% complete
2019-09-30 13:32:10,122 Task._call(1068) INFO 86.9% complete
2019-09-30 13:32:15,996 Task._call(1068) INFO 100.0% complete
2019-09-30 13:32:23,334 Task._call(1068) INFO 0.0% complete
2019-09-30 13:32:29,096 Task._call(1068) INFO 15.3% complete
2019-09-30 13:32:35,036 Task._call(1068) INFO 20.5% complete
2019-09-30 13:32:41,049 Task._call(1068) INFO 25.6% complete
2019-09-30 13:32:47,210 Task._call(1068) INFO 30.7% complete
2019-09-30 13:32:53,167 Task._call(1068) INFO 35.8% complete
2019-09-30 13:32:59,130 Task._call(1068) INFO 40.9% complete
2019-09-30 13:33:05,049 Task._call(1068) INFO 46.0% complete
2019-09-30 13:33:11,119 Task._call(1068) INFO 46.0% complete
2019-09-30 13:33:17,253 Task._call(1068) INFO 51.1% complete
2019-09-30 13:33:23,312 Task._call(1068) INFO 56.2% complete
2019-09-30 13:33:30,401 Task._call(1068) INFO 56.2% complete
2019-09-30 13:33:36,102 Task._call(1068) INFO 61.3% complete
2019-09-30 13:33:42,039 Task._call(1068) INFO 66.4% complete
2019-09-30 13:33:48,200 Task._call(1068) INFO 71.5% complete
2019-09-30 13:33:54,023 Task._call(1068) INFO 81.8% complete
2019-09-30 13:34:00,392 Task._call(1068) INFO 92.0% complete
2019-09-30 13:34:04,951 Task._call(1068) INFO 100.0% complete
2019-09-30 13:34:10,163 Task._call(1068) INFO 15.3% complete
2019-09-30 13:34:38,069 Task._call(1068) INFO 40.9% complete
2019-09-30 13:35:01,457 Task._call(1068) INFO 71.5% complete
2019-09-30 13:35:07,115 Task._call(1068) INFO 92.0% complete
2019-09-30 13:35:10,680 Task._call(1068) INFO 100.0% complete
2019-09-30 13:35:18,006 Task._call(1068) INFO 30.7% complete
2019-09-30 13:35:29,091 Task._call(1068) INFO 40.9% complete
2019-09-30 13:35:35,007 Task._call(1068) INFO 61.3% complete
2019-09-30 13:35:42,911 Task._call(1068) INFO 71.5% complete
2019-09-30 13:35:47,631 Task._call(1068) INFO filter out incomplete divergent streams
2019-09-30 13:35:48,812 Task._call(1068) INFO 100.0% complete
2019-09-30 13:35:48,845 seasonal_water_yield._execute(363) INFO quick flow
2019-09-30 13:35:48,845 seasonal_water_yield._execute(377) INFO loading number of monthly events
2019-09-30 13:36:09,250 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-09-30 13:36:09,250 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-7, started daemon 9256)>
2019-09-30 13:36:09,453 geoprocessing.raster_calculator(477) INFO Waiting for raster stats worker result.
2019-09-30 13:36:09,453 threading.run(754) DEBUG payload is None, terminating
2019-09-30 13:36:09,471 Task.add_task(636) ERROR Something went wrong when adding task calculate curve number (17), 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 818, in _calculate_curve_number_raster
  File "Z:\opt\atlassian\pipelines\agent\build\env\lib\site-packages\pygeoprocessing\geoprocessing.py", line 415, in raster_calculator
  File "Z:\opt\atlassian\pipelines\agent\build\env\lib\site-packages\natcap\invest\seasonal_water_yield\seasonal_water_yield.py", line 808, in cn_op
KeyError: 34
2019-09-30 13:36:09,473 model._logged_target(1639) ERROR Exception while executing <function execute at 0x10F09AF0>
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 429, 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 818, in _calculate_curve_number_raster
  File "Z:\opt\atlassian\pipelines\agent\build\env\lib\site-packages\pygeoprocessing\geoprocessing.py", line 415, in raster_calculator
  File "Z:\opt\atlassian\pipelines\agent\build\env\lib\site-packages\natcap\invest\seasonal_water_yield\seasonal_water_yield.py", line 808, in cn_op
KeyError: 34
2019-09-30 13:36:09,473 model._logged_target(1642) INFO Execution finished
2019-09-30 13:36:09,473 utils.prepare_workspace(115) INFO Elapsed time: 7m 42.78s