2021-11-25 12:39:34,081 utils.prepare_workspace(123) INFO Writing log messages to C:/Users/tershya/OneDrive - Western Washington University/Research/NDR Workspace/Model 2/Output\InVEST-Nutrient-Delivery-Ratio-Model-(NDR)-log-2021-11-25--12_39_34.txt
2021-11-25 12:39:34,082 model._logged_target(1646) Level 100 Starting model with parameters: 
Arguments for InVEST natcap.invest.ndr.ndr 3.9.0:
biophysical_table_path       C:/Users/tershya/OneDrive - Western Washington University/Research/NDR Workspace/Model 2/NDR Model Parameters - Model 2.csv
calc_n                       True
calc_p                       False
dem_path                     C:/Users/tershya/OneDrive - Western Washington University/Research/NDR Workspace/Model 2/DEM_AGREE.tif
k_param                      2
lulc_path                    C:/Users/tershya/OneDrive - Western Washington University/Research/NDR Workspace/Model 2/M2_clipped_1.tif
n_workers                    -1
results_suffix               M2
runoff_proxy_path            C:/Users/tershya/OneDrive - Western Washington University/Research/NDR Workspace/Model 2/rainfall_2015_gridMET_mm.tif
subsurface_critical_length_n 0
subsurface_critical_length_p 
subsurface_eff_n             0
subsurface_eff_p             
threshold_flow_accumulation  3000
watersheds_path              C:/Users/tershya/OneDrive - Western Washington University/Research/NDR Workspace/Model 2/NRW_Boundary/NRW_Boundary.shp
workspace_dir                C:/Users/tershya/OneDrive - Western Washington University/Research/NDR Workspace/Model 2/Output

2021-11-25 12:39:40,029 Task._call(1234) INFO 18.1% complete
2021-11-25 12:39:46,084 Task._call(1234) INFO 36.2% complete
2021-11-25 12:39:52,021 Task._call(1234) INFO 45.3% complete
2021-11-25 12:39:57,384 Task._call(1234) INFO 100.0% complete
2021-11-25 12:39:57,399 Task._call(1234) DEBUG creating target flow accum raster layer
2021-11-25 12:39:59,135 Task._call(1234) DEBUG creating visited raster layer
2021-11-25 12:39:59,156 Task._call(1234) DEBUG starting search
2021-11-25 12:40:03,001 Task._call(1234) INFO mfd flow accum 10.0% complete
2021-11-25 12:40:09,000 Task._call(1234) INFO mfd flow accum 28.0% complete
2021-11-25 12:40:15,007 Task._call(1234) INFO mfd flow accum 44.7% complete
2021-11-25 12:40:16,146 Task._call(1234) INFO 100.0% complete
2021-11-25 12:40:22,007 Task._call(1234) INFO 40.7% complete
2021-11-25 12:40:24,845 Task._call(1234) INFO filter out incomplete divergent streams
2021-11-25 12:40:25,127 Task._call(1234) INFO 100.0% complete
2021-11-25 12:40:27,599 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-11-25 12:40:27,600 threading.run(870) DEBUG stats worker PID: 24004
2021-11-25 12:40:27,600 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-134, started daemon 8948)>
2021-11-25 12:40:28,587 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-11-25 12:40:28,588 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-11-25 12:40:30,473 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-11-25 12:40:30,474 threading.run(870) DEBUG stats worker PID: 24004
2021-11-25 12:40:30,474 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-135, started daemon 1564)>
2021-11-25 12:40:31,563 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-11-25 12:40:31,564 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-11-25 12:40:32,190 Task._call(1234) DEBUG creating target flow accum raster layer
2021-11-25 12:40:33,958 Task._call(1234) DEBUG creating visited raster layer
2021-11-25 12:40:33,979 Task._call(1234) DEBUG starting search
2021-11-25 12:40:38,002 Task._call(1234) INFO mfd flow accum 9.0% complete
2021-11-25 12:40:44,000 Task._call(1234) INFO mfd flow accum 25.6% complete
2021-11-25 12:40:50,006 Task._call(1234) INFO mfd flow accum 41.1% complete
2021-11-25 12:40:52,601 Task._call(1234) INFO 100.0% complete
2021-11-25 12:40:52,662 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-11-25 12:40:52,663 threading.run(870) DEBUG stats worker PID: 24004
2021-11-25 12:40:52,663 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-136, started daemon 11580)>
2021-11-25 12:40:55,990 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-11-25 12:40:55,990 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-11-25 12:40:57,223 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-11-25 12:40:57,224 threading.run(870) DEBUG stats worker PID: 24004
2021-11-25 12:40:57,224 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-137, started daemon 23520)>
2021-11-25 12:40:59,822 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-11-25 12:40:59,823 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-11-25 12:41:01,214 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-11-25 12:41:01,215 threading.run(870) DEBUG stats worker PID: 24004
2021-11-25 12:41:01,216 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-138, started daemon 21620)>
2021-11-25 12:41:02,372 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-11-25 12:41:02,373 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-11-25 12:41:09,049 Task._call(1234) INFO 31.7% complete
2021-11-25 12:41:15,014 Task._call(1234) INFO 49.8% complete
2021-11-25 12:41:20,304 Task._call(1234) INFO 100.0% complete
2021-11-25 12:41:26,017 Task._call(1234) INFO 31.7% complete
2021-11-25 12:41:32,032 Task._call(1234) INFO 67.9% complete
2021-11-25 12:41:34,329 Task._call(1234) INFO 100.0% complete
2021-11-25 12:41:34,404 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-11-25 12:41:34,405 threading.run(870) DEBUG stats worker PID: 24004
2021-11-25 12:41:34,405 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-139, started daemon 23404)>
2021-11-25 12:41:37,171 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-11-25 12:41:37,171 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-11-25 12:41:38,367 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-11-25 12:41:38,368 threading.run(870) DEBUG stats worker PID: 24004
2021-11-25 12:41:38,368 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-140, started daemon 10368)>
2021-11-25 12:41:38,371 geoprocessing.raster_calculator(507) INFO Waiting for raster stats worker result.
2021-11-25 12:41:38,371 threading.run(870) WARNING No valid pixels were received, sending None.
2021-11-25 12:41:38,371 Task.add_task(781) ERROR Something went wrong when adding task n load (16), terminating taskgraph.
Traceback (most recent call last):
  File "site-packages\natcap\invest\ndr\ndr.py", line 934, in _map_load_op
KeyError: 0

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "site-packages\taskgraph\Task.py", line 747, in add_task
  File "site-packages\taskgraph\Task.py", line 1234, in _call
  File "site-packages\natcap\invest\ndr\ndr.py", line 944, in _calculate_load
  File "site-packages\pygeoprocessing\geoprocessing.py", line 438, in raster_calculator
  File "site-packages\natcap\invest\ndr\ndr.py", line 939, in _map_load_op
KeyError: 'lucode: 0 is present in the landuse raster but missing from the biophysical table'
2021-11-25 12:41:38,373 model._logged_target(1652) ERROR Exception while executing <function execute at 0x000002731AFC8798>
Traceback (most recent call last):
  File "site-packages\natcap\invest\ndr\ndr.py", line 934, in _map_load_op
KeyError: 0

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "site-packages\natcap\invest\ui\model.py", line 1649, in _logged_target
  File "site-packages\natcap\invest\ndr\ndr.py", line 568, in execute
  File "site-packages\taskgraph\Task.py", line 747, in add_task
  File "site-packages\taskgraph\Task.py", line 1234, in _call
  File "site-packages\natcap\invest\ndr\ndr.py", line 944, in _calculate_load
  File "site-packages\pygeoprocessing\geoprocessing.py", line 438, in raster_calculator
  File "site-packages\natcap\invest\ndr\ndr.py", line 939, in _map_load_op
KeyError: 'lucode: 0 is present in the landuse raster but missing from the biophysical table'
2021-11-25 12:41:38,373 model._logged_target(1655) INFO Execution finished
2021-11-25 12:41:38,375 utils.prepare_workspace(129) INFO Elapsed time: 2m 4.290000000000006s