2020-06-04 23:55:42,518 utils.prepare_workspace(111) INFO Writing log messages to F:/CI/12_PRODUCTIVO/2020_SLA_CAM/SLA_HUILA/INVEST/PRODUCTOS_INVEST\InVEST-Habitat-Quality-log-2020-06-04--23_55_42.txt
2020-06-04 23:55:42,520 model._logged_target(1629) Level 100 Starting model with parameters: 
Arguments for InVEST natcap.invest.habitat_quality 3.8.3:
half_saturation_constant 0.5
lulc_cur_path            F:/CI/12_PRODUCTIVO/2020_SLA_CAM/SLA_HUILA/INVEST/INSUMOS_INVEST/LULC_c.tif
n_workers                -1
results_suffix           
sensitivity_table_path   F:/CI/12_PRODUCTIVO/2020_SLA_CAM/SLA_HUILA/INVEST/INSUMOS_INVEST/Sensitivity_INV_2.csv
threat_raster_folder     F:/CI/12_PRODUCTIVO/2020_SLA_CAM/SLA_HUILA/SHAPES/THREATS/RASTERS
threats_table_path       F:/CI/12_PRODUCTIVO/2020_SLA_CAM/SLA_HUILA/INVEST/INSUMOS_INVEST/Threats_INV_2.csv
workspace_dir            F:/CI/12_PRODUCTIVO/2020_SLA_CAM/SLA_HUILA/INVEST/PRODUCTOS_INVEST

2020-06-04 23:55:43,243 habitat_quality.execute(365) INFO Starting aligning and resizing land cover and threat rasters
2020-06-04 23:55:43,349 geoprocessing.align_and_resize_raster_stack(646) DEBUG assuming manual bounding box mode of [767749.1091, 664352.8101000004, 948469.1091, 910532.8101000004]
2020-06-04 23:55:44,559 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 9 aligned: LULC_c_aligned.tif
2020-06-04 23:55:45,895 geoprocessing.align_and_resize_raster_stack(752) INFO 2 of 9 aligned: Vias_P_c_aligned.tif
2020-06-04 23:55:47,272 geoprocessing.align_and_resize_raster_stack(752) INFO 3 of 9 aligned: Vias_Spv_c_aligned.tif
2020-06-04 23:55:48,621 geoprocessing.align_and_resize_raster_stack(752) INFO 4 of 9 aligned: Urbano_c_aligned.tif
2020-06-04 23:55:49,843 geoprocessing.align_and_resize_raster_stack(752) INFO 5 of 9 aligned: Pastos_c_aligned.tif
2020-06-04 23:55:50,946 geoprocessing.align_and_resize_raster_stack(752) INFO 6 of 9 aligned: cultivos_c_aligned.tif
2020-06-04 23:55:52,045 geoprocessing.align_and_resize_raster_stack(752) INFO 7 of 9 aligned: suelde_c_aligned.tif
2020-06-04 23:55:53,146 geoprocessing.align_and_resize_raster_stack(752) INFO 8 of 9 aligned: ANH_Prod_c_aligned.tif
2020-06-04 23:55:54,272 geoprocessing.align_and_resize_raster_stack(752) INFO 9 of 9 aligned: ANH_Exp_c_aligned.tif
2020-06-04 23:55:54,273 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 9 rasters.
2020-06-04 23:55:54,273 habitat_quality.execute(380) INFO Finished aligning and resizing land cover and threat rasters
2020-06-04 23:55:54,273 habitat_quality.execute(401) INFO Preprocessing threat values for F:/CI/12_PRODUCTIVO/2020_SLA_CAM/SLA_HUILA/INVEST/PRODUCTOS_INVEST\intermediate\Vias_P_c_aligned.tif
2020-06-04 23:55:55,844 habitat_quality.execute(401) INFO Preprocessing threat values for F:/CI/12_PRODUCTIVO/2020_SLA_CAM/SLA_HUILA/INVEST/PRODUCTOS_INVEST\intermediate\Vias_Spv_c_aligned.tif
2020-06-04 23:55:57,392 habitat_quality.execute(401) INFO Preprocessing threat values for F:/CI/12_PRODUCTIVO/2020_SLA_CAM/SLA_HUILA/INVEST/PRODUCTOS_INVEST\intermediate\Urbano_c_aligned.tif
2020-06-04 23:55:58,945 habitat_quality.execute(401) INFO Preprocessing threat values for F:/CI/12_PRODUCTIVO/2020_SLA_CAM/SLA_HUILA/INVEST/PRODUCTOS_INVEST\intermediate\Pastos_c_aligned.tif
2020-06-04 23:56:00,513 habitat_quality.execute(401) INFO Preprocessing threat values for F:/CI/12_PRODUCTIVO/2020_SLA_CAM/SLA_HUILA/INVEST/PRODUCTOS_INVEST\intermediate\cultivos_c_aligned.tif
2020-06-04 23:56:02,034 habitat_quality.execute(401) INFO Preprocessing threat values for F:/CI/12_PRODUCTIVO/2020_SLA_CAM/SLA_HUILA/INVEST/PRODUCTOS_INVEST\intermediate\suelde_c_aligned.tif
2020-06-04 23:56:03,556 habitat_quality.execute(401) INFO Preprocessing threat values for F:/CI/12_PRODUCTIVO/2020_SLA_CAM/SLA_HUILA/INVEST/PRODUCTOS_INVEST\intermediate\ANH_Prod_c_aligned.tif
2020-06-04 23:56:05,185 habitat_quality.execute(401) INFO Preprocessing threat values for F:/CI/12_PRODUCTIVO/2020_SLA_CAM/SLA_HUILA/INVEST/PRODUCTOS_INVEST\intermediate\ANH_Exp_c_aligned.tif
2020-06-04 23:56:06,733 habitat_quality.execute(426) INFO Starting habitat_quality biophysical calculations
2020-06-04 23:56:06,734 habitat_quality.execute(433) INFO Handling Access Shape
2020-06-04 23:56:07,771 habitat_quality.execute(446) INFO No Access Shape Provided, access raster filled with 1s.
2020-06-04 23:56:07,771 habitat_quality.execute(454) DEBUG lulc_path_dict : {'_c': 'F:/CI/12_PRODUCTIVO/2020_SLA_CAM/SLA_HUILA/INVEST/PRODUCTOS_INVEST\\intermediate\\LULC_c_aligned.tif'}
2020-06-04 23:56:07,771 habitat_quality.execute(458) INFO Calculating habitat quality for landuse: F:/CI/12_PRODUCTIVO/2020_SLA_CAM/SLA_HUILA/INVEST/PRODUCTOS_INVEST\intermediate\LULC_c_aligned.tif
2020-06-04 23:56:07,771 habitat_quality.map_raster_to_dict_values(846) INFO Starting map_raster_to_dict_values
2020-06-04 23:56:07,806 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-06-04 23:56:07,806 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-2, started daemon 20920)>
2020-06-04 23:56:09,147 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-06-04 23:56:09,147 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-06-04 23:56:09,147 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-06-04 23:56:09,148 threading.run(870) DEBUG payload is None, terminating
2020-06-04 23:56:09,451 habitat_quality.execute(481) INFO Calculating threat: Vias_P.
Threat data: {'THREAT': 'Vias_P', 'MAX_DIST': 0.0, 'WEIGHT': 0.5, 'DECAY': 'exponential'}
2020-06-04 23:56:09,452 habitat_quality.execute(485) INFO threat_raster_path F:/CI/12_PRODUCTIVO/2020_SLA_CAM/SLA_HUILA/INVEST/PRODUCTOS_INVEST\intermediate\Vias_P_c_aligned.tif
2020-06-04 23:56:09,453 habitat_quality.execute(508) DEBUG Max distance in pixels: 0.000000
2020-06-04 23:56:09,460 warnings._showwarnmsg(110) WARNING C:\InVEST_3.8.3_x86\invest-3-x86\natcap\invest\utils.py:341: RuntimeWarning: invalid value encountered in true_divide

2020-06-04 23:56:12,099 geoprocessing.convolve_2d(2422) INFO starting convolve
2020-06-04 23:56:17,103 geoprocessing.<lambda>(2520) INFO convolution worker approximately 54.2% complete on filtered_Vias_P_c.tif
2020-06-04 23:56:21,389 geoprocessing.convolve_2d(2525) INFO convolution worker 100.0% complete on filtered_Vias_P_c.tif
2020-06-04 23:56:21,771 geoprocessing.convolve_2d(2530) INFO need to normalize result so nodata values are not included
2020-06-04 23:56:22,106 geoprocessing.<lambda>(2561) INFO convolution nodata normalizer approximately 6.8% complete on filtered_Vias_P_c.tif
2020-06-04 23:56:26,421 geoprocessing.convolve_2d(2568) INFO convolution nodata normalize 100.0% complete on filtered_Vias_P_c.tif
2020-06-04 23:56:26,719 habitat_quality.map_raster_to_dict_values(846) INFO Starting map_raster_to_dict_values
2020-06-04 23:56:26,757 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-06-04 23:56:26,758 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-4, started daemon 5020)>
2020-06-04 23:56:28,582 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-06-04 23:56:28,583 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-06-04 23:56:28,583 threading.run(870) DEBUG payload is None, terminating
2020-06-04 23:56:28,583 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-06-04 23:56:28,945 habitat_quality.execute(481) INFO Calculating threat: Vias_Spv.
Threat data: {'THREAT': 'Vias_Spv', 'MAX_DIST': 0.1, 'WEIGHT': 0.5, 'DECAY': 'linear'}
2020-06-04 23:56:28,946 habitat_quality.execute(485) INFO threat_raster_path F:/CI/12_PRODUCTIVO/2020_SLA_CAM/SLA_HUILA/INVEST/PRODUCTOS_INVEST\intermediate\Vias_Spv_c_aligned.tif
2020-06-04 23:56:28,947 habitat_quality.execute(508) DEBUG Max distance in pixels: 3.333333
2020-06-04 23:56:31,567 geoprocessing.convolve_2d(2422) INFO starting convolve
2020-06-04 23:56:36,570 geoprocessing.<lambda>(2520) INFO convolution worker approximately 41.9% complete on filtered_Vias_Spv_c.tif
2020-06-04 23:56:41,573 geoprocessing.<lambda>(2520) INFO convolution worker approximately 78.8% complete on filtered_Vias_Spv_c.tif
2020-06-04 23:56:43,936 geoprocessing.convolve_2d(2525) INFO convolution worker 100.0% complete on filtered_Vias_Spv_c.tif
2020-06-04 23:56:44,223 geoprocessing.convolve_2d(2530) INFO need to normalize result so nodata values are not included
2020-06-04 23:56:46,574 geoprocessing.<lambda>(2561) INFO convolution nodata normalizer approximately 48.7% complete on filtered_Vias_Spv_c.tif
2020-06-04 23:56:49,960 geoprocessing.convolve_2d(2568) INFO convolution nodata normalize 100.0% complete on filtered_Vias_Spv_c.tif
2020-06-04 23:56:50,422 habitat_quality.map_raster_to_dict_values(846) INFO Starting map_raster_to_dict_values
2020-06-04 23:56:50,472 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-06-04 23:56:50,473 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-6, started daemon 4304)>
2020-06-04 23:56:52,379 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-06-04 23:56:52,379 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-06-04 23:56:52,379 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-06-04 23:56:52,380 threading.run(870) DEBUG payload is None, terminating
2020-06-04 23:56:52,689 habitat_quality.execute(481) INFO Calculating threat: Urbano.
Threat data: {'THREAT': 'Urbano', 'MAX_DIST': 2.0, 'WEIGHT': 1.0, 'DECAY': 'exponential'}
2020-06-04 23:56:52,689 habitat_quality.execute(485) INFO threat_raster_path F:/CI/12_PRODUCTIVO/2020_SLA_CAM/SLA_HUILA/INVEST/PRODUCTOS_INVEST\intermediate\Urbano_c_aligned.tif
2020-06-04 23:56:52,690 habitat_quality.execute(508) DEBUG Max distance in pixels: 66.666667
2020-06-04 23:56:55,385 geoprocessing.convolve_2d(2422) INFO starting convolve
2020-06-04 23:57:00,402 geoprocessing.<lambda>(2520) INFO convolution worker approximately 2.4% complete on filtered_Urbano_c.tif
2020-06-04 23:57:05,436 geoprocessing.<lambda>(2520) INFO convolution worker approximately 4.8% complete on filtered_Urbano_c.tif
2020-06-04 23:57:10,442 geoprocessing.<lambda>(2520) INFO convolution worker approximately 7.2% complete on filtered_Urbano_c.tif
2020-06-04 23:57:15,445 geoprocessing.<lambda>(2520) INFO convolution worker approximately 9.6% complete on filtered_Urbano_c.tif
2020-06-04 23:57:20,461 geoprocessing.<lambda>(2520) INFO convolution worker approximately 12.0% complete on filtered_Urbano_c.tif
2020-06-04 23:57:25,464 geoprocessing.<lambda>(2520) INFO convolution worker approximately 14.3% complete on filtered_Urbano_c.tif
2020-06-04 23:57:30,488 geoprocessing.<lambda>(2520) INFO convolution worker approximately 16.6% complete on filtered_Urbano_c.tif
2020-06-04 23:57:35,498 geoprocessing.<lambda>(2520) INFO convolution worker approximately 18.9% complete on filtered_Urbano_c.tif
2020-06-04 23:57:40,514 geoprocessing.<lambda>(2520) INFO convolution worker approximately 21.3% complete on filtered_Urbano_c.tif
2020-06-04 23:57:45,535 geoprocessing.<lambda>(2520) INFO convolution worker approximately 23.4% complete on filtered_Urbano_c.tif
2020-06-04 23:57:50,550 geoprocessing.<lambda>(2520) INFO convolution worker approximately 25.7% complete on filtered_Urbano_c.tif
2020-06-04 23:57:55,562 geoprocessing.<lambda>(2520) INFO convolution worker approximately 28.0% complete on filtered_Urbano_c.tif
2020-06-04 23:58:00,597 geoprocessing.<lambda>(2520) INFO convolution worker approximately 30.3% complete on filtered_Urbano_c.tif
2020-06-04 23:58:05,620 geoprocessing.<lambda>(2520) INFO convolution worker approximately 32.6% complete on filtered_Urbano_c.tif
2020-06-04 23:58:10,631 geoprocessing.<lambda>(2520) INFO convolution worker approximately 34.9% complete on filtered_Urbano_c.tif
2020-06-04 23:58:15,657 geoprocessing.<lambda>(2520) INFO convolution worker approximately 37.2% complete on filtered_Urbano_c.tif
2020-06-04 23:58:20,672 geoprocessing.<lambda>(2520) INFO convolution worker approximately 39.6% complete on filtered_Urbano_c.tif
2020-06-04 23:58:25,687 geoprocessing.<lambda>(2520) INFO convolution worker approximately 42.0% complete on filtered_Urbano_c.tif
2020-06-04 23:58:30,720 geoprocessing.<lambda>(2520) INFO convolution worker approximately 44.3% complete on filtered_Urbano_c.tif
2020-06-04 23:58:35,734 geoprocessing.<lambda>(2520) INFO convolution worker approximately 46.7% complete on filtered_Urbano_c.tif
2020-06-04 23:58:40,743 geoprocessing.<lambda>(2520) INFO convolution worker approximately 49.1% complete on filtered_Urbano_c.tif
2020-06-04 23:58:45,760 geoprocessing.<lambda>(2520) INFO convolution worker approximately 51.4% complete on filtered_Urbano_c.tif
2020-06-04 23:58:50,774 geoprocessing.<lambda>(2520) INFO convolution worker approximately 53.7% complete on filtered_Urbano_c.tif
2020-06-04 23:58:55,785 geoprocessing.<lambda>(2520) INFO convolution worker approximately 56.0% complete on filtered_Urbano_c.tif
2020-06-04 23:59:00,796 geoprocessing.<lambda>(2520) INFO convolution worker approximately 58.4% complete on filtered_Urbano_c.tif
2020-06-04 23:59:05,814 geoprocessing.<lambda>(2520) INFO convolution worker approximately 60.7% complete on filtered_Urbano_c.tif
2020-06-04 23:59:10,846 geoprocessing.<lambda>(2520) INFO convolution worker approximately 63.0% complete on filtered_Urbano_c.tif
2020-06-04 23:59:15,857 geoprocessing.<lambda>(2520) INFO convolution worker approximately 65.3% complete on filtered_Urbano_c.tif
2020-06-04 23:59:20,870 geoprocessing.<lambda>(2520) INFO convolution worker approximately 67.7% complete on filtered_Urbano_c.tif
2020-06-04 23:59:25,876 geoprocessing.<lambda>(2520) INFO convolution worker approximately 70.0% complete on filtered_Urbano_c.tif
2020-06-04 23:59:30,895 geoprocessing.<lambda>(2520) INFO convolution worker approximately 72.4% complete on filtered_Urbano_c.tif
2020-06-04 23:59:35,916 geoprocessing.<lambda>(2520) INFO convolution worker approximately 74.8% complete on filtered_Urbano_c.tif
2020-06-04 23:59:40,921 geoprocessing.<lambda>(2520) INFO convolution worker approximately 77.1% complete on filtered_Urbano_c.tif
2020-06-04 23:59:45,940 geoprocessing.<lambda>(2520) INFO convolution worker approximately 79.5% complete on filtered_Urbano_c.tif
2020-06-04 23:59:50,942 geoprocessing.<lambda>(2520) INFO convolution worker approximately 81.8% complete on filtered_Urbano_c.tif
2020-06-04 23:59:55,957 geoprocessing.<lambda>(2520) INFO convolution worker approximately 84.2% complete on filtered_Urbano_c.tif
2020-06-05 00:00:00,987 geoprocessing.<lambda>(2520) INFO convolution worker approximately 86.4% complete on filtered_Urbano_c.tif
2020-06-05 00:00:06,015 geoprocessing.<lambda>(2520) INFO convolution worker approximately 88.6% complete on filtered_Urbano_c.tif
2020-06-05 00:00:11,019 geoprocessing.<lambda>(2520) INFO convolution worker approximately 90.9% complete on filtered_Urbano_c.tif
2020-06-05 00:00:16,036 geoprocessing.<lambda>(2520) INFO convolution worker approximately 93.3% complete on filtered_Urbano_c.tif
2020-06-05 00:00:21,039 geoprocessing.<lambda>(2520) INFO convolution worker approximately 96.1% complete on filtered_Urbano_c.tif
2020-06-05 00:00:22,661 geoprocessing.convolve_2d(2525) INFO convolution worker 100.0% complete on filtered_Urbano_c.tif
2020-06-05 00:00:22,973 geoprocessing.convolve_2d(2530) INFO need to normalize result so nodata values are not included
2020-06-05 00:00:26,048 geoprocessing.<lambda>(2561) INFO convolution nodata normalizer approximately 56.8% complete on filtered_Urbano_c.tif
2020-06-05 00:00:28,931 geoprocessing.convolve_2d(2568) INFO convolution nodata normalize 100.0% complete on filtered_Urbano_c.tif
2020-06-05 00:00:29,628 habitat_quality.map_raster_to_dict_values(846) INFO Starting map_raster_to_dict_values
2020-06-05 00:00:29,663 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-06-05 00:00:29,663 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-8, started daemon 16160)>
2020-06-05 00:00:31,459 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-06-05 00:00:31,459 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-06-05 00:00:31,459 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-06-05 00:00:31,459 threading.run(870) DEBUG payload is None, terminating
2020-06-05 00:00:31,771 habitat_quality.execute(481) INFO Calculating threat: Pastos.
Threat data: {'THREAT': 'Pastos', 'MAX_DIST': 0.5, 'WEIGHT': 0.5, 'DECAY': 'exponential'}
2020-06-05 00:00:31,771 habitat_quality.execute(485) INFO threat_raster_path F:/CI/12_PRODUCTIVO/2020_SLA_CAM/SLA_HUILA/INVEST/PRODUCTOS_INVEST\intermediate\Pastos_c_aligned.tif
2020-06-05 00:00:31,772 habitat_quality.execute(508) DEBUG Max distance in pixels: 16.666667
2020-06-05 00:00:34,409 geoprocessing.convolve_2d(2422) INFO starting convolve
2020-06-05 00:00:39,420 geoprocessing.<lambda>(2520) INFO convolution worker approximately 23.7% complete on filtered_Pastos_c.tif
2020-06-05 00:00:44,434 geoprocessing.<lambda>(2520) INFO convolution worker approximately 46.5% complete on filtered_Pastos_c.tif
2020-06-05 00:00:49,453 geoprocessing.<lambda>(2520) INFO convolution worker approximately 68.8% complete on filtered_Pastos_c.tif
2020-06-05 00:00:54,464 geoprocessing.<lambda>(2520) INFO convolution worker approximately 91.0% complete on filtered_Pastos_c.tif
2020-06-05 00:00:56,219 geoprocessing.convolve_2d(2525) INFO convolution worker 100.0% complete on filtered_Pastos_c.tif
2020-06-05 00:00:56,531 geoprocessing.convolve_2d(2530) INFO need to normalize result so nodata values are not included
2020-06-05 00:00:59,466 geoprocessing.<lambda>(2561) INFO convolution nodata normalizer approximately 61.3% complete on filtered_Pastos_c.tif
2020-06-05 00:01:01,690 geoprocessing.convolve_2d(2568) INFO convolution nodata normalize 100.0% complete on filtered_Pastos_c.tif
2020-06-05 00:01:02,305 habitat_quality.map_raster_to_dict_values(846) INFO Starting map_raster_to_dict_values
2020-06-05 00:01:02,339 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-06-05 00:01:02,340 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-10, started daemon 16144)>
2020-06-05 00:01:04,251 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-06-05 00:01:04,252 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-06-05 00:01:04,252 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-06-05 00:01:04,252 threading.run(870) DEBUG payload is None, terminating
2020-06-05 00:01:04,664 habitat_quality.execute(481) INFO Calculating threat: cultivos.
Threat data: {'THREAT': 'cultivos', 'MAX_DIST': 1.0, 'WEIGHT': 0.7, 'DECAY': 'linear'}
2020-06-05 00:01:04,665 habitat_quality.execute(485) INFO threat_raster_path F:/CI/12_PRODUCTIVO/2020_SLA_CAM/SLA_HUILA/INVEST/PRODUCTOS_INVEST\intermediate\cultivos_c_aligned.tif
2020-06-05 00:01:04,666 habitat_quality.execute(508) DEBUG Max distance in pixels: 33.333333
2020-06-05 00:01:07,308 geoprocessing.convolve_2d(2422) INFO starting convolve
2020-06-05 00:01:12,325 geoprocessing.<lambda>(2520) INFO convolution worker approximately 35.1% complete on filtered_cultivos_c.tif
2020-06-05 00:01:17,326 geoprocessing.<lambda>(2520) INFO convolution worker approximately 68.2% complete on filtered_cultivos_c.tif
2020-06-05 00:01:21,707 geoprocessing.convolve_2d(2525) INFO convolution worker 100.0% complete on filtered_cultivos_c.tif
2020-06-05 00:01:22,003 geoprocessing.convolve_2d(2530) INFO need to normalize result so nodata values are not included
2020-06-05 00:01:22,328 geoprocessing.<lambda>(2561) INFO convolution nodata normalizer approximately 7.8% complete on filtered_cultivos_c.tif
2020-06-05 00:01:26,755 geoprocessing.convolve_2d(2568) INFO convolution nodata normalize 100.0% complete on filtered_cultivos_c.tif
2020-06-05 00:01:27,242 habitat_quality.map_raster_to_dict_values(846) INFO Starting map_raster_to_dict_values
2020-06-05 00:01:27,276 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-06-05 00:01:27,278 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-12, started daemon 5520)>
2020-06-05 00:01:29,344 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-06-05 00:01:29,344 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-06-05 00:01:29,344 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-06-05 00:01:29,345 threading.run(870) DEBUG payload is None, terminating
2020-06-05 00:01:29,672 habitat_quality.execute(481) INFO Calculating threat: suelde.
Threat data: {'THREAT': 'suelde', 'MAX_DIST': 1.0, 'WEIGHT': 0.7, 'DECAY': 'exponential'}
2020-06-05 00:01:29,672 habitat_quality.execute(485) INFO threat_raster_path F:/CI/12_PRODUCTIVO/2020_SLA_CAM/SLA_HUILA/INVEST/PRODUCTOS_INVEST\intermediate\suelde_c_aligned.tif
2020-06-05 00:01:29,673 habitat_quality.execute(508) DEBUG Max distance in pixels: 33.333333
2020-06-05 00:01:32,388 geoprocessing.convolve_2d(2422) INFO starting convolve
2020-06-05 00:01:37,400 geoprocessing.<lambda>(2520) INFO convolution worker approximately 5.1% complete on filtered_suelde_c.tif
2020-06-05 00:01:42,409 geoprocessing.<lambda>(2520) INFO convolution worker approximately 10.8% complete on filtered_suelde_c.tif
2020-06-05 00:01:47,413 geoprocessing.<lambda>(2520) INFO convolution worker approximately 16.4% complete on filtered_suelde_c.tif
2020-06-05 00:01:52,425 geoprocessing.<lambda>(2520) INFO convolution worker approximately 22.1% complete on filtered_suelde_c.tif
2020-06-05 00:01:57,427 geoprocessing.<lambda>(2520) INFO convolution worker approximately 27.7% complete on filtered_suelde_c.tif
2020-06-05 00:02:02,439 geoprocessing.<lambda>(2520) INFO convolution worker approximately 33.4% complete on filtered_suelde_c.tif
2020-06-05 00:02:07,443 geoprocessing.<lambda>(2520) INFO convolution worker approximately 38.9% complete on filtered_suelde_c.tif
2020-06-05 00:02:12,462 geoprocessing.<lambda>(2520) INFO convolution worker approximately 44.5% complete on filtered_suelde_c.tif
2020-06-05 00:02:17,475 geoprocessing.<lambda>(2520) INFO convolution worker approximately 50.0% complete on filtered_suelde_c.tif
2020-06-05 00:02:22,489 geoprocessing.<lambda>(2520) INFO convolution worker approximately 55.6% complete on filtered_suelde_c.tif
2020-06-05 00:02:27,497 geoprocessing.<lambda>(2520) INFO convolution worker approximately 61.5% complete on filtered_suelde_c.tif
2020-06-05 00:02:32,504 geoprocessing.<lambda>(2520) INFO convolution worker approximately 67.3% complete on filtered_suelde_c.tif
2020-06-05 00:02:37,511 geoprocessing.<lambda>(2520) INFO convolution worker approximately 73.2% complete on filtered_suelde_c.tif
2020-06-05 00:02:42,517 geoprocessing.<lambda>(2520) INFO convolution worker approximately 79.1% complete on filtered_suelde_c.tif
2020-06-05 00:02:47,522 geoprocessing.<lambda>(2520) INFO convolution worker approximately 85.0% complete on filtered_suelde_c.tif
2020-06-05 00:02:52,545 geoprocessing.<lambda>(2520) INFO convolution worker approximately 90.9% complete on filtered_suelde_c.tif
2020-06-05 00:02:57,548 geoprocessing.<lambda>(2520) INFO convolution worker approximately 97.1% complete on filtered_suelde_c.tif
2020-06-05 00:02:58,189 geoprocessing.convolve_2d(2525) INFO convolution worker 100.0% complete on filtered_suelde_c.tif
2020-06-05 00:02:58,464 geoprocessing.convolve_2d(2530) INFO need to normalize result so nodata values are not included
2020-06-05 00:03:02,553 geoprocessing.<lambda>(2561) INFO convolution nodata normalizer approximately 75.8% complete on filtered_suelde_c.tif
2020-06-05 00:03:03,985 geoprocessing.convolve_2d(2568) INFO convolution nodata normalize 100.0% complete on filtered_suelde_c.tif
2020-06-05 00:03:04,434 habitat_quality.map_raster_to_dict_values(846) INFO Starting map_raster_to_dict_values
2020-06-05 00:03:04,468 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-06-05 00:03:04,469 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-14, started daemon 6572)>
2020-06-05 00:03:06,231 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-06-05 00:03:06,232 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-06-05 00:03:06,232 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-06-05 00:03:06,233 threading.run(870) DEBUG payload is None, terminating
2020-06-05 00:03:06,551 habitat_quality.execute(481) INFO Calculating threat: ANH_Prod.
Threat data: {'THREAT': 'ANH_Prod', 'MAX_DIST': 1.0, 'WEIGHT': 0.8, 'DECAY': 'exponential'}
2020-06-05 00:03:06,551 habitat_quality.execute(485) INFO threat_raster_path F:/CI/12_PRODUCTIVO/2020_SLA_CAM/SLA_HUILA/INVEST/PRODUCTOS_INVEST\intermediate\ANH_Prod_c_aligned.tif
2020-06-05 00:03:06,552 habitat_quality.execute(508) DEBUG Max distance in pixels: 33.333333
2020-06-05 00:03:09,157 geoprocessing.convolve_2d(2422) INFO starting convolve
2020-06-05 00:03:14,160 geoprocessing.<lambda>(2520) INFO convolution worker approximately 5.9% complete on filtered_ANH_Prod_c.tif
2020-06-05 00:03:19,163 geoprocessing.<lambda>(2520) INFO convolution worker approximately 12.0% complete on filtered_ANH_Prod_c.tif
2020-06-05 00:03:24,172 geoprocessing.<lambda>(2520) INFO convolution worker approximately 17.9% complete on filtered_ANH_Prod_c.tif
2020-06-05 00:03:29,179 geoprocessing.<lambda>(2520) INFO convolution worker approximately 23.8% complete on filtered_ANH_Prod_c.tif
2020-06-05 00:03:34,190 geoprocessing.<lambda>(2520) INFO convolution worker approximately 29.8% complete on filtered_ANH_Prod_c.tif
2020-06-05 00:03:39,209 geoprocessing.<lambda>(2520) INFO convolution worker approximately 35.6% complete on filtered_ANH_Prod_c.tif
2020-06-05 00:03:44,212 geoprocessing.<lambda>(2520) INFO convolution worker approximately 41.4% complete on filtered_ANH_Prod_c.tif
2020-06-05 00:03:49,215 geoprocessing.<lambda>(2520) INFO convolution worker approximately 47.3% complete on filtered_ANH_Prod_c.tif
2020-06-05 00:03:54,221 geoprocessing.<lambda>(2520) INFO convolution worker approximately 53.3% complete on filtered_ANH_Prod_c.tif
2020-06-05 00:03:59,241 geoprocessing.<lambda>(2520) INFO convolution worker approximately 59.3% complete on filtered_ANH_Prod_c.tif
2020-06-05 00:04:04,254 geoprocessing.<lambda>(2520) INFO convolution worker approximately 65.2% complete on filtered_ANH_Prod_c.tif
2020-06-05 00:04:09,267 geoprocessing.<lambda>(2520) INFO convolution worker approximately 70.8% complete on filtered_ANH_Prod_c.tif
2020-06-05 00:04:14,298 geoprocessing.<lambda>(2520) INFO convolution worker approximately 76.3% complete on filtered_ANH_Prod_c.tif
2020-06-05 00:04:19,301 geoprocessing.<lambda>(2520) INFO convolution worker approximately 82.0% complete on filtered_ANH_Prod_c.tif
2020-06-05 00:04:24,322 geoprocessing.<lambda>(2520) INFO convolution worker approximately 88.1% complete on filtered_ANH_Prod_c.tif
2020-06-05 00:04:29,326 geoprocessing.<lambda>(2520) INFO convolution worker approximately 94.1% complete on filtered_ANH_Prod_c.tif
2020-06-05 00:04:32,258 geoprocessing.convolve_2d(2525) INFO convolution worker 100.0% complete on filtered_ANH_Prod_c.tif
2020-06-05 00:04:32,442 geoprocessing.convolve_2d(2530) INFO need to normalize result so nodata values are not included
2020-06-05 00:04:34,330 geoprocessing.<lambda>(2561) INFO convolution nodata normalizer approximately 45.1% complete on filtered_ANH_Prod_c.tif
2020-06-05 00:04:37,142 geoprocessing.convolve_2d(2568) INFO convolution nodata normalize 100.0% complete on filtered_ANH_Prod_c.tif
2020-06-05 00:04:37,464 habitat_quality.map_raster_to_dict_values(846) INFO Starting map_raster_to_dict_values
2020-06-05 00:04:37,498 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-06-05 00:04:37,498 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-16, started daemon 1640)>
2020-06-05 00:04:39,274 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-06-05 00:04:39,274 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-06-05 00:04:39,274 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-06-05 00:04:39,275 threading.run(870) DEBUG payload is None, terminating
2020-06-05 00:04:39,596 habitat_quality.execute(481) INFO Calculating threat: ANH_Exp.
Threat data: {'THREAT': 'ANH_Exp', 'MAX_DIST': 1.0, 'WEIGHT': 0.6, 'DECAY': 'exponential'}
2020-06-05 00:04:39,597 habitat_quality.execute(485) INFO threat_raster_path F:/CI/12_PRODUCTIVO/2020_SLA_CAM/SLA_HUILA/INVEST/PRODUCTOS_INVEST\intermediate\ANH_Exp_c_aligned.tif
2020-06-05 00:04:39,598 habitat_quality.execute(508) DEBUG Max distance in pixels: 33.333333
2020-06-05 00:04:42,211 geoprocessing.convolve_2d(2422) INFO starting convolve
2020-06-05 00:04:47,213 geoprocessing.<lambda>(2520) INFO convolution worker approximately 5.8% complete on filtered_ANH_Exp_c.tif
2020-06-05 00:04:52,233 geoprocessing.<lambda>(2520) INFO convolution worker approximately 12.0% complete on filtered_ANH_Exp_c.tif
2020-06-05 00:04:57,233 geoprocessing.<lambda>(2520) INFO convolution worker approximately 17.8% complete on filtered_ANH_Exp_c.tif
2020-06-05 00:05:02,246 geoprocessing.<lambda>(2520) INFO convolution worker approximately 23.6% complete on filtered_ANH_Exp_c.tif
2020-06-05 00:05:07,253 geoprocessing.<lambda>(2520) INFO convolution worker approximately 29.5% complete on filtered_ANH_Exp_c.tif
2020-06-05 00:05:12,281 geoprocessing.<lambda>(2520) INFO convolution worker approximately 35.3% complete on filtered_ANH_Exp_c.tif
2020-06-05 00:05:17,296 geoprocessing.<lambda>(2520) INFO convolution worker approximately 41.0% complete on filtered_ANH_Exp_c.tif
2020-06-05 00:05:22,306 geoprocessing.<lambda>(2520) INFO convolution worker approximately 46.7% complete on filtered_ANH_Exp_c.tif
2020-06-05 00:05:27,316 geoprocessing.<lambda>(2520) INFO convolution worker approximately 52.5% complete on filtered_ANH_Exp_c.tif
2020-06-05 00:05:32,330 geoprocessing.<lambda>(2520) INFO convolution worker approximately 58.3% complete on filtered_ANH_Exp_c.tif
2020-06-05 00:05:37,348 geoprocessing.<lambda>(2520) INFO convolution worker approximately 63.9% complete on filtered_ANH_Exp_c.tif
2020-06-05 00:05:42,356 geoprocessing.<lambda>(2520) INFO convolution worker approximately 69.3% complete on filtered_ANH_Exp_c.tif
2020-06-05 00:05:47,383 geoprocessing.<lambda>(2520) INFO convolution worker approximately 75.2% complete on filtered_ANH_Exp_c.tif
2020-06-05 00:05:52,398 geoprocessing.<lambda>(2520) INFO convolution worker approximately 81.0% complete on filtered_ANH_Exp_c.tif
2020-06-05 00:05:57,404 geoprocessing.<lambda>(2520) INFO convolution worker approximately 86.4% complete on filtered_ANH_Exp_c.tif
2020-06-05 00:06:02,416 geoprocessing.<lambda>(2520) INFO convolution worker approximately 92.0% complete on filtered_ANH_Exp_c.tif
2020-06-05 00:06:07,433 geoprocessing.<lambda>(2520) INFO convolution worker approximately 98.3% complete on filtered_ANH_Exp_c.tif
2020-06-05 00:06:07,522 geoprocessing.convolve_2d(2525) INFO convolution worker 100.0% complete on filtered_ANH_Exp_c.tif
2020-06-05 00:06:07,706 geoprocessing.convolve_2d(2530) INFO need to normalize result so nodata values are not included
2020-06-05 00:06:12,440 geoprocessing.<lambda>(2561) INFO convolution nodata normalizer approximately 81.1% complete on filtered_ANH_Exp_c.tif
2020-06-05 00:06:13,430 geoprocessing.convolve_2d(2568) INFO convolution nodata normalize 100.0% complete on filtered_ANH_Exp_c.tif
2020-06-05 00:06:13,953 habitat_quality.map_raster_to_dict_values(846) INFO Starting map_raster_to_dict_values
2020-06-05 00:06:13,987 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-06-05 00:06:13,988 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-18, started daemon 7912)>
2020-06-05 00:06:15,777 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-06-05 00:06:15,777 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-06-05 00:06:15,777 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-06-05 00:06:15,778 threading.run(870) DEBUG payload is None, terminating
2020-06-05 00:06:16,124 habitat_quality.execute(600) INFO Starting raster calculation on total_degradation
2020-06-05 00:06:16,171 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-06-05 00:06:16,172 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-19, started daemon 8956)>
2020-06-05 00:06:21,173 geoprocessing.<lambda>(451) INFO 27.6% complete
2020-06-05 00:06:26,187 geoprocessing.<lambda>(451) INFO 53.7% complete
2020-06-05 00:06:31,200 geoprocessing.<lambda>(451) INFO 80.0% complete
2020-06-05 00:06:34,664 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-06-05 00:06:34,664 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-06-05 00:06:34,664 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-06-05 00:06:34,664 threading.run(870) DEBUG payload is None, terminating
2020-06-05 00:06:34,730 habitat_quality.execute(607) INFO Finished raster calculation on total_degradation
2020-06-05 00:06:34,730 habitat_quality.execute(636) INFO Starting raster calculation on quality_op
2020-06-05 00:06:34,766 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-06-05 00:06:34,767 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-20, started daemon 12236)>
2020-06-05 00:06:34,836 warnings._showwarnmsg(110) WARNING C:\InVEST_3.8.3_x86\invest-3-x86\natcap\invest\habitat_quality.py:625: RuntimeWarning: invalid value encountered in less

2020-06-05 00:06:37,289 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-06-05 00:06:37,290 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-06-05 00:06:37,290 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-06-05 00:06:37,290 threading.run(870) DEBUG payload is None, terminating
2020-06-05 00:06:37,451 habitat_quality.execute(646) INFO Finished raster calculation on quality_op
2020-06-05 00:06:37,451 habitat_quality.execute(650) INFO Baseline not provided to compute Rarity
2020-06-05 00:06:37,451 habitat_quality.execute(739) INFO Finished habitat_quality biophysical calculations
2020-06-05 00:06:37,451 model._logged_target(1638) INFO Execution finished
2020-06-05 00:06:37,452 utils.prepare_workspace(117) INFO Elapsed time: 10m 54.92999999999995s