2021-11-25 11:51:24,586 utils.prepare_workspace(123) INFO Writing log messages to C:/Users/tershya/OneDrive - Western Washington University/Research/NDR Workspace/Model 1/Output\InVEST-Nutrient-Delivery-Ratio-Model-(NDR)-log-2021-11-25--11_51_24.txt 2021-11-25 11:51:24,587 model._logged_target(1646) Level 100 Starting model with parameters: Arguments for InVEST natcap.invest.ndr.ndr 3.9.0: biophysical_table_path C:/Users/tershya/OneDrive - Western Washington University/Research/NDR Workspace/Model 1/NDR Model Parameters - Model 1.csv calc_n True calc_p False dem_path C:/Users/tershya/OneDrive - Western Washington University/Research/NDR Workspace/Model 1/DEM_AGREE.tif k_param 2 lulc_path C:/Users/tershya/OneDrive - Western Washington University/Research/NDR Workspace/Model 1/Reclass_Mode5.tif n_workers -1 results_suffix M1 runoff_proxy_path C:/Users/tershya/OneDrive - Western Washington University/Research/NDR Workspace/Model 1/rainfall_2015_gridMET_mm.tif subsurface_critical_length_n 0 subsurface_critical_length_p subsurface_eff_n 0 subsurface_eff_p threshold_flow_accumulation 3000 watersheds_path C:/Users/tershya/OneDrive - Western Washington University/Research/NDR Workspace/Model 1/NRW_Boundary/NRW_Boundary.shp workspace_dir C:/Users/tershya/OneDrive - Western Washington University/Research/NDR Workspace/Model 1/Output 2021-11-25 11:51:24,605 utils.build_lookup_from_csv(528) WARNING Empty or NaN values were found in the table: C:/Users/tershya/OneDrive - Western Washington University/Research/NDR Workspace/Model 1/NDR Model Parameters - Model 1.csv. 2021-11-25 11:51:24,671 utils._log_gdal_errors(80) WARNING [errno 1] Value 2041099877.57999992 of field Shape_Area of feature 0 not successfully written. Possibly due to too larger number with respect to field width 2021-11-25 11:51:31,002 Task._call(1234) INFO 40.7% complete 2021-11-25 11:51:34,107 Task._call(1234) INFO filter out incomplete divergent streams 2021-11-25 11:51:34,370 Task._call(1234) INFO 100.0% complete 2021-11-25 11:51:40,044 Task._call(1234) INFO 31.7% complete 2021-11-25 11:51:46,026 Task._call(1234) INFO 49.8% complete 2021-11-25 11:51:51,156 Task._call(1234) INFO 100.0% complete 2021-11-25 11:51:57,015 Task._call(1234) INFO 31.7% complete 2021-11-25 11:52:03,007 Task._call(1234) INFO 58.8% complete 2021-11-25 11:52:06,279 Task._call(1234) INFO 100.0% complete 2021-11-25 11:52:06,378 geoprocessing.raster_calculator(391) INFO starting stats_worker 2021-11-25 11:52:06,379 threading.run(870) DEBUG stats worker PID: 20548 2021-11-25 11:52:06,380 geoprocessing.raster_calculator(397) INFO started stats_worker 2021-11-25 11:52:09,467 geoprocessing.raster_calculator(477) INFO 100.0% complete 2021-11-25 11:52:09,468 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2021-11-25 11:52:10,869 geoprocessing.raster_calculator(391) INFO starting stats_worker 2021-11-25 11:52:10,869 threading.run(870) DEBUG stats worker PID: 20548 2021-11-25 11:52:10,870 geoprocessing.raster_calculator(397) INFO started stats_worker 2021-11-25 11:52:12,716 geoprocessing.raster_calculator(477) INFO 100.0% complete 2021-11-25 11:52:12,716 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2021-11-25 11:52:13,460 geoprocessing.raster_calculator(391) INFO starting stats_worker 2021-11-25 11:52:13,461 threading.run(870) DEBUG stats worker PID: 20548 2021-11-25 11:52:13,461 geoprocessing.raster_calculator(397) INFO started stats_worker 2021-11-25 11:52:15,394 geoprocessing.raster_calculator(477) INFO 100.0% complete 2021-11-25 11:52:15,394 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2021-11-25 11:52:16,034 geoprocessing.raster_calculator(391) INFO starting stats_worker 2021-11-25 11:52:16,035 threading.run(870) DEBUG stats worker PID: 20548 2021-11-25 11:52:16,035 geoprocessing.raster_calculator(397) INFO started stats_worker 2021-11-25 11:52:19,249 geoprocessing.raster_calculator(477) INFO 100.0% complete 2021-11-25 11:52:19,249 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2021-11-25 11:52:41,593 geoprocessing.raster_calculator(391) INFO starting stats_worker 2021-11-25 11:52:41,594 threading.run(870) DEBUG stats worker PID: 20548 2021-11-25 11:52:41,594 geoprocessing.raster_calculator(397) INFO started stats_worker 2021-11-25 11:52:43,606 geoprocessing.raster_calculator(477) INFO 100.0% complete 2021-11-25 11:52:43,607 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2021-11-25 11:52:44,857 geoprocessing.raster_calculator(391) INFO starting stats_worker 2021-11-25 11:52:44,857 threading.run(870) DEBUG stats worker PID: 20548 2021-11-25 11:52:44,858 geoprocessing.raster_calculator(397) INFO started stats_worker 2021-11-25 11:52:46,662 geoprocessing.raster_calculator(477) INFO 100.0% complete 2021-11-25 11:52:46,662 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2021-11-25 11:52:47,261 geoprocessing.raster_calculator(391) INFO starting stats_worker 2021-11-25 11:52:47,262 threading.run(870) DEBUG stats worker PID: 20548 2021-11-25 11:52:47,262 geoprocessing.raster_calculator(397) INFO started stats_worker 2021-11-25 11:52:50,197 geoprocessing.raster_calculator(477) INFO 100.0% complete 2021-11-25 11:52:50,197 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2021-11-25 11:52:51,471 geoprocessing.zonal_statistics(1184) DEBUG > 2021-11-25 11:52:54,316 geoprocessing.align_and_resize_raster_stack(795) INFO 1 of 1 aligned: clipped_rasterzc_ey3lw.tif 2021-11-25 11:52:54,316 geoprocessing.align_and_resize_raster_stack(797) INFO aligned all 1 rasters. 2021-11-25 11:52:54,324 geoprocessing.zonal_statistics(1244) INFO build a lookup of aggregate field value to FID 2021-11-25 11:52:54,324 geoprocessing.zonal_statistics(1251) INFO creating disjoint polygon set 2021-11-25 11:52:54,325 geoprocessing.calculate_disjoint_polygon_set(2183) INFO build shapely polygon list 2021-11-25 11:52:54,337 geoprocessing.calculate_disjoint_polygon_set(2204) INFO build shapely rtree index 2021-11-25 11:52:54,338 geoprocessing.calculate_disjoint_polygon_set(2219) INFO poly feature lookup 100.0% complete on watershed_results_ndr_M1.shp 2021-11-25 11:52:54,338 geoprocessing.calculate_disjoint_polygon_set(2221) INFO build poly intersection lookup 2021-11-25 11:52:54,339 geoprocessing.calculate_disjoint_polygon_set(2245) INFO poly intersection feature lookup 100.0% complete on watershed_results_ndr_M1.shp 2021-11-25 11:52:54,339 geoprocessing.calculate_disjoint_polygon_set(2279) INFO maximal subset build 100.0% complete on watershed_results_ndr_M1.shp 2021-11-25 11:52:54,353 geoprocessing.zonal_statistics(1274) INFO processing 1 disjoint polygon sets 2021-11-25 11:52:54,354 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 1 of 1 100.0% processed on watershed_results_ndr_M1.shp 2021-11-25 11:52:54,448 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 1 of 1 watershed_results_ndr_M1.shp 2021-11-25 11:52:55,149 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 1 of 1 watershed_results_ndr_M1.shp 2021-11-25 11:52:56,457 geoprocessing.zonal_statistics(1378) DEBUG unset_fids: 0 of 1 2021-11-25 11:52:56,457 geoprocessing.zonal_statistics(1381) DEBUG gt [ 3.4381594e+05 9.9974604e+00 0.0000000e+00 4.1786641e+05 0.0000000e+00 -9.9974604e+00] for ('C:/Users/tershya/OneDrive - Western Washington University/Research/NDR Workspace/Model 1/Output\\n_export_M1.tif', 1) 2021-11-25 11:52:56,458 geoprocessing.zonal_statistics(1460) DEBUG remaining unset_fids: 0 of 1 2021-11-25 11:52:56,458 geoprocessing.zonal_statistics(1468) INFO all done processing polygon sets for watershed_results_ndr_M1.shp 2021-11-25 11:52:56,499 geoprocessing.zonal_statistics(1184) DEBUG > 2021-11-25 11:52:58,533 geoprocessing.align_and_resize_raster_stack(795) INFO 1 of 1 aligned: clipped_rasteruu6ns8sn.tif 2021-11-25 11:52:58,533 geoprocessing.align_and_resize_raster_stack(797) INFO aligned all 1 rasters. 2021-11-25 11:52:58,544 geoprocessing.zonal_statistics(1244) INFO build a lookup of aggregate field value to FID 2021-11-25 11:52:58,545 geoprocessing.zonal_statistics(1251) INFO creating disjoint polygon set 2021-11-25 11:52:58,546 geoprocessing.calculate_disjoint_polygon_set(2183) INFO build shapely polygon list 2021-11-25 11:52:58,553 geoprocessing.calculate_disjoint_polygon_set(2204) INFO build shapely rtree index 2021-11-25 11:52:58,553 geoprocessing.calculate_disjoint_polygon_set(2219) INFO poly feature lookup 100.0% complete on watershed_results_ndr_M1.shp 2021-11-25 11:52:58,554 geoprocessing.calculate_disjoint_polygon_set(2221) INFO build poly intersection lookup 2021-11-25 11:52:58,554 geoprocessing.calculate_disjoint_polygon_set(2245) INFO poly intersection feature lookup 100.0% complete on watershed_results_ndr_M1.shp 2021-11-25 11:52:58,554 geoprocessing.calculate_disjoint_polygon_set(2279) INFO maximal subset build 100.0% complete on watershed_results_ndr_M1.shp 2021-11-25 11:52:58,567 geoprocessing.zonal_statistics(1274) INFO processing 1 disjoint polygon sets 2021-11-25 11:52:58,568 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 1 of 1 100.0% processed on watershed_results_ndr_M1.shp 2021-11-25 11:52:58,588 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 1 of 1 watershed_results_ndr_M1.shp 2021-11-25 11:52:59,291 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 1 of 1 watershed_results_ndr_M1.shp 2021-11-25 11:53:00,463 geoprocessing.zonal_statistics(1378) DEBUG unset_fids: 0 of 1 2021-11-25 11:53:00,463 geoprocessing.zonal_statistics(1381) DEBUG gt [ 3.4381594e+05 9.9974604e+00 0.0000000e+00 4.1786641e+05 0.0000000e+00 -9.9974604e+00] for ('C:/Users/tershya/OneDrive - Western Washington University/Research/NDR Workspace/Model 1/Output\\intermediate_outputs\\surface_load_n_M1.tif', 1) 2021-11-25 11:53:00,463 geoprocessing.zonal_statistics(1460) DEBUG remaining unset_fids: 0 of 1 2021-11-25 11:53:00,463 geoprocessing.zonal_statistics(1468) INFO all done processing polygon sets for watershed_results_ndr_M1.shp 2021-11-25 11:53:00,502 geoprocessing.zonal_statistics(1184) DEBUG > 2021-11-25 11:53:02,233 geoprocessing.align_and_resize_raster_stack(795) INFO 1 of 1 aligned: clipped_rasterchepeqbe.tif 2021-11-25 11:53:02,233 geoprocessing.align_and_resize_raster_stack(797) INFO aligned all 1 rasters. 2021-11-25 11:53:02,242 geoprocessing.zonal_statistics(1244) INFO build a lookup of aggregate field value to FID 2021-11-25 11:53:02,243 geoprocessing.zonal_statistics(1251) INFO creating disjoint polygon set 2021-11-25 11:53:02,244 geoprocessing.calculate_disjoint_polygon_set(2183) INFO build shapely polygon list 2021-11-25 11:53:02,251 geoprocessing.calculate_disjoint_polygon_set(2204) INFO build shapely rtree index 2021-11-25 11:53:02,251 geoprocessing.calculate_disjoint_polygon_set(2219) INFO poly feature lookup 100.0% complete on watershed_results_ndr_M1.shp 2021-11-25 11:53:02,252 geoprocessing.calculate_disjoint_polygon_set(2221) INFO build poly intersection lookup 2021-11-25 11:53:02,252 geoprocessing.calculate_disjoint_polygon_set(2245) INFO poly intersection feature lookup 100.0% complete on watershed_results_ndr_M1.shp 2021-11-25 11:53:02,252 geoprocessing.calculate_disjoint_polygon_set(2279) INFO maximal subset build 100.0% complete on watershed_results_ndr_M1.shp 2021-11-25 11:53:02,264 geoprocessing.zonal_statistics(1274) INFO processing 1 disjoint polygon sets 2021-11-25 11:53:02,264 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 1 of 1 100.0% processed on watershed_results_ndr_M1.shp 2021-11-25 11:53:02,285 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 1 of 1 watershed_results_ndr_M1.shp 2021-11-25 11:53:02,988 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 1 of 1 watershed_results_ndr_M1.shp 2021-11-25 11:53:04,017 geoprocessing.zonal_statistics(1378) DEBUG unset_fids: 0 of 1 2021-11-25 11:53:04,018 geoprocessing.zonal_statistics(1381) DEBUG gt [ 3.4381594e+05 9.9974604e+00 0.0000000e+00 4.1786641e+05 0.0000000e+00 -9.9974604e+00] for ('C:/Users/tershya/OneDrive - Western Washington University/Research/NDR Workspace/Model 1/Output\\intermediate_outputs\\sub_load_n_M1.tif', 1) 2021-11-25 11:53:04,018 geoprocessing.zonal_statistics(1460) DEBUG remaining unset_fids: 0 of 1 2021-11-25 11:53:04,018 geoprocessing.zonal_statistics(1468) INFO all done processing polygon sets for watershed_results_ndr_M1.shp 2021-11-25 11:53:04,047 ndr.execute(705) INFO Writing summaries to output shapefile 2021-11-25 11:53:04,056 utils._log_gdal_errors(80) WARNING [errno 1] Value 2041099877.57999992 of field Shape_Area of feature 0 not successfully written. Possibly due to too larger number with respect to field width 2021-11-25 11:53:04,057 ndr.execute(710) INFO NDR complete! 2021-11-25 11:53:04,057 ndr.execute(711) INFO _ _ ____ ____ 2021-11-25 11:53:04,058 ndr.execute(712) INFO | \ |"| | _"\U | _"\ u 2021-11-25 11:53:04,058 ndr.execute(713) INFO <| \| |>/| | | |\| |_) |/ 2021-11-25 11:53:04,058 ndr.execute(714) INFO U| |\ |uU| |_| |\| _ < 2021-11-25 11:53:04,058 ndr.execute(715) INFO |_| \_| |____/ u|_| \_\ 2021-11-25 11:53:04,058 ndr.execute(716) INFO || \\,-.|||_ // \\_ 2021-11-25 11:53:04,058 ndr.execute(717) INFO (_") (_/(__)_) (__) (__) 2021-11-25 11:53:04,058 model._logged_target(1655) INFO Execution finished 2021-11-25 11:53:04,059 utils.prepare_workspace(129) INFO Elapsed time: 1m 39.47s