2021-03-09 21:14:58,510 utils.prepare_workspace(123) INFO Writing log messages to E:/InVEST projects/shiv-ghadera/invest-wym-trial2/wym-workspace-09032021-2111hrs\InVEST-Hydropower-Water-Yield-log-2021-03-09--21_14_58.txt
2021-03-09 21:14:58,513 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        E:/InVEST projects/shiv-ghadera/invest-wym-trial2/sahigad-biophysical_table.csv
depth_to_root_rest_layer_path E:/InVEST projects/shiv-ghadera/invest-wym-trial2/roots_restricting_layer_mm.tif
do_scarcity_and_valuation     False
eto_path                      E:/InVEST projects/shiv-ghadera/invest-wym-trial2/et0_epsg32644/sahigad_epsg32644_et0.tif
lulc_path                     E:/InVEST projects/shiv-ghadera/invest-wym-trial2/sahigad-lulc-epsg_32644.tif
n_workers                     -1
pawc_path                     E:/InVEST projects/shiv-ghadera/invest-wym-trial2/pawc_fraction.tif
precipitation_path            E:/InVEST projects/shiv-ghadera/invest-wym-trial2/precip_epsg32644/sahigad_epsg32644_precip.tif
results_suffix                -z5
seasonality_constant          5
sub_watersheds_path           
watersheds_path               E:/InVEST projects/shiv-ghadera/invest-wym-trial2/watershed_sahigad.shp
workspace_dir                 E:/InVEST projects/shiv-ghadera/invest-wym-trial2/wym-workspace-09032021-2111hrs

2021-03-09 21:14:58,554 hydropower_water_yield.execute(269) INFO Validating arguments
2021-03-09 21:14:58,556 validation._wrapped_validate_func(911) DEBUG Using ARG_SPEC for validation
2021-03-09 21:14:58,557 validation._wrapped_validate_func(915) INFO Starting whole-model validation with ARGS_SPEC
2021-03-09 21:14:58,559 validation.timeout(625) DEBUG Starting file checking thread with timeout=5
2021-03-09 21:14:59,922 validation.timeout(637) DEBUG File checking thread completed.
2021-03-09 21:14:59,923 validation.timeout(625) DEBUG Starting file checking thread with timeout=5
2021-03-09 21:14:59,988 validation.timeout(637) DEBUG File checking thread completed.
2021-03-09 21:14:59,988 validation.timeout(625) DEBUG Starting file checking thread with timeout=5
2021-03-09 21:15:00,002 validation.timeout(637) DEBUG File checking thread completed.
2021-03-09 21:15:00,002 validation.timeout(625) DEBUG Starting file checking thread with timeout=5
2021-03-09 21:15:00,069 validation.timeout(637) DEBUG File checking thread completed.
2021-03-09 21:15:00,069 validation.validate(794) DEBUG Provided key do_scarcity_and_valuation does not exist in ARGS_SPEC
2021-03-09 21:15:00,069 validation.timeout(625) DEBUG Starting file checking thread with timeout=5
2021-03-09 21:15:00,124 validation.timeout(637) DEBUG File checking thread completed.
2021-03-09 21:15:00,139 validation.timeout(625) DEBUG Starting file checking thread with timeout=5
2021-03-09 21:15:00,193 validation.timeout(637) DEBUG File checking thread completed.
2021-03-09 21:15:00,193 validation.timeout(625) DEBUG Starting file checking thread with timeout=5
2021-03-09 21:15:00,264 validation.timeout(637) DEBUG File checking thread completed.
2021-03-09 21:15:00,264 validation.timeout(625) DEBUG Starting file checking thread with timeout=5
2021-03-09 21:15:00,284 validation.timeout(637) DEBUG File checking thread completed.
2021-03-09 21:15:00,400 validation._wrapped_validate_func(962) DEBUG Validation warnings: []
2021-03-09 21:15:05,997 geoprocessing.align_and_resize_raster_stack(795) INFO 1 of 5 aligned: eto_-z5.tif
2021-03-09 21:15:07,398 geoprocessing.align_and_resize_raster_stack(795) INFO 2 of 5 aligned: precip_-z5.tif
2021-03-09 21:15:08,244 geoprocessing.align_and_resize_raster_stack(795) INFO 3 of 5 aligned: depth_to_root_rest_layer_-z5.tif
2021-03-09 21:15:09,452 geoprocessing.align_and_resize_raster_stack(795) INFO 4 of 5 aligned: pawc_-z5.tif
2021-03-09 21:15:10,768 geoprocessing.align_and_resize_raster_stack(795) INFO 5 of 5 aligned: clipped_lulc_-z5.tif
2021-03-09 21:15:10,769 geoprocessing.align_and_resize_raster_stack(797) INFO aligned all 5 rasters.
2021-03-09 21:15:14,584 hydropower_water_yield.execute(405) DEBUG bio_lucodes {0.0, 40, 10, 50, 20, 60, 30}
2021-03-09 21:15:14,585 hydropower_water_yield.execute(453) INFO Reclassifying temp_Kc raster
2021-03-09 21:15:15,807 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-03-09 21:15:16,675 threading.run(870) DEBUG stats worker PID: 1548
2021-03-09 21:15:16,673 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-347, started daemon 11492)>
2021-03-09 21:15:23,858 geoprocessing.<lambda>(474) INFO 100.0% complete
2021-03-09 21:15:24,846 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-03-09 21:15:24,851 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-03-09 21:15:25,825 hydropower_water_yield.execute(465) INFO Reclassifying tmp_root raster
2021-03-09 21:15:25,851 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-03-09 21:15:25,852 threading.run(870) DEBUG stats worker PID: 1548
2021-03-09 21:15:25,852 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-348, started daemon 13976)>
2021-03-09 21:15:25,856 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-03-09 21:15:25,857 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-03-09 21:15:26,843 hydropower_water_yield.execute(479) INFO Reclassifying tmp_veg raster
2021-03-09 21:15:26,893 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-03-09 21:15:26,895 threading.run(870) DEBUG stats worker PID: 1548
2021-03-09 21:15:26,896 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-349, started daemon 13492)>
2021-03-09 21:15:26,905 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-03-09 21:15:26,906 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-03-09 21:15:28,203 hydropower_water_yield.execute(492) INFO Calculate PET from Ref Evap times Kc
2021-03-09 21:15:28,222 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-03-09 21:15:28,223 threading.run(870) DEBUG stats worker PID: 1548
2021-03-09 21:15:28,223 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-350, started daemon 9416)>
2021-03-09 21:15:28,628 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-03-09 21:15:28,631 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-03-09 21:15:29,749 hydropower_water_yield.execute(510) DEBUG Performing fractp operation
2021-03-09 21:15:30,222 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-03-09 21:15:30,226 threading.run(870) DEBUG stats worker PID: 1548
2021-03-09 21:15:30,226 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-351, started daemon 14756)>
2021-03-09 21:15:30,605 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-03-09 21:15:30,606 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-03-09 21:15:32,008 hydropower_water_yield.execute(523) INFO Performing wyield operation
2021-03-09 21:15:32,053 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-03-09 21:15:32,057 threading.run(870) DEBUG stats worker PID: 1548
2021-03-09 21:15:32,057 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-352, started daemon 13404)>
2021-03-09 21:15:32,070 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-03-09 21:15:32,071 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-03-09 21:15:33,341 hydropower_water_yield.execute(536) DEBUG Performing aet operation
2021-03-09 21:15:33,384 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-03-09 21:15:33,386 threading.run(870) DEBUG stats worker PID: 1548
2021-03-09 21:15:33,387 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-353, started daemon 8568)>
2021-03-09 21:15:33,393 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-03-09 21:15:33,394 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-03-09 21:15:35,179 geoprocessing.zonal_statistics(1184) DEBUG <osgeo.gdal.Dataset; proxy of <Swig Object of type 'GDALDatasetShadow *' at 0x0000013F8CF10630> >
2021-03-09 21:15:36,129 geoprocessing.align_and_resize_raster_stack(795) INFO 1 of 1 aligned: clipped_rasterqdf0bueb.tif
2021-03-09 21:15:36,131 geoprocessing.align_and_resize_raster_stack(797) INFO aligned all 1 rasters.
2021-03-09 21:15:36,551 geoprocessing.zonal_statistics(1244) INFO build a lookup of aggregate field value to FID
2021-03-09 21:15:36,624 geoprocessing.zonal_statistics(1251) INFO creating disjoint polygon set
2021-03-09 21:15:36,629 geoprocessing.calculate_disjoint_polygon_set(2183) INFO build shapely polygon list
2021-03-09 21:15:37,828 geos.callback(252) ERROR IllegalArgumentException: Points of LinearRing do not form a closed linestring
2021-03-09 21:15:37,828 Task.add_task(781) ERROR Something went wrong when adding task ws_id_precip_mn_zonalstats (8), terminating taskgraph.
Traceback (most recent call last):
  File "site-packages\taskgraph\Task.py", line 747, in add_task
  File "site-packages\taskgraph\Task.py", line 1234, in _call
  File "site-packages\natcap\invest\hydropower\hydropower_water_yield.py", line 717, in zonal_stats_tofile
  File "site-packages\pygeoprocessing\geoprocessing.py", line 1253, in zonal_statistics
  File "site-packages\pygeoprocessing\geoprocessing.py", line 2201, in calculate_disjoint_polygon_set
  File "site-packages\shapely\wkb.py", line 17, in loads
  File "site-packages\shapely\geos.py", line 431, in read
shapely.errors.WKBReadingError: Could not create geometry because of errors while reading input.
2021-03-09 21:15:38,657 model._logged_target(1652) ERROR Exception while executing <function execute at 0x0000013F87A88DC8>
Traceback (most recent call last):
  File "site-packages\natcap\invest\ui\model.py", line 1649, in _logged_target
  File "site-packages\natcap\invest\hydropower\hydropower_water_yield.py", line 592, in execute
  File "site-packages\taskgraph\Task.py", line 747, in add_task
  File "site-packages\taskgraph\Task.py", line 1234, in _call
  File "site-packages\natcap\invest\hydropower\hydropower_water_yield.py", line 717, in zonal_stats_tofile
  File "site-packages\pygeoprocessing\geoprocessing.py", line 1253, in zonal_statistics
  File "site-packages\pygeoprocessing\geoprocessing.py", line 2201, in calculate_disjoint_polygon_set
  File "site-packages\shapely\wkb.py", line 17, in loads
  File "site-packages\shapely\geos.py", line 431, in read
shapely.errors.WKBReadingError: Could not create geometry because of errors while reading input.
2021-03-09 21:15:38,662 model._logged_target(1655) INFO Execution finished
2021-03-09 21:15:38,678 utils.prepare_workspace(129) INFO Elapsed time: 40.17s