2020-10-27 15:46:24,811 utils.prepare_workspace(111) INFO Writing log messages to H:/Raphael/InVEST/Compton/Résultats/Scénario 1/NDR/Output\InVEST-Nutrient-Delivery-Ratio-Model-(NDR)-log-2020-10-27--15_46_24.txt
2020-10-27 15:46:24,827 model._logged_target(1629) Level 100 Starting model with parameters: 
Arguments for InVEST natcap.invest.ndr.ndr 3.8.4:
biophysical_table_path       H:/Raphael/InVEST/Compton/Données entrées/NDR/Biophysical_table_NDR.csv
calc_n                       True
calc_p                       True
dem_path                     H:\Raphael\InVEST\Compton\Données communes\MNE\LiDar_BV_10m.tif
k_param                      2
lulc_path                    H:/Raphael/InVEST/Compton/Couches scénarios/Scénario 1 (SQ) - Enrochement léger/LULC_ACA_SQR.tif
n_workers                    -1
results_suffix               
runoff_proxy_path            H:\Raphael\InVEST\Compton\Données entrées\NDR\Nutrient_runoff_proxy\NRP_p.tif
subsurface_critical_length_n 10
subsurface_critical_length_p 10
subsurface_eff_n             0.8
subsurface_eff_p             0.8
threshold_flow_accumulation  1200
watersheds_path              H:\Raphael\InVEST\Compton\Données communes\Bassin versant\BV_MT_MTM8.shp
workspace_dir                H:/Raphael/InVEST/Compton/Résultats/Scénario 1/NDR/Output

2020-10-27 15:46:26,195 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-10-27 15:46:26,197 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-92, started daemon 9808)>
2020-10-27 15:46:26,251 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-10-27 15:46:26,252 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-10-27 15:46:26,252 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-10-27 15:46:26,252 threading.run(870) DEBUG payload is None, terminating
2020-10-27 15:46:26,349 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 3 aligned: aligned_dem.tif
2020-10-27 15:46:26,648 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-10-27 15:46:26,650 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-93, started daemon 9932)>
2020-10-27 15:46:26,680 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-10-27 15:46:26,680 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-10-27 15:46:26,681 threading.run(870) DEBUG payload is None, terminating
2020-10-27 15:46:26,681 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-10-27 15:46:26,715 geoprocessing.align_and_resize_raster_stack(752) INFO 2 of 3 aligned: aligned_lulc.tif
2020-10-27 15:46:27,023 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-10-27 15:46:27,024 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-94, started daemon 8068)>
2020-10-27 15:46:27,063 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-10-27 15:46:27,063 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-10-27 15:46:27,064 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-10-27 15:46:27,064 threading.run(870) DEBUG payload is None, terminating
2020-10-27 15:46:27,113 geoprocessing.align_and_resize_raster_stack(752) INFO 3 of 3 aligned: aligned_runoff_proxy.tif
2020-10-27 15:46:27,114 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 3 rasters.
2020-10-27 15:46:30,148 Task._call(1174) INFO 100.0% complete
2020-10-27 15:46:35,204 Task._call(1174) INFO 100.0% complete
2020-10-27 15:46:35,239 Task._call(1174) DEBUG creating target flow accum raster layer
2020-10-27 15:46:35,452 Task._call(1174) DEBUG creating visited raster layer
2020-10-27 15:46:35,487 Task._call(1174) DEBUG starting search
2020-10-27 15:46:37,564 Task._call(1174) INFO 100.0% complete
2020-10-27 15:46:38,667 Task._call(1174) INFO filter out incomplete divergent streams
2020-10-27 15:46:38,706 Task._call(1174) INFO 100.0% complete
2020-10-27 15:46:39,250 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-10-27 15:46:39,251 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-95, started daemon 9336)>
2020-10-27 15:46:39,310 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-10-27 15:46:39,311 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-10-27 15:46:39,311 threading.run(870) DEBUG payload is None, terminating
2020-10-27 15:46:39,311 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-10-27 15:46:39,562 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-10-27 15:46:39,563 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-96, started daemon 9624)>
2020-10-27 15:46:39,620 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-10-27 15:46:39,620 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-10-27 15:46:39,621 threading.run(870) DEBUG payload is None, terminating
2020-10-27 15:46:39,621 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-10-27 15:46:39,708 Task._call(1174) DEBUG creating target flow accum raster layer
2020-10-27 15:46:39,920 Task._call(1174) DEBUG creating visited raster layer
2020-10-27 15:46:39,969 Task._call(1174) DEBUG starting search
2020-10-27 15:46:42,332 Task._call(1174) INFO 100.0% complete
2020-10-27 15:46:42,482 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-10-27 15:46:42,483 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-97, started daemon 1164)>
2020-10-27 15:46:42,818 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-10-27 15:46:42,818 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-10-27 15:46:42,820 threading.run(870) DEBUG payload is None, terminating
2020-10-27 15:46:42,820 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-10-27 15:46:43,181 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-10-27 15:46:43,183 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-98, started daemon 3388)>
2020-10-27 15:46:43,445 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-10-27 15:46:43,446 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-10-27 15:46:43,447 threading.run(870) DEBUG payload is None, terminating
2020-10-27 15:46:43,447 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-10-27 15:46:43,767 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-10-27 15:46:43,769 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-99, started daemon 5620)>
2020-10-27 15:46:43,842 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-10-27 15:46:43,844 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-10-27 15:46:43,845 threading.run(870) DEBUG payload is None, terminating
2020-10-27 15:46:43,845 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-10-27 15:46:46,324 Task._call(1174) INFO 100.0% complete
2020-10-27 15:46:48,019 Task._call(1174) INFO 100.0% complete
2020-10-27 15:46:48,188 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-10-27 15:46:48,189 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-100, started daemon 9312)>
2020-10-27 15:46:48,450 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-10-27 15:46:48,451 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-10-27 15:46:48,452 threading.run(870) DEBUG payload is None, terminating
2020-10-27 15:46:48,452 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-10-27 15:46:48,817 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-10-27 15:46:48,818 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-101, started daemon 9252)>
2020-10-27 15:46:48,907 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-10-27 15:46:48,908 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-10-27 15:46:48,909 threading.run(870) DEBUG payload is None, terminating
2020-10-27 15:46:48,909 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-10-27 15:46:49,130 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-10-27 15:46:49,131 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-102, started daemon 1312)>
2020-10-27 15:46:49,225 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-10-27 15:46:49,227 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-10-27 15:46:49,228 threading.run(870) DEBUG payload is None, terminating
2020-10-27 15:46:49,228 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-10-27 15:46:49,558 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-10-27 15:46:49,559 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-103, started daemon 300)>
2020-10-27 15:46:49,632 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-10-27 15:46:49,633 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-10-27 15:46:49,634 threading.run(870) DEBUG payload is None, terminating
2020-10-27 15:46:49,634 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-10-27 15:46:49,974 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-10-27 15:46:49,975 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-104, started daemon 5232)>
2020-10-27 15:46:50,035 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-10-27 15:46:50,036 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-10-27 15:46:50,037 threading.run(870) DEBUG payload is None, terminating
2020-10-27 15:46:50,036 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-10-27 15:46:50,417 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-10-27 15:46:50,418 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-105, started daemon 9632)>
2020-10-27 15:46:50,474 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-10-27 15:46:50,475 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-10-27 15:46:50,475 threading.run(870) DEBUG payload is None, terminating
2020-10-27 15:46:50,475 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-10-27 15:46:50,771 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-10-27 15:46:50,773 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-106, started daemon 1152)>
2020-10-27 15:46:50,820 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-10-27 15:46:50,821 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-10-27 15:46:50,821 threading.run(870) DEBUG payload is None, terminating
2020-10-27 15:46:50,821 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-10-27 15:46:51,076 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-10-27 15:46:51,078 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-107, started daemon 8564)>
2020-10-27 15:46:51,208 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-10-27 15:46:51,208 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-10-27 15:46:51,209 threading.run(870) DEBUG payload is None, terminating
2020-10-27 15:46:51,209 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-10-27 15:46:54,111 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-10-27 15:46:54,113 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-108, started daemon 8860)>
2020-10-27 15:46:54,238 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-10-27 15:46:54,238 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-10-27 15:46:54,239 threading.run(870) DEBUG payload is None, terminating
2020-10-27 15:46:54,238 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-10-27 15:46:54,477 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-10-27 15:46:54,478 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-109, started daemon 9068)>
2020-10-27 15:46:54,694 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-10-27 15:46:54,695 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-10-27 15:46:54,696 threading.run(870) DEBUG payload is None, terminating
2020-10-27 15:46:54,695 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-10-27 15:46:54,978 utils._log_gdal_errors(68) ERROR [errno 1] Deleting H:/Raphael/InVEST/Compton/Résultats/Scénario 1/NDR/Output\n_export.tif failed: Permission denied
2020-10-27 15:46:54,978 utils._log_gdal_errors(68) ERROR [errno 1] Deleting H:/Raphael/InVEST/Compton/Résultats/Scénario 1/NDR/Output\n_export.tif.ovr failed: Permission denied
2020-10-27 15:46:54,984 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-10-27 15:46:54,985 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-110, started daemon 5452)>
2020-10-27 15:46:55,152 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-10-27 15:46:55,152 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-10-27 15:46:55,153 threading.run(870) DEBUG payload is None, terminating
2020-10-27 15:46:55,153 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-10-27 15:46:55,299 geoprocessing.zonal_statistics(1129) DEBUG <osgeo.gdal.Dataset; proxy of <Swig Object of type 'GDALDatasetShadow *' at 0x1C68F098> >
2020-10-27 15:46:55,545 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 1 aligned: clipped_raster4vjb4lyx.tif
2020-10-27 15:46:55,545 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 1 rasters.
2020-10-27 15:46:55,581 geoprocessing.zonal_statistics(1189) INFO build a lookup of aggregate field value to FID
2020-10-27 15:46:55,582 geoprocessing.zonal_statistics(1196) INFO creating disjoint polygon set
2020-10-27 15:46:55,585 geoprocessing.calculate_disjoint_polygon_set(2082) INFO build shapely polygon list
2020-10-27 15:46:55,617 geoprocessing.calculate_disjoint_polygon_set(2096) INFO build shapely rtree index
2020-10-27 15:46:55,618 geoprocessing.calculate_disjoint_polygon_set(2111) INFO poly feature lookup 100.0% complete on watershed_results_ndr.shp
2020-10-27 15:46:55,619 geoprocessing.calculate_disjoint_polygon_set(2113) INFO build poly intersection lookup
2020-10-27 15:46:55,619 geoprocessing.calculate_disjoint_polygon_set(2137) INFO poly intersection feature lookup 100.0% complete on watershed_results_ndr.shp
2020-10-27 15:46:55,620 geoprocessing.calculate_disjoint_polygon_set(2171) INFO maximal subset build 100.0% complete on watershed_results_ndr.shp
2020-10-27 15:46:55,650 geoprocessing.zonal_statistics(1219) INFO processing 1 disjoint polygon sets
2020-10-27 15:46:55,652 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 1 of 1 100.0% processed on watershed_results_ndr.shp
2020-10-27 15:46:55,654 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 1 of 1 watershed_results_ndr.shp
2020-10-27 15:46:55,743 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 1 of 1 watershed_results_ndr.shp
2020-10-27 15:46:55,827 geoprocessing.zonal_statistics(1322) DEBUG unset_fids: 0 of 1 
2020-10-27 15:46:55,829 geoprocessing.zonal_statistics(1325) DEBUG gt [ 4.3158706e+05  1.0000000e+01  0.0000000e+00  5.0160080e+06
  0.0000000e+00 -1.0000000e+01] for ('H:/Raphael/InVEST/Compton/Résultats/Scénario 1/NDR/Output\\n_export.tif', 1)
2020-10-27 15:46:55,829 geoprocessing.zonal_statistics(1399) DEBUG remaining unset_fids: 0 of 1 
2020-10-27 15:46:55,830 geoprocessing.zonal_statistics(1407) INFO all done processing polygon sets for watershed_results_ndr.shp
2020-10-27 15:46:55,891 geoprocessing.zonal_statistics(1129) DEBUG <osgeo.gdal.Dataset; proxy of <Swig Object of type 'GDALDatasetShadow *' at 0x1C68F290> >
2020-10-27 15:46:56,082 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 1 aligned: clipped_rasterlypon0pu.tif
2020-10-27 15:46:56,083 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 1 rasters.
2020-10-27 15:46:56,120 geoprocessing.zonal_statistics(1189) INFO build a lookup of aggregate field value to FID
2020-10-27 15:46:56,121 geoprocessing.zonal_statistics(1196) INFO creating disjoint polygon set
2020-10-27 15:46:56,125 geoprocessing.calculate_disjoint_polygon_set(2082) INFO build shapely polygon list
2020-10-27 15:46:56,153 geoprocessing.calculate_disjoint_polygon_set(2096) INFO build shapely rtree index
2020-10-27 15:46:56,155 geoprocessing.calculate_disjoint_polygon_set(2111) INFO poly feature lookup 100.0% complete on watershed_results_ndr.shp
2020-10-27 15:46:56,155 geoprocessing.calculate_disjoint_polygon_set(2113) INFO build poly intersection lookup
2020-10-27 15:46:56,156 geoprocessing.calculate_disjoint_polygon_set(2137) INFO poly intersection feature lookup 100.0% complete on watershed_results_ndr.shp
2020-10-27 15:46:56,157 geoprocessing.calculate_disjoint_polygon_set(2171) INFO maximal subset build 100.0% complete on watershed_results_ndr.shp
2020-10-27 15:46:56,186 geoprocessing.zonal_statistics(1219) INFO processing 1 disjoint polygon sets
2020-10-27 15:46:56,187 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 1 of 1 100.0% processed on watershed_results_ndr.shp
2020-10-27 15:46:56,189 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 1 of 1 watershed_results_ndr.shp
2020-10-27 15:46:56,274 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 1 of 1 watershed_results_ndr.shp
2020-10-27 15:46:56,336 geoprocessing.zonal_statistics(1322) DEBUG unset_fids: 0 of 1 
2020-10-27 15:46:56,337 geoprocessing.zonal_statistics(1325) DEBUG gt [ 4.3158706e+05  1.0000000e+01  0.0000000e+00  5.0160080e+06
  0.0000000e+00 -1.0000000e+01] for ('H:/Raphael/InVEST/Compton/Résultats/Scénario 1/NDR/Output\\intermediate_outputs\\surface_load_n.tif', 1)
2020-10-27 15:46:56,338 geoprocessing.zonal_statistics(1399) DEBUG remaining unset_fids: 0 of 1 
2020-10-27 15:46:56,338 geoprocessing.zonal_statistics(1407) INFO all done processing polygon sets for watershed_results_ndr.shp
2020-10-27 15:46:56,400 geoprocessing.zonal_statistics(1129) DEBUG <osgeo.gdal.Dataset; proxy of <Swig Object of type 'GDALDatasetShadow *' at 0x18C040F8> >
2020-10-27 15:46:56,594 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 1 aligned: clipped_raster6gjkq6r0.tif
2020-10-27 15:46:56,594 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 1 rasters.
2020-10-27 15:46:56,631 geoprocessing.zonal_statistics(1189) INFO build a lookup of aggregate field value to FID
2020-10-27 15:46:56,631 geoprocessing.zonal_statistics(1196) INFO creating disjoint polygon set
2020-10-27 15:46:56,635 geoprocessing.calculate_disjoint_polygon_set(2082) INFO build shapely polygon list
2020-10-27 15:46:56,664 geoprocessing.calculate_disjoint_polygon_set(2096) INFO build shapely rtree index
2020-10-27 15:46:56,665 geoprocessing.calculate_disjoint_polygon_set(2111) INFO poly feature lookup 100.0% complete on watershed_results_ndr.shp
2020-10-27 15:46:56,666 geoprocessing.calculate_disjoint_polygon_set(2113) INFO build poly intersection lookup
2020-10-27 15:46:56,666 geoprocessing.calculate_disjoint_polygon_set(2137) INFO poly intersection feature lookup 100.0% complete on watershed_results_ndr.shp
2020-10-27 15:46:56,668 geoprocessing.calculate_disjoint_polygon_set(2171) INFO maximal subset build 100.0% complete on watershed_results_ndr.shp
2020-10-27 15:46:56,698 geoprocessing.zonal_statistics(1219) INFO processing 1 disjoint polygon sets
2020-10-27 15:46:56,699 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 1 of 1 100.0% processed on watershed_results_ndr.shp
2020-10-27 15:46:56,701 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 1 of 1 watershed_results_ndr.shp
2020-10-27 15:46:56,786 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 1 of 1 watershed_results_ndr.shp
2020-10-27 15:46:56,847 geoprocessing.zonal_statistics(1322) DEBUG unset_fids: 0 of 1 
2020-10-27 15:46:56,849 geoprocessing.zonal_statistics(1325) DEBUG gt [ 4.3158706e+05  1.0000000e+01  0.0000000e+00  5.0160080e+06
  0.0000000e+00 -1.0000000e+01] for ('H:/Raphael/InVEST/Compton/Résultats/Scénario 1/NDR/Output\\intermediate_outputs\\sub_load_n.tif', 1)
2020-10-27 15:46:56,849 geoprocessing.zonal_statistics(1399) DEBUG remaining unset_fids: 0 of 1 
2020-10-27 15:46:56,850 geoprocessing.zonal_statistics(1407) INFO all done processing polygon sets for watershed_results_ndr.shp
2020-10-27 15:46:57,126 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-10-27 15:46:57,127 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-111, started daemon 3580)>
2020-10-27 15:46:57,215 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-10-27 15:46:57,216 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-10-27 15:46:57,217 threading.run(870) DEBUG payload is None, terminating
2020-10-27 15:46:57,216 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-10-27 15:46:57,476 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-10-27 15:46:57,477 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-112, started daemon 876)>
2020-10-27 15:46:57,567 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-10-27 15:46:57,568 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-10-27 15:46:57,568 threading.run(870) DEBUG payload is None, terminating
2020-10-27 15:46:57,568 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-10-27 15:46:57,888 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-10-27 15:46:57,889 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-113, started daemon 8288)>
2020-10-27 15:46:57,928 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-10-27 15:46:57,929 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-10-27 15:46:57,929 threading.run(870) DEBUG payload is None, terminating
2020-10-27 15:46:57,929 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-10-27 15:46:58,245 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-10-27 15:46:58,247 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-114, started daemon 8464)>
2020-10-27 15:46:58,293 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-10-27 15:46:58,293 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-10-27 15:46:58,294 threading.run(870) DEBUG payload is None, terminating
2020-10-27 15:46:58,294 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-10-27 15:46:58,603 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-10-27 15:46:58,604 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-115, started daemon 9168)>
2020-10-27 15:46:58,655 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-10-27 15:46:58,656 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-10-27 15:46:58,656 threading.run(870) DEBUG payload is None, terminating
2020-10-27 15:46:58,656 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-10-27 15:46:58,957 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-10-27 15:46:58,958 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-116, started daemon 6588)>
2020-10-27 15:46:59,007 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-10-27 15:46:59,008 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-10-27 15:46:59,008 threading.run(870) DEBUG payload is None, terminating
2020-10-27 15:46:59,008 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-10-27 15:46:59,277 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-10-27 15:46:59,278 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-117, started daemon 6692)>
2020-10-27 15:46:59,407 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-10-27 15:46:59,407 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-10-27 15:46:59,408 threading.run(870) DEBUG payload is None, terminating
2020-10-27 15:46:59,408 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-10-27 15:47:02,352 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-10-27 15:47:02,354 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-118, started daemon 5380)>
2020-10-27 15:47:02,482 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-10-27 15:47:02,483 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-10-27 15:47:02,483 threading.run(870) DEBUG payload is None, terminating
2020-10-27 15:47:02,483 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-10-27 15:47:02,692 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-10-27 15:47:02,693 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-119, started daemon 6332)>
2020-10-27 15:47:02,912 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-10-27 15:47:02,912 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-10-27 15:47:02,913 threading.run(870) DEBUG payload is None, terminating
2020-10-27 15:47:02,913 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-10-27 15:47:03,199 utils._log_gdal_errors(68) ERROR [errno 1] Deleting H:/Raphael/InVEST/Compton/Résultats/Scénario 1/NDR/Output\p_export.tif failed: Permission denied
2020-10-27 15:47:03,200 utils._log_gdal_errors(68) ERROR [errno 1] Deleting H:/Raphael/InVEST/Compton/Résultats/Scénario 1/NDR/Output\p_export.tif.ovr failed: Permission denied
2020-10-27 15:47:03,210 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-10-27 15:47:03,212 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-120, started daemon 9616)>
2020-10-27 15:47:03,365 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-10-27 15:47:03,365 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-10-27 15:47:03,366 threading.run(870) DEBUG payload is None, terminating
2020-10-27 15:47:03,366 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-10-27 15:47:03,511 geoprocessing.zonal_statistics(1129) DEBUG <osgeo.gdal.Dataset; proxy of <Swig Object of type 'GDALDatasetShadow *' at 0x18C04428> >
2020-10-27 15:47:03,751 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 1 aligned: clipped_rasterg1xr7gb9.tif
2020-10-27 15:47:03,752 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 1 rasters.
2020-10-27 15:47:03,787 geoprocessing.zonal_statistics(1189) INFO build a lookup of aggregate field value to FID
2020-10-27 15:47:03,788 geoprocessing.zonal_statistics(1196) INFO creating disjoint polygon set
2020-10-27 15:47:03,791 geoprocessing.calculate_disjoint_polygon_set(2082) INFO build shapely polygon list
2020-10-27 15:47:03,821 geoprocessing.calculate_disjoint_polygon_set(2096) INFO build shapely rtree index
2020-10-27 15:47:03,822 geoprocessing.calculate_disjoint_polygon_set(2111) INFO poly feature lookup 100.0% complete on watershed_results_ndr.shp
2020-10-27 15:47:03,823 geoprocessing.calculate_disjoint_polygon_set(2113) INFO build poly intersection lookup
2020-10-27 15:47:03,823 geoprocessing.calculate_disjoint_polygon_set(2137) INFO poly intersection feature lookup 100.0% complete on watershed_results_ndr.shp
2020-10-27 15:47:03,824 geoprocessing.calculate_disjoint_polygon_set(2171) INFO maximal subset build 100.0% complete on watershed_results_ndr.shp
2020-10-27 15:47:03,853 geoprocessing.zonal_statistics(1219) INFO processing 1 disjoint polygon sets
2020-10-27 15:47:03,855 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 1 of 1 100.0% processed on watershed_results_ndr.shp
2020-10-27 15:47:03,857 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 1 of 1 watershed_results_ndr.shp
2020-10-27 15:47:03,941 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 1 of 1 watershed_results_ndr.shp
2020-10-27 15:47:04,023 geoprocessing.zonal_statistics(1322) DEBUG unset_fids: 0 of 1 
2020-10-27 15:47:04,025 geoprocessing.zonal_statistics(1325) DEBUG gt [ 4.3158706e+05  1.0000000e+01  0.0000000e+00  5.0160080e+06
  0.0000000e+00 -1.0000000e+01] for ('H:/Raphael/InVEST/Compton/Résultats/Scénario 1/NDR/Output\\p_export.tif', 1)
2020-10-27 15:47:04,025 geoprocessing.zonal_statistics(1399) DEBUG remaining unset_fids: 0 of 1 
2020-10-27 15:47:04,026 geoprocessing.zonal_statistics(1407) INFO all done processing polygon sets for watershed_results_ndr.shp
2020-10-27 15:47:04,091 geoprocessing.zonal_statistics(1129) DEBUG <osgeo.gdal.Dataset; proxy of <Swig Object of type 'GDALDatasetShadow *' at 0x18C047E8> >
2020-10-27 15:47:04,290 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 1 aligned: clipped_rasterc_vyejkz.tif
2020-10-27 15:47:04,291 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 1 rasters.
2020-10-27 15:47:04,328 geoprocessing.zonal_statistics(1189) INFO build a lookup of aggregate field value to FID
2020-10-27 15:47:04,328 geoprocessing.zonal_statistics(1196) INFO creating disjoint polygon set
2020-10-27 15:47:04,332 geoprocessing.calculate_disjoint_polygon_set(2082) INFO build shapely polygon list
2020-10-27 15:47:04,361 geoprocessing.calculate_disjoint_polygon_set(2096) INFO build shapely rtree index
2020-10-27 15:47:04,362 geoprocessing.calculate_disjoint_polygon_set(2111) INFO poly feature lookup 100.0% complete on watershed_results_ndr.shp
2020-10-27 15:47:04,363 geoprocessing.calculate_disjoint_polygon_set(2113) INFO build poly intersection lookup
2020-10-27 15:47:04,363 geoprocessing.calculate_disjoint_polygon_set(2137) INFO poly intersection feature lookup 100.0% complete on watershed_results_ndr.shp
2020-10-27 15:47:04,363 geoprocessing.calculate_disjoint_polygon_set(2171) INFO maximal subset build 100.0% complete on watershed_results_ndr.shp
2020-10-27 15:47:04,394 geoprocessing.zonal_statistics(1219) INFO processing 1 disjoint polygon sets
2020-10-27 15:47:04,395 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 1 of 1 100.0% processed on watershed_results_ndr.shp
2020-10-27 15:47:04,396 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 1 of 1 watershed_results_ndr.shp
2020-10-27 15:47:04,481 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 1 of 1 watershed_results_ndr.shp
2020-10-27 15:47:04,543 geoprocessing.zonal_statistics(1322) DEBUG unset_fids: 0 of 1 
2020-10-27 15:47:04,544 geoprocessing.zonal_statistics(1325) DEBUG gt [ 4.3158706e+05  1.0000000e+01  0.0000000e+00  5.0160080e+06
  0.0000000e+00 -1.0000000e+01] for ('H:/Raphael/InVEST/Compton/Résultats/Scénario 1/NDR/Output\\intermediate_outputs\\surface_load_p.tif', 1)
2020-10-27 15:47:04,545 geoprocessing.zonal_statistics(1399) DEBUG remaining unset_fids: 0 of 1 
2020-10-27 15:47:04,545 geoprocessing.zonal_statistics(1407) INFO all done processing polygon sets for watershed_results_ndr.shp
2020-10-27 15:47:04,605 geoprocessing.zonal_statistics(1129) DEBUG <osgeo.gdal.Dataset; proxy of <Swig Object of type 'GDALDatasetShadow *' at 0x18C042C0> >
2020-10-27 15:47:04,784 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 1 aligned: clipped_rasterv_g7ll1t.tif
2020-10-27 15:47:04,785 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 1 rasters.
2020-10-27 15:47:04,822 geoprocessing.zonal_statistics(1189) INFO build a lookup of aggregate field value to FID
2020-10-27 15:47:04,823 geoprocessing.zonal_statistics(1196) INFO creating disjoint polygon set
2020-10-27 15:47:04,827 geoprocessing.calculate_disjoint_polygon_set(2082) INFO build shapely polygon list
2020-10-27 15:47:04,856 geoprocessing.calculate_disjoint_polygon_set(2096) INFO build shapely rtree index
2020-10-27 15:47:04,858 geoprocessing.calculate_disjoint_polygon_set(2111) INFO poly feature lookup 100.0% complete on watershed_results_ndr.shp
2020-10-27 15:47:04,858 geoprocessing.calculate_disjoint_polygon_set(2113) INFO build poly intersection lookup
2020-10-27 15:47:04,859 geoprocessing.calculate_disjoint_polygon_set(2137) INFO poly intersection feature lookup 100.0% complete on watershed_results_ndr.shp
2020-10-27 15:47:04,859 geoprocessing.calculate_disjoint_polygon_set(2171) INFO maximal subset build 100.0% complete on watershed_results_ndr.shp
2020-10-27 15:47:04,888 geoprocessing.zonal_statistics(1219) INFO processing 1 disjoint polygon sets
2020-10-27 15:47:04,889 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 1 of 1 100.0% processed on watershed_results_ndr.shp
2020-10-27 15:47:04,891 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 1 of 1 watershed_results_ndr.shp
2020-10-27 15:47:04,976 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 1 of 1 watershed_results_ndr.shp
2020-10-27 15:47:05,035 geoprocessing.zonal_statistics(1322) DEBUG unset_fids: 0 of 1 
2020-10-27 15:47:05,037 geoprocessing.zonal_statistics(1325) DEBUG gt [ 4.3158706e+05  1.0000000e+01  0.0000000e+00  5.0160080e+06
  0.0000000e+00 -1.0000000e+01] for ('H:/Raphael/InVEST/Compton/Résultats/Scénario 1/NDR/Output\\intermediate_outputs\\sub_load_p.tif', 1)
2020-10-27 15:47:05,037 geoprocessing.zonal_statistics(1399) DEBUG remaining unset_fids: 0 of 1 
2020-10-27 15:47:05,038 geoprocessing.zonal_statistics(1407) INFO all done processing polygon sets for watershed_results_ndr.shp
2020-10-27 15:47:05,077 ndr.execute(705) INFO Writing summaries to output shapefile
2020-10-27 15:47:05,138 ndr.execute(710) INFO NDR complete!
2020-10-27 15:47:05,138 ndr.execute(711) INFO   _   _    ____    ____     
2020-10-27 15:47:05,139 ndr.execute(712) INFO  | \ |"|  |  _"\U |  _"\ u  
2020-10-27 15:47:05,139 ndr.execute(713) INFO <|  \| |>/| | | |\| |_) |/  
2020-10-27 15:47:05,139 ndr.execute(714) INFO U| |\  |uU| |_| |\|  _ <    
2020-10-27 15:47:05,140 ndr.execute(715) INFO  |_| \_|  |____/ u|_| \_\   
2020-10-27 15:47:05,140 ndr.execute(716) INFO  ||   \\,-.|||_   //   \\_  
2020-10-27 15:47:05,140 ndr.execute(717) INFO  (_")  (_/(__)_) (__)  (__) 
2020-10-27 15:47:05,145 model._logged_target(1638) INFO Execution finished
2020-10-27 15:47:05,146 utils.prepare_workspace(117) INFO Elapsed time: 40.32s