2019-06-17 13:17:06,000 utils.prepare_workspace(109) INFO Writing log messages to C:\Users\kmuha\Desktop\PhDWork\InVEST_TEST\Outputs\InVEST-Seasonal-Water-Yield-log-2019-06-17--13_17_05.txt
2019-06-17 13:17:06,003 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/kmuha/Desktop/PhDWork/NHD_H_03140201_HU8_Shape/Shape/WBDHU10.shp
beta_i                      1
biophysical_table_path      C:/Users/kmuha/Desktop/PhDWork/InVEST_TEST/testbiophysical_table.csv
dem_raster_path             C:/Users/kmuha/Desktop/PhDWork/InVEST_TEST/DEM/DEM.tif
et0_dir                     C:\Users\kmuha\Desktop\PhDWork\InVEST_TEST\ET0Directory
gamma                       1
lulc_raster_path            C:/Users/kmuha/Desktop/PhDWork/InVEST_TEST/DEM/landcover.tif
monthly_alpha               False
n_workers                   -1
precip_dir                  C:\Users\kmuha\Desktop\PhDWork\InVEST_TEST\Precipitation Directory
rain_events_table_path      C:/InVEST_3.6.0_x86/seasonal_water_yield/rain_events_table.csv
results_suffix              4
soil_group_path             C:/Users/kmuha/Desktop/PhDWork/InVEST_TEST/Soil/Soil2.tif
threshold_flow_accumulation 7878
user_defined_climate_zones  False
user_defined_local_recharge False
workspace_dir               C:\Users\kmuha\Desktop\PhDWork\InVEST_TEST\Outputs

2019-06-17 13:17:06,394 seasonal_water_yield._execute(184) INFO prepare and test inputs for common errors
2019-06-17 13:17:07,549 seasonal_water_yield._execute(246) INFO Building file registry
2019-06-17 13:17:07,552 seasonal_water_yield._execute(252) INFO Checking that the AOI is not the output aggregate vector
2019-06-17 13:17:07,553 seasonal_water_yield._execute(261) INFO Aligning and clipping dataset list
2019-06-17 13:17:07,611 geoprocessing.align_and_resize_raster_stack(729) INFO n_workers > 1 (2) so starting a processes pool.
2019-06-17 13:17:09,461 geoprocessing.align_and_resize_raster_stack(779) INFO 1 of 27 aligned: prcp_a0_4.tif
2019-06-17 13:17:10,305 geoprocessing.align_and_resize_raster_stack(779) INFO 2 of 27 aligned: prcp_a1_4.tif
2019-06-17 13:17:10,388 geoprocessing.align_and_resize_raster_stack(779) INFO 3 of 27 aligned: prcp_a2_4.tif
2019-06-17 13:17:11,224 geoprocessing.align_and_resize_raster_stack(779) INFO 4 of 27 aligned: prcp_a3_4.tif
2019-06-17 13:17:11,292 geoprocessing.align_and_resize_raster_stack(779) INFO 5 of 27 aligned: prcp_a4_4.tif
2019-06-17 13:17:12,331 geoprocessing.align_and_resize_raster_stack(779) INFO 6 of 27 aligned: prcp_a5_4.tif
2019-06-17 13:17:12,388 geoprocessing.align_and_resize_raster_stack(779) INFO 7 of 27 aligned: prcp_a6_4.tif
2019-06-17 13:17:13,305 geoprocessing.align_and_resize_raster_stack(779) INFO 8 of 27 aligned: prcp_a7_4.tif
2019-06-17 13:17:13,305 geoprocessing.align_and_resize_raster_stack(779) INFO 9 of 27 aligned: prcp_a8_4.tif
2019-06-17 13:17:14,283 geoprocessing.align_and_resize_raster_stack(779) INFO 10 of 27 aligned: prcp_a9_4.tif
2019-06-17 13:17:14,305 geoprocessing.align_and_resize_raster_stack(779) INFO 11 of 27 aligned: prcp_a10_4.tif
2019-06-17 13:17:15,292 geoprocessing.align_and_resize_raster_stack(779) INFO 12 of 27 aligned: prcp_a11_4.tif
2019-06-17 13:17:16,849 geoprocessing.align_and_resize_raster_stack(779) INFO 13 of 27 aligned: soil_group_aligned_4.tif
2019-06-17 13:17:16,849 geoprocessing.align_and_resize_raster_stack(779) INFO 14 of 27 aligned: et0_a0_4.tif
2019-06-17 13:17:17,131 geoprocessing.align_and_resize_raster_stack(779) INFO 15 of 27 aligned: et0_a1_4.tif
2019-06-17 13:17:17,815 geoprocessing.align_and_resize_raster_stack(779) INFO 16 of 27 aligned: et0_a2_4.tif
2019-06-17 13:17:18,046 geoprocessing.align_and_resize_raster_stack(779) INFO 17 of 27 aligned: et0_a3_4.tif
2019-06-17 13:17:18,723 geoprocessing.align_and_resize_raster_stack(779) INFO 18 of 27 aligned: et0_a4_4.tif
2019-06-17 13:17:19,107 geoprocessing.align_and_resize_raster_stack(779) INFO 19 of 27 aligned: et0_a5_4.tif
2019-06-17 13:17:19,604 geoprocessing.align_and_resize_raster_stack(779) INFO 20 of 27 aligned: et0_a6_4.tif
2019-06-17 13:17:20,111 geoprocessing.align_and_resize_raster_stack(779) INFO 21 of 27 aligned: et0_a7_4.tif
2019-06-17 13:17:20,532 geoprocessing.align_and_resize_raster_stack(779) INFO 22 of 27 aligned: et0_a8_4.tif
2019-06-17 13:17:21,132 geoprocessing.align_and_resize_raster_stack(779) INFO 23 of 27 aligned: et0_a9_4.tif
2019-06-17 13:17:21,500 geoprocessing.align_and_resize_raster_stack(779) INFO 24 of 27 aligned: et0_a10_4.tif
2019-06-17 13:17:22,029 geoprocessing.align_and_resize_raster_stack(779) INFO 25 of 27 aligned: et0_a11_4.tif
2019-06-17 13:17:23,275 geoprocessing.align_and_resize_raster_stack(779) INFO 26 of 27 aligned: lulc_aligned_4.tif
2019-06-17 13:17:29,904 geoprocessing.align_and_resize_raster_stack(779) INFO 27 of 27 aligned: dem_aligned_4.tif
2019-06-17 13:17:30,096 geoprocessing.align_and_resize_raster_stack(788) INFO aligned all 27 rasters.
2019-06-17 13:17:38,519 Task._call(1068) INFO 0.0% complete
2019-06-17 13:17:44,188 Task._call(1068) INFO 0.0% complete
2019-06-17 13:17:50,183 Task._call(1068) INFO 8.3% complete
2019-06-17 13:17:56,010 Task._call(1068) INFO 24.8% complete
2019-06-17 13:18:02,096 Task._call(1068) INFO 33.1% complete
2019-06-17 13:18:08,407 Task._call(1068) INFO 41.3% complete
2019-06-17 13:18:14,716 Task._call(1068) INFO 49.6% complete
2019-06-17 13:18:20,506 Task._call(1068) INFO 49.6% complete
2019-06-17 13:18:26,621 Task._call(1068) INFO 57.9% complete
2019-06-17 13:18:32,204 Task._call(1068) INFO 66.1% complete
2019-06-17 13:18:38,346 Task._call(1068) INFO 74.4% complete
2019-06-17 13:18:44,588 Task._call(1068) INFO 74.4% complete
2019-06-17 13:18:50,601 Task._call(1068) INFO 82.7% complete
2019-06-17 13:18:56,122 Task._call(1068) INFO 90.9% complete
2019-06-17 13:19:01,934 Task._call(1068) INFO 100.0% complete
2019-06-17 13:19:10,125 Task._call(1068) INFO 8.3% complete
2019-06-17 13:19:16,174 Task._call(1068) INFO 33.1% complete
2019-06-17 13:19:22,542 Task._call(1068) INFO 49.6% complete
2019-06-17 13:19:28,244 Task._call(1068) INFO 57.9% complete
2019-06-17 13:19:34,374 Task._call(1068) INFO 66.1% complete
2019-06-17 13:19:40,339 Task._call(1068) INFO 82.6% complete
2019-06-17 13:19:46,404 Task._call(1068) INFO 90.9% complete
2019-06-17 13:19:50,907 Task._call(1068) INFO 100.0% complete
2019-06-17 13:19:57,338 Task._call(1068) INFO 49.6% complete
2019-06-17 13:20:16,595 Task._call(1068) INFO 99.2% complete
2019-06-17 13:20:23,426 Task._call(1068) INFO 100.0% complete
2019-06-17 13:20:30,003 Task._call(1068) INFO 66.1% complete
2019-06-17 13:20:35,243 Task._call(1068) INFO filter out incomplete divergent streams
2019-06-17 13:20:35,448 Task._call(1068) INFO 100.0% complete
2019-06-17 13:20:35,667 seasonal_water_yield._execute(363) INFO quick flow
2019-06-17 13:20:35,667 seasonal_water_yield._execute(377) INFO loading number of monthly events
2019-06-17 13:20:42,829 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-06-17 13:20:42,839 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-5, started daemon 15504)>
2019-06-17 13:20:42,944 threading.run(754) DEBUG payload is None, terminating
2019-06-17 13:20:42,944 threading.run(754) WARNING No valid pixels were received, sending None.
2019-06-17 13:20:42,944 geoprocessing.raster_calculator(477) INFO Waiting for raster stats worker result.
2019-06-17 13:20:42,947 Task.add_task(636) ERROR Something went wrong when adding task calculate curve number (9), 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: 331298
2019-06-17 13:20:42,951 model._logged_target(1639) ERROR Exception while executing <function execute at 0x0FD55AB0>
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: 331298
2019-06-17 13:20:42,951 model._logged_target(1642) INFO Execution finished
2019-06-17 13:20:42,957 utils.prepare_workspace(115) INFO Elapsed time: 3m 36.95s