2022-08-06 13:36:02,972 (natcap.invest.utils) utils.prepare_workspace(162) Level 100 Writing log messages to [C:\Users\HarrisonSmith\OneDrive - Wave Hub Development Services Limited\Desktop\InVEST-natcap.invest.wind_energy-log-2022-08-06--13_36_02.txt] 2022-08-06 13:36:02,972 (__main__) cli.main(475) Level 100 Starting model with parameters: Arguments for InVEST natcap.invest.wind_energy 3.11.0: aoi_vector_path C:\Users\HarrisonSmith\OneDrive - Wave Hub Development Services Limited\Area of Interest.shp avg_grid_distance 0 bathymetry_path C:\Users\HarrisonSmith\OneDrive - Wave Hub Development Services Limited\Documents\InVEST\BathyRas.tif discount_rate 0.10 foundation_cost 5000000 global_wind_parameters_path C:\Users\HarrisonSmith\OneDrive - Wave Hub Development Services Limited\Documents\InVEST\global_wind_energy_parameters.csv grid_points_path C:\Users\HarrisonSmith\OneDrive - Wave Hub Development Services Limited\Documents\InVEST\WaveHubConnection.csv land_polygon_vector_path C:\Users\HarrisonSmith\OneDrive - Wave Hub Development Services Limited\Documents\InVEST\Over_gb (1)\VectorUK.shp max_depth 150 max_distance 250000 min_depth 50 min_distance 0 n_workers -1 number_of_turbines 66 price_table False rate_change 0.03 results_suffix Testrun3 turbine_parameters_path C:\Users\HarrisonSmith\OneDrive - Wave Hub Development Services Limited\Documents\InVEST\15_0_turbine.csv valuation_container True wind_data_path C:\Users\HarrisonSmith\OneDrive - Wave Hub Development Services Limited\Documents\InVEST\Celtic Sea Wind Parameters.csv wind_price 0.05 wind_schedule workspace_dir C:\Users\HarrisonSmith\OneDrive - Wave Hub Development Services Limited\Desktop 2022-08-06 13:36:02,972 (natcap.invest.wind_energy) wind_energy.execute(508) INFO Starting the Wind Energy Model 2022-08-06 13:36:02,972 (natcap.invest.validation) validation._wrapped_validate_func(1030) INFO Starting whole-model validation with ARGS_SPEC 2022-08-06 13:36:03,203 (natcap.invest.wind_energy) wind_energy.execute(594) INFO Valuation Selected. Checking required parameters from CSV files. 2022-08-06 13:36:03,205 (natcap.invest.wind_energy) wind_energy.execute(652) INFO Reading in Wind Data into a dictionary 2022-08-06 13:36:03,226 (natcap.invest.wind_energy) wind_energy.execute(667) INFO AOI Provided 2022-08-06 13:36:03,260 (natcap.invest.wind_energy) wind_energy.execute(682) INFO Clip and project bathymetry to AOI 2022-08-06 13:36:08,416 (pygeoprocessing.geoprocessing) geoprocessing.logger_callback(3320) INFO Warp 61.0% complete C:\Users\HarrisonSmith\OneDrive - Wave Hub Development Services Limited\Desktop\intermediate\bathymetry_projected_Testrun3.tif 2022-08-06 13:36:13,682 (pygeoprocessing.geoprocessing) geoprocessing.logger_callback(3320) INFO Warp 88.0% complete C:\Users\HarrisonSmith\OneDrive - Wave Hub Development Services Limited\Desktop\intermediate\bathymetry_projected_Testrun3.tif 2022-08-06 13:36:16,306 (pygeoprocessing.geoprocessing) geoprocessing.logger_callback(3320) INFO Warp 100.0% complete C:\Users\HarrisonSmith\OneDrive - Wave Hub Development Services Limited\Desktop\intermediate\bathymetry_projected_Testrun3.tif 2022-08-06 13:36:42,336 (pygeoprocessing.geoprocessing) geoprocessing.logger_callback(3322) INFO RasterizeLayer 100.0% complete 2022-08-06 13:36:43,157 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(396) INFO starting stats_worker 2022-08-06 13:36:43,159 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(402) INFO started stats_worker 2022-08-06 13:36:48,144 (pygeoprocessing.geoprocessing) geoprocessing.(477) INFO 72.7% complete 2022-08-06 13:36:51,175 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(482) INFO 100.0% complete 2022-08-06 13:36:51,175 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(485) INFO Waiting for raster stats worker result. 2022-08-06 13:36:53,292 (natcap.invest.wind_energy) wind_energy.execute(715) INFO Create point shapefile from wind data 2022-08-06 13:36:53,298 (natcap.invest.wind_energy) wind_energy._wind_data_to_point_vector(2288) INFO Entering _wind_data_to_point_vector 2022-08-06 13:36:53,332 (natcap.invest.wind_energy) wind_energy._wind_data_to_point_vector(2337) INFO Creating fields for the target vector 2022-08-06 13:36:53,332 (natcap.invest.wind_energy) wind_energy._wind_data_to_point_vector(2342) INFO Entering iteration to create and set the features 2022-08-06 13:36:53,335 (natcap.invest.wind_energy) wind_energy._wind_data_to_point_vector(2370) INFO Finished _wind_data_to_point_vector 2022-08-06 13:36:53,340 (natcap.invest.wind_energy) wind_energy.execute(727) INFO Clip and project wind points to AOI 2022-08-06 13:36:53,346 (natcap.invest.wind_energy) wind_energy._clip_vector_by_vector(2444) INFO Entering _clip_vector_by_vector 2022-08-06 13:36:53,399 (pygeoprocessing.geoprocessing) geoprocessing.reproject_vector(1741) INFO starting reprojection 2022-08-06 13:36:54,918 (osgeo) utils._log_gdal_errors(98) WARNING [errno 1] Value 172304799893 of field Shape__Are of feature 0 not successfully written. Possibly due to too larger number with respect to field width 2022-08-06 13:36:54,921 (pygeoprocessing.geoprocessing) geoprocessing.reproject_vector(1783) INFO reprojection 100.0% complete on reprojected_clip_vector.shp 2022-08-06 13:36:57,265 (natcap.invest.wind_energy) wind_energy._clip_vector_by_vector(2505) INFO Finished _clip_vector_by_vector 2022-08-06 13:36:57,270 (natcap.invest.wind_energy) wind_energy.execute(758) INFO Clip and project land polygon to AOI 2022-08-06 13:36:57,275 (natcap.invest.wind_energy) wind_energy._clip_and_reproject_vector(2389) INFO Entering _clip_and_reproject_vector 2022-08-06 13:36:57,351 (pygeoprocessing.geoprocessing) geoprocessing.reproject_vector(1741) INFO starting reprojection 2022-08-06 13:36:58,955 (osgeo) utils._log_gdal_errors(98) WARNING [errno 1] Value 172304799893 of field Shape__Are of feature 0 not successfully written. Possibly due to too larger number with respect to field width 2022-08-06 13:36:58,956 (pygeoprocessing.geoprocessing) geoprocessing.reproject_vector(1783) INFO reprojection 100.0% complete on reprojected_clip_vector.shp 2022-08-06 13:36:58,958 (natcap.invest.wind_energy) wind_energy._clip_vector_by_vector(2444) INFO Entering _clip_vector_by_vector 2022-08-06 17:37:30,387 (natcap.invest.wind_energy) wind_energy._clip_vector_by_vector(2505) INFO Finished _clip_vector_by_vector 2022-08-06 17:37:30,497 (pygeoprocessing.geoprocessing) geoprocessing.reproject_vector(1741) INFO starting reprojection 2022-08-06 17:37:32,461 (pygeoprocessing.geoprocessing) geoprocessing.reproject_vector(1783) INFO reprojection 100.0% complete on projected_clipped_land_poly_Testrun3.shp 2022-08-06 17:37:32,476 (natcap.invest.wind_energy) wind_energy._clip_and_reproject_vector(2422) INFO Finished _clip_and_reproject_vector 2022-08-06 17:37:32,483 (natcap.invest.wind_energy) wind_energy.execute(776) INFO Create Raster From AOI 2022-08-06 17:37:32,526 (pygeoprocessing.geoprocessing) geoprocessing.reproject_vector(1741) INFO starting reprojection 2022-08-06 17:37:36,064 (osgeo) utils._log_gdal_errors(98) WARNING [errno 1] Value 172304799893 of field Shape__Are of feature 0 not successfully written. Possibly due to too larger number with respect to field width 2022-08-06 17:37:36,068 (pygeoprocessing.geoprocessing) geoprocessing.reproject_vector(1783) INFO reprojection 100.0% complete on reprojected_aoi.shp 2022-08-06 17:37:36,151 (natcap.invest.wind_energy) wind_energy._create_distance_raster(1785) INFO Starting _create_distance_raster 2022-08-06 17:37:47,705 (pygeoprocessing.geoprocessing) geoprocessing.(477) INFO 76.8% complete 2022-08-06 17:37:49,954 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(482) INFO 100.0% complete 2022-08-06 17:38:03,670 (pygeoprocessing.geoprocessing_core) geoprocessing.distance_transform_edt(2436) INFO Distance Transform Phase 2 2022-08-06 17:39:24,080 (natcap.invest.wind_energy) wind_energy._create_distance_raster(1826) INFO Finished _create_distance_raster 2022-08-06 17:39:24,133 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(396) INFO starting stats_worker 2022-08-06 17:39:24,135 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(402) INFO started stats_worker 2022-08-06 17:39:29,120 (pygeoprocessing.geoprocessing) geoprocessing.(477) INFO 33.8% complete 2022-08-06 17:39:34,131 (pygeoprocessing.geoprocessing) geoprocessing.(477) INFO 54.6% complete 2022-08-06 17:39:39,136 (pygeoprocessing.geoprocessing) geoprocessing.(477) INFO 72.2% complete 2022-08-06 17:39:44,136 (pygeoprocessing.geoprocessing) geoprocessing.(477) INFO 91.6% complete 2022-08-06 17:39:46,535 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(482) INFO 100.0% complete 2022-08-06 17:39:46,536 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(485) INFO Waiting for raster stats worker result. 2022-08-06 17:39:51,439 (natcap.invest.wind_energy) wind_energy.execute(846) INFO Creating Depth Mask 2022-08-06 17:39:51,470 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(396) INFO starting stats_worker 2022-08-06 17:39:51,471 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(402) INFO started stats_worker 2022-08-06 17:39:56,461 (pygeoprocessing.geoprocessing) geoprocessing.(477) INFO 44.2% complete 2022-08-06 17:40:01,464 (pygeoprocessing.geoprocessing) geoprocessing.(477) INFO 67.6% complete 2022-08-06 17:40:06,469 (pygeoprocessing.geoprocessing) geoprocessing.(477) INFO 88.7% complete 2022-08-06 17:40:08,810 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(482) INFO 100.0% complete 2022-08-06 17:40:08,810 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(485) INFO Waiting for raster stats worker result. 2022-08-06 17:40:13,036 (natcap.invest.wind_energy) wind_energy.execute(865) INFO Create Density Raster 2022-08-06 17:40:13,074 (natcap.invest.wind_energy) wind_energy.execute(874) INFO Create Harvested Raster 2022-08-06 17:40:13,075 (taskgraph.Task) Task.add_task(653) INFO A task was created that had an identical args signature sans target paths, but a different target_path_list of the same length. To avoid recomputation, dynamically adding previous Task (create_density_raster (10)) as a dependent task to this one (create_harvested_raster (11)). 2022-08-06 17:40:13,081 (taskgraph.Task) Task.is_precalculated(1252) INFO not precalculated (create_harvested_raster (11)), Task hash exists, but there are these mismatches: Recorded path not in target path list c:\users\harrisonsmith\onedrive - wave hub development services limited\desktop\intermediate\temp_density_testrun3.tif 2022-08-06 17:40:13,115 (natcap.invest.wind_energy) wind_energy.execute(884) INFO Interpolate Density Points 2022-08-06 17:40:58,800 (natcap.invest.wind_energy) wind_energy.execute(893) INFO Interpolate Harvested Points 2022-08-06 17:41:44,561 (natcap.invest.wind_energy) wind_energy.execute(932) INFO Align and resize the Density rasters 2022-08-06 17:41:49,737 (pygeoprocessing.geoprocessing) geoprocessing.logger_callback(3320) INFO Warp 66.0% complete C:\Users\HarrisonSmith\OneDrive - Wave Hub Development Services Limited\Desktop\intermediate\temp_density_aligned_Testrun3.tif 2022-08-06 17:41:52,451 (pygeoprocessing.geoprocessing) geoprocessing.logger_callback(3320) INFO Warp 100.0% complete C:\Users\HarrisonSmith\OneDrive - Wave Hub Development Services Limited\Desktop\intermediate\temp_density_aligned_Testrun3.tif 2022-08-06 17:41:57,237 (pygeoprocessing.geoprocessing) geoprocessing.align_and_resize_raster_stack(824) INFO 1 of 4 aligned: temp_density_aligned_Testrun3.tif 2022-08-06 17:42:02,427 (pygeoprocessing.geoprocessing) geoprocessing.logger_callback(3320) INFO Warp 69.0% complete C:\Users\HarrisonSmith\OneDrive - Wave Hub Development Services Limited\Desktop\intermediate\depth_mask_aligned_Testrun3.tif 2022-08-06 17:42:05,535 (pygeoprocessing.geoprocessing) geoprocessing.logger_callback(3320) INFO Warp 100.0% complete C:\Users\HarrisonSmith\OneDrive - Wave Hub Development Services Limited\Desktop\intermediate\depth_mask_aligned_Testrun3.tif 2022-08-06 17:42:11,443 (pygeoprocessing.geoprocessing) geoprocessing.align_and_resize_raster_stack(824) INFO 2 of 4 aligned: depth_mask_aligned_Testrun3.tif 2022-08-06 17:42:16,536 (pygeoprocessing.geoprocessing) geoprocessing.logger_callback(3320) INFO Warp 66.0% complete C:\Users\HarrisonSmith\OneDrive - Wave Hub Development Services Limited\Desktop\intermediate\distance_mask_aligned_Testrun3.tif 2022-08-06 17:42:20,337 (pygeoprocessing.geoprocessing) geoprocessing.logger_callback(3320) INFO Warp 100.0% complete C:\Users\HarrisonSmith\OneDrive - Wave Hub Development Services Limited\Desktop\intermediate\distance_mask_aligned_Testrun3.tif 2022-08-06 17:42:23,945 (pygeoprocessing.geoprocessing) geoprocessing.align_and_resize_raster_stack(824) INFO 3 of 4 aligned: distance_mask_aligned_Testrun3.tif 2022-08-06 17:42:29,090 (pygeoprocessing.geoprocessing) geoprocessing.logger_callback(3320) INFO Warp 76.0% complete C:\Users\HarrisonSmith\OneDrive - Wave Hub Development Services Limited\Desktop\intermediate\temp_harvested_aligned_Testrun3.tif 2022-08-06 17:42:31,018 (pygeoprocessing.geoprocessing) geoprocessing.logger_callback(3320) INFO Warp 100.0% complete C:\Users\HarrisonSmith\OneDrive - Wave Hub Development Services Limited\Desktop\intermediate\temp_harvested_aligned_Testrun3.tif 2022-08-06 17:42:35,358 (pygeoprocessing.geoprocessing) geoprocessing.align_and_resize_raster_stack(824) INFO 4 of 4 aligned: temp_harvested_aligned_Testrun3.tif 2022-08-06 17:42:35,359 (pygeoprocessing.geoprocessing) geoprocessing.align_and_resize_raster_stack(828) INFO aligned all 4 rasters. 2022-08-06 17:42:35,366 (natcap.invest.wind_energy) wind_energy.execute(963) INFO Mask out depth and [distance] areas from Density raster 2022-08-06 17:42:35,401 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(396) INFO starting stats_worker 2022-08-06 17:42:35,403 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(402) INFO started stats_worker 2022-08-06 17:42:40,400 (pygeoprocessing.geoprocessing) geoprocessing.(477) INFO 37.7% complete 2022-08-06 17:42:45,409 (pygeoprocessing.geoprocessing) geoprocessing.(477) INFO 63.6% complete 2022-08-06 17:42:50,415 (pygeoprocessing.geoprocessing) geoprocessing.(477) INFO 87.4% complete 2022-08-06 17:42:53,099 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(482) INFO 100.0% complete 2022-08-06 17:42:53,099 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(485) INFO Waiting for raster stats worker result. 2022-08-06 17:42:55,565 (natcap.invest.wind_energy) wind_energy.execute(973) INFO Mask out depth and [distance] areas from Harvested raster 2022-08-06 17:42:55,601 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(396) INFO starting stats_worker 2022-08-06 17:42:55,603 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(402) INFO started stats_worker 2022-08-06 17:43:00,595 (pygeoprocessing.geoprocessing) geoprocessing.(477) INFO 34.4% complete 2022-08-06 17:43:05,599 (pygeoprocessing.geoprocessing) geoprocessing.(477) INFO 62.3% complete 2022-08-06 17:43:10,601 (pygeoprocessing.geoprocessing) geoprocessing.(477) INFO 87.3% complete 2022-08-06 17:43:13,085 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(482) INFO 100.0% complete 2022-08-06 17:43:13,085 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(485) INFO Waiting for raster stats worker result. 2022-08-06 17:43:15,362 (natcap.invest.wind_energy) wind_energy.execute(983) INFO Wind Energy Biophysical Model completed 2022-08-06 17:43:15,364 (natcap.invest.wind_energy) wind_energy.execute(986) INFO Starting Wind Energy Valuation Model 2022-08-06 17:43:15,364 (natcap.invest.wind_energy) wind_energy.execute(999) INFO Grid Points Provided. Reading in the grid points 2022-08-06 17:43:15,476 (natcap.invest.wind_energy) wind_energy._clip_and_reproject_vector(2389) INFO Entering _clip_and_reproject_vector 2022-08-06 17:43:15,510 (pygeoprocessing.geoprocessing) geoprocessing.reproject_vector(1741) INFO starting reprojection 2022-08-06 17:43:18,542 (osgeo) utils._log_gdal_errors(98) WARNING [errno 1] Value 172304799893 of field Shape__Are of feature 0 not successfully written. Possibly due to too larger number with respect to field width 2022-08-06 17:43:18,544 (pygeoprocessing.geoprocessing) geoprocessing.reproject_vector(1783) INFO reprojection 100.0% complete on reprojected_clip_vector.shp 2022-08-06 17:43:18,548 (natcap.invest.wind_energy) wind_energy._clip_vector_by_vector(2444) INFO Entering _clip_vector_by_vector 2022-08-06 17:43:18,918 (taskgraph.Task) Task.add_task(706) ERROR Something went wrong when adding task clip_and_reproject_grid_vector (17), terminating taskgraph. Traceback (most recent call last): File "taskgraph\Task.py", line 674, in add_task File "taskgraph\Task.py", line 1093, in _call File "natcap\invest\wind_energy.py", line 2413, in _clip_and_reproject_vector File "natcap\invest\wind_energy.py", line 2500, in _clip_vector_by_vector ValueError: Clipping C:\Users\HarrisonSmith\OneDrive - Wave Hub Development Services Limited\Desktop\intermediate\val_grid_points_Testrun3.shp by C:\Users\HarrisonSmith\OneDrive - Wave Hub Development Services Limited\Desktop\intermediate\clip-reproject-nrbfrygl\reprojected_clip_vector.shp returned 0 features. If an AOI was provided this could mean the AOI and Wind Data do not intersect spatially. 2022-08-06 17:43:18,923 (natcap.invest.utils) utils.prepare_workspace(167) ERROR Exception while executing natcap.invest.wind_energy Traceback (most recent call last): File "natcap\invest\utils.py", line 165, in prepare_workspace File "natcap\invest\cli.py", line 486, in main File "natcap\invest\wind_energy.py", line 1033, in execute File "taskgraph\Task.py", line 674, in add_task File "taskgraph\Task.py", line 1093, in _call File "natcap\invest\wind_energy.py", line 2413, in _clip_and_reproject_vector File "natcap\invest\wind_energy.py", line 2500, in _clip_vector_by_vector ValueError: Clipping C:\Users\HarrisonSmith\OneDrive - Wave Hub Development Services Limited\Desktop\intermediate\val_grid_points_Testrun3.shp by C:\Users\HarrisonSmith\OneDrive - Wave Hub Development Services Limited\Desktop\intermediate\clip-reproject-nrbfrygl\reprojected_clip_vector.shp returned 0 features. If an AOI was provided this could mean the AOI and Wind Data do not intersect spatially. 2022-08-06 17:43:18,923 (natcap.invest.utils) utils.prepare_workspace(170) INFO Elapsed time: 4h 7m 15.950000000000728s 2022-08-06 17:43:18,923 (natcap.invest.utils) utils.prepare_workspace(173) INFO Execution finished