2019-07-02 18:12:34,657 utils.prepare_workspace(109) INFO Writing log messages to E:\Campus notes\research\Inputs of Invest\Project\InVEST-Sediment-Delivery-Ratio-Model-(SDR)-log-2019-07-02--18_12_34.txt
2019-07-02 18:12:34,657 model._logged_target(1633) Level 100 Starting model with parameters: 
Arguments for InVEST natcap.invest.sdr 3.7.0:
biophysical_table_path      E:/Campus notes/research/Inputs of Invest/Project/biophytable42.csv
dem_path                    E:/Campus notes/research/Inputs of Invest/Project/MonaragalaSRTM302.tif
drainage_path               
erodibility_path            E:/Campus notes/research/Inputs of Invest/Project/tinraster511.tif
erosivity_path              E:/Campus notes/research/Inputs of Invest/Project/rasterRfactor11.tif
ic_0_param                  .5
k_param                     2
lulc_path                   E:/Campus notes/research/Inputs of Invest/Project/rasterlanduse1.tif
n_workers                   -1
results_suffix              
sdr_max                     .8
threshold_flow_accumulation 1000
watersheds_path             E:/Campus notes/research/Inputs of Invest/Project/shedpoly.shp
workspace_dir               E:\Campus notes\research\Inputs of Invest\Project

2019-07-02 18:12:35,282 geoprocessing.align_and_resize_raster_stack(729) INFO n_workers > 1 (2) so starting a processes pool.
2019-07-02 18:12:38,796 geoprocessing.align_and_resize_raster_stack(779) INFO 1 of 4 aligned: aligned_dem.tif
2019-07-02 18:12:38,913 geoprocessing.align_and_resize_raster_stack(779) INFO 2 of 4 aligned: aligned_lulc.tif
2019-07-02 18:12:40,190 geoprocessing.align_and_resize_raster_stack(779) INFO 3 of 4 aligned: aligned_erosivity.tif
2019-07-02 18:12:42,293 geoprocessing.align_and_resize_raster_stack(779) INFO 4 of 4 aligned: aligned_erodibility.tif
2019-07-02 18:12:42,450 geoprocessing.align_and_resize_raster_stack(788) INFO aligned all 4 rasters.
2019-07-02 18:12:48,107 Task._call(1068) INFO 45.4% complete
2019-07-02 18:12:54,079 Task._call(1068) INFO 77.9% complete
2019-07-02 18:12:58,068 Task._call(1068) INFO 100.0% complete
2019-07-02 18:13:03,288 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-07-02 18:13:03,289 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-6, started daemon 216044)>
2019-07-02 18:13:03,789 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-07-02 18:13:03,789 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-07-02 18:13:03,790 threading.run(754) DEBUG payload is None, terminating
2019-07-02 18:13:03,790 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-07-02 18:13:10,035 Task._call(1068) INFO 38.9% complete
2019-07-02 18:13:16,003 Task._call(1068) INFO 64.9% complete
2019-07-02 18:13:22,301 Task._call(1068) INFO 84.3% complete
2019-07-02 18:13:29,951 Task._call(1068) INFO 100.0% complete
2019-07-02 18:13:36,002 Task._call(1068) INFO 64.9% complete
2019-07-02 18:13:43,242 Task._call(1068) INFO 90.8% complete
2019-07-02 18:13:46,711 Task._call(1068) INFO 100.0% complete
2019-07-02 18:13:47,180 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-07-02 18:13:47,180 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-7, started daemon 215452)>
2019-07-02 18:13:52,236 geoprocessing.<lambda>(442) INFO 76.3% complete
2019-07-02 18:13:54,596 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-07-02 18:13:54,596 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-07-02 18:13:54,596 threading.run(754) DEBUG payload is None, terminating
2019-07-02 18:13:54,596 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-07-02 18:14:02,058 Task._call(1068) INFO 97.3% complete
2019-07-02 18:14:02,335 Task._call(1068) INFO filter out incomplete divergent streams
2019-07-02 18:14:02,602 Task._call(1068) INFO 100.0% complete
2019-07-02 18:14:02,757 Task.add_task(636) ERROR Something went wrong when adding task calculate W (8), 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\sdr.py", line 778, in _calculate_w
  File "Z:\opt\atlassian\pipelines\agent\build\env\lib\site-packages\pygeoprocessing\geoprocessing.py", line 1747, in reclassify_raster
KeyError: '255.0'
2019-07-02 18:14:02,759 model._logged_target(1639) ERROR Exception while executing <function execute at 0x10EF88F0>
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\sdr.py", line 294, 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\sdr.py", line 778, in _calculate_w
  File "Z:\opt\atlassian\pipelines\agent\build\env\lib\site-packages\pygeoprocessing\geoprocessing.py", line 1747, in reclassify_raster
KeyError: '255.0'
2019-07-02 18:14:02,759 model._logged_target(1642) INFO Execution finished
2019-07-02 18:14:02,759 utils.prepare_workspace(115) INFO Elapsed time: 1m 28.1s