07/09/2019 22:21:44 natcap.invest.utils INFO Writing log messages to D:\Data\Studies\ARC Data\Sanuka\Output\SDR\InVEST-Sediment-Delivery-Ratio-Model-(SDR)-log-2019-07-09--22_21_44.txt 07/09/2019 22:21:44 natcap.invest.ui.model Level 100 Starting model with parameters: Arguments for InVEST natcap.invest.sdr 3.5.0: biophysical_table_path D:/Data/Studies/ARC Data/Sanuka/Data/Biophysical.csv dem_path D:/Data/Studies/ARC Data/Sanuka/Output/New Projection/dem/DEMRaster_ProjectRaster1.tif drainage_path erodibility_path D:/Data/Studies/ARC Data/Sanuka/Output/New Projection/soil/SoilRaster_ProjectRaster1.tif erosivity_path D:/Data/Studies/ARC Data/Sanuka/Output/New Projection/rainfall/RainfallRaster_ProjectRaster1.tif ic_0_param 0.5 k_param 2.4 lulc_path D:/Data/Studies/ARC Data/Sanuka/Output/New Projection/landuse/Landuse_Raster_ProjectRaster1.tif results_suffix sdr_max 0.8 threshold_flow_accumulation 1000 watersheds_path D:/Data/Studies/ARC Data/Sanuka/Output/New Projection/watershed/Watershed Shapefile 3 HEC_KM/Export_Output.shp workspace_dir D:\Data\Studies\ARC Data\Sanuka\Output\SDR 07/09/2019 22:21:44 pygeoprocessing.geoprocessing INFO n_workers > 1 (2) so starting a processes pool. 07/09/2019 22:21:45 pygeoprocessing.geoprocessing INFO 1 of 4 aligned: aligned_dem.tif 07/09/2019 22:21:45 pygeoprocessing.geoprocessing INFO 2 of 4 aligned: aligned_lulc.tif 07/09/2019 22:21:45 pygeoprocessing.geoprocessing INFO 3 of 4 aligned: aligned_erosivity.tif 07/09/2019 22:21:45 pygeoprocessing.geoprocessing INFO 4 of 4 aligned: aligned_erodibility.tif 07/09/2019 22:21:45 pygeoprocessing.geoprocessing INFO aligned all 4 rasters. 07/09/2019 22:21:45 natcap.invest.sdr INFO calculating slope 07/09/2019 22:21:46 pygeoprocessing.geoprocessing INFO starting stats_worker 07/09/2019 22:21:46 pygeoprocessing.geoprocessing INFO started stats_worker 07/09/2019 22:21:46 pygeoprocessing.geoprocessing INFO 100.0%% complete 07/09/2019 22:21:46 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 07/09/2019 22:21:46 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 07/09/2019 22:21:46 natcap.invest.sdr INFO calculating flow direction 07/09/2019 22:21:47 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO labeling flats 07/09/2019 22:21:47 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO draining away from higher 07/09/2019 22:21:47 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO draining towards lower 07/09/2019 22:21:47 natcap.invest.pygeoprocessing_0_3_3.routing DEBUG flats exist, calculating flow direction for them 07/09/2019 22:21:47 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO labeling flats 07/09/2019 22:21:47 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO draining away from higher 07/09/2019 22:21:47 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO draining towards lower 07/09/2019 22:21:47 natcap.invest.pygeoprocessing_0_3_3.routing INFO flats exist on second pass, must be flat areas that abut the raster edge 07/09/2019 22:21:47 natcap.invest.sdr INFO calculating flow accumulation 07/09/2019 22:21:47 natcap.invest.pygeoprocessing_0_3_3.routing DEBUG starting flow accumulation 07/09/2019 22:21:48 natcap.invest.sdr INFO calculate ls term 07/09/2019 22:21:48 pygeoprocessing.geoprocessing INFO starting stats_worker 07/09/2019 22:21:48 pygeoprocessing.geoprocessing INFO started stats_worker 07/09/2019 22:21:48 pygeoprocessing.geoprocessing INFO 100.0%% complete 07/09/2019 22:21:48 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 07/09/2019 22:21:48 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 07/09/2019 22:21:48 natcap.invest.sdr INFO classifying streams from flow accumulation raster 07/09/2019 22:21:48 natcap.invest.sdr INFO calculate per pixel W 07/09/2019 22:21:48 pygeoprocessing.geoprocessing INFO starting stats_worker 07/09/2019 22:21:48 pygeoprocessing.geoprocessing INFO started stats_worker 07/09/2019 22:21:48 pygeoprocessing.geoprocessing INFO 100.0%% complete 07/09/2019 22:21:48 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 07/09/2019 22:21:48 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 07/09/2019 22:21:48 pygeoprocessing.geoprocessing INFO starting stats_worker 07/09/2019 22:21:48 pygeoprocessing.geoprocessing INFO started stats_worker 07/09/2019 22:21:48 pygeoprocessing.geoprocessing INFO 100.0%% complete 07/09/2019 22:21:48 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 07/09/2019 22:21:48 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 07/09/2019 22:21:48 natcap.invest.sdr INFO calculate CP raster 07/09/2019 22:21:48 pygeoprocessing.geoprocessing INFO starting stats_worker 07/09/2019 22:21:48 pygeoprocessing.geoprocessing INFO started stats_worker 07/09/2019 22:21:49 pygeoprocessing.geoprocessing INFO 100.0%% complete 07/09/2019 22:21:49 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 07/09/2019 22:21:49 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 07/09/2019 22:21:49 natcap.invest.sdr INFO calculating RKLS 07/09/2019 22:21:49 pygeoprocessing.geoprocessing INFO starting stats_worker 07/09/2019 22:21:49 pygeoprocessing.geoprocessing INFO started stats_worker 07/09/2019 22:21:49 pygeoprocessing.geoprocessing INFO 100.0%% complete 07/09/2019 22:21:49 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 07/09/2019 22:21:49 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 07/09/2019 22:21:49 natcap.invest.sdr INFO calculating USLE 07/09/2019 22:21:49 pygeoprocessing.geoprocessing INFO starting stats_worker 07/09/2019 22:21:49 pygeoprocessing.geoprocessing INFO started stats_worker 07/09/2019 22:21:49 pygeoprocessing.geoprocessing INFO 100.0%% complete 07/09/2019 22:21:49 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 07/09/2019 22:21:49 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 07/09/2019 22:21:49 natcap.invest.sdr INFO calculating w_bar 07/09/2019 22:21:50 pygeoprocessing.geoprocessing INFO starting stats_worker 07/09/2019 22:21:50 pygeoprocessing.geoprocessing INFO started stats_worker 07/09/2019 22:21:50 pygeoprocessing.geoprocessing INFO 100.0%% complete 07/09/2019 22:21:50 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 07/09/2019 22:21:50 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 07/09/2019 22:21:51 pygeoprocessing.geoprocessing INFO starting stats_worker 07/09/2019 22:21:51 pygeoprocessing.geoprocessing INFO started stats_worker 07/09/2019 22:21:51 pygeoprocessing.geoprocessing INFO 100.0%% complete 07/09/2019 22:21:51 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 07/09/2019 22:21:51 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 07/09/2019 22:21:51 natcap.invest.sdr INFO calculating d_up 07/09/2019 22:21:51 pygeoprocessing.geoprocessing INFO starting stats_worker 07/09/2019 22:21:51 pygeoprocessing.geoprocessing INFO started stats_worker 07/09/2019 22:21:51 pygeoprocessing.geoprocessing INFO 100.0%% complete 07/09/2019 22:21:51 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 07/09/2019 22:21:51 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 07/09/2019 22:21:51 natcap.invest.sdr INFO calculate WS factor 07/09/2019 22:21:51 pygeoprocessing.geoprocessing INFO starting stats_worker 07/09/2019 22:21:51 pygeoprocessing.geoprocessing INFO started stats_worker 07/09/2019 22:21:51 pygeoprocessing.geoprocessing INFO 100.0%% complete 07/09/2019 22:21:51 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 07/09/2019 22:21:51 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 07/09/2019 22:21:51 natcap.invest.sdr INFO calculating d_dn 07/09/2019 22:21:52 natcap.invest.sdr INFO calculate ic 07/09/2019 22:21:52 pygeoprocessing.geoprocessing INFO starting stats_worker 07/09/2019 22:21:52 pygeoprocessing.geoprocessing INFO started stats_worker 07/09/2019 22:21:52 pygeoprocessing.geoprocessing INFO 100.0%% complete 07/09/2019 22:21:52 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 07/09/2019 22:21:52 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 07/09/2019 22:21:52 natcap.invest.sdr INFO calculate sdr 07/09/2019 22:21:52 pygeoprocessing.geoprocessing INFO starting stats_worker 07/09/2019 22:21:52 pygeoprocessing.geoprocessing INFO started stats_worker 07/09/2019 22:21:52 pygeoprocessing.geoprocessing INFO 100.0%% complete 07/09/2019 22:21:52 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 07/09/2019 22:21:52 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 07/09/2019 22:21:52 natcap.invest.sdr INFO calculate sed export 07/09/2019 22:21:52 pygeoprocessing.geoprocessing INFO starting stats_worker 07/09/2019 22:21:52 pygeoprocessing.geoprocessing INFO started stats_worker 07/09/2019 22:21:52 pygeoprocessing.geoprocessing INFO 100.0%% complete 07/09/2019 22:21:52 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 07/09/2019 22:21:52 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 07/09/2019 22:21:53 natcap.invest.sdr INFO calculate sediment retention index 07/09/2019 22:21:53 pygeoprocessing.geoprocessing INFO starting stats_worker 07/09/2019 22:21:53 pygeoprocessing.geoprocessing INFO started stats_worker 07/09/2019 22:21:53 pygeoprocessing.geoprocessing INFO 100.0%% complete 07/09/2019 22:21:53 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 07/09/2019 22:21:53 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 07/09/2019 22:21:53 natcap.invest.sdr INFO calculate sediment retention 07/09/2019 22:21:53 natcap.invest.sdr INFO calculate S factor 07/09/2019 22:21:53 pygeoprocessing.geoprocessing INFO starting stats_worker 07/09/2019 22:21:53 pygeoprocessing.geoprocessing INFO started stats_worker 07/09/2019 22:21:53 pygeoprocessing.geoprocessing INFO 100.0%% complete 07/09/2019 22:21:53 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 07/09/2019 22:21:53 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 07/09/2019 22:21:53 natcap.invest.sdr INFO calculating d_dn bare soil 07/09/2019 22:21:54 natcap.invest.sdr INFO calculating d_up bare soil 07/09/2019 22:21:54 pygeoprocessing.geoprocessing INFO starting stats_worker 07/09/2019 22:21:54 pygeoprocessing.geoprocessing INFO started stats_worker 07/09/2019 22:21:54 pygeoprocessing.geoprocessing INFO 100.0%% complete 07/09/2019 22:21:54 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 07/09/2019 22:21:54 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 07/09/2019 22:21:54 natcap.invest.sdr INFO calculate ic 07/09/2019 22:21:54 pygeoprocessing.geoprocessing INFO starting stats_worker 07/09/2019 22:21:54 pygeoprocessing.geoprocessing INFO started stats_worker 07/09/2019 22:21:54 pygeoprocessing.geoprocessing INFO 100.0%% complete 07/09/2019 22:21:54 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 07/09/2019 22:21:54 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 07/09/2019 22:21:54 pygeoprocessing.geoprocessing INFO starting stats_worker 07/09/2019 22:21:54 pygeoprocessing.geoprocessing INFO started stats_worker 07/09/2019 22:21:54 pygeoprocessing.geoprocessing INFO 100.0%% complete 07/09/2019 22:21:54 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 07/09/2019 22:21:54 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 07/09/2019 22:21:54 pygeoprocessing.geoprocessing INFO starting stats_worker 07/09/2019 22:21:54 pygeoprocessing.geoprocessing INFO started stats_worker 07/09/2019 22:21:54 pygeoprocessing.geoprocessing INFO 100.0%% complete 07/09/2019 22:21:54 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 07/09/2019 22:21:54 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 07/09/2019 22:21:54 natcap.invest.sdr INFO generating report 07/09/2019 22:21:54 pygeoprocessing.geoprocessing INFO n_workers == 1 so a threadpool is sufficient 07/09/2019 22:21:55 pygeoprocessing.geoprocessing INFO 1 of 1 aligned: clipped_rasterrypwiy 07/09/2019 22:21:55 pygeoprocessing.geoprocessing INFO aligned all 1 rasters. 07/09/2019 22:21:55 pygeoprocessing.geoprocessing INFO n_workers == 1 so a threadpool is sufficient 07/09/2019 22:21:55 pygeoprocessing.geoprocessing INFO 1 of 1 aligned: clipped_rasterhunymd 07/09/2019 22:21:55 pygeoprocessing.geoprocessing INFO aligned all 1 rasters. 07/09/2019 22:21:55 pygeoprocessing.geoprocessing INFO n_workers == 1 so a threadpool is sufficient 07/09/2019 22:21:55 pygeoprocessing.geoprocessing INFO 1 of 1 aligned: clipped_rasteruziwbq 07/09/2019 22:21:56 pygeoprocessing.geoprocessing INFO aligned all 1 rasters. 07/09/2019 22:21:56 natcap.invest.ui.model INFO Execution finished 07/09/2019 22:21:56 natcap.invest.utils INFO Elapsed time: 12.22s