2020-10-19 13:01:31,125 utils.prepare_workspace(111) INFO Writing log messages to D:/Colombia/Golfo_de_Morrosquillo/output_CV_tmp\InVEST-Seasonal-Water-Yield-log-2020-10-19--13_01_31.txt
2020-10-19 13:01:31,126 model._logged_target(1626) Level 100 Starting model with parameters: 
Arguments for InVEST natcap.invest.seasonal_water_yield.seasonal_water_yield 3.8.9:
alpha_m                     1/12
aoi_path                    D:/Colombia/Golfo_de_Morrosquillo/input_data_SWY/SWY_GdMinputsJG19Oct2020/CuencasMorrosquillo.shp
beta_i                      1
biophysical_table_path      D:/Colombia/Golfo_de_Morrosquillo/input_data_SWY/SWY_GdMinputsJG19Oct2020/biophysGdMno128.csv
climate_zone_raster_path    D:/Colombia/Golfo_de_Morrosquillo/input_data_SWY/SWY_GdMinputsJG19Oct2020/climateZones3.tif
climate_zone_table_path     D:/Colombia/Golfo_de_Morrosquillo/input_data_SWY/SWY_GdMinputsJG19Oct2020/climateZoneTableGdMdays_mo.csv
dem_raster_path             D:/Colombia/Golfo_de_Morrosquillo/input_data_SWY/SWY_GdMinputsJG19Oct2020/DEM_Morrosquillo.tif
et0_dir                     D:/Colombia/Golfo_de_Morrosquillo/input_data_SWY/SWY_GdMinputsJG19Oct2020/ETclean
gamma                       1
lulc_raster_path            D:/Colombia/Golfo_de_Morrosquillo/input_data_SWY/SWY_GdMinputsJG19Oct2020/LULC_Cobertura_2012_GdM_nocanal.tif
monthly_alpha               False
n_workers                   -1
precip_dir                  D:/Colombia/Golfo_de_Morrosquillo/input_data_SWY/SWY_GdMinputsJG19Oct2020/PrecipClean
rain_events_table_path      
results_suffix              t1
soil_group_path             D:/Colombia/Golfo_de_Morrosquillo/input_data_SWY/SWY_GdMinputsJG19Oct2020/SoilGdM34_3.tif
threshold_flow_accumulation 1000
user_defined_climate_zones  True
user_defined_local_recharge False
workspace_dir               D:/Colombia/Golfo_de_Morrosquillo/output_CV_tmp

2020-10-19 13:01:31,172 seasonal_water_yield._execute(391) INFO prepare and test inputs for common errors
2020-10-19 13:01:31,276 seasonal_water_yield._execute(457) INFO Building file registry
2020-10-19 13:01:31,277 seasonal_water_yield._execute(463) INFO Checking that the AOI is not the output aggregate vector
2020-10-19 13:01:31,277 seasonal_water_yield._execute(472) INFO Aligning and clipping dataset list
2020-10-19 13:01:33,159 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 28 aligned: prcp_a0_t1.tif
2020-10-19 13:01:34,845 geoprocessing.align_and_resize_raster_stack(752) INFO 2 of 28 aligned: prcp_a1_t1.tif
2020-10-19 13:01:36,534 geoprocessing.align_and_resize_raster_stack(752) INFO 3 of 28 aligned: prcp_a2_t1.tif
2020-10-19 13:01:38,275 geoprocessing.align_and_resize_raster_stack(752) INFO 4 of 28 aligned: prcp_a3_t1.tif
2020-10-19 13:01:39,910 geoprocessing.align_and_resize_raster_stack(752) INFO 5 of 28 aligned: prcp_a4_t1.tif
2020-10-19 13:01:41,567 geoprocessing.align_and_resize_raster_stack(752) INFO 6 of 28 aligned: prcp_a5_t1.tif
2020-10-19 13:01:43,218 geoprocessing.align_and_resize_raster_stack(752) INFO 7 of 28 aligned: prcp_a6_t1.tif
2020-10-19 13:01:44,880 geoprocessing.align_and_resize_raster_stack(752) INFO 8 of 28 aligned: prcp_a7_t1.tif
2020-10-19 13:01:46,528 geoprocessing.align_and_resize_raster_stack(752) INFO 9 of 28 aligned: prcp_a8_t1.tif
2020-10-19 13:01:48,265 geoprocessing.align_and_resize_raster_stack(752) INFO 10 of 28 aligned: prcp_a9_t1.tif
2020-10-19 13:01:49,908 geoprocessing.align_and_resize_raster_stack(752) INFO 11 of 28 aligned: prcp_a10_t1.tif
2020-10-19 13:01:51,586 geoprocessing.align_and_resize_raster_stack(752) INFO 12 of 28 aligned: prcp_a11_t1.tif
2020-10-19 13:01:53,107 geoprocessing.align_and_resize_raster_stack(752) INFO 13 of 28 aligned: soil_group_aligned_t1.tif
2020-10-19 13:01:54,711 geoprocessing.align_and_resize_raster_stack(752) INFO 14 of 28 aligned: et0_a0_t1.tif
2020-10-19 13:01:56,300 geoprocessing.align_and_resize_raster_stack(752) INFO 15 of 28 aligned: et0_a1_t1.tif
2020-10-19 13:01:57,893 geoprocessing.align_and_resize_raster_stack(752) INFO 16 of 28 aligned: et0_a2_t1.tif
2020-10-19 13:01:59,474 geoprocessing.align_and_resize_raster_stack(752) INFO 17 of 28 aligned: et0_a3_t1.tif
2020-10-19 13:02:01,051 geoprocessing.align_and_resize_raster_stack(752) INFO 18 of 28 aligned: et0_a4_t1.tif
2020-10-19 13:02:02,630 geoprocessing.align_and_resize_raster_stack(752) INFO 19 of 28 aligned: et0_a5_t1.tif
2020-10-19 13:02:04,207 geoprocessing.align_and_resize_raster_stack(752) INFO 20 of 28 aligned: et0_a6_t1.tif
2020-10-19 13:02:05,790 geoprocessing.align_and_resize_raster_stack(752) INFO 21 of 28 aligned: et0_a7_t1.tif
2020-10-19 13:02:07,382 geoprocessing.align_and_resize_raster_stack(752) INFO 22 of 28 aligned: et0_a8_t1.tif
2020-10-19 13:02:08,957 geoprocessing.align_and_resize_raster_stack(752) INFO 23 of 28 aligned: et0_a9_t1.tif
2020-10-19 13:02:10,521 geoprocessing.align_and_resize_raster_stack(752) INFO 24 of 28 aligned: et0_a10_t1.tif
2020-10-19 13:02:12,108 geoprocessing.align_and_resize_raster_stack(752) INFO 25 of 28 aligned: et0_a11_t1.tif
2020-10-19 13:02:15,638 geoprocessing.align_and_resize_raster_stack(752) INFO 26 of 28 aligned: lulc_aligned_t1.tif
2020-10-19 13:02:19,658 geoprocessing.align_and_resize_raster_stack(752) INFO 27 of 28 aligned: dem_aligned_t1.tif
2020-10-19 13:02:21,260 geoprocessing.align_and_resize_raster_stack(752) INFO 28 of 28 aligned: cz_aligned_t1.tif
2020-10-19 13:02:21,260 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 28 rasters.
2020-10-19 13:02:27,000 Task._call(1174) INFO 2.0% complete
2020-10-19 13:02:33,013 Task._call(1174) INFO 30.5% complete
2020-10-19 13:02:39,018 Task._call(1174) INFO 46.7% complete
2020-10-19 13:02:45,674 Task._call(1174) INFO 56.9% complete
2020-10-19 13:02:51,034 Task._call(1174) INFO 73.1% complete
2020-10-19 13:02:57,018 Task._call(1174) INFO 100.0% complete
2020-10-19 13:03:03,120 Task._call(1174) INFO 0.0% complete
2020-10-19 13:03:09,011 Task._call(1174) INFO 30.5% complete
2020-10-19 13:03:15,003 Task._call(1174) INFO 46.7% complete
2020-10-19 13:03:21,048 Task._call(1174) INFO 58.9% complete
2020-10-19 13:03:27,003 Task._call(1174) INFO 77.2% complete
2020-10-19 13:03:32,162 Task._call(1174) INFO 100.0% complete
2020-10-19 13:03:32,190 Task._call(1174) DEBUG creating target flow accum raster layer
2020-10-19 13:03:35,157 Task._call(1174) DEBUG creating visited raster layer
2020-10-19 13:03:35,170 Task._call(1174) DEBUG starting search
2020-10-19 13:03:38,000 Task._call(1174) INFO mfd flow accum 4.4% complete
2020-10-19 13:03:44,000 Task._call(1174) INFO mfd flow accum 10.4% complete
2020-10-19 13:03:50,002 Task._call(1174) INFO mfd flow accum 17.4% complete
2020-10-19 13:03:56,011 Task._call(1174) INFO mfd flow accum 24.0% complete
2020-10-19 13:04:02,015 Task._call(1174) INFO mfd flow accum 30.0% complete
2020-10-19 13:04:08,000 Task._call(1174) INFO mfd flow accum 35.2% complete
2020-10-19 13:04:10,728 Task._call(1174) INFO 100.0% complete
2020-10-19 13:04:21,455 Task._call(1174) INFO 32.5% complete
2020-10-19 13:04:27,026 Task._call(1174) INFO 58.9% complete
2020-10-19 13:04:32,309 Task._call(1174) INFO filter out incomplete divergent streams
2020-10-19 13:04:32,776 Task._call(1174) INFO 100.0% complete
2020-10-19 13:04:32,800 seasonal_water_yield._execute(574) INFO quick flow
2020-10-19 13:04:32,801 seasonal_water_yield._execute(588) INFO loading number of monthly events
2020-10-19 13:04:32,817 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-10-19 13:04:32,819 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-1021, started daemon 36744)>
2020-10-19 13:04:32,835 geoprocessing.raster_calculator(486) INFO Waiting for raster stats worker result.
2020-10-19 13:04:32,835 threading.run(870) DEBUG payload is None, terminating
2020-10-19 13:04:32,835 threading.run(870) WARNING No valid pixels were received, sending None.
2020-10-19 13:04:32,836 Task.add_task(731) ERROR Something went wrong when adding task n_events for month 0 (5), terminating taskgraph.
Traceback (most recent call last):
  File "site-packages\taskgraph\Task.py", line 697, in add_task
  File "site-packages\taskgraph\Task.py", line 1174, in _call
  File "site-packages\pygeoprocessing\geoprocessing.py", line 1763, in reclassify_raster
  File "site-packages\pygeoprocessing\geoprocessing.py", line 424, in raster_calculator
  File "site-packages\pygeoprocessing\geoprocessing.py", line 1756, in _map_dataset_to_value_op
ValueError: The following 1 raster values [128] from "D:/Colombia/Golfo_de_Morrosquillo/output_CV_tmp\cache_dir\cz_aligned_t1.tif" do not have corresponding entries in the ``value_map``: {11: 10.1, 12: 10.0, 14: 3.7}
2020-10-19 13:04:32,837 model._logged_target(1632) ERROR Exception while executing <function execute at 0x26244588>
Traceback (most recent call last):
  File "site-packages\natcap\invest\ui\model.py", line 1629, in _logged_target
  File "site-packages\natcap\invest\seasonal_water_yield\seasonal_water_yield.py", line 376, in execute
  File "site-packages\natcap\invest\seasonal_water_yield\seasonal_water_yield.py", line 611, in _execute
  File "site-packages\taskgraph\Task.py", line 697, in add_task
  File "site-packages\taskgraph\Task.py", line 1174, in _call
  File "site-packages\pygeoprocessing\geoprocessing.py", line 1763, in reclassify_raster
  File "site-packages\pygeoprocessing\geoprocessing.py", line 424, in raster_calculator
  File "site-packages\pygeoprocessing\geoprocessing.py", line 1756, in _map_dataset_to_value_op
ValueError: The following 1 raster values [128] from "D:/Colombia/Golfo_de_Morrosquillo/output_CV_tmp\cache_dir\cz_aligned_t1.tif" do not have corresponding entries in the ``value_map``: {11: 10.1, 12: 10.0, 14: 3.7}
2020-10-19 13:04:32,838 model._logged_target(1635) INFO Execution finished
2020-10-19 13:04:32,841 utils.prepare_workspace(117) INFO Elapsed time: 3m 1.710000000000008s