2022-04-09 20:23:01,870 utils.prepare_workspace(123) INFO Writing log messages to D:/fptemp/NDRresult/jh1\InVEST-Nutrient-Delivery-Ratio-Model-(NDR)-log-2022-04-09--20_23_01.txt 2022-04-09 20:23:01,870 model._logged_target(1646) Level 100 Starting model with parameters: Arguments for InVEST natcap.invest.ndr.ndr 3.9.0: biophysical_table_path D:/fptemp/NDR/n_jh1.csv calc_n True calc_p False dem_path D:/fptemp/NDR/fill2.tif k_param 2 lulc_path D:/fptemp/NDR/lulcxin.tif n_workers -1 results_suffix runoff_proxy_path D:/fptemp/NDR/prec30.tif subsurface_critical_length_n 200 subsurface_critical_length_p subsurface_eff_n 0.8 subsurface_eff_p threshold_flow_accumulation 1000 watersheds_path D:/fptemp/watershed330/mian2.shp workspace_dir D:/fptemp/NDRresult/jh1 2022-04-09 20:23:01,886 model._validation_finished(1793) INFO Whole-model validation returned: [] 2022-04-09 20:23:01,886 inputs._validation_finished(1341) INFO Cleaning up validation for . Warnings: []. Valid: True 2022-04-09 20:23:01,886 inputs._validation_finished(1341) INFO Cleaning up validation for . Warnings: []. Valid: True 2022-04-09 20:23:01,886 inputs._validation_finished(1341) INFO Cleaning up validation for . Warnings: []. Valid: True 2022-04-09 20:23:01,886 inputs._validation_finished(1341) INFO Cleaning up validation for . Warnings: []. Valid: True 2022-04-09 20:23:01,886 inputs._validation_finished(1341) INFO Cleaning up validation for . Warnings: []. Valid: True 2022-04-09 20:23:01,886 inputs._validation_finished(1341) INFO Cleaning up validation for . Warnings: []. Valid: True 2022-04-09 20:23:01,886 inputs._validation_finished(1341) INFO Cleaning up validation for . Warnings: []. Valid: True 2022-04-09 20:23:01,886 inputs._validation_finished(1341) INFO Cleaning up validation for . Warnings: []. Valid: True 2022-04-09 20:23:01,886 inputs._validation_finished(1341) INFO Cleaning up validation for . Warnings: []. Valid: True 2022-04-09 20:23:01,886 inputs._validation_finished(1341) INFO Cleaning up validation for . Warnings: []. Valid: True 2022-04-09 20:23:01,886 inputs._validation_finished(1341) INFO Cleaning up validation for . Warnings: []. Valid: True 2022-04-09 20:23:01,886 inputs._validation_finished(1341) INFO Cleaning up validation for . Warnings: []. Valid: True 2022-04-09 20:23:01,886 inputs._validation_finished(1341) INFO Cleaning up validation for . Warnings: []. Valid: True 2022-04-09 20:23:01,886 inputs._validation_finished(1341) INFO Cleaning up validation for . Warnings: []. Valid: True 2022-04-09 20:23:01,886 inputs._validation_finished(1341) INFO Cleaning up validation for . Warnings: []. Valid: True 2022-04-09 20:23:01,902 inputs.run(680) INFO Starting validation thread with target=._wrapped_validate_func at 0x000001FABC4B9048>, args={'workspace_dir': 'D:/fptemp/NDRresult/jh1', 'results_suffix': '', 'dem_path': 'D:/fptemp/NDR/fill2.tif', 'lulc_path': 'D:/fptemp/NDR/lulcxin.tif', 'runoff_proxy_path': 'D:/fptemp/NDR/prec30.tif', 'watersheds_path': 'D:/fptemp/watershed330/mian2.shp', 'biophysical_table_path': 'D:/fptemp/NDR/n_jh1.csv', 'calc_p': False, 'calc_n': True, 'threshold_flow_accumulation': '1000', 'k_param': '2', 'subsurface_critical_length_n': '200', 'subsurface_critical_length_p': '', 'subsurface_eff_n': '0.8', 'subsurface_eff_p': ''}, limit_to=None 2022-04-09 20:23:01,902 validation._wrapped_validate_func(911) DEBUG Using ARG_SPEC for validation 2022-04-09 20:23:01,902 validation._wrapped_validate_func(915) INFO Starting whole-model validation with ARGS_SPEC 2022-04-09 20:23:01,902 validation.timeout(625) DEBUG Starting file checking thread with timeout=5 2022-04-09 20:23:01,902 validation.timeout(637) DEBUG File checking thread completed. 2022-04-09 20:23:01,902 validation.timeout(625) DEBUG Starting file checking thread with timeout=5 2022-04-09 20:23:01,933 validation.timeout(637) DEBUG File checking thread completed. 2022-04-09 20:23:01,933 validation.timeout(625) DEBUG Starting file checking thread with timeout=5 2022-04-09 20:23:01,964 validation.timeout(637) DEBUG File checking thread completed. 2022-04-09 20:23:01,964 validation.timeout(625) DEBUG Starting file checking thread with timeout=5 2022-04-09 20:23:01,995 validation.timeout(637) DEBUG File checking thread completed. 2022-04-09 20:23:01,995 validation.timeout(625) DEBUG Starting file checking thread with timeout=5 2022-04-09 20:23:02,027 validation.timeout(637) DEBUG File checking thread completed. 2022-04-09 20:23:02,027 validation.timeout(625) DEBUG Starting file checking thread with timeout=5 2022-04-09 20:23:02,042 validation.timeout(637) DEBUG File checking thread completed. 2022-04-09 20:23:02,058 ndr.validate(814) DEBUG Starting logging for biophysical table 2022-04-09 20:23:02,058 ndr.validate(837) DEBUG Required keys in CSV: ['lucode', 'load_n', 'eff_n', 'crit_len_n'] 2022-04-09 20:23:02,058 ndr.validate(840) DEBUG Error: None 2022-04-09 20:23:02,058 validation._wrapped_validate_func(962) DEBUG Validation warnings: [] 2022-04-09 20:23:02,058 inputs.run(685) INFO Validation thread returned warnings: [] 2022-04-09 20:23:02,089 model._validation_finished(1793) INFO Whole-model validation returned: [] 2022-04-09 20:23:02,089 inputs._validation_finished(1341) INFO Cleaning up validation for . Warnings: []. Valid: True 2022-04-09 20:23:02,089 inputs._validation_finished(1341) INFO Cleaning up validation for . Warnings: []. Valid: True 2022-04-09 20:23:02,089 inputs._validation_finished(1341) INFO Cleaning up validation for . Warnings: []. Valid: True 2022-04-09 20:23:02,089 inputs._validation_finished(1341) INFO Cleaning up validation for . Warnings: []. Valid: True 2022-04-09 20:23:02,089 inputs._validation_finished(1341) INFO Cleaning up validation for . Warnings: []. Valid: True 2022-04-09 20:23:02,089 inputs._validation_finished(1341) INFO Cleaning up validation for . Warnings: []. Valid: True 2022-04-09 20:23:02,089 inputs._validation_finished(1341) INFO Cleaning up validation for . Warnings: []. Valid: True 2022-04-09 20:23:02,089 inputs._validation_finished(1341) INFO Cleaning up validation for . Warnings: []. Valid: True 2022-04-09 20:23:02,089 inputs._validation_finished(1341) INFO Cleaning up validation for . Warnings: []. Valid: True 2022-04-09 20:23:02,089 inputs._validation_finished(1341) INFO Cleaning up validation for . Warnings: []. Valid: True 2022-04-09 20:23:02,089 inputs._validation_finished(1341) INFO Cleaning up validation for . Warnings: []. Valid: True 2022-04-09 20:23:02,089 inputs._validation_finished(1341) INFO Cleaning up validation for . Warnings: []. Valid: True 2022-04-09 20:23:02,089 inputs._validation_finished(1341) INFO Cleaning up validation for . Warnings: []. Valid: True 2022-04-09 20:23:02,089 inputs._validation_finished(1341) INFO Cleaning up validation for . Warnings: []. Valid: True 2022-04-09 20:23:02,089 inputs._validation_finished(1341) INFO Cleaning up validation for . Warnings: []. Valid: True 2022-04-09 20:23:02,089 model.post_warnings(1078) INFO Posting validation warnings to WMV dialog: [] 2022-04-09 20:23:02,089 model.post_warnings(1078) INFO Posting validation warnings to WMV dialog: [] 2022-04-09 20:23:02,089 model.post_warnings(1078) INFO Posting validation warnings to WMV dialog: [(['Threshold Flow Accumluation'], 'Value does not meet condition value > 0')] 2022-04-09 20:23:02,361 geoprocessing.raster_calculator(391) INFO starting stats_worker 2022-04-09 20:23:02,362 threading.run(870) DEBUG stats worker PID: 9824 2022-04-09 20:23:02,362 geoprocessing.raster_calculator(397) INFO started stats_worker 2022-04-09 20:23:02,415 geoprocessing.raster_calculator(477) INFO 100.0% complete 2022-04-09 20:23:02,416 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2022-04-09 20:23:02,455 geoprocessing.align_and_resize_raster_stack(795) INFO 1 of 3 aligned: aligned_dem.tif 2022-04-09 20:23:02,734 geoprocessing.raster_calculator(391) INFO starting stats_worker 2022-04-09 20:23:02,735 threading.run(870) DEBUG stats worker PID: 9824 2022-04-09 20:23:02,735 geoprocessing.raster_calculator(397) INFO started stats_worker 2022-04-09 20:23:02,777 geoprocessing.raster_calculator(477) INFO 100.0% complete 2022-04-09 20:23:02,778 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2022-04-09 20:23:02,802 geoprocessing.align_and_resize_raster_stack(795) INFO 2 of 3 aligned: aligned_lulc.tif 2022-04-09 20:23:03,292 geoprocessing.raster_calculator(391) INFO starting stats_worker 2022-04-09 20:23:03,294 threading.run(870) DEBUG stats worker PID: 9824 2022-04-09 20:23:03,294 geoprocessing.raster_calculator(397) INFO started stats_worker 2022-04-09 20:23:03,410 geoprocessing.raster_calculator(477) INFO 100.0% complete 2022-04-09 20:23:03,411 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2022-04-09 20:23:03,595 geoprocessing.align_and_resize_raster_stack(795) INFO 3 of 3 aligned: aligned_runoff_proxy.tif 2022-04-09 20:23:03,595 geoprocessing.align_and_resize_raster_stack(797) INFO aligned all 3 rasters. 2022-04-09 20:23:05,122 Task._call(1234) INFO 100.0% complete 2022-04-09 20:23:09,671 Task._call(1234) INFO 100.0% complete 2022-04-09 20:23:09,689 Task._call(1234) DEBUG creating target flow accum raster layer 2022-04-09 20:23:09,888 Task._call(1234) DEBUG creating visited raster layer 2022-04-09 20:23:09,895 Task._call(1234) DEBUG starting search 2022-04-09 20:23:11,915 Task._call(1234) INFO 100.0% complete 2022-04-09 20:23:12,754 Task._call(1234) INFO filter out incomplete divergent streams 2022-04-09 20:23:12,792 Task._call(1234) INFO 100.0% complete 2022-04-09 20:23:13,061 geoprocessing.raster_calculator(391) INFO starting stats_worker 2022-04-09 20:23:13,062 threading.run(870) DEBUG stats worker PID: 9824 2022-04-09 20:23:13,062 geoprocessing.raster_calculator(397) INFO started stats_worker 2022-04-09 20:23:13,171 geoprocessing.raster_calculator(477) INFO 100.0% complete 2022-04-09 20:23:13,171 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2022-04-09 20:23:13,418 geoprocessing.raster_calculator(391) INFO starting stats_worker 2022-04-09 20:23:13,419 threading.run(870) DEBUG stats worker PID: 9824 2022-04-09 20:23:13,419 geoprocessing.raster_calculator(397) INFO started stats_worker 2022-04-09 20:23:13,562 geoprocessing.raster_calculator(477) INFO 100.0% complete 2022-04-09 20:23:13,562 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2022-04-09 20:23:13,734 Task._call(1234) DEBUG creating target flow accum raster layer 2022-04-09 20:23:13,929 Task._call(1234) DEBUG creating visited raster layer 2022-04-09 20:23:13,940 Task._call(1234) DEBUG starting search 2022-04-09 20:23:16,306 Task._call(1234) INFO 100.0% complete 2022-04-09 20:23:16,328 geoprocessing.raster_calculator(391) INFO starting stats_worker 2022-04-09 20:23:16,329 threading.run(870) DEBUG stats worker PID: 9824 2022-04-09 20:23:16,329 geoprocessing.raster_calculator(397) INFO started stats_worker 2022-04-09 20:23:16,749 geoprocessing.raster_calculator(477) INFO 100.0% complete 2022-04-09 20:23:16,750 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2022-04-09 20:23:16,940 geoprocessing.raster_calculator(391) INFO starting stats_worker 2022-04-09 20:23:16,940 threading.run(870) DEBUG stats worker PID: 9824 2022-04-09 20:23:16,940 geoprocessing.raster_calculator(397) INFO started stats_worker 2022-04-09 20:23:17,273 geoprocessing.raster_calculator(477) INFO 100.0% complete 2022-04-09 20:23:17,273 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2022-04-09 20:23:17,481 geoprocessing.raster_calculator(391) INFO starting stats_worker 2022-04-09 20:23:17,482 threading.run(870) DEBUG stats worker PID: 9824 2022-04-09 20:23:17,482 geoprocessing.raster_calculator(397) INFO started stats_worker 2022-04-09 20:23:17,597 geoprocessing.raster_calculator(477) INFO 100.0% complete 2022-04-09 20:23:17,598 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2022-04-09 20:23:19,694 Task._call(1234) INFO 100.0% complete 2022-04-09 20:23:21,342 Task._call(1234) INFO 100.0% complete 2022-04-09 20:23:21,388 geoprocessing.raster_calculator(391) INFO starting stats_worker 2022-04-09 20:23:21,390 threading.run(870) DEBUG stats worker PID: 9824 2022-04-09 20:23:21,390 geoprocessing.raster_calculator(397) INFO started stats_worker 2022-04-09 20:23:21,738 geoprocessing.raster_calculator(477) INFO 100.0% complete 2022-04-09 20:23:21,738 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2022-04-09 20:23:21,917 geoprocessing.raster_calculator(391) INFO starting stats_worker 2022-04-09 20:23:21,918 threading.run(870) DEBUG stats worker PID: 9824 2022-04-09 20:23:21,919 geoprocessing.raster_calculator(397) INFO started stats_worker 2022-04-09 20:23:22,023 geoprocessing.raster_calculator(477) INFO 100.0% complete 2022-04-09 20:23:22,024 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2022-04-09 20:23:22,115 geoprocessing.raster_calculator(391) INFO starting stats_worker 2022-04-09 20:23:22,117 threading.run(870) DEBUG stats worker PID: 9824 2022-04-09 20:23:22,117 geoprocessing.raster_calculator(397) INFO started stats_worker 2022-04-09 20:23:22,315 geoprocessing.raster_calculator(477) INFO 100.0% complete 2022-04-09 20:23:22,315 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2022-04-09 20:23:22,495 geoprocessing.raster_calculator(391) INFO starting stats_worker 2022-04-09 20:23:22,496 threading.run(870) DEBUG stats worker PID: 9824 2022-04-09 20:23:22,496 geoprocessing.raster_calculator(397) INFO started stats_worker 2022-04-09 20:23:22,655 geoprocessing.raster_calculator(477) INFO 100.0% complete 2022-04-09 20:23:22,656 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2022-04-09 20:23:22,845 geoprocessing.raster_calculator(391) INFO starting stats_worker 2022-04-09 20:23:22,846 threading.run(870) DEBUG stats worker PID: 9824 2022-04-09 20:23:22,846 geoprocessing.raster_calculator(397) INFO started stats_worker 2022-04-09 20:23:23,006 geoprocessing.raster_calculator(477) INFO 100.0% complete 2022-04-09 20:23:23,006 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2022-04-09 20:23:23,102 geoprocessing.raster_calculator(391) INFO starting stats_worker 2022-04-09 20:23:23,103 threading.run(870) DEBUG stats worker PID: 9824 2022-04-09 20:23:23,104 geoprocessing.raster_calculator(397) INFO started stats_worker 2022-04-09 20:23:23,190 geoprocessing.raster_calculator(477) INFO 100.0% complete 2022-04-09 20:23:23,190 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2022-04-09 20:23:23,281 geoprocessing.raster_calculator(391) INFO starting stats_worker 2022-04-09 20:23:23,282 threading.run(870) DEBUG stats worker PID: 9824 2022-04-09 20:23:23,282 geoprocessing.raster_calculator(397) INFO started stats_worker 2022-04-09 20:23:23,382 geoprocessing.raster_calculator(477) INFO 100.0% complete 2022-04-09 20:23:23,382 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2022-04-09 20:23:23,480 geoprocessing.raster_calculator(391) INFO starting stats_worker 2022-04-09 20:23:23,482 threading.run(870) DEBUG stats worker PID: 9824 2022-04-09 20:23:23,482 geoprocessing.raster_calculator(397) INFO started stats_worker 2022-04-09 20:23:23,762 geoprocessing.raster_calculator(477) INFO 100.0% complete 2022-04-09 20:23:23,763 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2022-04-09 20:23:26,398 geoprocessing.raster_calculator(391) INFO starting stats_worker 2022-04-09 20:23:26,399 threading.run(870) DEBUG stats worker PID: 9824 2022-04-09 20:23:26,399 geoprocessing.raster_calculator(397) INFO started stats_worker 2022-04-09 20:23:26,597 geoprocessing.raster_calculator(477) INFO 100.0% complete 2022-04-09 20:23:26,598 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2022-04-09 20:23:26,785 geoprocessing.raster_calculator(391) INFO starting stats_worker 2022-04-09 20:23:26,786 threading.run(870) DEBUG stats worker PID: 9824 2022-04-09 20:23:26,786 geoprocessing.raster_calculator(397) INFO started stats_worker 2022-04-09 20:23:27,032 geoprocessing.raster_calculator(477) INFO 100.0% complete 2022-04-09 20:23:27,033 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2022-04-09 20:23:27,229 geoprocessing.raster_calculator(391) INFO starting stats_worker 2022-04-09 20:23:27,229 threading.run(870) DEBUG stats worker PID: 9824 2022-04-09 20:23:27,229 geoprocessing.raster_calculator(397) INFO started stats_worker 2022-04-09 20:23:27,586 geoprocessing.raster_calculator(477) INFO 100.0% complete 2022-04-09 20:23:27,586 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2022-04-09 20:23:27,771 geoprocessing.zonal_statistics(1184) DEBUG > 2022-04-09 20:23:28,114 geoprocessing.align_and_resize_raster_stack(795) INFO 1 of 1 aligned: clipped_rasteri3apesa9.tif 2022-04-09 20:23:28,114 geoprocessing.align_and_resize_raster_stack(797) INFO aligned all 1 rasters. 2022-04-09 20:23:28,118 geoprocessing.zonal_statistics(1244) INFO build a lookup of aggregate field value to FID 2022-04-09 20:23:28,121 geoprocessing.zonal_statistics(1251) INFO creating disjoint polygon set 2022-04-09 20:23:28,122 geoprocessing.calculate_disjoint_polygon_set(2183) INFO build shapely polygon list 2022-04-09 20:23:28,134 geoprocessing.calculate_disjoint_polygon_set(2204) INFO build shapely rtree index 2022-04-09 20:23:28,143 geoprocessing.calculate_disjoint_polygon_set(2219) INFO poly feature lookup 100.0% complete on watershed_results_ndr.shp 2022-04-09 20:23:28,143 geoprocessing.calculate_disjoint_polygon_set(2221) INFO build poly intersection lookup 2022-04-09 20:23:28,244 geoprocessing.calculate_disjoint_polygon_set(2245) INFO poly intersection feature lookup 100.0% complete on watershed_results_ndr.shp 2022-04-09 20:23:28,245 geoprocessing.calculate_disjoint_polygon_set(2279) INFO maximal subset build 100.0% complete on watershed_results_ndr.shp 2022-04-09 20:23:28,265 geoprocessing.zonal_statistics(1274) INFO processing 7 disjoint polygon sets 2022-04-09 20:23:28,268 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 1 of 7 100.0% processed on watershed_results_ndr.shp 2022-04-09 20:23:28,270 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 1 of 7 watershed_results_ndr.shp 2022-04-09 20:23:28,352 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 1 of 7 watershed_results_ndr.shp 2022-04-09 20:23:28,485 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 2 of 7 100.0% processed on watershed_results_ndr.shp 2022-04-09 20:23:28,487 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 2 of 7 watershed_results_ndr.shp 2022-04-09 20:23:28,565 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 2 of 7 watershed_results_ndr.shp 2022-04-09 20:23:28,623 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 3 of 7 100.0% processed on watershed_results_ndr.shp 2022-04-09 20:23:28,625 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 3 of 7 watershed_results_ndr.shp 2022-04-09 20:23:28,709 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 3 of 7 watershed_results_ndr.shp 2022-04-09 20:23:28,768 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 4 of 7 100.0% processed on watershed_results_ndr.shp 2022-04-09 20:23:28,770 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 4 of 7 watershed_results_ndr.shp 2022-04-09 20:23:28,849 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 4 of 7 watershed_results_ndr.shp 2022-04-09 20:23:28,900 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 5 of 7 100.0% processed on watershed_results_ndr.shp 2022-04-09 20:23:28,902 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 5 of 7 watershed_results_ndr.shp 2022-04-09 20:23:28,977 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 5 of 7 watershed_results_ndr.shp 2022-04-09 20:23:29,021 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 6 of 7 100.0% processed on watershed_results_ndr.shp 2022-04-09 20:23:29,023 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 6 of 7 watershed_results_ndr.shp 2022-04-09 20:23:29,099 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 6 of 7 watershed_results_ndr.shp 2022-04-09 20:23:29,140 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 7 of 7 100.0% processed on watershed_results_ndr.shp 2022-04-09 20:23:29,142 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 7 of 7 watershed_results_ndr.shp 2022-04-09 20:23:29,210 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 7 of 7 watershed_results_ndr.shp 2022-04-09 20:23:29,246 geoprocessing.zonal_statistics(1378) DEBUG unset_fids: 0 of 141 2022-04-09 20:23:29,247 geoprocessing.zonal_statistics(1381) DEBUG gt [ 4.4109719e+05 3.0000000e+01 0.0000000e+00 3.8671052e+06 0.0000000e+00 -3.0000000e+01] for ('D:/fptemp/NDRresult/jh1\\n_export.tif', 1) 2022-04-09 20:23:29,247 geoprocessing.zonal_statistics(1460) DEBUG remaining unset_fids: 0 of 141 2022-04-09 20:23:29,247 geoprocessing.zonal_statistics(1468) INFO all done processing polygon sets for watershed_results_ndr.shp 2022-04-09 20:23:29,267 geoprocessing.zonal_statistics(1184) DEBUG > 2022-04-09 20:23:29,601 geoprocessing.align_and_resize_raster_stack(795) INFO 1 of 1 aligned: clipped_raster1e5reg5j.tif 2022-04-09 20:23:29,601 geoprocessing.align_and_resize_raster_stack(797) INFO aligned all 1 rasters. 2022-04-09 20:23:29,606 geoprocessing.zonal_statistics(1244) INFO build a lookup of aggregate field value to FID 2022-04-09 20:23:29,609 geoprocessing.zonal_statistics(1251) INFO creating disjoint polygon set 2022-04-09 20:23:29,610 geoprocessing.calculate_disjoint_polygon_set(2183) INFO build shapely polygon list 2022-04-09 20:23:29,621 geoprocessing.calculate_disjoint_polygon_set(2204) INFO build shapely rtree index 2022-04-09 20:23:29,629 geoprocessing.calculate_disjoint_polygon_set(2219) INFO poly feature lookup 100.0% complete on watershed_results_ndr.shp 2022-04-09 20:23:29,630 geoprocessing.calculate_disjoint_polygon_set(2221) INFO build poly intersection lookup 2022-04-09 20:23:29,731 geoprocessing.calculate_disjoint_polygon_set(2245) INFO poly intersection feature lookup 100.0% complete on watershed_results_ndr.shp 2022-04-09 20:23:29,732 geoprocessing.calculate_disjoint_polygon_set(2279) INFO maximal subset build 100.0% complete on watershed_results_ndr.shp 2022-04-09 20:23:29,751 geoprocessing.zonal_statistics(1274) INFO processing 7 disjoint polygon sets 2022-04-09 20:23:29,755 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 1 of 7 100.0% processed on watershed_results_ndr.shp 2022-04-09 20:23:29,757 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 1 of 7 watershed_results_ndr.shp 2022-04-09 20:23:29,838 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 1 of 7 watershed_results_ndr.shp 2022-04-09 20:23:29,967 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 2 of 7 100.0% processed on watershed_results_ndr.shp 2022-04-09 20:23:29,969 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 2 of 7 watershed_results_ndr.shp 2022-04-09 20:23:30,045 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 2 of 7 watershed_results_ndr.shp 2022-04-09 20:23:30,097 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 3 of 7 100.0% processed on watershed_results_ndr.shp 2022-04-09 20:23:30,099 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 3 of 7 watershed_results_ndr.shp 2022-04-09 20:23:30,181 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 3 of 7 watershed_results_ndr.shp 2022-04-09 20:23:30,239 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 4 of 7 100.0% processed on watershed_results_ndr.shp 2022-04-09 20:23:30,240 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 4 of 7 watershed_results_ndr.shp 2022-04-09 20:23:30,319 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 4 of 7 watershed_results_ndr.shp 2022-04-09 20:23:30,368 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 5 of 7 100.0% processed on watershed_results_ndr.shp 2022-04-09 20:23:30,370 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 5 of 7 watershed_results_ndr.shp 2022-04-09 20:23:30,444 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 5 of 7 watershed_results_ndr.shp 2022-04-09 20:23:30,488 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 6 of 7 100.0% processed on watershed_results_ndr.shp 2022-04-09 20:23:30,490 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 6 of 7 watershed_results_ndr.shp 2022-04-09 20:23:30,564 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 6 of 7 watershed_results_ndr.shp 2022-04-09 20:23:30,606 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 7 of 7 100.0% processed on watershed_results_ndr.shp 2022-04-09 20:23:30,608 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 7 of 7 watershed_results_ndr.shp 2022-04-09 20:23:30,682 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 7 of 7 watershed_results_ndr.shp 2022-04-09 20:23:30,725 geoprocessing.zonal_statistics(1378) DEBUG unset_fids: 0 of 141 2022-04-09 20:23:30,726 geoprocessing.zonal_statistics(1381) DEBUG gt [ 4.4109719e+05 3.0000000e+01 0.0000000e+00 3.8671052e+06 0.0000000e+00 -3.0000000e+01] for ('D:/fptemp/NDRresult/jh1\\intermediate_outputs\\surface_load_n.tif', 1) 2022-04-09 20:23:30,726 geoprocessing.zonal_statistics(1460) DEBUG remaining unset_fids: 0 of 141 2022-04-09 20:23:30,726 geoprocessing.zonal_statistics(1468) INFO all done processing polygon sets for watershed_results_ndr.shp 2022-04-09 20:23:30,742 geoprocessing.zonal_statistics(1184) DEBUG > 2022-04-09 20:23:30,959 geoprocessing.align_and_resize_raster_stack(795) INFO 1 of 1 aligned: clipped_rasterfpr76h1m.tif 2022-04-09 20:23:30,960 geoprocessing.align_and_resize_raster_stack(797) INFO aligned all 1 rasters. 2022-04-09 20:23:30,964 geoprocessing.zonal_statistics(1244) INFO build a lookup of aggregate field value to FID 2022-04-09 20:23:30,967 geoprocessing.zonal_statistics(1251) INFO creating disjoint polygon set 2022-04-09 20:23:30,969 geoprocessing.calculate_disjoint_polygon_set(2183) INFO build shapely polygon list 2022-04-09 20:23:30,982 geoprocessing.calculate_disjoint_polygon_set(2204) INFO build shapely rtree index 2022-04-09 20:23:30,991 geoprocessing.calculate_disjoint_polygon_set(2219) INFO poly feature lookup 100.0% complete on watershed_results_ndr.shp 2022-04-09 20:23:30,992 geoprocessing.calculate_disjoint_polygon_set(2221) INFO build poly intersection lookup 2022-04-09 20:23:31,109 geoprocessing.calculate_disjoint_polygon_set(2245) INFO poly intersection feature lookup 100.0% complete on watershed_results_ndr.shp 2022-04-09 20:23:31,110 geoprocessing.calculate_disjoint_polygon_set(2279) INFO maximal subset build 100.0% complete on watershed_results_ndr.shp 2022-04-09 20:23:31,127 geoprocessing.zonal_statistics(1274) INFO processing 7 disjoint polygon sets 2022-04-09 20:23:31,130 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 1 of 7 100.0% processed on watershed_results_ndr.shp 2022-04-09 20:23:31,132 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 1 of 7 watershed_results_ndr.shp 2022-04-09 20:23:31,213 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 1 of 7 watershed_results_ndr.shp 2022-04-09 20:23:31,291 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 2 of 7 100.0% processed on watershed_results_ndr.shp 2022-04-09 20:23:31,293 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 2 of 7 watershed_results_ndr.shp 2022-04-09 20:23:31,371 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 2 of 7 watershed_results_ndr.shp 2022-04-09 20:23:31,422 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 3 of 7 100.0% processed on watershed_results_ndr.shp 2022-04-09 20:23:31,424 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 3 of 7 watershed_results_ndr.shp 2022-04-09 20:23:31,507 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 3 of 7 watershed_results_ndr.shp 2022-04-09 20:23:31,563 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 4 of 7 100.0% processed on watershed_results_ndr.shp 2022-04-09 20:23:31,565 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 4 of 7 watershed_results_ndr.shp 2022-04-09 20:23:31,644 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 4 of 7 watershed_results_ndr.shp 2022-04-09 20:23:31,695 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 5 of 7 100.0% processed on watershed_results_ndr.shp 2022-04-09 20:23:31,698 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 5 of 7 watershed_results_ndr.shp 2022-04-09 20:23:31,777 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 5 of 7 watershed_results_ndr.shp 2022-04-09 20:23:31,827 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 6 of 7 100.0% processed on watershed_results_ndr.shp 2022-04-09 20:23:31,829 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 6 of 7 watershed_results_ndr.shp 2022-04-09 20:23:31,902 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 6 of 7 watershed_results_ndr.shp 2022-04-09 20:23:31,944 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 7 of 7 100.0% processed on watershed_results_ndr.shp 2022-04-09 20:23:31,946 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 7 of 7 watershed_results_ndr.shp 2022-04-09 20:23:32,015 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 7 of 7 watershed_results_ndr.shp 2022-04-09 20:23:32,050 geoprocessing.zonal_statistics(1378) DEBUG unset_fids: 0 of 141 2022-04-09 20:23:32,051 geoprocessing.zonal_statistics(1381) DEBUG gt [ 4.4109719e+05 3.0000000e+01 0.0000000e+00 3.8671052e+06 0.0000000e+00 -3.0000000e+01] for ('D:/fptemp/NDRresult/jh1\\intermediate_outputs\\sub_load_n.tif', 1) 2022-04-09 20:23:32,051 geoprocessing.zonal_statistics(1460) DEBUG remaining unset_fids: 0 of 141 2022-04-09 20:23:32,051 geoprocessing.zonal_statistics(1468) INFO all done processing polygon sets for watershed_results_ndr.shp 2022-04-09 20:23:32,062 ndr.execute(705) INFO Writing summaries to output shapefile 2022-04-09 20:23:32,076 ndr.execute(710) INFO NDR complete! 2022-04-09 20:23:32,076 ndr.execute(711) INFO _ _ ____ ____ 2022-04-09 20:23:32,077 ndr.execute(712) INFO | \ |"| | _"\U | _"\ u 2022-04-09 20:23:32,078 ndr.execute(713) INFO <| \| |>/| | | |\| |_) |/ 2022-04-09 20:23:32,078 ndr.execute(714) INFO U| |\ |uU| |_| |\| _ < 2022-04-09 20:23:32,078 ndr.execute(715) INFO |_| \_| |____/ u|_| \_\ 2022-04-09 20:23:32,078 ndr.execute(716) INFO || \\,-.|||_ // \\_ 2022-04-09 20:23:32,078 ndr.execute(717) INFO (_") (_/(__)_) (__) (__) 2022-04-09 20:23:32,079 model._logged_target(1655) INFO Execution finished 2022-04-09 20:23:32,080 utils.prepare_workspace(129) INFO Elapsed time: 30.21s