2020-09-22 12:11:37,194 utils.prepare_workspace(111) INFO Writing log messages to D:/Resource Info Sys/2020/Ecosystem_services2020/SDR\InVEST-Sediment-Delivery-Ratio-Model-(SDR)-log-2020-09-22--12_11_37.txt
2020-09-22 12:11:37,196 model._logged_target(1626) Level 100 Starting model with parameters: 
Arguments for InVEST natcap.invest.sdr.sdr 3.8.9:
biophysical_table_path      D:/Resource Info Sys/2020/Ecosystem_services2020/SDR/InVEST_lulc_biophysical_table_K.csv
dem_path                    D:/Resource Info Sys/2020/Ecosystem_services2020/SDR/DEM_K.tif
drainage_path               
erodibility_path            D:/Resource Info Sys/2020/Ecosystem_services2020/SDR/Erodibility_K.tif
erosivity_path              D:/Resource Info Sys/2020/Ecosystem_services2020/SDR/Erosivity_K.tif
ic_0_param                  0.5
k_param                     2
lulc_path                   D:/Resource Info Sys/2020/Ecosystem_services2020/SDR/LU_K.tif
n_workers                   -1
results_suffix              
sdr_max                     0.8
threshold_flow_accumulation 1000
watersheds_path             D:/Resource Info Sys/2020/Ecosystem_services2020/SDR/Watershed_Kambarawa.shp
workspace_dir               D:/Resource Info Sys/2020/Ecosystem_services2020/SDR

2020-09-22 12:11:37,461 geoprocessing.zonal_statistics(1129) DEBUG <osgeo.gdal.Dataset; proxy of <Swig Object of type 'GDALDatasetShadow *' at 0x1F37E848> >
2020-09-22 12:11:37,473 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 1 aligned: clipped_rastercvdpuy0r.tif
2020-09-22 12:11:37,473 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 1 rasters.
2020-09-22 12:11:37,474 geoprocessing.zonal_statistics(1189) INFO build a lookup of aggregate field value to FID
2020-09-22 12:11:37,474 geoprocessing.zonal_statistics(1196) INFO creating disjoint polygon set
2020-09-22 12:11:37,475 geoprocessing.calculate_disjoint_polygon_set(2082) INFO build shapely polygon list
2020-09-22 12:11:37,477 geoprocessing.calculate_disjoint_polygon_set(2096) INFO build shapely rtree index
2020-09-22 12:11:37,478 geoprocessing.calculate_disjoint_polygon_set(2111) INFO poly feature lookup 100.0% complete on watershed_results_sdr.shp
2020-09-22 12:11:37,478 geoprocessing.calculate_disjoint_polygon_set(2113) INFO build poly intersection lookup
2020-09-22 12:11:37,481 geoprocessing.calculate_disjoint_polygon_set(2137) INFO poly intersection feature lookup 100.0% complete on watershed_results_sdr.shp
2020-09-22 12:11:37,481 geoprocessing.calculate_disjoint_polygon_set(2171) INFO maximal subset build 100.0% complete on watershed_results_sdr.shp
2020-09-22 12:11:37,486 geoprocessing.zonal_statistics(1219) INFO processing 4 disjoint polygon sets
2020-09-22 12:11:37,486 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 1 of 4 100.0% processed on watershed_results_sdr.shp
2020-09-22 12:11:37,487 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 1 of 4 watershed_results_sdr.shp
2020-09-22 12:11:37,489 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 1 of 4 watershed_results_sdr.shp
2020-09-22 12:11:37,495 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 2 of 4 100.0% processed on watershed_results_sdr.shp
2020-09-22 12:11:37,496 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 2 of 4 watershed_results_sdr.shp
2020-09-22 12:11:37,497 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 2 of 4 watershed_results_sdr.shp
2020-09-22 12:11:37,499 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 3 of 4 100.0% processed on watershed_results_sdr.shp
2020-09-22 12:11:37,499 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 3 of 4 watershed_results_sdr.shp
2020-09-22 12:11:37,501 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 3 of 4 watershed_results_sdr.shp
2020-09-22 12:11:37,502 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 4 of 4 100.0% processed on watershed_results_sdr.shp
2020-09-22 12:11:37,502 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 4 of 4 watershed_results_sdr.shp
2020-09-22 12:11:37,504 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 4 of 4 watershed_results_sdr.shp
2020-09-22 12:11:37,505 geoprocessing.zonal_statistics(1322) DEBUG unset_fids: 0 of 6 
2020-09-22 12:11:37,506 geoprocessing.zonal_statistics(1325) DEBUG gt [ 4.6786894e+05  1.0000000e+02  0.0000000e+00  8.4947338e+05
  0.0000000e+00 -1.0000000e+02] for ('D:/Resource Info Sys/2020/Ecosystem_services2020/SDR\\usle.tif', 1)
2020-09-22 12:11:37,506 geoprocessing.zonal_statistics(1399) DEBUG remaining unset_fids: 0 of 6 
2020-09-22 12:11:37,506 geoprocessing.zonal_statistics(1407) INFO all done processing polygon sets for watershed_results_sdr.shp
2020-09-22 12:11:37,507 geoprocessing.zonal_statistics(1129) DEBUG <osgeo.gdal.Dataset; proxy of <Swig Object of type 'GDALDatasetShadow *' at 0x1F37EB90> >
2020-09-22 12:11:37,518 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 1 aligned: clipped_raster39zexxsc.tif
2020-09-22 12:11:37,518 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 1 rasters.
2020-09-22 12:11:37,519 geoprocessing.zonal_statistics(1189) INFO build a lookup of aggregate field value to FID
2020-09-22 12:11:37,519 geoprocessing.zonal_statistics(1196) INFO creating disjoint polygon set
2020-09-22 12:11:37,520 geoprocessing.calculate_disjoint_polygon_set(2082) INFO build shapely polygon list
2020-09-22 12:11:37,521 geoprocessing.calculate_disjoint_polygon_set(2096) INFO build shapely rtree index
2020-09-22 12:11:37,522 geoprocessing.calculate_disjoint_polygon_set(2111) INFO poly feature lookup 100.0% complete on watershed_results_sdr.shp
2020-09-22 12:11:37,522 geoprocessing.calculate_disjoint_polygon_set(2113) INFO build poly intersection lookup
2020-09-22 12:11:37,524 geoprocessing.calculate_disjoint_polygon_set(2137) INFO poly intersection feature lookup 100.0% complete on watershed_results_sdr.shp
2020-09-22 12:11:37,525 geoprocessing.calculate_disjoint_polygon_set(2171) INFO maximal subset build 100.0% complete on watershed_results_sdr.shp
2020-09-22 12:11:37,529 geoprocessing.zonal_statistics(1219) INFO processing 4 disjoint polygon sets
2020-09-22 12:11:37,530 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 1 of 4 100.0% processed on watershed_results_sdr.shp
2020-09-22 12:11:37,530 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 1 of 4 watershed_results_sdr.shp
2020-09-22 12:11:37,532 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 1 of 4 watershed_results_sdr.shp
2020-09-22 12:11:37,535 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 2 of 4 100.0% processed on watershed_results_sdr.shp
2020-09-22 12:11:37,535 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 2 of 4 watershed_results_sdr.shp
2020-09-22 12:11:37,537 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 2 of 4 watershed_results_sdr.shp
2020-09-22 12:11:37,539 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 3 of 4 100.0% processed on watershed_results_sdr.shp
2020-09-22 12:11:37,539 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 3 of 4 watershed_results_sdr.shp
2020-09-22 12:11:37,541 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 3 of 4 watershed_results_sdr.shp
2020-09-22 12:11:37,542 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 4 of 4 100.0% processed on watershed_results_sdr.shp
2020-09-22 12:11:37,542 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 4 of 4 watershed_results_sdr.shp
2020-09-22 12:11:37,544 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 4 of 4 watershed_results_sdr.shp
2020-09-22 12:11:37,545 geoprocessing.zonal_statistics(1322) DEBUG unset_fids: 0 of 6 
2020-09-22 12:11:37,545 geoprocessing.zonal_statistics(1325) DEBUG gt [ 4.6786894e+05  1.0000000e+02  0.0000000e+00  8.4947338e+05
  0.0000000e+00 -1.0000000e+02] for ('D:/Resource Info Sys/2020/Ecosystem_services2020/SDR\\sed_export.tif', 1)
2020-09-22 12:11:37,546 geoprocessing.zonal_statistics(1399) DEBUG remaining unset_fids: 0 of 6 
2020-09-22 12:11:37,546 geoprocessing.zonal_statistics(1407) INFO all done processing polygon sets for watershed_results_sdr.shp
2020-09-22 12:11:37,547 geoprocessing.zonal_statistics(1129) DEBUG <osgeo.gdal.Dataset; proxy of <Swig Object of type 'GDALDatasetShadow *' at 0x1F37E908> >
2020-09-22 12:11:37,557 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 1 aligned: clipped_rasterc_nkv6p5.tif
2020-09-22 12:11:37,557 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 1 rasters.
2020-09-22 12:11:37,558 geoprocessing.zonal_statistics(1189) INFO build a lookup of aggregate field value to FID
2020-09-22 12:11:37,558 geoprocessing.zonal_statistics(1196) INFO creating disjoint polygon set
2020-09-22 12:11:37,559 geoprocessing.calculate_disjoint_polygon_set(2082) INFO build shapely polygon list
2020-09-22 12:11:37,560 geoprocessing.calculate_disjoint_polygon_set(2096) INFO build shapely rtree index
2020-09-22 12:11:37,561 geoprocessing.calculate_disjoint_polygon_set(2111) INFO poly feature lookup 100.0% complete on watershed_results_sdr.shp
2020-09-22 12:11:37,561 geoprocessing.calculate_disjoint_polygon_set(2113) INFO build poly intersection lookup
2020-09-22 12:11:37,563 geoprocessing.calculate_disjoint_polygon_set(2137) INFO poly intersection feature lookup 100.0% complete on watershed_results_sdr.shp
2020-09-22 12:11:37,563 geoprocessing.calculate_disjoint_polygon_set(2171) INFO maximal subset build 100.0% complete on watershed_results_sdr.shp
2020-09-22 12:11:37,568 geoprocessing.zonal_statistics(1219) INFO processing 4 disjoint polygon sets
2020-09-22 12:11:37,568 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 1 of 4 100.0% processed on watershed_results_sdr.shp
2020-09-22 12:11:37,569 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 1 of 4 watershed_results_sdr.shp
2020-09-22 12:11:37,571 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 1 of 4 watershed_results_sdr.shp
2020-09-22 12:11:37,574 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 2 of 4 100.0% processed on watershed_results_sdr.shp
2020-09-22 12:11:37,574 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 2 of 4 watershed_results_sdr.shp
2020-09-22 12:11:37,576 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 2 of 4 watershed_results_sdr.shp
2020-09-22 12:11:37,578 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 3 of 4 100.0% processed on watershed_results_sdr.shp
2020-09-22 12:11:37,578 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 3 of 4 watershed_results_sdr.shp
2020-09-22 12:11:37,579 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 3 of 4 watershed_results_sdr.shp
2020-09-22 12:11:37,581 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 4 of 4 100.0% processed on watershed_results_sdr.shp
2020-09-22 12:11:37,581 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 4 of 4 watershed_results_sdr.shp
2020-09-22 12:11:37,583 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 4 of 4 watershed_results_sdr.shp
2020-09-22 12:11:37,584 geoprocessing.zonal_statistics(1322) DEBUG unset_fids: 0 of 6 
2020-09-22 12:11:37,585 geoprocessing.zonal_statistics(1325) DEBUG gt [ 4.6786894e+05  1.0000000e+02  0.0000000e+00  8.4947338e+05
  0.0000000e+00 -1.0000000e+02] for ('D:/Resource Info Sys/2020/Ecosystem_services2020/SDR\\sed_retention.tif', 1)
2020-09-22 12:11:37,585 geoprocessing.zonal_statistics(1399) DEBUG remaining unset_fids: 0 of 6 
2020-09-22 12:11:37,585 geoprocessing.zonal_statistics(1407) INFO all done processing polygon sets for watershed_results_sdr.shp
2020-09-22 12:11:37,586 geoprocessing.zonal_statistics(1129) DEBUG <osgeo.gdal.Dataset; proxy of <Swig Object of type 'GDALDatasetShadow *' at 0x1F37E9B0> >
2020-09-22 12:11:37,596 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 1 aligned: clipped_rasteroj1afz93.tif
2020-09-22 12:11:37,596 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 1 rasters.
2020-09-22 12:11:37,597 geoprocessing.zonal_statistics(1189) INFO build a lookup of aggregate field value to FID
2020-09-22 12:11:37,598 geoprocessing.zonal_statistics(1196) INFO creating disjoint polygon set
2020-09-22 12:11:37,598 geoprocessing.calculate_disjoint_polygon_set(2082) INFO build shapely polygon list
2020-09-22 12:11:37,600 geoprocessing.calculate_disjoint_polygon_set(2096) INFO build shapely rtree index
2020-09-22 12:11:37,601 geoprocessing.calculate_disjoint_polygon_set(2111) INFO poly feature lookup 100.0% complete on watershed_results_sdr.shp
2020-09-22 12:11:37,601 geoprocessing.calculate_disjoint_polygon_set(2113) INFO build poly intersection lookup
2020-09-22 12:11:37,603 geoprocessing.calculate_disjoint_polygon_set(2137) INFO poly intersection feature lookup 100.0% complete on watershed_results_sdr.shp
2020-09-22 12:11:37,603 geoprocessing.calculate_disjoint_polygon_set(2171) INFO maximal subset build 100.0% complete on watershed_results_sdr.shp
2020-09-22 12:11:37,608 geoprocessing.zonal_statistics(1219) INFO processing 4 disjoint polygon sets
2020-09-22 12:11:37,608 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 1 of 4 100.0% processed on watershed_results_sdr.shp
2020-09-22 12:11:37,608 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 1 of 4 watershed_results_sdr.shp
2020-09-22 12:11:37,611 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 1 of 4 watershed_results_sdr.shp
2020-09-22 12:11:37,613 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 2 of 4 100.0% processed on watershed_results_sdr.shp
2020-09-22 12:11:37,614 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 2 of 4 watershed_results_sdr.shp
2020-09-22 12:11:37,615 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 2 of 4 watershed_results_sdr.shp
2020-09-22 12:11:37,617 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 3 of 4 100.0% processed on watershed_results_sdr.shp
2020-09-22 12:11:37,617 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 3 of 4 watershed_results_sdr.shp
2020-09-22 12:11:37,619 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 3 of 4 watershed_results_sdr.shp
2020-09-22 12:11:37,621 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 4 of 4 100.0% processed on watershed_results_sdr.shp
2020-09-22 12:11:37,621 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 4 of 4 watershed_results_sdr.shp
2020-09-22 12:11:37,622 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 4 of 4 watershed_results_sdr.shp
2020-09-22 12:11:37,624 geoprocessing.zonal_statistics(1322) DEBUG unset_fids: 0 of 6 
2020-09-22 12:11:37,624 geoprocessing.zonal_statistics(1325) DEBUG gt [ 4.6786894e+05  1.0000000e+02  0.0000000e+00  8.4947338e+05
  0.0000000e+00 -1.0000000e+02] for ('D:/Resource Info Sys/2020/Ecosystem_services2020/SDR\\sed_deposition.tif', 1)
2020-09-22 12:11:37,625 geoprocessing.zonal_statistics(1399) DEBUG remaining unset_fids: 0 of 6 
2020-09-22 12:11:37,625 geoprocessing.zonal_statistics(1407) INFO all done processing polygon sets for watershed_results_sdr.shp
2020-09-22 12:11:37,735 model._logged_target(1635) INFO Execution finished
2020-09-22 12:11:37,737 utils.prepare_workspace(117) INFO Elapsed time: 0.54s