2021-09-02 09:45:53,295 utils.prepare_workspace(123) INFO Writing log messages to F:/INVEST/wy/9.2zhang2\InVEST-Hydropower-Water-Yield-log-2021-09-02--09_45_53.txt 2021-09-02 09:45:53,297 model._logged_target(1646) Level 100 Starting model with parameters: Arguments for InVEST natcap.invest.hydropower.hydropower_water_yield 3.9.0: biophysical_table_path F:/北洛河研究区资料/表格数据/wy_biophysical table.csv depth_to_root_rest_layer_path F:/北洛河研究区资料/水源涵养数据8.17/depth1.tif do_scarcity_and_valuation False eto_path F:/北洛河研究区资料/水源涵养数据8.17/pet1.tif lulc_path F:/北洛河研究区资料/水源涵养数据8.17/lulc1.tif n_workers -1 pawc_path F:/北洛河研究区资料/水源涵养数据8.17/awc1.tif precipitation_path F:/北洛河研究区资料/Climate data (5)/1982-2015年逐年年降水-8km/res2000_clipclip1.tif results_suffix seasonality_constant 2 sub_watersheds_path watersheds_path F:/ziliuyu/watersheds_pro1.shp workspace_dir F:/INVEST/wy/9.2zhang2 2021-09-02 09:45:53,300 hydropower_water_yield.execute(269) INFO Validating arguments 2021-09-02 09:45:53,301 validation._wrapped_validate_func(911) DEBUG Using ARG_SPEC for validation 2021-09-02 09:45:53,302 validation._wrapped_validate_func(915) INFO Starting whole-model validation with ARGS_SPEC 2021-09-02 09:45:53,302 validation.timeout(625) DEBUG Starting file checking thread with timeout=5 2021-09-02 09:45:53,314 validation.timeout(637) DEBUG File checking thread completed. 2021-09-02 09:45:53,315 validation.validate(794) DEBUG Provided key do_scarcity_and_valuation does not exist in ARGS_SPEC 2021-09-02 09:45:53,315 validation.timeout(625) DEBUG Starting file checking thread with timeout=5 2021-09-02 09:45:53,384 validation.timeout(637) DEBUG File checking thread completed. 2021-09-02 09:45:53,384 validation.timeout(625) DEBUG Starting file checking thread with timeout=5 2021-09-02 09:45:53,475 validation.timeout(637) DEBUG File checking thread completed. 2021-09-02 09:45:53,475 validation.timeout(625) DEBUG Starting file checking thread with timeout=5 2021-09-02 09:45:53,527 validation.timeout(637) DEBUG File checking thread completed. 2021-09-02 09:45:53,528 validation.timeout(625) DEBUG Starting file checking thread with timeout=5 2021-09-02 09:45:53,568 validation.timeout(637) DEBUG File checking thread completed. 2021-09-02 09:45:53,568 validation.timeout(625) DEBUG Starting file checking thread with timeout=5 2021-09-02 09:45:53,572 validation.timeout(637) DEBUG File checking thread completed. 2021-09-02 09:45:53,573 validation.timeout(625) DEBUG Starting file checking thread with timeout=5 2021-09-02 09:45:53,612 validation.timeout(637) DEBUG File checking thread completed. 2021-09-02 09:45:53,612 validation.timeout(625) DEBUG Starting file checking thread with timeout=5 2021-09-02 09:45:53,829 validation.timeout(637) DEBUG File checking thread completed. 2021-09-02 09:45:54,268 validation._wrapped_validate_func(962) DEBUG Validation warnings: [] 2021-09-02 09:46:00,352 geoprocessing.logger_callback(3400) INFO Warp 88.0% complete F:/INVEST/wy/9.2zhang2\intermediate\eto.tif 2021-09-02 09:46:01,208 geoprocessing.logger_callback(3400) INFO Warp 100.0% complete F:/INVEST/wy/9.2zhang2\intermediate\eto.tif 2021-09-02 09:46:03,477 geoprocessing.align_and_resize_raster_stack(795) INFO 1 of 5 aligned: eto.tif 2021-09-02 09:46:08,619 geoprocessing.logger_callback(3400) INFO Warp 85.0% complete F:/INVEST/wy/9.2zhang2\intermediate\precip.tif 2021-09-02 09:46:09,889 geoprocessing.logger_callback(3400) INFO Warp 100.0% complete F:/INVEST/wy/9.2zhang2\intermediate\precip.tif 2021-09-02 09:46:12,562 geoprocessing.align_and_resize_raster_stack(795) INFO 2 of 5 aligned: precip.tif 2021-09-02 09:46:17,864 geoprocessing.logger_callback(3400) INFO Warp 72.0% complete F:/INVEST/wy/9.2zhang2\intermediate\depth_to_root_rest_layer.tif 2021-09-02 09:46:19,505 geoprocessing.logger_callback(3400) INFO Warp 100.0% complete F:/INVEST/wy/9.2zhang2\intermediate\depth_to_root_rest_layer.tif 2021-09-02 09:46:20,840 geoprocessing.align_and_resize_raster_stack(795) INFO 3 of 5 aligned: depth_to_root_rest_layer.tif 2021-09-02 09:46:26,179 geoprocessing.logger_callback(3400) INFO Warp 88.0% complete F:/INVEST/wy/9.2zhang2\intermediate\pawc.tif 2021-09-02 09:46:26,901 geoprocessing.logger_callback(3400) INFO Warp 100.0% complete F:/INVEST/wy/9.2zhang2\intermediate\pawc.tif 2021-09-02 09:46:29,989 geoprocessing.align_and_resize_raster_stack(795) INFO 4 of 5 aligned: pawc.tif 2021-09-02 09:46:34,701 geoprocessing.align_and_resize_raster_stack(795) INFO 5 of 5 aligned: clipped_lulc.tif 2021-09-02 09:46:34,702 geoprocessing.align_and_resize_raster_stack(797) INFO aligned all 5 rasters. 2021-09-02 09:46:34,960 hydropower_water_yield.execute(405) DEBUG bio_lucodes {1, 2, 3, 4, 5, 6, 7, 255.0} 2021-09-02 09:46:34,960 hydropower_water_yield.execute(453) INFO Reclassifying temp_Kc raster 2021-09-02 09:46:35,006 geoprocessing.raster_calculator(391) INFO starting stats_worker 2021-09-02 09:46:35,009 threading.run(870) DEBUG stats worker PID: 7840 2021-09-02 09:46:35,009 geoprocessing.raster_calculator(397) INFO started stats_worker 2021-09-02 09:46:40,008 geoprocessing.(474) INFO 65.2% complete 2021-09-02 09:46:42,784 geoprocessing.raster_calculator(477) INFO 100.0% complete 2021-09-02 09:46:42,786 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2021-09-02 09:46:46,292 hydropower_water_yield.execute(465) INFO Reclassifying tmp_root raster 2021-09-02 09:46:46,356 geoprocessing.raster_calculator(391) INFO starting stats_worker 2021-09-02 09:46:46,359 threading.run(870) DEBUG stats worker PID: 7840 2021-09-02 09:46:46,359 geoprocessing.raster_calculator(397) INFO started stats_worker 2021-09-02 09:46:51,355 geoprocessing.(474) INFO 72.4% complete 2021-09-02 09:46:53,341 geoprocessing.raster_calculator(477) INFO 100.0% complete 2021-09-02 09:46:53,342 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2021-09-02 09:46:57,012 hydropower_water_yield.execute(479) INFO Reclassifying tmp_veg raster 2021-09-02 09:46:57,091 geoprocessing.raster_calculator(391) INFO starting stats_worker 2021-09-02 09:46:57,095 threading.run(870) DEBUG stats worker PID: 7840 2021-09-02 09:46:57,095 geoprocessing.raster_calculator(397) INFO started stats_worker 2021-09-02 09:47:02,086 geoprocessing.(474) INFO 68.3% complete 2021-09-02 09:47:03,660 geoprocessing.raster_calculator(477) INFO 100.0% complete 2021-09-02 09:47:03,662 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2021-09-02 09:47:05,715 hydropower_water_yield.execute(492) INFO Calculate PET from Ref Evap times Kc 2021-09-02 09:47:05,742 geoprocessing.raster_calculator(391) INFO starting stats_worker 2021-09-02 09:47:05,744 threading.run(870) DEBUG stats worker PID: 7840 2021-09-02 09:47:05,745 geoprocessing.raster_calculator(397) INFO started stats_worker 2021-09-02 09:47:10,742 geoprocessing.(474) INFO 60.0% complete 2021-09-02 09:47:14,736 geoprocessing.raster_calculator(477) INFO 100.0% complete 2021-09-02 09:47:14,737 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2021-09-02 09:47:16,491 hydropower_water_yield.execute(510) DEBUG Performing fractp operation 2021-09-02 09:47:16,666 geoprocessing.raster_calculator(391) INFO starting stats_worker 2021-09-02 09:47:16,669 threading.run(870) DEBUG stats worker PID: 7840 2021-09-02 09:47:16,669 geoprocessing.raster_calculator(397) INFO started stats_worker 2021-09-02 09:47:21,673 geoprocessing.(474) INFO 15.6% complete 2021-09-02 09:47:26,703 geoprocessing.(474) INFO 30.2% complete 2021-09-02 09:47:31,713 geoprocessing.(474) INFO 43.2% complete 2021-09-02 09:47:36,744 geoprocessing.(474) INFO 56.8% complete 2021-09-02 09:47:41,745 geoprocessing.(474) INFO 69.8% complete 2021-09-02 09:47:46,771 geoprocessing.(474) INFO 83.9% complete 2021-09-02 09:47:51,287 geoprocessing.raster_calculator(477) INFO 100.0% complete 2021-09-02 09:47:51,287 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2021-09-02 09:47:53,164 hydropower_water_yield.execute(523) INFO Performing wyield operation 2021-09-02 09:47:53,223 geoprocessing.raster_calculator(391) INFO starting stats_worker 2021-09-02 09:47:53,227 threading.run(870) DEBUG stats worker PID: 7840 2021-09-02 09:47:53,227 geoprocessing.raster_calculator(397) INFO started stats_worker 2021-09-02 09:47:58,225 geoprocessing.(474) INFO 56.0% complete 2021-09-02 09:48:03,233 geoprocessing.(474) INFO 97.9% complete 2021-09-02 09:48:03,476 geoprocessing.raster_calculator(477) INFO 100.0% complete 2021-09-02 09:48:03,476 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2021-09-02 09:48:05,245 hydropower_water_yield.execute(536) DEBUG Performing aet operation 2021-09-02 09:48:05,295 geoprocessing.raster_calculator(391) INFO starting stats_worker 2021-09-02 09:48:05,298 threading.run(870) DEBUG stats worker PID: 7840 2021-09-02 09:48:05,299 geoprocessing.raster_calculator(397) INFO started stats_worker 2021-09-02 09:48:10,293 geoprocessing.(474) INFO 57.6% complete 2021-09-02 09:48:14,620 geoprocessing.raster_calculator(477) INFO 100.0% complete 2021-09-02 09:48:14,621 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2021-09-02 09:48:16,342 geoprocessing.zonal_statistics(1184) DEBUG > 2021-09-02 09:48:22,050 geoprocessing.logger_callback(3400) INFO Warp 66.0% complete C:\Users\zhaoxin\AppData\Local\Temp\clipped_rasterk3rzugow.tif 2021-09-02 09:48:24,564 geoprocessing.logger_callback(3400) INFO Warp 100.0% complete C:\Users\zhaoxin\AppData\Local\Temp\clipped_rasterk3rzugow.tif 2021-09-02 09:48:25,918 geoprocessing.align_and_resize_raster_stack(795) INFO 1 of 1 aligned: clipped_rasterk3rzugow.tif 2021-09-02 09:48:25,919 geoprocessing.align_and_resize_raster_stack(797) INFO aligned all 1 rasters. 2021-09-02 09:48:26,152 geoprocessing.zonal_statistics(1244) INFO build a lookup of aggregate field value to FID 2021-09-02 09:48:26,158 geoprocessing.zonal_statistics(1251) INFO creating disjoint polygon set 2021-09-02 09:48:26,160 geoprocessing.calculate_disjoint_polygon_set(2183) INFO build shapely polygon list 2021-09-02 09:48:26,448 geoprocessing.calculate_disjoint_polygon_set(2204) INFO build shapely rtree index 2021-09-02 09:48:26,465 geoprocessing.calculate_disjoint_polygon_set(2219) INFO poly feature lookup 100.0% complete on watersheds_pro1.shp 2021-09-02 09:48:26,465 geoprocessing.calculate_disjoint_polygon_set(2221) INFO build poly intersection lookup 2021-09-02 09:48:26,547 geoprocessing.calculate_disjoint_polygon_set(2245) INFO poly intersection feature lookup 100.0% complete on watersheds_pro1.shp 2021-09-02 09:48:26,550 geoprocessing.calculate_disjoint_polygon_set(2279) INFO maximal subset build 100.0% complete on watersheds_pro1.shp 2021-09-02 09:48:26,579 geoprocessing.zonal_statistics(1274) INFO processing 6 disjoint polygon sets 2021-09-02 09:48:26,585 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 1 of 6 100.0% processed on watersheds_pro1.shp 2021-09-02 09:48:26,663 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 1 of 6 watersheds_pro1.shp 2021-09-02 09:48:29,392 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 1 of 6 watersheds_pro1.shp 2021-09-02 09:48:34,015 geoprocessing.(1280) INFO zonal stats approximately 33.3% complete on watersheds_pro1.shp 2021-09-02 09:48:34,022 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 2 of 6 100.0% processed on watersheds_pro1.shp 2021-09-02 09:48:34,073 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 2 of 6 watersheds_pro1.shp 2021-09-02 09:48:37,320 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 2 of 6 watersheds_pro1.shp 2021-09-02 09:48:41,457 geoprocessing.(1280) INFO zonal stats approximately 50.0% complete on watersheds_pro1.shp 2021-09-02 09:48:41,467 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 3 of 6 100.0% processed on watersheds_pro1.shp 2021-09-02 09:48:41,522 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 3 of 6 watersheds_pro1.shp 2021-09-02 09:48:44,714 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 3 of 6 watersheds_pro1.shp 2021-09-02 09:48:48,625 geoprocessing.(1280) INFO zonal stats approximately 66.7% complete on watersheds_pro1.shp 2021-09-02 09:48:48,633 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 4 of 6 100.0% processed on watersheds_pro1.shp 2021-09-02 09:48:48,686 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 4 of 6 watersheds_pro1.shp 2021-09-02 09:48:51,787 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 4 of 6 watersheds_pro1.shp 2021-09-02 09:48:55,646 geoprocessing.(1280) INFO zonal stats approximately 83.3% complete on watersheds_pro1.shp 2021-09-02 09:48:55,656 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 5 of 6 100.0% processed on watersheds_pro1.shp 2021-09-02 09:48:55,716 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 5 of 6 watersheds_pro1.shp 2021-09-02 09:48:58,315 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 5 of 6 watersheds_pro1.shp 2021-09-02 09:49:00,896 geoprocessing.(1280) INFO zonal stats approximately 100.0% complete on watersheds_pro1.shp 2021-09-02 09:49:00,898 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 6 of 6 100.0% processed on watersheds_pro1.shp 2021-09-02 09:49:00,932 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 6 of 6 watersheds_pro1.shp 2021-09-02 09:49:04,180 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 6 of 6 watersheds_pro1.shp 2021-09-02 09:49:07,924 geoprocessing.zonal_statistics(1378) DEBUG unset_fids: 0 of 119 2021-09-02 09:49:07,931 geoprocessing.zonal_statistics(1381) DEBUG gt [ 4.2215765e+06 3.0000000e+01 0.0000000e+00 4.0009348e+06 0.0000000e+00 -3.0000000e+01] for ('F:/INVEST/wy/9.2zhang2\\intermediate\\precip.tif', 1) 2021-09-02 09:49:07,932 geoprocessing.zonal_statistics(1460) DEBUG remaining unset_fids: 0 of 119 2021-09-02 09:49:07,932 geoprocessing.zonal_statistics(1468) INFO all done processing polygon sets for watersheds_pro1.shp 2021-09-02 09:49:08,316 geoprocessing.zonal_statistics(1184) DEBUG > 2021-09-02 09:49:14,143 geoprocessing.logger_callback(3400) INFO Warp 75.0% complete C:\Users\zhaoxin\AppData\Local\Temp\clipped_rasterc2ldu_4q.tif 2021-09-02 09:49:15,706 geoprocessing.logger_callback(3400) INFO Warp 100.0% complete C:\Users\zhaoxin\AppData\Local\Temp\clipped_rasterc2ldu_4q.tif 2021-09-02 09:49:18,119 geoprocessing.align_and_resize_raster_stack(795) INFO 1 of 1 aligned: clipped_rasterc2ldu_4q.tif 2021-09-02 09:49:18,120 geoprocessing.align_and_resize_raster_stack(797) INFO aligned all 1 rasters. 2021-09-02 09:49:18,485 geoprocessing.zonal_statistics(1244) INFO build a lookup of aggregate field value to FID 2021-09-02 09:49:18,493 geoprocessing.zonal_statistics(1251) INFO creating disjoint polygon set 2021-09-02 09:49:18,496 geoprocessing.calculate_disjoint_polygon_set(2183) INFO build shapely polygon list 2021-09-02 09:49:18,910 geoprocessing.calculate_disjoint_polygon_set(2204) INFO build shapely rtree index 2021-09-02 09:49:18,933 geoprocessing.calculate_disjoint_polygon_set(2219) INFO poly feature lookup 100.0% complete on watersheds_pro1.shp 2021-09-02 09:49:18,934 geoprocessing.calculate_disjoint_polygon_set(2221) INFO build poly intersection lookup 2021-09-02 09:49:19,078 geoprocessing.calculate_disjoint_polygon_set(2245) INFO poly intersection feature lookup 100.0% complete on watersheds_pro1.shp 2021-09-02 09:49:19,081 geoprocessing.calculate_disjoint_polygon_set(2279) INFO maximal subset build 100.0% complete on watersheds_pro1.shp 2021-09-02 09:49:19,115 geoprocessing.zonal_statistics(1274) INFO processing 6 disjoint polygon sets 2021-09-02 09:49:19,123 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 1 of 6 100.0% processed on watersheds_pro1.shp 2021-09-02 09:49:19,236 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 1 of 6 watersheds_pro1.shp 2021-09-02 09:49:22,377 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 1 of 6 watersheds_pro1.shp 2021-09-02 09:49:26,232 geoprocessing.(1280) INFO zonal stats approximately 33.3% complete on watersheds_pro1.shp 2021-09-02 09:49:26,239 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 2 of 6 100.0% processed on watersheds_pro1.shp 2021-09-02 09:49:26,290 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 2 of 6 watersheds_pro1.shp 2021-09-02 09:49:29,422 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 2 of 6 watersheds_pro1.shp 2021-09-02 09:49:33,188 geoprocessing.(1280) INFO zonal stats approximately 50.0% complete on watersheds_pro1.shp 2021-09-02 09:49:33,195 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 3 of 6 100.0% processed on watersheds_pro1.shp 2021-09-02 09:49:33,243 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 3 of 6 watersheds_pro1.shp 2021-09-02 09:49:36,375 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 3 of 6 watersheds_pro1.shp 2021-09-02 09:49:40,665 geoprocessing.(1280) INFO zonal stats approximately 66.7% complete on watersheds_pro1.shp 2021-09-02 09:49:40,670 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 4 of 6 100.0% processed on watersheds_pro1.shp 2021-09-02 09:49:40,733 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 4 of 6 watersheds_pro1.shp 2021-09-02 09:49:43,951 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 4 of 6 watersheds_pro1.shp 2021-09-02 09:49:48,088 geoprocessing.(1280) INFO zonal stats approximately 83.3% complete on watersheds_pro1.shp 2021-09-02 09:49:48,092 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 5 of 6 100.0% processed on watersheds_pro1.shp 2021-09-02 09:49:48,144 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 5 of 6 watersheds_pro1.shp 2021-09-02 09:49:51,292 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 5 of 6 watersheds_pro1.shp 2021-09-02 09:49:54,885 geoprocessing.(1280) INFO zonal stats approximately 100.0% complete on watersheds_pro1.shp 2021-09-02 09:49:54,887 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 6 of 6 100.0% processed on watersheds_pro1.shp 2021-09-02 09:49:54,938 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 6 of 6 watersheds_pro1.shp 2021-09-02 09:49:57,989 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 6 of 6 watersheds_pro1.shp 2021-09-02 09:50:01,423 geoprocessing.zonal_statistics(1378) DEBUG unset_fids: 0 of 119 2021-09-02 09:50:01,427 geoprocessing.zonal_statistics(1381) DEBUG gt [ 4.2215765e+06 3.0000000e+01 0.0000000e+00 4.0009348e+06 0.0000000e+00 -3.0000000e+01] for ('F:/INVEST/wy/9.2zhang2\\intermediate\\pet.tif', 1) 2021-09-02 09:50:01,428 geoprocessing.zonal_statistics(1460) DEBUG remaining unset_fids: 0 of 119 2021-09-02 09:50:01,428 geoprocessing.zonal_statistics(1468) INFO all done processing polygon sets for watersheds_pro1.shp 2021-09-02 09:50:01,727 geoprocessing.zonal_statistics(1184) DEBUG > 2021-09-02 09:50:07,489 geoprocessing.logger_callback(3400) INFO Warp 75.0% complete C:\Users\zhaoxin\AppData\Local\Temp\clipped_rasterua9h7pag.tif 2021-09-02 09:50:09,194 geoprocessing.logger_callback(3400) INFO Warp 100.0% complete C:\Users\zhaoxin\AppData\Local\Temp\clipped_rasterua9h7pag.tif 2021-09-02 09:50:11,869 geoprocessing.align_and_resize_raster_stack(795) INFO 1 of 1 aligned: clipped_rasterua9h7pag.tif 2021-09-02 09:50:11,870 geoprocessing.align_and_resize_raster_stack(797) INFO aligned all 1 rasters. 2021-09-02 09:50:12,255 geoprocessing.zonal_statistics(1244) INFO build a lookup of aggregate field value to FID 2021-09-02 09:50:12,265 geoprocessing.zonal_statistics(1251) INFO creating disjoint polygon set 2021-09-02 09:50:12,268 geoprocessing.calculate_disjoint_polygon_set(2183) INFO build shapely polygon list 2021-09-02 09:50:12,744 geoprocessing.calculate_disjoint_polygon_set(2204) INFO build shapely rtree index 2021-09-02 09:50:12,778 geoprocessing.calculate_disjoint_polygon_set(2219) INFO poly feature lookup 100.0% complete on watersheds_pro1.shp 2021-09-02 09:50:12,779 geoprocessing.calculate_disjoint_polygon_set(2221) INFO build poly intersection lookup 2021-09-02 09:50:12,945 geoprocessing.calculate_disjoint_polygon_set(2245) INFO poly intersection feature lookup 100.0% complete on watersheds_pro1.shp 2021-09-02 09:50:12,948 geoprocessing.calculate_disjoint_polygon_set(2279) INFO maximal subset build 100.0% complete on watersheds_pro1.shp 2021-09-02 09:50:12,986 geoprocessing.zonal_statistics(1274) INFO processing 6 disjoint polygon sets 2021-09-02 09:50:12,994 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 1 of 6 100.0% processed on watersheds_pro1.shp 2021-09-02 09:50:13,092 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 1 of 6 watersheds_pro1.shp 2021-09-02 09:50:16,512 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 1 of 6 watersheds_pro1.shp 2021-09-02 09:50:19,201 geoprocessing.(1280) INFO zonal stats approximately 33.3% complete on watersheds_pro1.shp 2021-09-02 09:50:19,205 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 2 of 6 100.0% processed on watersheds_pro1.shp 2021-09-02 09:50:19,252 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 2 of 6 watersheds_pro1.shp 2021-09-02 09:50:21,832 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 2 of 6 watersheds_pro1.shp 2021-09-02 09:50:26,177 geoprocessing.(1280) INFO zonal stats approximately 50.0% complete on watersheds_pro1.shp 2021-09-02 09:50:26,183 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 3 of 6 100.0% processed on watersheds_pro1.shp 2021-09-02 09:50:26,233 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 3 of 6 watersheds_pro1.shp 2021-09-02 09:50:29,557 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 3 of 6 watersheds_pro1.shp 2021-09-02 09:50:33,841 geoprocessing.(1280) INFO zonal stats approximately 66.7% complete on watersheds_pro1.shp 2021-09-02 09:50:33,846 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 4 of 6 100.0% processed on watersheds_pro1.shp 2021-09-02 09:50:33,894 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 4 of 6 watersheds_pro1.shp 2021-09-02 09:50:37,053 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 4 of 6 watersheds_pro1.shp 2021-09-02 09:50:41,289 geoprocessing.(1280) INFO zonal stats approximately 83.3% complete on watersheds_pro1.shp 2021-09-02 09:50:41,293 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 5 of 6 100.0% processed on watersheds_pro1.shp 2021-09-02 09:50:41,344 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 5 of 6 watersheds_pro1.shp 2021-09-02 09:50:44,448 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 5 of 6 watersheds_pro1.shp 2021-09-02 09:50:48,517 geoprocessing.(1280) INFO zonal stats approximately 100.0% complete on watersheds_pro1.shp 2021-09-02 09:50:48,519 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 6 of 6 100.0% processed on watersheds_pro1.shp 2021-09-02 09:50:48,572 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 6 of 6 watersheds_pro1.shp 2021-09-02 09:50:50,622 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 6 of 6 watersheds_pro1.shp 2021-09-02 09:50:53,680 geoprocessing.zonal_statistics(1378) DEBUG unset_fids: 0 of 119 2021-09-02 09:50:53,682 geoprocessing.zonal_statistics(1381) DEBUG gt [ 4.2215765e+06 3.0000000e+01 0.0000000e+00 4.0009348e+06 0.0000000e+00 -3.0000000e+01] for ('F:/INVEST/wy/9.2zhang2\\output\\per_pixel\\aet.tif', 1) 2021-09-02 09:50:53,682 geoprocessing.zonal_statistics(1460) DEBUG remaining unset_fids: 0 of 119 2021-09-02 09:50:53,682 geoprocessing.zonal_statistics(1468) INFO all done processing polygon sets for watersheds_pro1.shp 2021-09-02 09:50:54,061 geoprocessing.zonal_statistics(1184) DEBUG > 2021-09-02 09:50:59,776 geoprocessing.logger_callback(3400) INFO Warp 75.0% complete C:\Users\zhaoxin\AppData\Local\Temp\clipped_rasterz3h9t0sk.tif 2021-09-02 09:51:01,408 geoprocessing.logger_callback(3400) INFO Warp 100.0% complete C:\Users\zhaoxin\AppData\Local\Temp\clipped_rasterz3h9t0sk.tif 2021-09-02 09:51:03,876 geoprocessing.align_and_resize_raster_stack(795) INFO 1 of 1 aligned: clipped_rasterz3h9t0sk.tif 2021-09-02 09:51:03,877 geoprocessing.align_and_resize_raster_stack(797) INFO aligned all 1 rasters. 2021-09-02 09:51:04,317 geoprocessing.zonal_statistics(1244) INFO build a lookup of aggregate field value to FID 2021-09-02 09:51:04,323 geoprocessing.zonal_statistics(1251) INFO creating disjoint polygon set 2021-09-02 09:51:04,325 geoprocessing.calculate_disjoint_polygon_set(2183) INFO build shapely polygon list 2021-09-02 09:51:04,843 geoprocessing.calculate_disjoint_polygon_set(2204) INFO build shapely rtree index 2021-09-02 09:51:04,858 geoprocessing.calculate_disjoint_polygon_set(2219) INFO poly feature lookup 100.0% complete on watersheds_pro1.shp 2021-09-02 09:51:04,859 geoprocessing.calculate_disjoint_polygon_set(2221) INFO build poly intersection lookup 2021-09-02 09:51:04,970 geoprocessing.calculate_disjoint_polygon_set(2245) INFO poly intersection feature lookup 100.0% complete on watersheds_pro1.shp 2021-09-02 09:51:04,974 geoprocessing.calculate_disjoint_polygon_set(2279) INFO maximal subset build 100.0% complete on watersheds_pro1.shp 2021-09-02 09:51:05,015 geoprocessing.zonal_statistics(1274) INFO processing 6 disjoint polygon sets 2021-09-02 09:51:05,027 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 1 of 6 100.0% processed on watersheds_pro1.shp 2021-09-02 09:51:05,151 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 1 of 6 watersheds_pro1.shp 2021-09-02 09:51:08,457 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 1 of 6 watersheds_pro1.shp 2021-09-02 09:51:12,466 geoprocessing.(1280) INFO zonal stats approximately 33.3% complete on watersheds_pro1.shp 2021-09-02 09:51:12,473 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 2 of 6 100.0% processed on watersheds_pro1.shp 2021-09-02 09:51:12,525 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 2 of 6 watersheds_pro1.shp 2021-09-02 09:51:15,757 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 2 of 6 watersheds_pro1.shp 2021-09-02 09:51:19,616 geoprocessing.(1280) INFO zonal stats approximately 50.0% complete on watersheds_pro1.shp 2021-09-02 09:51:19,621 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 3 of 6 100.0% processed on watersheds_pro1.shp 2021-09-02 09:51:19,670 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 3 of 6 watersheds_pro1.shp 2021-09-02 09:51:22,959 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 3 of 6 watersheds_pro1.shp 2021-09-02 09:51:27,570 geoprocessing.(1280) INFO zonal stats approximately 66.7% complete on watersheds_pro1.shp 2021-09-02 09:51:27,574 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 4 of 6 100.0% processed on watersheds_pro1.shp 2021-09-02 09:51:27,634 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 4 of 6 watersheds_pro1.shp 2021-09-02 09:51:30,802 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 4 of 6 watersheds_pro1.shp 2021-09-02 09:51:34,807 geoprocessing.(1280) INFO zonal stats approximately 83.3% complete on watersheds_pro1.shp 2021-09-02 09:51:34,812 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 5 of 6 100.0% processed on watersheds_pro1.shp 2021-09-02 09:51:34,862 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 5 of 6 watersheds_pro1.shp 2021-09-02 09:51:37,945 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 5 of 6 watersheds_pro1.shp 2021-09-02 09:51:41,645 geoprocessing.(1280) INFO zonal stats approximately 100.0% complete on watersheds_pro1.shp 2021-09-02 09:51:41,646 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 6 of 6 100.0% processed on watersheds_pro1.shp 2021-09-02 09:51:41,694 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 6 of 6 watersheds_pro1.shp 2021-09-02 09:51:44,843 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 6 of 6 watersheds_pro1.shp 2021-09-02 09:51:48,467 geoprocessing.zonal_statistics(1378) DEBUG unset_fids: 0 of 119 2021-09-02 09:51:48,472 geoprocessing.zonal_statistics(1381) DEBUG gt [ 4.2215765e+06 3.0000000e+01 0.0000000e+00 4.0009348e+06 0.0000000e+00 -3.0000000e+01] for ('F:/INVEST/wy/9.2zhang2\\output\\per_pixel\\wyield.tif', 1) 2021-09-02 09:51:48,472 geoprocessing.zonal_statistics(1460) DEBUG remaining unset_fids: 0 of 119 2021-09-02 09:51:48,472 geoprocessing.zonal_statistics(1468) INFO all done processing polygon sets for watersheds_pro1.shp 2021-09-02 09:51:52,738 model._logged_target(1655) INFO Execution finished 2021-09-02 09:51:52,746 utils.prepare_workspace(129) INFO Elapsed time: 5m 59.44999999999999s