2020-11-05 20:44:15,913 utils.prepare_workspace(111) INFO Writing log messages to D:/Urban_Flood_Risk_Mitigation\InVEST-UrbanFloodRiskMitigation-log-2020-11-05--20_44_15.txt
2020-11-05 20:44:15,914 model._logged_target(1626) Level 100 Starting model with parameters: 
Arguments for InVEST natcap.invest.urban_flood_risk_mitigation 3.8.9:
aoi_watersheds_path                   E:/Carlotta/InVEST-Flood/Genova/Watershed/Clip_Bacino.shp
built_infrastructure_vector_path      E:/Carlotta/InVEST-Flood/Genova/Input_data/Infrastructure_multi.shp
curve_number_table_path               E:/Carlotta/InVEST-Flood/Genova/Input_data/tables/Biophysical_table.csv
infrastructure_damage_loss_table_path E:/Carlotta/InVEST-Flood/Genova/Input_data/tables/Damage_table.csv
lulc_path                             E:/Carlotta/InVEST-Flood/Genova/LULC_Liguria_2019/LULC_raster/lulc_map_1/w001001.adf
n_workers                             -1
rainfall_depth                        50
results_suffix                        Genova
soils_hydrological_group_raster_path  E:/Carlotta/InVEST-Flood/Genova/UDP/hsg_map_1/w001001.adf
workspace_dir                         D:/Urban_Flood_Risk_Mitigation

2020-11-05 20:44:16,215 validation._wrapped_validate_func(912) DEBUG Using ARG_SPEC for validation
2020-11-05 20:44:16,215 validation._wrapped_validate_func(916) INFO Starting whole-model validation with ARGS_SPEC
2020-11-05 20:44:16,217 validation.timeout(626) INFO Starting file checking thread with timeout=5
2020-11-05 20:44:16,223 validation.timeout(638) INFO File checking thread completed.
2020-11-05 20:44:16,224 validation.timeout(626) INFO Starting file checking thread with timeout=5
2020-11-05 20:44:16,235 validation.timeout(638) INFO File checking thread completed.
2020-11-05 20:44:16,236 validation.timeout(626) INFO Starting file checking thread with timeout=5
2020-11-05 20:44:16,240 validation.timeout(638) INFO File checking thread completed.
2020-11-05 20:44:16,241 validation.timeout(626) INFO Starting file checking thread with timeout=5
2020-11-05 20:44:16,246 validation.timeout(638) INFO File checking thread completed.
2020-11-05 20:44:16,246 validation.timeout(626) INFO Starting file checking thread with timeout=5
2020-11-05 20:44:16,261 validation.timeout(638) INFO File checking thread completed.
2020-11-05 20:44:16,262 validation.timeout(626) INFO Starting file checking thread with timeout=5
2020-11-05 20:44:16,268 validation.timeout(638) INFO File checking thread completed.
2020-11-05 20:44:16,269 validation.timeout(626) INFO Starting file checking thread with timeout=5
2020-11-05 20:44:16,276 validation.timeout(638) INFO File checking thread completed.
2020-11-05 20:44:16,332 validation._wrapped_validate_func(963) DEBUG Validation warnings: []
2020-11-05 20:44:16,935 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 2 aligned: aligned_lulc_Genova.tif
2020-11-05 20:44:16,953 geoprocessing.align_and_resize_raster_stack(752) INFO 2 of 2 aligned: aligned_soils_hydrological_group_Genova.tif
2020-11-05 20:44:16,953 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 2 rasters.
2020-11-05 20:44:17,091 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-11-05 20:44:17,093 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-217, started daemon 15452)>
2020-11-05 20:44:17,120 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-11-05 20:44:17,121 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-11-05 20:44:17,121 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-11-05 20:44:17,124 threading.run(870) DEBUG payload is None, terminating
2020-11-05 20:44:17,248 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-11-05 20:44:17,249 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-218, started daemon 15964)>
2020-11-05 20:44:17,253 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-11-05 20:44:17,253 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-11-05 20:44:17,254 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-11-05 20:44:17,254 threading.run(870) DEBUG payload is None, terminating
2020-11-05 20:44:17,396 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-11-05 20:44:17,398 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-219, started daemon 1288)>
2020-11-05 20:44:17,410 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-11-05 20:44:17,410 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-11-05 20:44:17,411 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-11-05 20:44:17,413 threading.run(870) DEBUG payload is None, terminating
2020-11-05 20:44:17,569 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-11-05 20:44:17,571 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-220, started daemon 14660)>
2020-11-05 20:44:17,583 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-11-05 20:44:17,584 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-11-05 20:44:17,584 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-11-05 20:44:17,585 threading.run(870) DEBUG payload is None, terminating
2020-11-05 20:44:17,742 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-11-05 20:44:17,744 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-221, started daemon 10000)>
2020-11-05 20:44:17,754 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-11-05 20:44:17,755 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-11-05 20:44:17,755 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-11-05 20:44:17,756 threading.run(870) DEBUG payload is None, terminating
2020-11-05 20:44:17,911 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-11-05 20:44:17,912 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-222, started daemon 15408)>
2020-11-05 20:44:17,916 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-11-05 20:44:17,917 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-11-05 20:44:17,917 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-11-05 20:44:17,917 threading.run(870) DEBUG payload is None, terminating
2020-11-05 20:44:18,180 geoprocessing.reproject_vector(1640) INFO starting reprojection
2020-11-05 20:44:18,396 geoprocessing.reproject_vector(1683) INFO reprojection 100.0% complete on reprojected_aoi.gpkg
2020-11-05 20:44:18,555 geoprocessing.zonal_statistics(1129) DEBUG <osgeo.gdal.Dataset; proxy of <Swig Object of type 'GDALDatasetShadow *' at 0x23DDEE48> >
2020-11-05 20:44:18,662 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 1 aligned: clipped_rastern0sj326v.tif
2020-11-05 20:44:18,663 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 1 rasters.
2020-11-05 20:44:18,667 geoprocessing.zonal_statistics(1189) INFO build a lookup of aggregate field value to FID
2020-11-05 20:44:18,688 geoprocessing.zonal_statistics(1196) INFO creating disjoint polygon set
2020-11-05 20:44:18,700 geoprocessing.calculate_disjoint_polygon_set(2082) INFO build shapely polygon list
2020-11-05 20:44:18,820 geoprocessing.calculate_disjoint_polygon_set(2096) INFO build shapely rtree index
2020-11-05 20:44:18,835 geoprocessing.calculate_disjoint_polygon_set(2111) INFO poly feature lookup 100.0% complete on reprojected_aoi.gpkg
2020-11-05 20:44:18,835 geoprocessing.calculate_disjoint_polygon_set(2113) INFO build poly intersection lookup
2020-11-05 20:44:18,931 geoprocessing.calculate_disjoint_polygon_set(2137) INFO poly intersection feature lookup 100.0% complete on reprojected_aoi.gpkg
2020-11-05 20:44:18,931 geoprocessing.calculate_disjoint_polygon_set(2171) INFO maximal subset build 100.0% complete on reprojected_aoi.gpkg
2020-11-05 20:44:18,997 geoprocessing.zonal_statistics(1219) INFO processing 5 disjoint polygon sets
2020-11-05 20:44:19,024 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 1 of 5 100.0% processed on reprojected_aoi.gpkg
2020-11-05 20:44:19,025 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 1 of 5 reprojected_aoi.gpkg
2020-11-05 20:44:19,052 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 1 of 5 reprojected_aoi.gpkg
2020-11-05 20:44:19,094 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 2 of 5 100.0% processed on reprojected_aoi.gpkg
2020-11-05 20:44:19,095 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 2 of 5 reprojected_aoi.gpkg
2020-11-05 20:44:19,113 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 2 of 5 reprojected_aoi.gpkg
2020-11-05 20:44:19,140 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 3 of 5 100.0% processed on reprojected_aoi.gpkg
2020-11-05 20:44:19,141 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 3 of 5 reprojected_aoi.gpkg
2020-11-05 20:44:19,170 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 3 of 5 reprojected_aoi.gpkg
2020-11-05 20:44:19,188 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 4 of 5 100.0% processed on reprojected_aoi.gpkg
2020-11-05 20:44:19,189 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 4 of 5 reprojected_aoi.gpkg
2020-11-05 20:44:19,206 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 4 of 5 reprojected_aoi.gpkg
2020-11-05 20:44:19,216 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 5 of 5 100.0% processed on reprojected_aoi.gpkg
2020-11-05 20:44:19,218 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 5 of 5 reprojected_aoi.gpkg
2020-11-05 20:44:19,234 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 5 of 5 reprojected_aoi.gpkg
2020-11-05 20:44:19,241 geoprocessing.zonal_statistics(1322) DEBUG unset_fids: 9 of 139 
2020-11-05 20:44:19,245 geoprocessing.zonal_statistics(1325) DEBUG gt [ 4.6680356e+05  1.6981973e+02  0.0000000e+00  4.9348490e+06
  0.0000000e+00 -1.6981973e+02] for ('D:/Urban_Flood_Risk_Mitigation\\intermediate_files\\Q_m3_Genova.tif', 1)
2020-11-05 20:44:19,254 geoprocessing.zonal_statistics(1399) DEBUG remaining unset_fids: 0 of 139 
2020-11-05 20:44:19,254 geoprocessing.zonal_statistics(1407) INFO all done processing polygon sets for reprojected_aoi.gpkg
2020-11-05 20:44:19,419 geoprocessing.zonal_statistics(1129) DEBUG <osgeo.gdal.Dataset; proxy of <Swig Object of type 'GDALDatasetShadow *' at 0x016CC1B8> >
2020-11-05 20:44:19,528 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 1 aligned: clipped_rasters2sd6i1c.tif
2020-11-05 20:44:19,529 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 1 rasters.
2020-11-05 20:44:19,533 geoprocessing.zonal_statistics(1189) INFO build a lookup of aggregate field value to FID
2020-11-05 20:44:19,552 geoprocessing.zonal_statistics(1196) INFO creating disjoint polygon set
2020-11-05 20:44:19,564 geoprocessing.calculate_disjoint_polygon_set(2082) INFO build shapely polygon list
2020-11-05 20:44:19,677 geoprocessing.calculate_disjoint_polygon_set(2096) INFO build shapely rtree index
2020-11-05 20:44:19,690 geoprocessing.calculate_disjoint_polygon_set(2111) INFO poly feature lookup 100.0% complete on reprojected_aoi.gpkg
2020-11-05 20:44:19,691 geoprocessing.calculate_disjoint_polygon_set(2113) INFO build poly intersection lookup
2020-11-05 20:44:19,786 geoprocessing.calculate_disjoint_polygon_set(2137) INFO poly intersection feature lookup 100.0% complete on reprojected_aoi.gpkg
2020-11-05 20:44:19,787 geoprocessing.calculate_disjoint_polygon_set(2171) INFO maximal subset build 100.0% complete on reprojected_aoi.gpkg
2020-11-05 20:44:19,822 geoprocessing.zonal_statistics(1219) INFO processing 5 disjoint polygon sets
2020-11-05 20:44:19,849 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 1 of 5 100.0% processed on reprojected_aoi.gpkg
2020-11-05 20:44:19,852 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 1 of 5 reprojected_aoi.gpkg
2020-11-05 20:44:19,878 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 1 of 5 reprojected_aoi.gpkg
2020-11-05 20:44:19,919 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 2 of 5 100.0% processed on reprojected_aoi.gpkg
2020-11-05 20:44:19,920 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 2 of 5 reprojected_aoi.gpkg
2020-11-05 20:44:19,939 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 2 of 5 reprojected_aoi.gpkg
2020-11-05 20:44:19,955 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 3 of 5 100.0% processed on reprojected_aoi.gpkg
2020-11-05 20:44:19,956 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 3 of 5 reprojected_aoi.gpkg
2020-11-05 20:44:19,964 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 3 of 5 reprojected_aoi.gpkg
2020-11-05 20:44:19,969 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 4 of 5 100.0% processed on reprojected_aoi.gpkg
2020-11-05 20:44:19,970 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 4 of 5 reprojected_aoi.gpkg
2020-11-05 20:44:19,973 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 4 of 5 reprojected_aoi.gpkg
2020-11-05 20:44:19,977 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 5 of 5 100.0% processed on reprojected_aoi.gpkg
2020-11-05 20:44:19,978 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 5 of 5 reprojected_aoi.gpkg
2020-11-05 20:44:19,982 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 5 of 5 reprojected_aoi.gpkg
2020-11-05 20:44:19,984 geoprocessing.zonal_statistics(1322) DEBUG unset_fids: 9 of 139 
2020-11-05 20:44:19,985 geoprocessing.zonal_statistics(1325) DEBUG gt [ 4.6680356e+05  1.6981973e+02  0.0000000e+00  4.9348490e+06
  0.0000000e+00 -1.6981973e+02] for ('D:/Urban_Flood_Risk_Mitigation\\Runoff_retention_Genova.tif', 1)
2020-11-05 20:44:19,988 geoprocessing.zonal_statistics(1399) DEBUG remaining unset_fids: 0 of 139 
2020-11-05 20:44:19,988 geoprocessing.zonal_statistics(1407) INFO all done processing polygon sets for reprojected_aoi.gpkg
2020-11-05 20:44:20,124 geoprocessing.zonal_statistics(1129) DEBUG <osgeo.gdal.Dataset; proxy of <Swig Object of type 'GDALDatasetShadow *' at 0x016CC1B8> >
2020-11-05 20:44:20,196 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 1 aligned: clipped_raster4m_en509.tif
2020-11-05 20:44:20,197 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 1 rasters.
2020-11-05 20:44:20,199 geoprocessing.zonal_statistics(1189) INFO build a lookup of aggregate field value to FID
2020-11-05 20:44:20,205 geoprocessing.zonal_statistics(1196) INFO creating disjoint polygon set
2020-11-05 20:44:20,210 geoprocessing.calculate_disjoint_polygon_set(2082) INFO build shapely polygon list
2020-11-05 20:44:20,260 geoprocessing.calculate_disjoint_polygon_set(2096) INFO build shapely rtree index
2020-11-05 20:44:20,274 geoprocessing.calculate_disjoint_polygon_set(2111) INFO poly feature lookup 100.0% complete on reprojected_aoi.gpkg
2020-11-05 20:44:20,275 geoprocessing.calculate_disjoint_polygon_set(2113) INFO build poly intersection lookup
2020-11-05 20:44:20,371 geoprocessing.calculate_disjoint_polygon_set(2137) INFO poly intersection feature lookup 100.0% complete on reprojected_aoi.gpkg
2020-11-05 20:44:20,372 geoprocessing.calculate_disjoint_polygon_set(2171) INFO maximal subset build 100.0% complete on reprojected_aoi.gpkg
2020-11-05 20:44:20,382 geoprocessing.zonal_statistics(1219) INFO processing 5 disjoint polygon sets
2020-11-05 20:44:20,390 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 1 of 5 100.0% processed on reprojected_aoi.gpkg
2020-11-05 20:44:20,391 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 1 of 5 reprojected_aoi.gpkg
2020-11-05 20:44:20,399 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 1 of 5 reprojected_aoi.gpkg
2020-11-05 20:44:20,411 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 2 of 5 100.0% processed on reprojected_aoi.gpkg
2020-11-05 20:44:20,412 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 2 of 5 reprojected_aoi.gpkg
2020-11-05 20:44:20,417 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 2 of 5 reprojected_aoi.gpkg
2020-11-05 20:44:20,425 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 3 of 5 100.0% processed on reprojected_aoi.gpkg
2020-11-05 20:44:20,426 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 3 of 5 reprojected_aoi.gpkg
2020-11-05 20:44:20,435 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 3 of 5 reprojected_aoi.gpkg
2020-11-05 20:44:20,441 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 4 of 5 100.0% processed on reprojected_aoi.gpkg
2020-11-05 20:44:20,442 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 4 of 5 reprojected_aoi.gpkg
2020-11-05 20:44:20,447 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 4 of 5 reprojected_aoi.gpkg
2020-11-05 20:44:20,450 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 5 of 5 100.0% processed on reprojected_aoi.gpkg
2020-11-05 20:44:20,451 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 5 of 5 reprojected_aoi.gpkg
2020-11-05 20:44:20,457 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 5 of 5 reprojected_aoi.gpkg
2020-11-05 20:44:20,460 geoprocessing.zonal_statistics(1322) DEBUG unset_fids: 9 of 139 
2020-11-05 20:44:20,461 geoprocessing.zonal_statistics(1325) DEBUG gt [ 4.6680356e+05  1.6981973e+02  0.0000000e+00  4.9348490e+06
  0.0000000e+00 -1.6981973e+02] for ('D:/Urban_Flood_Risk_Mitigation\\Runoff_retention_m3_Genova.tif', 1)
2020-11-05 20:44:20,465 geoprocessing.zonal_statistics(1399) DEBUG remaining unset_fids: 0 of 139 
2020-11-05 20:44:20,465 geoprocessing.zonal_statistics(1407) INFO all done processing polygon sets for reprojected_aoi.gpkg
2020-11-05 20:44:20,780 geoprocessing.reproject_vector(1640) INFO starting reprojection
2020-11-05 20:44:22,282 geoprocessing.reproject_vector(1683) INFO reprojection 100.0% complete on structures_reprojected.gpkg
2020-11-05 20:45:28,519 urban_flood_risk_mitigation._write_summary_vector(471) DEBUG creating layer flood_risk_service_Genova
2020-11-05 20:45:28,726 model._logged_target(1635) INFO Execution finished
2020-11-05 20:45:28,728 utils.prepare_workspace(117) INFO Elapsed time: 1m 12.810000000000002s