09/18/2019 13:45:59 natcap.invest.utils INFO Writing log messages to C:\Users\jureta\Documents\PhDDocuments\PES Project\map files\SRBN\SRBN_SDR_2018_30cell\InVEST-Sediment-Delivery-Ratio-Model-(SDR)-log-2019-09-18--13_45_59.txt 09/18/2019 13:45:59 natcap.invest.ui.model Level 100 Starting model with parameters: Arguments for InVEST natcap.invest.sdr 3.5.0: biophysical_table_path C:/Users/jureta/Documents/PhDDocuments/PES Project/map files/SRBN/SRBN_SDR_bptab_final.csv dem_path C:/Users/jureta/Documents/PhDDocuments/PES Project/map files/SRBN/SRBN_DEM_30mres_30cell.tif drainage_path erodibility_path C:/Users/jureta/Documents/PhDDocuments/PES Project/map files/SRBN/SRBN_K_UTM_30cell.tif erosivity_path C:/Users/jureta/Documents/PhDDocuments/PES Project/map files/SRBN/SRBN_R2018_30cell.tif ic_0_param 0.5 k_param 2 lulc_path C:/Users/jureta/Documents/PhDDocuments/PES Project/map files/SRBN/2018_SRBN_CDL_UTM.tif results_suffix ws_2018_30cell sdr_max 0.8 threshold_flow_accumulation 1000 watersheds_path C:/Users/jureta/Documents/PhDDocuments/PES Project/map files/SRBN/SRBN_ws_UTM.shp workspace_dir C:\Users\jureta\Documents\PhDDocuments\PES Project\map files\SRBN\SRBN_SDR_2018_30cell 09/18/2019 13:46:00 pygeoprocessing.geoprocessing INFO n_workers > 1 (2) so starting a processes pool. 09/18/2019 13:46:16 pygeoprocessing.geoprocessing INFO 1 of 4 aligned: aligned_dem_ws_2018_30cell.tif 09/18/2019 13:46:16 pygeoprocessing.geoprocessing INFO 2 of 4 aligned: aligned_lulc_ws_2018_30cell.tif 09/18/2019 13:46:25 pygeoprocessing.geoprocessing INFO 3 of 4 aligned: aligned_erosivity_ws_2018_30cell.tif 09/18/2019 13:46:32 pygeoprocessing.geoprocessing INFO 4 of 4 aligned: aligned_erodibility_ws_2018_30cell.tif 09/18/2019 13:46:32 pygeoprocessing.geoprocessing INFO aligned all 4 rasters. 09/18/2019 13:46:32 natcap.invest.sdr INFO calculating slope 09/18/2019 13:46:38 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 34.0% complete c:\users\public\documents\wondershare\creatortemp\tmpjzfply.tif, psz_message 09/18/2019 13:46:43 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 76.0% complete c:\users\public\documents\wondershare\creatortemp\tmpjzfply.tif, psz_message 09/18/2019 13:46:45 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 100.0% complete c:\users\public\documents\wondershare\creatortemp\tmpjzfply.tif, psz_message 09/18/2019 13:47:07 pygeoprocessing.geoprocessing INFO starting stats_worker 09/18/2019 13:47:07 pygeoprocessing.geoprocessing INFO started stats_worker 09/18/2019 13:47:12 pygeoprocessing.geoprocessing INFO 61.7% complete 09/18/2019 13:47:14 pygeoprocessing.geoprocessing INFO 100.0%% complete 09/18/2019 13:47:14 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 09/18/2019 13:47:14 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 09/18/2019 13:47:15 natcap.invest.sdr INFO calculating flow direction 09/18/2019 13:47:26 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO flow_direction_inf 8.2% complete 09/18/2019 13:47:35 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO flow_direction_inf 13.6% complete 09/18/2019 13:47:44 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO flow_direction_inf 19.1% complete 09/18/2019 13:47:53 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO flow_direction_inf 24.5% complete 09/18/2019 13:48:02 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO flow_direction_inf 30.0% complete 09/18/2019 13:48:11 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO flow_direction_inf 35.4% complete 09/18/2019 13:48:20 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO flow_direction_inf 40.9% complete 09/18/2019 13:48:28 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO flow_direction_inf 46.3% complete 09/18/2019 13:48:35 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO flow_direction_inf 51.8% complete 09/18/2019 13:48:42 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO flow_direction_inf 62.7% complete 09/18/2019 13:48:48 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO flow_direction_inf 73.6% complete 09/18/2019 13:48:54 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO flow_direction_inf 81.8% complete 09/18/2019 13:49:00 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO flow_direction_inf 95.4% complete 09/18/2019 13:49:11 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO flat_edges 19.1% complete 09/18/2019 13:49:17 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO flat_edges 35.4% complete 09/18/2019 13:49:23 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO flat_edges 60.0% complete 09/18/2019 13:49:29 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO flat_edges 92.7% complete 09/18/2019 13:49:29 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO labeling flats 09/18/2019 13:49:35 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO draining away from higher 09/18/2019 13:49:42 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO away_from_higher, work queue size: 1517367 complete 09/18/2019 13:49:46 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO draining towards lower 09/18/2019 13:49:52 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO toward_lower work queue size: 311545 09/18/2019 13:49:58 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO toward_lower work queue size: 244041 09/18/2019 13:50:04 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO toward_lower work queue size: 163270 09/18/2019 13:50:10 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO toward_lower work queue size: 120655 09/18/2019 13:50:16 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO toward_lower work queue size: 89898 09/18/2019 13:50:22 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO toward_lower work queue size: 65124 09/18/2019 13:50:28 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO toward_lower work queue size: 32246 09/18/2019 13:50:34 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO toward_lower work queue size: 2434 09/18/2019 13:50:34 natcap.invest.pygeoprocessing_0_3_3.routing DEBUG flats exist, calculating flow direction for them 09/18/2019 13:50:40 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO flow_direction_inf 40.9% complete 09/18/2019 13:50:46 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO flow_direction_inf 76.3% complete 09/18/2019 13:50:59 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO flat_edges 13.6% complete 09/18/2019 13:51:05 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO flat_edges 24.5% complete 09/18/2019 13:51:11 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO flat_edges 40.9% complete 09/18/2019 13:51:17 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO flat_edges 57.2% complete 09/18/2019 13:51:23 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO flat_edges 84.5% complete 09/18/2019 13:51:25 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO labeling flats 09/18/2019 13:51:26 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO draining away from higher 09/18/2019 13:51:29 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO draining towards lower 09/18/2019 13:51:30 natcap.invest.pygeoprocessing_0_3_3.routing INFO flats exist on second pass, must be flat areas that abut the raster edge 09/18/2019 13:51:36 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO flow_direction_inf 76.3% complete 09/18/2019 13:51:42 natcap.invest.sdr INFO calculating flow accumulation 09/18/2019 13:51:42 natcap.invest.pygeoprocessing_0_3_3.routing DEBUG starting flow accumulation 09/18/2019 13:51:50 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 38.0% complete c:\users\public\documents\wondershare\creatortemp\tmpq6avnc.tif, psz_message 09/18/2019 13:51:55 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 81.0% complete c:\users\public\documents\wondershare\creatortemp\tmpq6avnc.tif, psz_message 09/18/2019 13:51:58 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 100.0% complete c:\users\public\documents\wondershare\creatortemp\tmpq6avnc.tif, psz_message 09/18/2019 13:52:03 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO align_dataset_list aligning dataset 1 of 4 09/18/2019 13:52:09 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 32.0% complete c:\users\public\documents\wondershare\creatortemp\tmplsbmh5.tif, psz_message 09/18/2019 13:52:14 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 68.0% complete c:\users\public\documents\wondershare\creatortemp\tmplsbmh5.tif, psz_message 09/18/2019 13:52:19 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 100.0% complete c:\users\public\documents\wondershare\creatortemp\tmplsbmh5.tif, psz_message 09/18/2019 13:52:23 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO align_dataset_list aligning dataset 2 of 4 09/18/2019 13:52:29 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 43.0% complete c:\users\public\documents\wondershare\creatortemp\tmptjmfxa.tif, psz_message 09/18/2019 13:52:34 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 79.0% complete c:\users\public\documents\wondershare\creatortemp\tmptjmfxa.tif, psz_message 09/18/2019 13:52:36 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 100.0% complete c:\users\public\documents\wondershare\creatortemp\tmptjmfxa.tif, psz_message 09/18/2019 13:52:45 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO align_dataset_list aligning dataset 3 of 4 09/18/2019 13:52:51 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 44.0% complete c:\users\public\documents\wondershare\creatortemp\tmp51d454.tif, psz_message 09/18/2019 13:52:56 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 85.0% complete c:\users\public\documents\wondershare\creatortemp\tmp51d454.tif, psz_message 09/18/2019 13:52:57 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 100.0% complete c:\users\public\documents\wondershare\creatortemp\tmp51d454.tif, psz_message 09/18/2019 13:53:11 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO find outlet percent complete = 7.87, outlet_deque size = 36113 09/18/2019 13:53:17 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO find outlet percent complete = 15.90, outlet_deque size = 88883 09/18/2019 13:53:23 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO find outlet percent complete = 23.94, outlet_deque size = 134434 09/18/2019 13:53:29 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO find outlet percent complete = 31.98, outlet_deque size = 178021 09/18/2019 13:53:35 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO find outlet percent complete = 40.45, outlet_deque size = 215472 09/18/2019 13:53:41 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO find outlet percent complete = 48.66, outlet_deque size = 266993 09/18/2019 13:53:47 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO find outlet percent complete = 57.33, outlet_deque size = 324144 09/18/2019 13:53:53 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO find outlet percent complete = 66.01, outlet_deque size = 338592 09/18/2019 13:53:59 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO find outlet percent complete = 74.51, outlet_deque size = 371440 09/18/2019 13:54:05 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO find outlet percent complete = 82.47, outlet_deque size = 387267 09/18/2019 13:54:11 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO find outlet percent complete = 90.57, outlet_deque size = 451258 09/18/2019 13:54:17 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO find outlet percent complete = 98.71, outlet_deque size = 471882 09/18/2019 13:54:25 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO calculate_flow_weights 43.6% complete 09/18/2019 13:54:40 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO calculate transport cells_to_process.size() = 432429 09/18/2019 13:54:46 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO calculate transport cells_to_process.size() = 387591 09/18/2019 13:54:52 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO calculate transport cells_to_process.size() = 358619 09/18/2019 13:54:58 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO calculate transport cells_to_process.size() = 330753 09/18/2019 13:55:04 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO calculate transport cells_to_process.size() = 303283 09/18/2019 13:55:10 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO calculate transport cells_to_process.size() = 277866 09/18/2019 13:55:16 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO calculate transport cells_to_process.size() = 250014 09/18/2019 13:55:22 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO calculate transport cells_to_process.size() = 197570 09/18/2019 13:55:28 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO calculate transport cells_to_process.size() = 96562 09/18/2019 13:55:33 natcap.invest.sdr INFO calculate ls term 09/18/2019 13:55:33 pygeoprocessing.geoprocessing INFO starting stats_worker 09/18/2019 13:55:33 pygeoprocessing.geoprocessing INFO started stats_worker 09/18/2019 13:55:38 pygeoprocessing.geoprocessing INFO 11.3% complete 09/18/2019 13:55:43 pygeoprocessing.geoprocessing INFO 19.5% complete 09/18/2019 13:55:48 pygeoprocessing.geoprocessing INFO 26.7% complete 09/18/2019 13:55:53 pygeoprocessing.geoprocessing INFO 33.9% complete 09/18/2019 13:55:58 pygeoprocessing.geoprocessing INFO 41.6% complete 09/18/2019 13:56:03 pygeoprocessing.geoprocessing INFO 49.9% complete 09/18/2019 13:56:08 pygeoprocessing.geoprocessing INFO 64.4% complete 09/18/2019 13:56:13 pygeoprocessing.geoprocessing INFO 79.6% complete 09/18/2019 13:56:18 pygeoprocessing.geoprocessing INFO 97.1% complete 09/18/2019 13:56:19 pygeoprocessing.geoprocessing INFO 100.0%% complete 09/18/2019 13:56:19 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 09/18/2019 13:56:19 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 09/18/2019 13:56:19 natcap.invest.sdr INFO classifying streams from flow accumulation raster 09/18/2019 13:56:25 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 38.0% complete c:\users\public\documents\wondershare\creatortemp\tmpgafyjx.tif, psz_message 09/18/2019 13:56:30 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 76.0% complete c:\users\public\documents\wondershare\creatortemp\tmpgafyjx.tif, psz_message 09/18/2019 13:56:33 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 100.0% complete c:\users\public\documents\wondershare\creatortemp\tmpgafyjx.tif, psz_message 09/18/2019 13:56:40 natcap.invest.sdr INFO calculate per pixel W 09/18/2019 13:56:40 pygeoprocessing.geoprocessing INFO starting stats_worker 09/18/2019 13:56:40 pygeoprocessing.geoprocessing INFO started stats_worker 09/18/2019 13:56:45 pygeoprocessing.geoprocessing INFO 33.0% complete 09/18/2019 13:56:50 pygeoprocessing.geoprocessing INFO 60.6% complete 09/18/2019 13:56:55 pygeoprocessing.geoprocessing INFO 91.1% complete 09/18/2019 13:56:57 pygeoprocessing.geoprocessing INFO 100.0%% complete 09/18/2019 13:56:57 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 09/18/2019 13:56:57 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 09/18/2019 13:56:58 pygeoprocessing.geoprocessing INFO starting stats_worker 09/18/2019 13:56:58 pygeoprocessing.geoprocessing INFO started stats_worker 09/18/2019 13:57:03 pygeoprocessing.geoprocessing INFO 65.9% complete 09/18/2019 13:57:06 pygeoprocessing.geoprocessing INFO 100.0%% complete 09/18/2019 13:57:06 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 09/18/2019 13:57:06 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 09/18/2019 13:57:06 natcap.invest.sdr INFO calculate CP raster 09/18/2019 13:57:06 pygeoprocessing.geoprocessing INFO starting stats_worker 09/18/2019 13:57:06 pygeoprocessing.geoprocessing INFO started stats_worker 09/18/2019 13:57:11 pygeoprocessing.geoprocessing INFO 36.9% complete 09/18/2019 13:57:16 pygeoprocessing.geoprocessing INFO 66.9% complete 09/18/2019 13:57:21 pygeoprocessing.geoprocessing INFO 100.0%% complete 09/18/2019 13:57:21 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 09/18/2019 13:57:21 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 09/18/2019 13:57:22 natcap.invest.sdr INFO calculating RKLS 09/18/2019 13:57:22 pygeoprocessing.geoprocessing INFO starting stats_worker 09/18/2019 13:57:22 pygeoprocessing.geoprocessing INFO started stats_worker 09/18/2019 13:57:27 pygeoprocessing.geoprocessing INFO 27.1% complete 09/18/2019 13:57:32 pygeoprocessing.geoprocessing INFO 51.5% complete 09/18/2019 13:57:37 pygeoprocessing.geoprocessing INFO 84.2% complete 09/18/2019 13:57:39 pygeoprocessing.geoprocessing INFO 100.0%% complete 09/18/2019 13:57:39 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 09/18/2019 13:57:39 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 09/18/2019 13:57:39 natcap.invest.sdr INFO calculating USLE 09/18/2019 13:57:39 pygeoprocessing.geoprocessing INFO starting stats_worker 09/18/2019 13:57:39 pygeoprocessing.geoprocessing INFO started stats_worker 09/18/2019 13:57:44 pygeoprocessing.geoprocessing INFO 29.1% complete 09/18/2019 13:57:49 pygeoprocessing.geoprocessing INFO 55.5% complete 09/18/2019 13:57:54 pygeoprocessing.geoprocessing INFO 93.4% complete 09/18/2019 13:57:55 pygeoprocessing.geoprocessing INFO 100.0%% complete 09/18/2019 13:57:55 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 09/18/2019 13:57:55 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 09/18/2019 13:57:56 natcap.invest.sdr INFO calculating w_bar 09/18/2019 13:58:06 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 40.0% complete c:\users\public\documents\wondershare\creatortemp\tmpiqnosg.tif, psz_message 09/18/2019 13:58:11 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 82.0% complete c:\users\public\documents\wondershare\creatortemp\tmpiqnosg.tif, psz_message 09/18/2019 13:58:14 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 100.0% complete c:\users\public\documents\wondershare\creatortemp\tmpiqnosg.tif, psz_message 09/18/2019 13:58:18 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO align_dataset_list aligning dataset 1 of 4 09/18/2019 13:58:24 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 32.0% complete c:\users\public\documents\wondershare\creatortemp\tmpin8nmd.tif, psz_message 09/18/2019 13:58:30 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 63.0% complete c:\users\public\documents\wondershare\creatortemp\tmpin8nmd.tif, psz_message 09/18/2019 13:58:35 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 96.0% complete c:\users\public\documents\wondershare\creatortemp\tmpin8nmd.tif, psz_message 09/18/2019 13:58:35 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 100.0% complete c:\users\public\documents\wondershare\creatortemp\tmpin8nmd.tif, psz_message 09/18/2019 13:58:40 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO align_dataset_list aligning dataset 2 of 4 09/18/2019 13:58:45 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 33.0% complete c:\users\public\documents\wondershare\creatortemp\tmpev2by2.tif, psz_message 09/18/2019 13:58:51 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 69.0% complete c:\users\public\documents\wondershare\creatortemp\tmpev2by2.tif, psz_message 09/18/2019 13:58:54 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 100.0% complete c:\users\public\documents\wondershare\creatortemp\tmpev2by2.tif, psz_message 09/18/2019 13:58:59 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO align_dataset_list aligning dataset 3 of 4 09/18/2019 13:59:05 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 36.0% complete c:\users\public\documents\wondershare\creatortemp\tmpdfraun.tif, psz_message 09/18/2019 13:59:10 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 69.0% complete c:\users\public\documents\wondershare\creatortemp\tmpdfraun.tif, psz_message 09/18/2019 13:59:14 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 100.0% complete c:\users\public\documents\wondershare\creatortemp\tmpdfraun.tif, psz_message 09/18/2019 13:59:29 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO find outlet percent complete = 6.71, outlet_deque size = 29622 09/18/2019 13:59:35 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO find outlet percent complete = 14.69, outlet_deque size = 80026 09/18/2019 13:59:41 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO find outlet percent complete = 22.70, outlet_deque size = 127290 09/18/2019 13:59:47 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO find outlet percent complete = 30.88, outlet_deque size = 171537 09/18/2019 13:59:53 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO find outlet percent complete = 38.99, outlet_deque size = 208142 09/18/2019 13:59:59 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO find outlet percent complete = 47.25, outlet_deque size = 262313 09/18/2019 14:00:05 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO find outlet percent complete = 55.27, outlet_deque size = 321245 09/18/2019 14:00:11 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO find outlet percent complete = 63.27, outlet_deque size = 331434 09/18/2019 14:00:17 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO find outlet percent complete = 71.34, outlet_deque size = 364031 09/18/2019 14:00:23 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO find outlet percent complete = 82.42, outlet_deque size = 387261 09/18/2019 14:00:29 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO find outlet percent complete = 90.46, outlet_deque size = 450145 09/18/2019 14:00:35 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO find outlet percent complete = 98.65, outlet_deque size = 471879 09/18/2019 14:00:43 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO calculate_flow_weights 40.9% complete 09/18/2019 14:00:49 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO calculate_flow_weights 95.4% complete 09/18/2019 14:00:59 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO calculate transport cells_to_process.size() = 433860 09/18/2019 14:01:05 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO calculate transport cells_to_process.size() = 398306 09/18/2019 14:01:11 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO calculate transport cells_to_process.size() = 365866 09/18/2019 14:01:17 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO calculate transport cells_to_process.size() = 338666 09/18/2019 14:01:23 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO calculate transport cells_to_process.size() = 310758 09/18/2019 14:01:29 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO calculate transport cells_to_process.size() = 280154 09/18/2019 14:01:35 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO calculate transport cells_to_process.size() = 249130 09/18/2019 14:01:41 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO calculate transport cells_to_process.size() = 197883 09/18/2019 14:01:47 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO calculate transport cells_to_process.size() = 103337 09/18/2019 14:01:53 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO calculate transport cells_to_process.size() = 1494 09/18/2019 14:01:55 pygeoprocessing.geoprocessing INFO starting stats_worker 09/18/2019 14:01:55 pygeoprocessing.geoprocessing INFO started stats_worker 09/18/2019 14:02:00 pygeoprocessing.geoprocessing INFO 40.1% complete 09/18/2019 14:02:05 pygeoprocessing.geoprocessing INFO 84.6% complete 09/18/2019 14:02:06 pygeoprocessing.geoprocessing INFO 100.0%% complete 09/18/2019 14:02:06 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 09/18/2019 14:02:06 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 09/18/2019 14:02:18 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 38.0% complete c:\users\public\documents\wondershare\creatortemp\tmpd8fmsy.tif, psz_message 09/18/2019 14:02:23 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 79.0% complete c:\users\public\documents\wondershare\creatortemp\tmpd8fmsy.tif, psz_message 09/18/2019 14:02:26 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 100.0% complete c:\users\public\documents\wondershare\creatortemp\tmpd8fmsy.tif, psz_message 09/18/2019 14:02:31 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO align_dataset_list aligning dataset 1 of 4 09/18/2019 14:02:36 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 32.0% complete c:\users\public\documents\wondershare\creatortemp\tmpmsjy3c.tif, psz_message 09/18/2019 14:02:41 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 66.0% complete c:\users\public\documents\wondershare\creatortemp\tmpmsjy3c.tif, psz_message 09/18/2019 14:02:46 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 98.0% complete c:\users\public\documents\wondershare\creatortemp\tmpmsjy3c.tif, psz_message 09/18/2019 14:02:47 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 100.0% complete c:\users\public\documents\wondershare\creatortemp\tmpmsjy3c.tif, psz_message 09/18/2019 14:02:52 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO align_dataset_list aligning dataset 2 of 4 09/18/2019 14:02:58 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 32.0% complete c:\users\public\documents\wondershare\creatortemp\tmpmyogxo.tif, psz_message 09/18/2019 14:03:03 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 65.0% complete c:\users\public\documents\wondershare\creatortemp\tmpmyogxo.tif, psz_message 09/18/2019 14:03:08 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 98.0% complete c:\users\public\documents\wondershare\creatortemp\tmpmyogxo.tif, psz_message 09/18/2019 14:03:08 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 100.0% complete c:\users\public\documents\wondershare\creatortemp\tmpmyogxo.tif, psz_message 09/18/2019 14:03:13 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO align_dataset_list aligning dataset 3 of 4 09/18/2019 14:03:19 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 34.0% complete c:\users\public\documents\wondershare\creatortemp\tmpyaud7k.tif, psz_message 09/18/2019 14:03:24 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 69.0% complete c:\users\public\documents\wondershare\creatortemp\tmpyaud7k.tif, psz_message 09/18/2019 14:03:28 natcap.invest.pygeoprocessing_0_3_3.geoprocessing INFO ReprojectImage 100.0% complete c:\users\public\documents\wondershare\creatortemp\tmpyaud7k.tif, psz_message 09/18/2019 14:03:43 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO find outlet percent complete = 7.72, outlet_deque size = 35035 09/18/2019 14:03:49 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO find outlet percent complete = 15.73, outlet_deque size = 87666 09/18/2019 14:03:55 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO find outlet percent complete = 23.75, outlet_deque size = 133611 09/18/2019 14:04:01 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO find outlet percent complete = 31.71, outlet_deque size = 175715 09/18/2019 14:04:07 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO find outlet percent complete = 39.83, outlet_deque size = 212749 09/18/2019 14:04:13 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO find outlet percent complete = 48.04, outlet_deque size = 265438 09/18/2019 14:04:19 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO find outlet percent complete = 56.06, outlet_deque size = 321983 09/18/2019 14:04:25 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO find outlet percent complete = 65.67, outlet_deque size = 337250 09/18/2019 14:04:31 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO find outlet percent complete = 77.33, outlet_deque size = 376959 09/18/2019 14:04:37 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO find outlet percent complete = 87.62, outlet_deque size = 409993 09/18/2019 14:04:43 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO find outlet percent complete = 99.90, outlet_deque size = 471990 09/18/2019 14:04:50 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO calculate_flow_weights 35.4% complete 09/18/2019 14:04:56 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO calculate_flow_weights 87.2% complete 09/18/2019 14:05:07 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO calculate transport cells_to_process.size() = 430874 09/18/2019 14:05:13 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO calculate transport cells_to_process.size() = 395876 09/18/2019 14:05:19 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO calculate transport cells_to_process.size() = 364411 09/18/2019 14:05:25 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO calculate transport cells_to_process.size() = 336377 09/18/2019 14:05:31 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO calculate transport cells_to_process.size() = 308186 09/18/2019 14:05:37 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO calculate transport cells_to_process.size() = 280558 09/18/2019 14:05:43 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO calculate transport cells_to_process.size() = 254134 09/18/2019 14:05:49 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO calculate transport cells_to_process.size() = 205235 09/18/2019 14:05:55 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO calculate transport cells_to_process.size() = 117769 09/18/2019 14:06:01 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO calculate transport cells_to_process.size() = 67186 09/18/2019 14:06:04 pygeoprocessing.geoprocessing INFO starting stats_worker 09/18/2019 14:06:04 pygeoprocessing.geoprocessing INFO started stats_worker 09/18/2019 14:06:09 pygeoprocessing.geoprocessing INFO 25.9% complete 09/18/2019 14:06:14 pygeoprocessing.geoprocessing INFO 62.4% complete 09/18/2019 14:06:18 pygeoprocessing.geoprocessing INFO 100.0%% complete 09/18/2019 14:06:18 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 09/18/2019 14:06:18 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 09/18/2019 14:06:18 natcap.invest.sdr INFO calculating d_up 09/18/2019 14:06:18 pygeoprocessing.geoprocessing INFO starting stats_worker 09/18/2019 14:06:18 pygeoprocessing.geoprocessing INFO started stats_worker 09/18/2019 14:06:23 pygeoprocessing.geoprocessing INFO 26.6% complete 09/18/2019 14:06:28 pygeoprocessing.geoprocessing INFO 49.8% complete 09/18/2019 14:06:33 pygeoprocessing.geoprocessing INFO 83.5% complete 09/18/2019 14:06:35 pygeoprocessing.geoprocessing INFO 100.0%% complete 09/18/2019 14:06:35 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 09/18/2019 14:06:35 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 09/18/2019 14:06:36 natcap.invest.sdr INFO calculate WS factor 09/18/2019 14:06:36 pygeoprocessing.geoprocessing INFO starting stats_worker 09/18/2019 14:06:36 pygeoprocessing.geoprocessing INFO started stats_worker 09/18/2019 14:06:41 pygeoprocessing.geoprocessing INFO 34.0% complete 09/18/2019 14:06:46 pygeoprocessing.geoprocessing INFO 64.1% complete 09/18/2019 14:06:50 pygeoprocessing.geoprocessing INFO 100.0%% complete 09/18/2019 14:06:50 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 09/18/2019 14:06:50 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 09/18/2019 14:06:50 natcap.invest.sdr INFO calculating d_dn 09/18/2019 14:07:00 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO calculate_flow_weights 35.4% complete 09/18/2019 14:07:06 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO calculate_flow_weights 92.7% complete 09/18/2019 14:07:13 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO find_sinks 51.4% complete 09/18/2019 14:07:19 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 372831 09/18/2019 14:07:25 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 795378 09/18/2019 14:07:31 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 850039 09/18/2019 14:07:37 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 862752 09/18/2019 14:07:43 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 881240 09/18/2019 14:07:49 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 893070 09/18/2019 14:07:55 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 917834 09/18/2019 14:08:01 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 935070 09/18/2019 14:08:07 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 952943 09/18/2019 14:08:13 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 972929 09/18/2019 14:08:19 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 995362 09/18/2019 14:08:25 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1013278 09/18/2019 14:08:31 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1038018 09/18/2019 14:08:37 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1057326 09/18/2019 14:08:43 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1074202 09/18/2019 14:08:49 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1090748 09/18/2019 14:08:55 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1105911 09/18/2019 14:09:01 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1119904 09/18/2019 14:09:07 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1134045 09/18/2019 14:09:13 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1150664 09/18/2019 14:09:19 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1167839 09/18/2019 14:09:25 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1178390 09/18/2019 14:09:31 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1188249 09/18/2019 14:09:37 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1195435 09/18/2019 14:09:43 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1198794 09/18/2019 14:09:49 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1203787 09/18/2019 14:09:55 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1208891 09/18/2019 14:10:01 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1210865 09/18/2019 14:10:07 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1211699 09/18/2019 14:10:13 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1209834 09/18/2019 14:10:19 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1207301 09/18/2019 14:10:25 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1202828 09/18/2019 14:10:31 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1198348 09/18/2019 14:10:37 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1195549 09/18/2019 14:10:43 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1187594 09/18/2019 14:10:49 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1182320 09/18/2019 14:10:55 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1169153 09/18/2019 14:11:01 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1161818 09/18/2019 14:11:07 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1148712 09/18/2019 14:11:13 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1135010 09/18/2019 14:11:19 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1118081 09/18/2019 14:11:25 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1099073 09/18/2019 14:11:31 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1084952 09/18/2019 14:11:37 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1071394 09/18/2019 14:11:43 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1052037 09/18/2019 14:11:49 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1034788 09/18/2019 14:11:55 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1015447 09/18/2019 14:12:01 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 994903 09/18/2019 14:12:07 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 974166 09/18/2019 14:12:13 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 951330 09/18/2019 14:12:19 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 930853 09/18/2019 14:12:25 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 899103 09/18/2019 14:12:31 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 875186 09/18/2019 14:12:37 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 851269 09/18/2019 14:12:43 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 825485 09/18/2019 14:12:49 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 800595 09/18/2019 14:12:55 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 777143 09/18/2019 14:13:01 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 754395 09/18/2019 14:13:07 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 729400 09/18/2019 14:13:13 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 703334 09/18/2019 14:13:19 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 678083 09/18/2019 14:13:25 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 653505 09/18/2019 14:13:31 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 630027 09/18/2019 14:13:37 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 606132 09/18/2019 14:13:43 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 585329 09/18/2019 14:13:49 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 562029 09/18/2019 14:13:55 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 538693 09/18/2019 14:14:01 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 517236 09/18/2019 14:14:07 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 495743 09/18/2019 14:14:13 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 474810 09/18/2019 14:14:19 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 454688 09/18/2019 14:14:25 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 435579 09/18/2019 14:14:31 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 417378 09/18/2019 14:14:37 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 399032 09/18/2019 14:14:43 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 381566 09/18/2019 14:14:49 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 364586 09/18/2019 14:14:55 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 348117 09/18/2019 14:15:01 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 330777 09/18/2019 14:15:07 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 316035 09/18/2019 14:15:13 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 300424 09/18/2019 14:15:19 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 285798 09/18/2019 14:15:25 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 272639 09/18/2019 14:15:31 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 260359 09/18/2019 14:15:37 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 248676 09/18/2019 14:15:43 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 236199 09/18/2019 14:15:49 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 225653 09/18/2019 14:15:55 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 214324 09/18/2019 14:16:01 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 206035 09/18/2019 14:16:07 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 196393 09/18/2019 14:16:13 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 186856 09/18/2019 14:16:19 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 178505 09/18/2019 14:16:25 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 170602 09/18/2019 14:16:31 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 162373 09/18/2019 14:16:37 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 154879 09/18/2019 14:16:43 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 147311 09/18/2019 14:16:49 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 139580 09/18/2019 14:16:55 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 132842 09/18/2019 14:17:01 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 124808 09/18/2019 14:17:07 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 116761 09/18/2019 14:17:13 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 108867 09/18/2019 14:17:19 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 100229 09/18/2019 14:17:25 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 90302 09/18/2019 14:17:31 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 64150 09/18/2019 14:17:37 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 9481 09/18/2019 14:17:37 natcap.invest.sdr INFO calculate ic 09/18/2019 14:17:37 pygeoprocessing.geoprocessing INFO starting stats_worker 09/18/2019 14:17:37 pygeoprocessing.geoprocessing INFO started stats_worker 09/18/2019 14:17:42 pygeoprocessing.geoprocessing INFO 30.0% complete 09/18/2019 14:17:47 pygeoprocessing.geoprocessing INFO 61.9% complete 09/18/2019 14:17:51 pygeoprocessing.geoprocessing INFO 100.0%% complete 09/18/2019 14:17:51 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 09/18/2019 14:17:51 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 09/18/2019 14:18:33 natcap.invest.sdr INFO calculate sdr 09/18/2019 14:18:33 pygeoprocessing.geoprocessing INFO starting stats_worker 09/18/2019 14:18:33 pygeoprocessing.geoprocessing INFO started stats_worker 09/18/2019 14:18:38 pygeoprocessing.geoprocessing INFO 34.3% complete 09/18/2019 14:18:43 pygeoprocessing.geoprocessing INFO 67.2% complete 09/18/2019 14:18:47 pygeoprocessing.geoprocessing INFO 100.0%% complete 09/18/2019 14:18:47 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 09/18/2019 14:18:47 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 09/18/2019 14:19:32 natcap.invest.sdr INFO calculate sed export 09/18/2019 14:19:32 pygeoprocessing.geoprocessing INFO starting stats_worker 09/18/2019 14:19:32 pygeoprocessing.geoprocessing INFO started stats_worker 09/18/2019 14:19:37 pygeoprocessing.geoprocessing INFO 28.0% complete 09/18/2019 14:19:42 pygeoprocessing.geoprocessing INFO 52.0% complete 09/18/2019 14:19:47 pygeoprocessing.geoprocessing INFO 87.2% complete 09/18/2019 14:19:49 pygeoprocessing.geoprocessing INFO 100.0%% complete 09/18/2019 14:19:49 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 09/18/2019 14:19:49 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 09/18/2019 14:19:49 natcap.invest.sdr INFO calculate sediment retention index 09/18/2019 14:19:49 pygeoprocessing.geoprocessing INFO starting stats_worker 09/18/2019 14:19:49 pygeoprocessing.geoprocessing INFO started stats_worker 09/18/2019 14:19:54 pygeoprocessing.geoprocessing INFO 22.1% complete 09/18/2019 14:19:59 pygeoprocessing.geoprocessing INFO 40.3% complete 09/18/2019 14:20:04 pygeoprocessing.geoprocessing INFO 64.0% complete 09/18/2019 14:20:09 pygeoprocessing.geoprocessing INFO 93.2% complete 09/18/2019 14:20:10 pygeoprocessing.geoprocessing INFO 100.0%% complete 09/18/2019 14:20:10 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 09/18/2019 14:20:10 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 09/18/2019 14:20:10 natcap.invest.sdr INFO calculate sediment retention 09/18/2019 14:20:10 natcap.invest.sdr INFO calculate S factor 09/18/2019 14:20:10 pygeoprocessing.geoprocessing INFO starting stats_worker 09/18/2019 14:20:10 pygeoprocessing.geoprocessing INFO started stats_worker 09/18/2019 14:20:15 pygeoprocessing.geoprocessing INFO 39.0% complete 09/18/2019 14:20:20 pygeoprocessing.geoprocessing INFO 78.6% complete 09/18/2019 14:20:23 pygeoprocessing.geoprocessing INFO 100.0%% complete 09/18/2019 14:20:23 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 09/18/2019 14:20:23 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 09/18/2019 14:20:23 natcap.invest.sdr INFO calculating d_dn bare soil 09/18/2019 14:20:34 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO calculate_flow_weights 38.2% complete 09/18/2019 14:20:40 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO calculate_flow_weights 89.9% complete 09/18/2019 14:20:47 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO find_sinks 43.2% complete 09/18/2019 14:20:53 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO find_sinks 97.3% complete 09/18/2019 14:20:59 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 751814 09/18/2019 14:21:05 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 855619 09/18/2019 14:21:11 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 868082 09/18/2019 14:21:17 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 896532 09/18/2019 14:21:23 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 938521 09/18/2019 14:21:29 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 961715 09/18/2019 14:21:35 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 989493 09/18/2019 14:21:41 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1011823 09/18/2019 14:21:47 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1035689 09/18/2019 14:21:53 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1055071 09/18/2019 14:21:59 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1071716 09/18/2019 14:22:05 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1089830 09/18/2019 14:22:11 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1105447 09/18/2019 14:22:17 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1121235 09/18/2019 14:22:23 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1135707 09/18/2019 14:22:29 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1151580 09/18/2019 14:22:35 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1159478 09/18/2019 14:22:41 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1171710 09/18/2019 14:22:47 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1183984 09/18/2019 14:22:53 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1192688 09/18/2019 14:22:59 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1197673 09/18/2019 14:23:05 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1198653 09/18/2019 14:23:11 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1204376 09/18/2019 14:23:17 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1207161 09/18/2019 14:23:23 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1211432 09/18/2019 14:23:29 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1209617 09/18/2019 14:23:35 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1208953 09/18/2019 14:23:41 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1202103 09/18/2019 14:23:47 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1195830 09/18/2019 14:23:53 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1187116 09/18/2019 14:23:59 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1180075 09/18/2019 14:24:05 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1166273 09/18/2019 14:24:11 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1157659 09/18/2019 14:24:17 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1138031 09/18/2019 14:24:23 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1119551 09/18/2019 14:24:29 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1095598 09/18/2019 14:24:35 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1078818 09/18/2019 14:24:41 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1057792 09/18/2019 14:24:47 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1037626 09/18/2019 14:24:53 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 1015532 09/18/2019 14:24:59 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 994425 09/18/2019 14:25:05 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 970840 09/18/2019 14:25:11 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 947980 09/18/2019 14:25:17 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 924422 09/18/2019 14:25:23 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 899484 09/18/2019 14:25:29 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 872599 09/18/2019 14:25:35 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 847458 09/18/2019 14:25:41 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 821867 09/18/2019 14:25:47 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 795810 09/18/2019 14:25:53 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 772989 09/18/2019 14:25:59 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 752801 09/18/2019 14:26:05 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 720794 09/18/2019 14:26:11 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 694678 09/18/2019 14:26:17 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 656678 09/18/2019 14:26:23 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 631521 09/18/2019 14:26:29 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 605950 09/18/2019 14:26:35 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 583892 09/18/2019 14:26:41 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 558678 09/18/2019 14:26:47 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 534430 09/18/2019 14:26:53 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 511859 09/18/2019 14:26:59 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 489647 09/18/2019 14:27:05 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 467256 09/18/2019 14:27:11 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 447070 09/18/2019 14:27:17 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 424961 09/18/2019 14:27:23 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 399433 09/18/2019 14:27:29 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 381317 09/18/2019 14:27:35 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 363473 09/18/2019 14:27:41 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 342602 09/18/2019 14:27:47 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 324470 09/18/2019 14:27:53 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 308099 09/18/2019 14:27:59 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 292089 09/18/2019 14:28:05 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 277516 09/18/2019 14:28:11 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 264287 09/18/2019 14:28:17 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 252741 09/18/2019 14:28:23 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 240627 09/18/2019 14:28:29 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 228037 09/18/2019 14:28:35 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 216515 09/18/2019 14:28:41 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 206568 09/18/2019 14:28:47 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 198760 09/18/2019 14:28:53 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 189041 09/18/2019 14:28:59 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 179986 09/18/2019 14:29:05 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 172147 09/18/2019 14:29:11 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 163600 09/18/2019 14:29:17 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 155270 09/18/2019 14:29:23 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 147682 09/18/2019 14:29:29 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 140211 09/18/2019 14:29:35 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 132940 09/18/2019 14:29:41 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 125382 09/18/2019 14:29:47 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 116849 09/18/2019 14:29:53 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 108783 09/18/2019 14:29:59 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 100125 09/18/2019 14:30:05 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 89400 09/18/2019 14:30:11 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 61669 09/18/2019 14:30:17 natcap.invest.pygeoprocessing_0_3_3.routing.routing_core INFO visit_stack on stream distance size: 3496 09/18/2019 14:30:17 natcap.invest.sdr INFO calculating d_up bare soil 09/18/2019 14:30:17 pygeoprocessing.geoprocessing INFO starting stats_worker 09/18/2019 14:30:17 pygeoprocessing.geoprocessing INFO started stats_worker 09/18/2019 14:30:22 pygeoprocessing.geoprocessing INFO 34.2% complete 09/18/2019 14:30:27 pygeoprocessing.geoprocessing INFO 76.2% complete 09/18/2019 14:30:29 pygeoprocessing.geoprocessing INFO 100.0%% complete 09/18/2019 14:30:29 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 09/18/2019 14:30:29 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 09/18/2019 14:30:29 natcap.invest.sdr INFO calculate ic 09/18/2019 14:30:29 pygeoprocessing.geoprocessing INFO starting stats_worker 09/18/2019 14:30:29 pygeoprocessing.geoprocessing INFO started stats_worker 09/18/2019 14:30:34 pygeoprocessing.geoprocessing INFO 48.3% complete 09/18/2019 14:30:38 pygeoprocessing.geoprocessing INFO 100.0%% complete 09/18/2019 14:30:38 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 09/18/2019 14:30:38 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 09/18/2019 14:30:38 pygeoprocessing.geoprocessing INFO starting stats_worker 09/18/2019 14:30:38 pygeoprocessing.geoprocessing INFO started stats_worker 09/18/2019 14:30:43 pygeoprocessing.geoprocessing INFO 70.3% complete 09/18/2019 14:30:45 pygeoprocessing.geoprocessing INFO 100.0%% complete 09/18/2019 14:30:45 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 09/18/2019 14:30:45 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 09/18/2019 14:30:45 pygeoprocessing.geoprocessing INFO starting stats_worker 09/18/2019 14:30:45 pygeoprocessing.geoprocessing INFO started stats_worker 09/18/2019 14:30:50 pygeoprocessing.geoprocessing INFO 33.6% complete 09/18/2019 14:30:55 pygeoprocessing.geoprocessing INFO 63.8% complete 09/18/2019 14:31:00 pygeoprocessing.geoprocessing INFO 99.0% complete 09/18/2019 14:31:01 pygeoprocessing.geoprocessing INFO 100.0%% complete 09/18/2019 14:31:01 pygeoprocessing.geoprocessing INFO signaling stats worker to terminate 09/18/2019 14:31:01 pygeoprocessing.geoprocessing INFO Waiting for raster stats worker result. 09/18/2019 14:31:01 natcap.invest.sdr INFO generating report 09/18/2019 14:31:01 pygeoprocessing.geoprocessing INFO n_workers == 1 so a threadpool is sufficient 09/18/2019 14:31:12 pygeoprocessing.geoprocessing INFO 1 of 1 aligned: clipped_rasteroytkib 09/18/2019 14:31:12 pygeoprocessing.geoprocessing INFO aligned all 1 rasters. 09/18/2019 14:33:27 pygeoprocessing.geoprocessing INFO n_workers == 1 so a threadpool is sufficient 09/18/2019 14:33:48 pygeoprocessing.geoprocessing INFO 1 of 1 aligned: clipped_rasterzdns6v 09/18/2019 14:33:48 pygeoprocessing.geoprocessing INFO aligned all 1 rasters. 09/18/2019 14:36:11 pygeoprocessing.geoprocessing INFO n_workers == 1 so a threadpool is sufficient 09/18/2019 14:36:34 pygeoprocessing.geoprocessing INFO 1 of 1 aligned: clipped_rasterclzark 09/18/2019 14:36:34 pygeoprocessing.geoprocessing INFO aligned all 1 rasters. 09/18/2019 14:38:24 natcap.invest.ui.model ERROR Exception while executing Traceback (most recent call last): File "C:\Users\natcap-servers\jenkins-home\workspace\natcap.invest\label\GCE-windows-1\env\lib\site-packages\natcap\invest\ui\model.py", line 1541, in _logged_target File "C:\Users\natcap-servers\jenkins-home\workspace\natcap.invest\label\GCE-windows-1\env\lib\site-packages\natcap\invest\sdr.py", line 302, in execute File "C:\Users\natcap-servers\jenkins-home\workspace\natcap.invest\label\GCE-windows-1\env\lib\site-packages\natcap\invest\sdr.py", line 926, in _generate_report KeyError: 184 09/18/2019 14:38:24 natcap.invest.ui.model INFO Execution finished 09/18/2019 14:38:24 natcap.invest.utils INFO Elapsed time: 52m 24.95s