2022-04-08 12:10:34,344 utils.prepare_workspace(123) INFO Writing log messages to C:/Users/tershya/OneDrive - Western Washington University/Research/NDR Workspace/TestRainfall_old\InVEST-Nutrient-Delivery-Ratio-Model-(NDR)-log-2022-04-08--12_10_34.txt
2022-04-08 12:10:34,346 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 2/Model2_BiophysicalTable.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 2/Model2_LULC_Nooksack.tif
n_workers                    -1
results_suffix               M2.2015_rainfall.test.OLD.clipped
runoff_proxy_path            C:/Users/tershya/OneDrive - Western Washington University/Research/NDR Workspace/Subwatersheds_Model2/Precipitation/original_ProjectRaster_Re.tif
subsurface_critical_length_n 0
subsurface_critical_length_p 
subsurface_eff_n             0
subsurface_eff_p             
threshold_flow_accumulation  2000
watersheds_path              C:/Users/tershya/OneDrive - Western Washington University/Research/NDR Workspace/Model 2/NRW_Boundary/NRW_Boundary.shp
workspace_dir                C:/Users/tershya/OneDrive - Western Washington University/Research/NDR Workspace/TestRainfall_old

2022-04-08 12:10:34,523 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
2022-04-08 12:10:38,307 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-04-08 12:10:38,308 threading.run(870) DEBUG stats worker PID: 15292
2022-04-08 12:10:38,308 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-8, started daemon 14024)>
2022-04-08 12:10:39,378 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-04-08 12:10:39,379 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-04-08 12:10:40,395 geoprocessing.align_and_resize_raster_stack(795) INFO 1 of 3 aligned: aligned_dem_M2.2015_rainfall.test.OLD.clipped.tif
2022-04-08 12:10:41,903 geoprocessing.mask_raster(3204) WARNING No mask value was passed and target nodata is undefined, defaulting to 0 as the target mask value.
2022-04-08 12:10:41,936 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-04-08 12:10:41,937 threading.run(870) DEBUG stats worker PID: 15292
2022-04-08 12:10:41,937 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-9, started daemon 7832)>
2022-04-08 12:10:42,323 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-04-08 12:10:42,324 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-04-08 12:10:42,525 geoprocessing.align_and_resize_raster_stack(795) INFO 2 of 3 aligned: aligned_lulc_M2.2015_rainfall.test.OLD.clipped.tif
2022-04-08 12:10:46,294 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-04-08 12:10:46,295 threading.run(870) DEBUG stats worker PID: 15292
2022-04-08 12:10:46,295 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-10, started daemon 9544)>
2022-04-08 12:10:47,558 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-04-08 12:10:47,558 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-04-08 12:10:49,052 geoprocessing.align_and_resize_raster_stack(795) INFO 3 of 3 aligned: aligned_runoff_proxy_M2.2015_rainfall.test.OLD.clipped.tif
2022-04-08 12:10:49,052 geoprocessing.align_and_resize_raster_stack(797) INFO aligned all 3 rasters.
2022-04-08 12:10:55,025 Task._call(1234) INFO 27.2% complete
2022-04-08 12:11:01,032 Task._call(1234) INFO 58.8% complete
2022-04-08 12:11:04,314 Task._call(1234) INFO 100.0% complete
2022-04-08 12:11:10,144 Task._call(1234) INFO 18.1% complete
2022-04-08 12:11:16,015 Task._call(1234) INFO 36.2% complete
2022-04-08 12:11:22,022 Task._call(1234) INFO 45.3% complete
2022-04-08 12:11:28,141 Task._call(1234) INFO 100.0% complete
2022-04-08 12:11:28,156 Task._call(1234) DEBUG creating target flow accum raster layer
2022-04-08 12:11:29,820 Task._call(1234) DEBUG creating visited raster layer
2022-04-08 12:11:29,836 Task._call(1234) DEBUG starting search
2022-04-08 12:11:34,000 Task._call(1234) INFO mfd flow accum 11.0% complete
2022-04-08 12:11:40,005 Task._call(1234) INFO mfd flow accum 28.8% complete
2022-04-08 12:11:46,000 Task._call(1234) INFO 58.8% complete
2022-04-08 12:11:46,904 Task._call(1234) INFO 100.0% complete
2022-04-08 12:11:53,000 Task._call(1234) INFO 45.3% complete
2022-04-08 12:11:55,812 Task._call(1234) INFO filter out incomplete divergent streams
2022-04-08 12:11:56,092 Task._call(1234) INFO 100.0% complete
2022-04-08 12:11:58,470 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-04-08 12:11:58,470 threading.run(870) DEBUG stats worker PID: 15292
2022-04-08 12:11:58,471 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-11, started daemon 20512)>
2022-04-08 12:11:59,411 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-04-08 12:11:59,411 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-04-08 12:12:01,505 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-04-08 12:12:01,505 threading.run(870) DEBUG stats worker PID: 15292
2022-04-08 12:12:01,505 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-12, started daemon 2400)>
2022-04-08 12:12:02,900 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-04-08 12:12:02,901 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-04-08 12:12:03,503 Task._call(1234) DEBUG creating target flow accum raster layer
2022-04-08 12:12:05,162 Task._call(1234) DEBUG creating visited raster layer
2022-04-08 12:12:05,182 Task._call(1234) DEBUG starting search
2022-04-08 12:12:09,000 Task._call(1234) INFO mfd flow accum 8.7% complete
2022-04-08 12:12:15,008 Task._call(1234) INFO mfd flow accum 25.3% complete
2022-04-08 12:12:21,000 Task._call(1234) INFO mfd flow accum 40.8% complete
2022-04-08 12:12:23,784 Task._call(1234) INFO 100.0% complete
2022-04-08 12:12:23,836 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-04-08 12:12:23,837 threading.run(870) DEBUG stats worker PID: 15292
2022-04-08 12:12:23,837 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-13, started daemon 13320)>
2022-04-08 12:12:27,201 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-04-08 12:12:27,202 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-04-08 12:12:28,454 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-04-08 12:12:28,455 threading.run(870) DEBUG stats worker PID: 15292
2022-04-08 12:12:28,455 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-14, started daemon 23076)>
2022-04-08 12:12:30,978 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-04-08 12:12:30,979 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-04-08 12:12:32,295 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-04-08 12:12:32,295 threading.run(870) DEBUG stats worker PID: 15292
2022-04-08 12:12:32,296 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-15, started daemon 152)>
2022-04-08 12:12:33,332 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-04-08 12:12:33,333 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-04-08 12:12:40,032 Task._call(1234) INFO 31.7% complete
2022-04-08 12:12:46,009 Task._call(1234) INFO 49.8% complete
2022-04-08 12:12:50,839 Task._call(1234) INFO 100.0% complete
2022-04-08 12:12:56,050 Task._call(1234) INFO 31.7% complete
2022-04-08 12:13:02,030 Task._call(1234) INFO 63.4% complete
2022-04-08 12:13:04,749 Task._call(1234) INFO 100.0% complete
2022-04-08 12:13:04,793 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-04-08 12:13:04,795 threading.run(870) DEBUG stats worker PID: 15292
2022-04-08 12:13:04,795 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-16, started daemon 20876)>
2022-04-08 12:13:07,493 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-04-08 12:13:07,494 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-04-08 12:13:08,783 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-04-08 12:13:08,784 threading.run(870) DEBUG stats worker PID: 15292
2022-04-08 12:13:08,785 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-17, started daemon 23444)>
2022-04-08 12:13:09,908 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-04-08 12:13:09,908 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-04-08 12:13:10,546 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-04-08 12:13:10,546 threading.run(870) DEBUG stats worker PID: 15292
2022-04-08 12:13:10,546 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-18, started daemon 21976)>
2022-04-08 12:13:12,373 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-04-08 12:13:12,373 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-04-08 12:13:13,106 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-04-08 12:13:13,107 threading.run(870) DEBUG stats worker PID: 15292
2022-04-08 12:13:13,107 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-19, started daemon 22288)>
2022-04-08 12:13:15,430 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-04-08 12:13:15,430 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-04-08 12:13:16,096 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-04-08 12:13:16,096 threading.run(870) DEBUG stats worker PID: 15292
2022-04-08 12:13:16,097 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-20, started daemon 21672)>
2022-04-08 12:13:18,338 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-04-08 12:13:18,339 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-04-08 12:13:18,905 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-04-08 12:13:18,905 threading.run(870) DEBUG stats worker PID: 15292
2022-04-08 12:13:18,906 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-21, started daemon 14424)>
2022-04-08 12:13:20,687 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-04-08 12:13:20,688 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-04-08 12:13:21,316 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-04-08 12:13:21,317 threading.run(870) DEBUG stats worker PID: 15292
2022-04-08 12:13:21,317 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-22, started daemon 20972)>
2022-04-08 12:13:22,998 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-04-08 12:13:22,999 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-04-08 12:13:23,567 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-04-08 12:13:23,568 threading.run(870) DEBUG stats worker PID: 15292
2022-04-08 12:13:23,568 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-23, started daemon 3476)>
2022-04-08 12:13:26,336 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-04-08 12:13:26,337 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-04-08 12:13:47,296 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-04-08 12:13:47,297 threading.run(870) DEBUG stats worker PID: 15292
2022-04-08 12:13:47,297 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-24, started daemon 21920)>
2022-04-08 12:13:48,929 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-04-08 12:13:48,930 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-04-08 12:13:50,186 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-04-08 12:13:50,187 threading.run(870) DEBUG stats worker PID: 15292
2022-04-08 12:13:50,187 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-25, started daemon 20788)>
2022-04-08 12:13:50,193 warnings._showwarnmsg(110) WARNING C:\Users\tershya\OneDrive - Western Washington University\Research\NDR Program\InVEST_3.9.0_x64\invest-3-x64\natcap\invest\ndr\ndr.py:1280: RuntimeWarning: divide by zero encountered in true_divide

2022-04-08 12:13:50,197 warnings._showwarnmsg(110) WARNING C:\Users\tershya\OneDrive - Western Washington University\Research\NDR Program\InVEST_3.9.0_x64\invest-3-x64\natcap\invest\ndr\ndr.py:1280: RuntimeWarning: invalid value encountered in true_divide

2022-04-08 12:13:52,442 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-04-08 12:13:52,443 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-04-08 12:13:53,038 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-04-08 12:13:53,038 threading.run(870) DEBUG stats worker PID: 15292
2022-04-08 12:13:53,039 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-26, started daemon 21868)>
2022-04-08 12:13:56,079 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-04-08 12:13:56,079 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-04-08 12:13:57,401 geoprocessing.zonal_statistics(1184) DEBUG <osgeo.gdal.Dataset; proxy of <Swig Object of type 'GDALDatasetShadow *' at 0x0000018F217AF7E0> >
2022-04-08 12:14:00,311 geoprocessing.align_and_resize_raster_stack(795) INFO 1 of 1 aligned: clipped_rasterq35tmppx.tif
2022-04-08 12:14:00,312 geoprocessing.align_and_resize_raster_stack(797) INFO aligned all 1 rasters.
2022-04-08 12:14:00,319 geoprocessing.zonal_statistics(1244) INFO build a lookup of aggregate field value to FID
2022-04-08 12:14:00,320 geoprocessing.zonal_statistics(1251) INFO creating disjoint polygon set
2022-04-08 12:14:00,321 geoprocessing.calculate_disjoint_polygon_set(2183) INFO build shapely polygon list
2022-04-08 12:14:00,328 geoprocessing.calculate_disjoint_polygon_set(2204) INFO build shapely rtree index
2022-04-08 12:14:00,329 geoprocessing.calculate_disjoint_polygon_set(2219) INFO poly feature lookup 100.0% complete on watershed_results_ndr_M2.2015_rainfall.test.OLD.clipped.shp
2022-04-08 12:14:00,329 geoprocessing.calculate_disjoint_polygon_set(2221) INFO build poly intersection lookup
2022-04-08 12:14:00,329 geoprocessing.calculate_disjoint_polygon_set(2245) INFO poly intersection feature lookup 100.0% complete on watershed_results_ndr_M2.2015_rainfall.test.OLD.clipped.shp
2022-04-08 12:14:00,329 geoprocessing.calculate_disjoint_polygon_set(2279) INFO maximal subset build 100.0% complete on watershed_results_ndr_M2.2015_rainfall.test.OLD.clipped.shp
2022-04-08 12:14:00,341 geoprocessing.zonal_statistics(1274) INFO processing 1 disjoint polygon sets
2022-04-08 12:14:00,342 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 1 of 1 100.0% processed on watershed_results_ndr_M2.2015_rainfall.test.OLD.clipped.shp
2022-04-08 12:14:00,356 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 1 of 1 watershed_results_ndr_M2.2015_rainfall.test.OLD.clipped.shp
2022-04-08 12:14:01,055 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 1 of 1 watershed_results_ndr_M2.2015_rainfall.test.OLD.clipped.shp
2022-04-08 12:14:02,371 geoprocessing.zonal_statistics(1378) DEBUG unset_fids: 0 of 1 
2022-04-08 12:14:02,371 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/TestRainfall_old\\n_export_M2.2015_rainfall.test.OLD.clipped.tif', 1)
2022-04-08 12:14:02,371 geoprocessing.zonal_statistics(1460) DEBUG remaining unset_fids: 0 of 1 
2022-04-08 12:14:02,371 geoprocessing.zonal_statistics(1468) INFO all done processing polygon sets for watershed_results_ndr_M2.2015_rainfall.test.OLD.clipped.shp
2022-04-08 12:14:02,408 geoprocessing.zonal_statistics(1184) DEBUG <osgeo.gdal.Dataset; proxy of <Swig Object of type 'GDALDatasetShadow *' at 0x0000018F217AF8D0> >
2022-04-08 12:14:04,311 geoprocessing.align_and_resize_raster_stack(795) INFO 1 of 1 aligned: clipped_rasterqw4zarc1.tif
2022-04-08 12:14:04,312 geoprocessing.align_and_resize_raster_stack(797) INFO aligned all 1 rasters.
2022-04-08 12:14:04,320 geoprocessing.zonal_statistics(1244) INFO build a lookup of aggregate field value to FID
2022-04-08 12:14:04,320 geoprocessing.zonal_statistics(1251) INFO creating disjoint polygon set
2022-04-08 12:14:04,321 geoprocessing.calculate_disjoint_polygon_set(2183) INFO build shapely polygon list
2022-04-08 12:14:04,329 geoprocessing.calculate_disjoint_polygon_set(2204) INFO build shapely rtree index
2022-04-08 12:14:04,330 geoprocessing.calculate_disjoint_polygon_set(2219) INFO poly feature lookup 100.0% complete on watershed_results_ndr_M2.2015_rainfall.test.OLD.clipped.shp
2022-04-08 12:14:04,330 geoprocessing.calculate_disjoint_polygon_set(2221) INFO build poly intersection lookup
2022-04-08 12:14:04,330 geoprocessing.calculate_disjoint_polygon_set(2245) INFO poly intersection feature lookup 100.0% complete on watershed_results_ndr_M2.2015_rainfall.test.OLD.clipped.shp
2022-04-08 12:14:04,330 geoprocessing.calculate_disjoint_polygon_set(2279) INFO maximal subset build 100.0% complete on watershed_results_ndr_M2.2015_rainfall.test.OLD.clipped.shp
2022-04-08 12:14:04,344 geoprocessing.zonal_statistics(1274) INFO processing 1 disjoint polygon sets
2022-04-08 12:14:04,345 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 1 of 1 100.0% processed on watershed_results_ndr_M2.2015_rainfall.test.OLD.clipped.shp
2022-04-08 12:14:04,361 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 1 of 1 watershed_results_ndr_M2.2015_rainfall.test.OLD.clipped.shp
2022-04-08 12:14:05,138 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 1 of 1 watershed_results_ndr_M2.2015_rainfall.test.OLD.clipped.shp
2022-04-08 12:14:06,083 geoprocessing.zonal_statistics(1378) DEBUG unset_fids: 0 of 1 
2022-04-08 12:14:06,083 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/TestRainfall_old\\intermediate_outputs\\surface_load_n_M2.2015_rainfall.test.OLD.clipped.tif', 1)
2022-04-08 12:14:06,083 geoprocessing.zonal_statistics(1460) DEBUG remaining unset_fids: 0 of 1 
2022-04-08 12:14:06,083 geoprocessing.zonal_statistics(1468) INFO all done processing polygon sets for watershed_results_ndr_M2.2015_rainfall.test.OLD.clipped.shp
2022-04-08 12:14:06,156 geoprocessing.zonal_statistics(1184) DEBUG <osgeo.gdal.Dataset; proxy of <Swig Object of type 'GDALDatasetShadow *' at 0x0000018F217AF330> >
2022-04-08 12:14:08,038 geoprocessing.align_and_resize_raster_stack(795) INFO 1 of 1 aligned: clipped_rastertpple4vs.tif
2022-04-08 12:14:08,039 geoprocessing.align_and_resize_raster_stack(797) INFO aligned all 1 rasters.
2022-04-08 12:14:08,046 geoprocessing.zonal_statistics(1244) INFO build a lookup of aggregate field value to FID
2022-04-08 12:14:08,046 geoprocessing.zonal_statistics(1251) INFO creating disjoint polygon set
2022-04-08 12:14:08,047 geoprocessing.calculate_disjoint_polygon_set(2183) INFO build shapely polygon list
2022-04-08 12:14:08,053 geoprocessing.calculate_disjoint_polygon_set(2204) INFO build shapely rtree index
2022-04-08 12:14:08,054 geoprocessing.calculate_disjoint_polygon_set(2219) INFO poly feature lookup 100.0% complete on watershed_results_ndr_M2.2015_rainfall.test.OLD.clipped.shp
2022-04-08 12:14:08,054 geoprocessing.calculate_disjoint_polygon_set(2221) INFO build poly intersection lookup
2022-04-08 12:14:08,055 geoprocessing.calculate_disjoint_polygon_set(2245) INFO poly intersection feature lookup 100.0% complete on watershed_results_ndr_M2.2015_rainfall.test.OLD.clipped.shp
2022-04-08 12:14:08,055 geoprocessing.calculate_disjoint_polygon_set(2279) INFO maximal subset build 100.0% complete on watershed_results_ndr_M2.2015_rainfall.test.OLD.clipped.shp
2022-04-08 12:14:08,067 geoprocessing.zonal_statistics(1274) INFO processing 1 disjoint polygon sets
2022-04-08 12:14:08,067 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 1 of 1 100.0% processed on watershed_results_ndr_M2.2015_rainfall.test.OLD.clipped.shp
2022-04-08 12:14:08,082 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 1 of 1 watershed_results_ndr_M2.2015_rainfall.test.OLD.clipped.shp
2022-04-08 12:14:08,804 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 1 of 1 watershed_results_ndr_M2.2015_rainfall.test.OLD.clipped.shp
2022-04-08 12:14:09,727 geoprocessing.zonal_statistics(1378) DEBUG unset_fids: 0 of 1 
2022-04-08 12:14:09,727 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/TestRainfall_old\\intermediate_outputs\\sub_load_n_M2.2015_rainfall.test.OLD.clipped.tif', 1)
2022-04-08 12:14:09,728 geoprocessing.zonal_statistics(1460) DEBUG remaining unset_fids: 0 of 1 
2022-04-08 12:14:09,728 geoprocessing.zonal_statistics(1468) INFO all done processing polygon sets for watershed_results_ndr_M2.2015_rainfall.test.OLD.clipped.shp
2022-04-08 12:14:09,754 ndr.execute(705) INFO Writing summaries to output shapefile
2022-04-08 12:14:09,762 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
2022-04-08 12:14:09,764 ndr.execute(710) INFO NDR complete!
2022-04-08 12:14:09,764 ndr.execute(711) INFO   _   _    ____    ____     
2022-04-08 12:14:09,764 ndr.execute(712) INFO  | \ |"|  |  _"\U |  _"\ u  
2022-04-08 12:14:09,764 ndr.execute(713) INFO <|  \| |>/| | | |\| |_) |/  
2022-04-08 12:14:09,764 ndr.execute(714) INFO U| |\  |uU| |_| |\|  _ <    
2022-04-08 12:14:09,765 ndr.execute(715) INFO  |_| \_|  |____/ u|_| \_\   
2022-04-08 12:14:09,765 ndr.execute(716) INFO  ||   \\,-.|||_   //   \\_  
2022-04-08 12:14:09,765 ndr.execute(717) INFO  (_")  (_/(__)_) (__)  (__) 
2022-04-08 12:14:09,765 model._logged_target(1655) INFO Execution finished
2022-04-08 12:14:09,766 utils.prepare_workspace(129) INFO Elapsed time: 3m 35.41999999999999s