2020-05-04 14:35:10,279 utils.prepare_workspace(115) INFO Writing log messages to C:/Users/karen/Dropbox/Karen Lourdes/Dropbox Backup/ArcGIS/InVEST Models/GLOBIO/Trial Run 5\InVEST-GLOBIO-log-2020-05-04--14_35_10.txt
2020-05-04 14:35:10,281 model._logged_target(1642) Level 100 Starting model with parameters: 
Arguments for InVEST natcap.invest.globio 3.8.0:
aoi_path                  C:/Users/karen/Dropbox/Karen Lourdes/Dropbox Backup/ArcGIS/InVEST Models/GLOBIO/Trial Run 2/Input/upper_langat_catchment_boundary.shp
infrastructure_dir        C:/Users/karen/Desktop/Workspace/ArcGIS/InVEST Models/GLOBIO/Major roads
intensification_fraction  0.6
lulc_path                 C:/Users/karen/Dropbox/Karen Lourdes/Dropbox Backup/ArcGIS/InVEST Models/GLOBIO/Trial Run 2/Input/upper_langat_globio_lulc1.tif
lulc_to_globio_table_path C:/Users/karen/Dropbox/Karen Lourdes/Dropbox Backup/ArcGIS/InVEST Models/GLOBIO/Conversion_table3.csv
msa_parameters_path       C:/Users/karen/Dropbox/Karen Lourdes/Dropbox Backup/ArcGIS/InVEST Models/GLOBIO/MSA_parameter_table.csv
n_workers                 -1
pasture_path              C:/Users/karen/Dropbox/Karen Lourdes/Dropbox Backup/ArcGIS/InVEST Models/GLOBIO/Trial Run 2/Input/pasture4.tif
pasture_threshold         0.1
potential_vegetation_path C:/Users/karen/Dropbox/Karen Lourdes/Dropbox Backup/ArcGIS/InVEST Models/GLOBIO/Trial Run 2/Input/tropical_forest_langat_v2.tif
predefined_globio         False
primary_threshold         0.7
results_suffix            trial_run5
workspace_dir             C:/Users/karen/Dropbox/Karen Lourdes/Dropbox Backup/ArcGIS/InVEST Models/GLOBIO/Trial Run 5

2020-05-04 14:35:10,855 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-05-04 14:35:10,856 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-16, started daemon 1848)>
2020-05-04 14:35:11,378 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-05-04 14:35:11,379 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-05-04 14:35:11,379 threading.run(870) DEBUG payload is None, terminating
2020-05-04 14:35:11,379 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-05-04 14:35:11,903 globio._calculate_globio_lulc_map(743) INFO create mask of natural areas
2020-05-04 14:35:11,916 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-05-04 14:35:11,917 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-17, started daemon 14048)>
2020-05-04 14:35:12,515 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-05-04 14:35:12,516 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-05-04 14:35:12,516 threading.run(870) DEBUG payload is None, terminating
2020-05-04 14:35:12,516 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-05-04 14:35:12,884 globio._calculate_globio_lulc_map(753) INFO smooth natural areas with gaussian filter
2020-05-04 14:35:13,511 geoprocessing.convolve_2d(2422) INFO starting convolve
2020-05-04 14:35:17,605 geoprocessing.convolve_2d(2525) INFO convolution worker 100.0% complete on smoothed_forest_areas_trial_run5.tif
2020-05-04 14:35:18,309 globio._calculate_globio_lulc_map(766) INFO calculate ffqi
2020-05-04 14:35:18,320 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-05-04 14:35:18,322 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-19, started daemon 15036)>
2020-05-04 14:35:19,896 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-05-04 14:35:19,896 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-05-04 14:35:19,896 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-05-04 14:35:19,896 threading.run(870) DEBUG payload is None, terminating
2020-05-04 14:35:20,657 globio._calculate_globio_lulc_map(776) INFO create the globio lulc
2020-05-04 14:35:20,664 geoprocessing.align_and_resize_raster_stack(646) DEBUG assuming manual bounding box mode of [801904.85829729, 306151.9260131112, 833614.85829729, 363411.9260131112]
2020-05-04 14:35:21,174 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 2 aligned: tropical_forest_langat_v2.tif
2020-05-04 14:35:21,937 geoprocessing.align_and_resize_raster_stack(752) INFO 2 of 2 aligned: pasture4.tif
2020-05-04 14:35:21,937 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 2 rasters.
2020-05-04 14:35:22,189 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-05-04 14:35:22,190 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-20, started daemon 4000)>
2020-05-04 14:35:23,178 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-05-04 14:35:23,178 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-05-04 14:35:23,178 threading.run(870) DEBUG payload is None, terminating
2020-05-04 14:35:23,178 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-05-04 14:35:23,678 globio._collapse_infrastructure_layers(962) INFO collapse infrastructure into one raster
2020-05-04 14:35:23,679 geoprocessing.align_and_resize_raster_stack(646) DEBUG assuming manual bounding box mode of [801904.85829729, 306151.9260131112, 833614.85829729, 363411.9260131112]
2020-05-04 14:35:24,242 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 1 aligned: upper_langat_major_roads.tif
2020-05-04 14:35:24,242 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 1 rasters.
2020-05-04 14:35:24,270 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-05-04 14:35:24,270 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-21, started daemon 9564)>
2020-05-04 14:35:24,681 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-05-04 14:35:24,681 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-05-04 14:35:24,682 threading.run(870) DEBUG payload is None, terminating
2020-05-04 14:35:24,682 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-05-04 14:35:25,136 globio.execute(296) INFO create mask of primary veg areas
2020-05-04 14:35:25,194 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-05-04 14:35:25,195 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-22, started daemon 18388)>
2020-05-04 14:35:25,552 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-05-04 14:35:25,552 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-05-04 14:35:25,553 threading.run(870) DEBUG payload is None, terminating
2020-05-04 14:35:25,552 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-05-04 14:35:25,920 globio.execute(307) INFO smooth primary veg areas with gaussian filter
2020-05-04 14:35:26,671 geoprocessing.convolve_2d(2422) INFO starting convolve
2020-05-04 14:35:30,317 geoprocessing.convolve_2d(2525) INFO convolution worker 100.0% complete on smoothed_primary_veg_mask_trial_run5.tif
2020-05-04 14:35:31,109 globio.execute(318) INFO calculate primary_veg_smooth
2020-05-04 14:35:31,127 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-05-04 14:35:31,128 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-24, started daemon 18252)>
2020-05-04 14:35:32,341 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-05-04 14:35:32,341 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-05-04 14:35:32,341 threading.run(870) DEBUG payload is None, terminating
2020-05-04 14:35:32,341 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-05-04 14:35:33,194 globio.execute(333) INFO calculate msa_f
2020-05-04 14:35:33,216 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-05-04 14:35:33,218 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-25, started daemon 15072)>
2020-05-04 14:35:34,081 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-05-04 14:35:34,081 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-05-04 14:35:34,083 threading.run(870) DEBUG payload is None, terminating
2020-05-04 14:35:34,083 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-05-04 14:35:34,524 globio.execute(351) INFO distance transform infrasture raster
2020-05-04 14:35:34,956 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-05-04 14:35:35,465 geoprocessing.distance_transform_edt(2257) INFO Distance Transform Phase 2
2020-05-04 14:35:38,997 globio.execute(361) INFO calculate msa_i
2020-05-04 14:35:39,033 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-05-04 14:35:39,033 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-26, started daemon 13064)>
2020-05-04 14:35:40,122 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-05-04 14:35:40,123 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-05-04 14:35:40,123 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-05-04 14:35:40,123 threading.run(870) DEBUG payload is None, terminating
2020-05-04 14:35:40,527 globio.execute(376) INFO calculate msa_lu
2020-05-04 14:35:40,558 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-05-04 14:35:40,560 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-27, started daemon 6232)>
2020-05-04 14:35:41,295 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-05-04 14:35:41,295 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-05-04 14:35:41,295 threading.run(870) DEBUG payload is None, terminating
2020-05-04 14:35:41,295 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-05-04 14:35:41,899 globio.execute(385) INFO calculate msa
2020-05-04 14:35:41,907 geoprocessing.raster_calculator(375) INFO starting stats_worker
2020-05-04 14:35:41,908 geoprocessing.raster_calculator(381) INFO started stats_worker <Thread(Thread-28, started daemon 14240)>
2020-05-04 14:35:42,826 geoprocessing.raster_calculator(454) INFO 100.0%% complete
2020-05-04 14:35:42,826 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate
2020-05-04 14:35:42,827 threading.run(870) DEBUG payload is None, terminating
2020-05-04 14:35:42,827 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result.
2020-05-04 14:35:43,203 globio.execute(398) INFO summarize msa result in AOI polygons
2020-05-04 14:35:43,319 utils._log_gdal_errors(72) WARNING [errno 1] Value 1123030042.06999993 of field Shape_Area of feature 0 not successfully written. Possibly due to too larger number with respect to field width
2020-05-04 14:35:43,322 geoprocessing.zonal_statistics(1129) DEBUG <osgeo.gdal.Dataset; proxy of <Swig Object of type 'GDALDatasetShadow *' at 0x0DF71140> >
2020-05-04 14:35:44,360 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 1 aligned: clipped_raster3o6bsvv4.tif
2020-05-04 14:35:44,360 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 1 rasters.
2020-05-04 14:35:44,361 geoprocessing.zonal_statistics(1189) INFO build a lookup of aggregate field value to FID
2020-05-04 14:35:44,454 geoprocessing.zonal_statistics(1196) INFO creating disjoint polygon set
2020-05-04 14:35:44,455 geoprocessing.calculate_disjoint_polygon_set(2082) INFO build shapely polygon list
2020-05-04 14:35:44,456 geoprocessing.calculate_disjoint_polygon_set(2096) INFO build shapely rtree index
2020-05-04 14:35:44,457 geoprocessing.calculate_disjoint_polygon_set(2111) INFO poly feature lookup 100.0% complete on aoi_summary_trial_run5.shp
2020-05-04 14:35:44,457 geoprocessing.calculate_disjoint_polygon_set(2113) INFO build poly intersection lookup
2020-05-04 14:35:44,457 geoprocessing.calculate_disjoint_polygon_set(2137) INFO poly intersection feature lookup 100.0% complete on aoi_summary_trial_run5.shp
2020-05-04 14:35:44,457 geoprocessing.calculate_disjoint_polygon_set(2171) INFO maximal subset build 100.0% complete on aoi_summary_trial_run5.shp
2020-05-04 14:35:44,501 geoprocessing.zonal_statistics(1219) INFO processing 1 disjoint polygon sets
2020-05-04 14:35:44,501 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 1 of 1 100.0% processed on aoi_summary_trial_run5.shp
2020-05-04 14:35:44,519 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 1 of 1 aoi_summary_trial_run5.shp
2020-05-04 14:35:44,960 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 1 of 1 aoi_summary_trial_run5.shp
2020-05-04 14:35:45,407 geoprocessing.zonal_statistics(1322) DEBUG unset_fids: 0 of 1 
2020-05-04 14:35:45,408 geoprocessing.zonal_statistics(1325) DEBUG gt [ 8.0240488e+05  1.0000000e+01  0.0000000e+00  3.6291194e+05
  0.0000000e+00 -1.0000000e+01] for ('C:/Users/karen/Dropbox/Karen Lourdes/Dropbox Backup/ArcGIS/InVEST Models/GLOBIO/Trial Run 5\\msa_trial_run5.tif', 1)
2020-05-04 14:35:45,409 geoprocessing.zonal_statistics(1399) DEBUG remaining unset_fids: 0 of 1 
2020-05-04 14:35:45,409 geoprocessing.zonal_statistics(1407) INFO all done processing polygon sets for aoi_summary_trial_run5.shp
2020-05-04 14:35:45,421 utils._log_gdal_errors(72) WARNING [errno 1] Value 1123030042.06999993 of field Shape_Area of feature 0 not successfully written. Possibly due to too larger number with respect to field width
2020-05-04 14:35:45,763 model._logged_target(1651) INFO Execution finished
2020-05-04 14:35:45,764 utils.prepare_workspace(121) INFO Elapsed time: 35.48s