2020-04-07 16:51:37,616 utils.prepare_workspace(115) INFO Writing log messages to E:/ELD_Final_16.10.19/Villages_shapefile/Datia/INVEST/GLOBIO/OUTPUT_BENE_18\InVEST-GLOBIO-log-2020-04-07--16_51_37.txt
2020-04-07 16:51:37,619 model._logged_target(1642) Level 100 Starting model with parameters: 
Arguments for InVEST natcap.invest.globio 3.8.0:
aoi_path                  E:/ELD_Final_16.10.19/Villages_shapefile/Datia/bene_2018_re.shp
globio_lulc_path          E:/ELD_Final_16.10.19/Villages_shapefile/Datia/INVEST/GLOBIO/bene_2018_new.tif
infrastructure_dir        E:/ELD_Final_16.10.19/Villages_shapefile/Datia/INVEST/GLOBIO/INFRA_DIR_BENE_18
intensification_fraction  0.4
lulc_path                 
lulc_to_globio_table_path 
msa_parameters_path       E:/INVEST/invest_3.7.0_data_globio/globio/msa_parameters.csv
n_workers                 -1
pasture_path              
pasture_threshold         
potential_vegetation_path 
predefined_globio         True
primary_threshold         
results_suffix            
workspace_dir             E:/ELD_Final_16.10.19/Villages_shapefile/Datia/INVEST/GLOBIO/OUTPUT_BENE_18

2020-04-07 16:51:38,577 globio.execute(274) INFO no need to calculate GLOBIO LULC because it is passed in
2020-04-07 16:51:38,789 globio._collapse_infrastructure_layers(962) INFO collapse infrastructure into one raster
2020-04-07 16:51:38,795 geoprocessing.align_and_resize_raster_stack(646) DEBUG assuming manual bounding box mode of [8718268.279918736, 2928557.66436556, 8770447.035094498, 2983032.2847690554]
2020-04-07 16:51:38,813 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 1 aligned: tmpbfvtvy2e.tif
2020-04-07 16:51:38,813 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 1 rasters.
2020-04-07 16:51:38,854 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-04-07 16:51:38,857 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-2, started daemon 14200)>
2020-04-07 16:51:38,872 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-04-07 16:51:38,873 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-04-07 16:51:38,875 threading.run(870) DEBUG payload is None, terminating
2020-04-07 16:51:38,875 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-04-07 16:51:38,962 globio.execute(296) INFO create mask of primary veg areas
2020-04-07 16:51:39,035 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-04-07 16:51:39,039 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-3, started daemon 11912)>
2020-04-07 16:51:39,055 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-04-07 16:51:39,056 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-04-07 16:51:39,058 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-04-07 16:51:39,059 threading.run(870) DEBUG payload is None, terminating
2020-04-07 16:51:39,147 globio.execute(307) INFO smooth primary veg areas with gaussian filter
2020-04-07 16:51:39,429 geoprocessing.convolve_2d(2422) INFO starting convolve
2020-04-07 16:51:39,539 geoprocessing.convolve_2d(2525) INFO convolution worker 100.0% complete on smoothed_primary_veg_mask.tif
2020-04-07 16:51:39,618 globio.execute(318) INFO calculate primary_veg_smooth
2020-04-07 16:51:39,691 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-04-07 16:51:39,694 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-5, started daemon 19400)>
2020-04-07 16:51:39,707 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-04-07 16:51:39,708 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-04-07 16:51:39,709 threading.run(870) DEBUG payload is None, terminating
2020-04-07 16:51:39,709 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-04-07 16:51:39,816 globio.execute(333) INFO calculate msa_f
2020-04-07 16:51:39,907 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-04-07 16:51:39,911 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-6, started daemon 18480)>
2020-04-07 16:51:39,934 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-04-07 16:51:39,936 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-04-07 16:51:39,938 threading.run(870) DEBUG payload is None, terminating
2020-04-07 16:51:39,937 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-04-07 16:51:40,043 globio.execute(351) INFO distance transform infrasture raster
2020-04-07 16:51:40,074 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-04-07 16:51:40,167 geoprocessing.distance_transform_edt(2257) INFO Distance Transform Phase 2
2020-04-07 16:51:40,281 globio.execute(361) INFO calculate msa_i
2020-04-07 16:51:40,325 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-04-07 16:51:40,327 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-7, started daemon 13744)>
2020-04-07 16:51:40,339 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-04-07 16:51:40,339 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-04-07 16:51:40,340 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-04-07 16:51:40,340 threading.run(870) DEBUG payload is None, terminating
2020-04-07 16:51:40,422 globio.execute(376) INFO calculate msa_lu
2020-04-07 16:51:40,466 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-04-07 16:51:40,468 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-8, started daemon 22660)>
2020-04-07 16:51:40,510 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-04-07 16:51:40,511 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-04-07 16:51:40,512 threading.run(870) DEBUG payload is None, terminating
2020-04-07 16:51:40,512 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-04-07 16:51:40,609 globio.execute(385) INFO calculate msa
2020-04-07 16:51:40,654 utils._log_gdal_errors(72) ERROR [errno 1] Deleting E:/ELD_Final_16.10.19/Villages_shapefile/Datia/INVEST/GLOBIO/OUTPUT_BENE_18\msa.tif failed: Permission denied
2020-04-07 16:51:40,663 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-04-07 16:51:40,665 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-9, started daemon 20544)>
2020-04-07 16:51:40,684 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-04-07 16:51:40,685 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-04-07 16:51:40,686 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-04-07 16:51:40,688 threading.run(870) DEBUG payload is None, terminating
2020-04-07 16:51:40,759 globio.execute(398) INFO summarize msa result in AOI polygons
2020-04-07 16:51:40,849 geoprocessing.zonal_statistics(1129) DEBUG <osgeo.gdal.Dataset; proxy of <Swig Object of type 'GDALDatasetShadow *' at 0x0E4554A0> >
2020-04-07 16:51:40,900 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 1 aligned: clipped_rasterxdeat1oh.tif
2020-04-07 16:51:40,900 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 1 rasters.
2020-04-07 16:51:40,947 geoprocessing.zonal_statistics(1189) INFO build a lookup of aggregate field value to FID
2020-04-07 16:51:40,958 geoprocessing.zonal_statistics(1196) INFO creating disjoint polygon set
2020-04-07 16:51:40,960 geoprocessing.calculate_disjoint_polygon_set(2082) INFO build shapely polygon list
2020-04-07 16:51:41,026 geoprocessing.calculate_disjoint_polygon_set(2096) INFO build shapely rtree index
2020-04-07 16:51:41,065 geoprocessing.calculate_disjoint_polygon_set(2111) INFO poly feature lookup 100.0% complete on aoi_summary.shp
2020-04-07 16:51:41,065 geoprocessing.calculate_disjoint_polygon_set(2113) INFO build poly intersection lookup
2020-04-07 16:51:41,574 geoprocessing.calculate_disjoint_polygon_set(2137) INFO poly intersection feature lookup 100.0% complete on aoi_summary.shp
2020-04-07 16:51:41,577 geoprocessing.calculate_disjoint_polygon_set(2171) INFO maximal subset build 100.0% complete on aoi_summary.shp
2020-04-07 16:51:41,602 geoprocessing.zonal_statistics(1219) INFO processing 8 disjoint polygon sets
2020-04-07 16:51:41,617 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 1 of 8 100.0% processed on aoi_summary.shp
2020-04-07 16:51:41,618 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 1 of 8 aoi_summary.shp
2020-04-07 16:51:41,631 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 1 of 8 aoi_summary.shp
2020-04-07 16:51:41,656 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 2 of 8 100.0% processed on aoi_summary.shp
2020-04-07 16:51:41,657 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 2 of 8 aoi_summary.shp
2020-04-07 16:51:41,664 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 2 of 8 aoi_summary.shp
2020-04-07 16:51:41,680 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 3 of 8 100.0% processed on aoi_summary.shp
2020-04-07 16:51:41,681 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 3 of 8 aoi_summary.shp
2020-04-07 16:51:41,688 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 3 of 8 aoi_summary.shp
2020-04-07 16:51:41,695 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 4 of 8 100.0% processed on aoi_summary.shp
2020-04-07 16:51:41,696 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 4 of 8 aoi_summary.shp
2020-04-07 16:51:41,700 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 4 of 8 aoi_summary.shp
2020-04-07 16:51:41,704 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 5 of 8 100.0% processed on aoi_summary.shp
2020-04-07 16:51:41,705 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 5 of 8 aoi_summary.shp
2020-04-07 16:51:41,709 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 5 of 8 aoi_summary.shp
2020-04-07 16:51:41,712 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 6 of 8 100.0% processed on aoi_summary.shp
2020-04-07 16:51:41,713 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 6 of 8 aoi_summary.shp
2020-04-07 16:51:41,717 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 6 of 8 aoi_summary.shp
2020-04-07 16:51:41,719 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 7 of 8 100.0% processed on aoi_summary.shp
2020-04-07 16:51:41,720 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 7 of 8 aoi_summary.shp
2020-04-07 16:51:41,731 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 7 of 8 aoi_summary.shp
2020-04-07 16:51:41,733 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 8 of 8 100.0% processed on aoi_summary.shp
2020-04-07 16:51:41,734 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 8 of 8 aoi_summary.shp
2020-04-07 16:51:41,738 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 8 of 8 aoi_summary.shp
2020-04-07 16:51:41,740 geoprocessing.zonal_statistics(1322) DEBUG unset_fids: 155 of 364 
2020-04-07 16:51:41,744 geoprocessing.zonal_statistics(1325) DEBUG gt [ 8.7182680e+06  2.0871503e+02  0.0000000e+00  2.9830322e+06
  0.0000000e+00 -2.0871503e+02] for ('E:/ELD_Final_16.10.19/Villages_shapefile/Datia/INVEST/GLOBIO/OUTPUT_BENE_18\\msa.tif', 1)
2020-04-07 16:51:41,776 geoprocessing.zonal_statistics(1399) DEBUG remaining unset_fids: 0 of 364 
2020-04-07 16:51:41,777 geoprocessing.zonal_statistics(1407) INFO all done processing polygon sets for aoi_summary.shp
2020-04-07 16:51:42,083 model._logged_target(1651) INFO Execution finished
2020-04-07 16:51:42,088 utils.prepare_workspace(121) INFO Elapsed time: 4.47s