2019-06-12 21:56:19,142 utils.prepare_workspace(109) INFO Writing log messages to C:\Investdata\Project\SDM_data\SDRData_final\Int\working\InVEST-Sediment-Delivery-Ratio-Model-(SDR)-log-2019-06-12--21_56_19.txt 2019-06-12 21:56:19,144 model._logged_target(1633) Level 100 Starting model with parameters: Arguments for InVEST natcap.invest.sdr 3.7.0: biophysical_table_path C:/Investdata/Project/SDM_data/SDRData_final/biophysical_mabiratable2.csv dem_path C:/Investdata/Project/SDM_data/SDRData_final/Int/Dem_fill1.tif drainage_path erodibility_path C:/Investdata/Project/SDM_data/SDRData_final/Int/soil_kfactor1.tif erosivity_path C:/Investdata/Project/SDM_data/SDRData_final/Int/erosivityb1.tif ic_0_param 0.5 k_param 2 lulc_path C:/Investdata/Project/SDM_data/SDRData_final/Int/lulc_samp_futprj_clip.tif n_workers -1 results_suffix _sdr2019 sdr_max 0.8 threshold_flow_accumulation 1000 watersheds_path C:/Investdata/Project/SDM_data/SDRData_final/Int/Watershedprjb.shp workspace_dir C:\Investdata\Project\SDM_data\SDRData_final\Int\working 2019-06-12 21:56:19,417 Task._call(1068) INFO filter out incomplete divergent streams 2019-06-12 21:56:19,421 Task._call(1068) INFO 100.0% complete 2019-06-12 21:56:19,687 geoprocessing.raster_calculator(366) INFO starting stats_worker 2019-06-12 21:56:19,688 geoprocessing.raster_calculator(372) INFO started stats_worker 2019-06-12 21:56:19,693 geoprocessing.raster_calculator(445) INFO 100.0%% complete 2019-06-12 21:56:19,693 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate 2019-06-12 21:56:19,694 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result. 2019-06-12 21:56:19,694 threading.run(754) DEBUG payload is None, terminating 2019-06-12 21:56:19,881 geoprocessing.raster_calculator(366) INFO starting stats_worker 2019-06-12 21:56:19,882 geoprocessing.raster_calculator(372) INFO started stats_worker 2019-06-12 21:56:19,885 geoprocessing.raster_calculator(445) INFO 100.0%% complete 2019-06-12 21:56:19,885 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate 2019-06-12 21:56:19,887 threading.run(754) DEBUG payload is None, terminating 2019-06-12 21:56:19,887 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result. 2019-06-12 21:56:20,170 Task._call(1068) INFO 100.0% complete 2019-06-12 21:56:20,348 geoprocessing.raster_calculator(366) INFO starting stats_worker 2019-06-12 21:56:20,351 geoprocessing.raster_calculator(372) INFO started stats_worker 2019-06-12 21:56:20,355 geoprocessing.raster_calculator(445) INFO 100.0%% complete 2019-06-12 21:56:20,355 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate 2019-06-12 21:56:20,355 threading.run(754) DEBUG payload is None, terminating 2019-06-12 21:56:20,355 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result. 2019-06-12 21:56:20,556 geoprocessing.raster_calculator(366) INFO starting stats_worker 2019-06-12 21:56:20,558 geoprocessing.raster_calculator(372) INFO started stats_worker 2019-06-12 21:56:20,561 geoprocessing.raster_calculator(445) INFO 100.0%% complete 2019-06-12 21:56:20,562 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate 2019-06-12 21:56:20,562 threading.run(754) DEBUG payload is None, terminating 2019-06-12 21:56:20,562 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result. 2019-06-12 21:56:20,753 geoprocessing.raster_calculator(366) INFO starting stats_worker 2019-06-12 21:56:20,755 geoprocessing.raster_calculator(372) INFO started stats_worker 2019-06-12 21:56:20,759 geoprocessing.raster_calculator(445) INFO 100.0%% complete 2019-06-12 21:56:20,759 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate 2019-06-12 21:56:20,760 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result. 2019-06-12 21:56:20,762 threading.run(754) DEBUG payload is None, terminating 2019-06-12 21:56:20,986 geoprocessing.raster_calculator(366) INFO starting stats_worker 2019-06-12 21:56:20,987 geoprocessing.raster_calculator(372) INFO started stats_worker 2019-06-12 21:56:20,992 geoprocessing.raster_calculator(445) INFO 100.0%% complete 2019-06-12 21:56:20,993 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate 2019-06-12 21:56:20,993 threading.run(754) DEBUG payload is None, terminating 2019-06-12 21:56:20,993 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result. 2019-06-12 21:56:21,210 Task._call(1068) INFO 100.0% complete 2019-06-12 21:56:21,401 geoprocessing.raster_calculator(366) INFO starting stats_worker 2019-06-12 21:56:21,403 geoprocessing.raster_calculator(372) INFO started stats_worker 2019-06-12 21:56:21,407 geoprocessing.raster_calculator(445) INFO 100.0%% complete 2019-06-12 21:56:21,407 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate 2019-06-12 21:56:21,407 threading.run(754) DEBUG payload is None, terminating 2019-06-12 21:56:21,407 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result. 2019-06-12 21:56:21,714 geoprocessing.raster_calculator(366) INFO starting stats_worker 2019-06-12 21:56:21,716 geoprocessing.raster_calculator(372) INFO started stats_worker 2019-06-12 21:56:21,719 geoprocessing.raster_calculator(445) INFO 100.0%% complete 2019-06-12 21:56:21,719 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate 2019-06-12 21:56:21,720 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result. 2019-06-12 21:56:21,720 threading.run(754) DEBUG payload is None, terminating 2019-06-12 21:56:21,964 geoprocessing.raster_calculator(366) INFO starting stats_worker 2019-06-12 21:56:21,966 geoprocessing.raster_calculator(372) INFO started stats_worker 2019-06-12 21:56:21,970 geoprocessing.raster_calculator(445) INFO 100.0%% complete 2019-06-12 21:56:21,971 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate 2019-06-12 21:56:21,973 threading.run(754) DEBUG payload is None, terminating 2019-06-12 21:56:21,973 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result. 2019-06-12 21:56:22,131 geoprocessing.zonal_statistics(1152) DEBUG > 2019-06-12 21:56:22,138 geoprocessing.align_and_resize_raster_stack(752) DEBUG n_workers == 1 so a threadpool is sufficient 2019-06-12 21:56:22,163 geoprocessing.align_and_resize_raster_stack(779) INFO 1 of 1 aligned: clipped_rasterjdge2z.tif 2019-06-12 21:56:22,243 geoprocessing.align_and_resize_raster_stack(788) INFO aligned all 1 rasters. 2019-06-12 21:56:22,244 geoprocessing.zonal_statistics(1212) INFO build a lookup of aggregate field value to FID 2019-06-12 21:56:22,246 geoprocessing.zonal_statistics(1219) INFO creating disjoint polygon set 2019-06-12 21:56:22,246 geoprocessing.calculate_disjoint_polygon_set(2048) INFO build shapely polygon list 2019-06-12 21:56:22,252 geoprocessing.calculate_disjoint_polygon_set(2057) INFO build shapely rtree index 2019-06-12 21:56:22,335 geoprocessing.calculate_disjoint_polygon_set(2072) INFO poly feature lookup 100.0% complete on Watershedprjb.shp 2019-06-12 21:56:22,335 geoprocessing.calculate_disjoint_polygon_set(2074) INFO build poly intersection lookup 2019-06-12 21:56:22,336 geoprocessing.calculate_disjoint_polygon_set(2098) INFO poly intersection feature lookup 100.0% complete on Watershedprjb.shp 2019-06-12 21:56:22,338 geoprocessing.calculate_disjoint_polygon_set(2132) INFO maximal subset build 100.0% complete on Watershedprjb.shp 2019-06-12 21:56:22,352 geoprocessing.zonal_statistics(1239) INFO processing 1 disjoint polygon sets 2019-06-12 21:56:22,354 geoprocessing.zonal_statistics(1273) INFO disjoint polygon set 1 of 1 100.0% processed on Watershedprjb.shp 2019-06-12 21:56:22,354 geoprocessing.zonal_statistics(1281) INFO rasterizing disjoint polygon set 1 of 1 Watershedprjb.shp 2019-06-12 21:56:22,362 geoprocessing.zonal_statistics(1299) INFO summarizing rasterized disjoint polygon set 1 of 1 Watershedprjb.shp 2019-06-12 21:56:22,367 geoprocessing.zonal_statistics(1343) DEBUG unset_fids: 0 of 1 2019-06-12 21:56:22,368 geoprocessing.zonal_statistics(1346) DEBUG gt [ 3.2905125e+01 8.3333338e-03 0.0000000e+00 6.9108081e-01 0.0000000e+00 -8.3333338e-03] for (u'C:\\Investdata\\Project\\SDM_data\\SDRData_final\\Int\\working\\usle_sdr2019.tif', 1) 2019-06-12 21:56:22,368 geoprocessing.zonal_statistics(1420) DEBUG remaining unset_fids: 0 of 1 2019-06-12 21:56:22,368 geoprocessing.zonal_statistics(1428) INFO all done processing polygon sets for Watershedprjb.shp 2019-06-12 21:56:22,372 geoprocessing.zonal_statistics(1152) DEBUG > 2019-06-12 21:56:22,381 geoprocessing.align_and_resize_raster_stack(752) DEBUG n_workers == 1 so a threadpool is sufficient 2019-06-12 21:56:22,407 geoprocessing.align_and_resize_raster_stack(779) INFO 1 of 1 aligned: clipped_rasterdgmt4g.tif 2019-06-12 21:56:22,483 geoprocessing.align_and_resize_raster_stack(788) INFO aligned all 1 rasters. 2019-06-12 21:56:22,486 geoprocessing.zonal_statistics(1212) INFO build a lookup of aggregate field value to FID 2019-06-12 21:56:22,486 geoprocessing.zonal_statistics(1219) INFO creating disjoint polygon set 2019-06-12 21:56:22,487 geoprocessing.calculate_disjoint_polygon_set(2048) INFO build shapely polygon list 2019-06-12 21:56:22,492 geoprocessing.calculate_disjoint_polygon_set(2057) INFO build shapely rtree index 2019-06-12 21:56:22,573 geoprocessing.calculate_disjoint_polygon_set(2072) INFO poly feature lookup 100.0% complete on Watershedprjb.shp 2019-06-12 21:56:22,573 geoprocessing.calculate_disjoint_polygon_set(2074) INFO build poly intersection lookup 2019-06-12 21:56:22,575 geoprocessing.calculate_disjoint_polygon_set(2098) INFO poly intersection feature lookup 100.0% complete on Watershedprjb.shp 2019-06-12 21:56:22,575 geoprocessing.calculate_disjoint_polygon_set(2132) INFO maximal subset build 100.0% complete on Watershedprjb.shp 2019-06-12 21:56:22,589 geoprocessing.zonal_statistics(1239) INFO processing 1 disjoint polygon sets 2019-06-12 21:56:22,591 geoprocessing.zonal_statistics(1273) INFO disjoint polygon set 1 of 1 100.0% processed on Watershedprjb.shp 2019-06-12 21:56:22,592 geoprocessing.zonal_statistics(1281) INFO rasterizing disjoint polygon set 1 of 1 Watershedprjb.shp 2019-06-12 21:56:22,599 geoprocessing.zonal_statistics(1299) INFO summarizing rasterized disjoint polygon set 1 of 1 Watershedprjb.shp 2019-06-12 21:56:22,604 geoprocessing.zonal_statistics(1343) DEBUG unset_fids: 0 of 1 2019-06-12 21:56:22,605 geoprocessing.zonal_statistics(1346) DEBUG gt [ 3.2905125e+01 8.3333338e-03 0.0000000e+00 6.9108081e-01 0.0000000e+00 -8.3333338e-03] for (u'C:\\Investdata\\Project\\SDM_data\\SDRData_final\\Int\\working\\sed_export_sdr2019.tif', 1) 2019-06-12 21:56:22,605 geoprocessing.zonal_statistics(1420) DEBUG remaining unset_fids: 0 of 1 2019-06-12 21:56:22,605 geoprocessing.zonal_statistics(1428) INFO all done processing polygon sets for Watershedprjb.shp 2019-06-12 21:56:22,611 geoprocessing.zonal_statistics(1152) DEBUG > 2019-06-12 21:56:22,618 geoprocessing.align_and_resize_raster_stack(752) DEBUG n_workers == 1 so a threadpool is sufficient 2019-06-12 21:56:22,641 geoprocessing.align_and_resize_raster_stack(779) INFO 1 of 1 aligned: clipped_raster9vt4ms.tif 2019-06-12 21:56:22,721 geoprocessing.align_and_resize_raster_stack(788) INFO aligned all 1 rasters. 2019-06-12 21:56:22,723 geoprocessing.zonal_statistics(1212) INFO build a lookup of aggregate field value to FID 2019-06-12 21:56:22,724 geoprocessing.zonal_statistics(1219) INFO creating disjoint polygon set 2019-06-12 21:56:22,726 geoprocessing.calculate_disjoint_polygon_set(2048) INFO build shapely polygon list 2019-06-12 21:56:22,729 geoprocessing.calculate_disjoint_polygon_set(2057) INFO build shapely rtree index 2019-06-12 21:56:22,815 geoprocessing.calculate_disjoint_polygon_set(2072) INFO poly feature lookup 100.0% complete on Watershedprjb.shp 2019-06-12 21:56:22,815 geoprocessing.calculate_disjoint_polygon_set(2074) INFO build poly intersection lookup 2019-06-12 21:56:22,815 geoprocessing.calculate_disjoint_polygon_set(2098) INFO poly intersection feature lookup 100.0% complete on Watershedprjb.shp 2019-06-12 21:56:22,816 geoprocessing.calculate_disjoint_polygon_set(2132) INFO maximal subset build 100.0% complete on Watershedprjb.shp 2019-06-12 21:56:22,832 geoprocessing.zonal_statistics(1239) INFO processing 1 disjoint polygon sets 2019-06-12 21:56:22,832 geoprocessing.zonal_statistics(1273) INFO disjoint polygon set 1 of 1 100.0% processed on Watershedprjb.shp 2019-06-12 21:56:22,835 geoprocessing.zonal_statistics(1281) INFO rasterizing disjoint polygon set 1 of 1 Watershedprjb.shp 2019-06-12 21:56:22,855 geoprocessing.zonal_statistics(1299) INFO summarizing rasterized disjoint polygon set 1 of 1 Watershedprjb.shp 2019-06-12 21:56:22,865 geoprocessing.zonal_statistics(1343) DEBUG unset_fids: 0 of 1 2019-06-12 21:56:22,868 geoprocessing.zonal_statistics(1346) DEBUG gt [ 3.2905125e+01 8.3333338e-03 0.0000000e+00 6.9108081e-01 0.0000000e+00 -8.3333338e-03] for (u'C:\\Investdata\\Project\\SDM_data\\SDRData_final\\Int\\working\\sed_retention_sdr2019.tif', 1) 2019-06-12 21:56:22,869 geoprocessing.zonal_statistics(1420) DEBUG remaining unset_fids: 0 of 1 2019-06-12 21:56:22,869 geoprocessing.zonal_statistics(1428) INFO all done processing polygon sets for Watershedprjb.shp 2019-06-12 21:56:23,022 model._logged_target(1642) INFO Execution finished 2019-06-12 21:56:23,023 utils.prepare_workspace(115) INFO Elapsed time: 3.88s