2019-11-09 10:32:58,296 utils.prepare_workspace(115) INFO Writing log messages to D:\Data\NDR_Africa\output\InVEST-Nutrient-Delivery-Ratio-Model-(NDR)-log-2019-11-09--10_32_58.txt
2019-11-09 10:32:58,296 model._logged_target(1653) Level 100 Starting model with parameters: 
Arguments for InVEST natcap.invest.ndr.ndr 3.7.0.post366+hc94dfedffddd:
biophysical_table_path       D:/Data/NDR_Africa/input/biophysical_table_ndr_africa.csv
calc_n                       True
calc_p                       True
dem_path                     D:/Data/SDR_Africa/input/af_STRMGL1_30m_100_Africa_clip_pro_300m_fill.tif
k_param                      2
lulc_path                    D:/Data/SDR_Africa/input/af_lc_w_roads_prj_300m.tif
n_workers                    -1
results_suffix               _baseline_2015_300m
runoff_proxy_path            D:/Data/NDR_Africa/input/af_c0_bio_30s_12_Clip_prj_nutrient_runoff_proxy_300m.tif
subsurface_critical_length_n 150
subsurface_critical_length_p 150
subsurface_eff_n             0.8
subsurface_eff_p             0.8
threshold_flow_accumulation  1000
watersheds_path              D:/Data/SDR_Africa/input/hybas_af_lev04_lev05_report_prj.shp
workspace_dir                D:\Data\NDR_Africa\output

2019-11-09 10:33:01,627 geoprocessing.raster_calculator(367) INFO starting stats_worker
2019-11-09 10:33:01,627 geoprocessing.raster_calculator(373) INFO started stats_worker <Thread(Thread-2, started daemon 2736)>
2019-11-09 10:33:06,642 geoprocessing.<lambda>(443) INFO 62.7% complete
2019-11-09 10:33:10,313 geoprocessing.raster_calculator(446) INFO 100.0%% complete
2019-11-09 10:33:10,313 geoprocessing.raster_calculator(449) INFO signaling stats worker to terminate
2019-11-09 10:33:10,313 geoprocessing.raster_calculator(451) INFO Waiting for raster stats worker result.
2019-11-09 10:34:10,519 geoprocessing.raster_calculator(478) INFO Waiting for raster stats worker result.
2019-11-09 10:35:00,348 threading.run(754) DEBUG payload is None, terminating
2019-11-09 10:35:00,364 Task.add_task(636) ERROR Something went wrong when adding task runoff proxy mean (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\ndr\ndr.py", line 730, in _normalize_raster
  File "Z:\opt\atlassian\pipelines\agent\build\env\lib\site-packages\pygeoprocessing\geoprocessing.py", line 454, in raster_calculator
RuntimeError: stats_worker_thread.join() timed out
2019-11-09 10:35:00,364 model._logged_target(1659) ERROR Exception while executing <function execute at 0x1C263170>
Traceback (most recent call last):
  File "Z:\opt\atlassian\pipelines\agent\build\env\lib\site-packages\natcap\invest\ui\model.py", line 1656, in _logged_target
  File "Z:\opt\atlassian\pipelines\agent\build\env\lib\site-packages\natcap\invest\ndr\ndr.py", line 297, 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\ndr\ndr.py", line 730, in _normalize_raster
  File "Z:\opt\atlassian\pipelines\agent\build\env\lib\site-packages\pygeoprocessing\geoprocessing.py", line 454, in raster_calculator
RuntimeError: stats_worker_thread.join() timed out
2019-11-09 10:35:00,364 model._logged_target(1662) INFO Execution finished
2019-11-09 10:35:00,364 utils.prepare_workspace(121) INFO Elapsed time: 2m 2.07s