2020-04-17 11:53:55,279 utils.prepare_workspace(115) INFO Writing log messages to H:/sattalite/thesis/mr/scenarion\InVEST-Hydropower-Water-Yield-log-2020-04-17--11_53_55.txt 2020-04-17 11:53:55,288 model._logged_target(1642) Level 100 Starting model with parameters: Arguments for InVEST natcap.invest.hydropower.hydropower_water_yield 3.8.0: biophysical_table_path H:/sattalite/thesis/mr/scenarion/lulcsvc.scv.csv demand_table_path H:/sattalite/thesis/mr/scenarion/demand.csv depth_to_root_rest_layer_path H:/sattalite/thesis/mr/scenarion/rootdepth.tif do_scarcity_and_valuation True eto_path H:/sattalite/thesis/mr/scenarion/evapofinal.tif lulc_path H:/sattalite/thesis/mr/scenarion/mosaic1.tif n_workers -1 pawc_path H:/sattalite/thesis/mr/scenarion/pawcnew1.tif precipitation_path H:/sattalite/thesis/mr/scenarion/precipitation.tif results_suffix water12 seasonality_constant 20 sub_watersheds_path H:/sattalite/thesis/mr/scenarion/subwatershed.shp valuation_table_path watersheds_path H:/sattalite/thesis/mr/scenarion/watershed.shp workspace_dir H:/sattalite/thesis/mr/scenarion 2020-04-17 11:53:55,291 hydropower_water_yield.execute(271) INFO Validating arguments 2020-04-17 11:53:55,293 validation._wrapped_validate_func(839) DEBUG Using ARG_SPEC for validation 2020-04-17 11:53:55,293 validation._wrapped_validate_func(843) INFO Starting whole-model validation with ARGS_SPEC 2020-04-17 11:53:56,645 validation._wrapped_validate_func(887) DEBUG Validation warnings: [] 2020-04-17 11:54:02,523 geoprocessing.logger_callback(3239) INFO Warp 53.0% complete H:/sattalite/thesis/mr/scenarion\intermediate\eto_water12.tif 2020-04-17 11:54:06,242 geoprocessing.logger_callback(3239) INFO Warp 100.0% complete H:/sattalite/thesis/mr/scenarion\intermediate\eto_water12.tif 2020-04-17 11:54:08,597 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 5 aligned: eto_water12.tif 2020-04-17 11:54:13,703 geoprocessing.logger_callback(3239) INFO Warp 38.0% complete H:/sattalite/thesis/mr/scenarion\intermediate\precip_water12.tif 2020-04-17 11:54:18,770 geoprocessing.logger_callback(3239) INFO Warp 64.0% complete H:/sattalite/thesis/mr/scenarion\intermediate\precip_water12.tif 2020-04-17 11:54:24,342 geoprocessing.logger_callback(3239) INFO Warp 88.0% complete H:/sattalite/thesis/mr/scenarion\intermediate\precip_water12.tif 2020-04-17 11:54:25,860 geoprocessing.logger_callback(3239) INFO Warp 100.0% complete H:/sattalite/thesis/mr/scenarion\intermediate\precip_water12.tif 2020-04-17 11:54:30,275 geoprocessing.align_and_resize_raster_stack(752) INFO 2 of 5 aligned: precip_water12.tif 2020-04-17 11:54:35,338 geoprocessing.logger_callback(3239) INFO Warp 72.0% complete H:/sattalite/thesis/mr/scenarion\intermediate\depth_to_root_rest_layer_water12.tif 2020-04-17 11:54:36,633 geoprocessing.logger_callback(3239) INFO Warp 100.0% complete H:/sattalite/thesis/mr/scenarion\intermediate\depth_to_root_rest_layer_water12.tif 2020-04-17 11:54:38,367 geoprocessing.align_and_resize_raster_stack(752) INFO 3 of 5 aligned: depth_to_root_rest_layer_water12.tif 2020-04-17 11:54:43,407 geoprocessing.logger_callback(3239) INFO Warp 43.0% complete H:/sattalite/thesis/mr/scenarion\intermediate\pawc_water12.tif 2020-04-17 11:54:48,414 geoprocessing.logger_callback(3239) INFO Warp 70.0% complete H:/sattalite/thesis/mr/scenarion\intermediate\pawc_water12.tif 2020-04-17 11:54:52,666 geoprocessing.logger_callback(3239) INFO Warp 100.0% complete H:/sattalite/thesis/mr/scenarion\intermediate\pawc_water12.tif 2020-04-17 11:54:55,114 geoprocessing.align_and_resize_raster_stack(752) INFO 4 of 5 aligned: pawc_water12.tif 2020-04-17 11:55:02,105 geoprocessing.logger_callback(3239) INFO Warp 50.0% complete H:/sattalite/thesis/mr/scenarion\intermediate\clipped_lulc_water12.tif 2020-04-17 11:55:04,587 geoprocessing.logger_callback(3239) INFO Warp 100.0% complete H:/sattalite/thesis/mr/scenarion\intermediate\clipped_lulc_water12.tif 2020-04-17 11:55:06,407 geoprocessing.align_and_resize_raster_stack(752) INFO 5 of 5 aligned: clipped_lulc_water12.tif 2020-04-17 11:55:06,409 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 5 rasters. 2020-04-17 11:55:06,575 hydropower_water_yield.execute(406) DEBUG bio_lucodes {0.0, 1, 2, 3, 4, 5, 6, 7} 2020-04-17 11:55:06,629 hydropower_water_yield.execute(416) DEBUG demand_lucodes {0.0, 1, 2, 3, 4, 5, 6, 7} 2020-04-17 11:55:06,642 hydropower_water_yield._check_missing_lucodes(906) INFO Checking that input tables have landcover codes for every value in the landcover map. 2020-04-17 11:55:10,720 hydropower_water_yield.execute(460) INFO Reclassifying temp_Kc raster 2020-04-17 11:55:10,772 geoprocessing.raster_calculator(375) INFO starting stats_worker 2020-04-17 11:55:10,776 geoprocessing.raster_calculator(381) INFO started stats_worker 2020-04-17 11:55:15,776 geoprocessing.(451) INFO 50.1% complete 2020-04-17 11:55:20,783 geoprocessing.(451) INFO 86.3% complete 2020-04-17 11:55:22,781 geoprocessing.raster_calculator(454) INFO 100.0%% complete 2020-04-17 11:55:22,806 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate 2020-04-17 11:55:22,809 threading.run(870) DEBUG payload is None, terminating 2020-04-17 11:55:22,808 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result. 2020-04-17 11:55:25,684 hydropower_water_yield.execute(472) INFO Reclassifying tmp_root raster 2020-04-17 11:55:25,732 geoprocessing.raster_calculator(375) INFO starting stats_worker 2020-04-17 11:55:25,736 geoprocessing.raster_calculator(381) INFO started stats_worker 2020-04-17 11:55:30,735 geoprocessing.(451) INFO 49.6% complete 2020-04-17 11:55:35,747 geoprocessing.(451) INFO 86.0% complete 2020-04-17 11:55:37,767 geoprocessing.raster_calculator(454) INFO 100.0%% complete 2020-04-17 11:55:37,776 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate 2020-04-17 11:55:37,778 threading.run(870) DEBUG payload is None, terminating 2020-04-17 11:55:37,777 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result. 2020-04-17 11:55:40,614 hydropower_water_yield.execute(486) INFO Reclassifying tmp_veg raster 2020-04-17 11:55:40,664 geoprocessing.raster_calculator(375) INFO starting stats_worker 2020-04-17 11:55:40,668 geoprocessing.raster_calculator(381) INFO started stats_worker 2020-04-17 11:55:45,667 geoprocessing.(451) INFO 50.9% complete 2020-04-17 11:55:50,681 geoprocessing.(451) INFO 88.5% complete 2020-04-17 11:55:52,351 geoprocessing.raster_calculator(454) INFO 100.0%% complete 2020-04-17 11:55:52,353 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate 2020-04-17 11:55:52,354 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result. 2020-04-17 11:55:52,355 threading.run(870) DEBUG payload is None, terminating 2020-04-17 11:55:55,255 hydropower_water_yield.execute(499) INFO Calculate PET from Ref Evap times Kc 2020-04-17 11:55:55,318 geoprocessing.raster_calculator(375) INFO starting stats_worker 2020-04-17 11:55:55,322 geoprocessing.raster_calculator(381) INFO started stats_worker 2020-04-17 11:56:00,320 geoprocessing.(451) INFO 41.4% complete 2020-04-17 11:56:05,331 geoprocessing.(451) INFO 71.8% complete 2020-04-17 11:56:09,887 geoprocessing.raster_calculator(454) INFO 100.0%% complete 2020-04-17 11:56:09,889 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate 2020-04-17 11:56:09,891 threading.run(870) DEBUG payload is None, terminating 2020-04-17 11:56:09,890 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result. 2020-04-17 11:56:11,380 hydropower_water_yield.execute(517) DEBUG Performing fractp operation 2020-04-17 11:56:11,527 geoprocessing.raster_calculator(375) INFO starting stats_worker 2020-04-17 11:56:11,533 geoprocessing.raster_calculator(381) INFO started stats_worker 2020-04-17 11:56:16,543 geoprocessing.(451) INFO 12.0% complete 2020-04-17 11:56:21,644 geoprocessing.(451) INFO 20.8% complete 2020-04-17 11:56:26,688 geoprocessing.(451) INFO 29.2% complete 2020-04-17 11:56:31,790 geoprocessing.(451) INFO 38.0% complete 2020-04-17 11:56:36,877 geoprocessing.(451) INFO 46.2% complete 2020-04-17 11:56:41,898 geoprocessing.(451) INFO 52.5% complete 2020-04-17 11:56:47,006 geoprocessing.(451) INFO 58.7% complete 2020-04-17 11:56:52,030 geoprocessing.(451) INFO 66.6% complete 2020-04-17 11:56:57,143 geoprocessing.(451) INFO 77.4% complete 2020-04-17 11:57:02,232 geoprocessing.(451) INFO 90.4% complete 2020-04-17 11:57:06,446 geoprocessing.raster_calculator(454) INFO 100.0%% complete 2020-04-17 11:57:06,447 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate 2020-04-17 11:57:06,448 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result. 2020-04-17 11:57:06,450 threading.run(870) DEBUG payload is None, terminating 2020-04-17 11:57:07,045 hydropower_water_yield.execute(530) INFO Performing wyield operation 2020-04-17 11:57:07,099 geoprocessing.raster_calculator(375) INFO starting stats_worker 2020-04-17 11:57:07,102 geoprocessing.raster_calculator(381) INFO started stats_worker 2020-04-17 11:57:12,124 geoprocessing.(451) INFO 29.0% complete 2020-04-17 11:57:17,180 geoprocessing.(451) INFO 47.3% complete 2020-04-17 11:57:22,189 geoprocessing.(451) INFO 61.6% complete 2020-04-17 11:57:27,193 geoprocessing.(451) INFO 81.1% complete 2020-04-17 11:57:32,646 geoprocessing.raster_calculator(454) INFO 100.0%% complete 2020-04-17 11:57:32,647 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate 2020-04-17 11:57:32,649 threading.run(870) DEBUG payload is None, terminating 2020-04-17 11:57:32,649 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result. 2020-04-17 11:57:34,249 hydropower_water_yield.execute(543) DEBUG Performing aet operation 2020-04-17 11:57:34,306 geoprocessing.raster_calculator(375) INFO starting stats_worker 2020-04-17 11:57:34,310 geoprocessing.raster_calculator(381) INFO started stats_worker 2020-04-17 11:57:39,312 geoprocessing.(451) INFO 29.5% complete 2020-04-17 11:57:44,354 geoprocessing.(451) INFO 47.6% complete 2020-04-17 11:57:49,389 geoprocessing.(451) INFO 62.3% complete 2020-04-17 11:57:54,420 geoprocessing.(451) INFO 82.2% complete 2020-04-17 11:57:59,059 geoprocessing.raster_calculator(454) INFO 100.0%% complete 2020-04-17 11:57:59,061 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate 2020-04-17 11:57:59,062 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result. 2020-04-17 11:57:59,063 threading.run(870) DEBUG payload is None, terminating 2020-04-17 11:58:00,730 geoprocessing.raster_calculator(375) INFO starting stats_worker 2020-04-17 11:58:00,734 geoprocessing.raster_calculator(381) INFO started stats_worker 2020-04-17 11:58:05,740 geoprocessing.(451) INFO 50.5% complete 2020-04-17 11:58:10,757 geoprocessing.(451) INFO 88.5% complete 2020-04-17 11:58:12,380 geoprocessing.raster_calculator(454) INFO 100.0%% complete 2020-04-17 11:58:12,382 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate 2020-04-17 11:58:12,383 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result. 2020-04-17 11:58:12,384 threading.run(870) DEBUG payload is None, terminating 2020-04-17 11:58:14,889 geoprocessing.zonal_statistics(1129) DEBUG > 2020-04-17 11:58:20,884 geoprocessing.logger_callback(3239) INFO Warp 32.0% complete C:\Users\user\AppData\Local\Temp\clipped_rasterqxslttg5.tif 2020-04-17 11:58:26,450 geoprocessing.logger_callback(3239) INFO Warp 51.0% complete C:\Users\user\AppData\Local\Temp\clipped_rasterqxslttg5.tif 2020-04-17 11:58:33,633 geoprocessing.logger_callback(3239) INFO Warp 69.0% complete C:\Users\user\AppData\Local\Temp\clipped_rasterqxslttg5.tif 2020-04-17 11:58:38,697 geoprocessing.logger_callback(3239) INFO Warp 86.0% complete C:\Users\user\AppData\Local\Temp\clipped_rasterqxslttg5.tif 2020-04-17 11:58:44,455 geoprocessing.logger_callback(3239) INFO Warp 94.0% complete C:\Users\user\AppData\Local\Temp\clipped_rasterqxslttg5.tif 2020-04-17 11:58:45,074 geoprocessing.logger_callback(3239) INFO Warp 100.0% complete C:\Users\user\AppData\Local\Temp\clipped_rasterqxslttg5.tif 2020-04-17 11:58:49,451 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 1 aligned: clipped_rasterqxslttg5.tif 2020-04-17 11:58:49,453 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 1 rasters. 2020-04-17 11:58:49,462 geoprocessing.zonal_statistics(1189) INFO build a lookup of aggregate field value to FID 2020-04-17 11:58:49,467 geoprocessing.zonal_statistics(1196) INFO creating disjoint polygon set 2020-04-17 11:58:49,475 geoprocessing.calculate_disjoint_polygon_set(2082) INFO build shapely polygon list 2020-04-17 11:58:49,499 geoprocessing.calculate_disjoint_polygon_set(2096) INFO build shapely rtree index 2020-04-17 11:58:49,507 geoprocessing.calculate_disjoint_polygon_set(2111) INFO poly feature lookup 100.0% complete on watershed.shp 2020-04-17 11:58:49,509 geoprocessing.calculate_disjoint_polygon_set(2113) INFO build poly intersection lookup 2020-04-17 11:58:49,512 geoprocessing.calculate_disjoint_polygon_set(2137) INFO poly intersection feature lookup 100.0% complete on watershed.shp 2020-04-17 11:58:49,514 geoprocessing.calculate_disjoint_polygon_set(2171) INFO maximal subset build 100.0% complete on watershed.shp 2020-04-17 11:58:49,577 geoprocessing.zonal_statistics(1219) INFO processing 1 disjoint polygon sets 2020-04-17 11:58:49,579 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 1 of 1 100.0% processed on watershed.shp 2020-04-17 11:58:51,666 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 1 of 1 watershed.shp 2020-04-17 11:58:58,410 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 0.7324239244491081, message: rasterizing polygon 1 of 1 set %.1f%% complete 2020-04-17 11:59:00,644 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 1.0, message: rasterizing polygon 1 of 1 set %.1f%% complete 2020-04-17 11:59:04,056 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 1 of 1 watershed.shp 2020-04-17 11:59:15,186 geoprocessing.zonal_statistics(1322) DEBUG unset_fids: 0 of 1 2020-04-17 11:59:15,192 geoprocessing.zonal_statistics(1325) DEBUG gt [ 5.789650e+05 3.000000e+01 0.000000e+00 3.960941e+06 0.000000e+00 -3.000000e+01] for ('H:/sattalite/thesis/mr/scenarion\\intermediate\\precip_water12.tif', 1) 2020-04-17 11:59:15,195 geoprocessing.zonal_statistics(1399) DEBUG remaining unset_fids: 0 of 1 2020-04-17 11:59:15,196 geoprocessing.zonal_statistics(1407) INFO all done processing polygon sets for watershed.shp 2020-04-17 11:59:15,381 geoprocessing.zonal_statistics(1129) DEBUG > 2020-04-17 11:59:21,390 geoprocessing.logger_callback(3239) INFO Warp 38.0% complete C:\Users\user\AppData\Local\Temp\clipped_raster_9g_jkz7.tif 2020-04-17 11:59:26,436 geoprocessing.logger_callback(3239) INFO Warp 66.0% complete C:\Users\user\AppData\Local\Temp\clipped_raster_9g_jkz7.tif 2020-04-17 11:59:30,827 geoprocessing.logger_callback(3239) INFO Warp 100.0% complete C:\Users\user\AppData\Local\Temp\clipped_raster_9g_jkz7.tif 2020-04-17 11:59:33,241 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 1 aligned: clipped_raster_9g_jkz7.tif 2020-04-17 11:59:33,243 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 1 rasters. 2020-04-17 11:59:33,250 geoprocessing.zonal_statistics(1189) INFO build a lookup of aggregate field value to FID 2020-04-17 11:59:33,252 geoprocessing.zonal_statistics(1196) INFO creating disjoint polygon set 2020-04-17 11:59:33,258 geoprocessing.calculate_disjoint_polygon_set(2082) INFO build shapely polygon list 2020-04-17 11:59:33,266 geoprocessing.calculate_disjoint_polygon_set(2096) INFO build shapely rtree index 2020-04-17 11:59:33,272 geoprocessing.calculate_disjoint_polygon_set(2111) INFO poly feature lookup 100.0% complete on watershed.shp 2020-04-17 11:59:33,273 geoprocessing.calculate_disjoint_polygon_set(2113) INFO build poly intersection lookup 2020-04-17 11:59:33,275 geoprocessing.calculate_disjoint_polygon_set(2137) INFO poly intersection feature lookup 100.0% complete on watershed.shp 2020-04-17 11:59:33,276 geoprocessing.calculate_disjoint_polygon_set(2171) INFO maximal subset build 100.0% complete on watershed.shp 2020-04-17 11:59:33,325 geoprocessing.zonal_statistics(1219) INFO processing 1 disjoint polygon sets 2020-04-17 11:59:33,328 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 1 of 1 100.0% processed on watershed.shp 2020-04-17 11:59:34,843 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 1 of 1 watershed.shp 2020-04-17 11:59:40,287 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 0.7324239244491081, message: rasterizing polygon 1 of 1 set %.1f%% complete 2020-04-17 11:59:42,433 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 1.0, message: rasterizing polygon 1 of 1 set %.1f%% complete 2020-04-17 11:59:45,623 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 1 of 1 watershed.shp 2020-04-17 11:59:53,099 geoprocessing.zonal_statistics(1322) DEBUG unset_fids: 0 of 1 2020-04-17 11:59:53,104 geoprocessing.zonal_statistics(1325) DEBUG gt [ 5.789650e+05 3.000000e+01 0.000000e+00 3.960941e+06 0.000000e+00 -3.000000e+01] for ('H:/sattalite/thesis/mr/scenarion\\intermediate\\pet_water12.tif', 1) 2020-04-17 11:59:53,107 geoprocessing.zonal_statistics(1399) DEBUG remaining unset_fids: 0 of 1 2020-04-17 11:59:53,108 geoprocessing.zonal_statistics(1407) INFO all done processing polygon sets for watershed.shp 2020-04-17 11:59:53,288 geoprocessing.zonal_statistics(1129) DEBUG > 2020-04-17 11:59:58,900 geoprocessing.logger_callback(3239) INFO Warp 25.0% complete C:\Users\user\AppData\Local\Temp\clipped_rasterd9bl3l6t.tif 2020-04-17 12:00:05,280 geoprocessing.logger_callback(3239) INFO Warp 51.0% complete C:\Users\user\AppData\Local\Temp\clipped_rasterd9bl3l6t.tif 2020-04-17 12:00:10,318 geoprocessing.logger_callback(3239) INFO Warp 78.0% complete C:\Users\user\AppData\Local\Temp\clipped_rasterd9bl3l6t.tif 2020-04-17 12:00:13,789 geoprocessing.logger_callback(3239) INFO Warp 100.0% complete C:\Users\user\AppData\Local\Temp\clipped_rasterd9bl3l6t.tif 2020-04-17 12:00:16,970 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 1 aligned: clipped_rasterd9bl3l6t.tif 2020-04-17 12:00:16,973 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 1 rasters. 2020-04-17 12:00:16,981 geoprocessing.zonal_statistics(1189) INFO build a lookup of aggregate field value to FID 2020-04-17 12:00:16,984 geoprocessing.zonal_statistics(1196) INFO creating disjoint polygon set 2020-04-17 12:00:16,992 geoprocessing.calculate_disjoint_polygon_set(2082) INFO build shapely polygon list 2020-04-17 12:00:17,001 geoprocessing.calculate_disjoint_polygon_set(2096) INFO build shapely rtree index 2020-04-17 12:00:17,005 geoprocessing.calculate_disjoint_polygon_set(2111) INFO poly feature lookup 100.0% complete on watershed.shp 2020-04-17 12:00:17,006 geoprocessing.calculate_disjoint_polygon_set(2113) INFO build poly intersection lookup 2020-04-17 12:00:17,008 geoprocessing.calculate_disjoint_polygon_set(2137) INFO poly intersection feature lookup 100.0% complete on watershed.shp 2020-04-17 12:00:17,009 geoprocessing.calculate_disjoint_polygon_set(2171) INFO maximal subset build 100.0% complete on watershed.shp 2020-04-17 12:00:17,058 geoprocessing.zonal_statistics(1219) INFO processing 1 disjoint polygon sets 2020-04-17 12:00:17,061 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 1 of 1 100.0% processed on watershed.shp 2020-04-17 12:00:18,571 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 1 of 1 watershed.shp 2020-04-17 12:00:24,028 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 0.7324239244491081, message: rasterizing polygon 1 of 1 set %.1f%% complete 2020-04-17 12:00:25,825 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 1.0, message: rasterizing polygon 1 of 1 set %.1f%% complete 2020-04-17 12:00:28,691 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 1 of 1 watershed.shp 2020-04-17 12:00:38,750 geoprocessing.zonal_statistics(1322) DEBUG unset_fids: 0 of 1 2020-04-17 12:00:38,756 geoprocessing.zonal_statistics(1325) DEBUG gt [ 5.789650e+05 3.000000e+01 0.000000e+00 3.960941e+06 0.000000e+00 -3.000000e+01] for ('H:/sattalite/thesis/mr/scenarion\\output\\per_pixel\\aet_water12.tif', 1) 2020-04-17 12:00:38,759 geoprocessing.zonal_statistics(1399) DEBUG remaining unset_fids: 0 of 1 2020-04-17 12:00:38,759 geoprocessing.zonal_statistics(1407) INFO all done processing polygon sets for watershed.shp 2020-04-17 12:00:38,971 geoprocessing.zonal_statistics(1129) DEBUG > 2020-04-17 12:00:44,642 geoprocessing.logger_callback(3239) INFO Warp 25.0% complete C:\Users\user\AppData\Local\Temp\clipped_rasterpknygh7t.tif 2020-04-17 12:00:51,035 geoprocessing.logger_callback(3239) INFO Warp 51.0% complete C:\Users\user\AppData\Local\Temp\clipped_rasterpknygh7t.tif 2020-04-17 12:00:56,082 geoprocessing.logger_callback(3239) INFO Warp 77.0% complete C:\Users\user\AppData\Local\Temp\clipped_rasterpknygh7t.tif 2020-04-17 12:00:59,542 geoprocessing.logger_callback(3239) INFO Warp 100.0% complete C:\Users\user\AppData\Local\Temp\clipped_rasterpknygh7t.tif 2020-04-17 12:01:02,849 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 1 aligned: clipped_rasterpknygh7t.tif 2020-04-17 12:01:02,852 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 1 rasters. 2020-04-17 12:01:02,858 geoprocessing.zonal_statistics(1189) INFO build a lookup of aggregate field value to FID 2020-04-17 12:01:02,861 geoprocessing.zonal_statistics(1196) INFO creating disjoint polygon set 2020-04-17 12:01:02,866 geoprocessing.calculate_disjoint_polygon_set(2082) INFO build shapely polygon list 2020-04-17 12:01:02,875 geoprocessing.calculate_disjoint_polygon_set(2096) INFO build shapely rtree index 2020-04-17 12:01:02,880 geoprocessing.calculate_disjoint_polygon_set(2111) INFO poly feature lookup 100.0% complete on watershed.shp 2020-04-17 12:01:02,881 geoprocessing.calculate_disjoint_polygon_set(2113) INFO build poly intersection lookup 2020-04-17 12:01:02,885 geoprocessing.calculate_disjoint_polygon_set(2137) INFO poly intersection feature lookup 100.0% complete on watershed.shp 2020-04-17 12:01:02,887 geoprocessing.calculate_disjoint_polygon_set(2171) INFO maximal subset build 100.0% complete on watershed.shp 2020-04-17 12:01:02,942 geoprocessing.zonal_statistics(1219) INFO processing 1 disjoint polygon sets 2020-04-17 12:01:02,944 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 1 of 1 100.0% processed on watershed.shp 2020-04-17 12:01:04,498 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 1 of 1 watershed.shp 2020-04-17 12:01:09,867 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 0.7324239244491081, message: rasterizing polygon 1 of 1 set %.1f%% complete 2020-04-17 12:01:11,739 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 1.0, message: rasterizing polygon 1 of 1 set %.1f%% complete 2020-04-17 12:01:14,881 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 1 of 1 watershed.shp 2020-04-17 12:01:27,435 geoprocessing.zonal_statistics(1322) DEBUG unset_fids: 0 of 1 2020-04-17 12:01:27,441 geoprocessing.zonal_statistics(1325) DEBUG gt [ 5.789650e+05 3.000000e+01 0.000000e+00 3.960941e+06 0.000000e+00 -3.000000e+01] for ('H:/sattalite/thesis/mr/scenarion\\output\\per_pixel\\wyield_water12.tif', 1) 2020-04-17 12:01:27,444 geoprocessing.zonal_statistics(1399) DEBUG remaining unset_fids: 0 of 1 2020-04-17 12:01:27,445 geoprocessing.zonal_statistics(1407) INFO all done processing polygon sets for watershed.shp 2020-04-17 12:01:27,803 geoprocessing.zonal_statistics(1129) DEBUG > 2020-04-17 12:01:34,022 geoprocessing.logger_callback(3239) INFO Warp 38.0% complete C:\Users\user\AppData\Local\Temp\clipped_rasterpatawho5.tif 2020-04-17 12:01:39,063 geoprocessing.logger_callback(3239) INFO Warp 64.0% complete C:\Users\user\AppData\Local\Temp\clipped_rasterpatawho5.tif 2020-04-17 12:01:44,098 geoprocessing.logger_callback(3239) INFO Warp 98.0% complete C:\Users\user\AppData\Local\Temp\clipped_rasterpatawho5.tif 2020-04-17 12:01:44,211 geoprocessing.logger_callback(3239) INFO Warp 100.0% complete C:\Users\user\AppData\Local\Temp\clipped_rasterpatawho5.tif 2020-04-17 12:01:46,884 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 1 aligned: clipped_rasterpatawho5.tif 2020-04-17 12:01:46,888 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 1 rasters. 2020-04-17 12:01:46,896 geoprocessing.zonal_statistics(1189) INFO build a lookup of aggregate field value to FID 2020-04-17 12:01:46,899 geoprocessing.zonal_statistics(1196) INFO creating disjoint polygon set 2020-04-17 12:01:46,908 geoprocessing.calculate_disjoint_polygon_set(2082) INFO build shapely polygon list 2020-04-17 12:01:46,934 geoprocessing.calculate_disjoint_polygon_set(2096) INFO build shapely rtree index 2020-04-17 12:01:46,949 geoprocessing.calculate_disjoint_polygon_set(2111) INFO poly feature lookup 100.0% complete on watershed.shp 2020-04-17 12:01:46,951 geoprocessing.calculate_disjoint_polygon_set(2113) INFO build poly intersection lookup 2020-04-17 12:01:46,955 geoprocessing.calculate_disjoint_polygon_set(2137) INFO poly intersection feature lookup 100.0% complete on watershed.shp 2020-04-17 12:01:46,956 geoprocessing.calculate_disjoint_polygon_set(2171) INFO maximal subset build 100.0% complete on watershed.shp 2020-04-17 12:01:47,030 geoprocessing.zonal_statistics(1219) INFO processing 1 disjoint polygon sets 2020-04-17 12:01:47,035 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 1 of 1 100.0% processed on watershed.shp 2020-04-17 12:01:49,204 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 1 of 1 watershed.shp 2020-04-17 12:01:55,639 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 0.7324239244491081, message: rasterizing polygon 1 of 1 set %.1f%% complete 2020-04-17 12:01:57,438 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 1.0, message: rasterizing polygon 1 of 1 set %.1f%% complete 2020-04-17 12:02:00,285 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 1 of 1 watershed.shp 2020-04-17 12:02:06,941 geoprocessing.zonal_statistics(1322) DEBUG unset_fids: 0 of 1 2020-04-17 12:02:06,948 geoprocessing.zonal_statistics(1325) DEBUG gt [ 5.789650e+05 3.000000e+01 0.000000e+00 3.960941e+06 0.000000e+00 -3.000000e+01] for ('H:/sattalite/thesis/mr/scenarion\\intermediate\\demand_water12.tif', 1) 2020-04-17 12:02:06,951 geoprocessing.zonal_statistics(1399) DEBUG remaining unset_fids: 0 of 1 2020-04-17 12:02:06,952 geoprocessing.zonal_statistics(1407) INFO all done processing polygon sets for watershed.shp 2020-04-17 12:02:07,786 geoprocessing.zonal_statistics(1129) DEBUG > 2020-04-17 12:02:13,924 geoprocessing.logger_callback(3239) INFO Warp 32.0% complete C:\Users\user\AppData\Local\Temp\clipped_rasterus9c0aja.tif 2020-04-17 12:02:18,990 geoprocessing.logger_callback(3239) INFO Warp 51.0% complete C:\Users\user\AppData\Local\Temp\clipped_rasterus9c0aja.tif 2020-04-17 12:02:25,389 geoprocessing.logger_callback(3239) INFO Warp 75.0% complete C:\Users\user\AppData\Local\Temp\clipped_rasterus9c0aja.tif 2020-04-17 12:02:30,110 geoprocessing.logger_callback(3239) INFO Warp 100.0% complete C:\Users\user\AppData\Local\Temp\clipped_rasterus9c0aja.tif 2020-04-17 12:02:32,780 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 1 aligned: clipped_rasterus9c0aja.tif 2020-04-17 12:02:32,782 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 1 rasters. 2020-04-17 12:02:32,791 geoprocessing.zonal_statistics(1189) INFO build a lookup of aggregate field value to FID 2020-04-17 12:02:32,796 geoprocessing.zonal_statistics(1196) INFO creating disjoint polygon set 2020-04-17 12:02:32,803 geoprocessing.calculate_disjoint_polygon_set(2082) INFO build shapely polygon list 2020-04-17 12:02:32,825 geoprocessing.calculate_disjoint_polygon_set(2096) INFO build shapely rtree index 2020-04-17 12:02:32,844 geoprocessing.calculate_disjoint_polygon_set(2111) INFO poly feature lookup 100.0% complete on subwatershed.shp 2020-04-17 12:02:32,845 geoprocessing.calculate_disjoint_polygon_set(2113) INFO build poly intersection lookup 2020-04-17 12:02:32,905 geoprocessing.calculate_disjoint_polygon_set(2137) INFO poly intersection feature lookup 100.0% complete on subwatershed.shp 2020-04-17 12:02:32,907 geoprocessing.calculate_disjoint_polygon_set(2171) INFO maximal subset build 100.0% complete on subwatershed.shp 2020-04-17 12:02:32,962 geoprocessing.zonal_statistics(1219) INFO processing 4 disjoint polygon sets 2020-04-17 12:02:32,966 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 1 of 4 100.0% processed on subwatershed.shp 2020-04-17 12:02:34,506 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 1 of 4 subwatershed.shp 2020-04-17 12:02:39,626 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 0.7324239244491081, message: rasterizing polygon 1 of 5 set %.1f%% complete 2020-04-17 12:02:41,417 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 1.0, message: rasterizing polygon 1 of 5 set %.1f%% complete 2020-04-17 12:02:44,359 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 1 of 4 subwatershed.shp 2020-04-17 12:02:53,472 geoprocessing.(1225) INFO zonal stats approximately 50.0% complete on subwatershed.shp 2020-04-17 12:02:53,476 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 2 of 4 100.0% processed on subwatershed.shp 2020-04-17 12:02:55,295 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 2 of 4 subwatershed.shp 2020-04-17 12:03:01,744 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 0.7324239244491081, message: rasterizing polygon 2 of 3 set %.1f%% complete 2020-04-17 12:03:03,939 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 1.0, message: rasterizing polygon 2 of 3 set %.1f%% complete 2020-04-17 12:03:07,679 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 2 of 4 subwatershed.shp 2020-04-17 12:03:17,259 geoprocessing.(1225) INFO zonal stats approximately 75.0% complete on subwatershed.shp 2020-04-17 12:03:17,262 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 3 of 4 100.0% processed on subwatershed.shp 2020-04-17 12:03:18,560 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 3 of 4 subwatershed.shp 2020-04-17 12:03:23,681 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 0.7324239244491081, message: rasterizing polygon 3 of 2 set %.1f%% complete 2020-04-17 12:03:25,398 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 1.0, message: rasterizing polygon 3 of 2 set %.1f%% complete 2020-04-17 12:03:28,425 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 3 of 4 subwatershed.shp 2020-04-17 12:03:36,976 geoprocessing.(1225) INFO zonal stats approximately 100.0% complete on subwatershed.shp 2020-04-17 12:03:36,979 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 4 of 4 100.0% processed on subwatershed.shp 2020-04-17 12:03:38,373 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 4 of 4 subwatershed.shp 2020-04-17 12:03:44,562 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 0.7324239244491081, message: rasterizing polygon 4 of 1 set %.1f%% complete 2020-04-17 12:03:46,841 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 1.0, message: rasterizing polygon 4 of 1 set %.1f%% complete 2020-04-17 12:03:51,517 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 4 of 4 subwatershed.shp 2020-04-17 12:04:03,056 geoprocessing.zonal_statistics(1322) DEBUG unset_fids: 0 of 11 2020-04-17 12:04:03,066 geoprocessing.zonal_statistics(1325) DEBUG gt [ 5.789650e+05 3.000000e+01 0.000000e+00 3.960941e+06 0.000000e+00 -3.000000e+01] for ('H:/sattalite/thesis/mr/scenarion\\intermediate\\precip_water12.tif', 1) 2020-04-17 12:04:03,070 geoprocessing.zonal_statistics(1399) DEBUG remaining unset_fids: 0 of 11 2020-04-17 12:04:03,071 geoprocessing.zonal_statistics(1407) INFO all done processing polygon sets for subwatershed.shp 2020-04-17 12:04:03,382 geoprocessing.zonal_statistics(1129) DEBUG > 2020-04-17 12:04:08,480 geoprocessing.logger_callback(3239) INFO Warp 25.0% complete C:\Users\user\AppData\Local\Temp\clipped_rasters3nd9ylv.tif 2020-04-17 12:04:13,494 geoprocessing.logger_callback(3239) INFO Warp 51.0% complete C:\Users\user\AppData\Local\Temp\clipped_rasters3nd9ylv.tif 2020-04-17 12:04:19,297 geoprocessing.logger_callback(3239) INFO Warp 75.0% complete C:\Users\user\AppData\Local\Temp\clipped_rasters3nd9ylv.tif 2020-04-17 12:04:23,050 geoprocessing.logger_callback(3239) INFO Warp 100.0% complete C:\Users\user\AppData\Local\Temp\clipped_rasters3nd9ylv.tif 2020-04-17 12:04:25,662 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 1 aligned: clipped_rasters3nd9ylv.tif 2020-04-17 12:04:25,664 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 1 rasters. 2020-04-17 12:04:25,671 geoprocessing.zonal_statistics(1189) INFO build a lookup of aggregate field value to FID 2020-04-17 12:04:25,676 geoprocessing.zonal_statistics(1196) INFO creating disjoint polygon set 2020-04-17 12:04:25,682 geoprocessing.calculate_disjoint_polygon_set(2082) INFO build shapely polygon list 2020-04-17 12:04:25,701 geoprocessing.calculate_disjoint_polygon_set(2096) INFO build shapely rtree index 2020-04-17 12:04:25,716 geoprocessing.calculate_disjoint_polygon_set(2111) INFO poly feature lookup 100.0% complete on subwatershed.shp 2020-04-17 12:04:25,717 geoprocessing.calculate_disjoint_polygon_set(2113) INFO build poly intersection lookup 2020-04-17 12:04:25,769 geoprocessing.calculate_disjoint_polygon_set(2137) INFO poly intersection feature lookup 100.0% complete on subwatershed.shp 2020-04-17 12:04:25,771 geoprocessing.calculate_disjoint_polygon_set(2171) INFO maximal subset build 100.0% complete on subwatershed.shp 2020-04-17 12:04:25,854 geoprocessing.zonal_statistics(1219) INFO processing 4 disjoint polygon sets 2020-04-17 12:04:25,860 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 1 of 4 100.0% processed on subwatershed.shp 2020-04-17 12:04:27,805 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 1 of 4 subwatershed.shp 2020-04-17 12:04:35,575 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 0.7324239244491081, message: rasterizing polygon 1 of 5 set %.1f%% complete 2020-04-17 12:04:37,644 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 1.0, message: rasterizing polygon 1 of 5 set %.1f%% complete 2020-04-17 12:04:41,608 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 1 of 4 subwatershed.shp 2020-04-17 12:04:49,725 geoprocessing.(1225) INFO zonal stats approximately 50.0% complete on subwatershed.shp 2020-04-17 12:04:49,729 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 2 of 4 100.0% processed on subwatershed.shp 2020-04-17 12:04:51,447 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 2 of 4 subwatershed.shp 2020-04-17 12:04:58,318 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 0.7324239244491081, message: rasterizing polygon 2 of 3 set %.1f%% complete 2020-04-17 12:05:00,640 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 1.0, message: rasterizing polygon 2 of 3 set %.1f%% complete 2020-04-17 12:05:04,471 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 2 of 4 subwatershed.shp 2020-04-17 12:05:10,483 geoprocessing.(1225) INFO zonal stats approximately 75.0% complete on subwatershed.shp 2020-04-17 12:05:10,486 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 3 of 4 100.0% processed on subwatershed.shp 2020-04-17 12:05:11,833 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 3 of 4 subwatershed.shp 2020-04-17 12:05:18,545 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 1.0, message: rasterizing polygon 3 of 2 set %.1f%% complete 2020-04-17 12:05:21,554 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 3 of 4 subwatershed.shp 2020-04-17 12:05:27,360 geoprocessing.(1225) INFO zonal stats approximately 100.0% complete on subwatershed.shp 2020-04-17 12:05:27,362 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 4 of 4 100.0% processed on subwatershed.shp 2020-04-17 12:05:28,703 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 4 of 4 subwatershed.shp 2020-04-17 12:05:33,840 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 0.7324239244491081, message: rasterizing polygon 4 of 1 set %.1f%% complete 2020-04-17 12:05:35,516 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 1.0, message: rasterizing polygon 4 of 1 set %.1f%% complete 2020-04-17 12:05:38,525 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 4 of 4 subwatershed.shp 2020-04-17 12:05:44,308 geoprocessing.zonal_statistics(1322) DEBUG unset_fids: 0 of 11 2020-04-17 12:05:44,314 geoprocessing.zonal_statistics(1325) DEBUG gt [ 5.789650e+05 3.000000e+01 0.000000e+00 3.960941e+06 0.000000e+00 -3.000000e+01] for ('H:/sattalite/thesis/mr/scenarion\\intermediate\\pet_water12.tif', 1) 2020-04-17 12:05:44,317 geoprocessing.zonal_statistics(1399) DEBUG remaining unset_fids: 0 of 11 2020-04-17 12:05:44,318 geoprocessing.zonal_statistics(1407) INFO all done processing polygon sets for subwatershed.shp 2020-04-17 12:05:44,514 geoprocessing.zonal_statistics(1129) DEBUG > 2020-04-17 12:05:50,166 geoprocessing.logger_callback(3239) INFO Warp 25.0% complete C:\Users\user\AppData\Local\Temp\clipped_raster0plcl5_h.tif 2020-04-17 12:05:56,708 geoprocessing.logger_callback(3239) INFO Warp 51.0% complete C:\Users\user\AppData\Local\Temp\clipped_raster0plcl5_h.tif 2020-04-17 12:06:01,973 geoprocessing.logger_callback(3239) INFO Warp 75.0% complete C:\Users\user\AppData\Local\Temp\clipped_raster0plcl5_h.tif 2020-04-17 12:06:06,057 geoprocessing.logger_callback(3239) INFO Warp 100.0% complete C:\Users\user\AppData\Local\Temp\clipped_raster0plcl5_h.tif 2020-04-17 12:06:09,322 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 1 aligned: clipped_raster0plcl5_h.tif 2020-04-17 12:06:09,324 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 1 rasters. 2020-04-17 12:06:09,332 geoprocessing.zonal_statistics(1189) INFO build a lookup of aggregate field value to FID 2020-04-17 12:06:09,336 geoprocessing.zonal_statistics(1196) INFO creating disjoint polygon set 2020-04-17 12:06:09,342 geoprocessing.calculate_disjoint_polygon_set(2082) INFO build shapely polygon list 2020-04-17 12:06:09,358 geoprocessing.calculate_disjoint_polygon_set(2096) INFO build shapely rtree index 2020-04-17 12:06:09,369 geoprocessing.calculate_disjoint_polygon_set(2111) INFO poly feature lookup 100.0% complete on subwatershed.shp 2020-04-17 12:06:09,370 geoprocessing.calculate_disjoint_polygon_set(2113) INFO build poly intersection lookup 2020-04-17 12:06:09,432 geoprocessing.calculate_disjoint_polygon_set(2137) INFO poly intersection feature lookup 100.0% complete on subwatershed.shp 2020-04-17 12:06:09,434 geoprocessing.calculate_disjoint_polygon_set(2171) INFO maximal subset build 100.0% complete on subwatershed.shp 2020-04-17 12:06:09,489 geoprocessing.zonal_statistics(1219) INFO processing 4 disjoint polygon sets 2020-04-17 12:06:09,493 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 1 of 4 100.0% processed on subwatershed.shp 2020-04-17 12:06:11,091 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 1 of 4 subwatershed.shp 2020-04-17 12:06:16,346 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 0.7324239244491081, message: rasterizing polygon 1 of 5 set %.1f%% complete 2020-04-17 12:06:18,155 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 1.0, message: rasterizing polygon 1 of 5 set %.1f%% complete 2020-04-17 12:06:21,057 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 1 of 4 subwatershed.shp 2020-04-17 12:06:29,593 geoprocessing.(1225) INFO zonal stats approximately 50.0% complete on subwatershed.shp 2020-04-17 12:06:29,597 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 2 of 4 100.0% processed on subwatershed.shp 2020-04-17 12:06:30,899 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 2 of 4 subwatershed.shp 2020-04-17 12:06:35,980 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 0.7324239244491081, message: rasterizing polygon 2 of 3 set %.1f%% complete 2020-04-17 12:06:37,782 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 1.0, message: rasterizing polygon 2 of 3 set %.1f%% complete 2020-04-17 12:06:40,693 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 2 of 4 subwatershed.shp 2020-04-17 12:06:49,201 geoprocessing.(1225) INFO zonal stats approximately 75.0% complete on subwatershed.shp 2020-04-17 12:06:49,203 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 3 of 4 100.0% processed on subwatershed.shp 2020-04-17 12:06:50,532 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 3 of 4 subwatershed.shp 2020-04-17 12:06:55,666 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 0.7324239244491081, message: rasterizing polygon 3 of 2 set %.1f%% complete 2020-04-17 12:06:57,417 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 1.0, message: rasterizing polygon 3 of 2 set %.1f%% complete 2020-04-17 12:07:00,499 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 3 of 4 subwatershed.shp 2020-04-17 12:07:09,103 geoprocessing.(1225) INFO zonal stats approximately 100.0% complete on subwatershed.shp 2020-04-17 12:07:09,106 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 4 of 4 100.0% processed on subwatershed.shp 2020-04-17 12:07:10,503 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 4 of 4 subwatershed.shp 2020-04-17 12:07:15,614 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 0.7324239244491081, message: rasterizing polygon 4 of 1 set %.1f%% complete 2020-04-17 12:07:17,371 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 1.0, message: rasterizing polygon 4 of 1 set %.1f%% complete 2020-04-17 12:07:20,409 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 4 of 4 subwatershed.shp 2020-04-17 12:07:28,867 geoprocessing.zonal_statistics(1322) DEBUG unset_fids: 0 of 11 2020-04-17 12:07:28,873 geoprocessing.zonal_statistics(1325) DEBUG gt [ 5.789650e+05 3.000000e+01 0.000000e+00 3.960941e+06 0.000000e+00 -3.000000e+01] for ('H:/sattalite/thesis/mr/scenarion\\output\\per_pixel\\aet_water12.tif', 1) 2020-04-17 12:07:28,876 geoprocessing.zonal_statistics(1399) DEBUG remaining unset_fids: 0 of 11 2020-04-17 12:07:28,876 geoprocessing.zonal_statistics(1407) INFO all done processing polygon sets for subwatershed.shp 2020-04-17 12:07:29,092 geoprocessing.zonal_statistics(1129) DEBUG > 2020-04-17 12:07:34,796 geoprocessing.logger_callback(3239) INFO Warp 25.0% complete C:\Users\user\AppData\Local\Temp\clipped_rasteri3yp_ffe.tif 2020-04-17 12:07:41,305 geoprocessing.logger_callback(3239) INFO Warp 51.0% complete C:\Users\user\AppData\Local\Temp\clipped_rasteri3yp_ffe.tif 2020-04-17 12:07:46,310 geoprocessing.logger_callback(3239) INFO Warp 76.0% complete C:\Users\user\AppData\Local\Temp\clipped_rasteri3yp_ffe.tif 2020-04-17 12:07:50,006 geoprocessing.logger_callback(3239) INFO Warp 100.0% complete C:\Users\user\AppData\Local\Temp\clipped_rasteri3yp_ffe.tif 2020-04-17 12:07:53,251 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 1 aligned: clipped_rasteri3yp_ffe.tif 2020-04-17 12:07:53,253 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 1 rasters. 2020-04-17 12:07:53,262 geoprocessing.zonal_statistics(1189) INFO build a lookup of aggregate field value to FID 2020-04-17 12:07:53,267 geoprocessing.zonal_statistics(1196) INFO creating disjoint polygon set 2020-04-17 12:07:53,273 geoprocessing.calculate_disjoint_polygon_set(2082) INFO build shapely polygon list 2020-04-17 12:07:53,290 geoprocessing.calculate_disjoint_polygon_set(2096) INFO build shapely rtree index 2020-04-17 12:07:53,305 geoprocessing.calculate_disjoint_polygon_set(2111) INFO poly feature lookup 100.0% complete on subwatershed.shp 2020-04-17 12:07:53,306 geoprocessing.calculate_disjoint_polygon_set(2113) INFO build poly intersection lookup 2020-04-17 12:07:53,363 geoprocessing.calculate_disjoint_polygon_set(2137) INFO poly intersection feature lookup 100.0% complete on subwatershed.shp 2020-04-17 12:07:53,366 geoprocessing.calculate_disjoint_polygon_set(2171) INFO maximal subset build 100.0% complete on subwatershed.shp 2020-04-17 12:07:53,417 geoprocessing.zonal_statistics(1219) INFO processing 4 disjoint polygon sets 2020-04-17 12:07:53,421 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 1 of 4 100.0% processed on subwatershed.shp 2020-04-17 12:07:55,019 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 1 of 4 subwatershed.shp 2020-04-17 12:08:00,114 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 0.7324239244491081, message: rasterizing polygon 1 of 5 set %.1f%% complete 2020-04-17 12:08:01,888 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 1.0, message: rasterizing polygon 1 of 5 set %.1f%% complete 2020-04-17 12:08:04,911 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 1 of 4 subwatershed.shp 2020-04-17 12:08:13,627 geoprocessing.(1225) INFO zonal stats approximately 50.0% complete on subwatershed.shp 2020-04-17 12:08:13,630 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 2 of 4 100.0% processed on subwatershed.shp 2020-04-17 12:08:14,966 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 2 of 4 subwatershed.shp 2020-04-17 12:08:20,176 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 0.7324239244491081, message: rasterizing polygon 2 of 3 set %.1f%% complete 2020-04-17 12:08:21,921 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 1.0, message: rasterizing polygon 2 of 3 set %.1f%% complete 2020-04-17 12:08:24,810 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 2 of 4 subwatershed.shp 2020-04-17 12:08:33,526 geoprocessing.(1225) INFO zonal stats approximately 75.0% complete on subwatershed.shp 2020-04-17 12:08:33,530 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 3 of 4 100.0% processed on subwatershed.shp 2020-04-17 12:08:34,901 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 3 of 4 subwatershed.shp 2020-04-17 12:08:40,127 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 0.7324239244491081, message: rasterizing polygon 3 of 2 set %.1f%% complete 2020-04-17 12:08:41,855 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 1.0, message: rasterizing polygon 3 of 2 set %.1f%% complete 2020-04-17 12:08:44,982 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 3 of 4 subwatershed.shp 2020-04-17 12:08:54,027 geoprocessing.(1225) INFO zonal stats approximately 100.0% complete on subwatershed.shp 2020-04-17 12:08:54,030 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 4 of 4 100.0% processed on subwatershed.shp 2020-04-17 12:08:55,411 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 4 of 4 subwatershed.shp 2020-04-17 12:09:00,471 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 0.7324239244491081, message: rasterizing polygon 4 of 1 set %.1f%% complete 2020-04-17 12:09:02,261 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 1.0, message: rasterizing polygon 4 of 1 set %.1f%% complete 2020-04-17 12:09:05,243 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 4 of 4 subwatershed.shp 2020-04-17 12:09:13,935 geoprocessing.zonal_statistics(1322) DEBUG unset_fids: 0 of 11 2020-04-17 12:09:13,942 geoprocessing.zonal_statistics(1325) DEBUG gt [ 5.789650e+05 3.000000e+01 0.000000e+00 3.960941e+06 0.000000e+00 -3.000000e+01] for ('H:/sattalite/thesis/mr/scenarion\\output\\per_pixel\\wyield_water12.tif', 1) 2020-04-17 12:09:13,945 geoprocessing.zonal_statistics(1399) DEBUG remaining unset_fids: 0 of 11 2020-04-17 12:09:13,945 geoprocessing.zonal_statistics(1407) INFO all done processing polygon sets for subwatershed.shp 2020-04-17 12:09:14,165 geoprocessing.zonal_statistics(1129) DEBUG > 2020-04-17 12:09:19,444 geoprocessing.logger_callback(3239) INFO Warp 38.0% complete C:\Users\user\AppData\Local\Temp\clipped_rasterxcvmk9jm.tif 2020-04-17 12:09:24,860 geoprocessing.logger_callback(3239) INFO Warp 75.0% complete C:\Users\user\AppData\Local\Temp\clipped_rasterxcvmk9jm.tif 2020-04-17 12:09:27,413 geoprocessing.logger_callback(3239) INFO Warp 100.0% complete C:\Users\user\AppData\Local\Temp\clipped_rasterxcvmk9jm.tif 2020-04-17 12:09:29,371 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 1 aligned: clipped_rasterxcvmk9jm.tif 2020-04-17 12:09:29,373 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 1 rasters. 2020-04-17 12:09:29,380 geoprocessing.zonal_statistics(1189) INFO build a lookup of aggregate field value to FID 2020-04-17 12:09:29,384 geoprocessing.zonal_statistics(1196) INFO creating disjoint polygon set 2020-04-17 12:09:29,389 geoprocessing.calculate_disjoint_polygon_set(2082) INFO build shapely polygon list 2020-04-17 12:09:29,413 geoprocessing.calculate_disjoint_polygon_set(2096) INFO build shapely rtree index 2020-04-17 12:09:29,431 geoprocessing.calculate_disjoint_polygon_set(2111) INFO poly feature lookup 100.0% complete on subwatershed.shp 2020-04-17 12:09:29,432 geoprocessing.calculate_disjoint_polygon_set(2113) INFO build poly intersection lookup 2020-04-17 12:09:29,484 geoprocessing.calculate_disjoint_polygon_set(2137) INFO poly intersection feature lookup 100.0% complete on subwatershed.shp 2020-04-17 12:09:29,486 geoprocessing.calculate_disjoint_polygon_set(2171) INFO maximal subset build 100.0% complete on subwatershed.shp 2020-04-17 12:09:29,539 geoprocessing.zonal_statistics(1219) INFO processing 4 disjoint polygon sets 2020-04-17 12:09:29,543 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 1 of 4 100.0% processed on subwatershed.shp 2020-04-17 12:09:31,124 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 1 of 4 subwatershed.shp 2020-04-17 12:09:36,267 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 0.7324239244491081, message: rasterizing polygon 1 of 5 set %.1f%% complete 2020-04-17 12:09:38,007 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 1.0, message: rasterizing polygon 1 of 5 set %.1f%% complete 2020-04-17 12:09:40,953 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 1 of 4 subwatershed.shp 2020-04-17 12:09:45,998 geoprocessing.(1225) INFO zonal stats approximately 50.0% complete on subwatershed.shp 2020-04-17 12:09:46,002 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 2 of 4 100.0% processed on subwatershed.shp 2020-04-17 12:09:47,390 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 2 of 4 subwatershed.shp 2020-04-17 12:09:52,526 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 0.7324239244491081, message: rasterizing polygon 2 of 3 set %.1f%% complete 2020-04-17 12:09:54,298 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 1.0, message: rasterizing polygon 2 of 3 set %.1f%% complete 2020-04-17 12:09:57,219 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 2 of 4 subwatershed.shp 2020-04-17 12:10:02,225 geoprocessing.(1225) INFO zonal stats approximately 75.0% complete on subwatershed.shp 2020-04-17 12:10:02,228 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 3 of 4 100.0% processed on subwatershed.shp 2020-04-17 12:10:03,583 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 3 of 4 subwatershed.shp 2020-04-17 12:10:09,063 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 0.7324239244491081, message: rasterizing polygon 3 of 2 set %.1f%% complete 2020-04-17 12:10:10,932 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 1.0, message: rasterizing polygon 3 of 2 set %.1f%% complete 2020-04-17 12:10:13,990 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 3 of 4 subwatershed.shp 2020-04-17 12:10:18,972 geoprocessing.(1225) INFO zonal stats approximately 100.0% complete on subwatershed.shp 2020-04-17 12:10:18,975 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 4 of 4 100.0% processed on subwatershed.shp 2020-04-17 12:10:20,349 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 4 of 4 subwatershed.shp 2020-04-17 12:10:25,579 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 0.7324239244491081, message: rasterizing polygon 4 of 1 set %.1f%% complete 2020-04-17 12:10:27,326 geoprocessing.logger_callback(3243) INFO p_progress_arg is None df_complete: 1.0, message: rasterizing polygon 4 of 1 set %.1f%% complete 2020-04-17 12:10:30,328 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 4 of 4 subwatershed.shp 2020-04-17 12:10:35,434 geoprocessing.zonal_statistics(1322) DEBUG unset_fids: 0 of 11 2020-04-17 12:10:35,440 geoprocessing.zonal_statistics(1325) DEBUG gt [ 5.789650e+05 3.000000e+01 0.000000e+00 3.960941e+06 0.000000e+00 -3.000000e+01] for ('H:/sattalite/thesis/mr/scenarion\\intermediate\\demand_water12.tif', 1) 2020-04-17 12:10:35,443 geoprocessing.zonal_statistics(1399) DEBUG remaining unset_fids: 0 of 11 2020-04-17 12:10:35,444 geoprocessing.zonal_statistics(1407) INFO all done processing polygon sets for subwatershed.shp 2020-04-17 12:10:36,322 model._logged_target(1651) INFO Execution finished 2020-04-17 12:10:36,328 utils.prepare_workspace(121) INFO Elapsed time: 16m 41.049999999999955s