2019-11-27 15:57:22,107 utils.prepare_workspace(109) INFO Writing log messages to H:\PuntaArenas_runs\test1_PA\InVEST-Recreation-Model-log-2019-11-27--15_57_22.txt 2019-11-27 15:57:22,107 model._logged_target(1633) Level 100 Starting model with parameters: Arguments for InVEST natcap.invest.recreation.recmodel_client 3.7.0: aoi_path H:/Invest_recreation/Processed/Magallanes/PuntaArenas.shp cell_size 100 compute_regression True end_year 2017 grid_aoi True grid_type square n_workers -1 predictor_table_path H:/PuntaArenas_runs/test1_PA/PuntaArenas_predictors.csv results_suffix scenario_predictor_table_path start_year 2016 workspace_dir H:\PuntaArenas_runs\test1_PA 2019-11-27 15:57:23,494 recmodel_client._grid_vector(411) INFO gridding aoi 2019-11-27 16:14:24,372 recmodel_client._retrieve_photo_user_days(327) INFO Contacting server, please wait. 2019-11-27 16:14:24,733 recmodel_client._retrieve_photo_user_days(330) INFO Server online, version: 3.3.2:./recserver_cache_2017/76e890d2cf86640e_fast_hash.pickle 2019-11-27 16:14:24,858 recmodel_client._retrieve_photo_user_days(338) INFO Server supports year queries between 2005 and 2017 2019-11-27 16:14:24,874 recmodel_client._retrieve_photo_user_days(357) INFO archiving H:\PuntaArenas_runs\test1_PA\intermediate\aoi.prj 2019-11-27 16:14:24,874 recmodel_client._retrieve_photo_user_days(357) INFO archiving H:\PuntaArenas_runs\test1_PA\intermediate\aoi.shp 2019-11-27 16:14:57,589 recmodel_client._retrieve_photo_user_days(357) INFO archiving H:\PuntaArenas_runs\test1_PA\intermediate\aoi.shx 2019-11-27 16:14:59,447 recmodel_client._retrieve_photo_user_days(357) INFO archiving H:\PuntaArenas_runs\test1_PA\intermediate\aoi.dbf 2019-11-27 16:15:05,177 recmodel_client._retrieve_photo_user_days(365) INFO Please wait for server to calculate PUD... 2019-11-27 17:01:05,846 recmodel_client._retrieve_photo_user_days(373) INFO received result, took 2760.669000 seconds, workspace_id: 7c468ac8-b3cb-42bc-9c11-da79a6350674 2019-11-27 17:02:51,857 recmodel_client._retrieve_photo_user_days(386) INFO connection release 2019-11-27 17:10:01,572 recmodel_client._schedule_predictor_data_processing(546) INFO Processing predictor datasets 2019-11-27 17:10:01,602 recmodel_client._schedule_predictor_data_processing(563) INFO Building predictor elevation 2019-11-27 17:10:01,838 geoprocessing.zonal_statistics(1152) DEBUG > 2019-11-27 17:10:01,931 geoprocessing.align_and_resize_raster_stack(752) DEBUG n_workers == 1 so a threadpool is sufficient 2019-11-27 17:10:08,065 geoprocessing.logger_callback(3155) INFO Warp 56.0% complete c:\users\nb78bumo\appdata\local\temp\clipped_rasteroz5fga.tif 2019-11-27 17:10:13,075 geoprocessing.logger_callback(3155) INFO Warp 97.0% complete c:\users\nb78bumo\appdata\local\temp\clipped_rasteroz5fga.tif 2019-11-27 17:10:13,184 geoprocessing.logger_callback(3155) INFO Warp 100.0% complete c:\users\nb78bumo\appdata\local\temp\clipped_rasteroz5fga.tif 2019-11-27 17:10:16,009 geoprocessing.align_and_resize_raster_stack(779) INFO 1 of 1 aligned: clipped_rasteroz5fga.tif 2019-11-27 17:10:16,118 geoprocessing.align_and_resize_raster_stack(788) INFO aligned all 1 rasters. 2019-11-27 17:10:16,118 geoprocessing.zonal_statistics(1212) INFO build a lookup of aggregate field value to FID 2019-11-27 17:10:42,933 geoprocessing.zonal_statistics(1219) INFO creating disjoint polygon set 2019-11-27 17:10:43,119 geoprocessing.calculate_disjoint_polygon_set(2048) INFO build shapely polygon list 2019-11-27 17:12:20,858 geoprocessing.calculate_disjoint_polygon_set(2057) INFO build shapely rtree index 2019-11-27 17:16:06,832 geoprocessing.calculate_disjoint_polygon_set(2072) INFO poly feature lookup 100.0% complete on aoi.shp 2019-11-27 17:16:06,832 geoprocessing.calculate_disjoint_polygon_set(2074) INFO build poly intersection lookup 2019-11-27 17:16:08,486 geoprocessing.(2082) INFO poly intersection lookup approximately 0.0% complete on aoi.shp 2019-11-27 17:16:13,496 geoprocessing.(2082) INFO poly intersection lookup approximately 0.8% complete on aoi.shp 2019-11-27 17:16:18,506 geoprocessing.(2082) INFO poly intersection lookup approximately 1.7% complete on aoi.shp 2019-11-27 17:16:23,516 geoprocessing.(2082) INFO poly intersection lookup approximately 2.5% complete on aoi.shp 2019-11-27 17:16:28,523 geoprocessing.(2082) INFO poly intersection lookup approximately 3.3% complete on aoi.shp 2019-11-27 17:16:33,533 geoprocessing.(2082) INFO poly intersection lookup approximately 4.2% complete on aoi.shp 2019-11-27 17:16:38,542 geoprocessing.(2082) INFO poly intersection lookup approximately 5.0% complete on aoi.shp 2019-11-27 17:16:43,553 geoprocessing.(2082) INFO poly intersection lookup approximately 5.9% complete on aoi.shp 2019-11-27 17:16:48,562 geoprocessing.(2082) INFO poly intersection lookup approximately 6.7% complete on aoi.shp 2019-11-27 17:16:53,572 geoprocessing.(2082) INFO poly intersection lookup approximately 7.5% complete on aoi.shp 2019-11-27 17:16:58,582 geoprocessing.(2082) INFO poly intersection lookup approximately 8.4% complete on aoi.shp 2019-11-27 17:17:03,594 geoprocessing.(2082) INFO poly intersection lookup approximately 9.2% complete on aoi.shp 2019-11-27 17:17:08,605 geoprocessing.(2082) INFO poly intersection lookup approximately 10.0% complete on aoi.shp 2019-11-27 17:17:13,612 geoprocessing.(2082) INFO poly intersection lookup approximately 10.9% complete on aoi.shp 2019-11-27 17:17:18,621 geoprocessing.(2082) INFO poly intersection lookup approximately 11.7% complete on aoi.shp 2019-11-27 17:17:23,627 geoprocessing.(2082) INFO poly intersection lookup approximately 12.5% complete on aoi.shp 2019-11-27 17:17:28,637 geoprocessing.(2082) INFO poly intersection lookup approximately 13.3% complete on aoi.shp 2019-11-27 17:17:33,648 geoprocessing.(2082) INFO poly intersection lookup approximately 14.2% complete on aoi.shp 2019-11-27 17:17:38,658 geoprocessing.(2082) INFO poly intersection lookup approximately 15.0% complete on aoi.shp 2019-11-27 17:17:43,668 geoprocessing.(2082) INFO poly intersection lookup approximately 15.8% complete on aoi.shp 2019-11-27 17:17:48,677 geoprocessing.(2082) INFO poly intersection lookup approximately 16.6% complete on aoi.shp 2019-11-27 17:17:53,684 geoprocessing.(2082) INFO poly intersection lookup approximately 17.5% complete on aoi.shp 2019-11-27 17:17:58,697 geoprocessing.(2082) INFO poly intersection lookup approximately 18.2% complete on aoi.shp 2019-11-27 17:18:03,710 geoprocessing.(2082) INFO poly intersection lookup approximately 19.0% complete on aoi.shp 2019-11-27 17:18:08,721 geoprocessing.(2082) INFO poly intersection lookup approximately 19.8% complete on aoi.shp 2019-11-27 17:18:13,733 geoprocessing.(2082) INFO poly intersection lookup approximately 20.6% complete on aoi.shp 2019-11-27 17:18:18,743 geoprocessing.(2082) INFO poly intersection lookup approximately 21.4% complete on aoi.shp 2019-11-27 17:18:23,753 geoprocessing.(2082) INFO poly intersection lookup approximately 22.2% complete on aoi.shp 2019-11-27 17:18:28,762 geoprocessing.(2082) INFO poly intersection lookup approximately 23.0% complete on aoi.shp 2019-11-27 17:18:33,770 geoprocessing.(2082) INFO poly intersection lookup approximately 23.7% complete on aoi.shp 2019-11-27 17:18:38,780 geoprocessing.(2082) INFO poly intersection lookup approximately 24.4% complete on aoi.shp 2019-11-27 17:18:43,792 geoprocessing.(2082) INFO poly intersection lookup approximately 25.1% complete on aoi.shp 2019-11-27 17:18:48,802 geoprocessing.(2082) INFO poly intersection lookup approximately 25.8% complete on aoi.shp 2019-11-27 17:18:53,812 geoprocessing.(2082) INFO poly intersection lookup approximately 26.5% complete on aoi.shp 2019-11-27 17:18:58,825 geoprocessing.(2082) INFO poly intersection lookup approximately 27.2% complete on aoi.shp 2019-11-27 17:19:03,834 geoprocessing.(2082) INFO poly intersection lookup approximately 27.9% complete on aoi.shp 2019-11-27 17:19:08,846 geoprocessing.(2082) INFO poly intersection lookup approximately 28.6% complete on aoi.shp 2019-11-27 17:19:13,855 geoprocessing.(2082) INFO poly intersection lookup approximately 29.3% complete on aoi.shp 2019-11-27 17:19:18,865 geoprocessing.(2082) INFO poly intersection lookup approximately 30.0% complete on aoi.shp 2019-11-27 17:19:23,871 geoprocessing.(2082) INFO poly intersection lookup approximately 30.7% complete on aoi.shp 2019-11-27 17:19:28,884 geoprocessing.(2082) INFO poly intersection lookup approximately 31.4% complete on aoi.shp 2019-11-27 17:19:33,892 geoprocessing.(2082) INFO poly intersection lookup approximately 32.1% complete on aoi.shp 2019-11-27 17:19:38,903 geoprocessing.(2082) INFO poly intersection lookup approximately 32.8% complete on aoi.shp 2019-11-27 17:19:43,914 geoprocessing.(2082) INFO poly intersection lookup approximately 33.5% complete on aoi.shp 2019-11-27 17:19:48,921 geoprocessing.(2082) INFO poly intersection lookup approximately 34.2% complete on aoi.shp 2019-11-27 17:19:53,928 geoprocessing.(2082) INFO poly intersection lookup approximately 34.9% complete on aoi.shp 2019-11-27 17:19:58,937 geoprocessing.(2082) INFO poly intersection lookup approximately 35.6% complete on aoi.shp 2019-11-27 17:20:03,950 geoprocessing.(2082) INFO poly intersection lookup approximately 36.3% complete on aoi.shp 2019-11-27 17:20:08,960 geoprocessing.(2082) INFO poly intersection lookup approximately 37.0% complete on aoi.shp 2019-11-27 17:20:13,970 geoprocessing.(2082) INFO poly intersection lookup approximately 37.7% complete on aoi.shp 2019-11-27 17:20:18,980 geoprocessing.(2082) INFO poly intersection lookup approximately 38.4% complete on aoi.shp 2019-11-27 17:20:23,990 geoprocessing.(2082) INFO poly intersection lookup approximately 39.1% complete on aoi.shp 2019-11-27 17:20:29,000 geoprocessing.(2082) INFO poly intersection lookup approximately 39.8% complete on aoi.shp 2019-11-27 17:20:34,010 geoprocessing.(2082) INFO poly intersection lookup approximately 40.5% complete on aoi.shp 2019-11-27 17:20:39,019 geoprocessing.(2082) INFO poly intersection lookup approximately 41.2% complete on aoi.shp 2019-11-27 17:20:44,030 geoprocessing.(2082) INFO poly intersection lookup approximately 42.0% complete on aoi.shp 2019-11-27 17:20:49,038 geoprocessing.(2082) INFO poly intersection lookup approximately 42.7% complete on aoi.shp 2019-11-27 17:20:54,048 geoprocessing.(2082) INFO poly intersection lookup approximately 43.4% complete on aoi.shp 2019-11-27 17:20:59,059 geoprocessing.(2082) INFO poly intersection lookup approximately 44.1% complete on aoi.shp 2019-11-27 17:21:04,073 geoprocessing.(2082) INFO poly intersection lookup approximately 44.8% complete on aoi.shp 2019-11-27 17:21:09,082 geoprocessing.(2082) INFO poly intersection lookup approximately 45.5% complete on aoi.shp 2019-11-27 17:21:14,092 geoprocessing.(2082) INFO poly intersection lookup approximately 46.3% complete on aoi.shp 2019-11-27 17:21:19,099 geoprocessing.(2082) INFO poly intersection lookup approximately 47.0% complete on aoi.shp 2019-11-27 17:21:24,108 geoprocessing.(2082) INFO poly intersection lookup approximately 47.7% complete on aoi.shp 2019-11-27 17:21:29,122 geoprocessing.(2082) INFO poly intersection lookup approximately 48.4% complete on aoi.shp 2019-11-27 17:21:34,135 geoprocessing.(2082) INFO poly intersection lookup approximately 49.1% complete on aoi.shp 2019-11-27 17:21:39,147 geoprocessing.(2082) INFO poly intersection lookup approximately 49.8% complete on aoi.shp 2019-11-27 17:21:44,157 geoprocessing.(2082) INFO poly intersection lookup approximately 50.5% complete on aoi.shp 2019-11-27 17:21:49,167 geoprocessing.(2082) INFO poly intersection lookup approximately 51.1% complete on aoi.shp 2019-11-27 17:21:54,177 geoprocessing.(2082) INFO poly intersection lookup approximately 51.9% complete on aoi.shp 2019-11-27 17:21:59,188 geoprocessing.(2082) INFO poly intersection lookup approximately 52.6% complete on aoi.shp 2019-11-27 17:22:04,200 geoprocessing.(2082) INFO poly intersection lookup approximately 53.3% complete on aoi.shp 2019-11-27 17:22:09,210 geoprocessing.(2082) INFO poly intersection lookup approximately 54.0% complete on aoi.shp 2019-11-27 17:22:14,223 geoprocessing.(2082) INFO poly intersection lookup approximately 54.7% complete on aoi.shp 2019-11-27 17:22:19,230 geoprocessing.(2082) INFO poly intersection lookup approximately 55.4% complete on aoi.shp 2019-11-27 17:22:24,240 geoprocessing.(2082) INFO poly intersection lookup approximately 56.1% complete on aoi.shp 2019-11-27 17:22:29,250 geoprocessing.(2082) INFO poly intersection lookup approximately 56.8% complete on aoi.shp 2019-11-27 17:22:34,259 geoprocessing.(2082) INFO poly intersection lookup approximately 57.5% complete on aoi.shp 2019-11-27 17:22:39,272 geoprocessing.(2082) INFO poly intersection lookup approximately 58.2% complete on aoi.shp 2019-11-27 17:22:44,279 geoprocessing.(2082) INFO poly intersection lookup approximately 58.9% complete on aoi.shp 2019-11-27 17:22:49,289 geoprocessing.(2082) INFO poly intersection lookup approximately 59.6% complete on aoi.shp 2019-11-27 17:22:54,296 geoprocessing.(2082) INFO poly intersection lookup approximately 60.3% complete on aoi.shp 2019-11-27 17:22:59,309 geoprocessing.(2082) INFO poly intersection lookup approximately 61.0% complete on aoi.shp 2019-11-27 17:23:04,322 geoprocessing.(2082) INFO poly intersection lookup approximately 61.7% complete on aoi.shp 2019-11-27 17:23:09,335 geoprocessing.(2082) INFO poly intersection lookup approximately 62.4% complete on aoi.shp 2019-11-27 17:23:14,345 geoprocessing.(2082) INFO poly intersection lookup approximately 63.1% complete on aoi.shp 2019-11-27 17:23:19,352 geoprocessing.(2082) INFO poly intersection lookup approximately 63.8% complete on aoi.shp 2019-11-27 17:23:24,361 geoprocessing.(2082) INFO poly intersection lookup approximately 64.5% complete on aoi.shp 2019-11-27 17:23:29,371 geoprocessing.(2082) INFO poly intersection lookup approximately 65.2% complete on aoi.shp 2019-11-27 17:23:34,381 geoprocessing.(2082) INFO poly intersection lookup approximately 65.9% complete on aoi.shp 2019-11-27 17:23:39,391 geoprocessing.(2082) INFO poly intersection lookup approximately 66.6% complete on aoi.shp 2019-11-27 17:23:44,401 geoprocessing.(2082) INFO poly intersection lookup approximately 67.3% complete on aoi.shp 2019-11-27 17:23:49,410 geoprocessing.(2082) INFO poly intersection lookup approximately 68.0% complete on aoi.shp 2019-11-27 17:23:54,420 geoprocessing.(2082) INFO poly intersection lookup approximately 68.7% complete on aoi.shp 2019-11-27 17:23:59,430 geoprocessing.(2082) INFO poly intersection lookup approximately 69.5% complete on aoi.shp 2019-11-27 17:24:04,441 geoprocessing.(2082) INFO poly intersection lookup approximately 70.2% complete on aoi.shp 2019-11-27 17:24:09,451 geoprocessing.(2082) INFO poly intersection lookup approximately 70.9% complete on aoi.shp 2019-11-27 17:24:14,460 geoprocessing.(2082) INFO poly intersection lookup approximately 71.7% complete on aoi.shp 2019-11-27 17:24:19,473 geoprocessing.(2082) INFO poly intersection lookup approximately 72.4% complete on aoi.shp 2019-11-27 17:24:25,234 geoprocessing.(2082) INFO poly intersection lookup approximately 73.1% complete on aoi.shp 2019-11-27 17:24:30,244 geoprocessing.(2082) INFO poly intersection lookup approximately 73.8% complete on aoi.shp 2019-11-27 17:24:35,257 geoprocessing.(2082) INFO poly intersection lookup approximately 74.5% complete on aoi.shp 2019-11-27 17:24:40,267 geoprocessing.(2082) INFO poly intersection lookup approximately 75.3% complete on aoi.shp 2019-11-27 17:24:45,276 geoprocessing.(2082) INFO poly intersection lookup approximately 76.0% complete on aoi.shp 2019-11-27 17:24:50,285 geoprocessing.(2082) INFO poly intersection lookup approximately 76.7% complete on aoi.shp 2019-11-27 17:24:55,293 geoprocessing.(2082) INFO poly intersection lookup approximately 77.4% complete on aoi.shp 2019-11-27 17:25:00,302 geoprocessing.(2082) INFO poly intersection lookup approximately 78.1% complete on aoi.shp 2019-11-27 17:25:05,319 geoprocessing.(2082) INFO poly intersection lookup approximately 78.9% complete on aoi.shp 2019-11-27 17:25:10,329 geoprocessing.(2082) INFO poly intersection lookup approximately 79.6% complete on aoi.shp 2019-11-27 17:25:15,338 geoprocessing.(2082) INFO poly intersection lookup approximately 80.4% complete on aoi.shp 2019-11-27 17:25:20,348 geoprocessing.(2082) INFO poly intersection lookup approximately 81.1% complete on aoi.shp 2019-11-27 17:25:25,355 geoprocessing.(2082) INFO poly intersection lookup approximately 81.8% complete on aoi.shp 2019-11-27 17:25:30,367 geoprocessing.(2082) INFO poly intersection lookup approximately 82.5% complete on aoi.shp 2019-11-27 17:25:35,377 geoprocessing.(2082) INFO poly intersection lookup approximately 83.2% complete on aoi.shp 2019-11-27 17:25:40,388 geoprocessing.(2082) INFO poly intersection lookup approximately 84.0% complete on aoi.shp 2019-11-27 17:25:45,403 geoprocessing.(2082) INFO poly intersection lookup approximately 84.7% complete on aoi.shp 2019-11-27 17:25:50,413 geoprocessing.(2082) INFO poly intersection lookup approximately 85.4% complete on aoi.shp 2019-11-27 17:25:55,423 geoprocessing.(2082) INFO poly intersection lookup approximately 86.1% complete on aoi.shp 2019-11-27 17:26:00,426 geoprocessing.(2082) INFO poly intersection lookup approximately 86.9% complete on aoi.shp 2019-11-27 17:26:05,437 geoprocessing.(2082) INFO poly intersection lookup approximately 87.6% complete on aoi.shp 2019-11-27 17:26:10,450 geoprocessing.(2082) INFO poly intersection lookup approximately 88.3% complete on aoi.shp 2019-11-27 17:26:15,457 geoprocessing.(2082) INFO poly intersection lookup approximately 89.0% complete on aoi.shp 2019-11-27 17:26:20,467 geoprocessing.(2082) INFO poly intersection lookup approximately 89.7% complete on aoi.shp 2019-11-27 17:26:25,476 geoprocessing.(2082) INFO poly intersection lookup approximately 90.5% complete on aoi.shp 2019-11-27 17:26:30,487 geoprocessing.(2082) INFO poly intersection lookup approximately 91.2% complete on aoi.shp 2019-11-27 17:26:35,500 geoprocessing.(2082) INFO poly intersection lookup approximately 91.9% complete on aoi.shp 2019-11-27 17:26:40,513 geoprocessing.(2082) INFO poly intersection lookup approximately 92.6% complete on aoi.shp 2019-11-27 17:26:45,520 geoprocessing.(2082) INFO poly intersection lookup approximately 93.3% complete on aoi.shp 2019-11-27 17:26:50,529 geoprocessing.(2082) INFO poly intersection lookup approximately 94.1% complete on aoi.shp 2019-11-27 17:26:55,539 geoprocessing.(2082) INFO poly intersection lookup approximately 94.8% complete on aoi.shp 2019-11-27 17:27:00,546 geoprocessing.(2082) INFO poly intersection lookup approximately 95.5% complete on aoi.shp 2019-11-27 17:27:05,561 geoprocessing.(2082) INFO poly intersection lookup approximately 96.2% complete on aoi.shp 2019-11-27 17:27:10,572 geoprocessing.(2082) INFO poly intersection lookup approximately 96.9% complete on aoi.shp 2019-11-27 17:27:15,582 geoprocessing.(2082) INFO poly intersection lookup approximately 97.7% complete on aoi.shp 2019-11-27 17:27:20,595 geoprocessing.(2082) INFO poly intersection lookup approximately 98.6% complete on aoi.shp 2019-11-27 17:27:25,602 geoprocessing.(2082) INFO poly intersection lookup approximately 99.4% complete on aoi.shp 2019-11-27 17:27:28,897 geoprocessing.calculate_disjoint_polygon_set(2098) INFO poly intersection feature lookup 100.0% complete on aoi.shp 2019-11-27 17:27:34,486 geoprocessing.(2118) INFO maximal subset build approximately 0.0% complete on aoi.shp 2019-11-27 17:27:50,502 geoprocessing.(2118) INFO maximal subset build approximately 24.2% complete on aoi.shp 2019-11-27 17:28:00,243 geoprocessing.(2118) INFO maximal subset build approximately 48.0% complete on aoi.shp 2019-11-27 17:28:05,257 geoprocessing.(2118) INFO maximal subset build approximately 84.6% complete on aoi.shp 2019-11-27 17:28:08,226 geoprocessing.calculate_disjoint_polygon_set(2132) INFO maximal subset build 100.0% complete on aoi.shp 2019-11-27 17:28:16,796 geoprocessing.zonal_statistics(1239) INFO processing 9 disjoint polygon sets 2019-11-27 17:28:21,805 geoprocessing.(1260) INFO polygon set 1 of 9 approximately 27.7% processed on aoi.shp 2019-11-27 17:28:26,815 geoprocessing.(1260) INFO polygon set 1 of 9 approximately 55.3% processed on aoi.shp 2019-11-27 17:28:31,823 geoprocessing.(1260) INFO polygon set 1 of 9 approximately 84.2% processed on aoi.shp 2019-11-27 17:28:34,572 geoprocessing.zonal_statistics(1273) INFO disjoint polygon set 1 of 9 100.0% processed on aoi.shp 2019-11-27 17:28:37,150 geoprocessing.zonal_statistics(1281) INFO rasterizing disjoint polygon set 1 of 9 aoi.shp 2019-11-27 17:28:37,150 utils._log_gdal_errors(66) ERROR [errno 2] gdalrasterize.cpp, 902: cannot allocate 107298768 bytes 2019-11-27 17:28:38,631 geoprocessing.zonal_statistics(1299) INFO summarizing rasterized disjoint polygon set 1 of 9 aoi.shp 2019-11-27 17:28:40,834 geoprocessing.(1245) INFO zonal stats approximately 22.2% complete on aoi.shp 2019-11-27 17:28:45,845 geoprocessing.(1260) INFO polygon set 2 of 9 approximately 29.1% processed on aoi.shp 2019-11-27 17:28:50,854 geoprocessing.(1260) INFO polygon set 2 of 9 approximately 59.3% processed on aoi.shp 2019-11-27 17:28:55,861 geoprocessing.(1260) INFO polygon set 2 of 9 approximately 89.1% processed on aoi.shp 2019-11-27 17:28:57,703 geoprocessing.zonal_statistics(1273) INFO disjoint polygon set 2 of 9 100.0% processed on aoi.shp 2019-11-27 17:29:00,280 geoprocessing.zonal_statistics(1281) INFO rasterizing disjoint polygon set 2 of 9 aoi.shp 2019-11-27 17:29:00,280 utils._log_gdal_errors(66) ERROR [errno 2] gdalrasterize.cpp, 902: cannot allocate 107298768 bytes 2019-11-27 17:29:01,763 geoprocessing.zonal_statistics(1299) INFO summarizing rasterized disjoint polygon set 2 of 9 aoi.shp 2019-11-27 17:29:03,871 geoprocessing.(1245) INFO zonal stats approximately 33.3% complete on aoi.shp 2019-11-27 17:29:08,884 geoprocessing.(1260) INFO polygon set 3 of 9 approximately 31.4% processed on aoi.shp 2019-11-27 17:29:13,891 geoprocessing.(1260) INFO polygon set 3 of 9 approximately 63.4% processed on aoi.shp 2019-11-27 17:29:18,903 geoprocessing.(1260) INFO polygon set 3 of 9 approximately 95.2% processed on aoi.shp 2019-11-27 17:29:19,668 geoprocessing.zonal_statistics(1273) INFO disjoint polygon set 3 of 9 100.0% processed on aoi.shp 2019-11-27 17:29:22,276 geoprocessing.zonal_statistics(1281) INFO rasterizing disjoint polygon set 3 of 9 aoi.shp 2019-11-27 17:29:22,276 utils._log_gdal_errors(66) ERROR [errno 2] gdalrasterize.cpp, 902: cannot allocate 107298768 bytes 2019-11-27 17:29:23,742 geoprocessing.zonal_statistics(1299) INFO summarizing rasterized disjoint polygon set 3 of 9 aoi.shp 2019-11-27 17:29:25,944 geoprocessing.(1245) INFO zonal stats approximately 44.4% complete on aoi.shp 2019-11-27 17:29:30,956 geoprocessing.(1260) INFO polygon set 4 of 9 approximately 32.7% processed on aoi.shp 2019-11-27 17:29:35,966 geoprocessing.(1260) INFO polygon set 4 of 9 approximately 65.8% processed on aoi.shp 2019-11-27 17:29:40,976 geoprocessing.(1260) INFO polygon set 4 of 9 approximately 99.1% processed on aoi.shp 2019-11-27 17:29:41,118 geoprocessing.zonal_statistics(1273) INFO disjoint polygon set 4 of 9 100.0% processed on aoi.shp 2019-11-27 17:29:43,676 geoprocessing.zonal_statistics(1281) INFO rasterizing disjoint polygon set 4 of 9 aoi.shp 2019-11-27 17:29:43,676 utils._log_gdal_errors(66) ERROR [errno 2] gdalrasterize.cpp, 902: cannot allocate 107298768 bytes 2019-11-27 17:29:45,174 geoprocessing.zonal_statistics(1299) INFO summarizing rasterized disjoint polygon set 4 of 9 aoi.shp 2019-11-27 17:29:47,358 geoprocessing.(1245) INFO zonal stats approximately 55.6% complete on aoi.shp 2019-11-27 17:29:52,194 geoprocessing.zonal_statistics(1273) INFO disjoint polygon set 5 of 9 100.0% processed on aoi.shp 2019-11-27 17:29:54,769 geoprocessing.zonal_statistics(1281) INFO rasterizing disjoint polygon set 5 of 9 aoi.shp 2019-11-27 17:29:54,769 utils._log_gdal_errors(66) ERROR [errno 2] gdalrasterize.cpp, 902: cannot allocate 107298768 bytes 2019-11-27 17:29:56,082 geoprocessing.zonal_statistics(1299) INFO summarizing rasterized disjoint polygon set 5 of 9 aoi.shp 2019-11-27 17:29:58,221 geoprocessing.(1245) INFO zonal stats approximately 66.7% complete on aoi.shp 2019-11-27 17:29:59,798 geoprocessing.zonal_statistics(1273) INFO disjoint polygon set 6 of 9 100.0% processed on aoi.shp 2019-11-27 17:30:02,345 geoprocessing.zonal_statistics(1281) INFO rasterizing disjoint polygon set 6 of 9 aoi.shp 2019-11-27 17:30:02,345 utils._log_gdal_errors(66) ERROR [errno 2] gdalrasterize.cpp, 902: cannot allocate 107298768 bytes 2019-11-27 17:30:03,578 geoprocessing.zonal_statistics(1299) INFO summarizing rasterized disjoint polygon set 6 of 9 aoi.shp 2019-11-27 17:30:05,701 geoprocessing.(1245) INFO zonal stats approximately 77.8% complete on aoi.shp 2019-11-27 17:30:05,858 geoprocessing.zonal_statistics(1273) INFO disjoint polygon set 7 of 9 100.0% processed on aoi.shp 2019-11-27 17:30:08,484 geoprocessing.zonal_statistics(1281) INFO rasterizing disjoint polygon set 7 of 9 aoi.shp 2019-11-27 17:30:08,484 utils._log_gdal_errors(66) ERROR [errno 2] gdalrasterize.cpp, 902: cannot allocate 107298768 bytes 2019-11-27 17:30:09,811 geoprocessing.zonal_statistics(1299) INFO summarizing rasterized disjoint polygon set 7 of 9 aoi.shp 2019-11-27 17:30:11,980 geoprocessing.(1245) INFO zonal stats approximately 88.9% complete on aoi.shp 2019-11-27 17:30:11,996 geoprocessing.zonal_statistics(1273) INFO disjoint polygon set 8 of 9 100.0% processed on aoi.shp 2019-11-27 17:30:14,601 geoprocessing.zonal_statistics(1281) INFO rasterizing disjoint polygon set 8 of 9 aoi.shp 2019-11-27 17:30:14,601 utils._log_gdal_errors(66) ERROR [errno 2] gdalrasterize.cpp, 902: cannot allocate 107298768 bytes 2019-11-27 17:30:15,911 geoprocessing.zonal_statistics(1299) INFO summarizing rasterized disjoint polygon set 8 of 9 aoi.shp 2019-11-27 17:30:18,035 geoprocessing.(1245) INFO zonal stats approximately 100.0% complete on aoi.shp 2019-11-27 17:30:18,035 geoprocessing.zonal_statistics(1273) INFO disjoint polygon set 9 of 9 100.0% processed on aoi.shp 2019-11-27 17:30:20,578 geoprocessing.zonal_statistics(1281) INFO rasterizing disjoint polygon set 9 of 9 aoi.shp 2019-11-27 17:30:20,578 utils._log_gdal_errors(66) ERROR [errno 2] gdalrasterize.cpp, 902: cannot allocate 107298768 bytes 2019-11-27 17:30:21,905 geoprocessing.zonal_statistics(1299) INFO summarizing rasterized disjoint polygon set 9 of 9 aoi.shp 2019-11-27 17:30:24,137 geoprocessing.zonal_statistics(1343) DEBUG unset_fids: 1759171 of 1759171 2019-11-27 17:30:24,153 geoprocessing.zonal_statistics(1346) DEBUG gt [ 1.1294350e+05 2.8312359e+01 0.0000000e+00 4.1588660e+06 0.0000000e+00 -2.8312359e+01] for ('h:\\invest_recreation\\puntaarenas_predict\\dem_pa', 1) 2019-11-27 17:35:10,099 Task.add_task(636) ERROR Something went wrong when adding task predictor elevation (3), terminating taskgraph. Traceback (most recent call last): File "Z:\opt\atlassian\pipelines\agent\build\env\lib\site-packages\taskgraph\Task.py", line 602, in add_task File "Z:\opt\atlassian\pipelines\agent\build\env\lib\site-packages\taskgraph\Task.py", line 1068, in _call File "Z:\opt\atlassian\pipelines\agent\build\env\lib\site-packages\natcap\invest\recreation\recmodel_client.py", line 720, in _raster_sum_mean File "Z:\opt\atlassian\pipelines\agent\build\env\lib\site-packages\pygeoprocessing\geoprocessing.py", line 1408, in zonal_statistics MemoryError 2019-11-27 17:35:10,115 model._logged_target(1639) ERROR Exception while executing Traceback (most recent call last): File "Z:\opt\atlassian\pipelines\agent\build\env\lib\site-packages\natcap\invest\ui\model.py", line 1636, in _logged_target File "Z:\opt\atlassian\pipelines\agent\build\env\lib\site-packages\natcap\invest\recreation\recmodel_client.py", line 244, in execute File "Z:\opt\atlassian\pipelines\agent\build\env\lib\site-packages\natcap\invest\recreation\recmodel_client.py", line 579, in _schedule_predictor_data_processing File "Z:\opt\atlassian\pipelines\agent\build\env\lib\site-packages\taskgraph\Task.py", line 602, in add_task File "Z:\opt\atlassian\pipelines\agent\build\env\lib\site-packages\taskgraph\Task.py", line 1068, in _call File "Z:\opt\atlassian\pipelines\agent\build\env\lib\site-packages\natcap\invest\recreation\recmodel_client.py", line 720, in _raster_sum_mean File "Z:\opt\atlassian\pipelines\agent\build\env\lib\site-packages\pygeoprocessing\geoprocessing.py", line 1408, in zonal_statistics MemoryError 2019-11-27 17:35:10,115 model._logged_target(1642) INFO Execution finished 2019-11-27 17:35:10,115 utils.prepare_workspace(115) INFO Elapsed time: 1h 37m 48.01s