2021-02-08 02:05:26,176 utils.prepare_workspace(123) INFO Writing log messages to F:/habitat quality\InVEST-Habitat-Quality-log-2021-02-08--02_05_26.txt
2021-02-08 02:05:26,177 model._logged_target(1646) Level 100 Starting model with parameters: 
Arguments for InVEST natcap.invest.habitat_quality 3.9.0:
half_saturation_constant 0.5
lulc_cur_path            D:/MLA-year2-1/00-Dissertation/Data/Habitat/landcover.tif
n_workers                -1
results_suffix           
sensitivity_table_path   D:/MLA-year2-1/00-Dissertation/Data/Habitat/SENTIVITY1.csv
threats_table_path       D:/MLA-year2-1/00-Dissertation/Data/Habitat/Treat2.csv
workspace_dir            F:/habitat quality

2021-02-08 02:05:26,491 habitat_quality.execute(246) INFO Starting execute of Habitat Quality model.
2021-02-08 02:05:26,492 habitat_quality.execute(252) INFO Creating workspace
2021-02-08 02:05:26,501 habitat_quality.execute(265) INFO Checking Threat and Sensitivity tables for compliance
2021-02-08 02:05:26,520 habitat_quality.execute(292) INFO Validate threat rasters and collect unique LULC codes
2021-02-08 02:05:26,520 habitat_quality.execute(310) DEBUG Validating path for threat: roads
2021-02-08 02:05:26,709 habitat_quality.execute(310) DEBUG Validating path for threat: popu
2021-02-08 02:05:26,862 habitat_quality.execute(310) DEBUG Validating path for threat: gdp
2021-02-08 02:05:27,012 habitat_quality.execute(310) DEBUG Validating path for threat: built
2021-02-08 02:05:27,143 habitat_quality.execute(356) INFO Checking threat raster values are valid ( 0 <= x <= 1 ).
2021-02-08 02:05:27,143 habitat_quality.execute(367) INFO Aligning and resizing land cover and threat rasters
2021-02-08 02:05:27,177 habitat_quality.execute(390) DEBUG Raster paths for aligning: ['F:/habitat quality\\intermediate\\landcover_aligned.tif', 'F:/habitat quality\\intermediate\\roads_aligned.tif', 'F:/habitat quality\\intermediate\\popu_aligned.tif', 'F:/habitat quality\\intermediate\\gdp_aligned.tif', 'F:/habitat quality\\intermediate\\built_aligned.tif']
2021-02-08 02:05:27,347 geoprocessing.align_and_resize_raster_stack(686) DEBUG assuming manual bounding box mode of [-259256.86737670837, 2563281.745879827, -202106.86737670837, 2642871.745879827]
2021-02-08 02:05:27,601 geoprocessing.align_and_resize_raster_stack(795) INFO 1 of 5 aligned: landcover_aligned.tif
2021-02-08 02:05:27,691 geoprocessing.align_and_resize_raster_stack(795) INFO 2 of 5 aligned: roads_aligned.tif
2021-02-08 02:05:27,790 geoprocessing.align_and_resize_raster_stack(795) INFO 3 of 5 aligned: popu_aligned.tif
2021-02-08 02:05:27,877 geoprocessing.align_and_resize_raster_stack(795) INFO 4 of 5 aligned: gdp_aligned.tif
2021-02-08 02:05:27,965 geoprocessing.align_and_resize_raster_stack(795) INFO 5 of 5 aligned: built_aligned.tif
2021-02-08 02:05:27,965 geoprocessing.align_and_resize_raster_stack(797) INFO aligned all 5 rasters.
2021-02-08 02:05:28,026 habitat_quality.execute(402) DEBUG Updating dict raster paths to reflect aligned paths
2021-02-08 02:05:28,026 habitat_quality.execute(420) INFO Starting habitat_quality biophysical calculations
2021-02-08 02:05:28,210 habitat_quality.execute(464) INFO Calculating habitat quality for landuse: F:/habitat quality\intermediate\landcover_aligned.tif
2021-02-08 02:05:28,225 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-02-08 02:05:28,226 threading.run(870) DEBUG stats worker PID: 7044
2021-02-08 02:05:28,226 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-37, started daemon 1364)>
2021-02-08 02:05:28,413 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-02-08 02:05:28,413 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-02-08 02:05:28,606 habitat_quality.execute(499) DEBUG Calculating threat: roads.
Threat data: {'threat': 'roads', 'max_dist': 1, 'weight': 0.5, 'decay': 'linear', 'cur_path': 'd:\\mla-year2-1\\00-dissertation\\data\\habitat\\threats6\\roads.tif'}
2021-02-08 02:05:28,611 habitat_quality._create_decay_kernel(859) DEBUG Max distance in pixels: 33.333333333333336
2021-02-08 02:05:28,699 geoprocessing.convolve_2d(2562) INFO starting convolve
2021-02-08 02:05:28,702 geoprocessing.convolve_2d(2580) DEBUG start fill work queue thread
2021-02-08 02:05:28,703 geoprocessing._fill_work_queue(2584) DEBUG fill work queue
2021-02-08 02:05:28,703 geoprocessing.convolve_2d(2598) DEBUG start worker thread
2021-02-08 02:05:28,704 geoprocessing.convolve_2d(2610) INFO 88 sent to workers, wait for worker results
2021-02-08 02:05:29,277 geoprocessing._fill_work_queue(2589) DEBUG work queue full
2021-02-08 02:05:29,318 geoprocessing.convolve_2d(2675) INFO convolution worker 100.0% complete on filtered_roads_c.tif
2021-02-08 02:05:29,569 model._logged_target(1652) ERROR Exception while executing <function execute at 0x000002456C50CD38>
Traceback (most recent call last):
  File "site-packages\natcap\invest\ui\model.py", line 1649, in _logged_target
  File "site-packages\natcap\invest\habitat_quality.py", line 557, in execute
  File "site-packages\natcap\invest\habitat_quality.py", line 556, in <dictcomp>
KeyError: 'roads'
2021-02-08 02:05:29,570 model._logged_target(1655) INFO Execution finished
2021-02-08 02:05:29,572 utils.prepare_workspace(129) INFO Elapsed time: 3.4s