Nutrient Retention, unable to duplicate previous model run.

Hi,

I have been using the nutrient retention model for my project, and I was trying to run the model again with a new land use scenario. However, the model is getting stuck not too long after starting. I've tried several times, and can't figure out what the problem is. I just tried running the model again with the exact same inputs as a previously successful run, and I am having the same problem. I've pasted the logs below. Can you help me figure out how to move forward? Thanks!!

Error log:

Initializing...

03/27/2016 12:59:13 root DEBUG PATH: ['C:\\InVEST_3_2_0_x86\\invest-3-x86\\.']

03/27/2016 12:59:13 root DEBUG Loading nutrient from PATH

Arguments:

accum_threshold 500

biophysical_table_uri D:/InVestModelWrkspc/NutReten/GAP_biophys_8_5_15_csv.csv

calc_n True

calc_p True

dem_uri D:/InVestModelWrkspc/NutReten/RasterTiFFs/Fill_DEM_30m.tif

depth_to_root_rest_layer_uri D:/InVestModelWrkspc/NutReten/RasterTiFFs/ROOT.tif

eto_uri D:/InVestModelWrkspc/NutReten/RasterTiFFs/pet_he_yr.tif

lulc_uri D:/InVestModelWrkspc/NutReten/RasterTiFFs/Comp25buff.tif

pawc_uri D:/InVestModelWrkspc/NutReten/RasterTiFFs/AWS.tif

precipitation_uri D:/InVestModelWrkspc/NutReten/RasterTiFFs/PRISM30yrnorm.tif

seasonality_constant 8

valuation_enabled False

water_purification_threshold_table_uri D:/InVestModelWrkspc/NutReten/water_pur_threshold_default.csv

watersheds_uri D:/InVestModelWrkspc/NutReten/SatillaWS_huc12.shp

workspace_dir D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25



03/27/2016 12:59:13 root INFO Logging will be saved to nutrient-log-2016-03-27--12_59_13.txt

03/27/2016 12:59:13 root DEBUG Loaded the model from invest_natcap.nutrient.nutrient

03/27/2016 12:59:13 root INFO Executing the loaded model

03/27/2016 12:59:13 root INFO Running InVEST version "3.2.0"

03/27/2016 12:59:13 root INFO Python architecture: ('32bit', 'WindowsPE')

03/27/2016 12:59:13 root INFO Disk space remaining for workspace: 1.47 TB

an exception encountered when logging

03/27/2016 12:59:35 root INFO Pointing temporary directory at the workspace at D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25

03/27/2016 12:59:35 root INFO Updating os.environ["TMP"]=C:\Users\learly\AppData\Local\Temp to D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25

03/27/2016 12:59:35 root INFO Updating os.environ["TEMP"]=C:\Users\learly\AppData\Local\Temp to D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25

03/27/2016 12:59:35 root INFO Setting os.environ["TMPDIR"]=D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25

03/27/2016 12:59:35 root INFO Setting tempfile.tempdir to D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25\tmp

03/27/2016 12:59:35 root INFO Starting nutrient

03/27/2016 12:59:35 invest_natcap.hydropower.hydropower_water_yield INFO Starting Water Yield Core Calculations

03/27/2016 12:59:46 pygeoprocessing.geoprocessing INFO align_dataset_list aligning dataset 2 of 5

03/27/2016 12:59:53 pygeoprocessing.geoprocessing INFO ReprojectImage 13.0% complete D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25\tmp\tmpbokviy, psz_message

03/27/2016 12:59:59 pygeoprocessing.geoprocessing INFO ReprojectImage 26.0% complete D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25\tmp\tmpbokviy, psz_message

03/27/2016 13:00:04 pygeoprocessing.geoprocessing INFO ReprojectImage 38.0% complete D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25\tmp\tmpbokviy, psz_message

03/27/2016 13:00:10 pygeoprocessing.geoprocessing INFO ReprojectImage 51.0% complete D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25\tmp\tmpbokviy, psz_message

03/27/2016 13:00:15 pygeoprocessing.geoprocessing INFO ReprojectImage 63.0% complete D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25\tmp\tmpbokviy, psz_message

03/27/2016 13:00:22 pygeoprocessing.geoprocessing INFO ReprojectImage 76.0% complete D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25\tmp\tmpbokviy, psz_message

03/27/2016 13:00:27 pygeoprocessing.geoprocessing INFO ReprojectImage 88.0% complete D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25\tmp\tmpbokviy, psz_message

03/27/2016 13:00:32 pygeoprocessing.geoprocessing INFO ReprojectImage 100.0% complete D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25\tmp\tmpbokviy, psz_message

03/27/2016 13:00:33 pygeoprocessing.geoprocessing INFO align_dataset_list aligning dataset 3 of 5

03/27/2016 13:00:40 pygeoprocessing.geoprocessing INFO ReprojectImage 13.0% complete D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25\tmp\tmp9ulazk, psz_message

03/27/2016 13:00:46 pygeoprocessing.geoprocessing INFO ReprojectImage 26.0% complete D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25\tmp\tmp9ulazk, psz_message

03/27/2016 13:00:51 pygeoprocessing.geoprocessing INFO ReprojectImage 38.0% complete D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25\tmp\tmp9ulazk, psz_message

03/27/2016 13:00:57 pygeoprocessing.geoprocessing INFO ReprojectImage 51.0% complete D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25\tmp\tmp9ulazk, psz_message

03/27/2016 13:01:02 pygeoprocessing.geoprocessing INFO ReprojectImage 63.0% complete D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25\tmp\tmp9ulazk, psz_message

03/27/2016 13:01:08 pygeoprocessing.geoprocessing INFO ReprojectImage 76.0% complete D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25\tmp\tmp9ulazk, psz_message

03/27/2016 13:01:13 pygeoprocessing.geoprocessing INFO ReprojectImage 88.0% complete D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25\tmp\tmp9ulazk, psz_message

03/27/2016 13:01:17 pygeoprocessing.geoprocessing INFO ReprojectImage 100.0% complete D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25\tmp\tmp9ulazk, psz_message

03/27/2016 13:01:18 pygeoprocessing.geoprocessing INFO align_dataset_list aligning dataset 4 of 5

03/27/2016 13:01:25 pygeoprocessing.geoprocessing INFO ReprojectImage 51.0% complete D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25\tmp\tmp4gnlfs, psz_message

03/27/2016 13:01:28 pygeoprocessing.geoprocessing INFO ReprojectImage 100.0% complete D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25\tmp\tmp4gnlfs, psz_message

03/27/2016 13:01:58 invest_natcap.hydropower.hydropower_water_yield INFO Reclassifying temp_Kc raster

03/27/2016 13:02:12 pygeoprocessing.geoprocessing INFO raster stack calculation approx. 20.94% complete

03/27/2016 13:02:17 pygeoprocessing.geoprocessing INFO raster stack calculation approx. 48.94% complete

03/27/2016 13:02:22 pygeoprocessing.geoprocessing INFO raster stack calculation approx. 75.76% complete

03/27/2016 13:02:29 invest_natcap.hydropower.hydropower_water_yield INFO Reclassifying tmp_root raster

03/27/2016 13:02:44 pygeoprocessing.geoprocessing INFO raster stack calculation approx. 21.88% complete

03/27/2016 13:02:49 pygeoprocessing.geoprocessing INFO raster stack calculation approx. 52.71% complete

03/27/2016 13:02:54 pygeoprocessing.geoprocessing INFO raster stack calculation approx. 80.94% complete

03/27/2016 13:02:59 invest_natcap.hydropower.hydropower_water_yield INFO Reclassifying tmp_veg raster

03/27/2016 13:03:14 pygeoprocessing.geoprocessing INFO raster stack calculation approx. 22.35% complete

03/27/2016 13:03:19 pygeoprocessing.geoprocessing INFO raster stack calculation approx. 49.41% complete

03/27/2016 13:03:24 pygeoprocessing.geoprocessing INFO raster stack calculation approx. 78.59% complete

03/27/2016 13:03:30 invest_natcap.hydropower.hydropower_water_yield DEBUG Calculate PET from Ref Evap times Kc

03/27/2016 13:03:41 pygeoprocessing.geoprocessing INFO ReprojectImage 38.0% complete D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25\tmp\tmpnmkdt4.tif, psz_message

03/27/2016 13:03:47 pygeoprocessing.geoprocessing INFO ReprojectImage 76.0% complete D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25\tmp\tmpnmkdt4.tif, psz_message

03/27/2016 13:03:49 pygeoprocessing.geoprocessing INFO ReprojectImage 100.0% complete D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25\tmp\tmpnmkdt4.tif, psz_message

03/27/2016 13:04:25 pygeoprocessing.geoprocessing INFO raster stack calculation approx. 24.71% complete

03/27/2016 13:04:30 pygeoprocessing.geoprocessing INFO raster stack calculation approx. 57.65% complete

03/27/2016 13:04:35 pygeoprocessing.geoprocessing INFO raster stack calculation approx. 89.41% complete

03/27/2016 13:04:39 invest_natcap.hydropower.hydropower_water_yield DEBUG Performing fractp operation

03/27/2016 13:04:39 invest_natcap.hydropower.hydropower_water_yield DEBUG {'Kc': -1.0, 'soil': 2147483647, 'pawc': 2147483647, 'veg': -1.0, 'precip': -3.4028231e+38, 'root': -1.0, 'eto': 32767}

03/27/2016 13:04:45 pygeoprocessing.geoprocessing INFO ReprojectImage 38.0% complete D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25\tmp\tmp2r6sqf.tif, psz_message

03/27/2016 13:04:51 pygeoprocessing.geoprocessing INFO ReprojectImage 76.0% complete D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25\tmp\tmp2r6sqf.tif, psz_message

03/27/2016 13:04:53 pygeoprocessing.geoprocessing INFO ReprojectImage 100.0% complete D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25\tmp\tmp2r6sqf.tif, psz_message

03/27/2016 13:04:55 pygeoprocessing.geoprocessing INFO align_dataset_list aligning dataset 1 of 7

03/27/2016 13:05:06 pygeoprocessing.geoprocessing INFO align_dataset_list aligning dataset 3 of 7

03/27/2016 13:05:12 pygeoprocessing.geoprocessing INFO ReprojectImage 38.0% complete D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25\tmp\tmpdav9pr.tif, psz_message

03/27/2016 13:05:18 pygeoprocessing.geoprocessing INFO ReprojectImage 76.0% complete D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25\tmp\tmpdav9pr.tif, psz_message

03/27/2016 13:05:20 pygeoprocessing.geoprocessing INFO ReprojectImage 100.0% complete D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25\tmp\tmpdav9pr.tif, psz_message

03/27/2016 13:05:22 pygeoprocessing.geoprocessing INFO align_dataset_list aligning dataset 4 of 7

03/27/2016 13:05:28 pygeoprocessing.geoprocessing INFO ReprojectImage 76.0% complete D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25\tmp\tmp3vaesz.tif, psz_message

03/27/2016 13:05:28 pygeoprocessing.geoprocessing INFO ReprojectImage 100.0% complete D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25\tmp\tmp3vaesz.tif, psz_message

03/27/2016 13:05:30 pygeoprocessing.geoprocessing INFO align_dataset_list aligning dataset 5 of 7

03/27/2016 13:05:35 pygeoprocessing.geoprocessing INFO ReprojectImage 82.0% complete D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25\tmp\tmpna5svm.tif, psz_message

03/27/2016 13:05:35 pygeoprocessing.geoprocessing INFO ReprojectImage 100.0% complete D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25\tmp\tmpna5svm.tif, psz_message

03/27/2016 13:05:37 pygeoprocessing.geoprocessing INFO align_dataset_list aligning dataset 6 of 7

03/27/2016 13:05:43 pygeoprocessing.geoprocessing INFO ReprojectImage 38.0% complete D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25\tmp\tmpsp4kf4.tif, psz_message

03/27/2016 13:05:48 pygeoprocessing.geoprocessing INFO ReprojectImage 76.0% complete D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25\tmp\tmpsp4kf4.tif, psz_message

03/27/2016 13:05:51 pygeoprocessing.geoprocessing INFO ReprojectImage 100.0% complete D:\InVestModelWrkspc\NutReten\WorkSpace\3_27_16TestCompBuff 25\tmp\tmpsp4kf4.tif, psz_message

((GETS STUCK HERE FOR AN HOUR+))


Previously successful log:

Arguments:
accum_threshold                        500
biophysical_table_uri                  E:/InVestModelWrkspc/NutReten/GAP_biophys_8_5_15_csv.csv
calc_n                                 True
calc_p                                 True
dem_uri                                E:/InVestModelWrkspc/NutReten/RasterTiFFs/Fill_DEM_30m.tif
depth_to_root_rest_layer_uri           E:/InVestModelWrkspc/NutReten/RasterTiFFs/ROOT.tif
eto_uri                                E:/InVestModelWrkspc/NutReten/RasterTiFFs/pet_he_yr.tif
lulc_uri                               E:/InVestModelWrkspc/NutReten/RasterTiFFs/Comp25buff.tif
pawc_uri                               E:/InVestModelWrkspc/NutReten/RasterTiFFs/AWS.tif
precipitation_uri                      E:/InVestModelWrkspc/NutReten/RasterTiFFs/PRISM30yrnorm.tif
seasonality_constant                   8
valuation_enabled                      False
water_purification_threshold_table_uri E:/InVestModelWrkspc/NutReten/water_pur_threshold_default.csv
watersheds_uri                         E:/InVestModelWrkspc/NutReten/SatillaWS_huc12.shp
workspace_dir                          E:\InVestModelWrkspc\NutReten\WorkSpace\11_24_15Comp_Buff25


11/24/2015 10:35:11  root               INFO     Logging will be saved to nutrient-log-2015-11-24--10_35_11.txt
11/24/2015 10:35:11  root               DEBUG    Loaded the model from invest_natcap.nutrient.nutrient
11/24/2015 10:35:11  root               INFO     Executing the loaded model
11/24/2015 10:35:11  root               INFO     Running InVEST version "3.1.3"
11/24/2015 10:35:11  root               INFO     Python architecture: ('32bit', 'WindowsPE')
11/24/2015 10:35:11  root               INFO     Disk space remaining for workspace: 1.51 TB
11/24/2015 10:35:11  root               INFO     Pointing temporary directory at the workspace at E:\InVestModelWrkspc\NutReten\WorkSpace\11_24_15Comp_Buff25
11/24/2015 10:35:11  root               INFO     Updating os.environ["TMP"]=C:\Users\lme83208\AppData\Local\Temp to E:\InVestModelWrkspc\NutReten\WorkSpace\11_24_15Comp_Buff25
11/24/2015 10:35:11  root               INFO     Updating os.environ["TEMP"]=C:\Users\lme83208\AppData\Local\Temp to E:\InVestModelWrkspc\NutReten\WorkSpace\11_24_15Comp_Buff25
11/24/2015 10:35:11  root               INFO     Setting os.environ["TMPDIR"]=E:\InVestModelWrkspc\NutReten\WorkSpace\11_24_15Comp_Buff25
11/24/2015 10:35:11  root               INFO     Setting tempfile.tempdir to E:\InVestModelWrkspc\NutReten\WorkSpace\11_24_15Comp_Buff25\tmp
11/24/2015 10:35:11  root               INFO     Starting nutrient
11/24/2015 10:35:11  invest_natcap.hydropower.hydropower_water_yield INFO     Starting Water Yield Core Calculations
11/24/2015 10:35:21  pygeoprocessing.geoprocessing INFO     align_dataset_list aligning dataset 2 of 5
11/24/2015 10:35:29  pygeoprocessing.geoprocessing INFO     ReprojectImage 13.0% complete E:\InVestModelWrkspc\NutReten\WorkSpace\11_24_15Comp_Buff25\tmp\tmpk4qisu, psz_message
11/24/2015 10:35:35  pygeoprocessing.geoprocessing INFO     ReprojectImage 26.0% complete E:\InVestModelWrkspc\NutReten\WorkSpace\11_24_15Comp_Buff25\tmp\tmpk4qisu, psz_message
11/24/2015 10:35:40  pygeoprocessing.geoprocessing INFO     ReprojectImage 38.0% complete E:\InVestModelWrkspc\NutReten\WorkSpace\11_24_15Comp_Buff25\tmp\tmpk4qisu, psz_message
11/24/2015 10:35:47  pygeoprocessing.geoprocessing INFO     ReprojectImage 51.0% complete E:\InVestModelWrkspc\NutReten\WorkSpace\11_24_15Comp_Buff25\tmp\tmpk4qisu, psz_message
11/24/2015 10:35:52  pygeoprocessing.geoprocessing INFO     ReprojectImage 63.0% complete E:\InVestModelWrkspc\NutReten\WorkSpace\11_24_15Comp_Buff25\tmp\tmpk4qisu, psz_message
11/24/2015 10:35:59  pygeoprocessing.geoprocessing INFO     ReprojectImage 76.0% complete E:\InVestModelWrkspc\NutReten\WorkSpace\11_24_15Comp_Buff25\tmp\tmpk4qisu, psz_message
11/24/2015 10:36:05  pygeoprocessing.geoprocessing INFO     ReprojectImage 88.0% complete E:\InVestModelWrkspc\NutReten\WorkSpace\11_24_15Comp_Buff25\tmp\tmpk4qisu, psz_message
11/24/2015 10:36:10  pygeoprocessing.geoprocessing INFO     ReprojectImage 100.0% complete E:\InVestModelWrkspc\NutReten\WorkSpace\11_24_15Comp_Buff25\tmp\tmpk4qisu, psz_message
11/24/2015 10:36:12  pygeoprocessing.geoprocessing INFO     align_dataset_list aligning dataset 3 of 5
11/24/2015 10:36:19  pygeoprocessing.geoprocessing INFO     ReprojectImage 13.0% complete E:\InVestModelWrkspc\NutReten\WorkSpace\11_24_15Comp_Buff25\tmp\tmpw651ur, psz_message
11/24/2015 10:36:26  pygeoprocessing.geoprocessing INFO     ReprojectImage 26.0% complete E:\InVestModelWrkspc\NutReten\WorkSpace\11_24_15Comp_Buff25\tmp\tmpw651ur, psz_message
11/24/2015 10:36:32  pygeoprocessing.geoprocessing INFO     ReprojectImage 38.0% complete E:\InVestModelWrkspc\NutReten\WorkSpace\11_24_15Comp_Buff25\tmp\tmpw651ur, psz_message
11/24/2015 10:36:38  pygeoprocessing.geoprocessing INFO     ReprojectImage 51.0% complete E:\InVestModelWrkspc\NutReten\WorkSpace\11_24_15Comp_Buff25\tmp\tmpw651ur, psz_message
11/24/2015 10:36:44  pygeoprocessing.geoprocessing INFO     ReprojectImage 63.0% complete E:\InVestModelWrkspc\NutReten\WorkSpace\11_24_15Comp_Buff25\tmp\tmpw651ur, psz_message
11/24/2015 10:36:51  pygeoprocessing.geoprocessing INFO     ReprojectImage 76.0% complete E:\InVestModelWrkspc\NutReten\WorkSpace\11_24_15Comp_Buff25\tmp\tmpw651ur, psz_message
11/24/2015 10:36:56  pygeoprocessing.geoprocessing INFO     ReprojectImage 87.0% complete E:\InVestModelWrkspc\NutReten\WorkSpace\11_24_15Comp_Buff25\tmp\tmpw651ur, psz_message
11/24/2015 10:37:02  pygeoprocessing.geoprocessing INFO     ReprojectImage 94.0% complete E:\InVestModelWrkspc\NutReten\WorkSpace\11_24_15Comp_Buff25\tmp\tmpw651ur, psz_message
11/24/2015 10:37:06  pygeoprocessing.geoprocessing INFO     ReprojectImage 100.0% complete E:\InVestModelWrkspc\NutReten\WorkSpace\11_24_15Comp_Buff25\tmp\tmpw651ur, psz_message
11/24/2015 10:37:09  pygeoprocessing.geoprocessing INFO     align_dataset_list aligning dataset 4 of 5
11/24/2015 10:37:16  pygeoprocessing.geoprocessing INFO     ReprojectImage 76.0% complete E:\InVestModelWrkspc\NutReten\WorkSpace\11_24_15Comp_Buff25\tmp\tmpaw6emq, psz_message
11/24/2015 10:37:16  pygeoprocessing.geoprocessing INFO     ReprojectImage 100.0% complete E:\InVestModelWrkspc\NutReten\WorkSpace\11_24_15Comp_Buff25\tmp\tmpaw6emq, psz_message
11/24/2015 10:37:50  invest_natcap.hydropower.hydropower_water_yield INFO     Reclassifying temp_Kc raster
11/24/2015 10:38:01  invest_natcap.hydropower.hydropower_water_yield INFO     Reclassifying tmp_root raster
11/24/2015 10:38:13  invest_natcap.hydropower.hydropower_water_yield INFO     Reclassifying tmp_veg raster
11/24/2015 10:38:26  invest_natcap.hydropower.hydropower_water_yield DEBUG    Calculate PET from Ref Evap times Kc
11/24/2015 10:39:11  invest_natcap.hydropower.hydropower_water_yield DEBUG    Performing fractp operation
11/24/2015 10:39:11  invest_natcap.hydropower.hydropower_water_yield DEBUG    {'Kc': -1.0, 'soil': 2147483647, 'pawc': 2147483647, 'veg': -1.0, 'precip': -3.4028231e+38, 'root': -1.0, 'eto': 32767}
11/24/2015 10:39:24  pygeoprocessing.geoprocessing INFO     align_dataset_list aligning dataset 1 of 7
11/24/2015 10:39:36  pygeoprocessing.geoprocessing INFO     align_dataset_list aligning dataset 3 of 7
11/24/2015 10:39:49  pygeoprocessing.geoprocessing INFO     align_dataset_list aligning dataset 4 of 7
11/24/2015 10:39:57  pygeoprocessing.geoprocessing INFO     align_dataset_list aligning dataset 5 of 7
11/24/2015 10:40:03  pygeoprocessing.geoprocessing INFO     align_dataset_list aligning dataset 6 of 7
11/24/2015 10:46:03  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 32.94% complete
11/24/2015 10:46:08  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 65.18% complete
11/24/2015 10:46:13  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 98.35% complete
11/24/2015 10:46:22  invest_natcap.hydropower.hydropower_water_yield DEBUG    Performing wyield operation
11/24/2015 10:46:33  pygeoprocessing.geoprocessing INFO     align_dataset_list aligning dataset 1 of 2
11/24/2015 10:47:12  invest_natcap.hydropower.hydropower_water_yield DEBUG    Performing aet operation
11/24/2015 10:47:23  pygeoprocessing.geoprocessing INFO     align_dataset_list aligning dataset 1 of 3
11/24/2015 10:47:29  pygeoprocessing.geoprocessing INFO     align_dataset_list aligning dataset 2 of 3
11/24/2015 10:51:58  invest_natcap.hydropower.hydropower_water_yield DEBUG    wyield_value_dict_ws : {1: {'precip_mn': 402.4720087547524, 'AET_mn': 1.913123995495291, 'PET_mn': 6.534426719997481, 'wyield_mn': 0.044063116512458, 'wyield_vol': 5036.652158203116, 'num_pixels': 127006.0, 'ws_id': 1}, 2: {'precip_mn': 1151.8544755161938, 'AET_mn': 644.0704808823792, 'PET_mn': 760.5018638324065, 'wyield_mn': 328.6398120066346, 'wyield_vol': 5760530.080777093, 'num_pixels': 19476.0, 'ws_id': 2}, 3: {'precip_mn': 1232.1986024494818, 'AET_mn': 709.5888195844931, 'PET_mn': 829.6850807279934, 'wyield_mn': 355.0458418086552, 'wyield_vol': 85557491.27109832, 'num_pixels': 267751.0, 'ws_id': 3}, 7: {'precip_mn': 1259.8432338885332, 'AET_mn': 639.3721420541397, 'PET_mn': 719.3001572913287, 'wyield_mn': 531.5832432208463, 'wyield_vol': 111628494.20205356, 'num_pixels': 233325.0, 'ws_id': 7}, 10: {'precip_mn': 1220.5442000181602, 'AET_mn': 936.2178489016233, 'PET_mn': 1119.6165203570613, 'wyield_mn': 284.2250638256724, 'wyield_vol': 27682441.161377955, 'num_pixels': 108218.0, 'ws_id': 10}, 12: {'precip_mn': 1322.131372202583, 'AET_mn': 986.1308814266528, 'PET_mn': 1178.425224214985, 'wyield_mn': 335.98755091042545, 'wyield_vol': 139821857.68772027, 'num_pixels': 462391.0, 'ws_id': 12}, 13: {'precip_mn': 455.44074639994034, 'AET_mn': 3.039433695095723, 'PET_mn': 5.680768647435316, 'wyield_mn': 3.067772280559149, 'wyield_vol': 364086.8955834964, 'num_pixels': 131868.0, 'ws_id': 13}, 15: {'precip_mn': 1315.730656172969, 'AET_mn': 730.4320334941685, 'PET_mn': 830.1690571712769, 'wyield_mn': 585.2774860547629, 'wyield_vol': 89675455.5525789, 'num_pixels': 170243.0, 'ws_id': 15}, 17: {'precip_mn': 1284.391360883338, 'AET_mn': 979.7716973696739, 'PET_mn': 1137.0282601159265, 'wyield_mn': 256.4725610446505, 'wyield_vol': 16645736.040456533, 'num_pixels': 72114.0, 'ws_id': 17}, 19: {'precip_mn': 1307.5239368150512, 'AET_mn': 889.2484366962973, 'PET_mn': 1007.5194342972758, 'wyield_mn': 418.16872275542715, 'wyield_vol': 33277406.97128186, 'num_pixels': 88421.0, 'ws_id': 19}, 21: {'precip_mn': 1269.5183029346979, 'AET_mn': 785.6314937609897, 'PET_mn': 905.5935110352783, 'wyield_mn': 184.09869496129778, 'wyield_vol': 24375974.31361005, 'num_pixels': 147119.0, 'ws_id': 21}, 28: {'precip_mn': 1184.1485689442022, 'AET_mn': 918.3305648000318, 'PET_mn': 1084.489466153831, 'wyield_mn': 265.1171589447289, 'wyield_vol': 31519301.816052716, 'num_pixels': 132098.0, 'ws_id': 28}, 35: {'precip_mn': 1238.9629403250995, 'AET_mn': 985.3747868402796, 'PET_mn': 1190.8995118889243, 'wyield_mn': 253.57778492178346, 'wyield_vol': 36539620.56942479, 'num_pixels': 160107.0, 'ws_id': 35}, 40: {'precip_mn': 1259.7744940624716, 'AET_mn': 1036.659345350593, 'PET_mn': 1284.1703053091135, 'wyield_mn': 223.06776859808974, 'wyield_vol': 45891553.57947012, 'num_pixels': 228588.0, 'ws_id': 40}, 41: {'precip_mn': 1270.716490281271, 'AET_mn': 1003.1679215836826, 'PET_mn': 1208.767252044025, 'wyield_mn': 267.52703163745053, 'wyield_vol': 60723767.18972547, 'num_pixels': 252202.0, 'ws_id': 41}, 42: {'precip_mn': 1225.3395128540237, 'AET_mn': 676.7921558677479, 'PET_mn': 752.495816475993, 'wyield_mn': 548.546628085988, 'wyield_vol': 30994968.96404505, 'num_pixels': 62782.0, 'ws_id': 42}, 43: {'precip_mn': 1218.7169050100538, 'AET_mn': 733.2407968535725, 'PET_mn': 809.7194457330471, 'wyield_mn': 485.4736213643317, 'wyield_vol': 36734575.244585566, 'num_pixels': 84075.0, 'ws_id': 43}, 44: {'precip_mn': 1218.290618138522, 'AET_mn': 818.4699452268108, 'PET_mn': 908.9722029771524, 'wyield_mn': 399.81798310874547, 'wyield_vol': 36356408.767237686, 'num_pixels': 101036.0, 'ws_id': 44}, 45: {'precip_mn': 1225.3106798491192, 'AET_mn': 753.772459892168, 'PET_mn': 874.5139938824688, 'wyield_mn': 471.5363659814665, 'wyield_vol': 40790394.80013655, 'num_pixels': 96117.0, 'ws_id': 45}, 46: {'precip_mn': 1203.7401058849919, 'AET_mn': 979.751404041534, 'PET_mn': 1187.9170801013506, 'wyield_mn': 223.91303729034868, 'wyield_vol': 10496057.97080747, 'num_pixels': 52084.0, 'ws_id': 46}, 48: {'precip_mn': 1189.1704255922778, 'AET_mn': 934.4921621976499, 'PET_mn': 1122.780406771097, 'wyield_mn': 253.57818670094022, 'wyield_vol': 28905478.933314856, 'num_pixels': 126656.0, 'ws_id': 48}, 49: {'precip_mn': 1191.9030571342398, 'AET_mn': 949.1183182365072, 'PET_mn': 1148.0642796335799, 'wyield_mn': 242.5703103323121, 'wyield_vol': 24618970.19327805, 'num_pixels': 112769.0, 'ws_id': 49}, 58: {'precip_mn': 1196.957814629727, 'AET_mn': 964.3991531579292, 'PET_mn': 1185.6408389302512, 'wyield_mn': 231.895786388014, 'wyield_vol': 18862240.93775059, 'num_pixels': 90377.0, 'ws_id': 58}, 64: {'precip_mn': 1227.7429225621227, 'AET_mn': 887.7158125976599, 'PET_mn': 1001.6950259402647, 'wyield_mn': 339.96538212562035, 'wyield_vol': 20877478.09556362, 'num_pixels': 68234.0, 'ws_id': 64}, 66: {'precip_mn': 1220.6495773807967, 'AET_mn': 880.3019138306031, 'PET_mn': 995.1243548030147, 'wyield_mn': 340.34336195091527, 'wyield_vol': 59593339.88787278, 'num_pixels': 194553.0, 'ws_id': 66}, 71: {'precip_mn': 1264.2237588811527, 'AET_mn': 697.6807549446231, 'PET_mn': 800.7178422292126, 'wyield_mn': 273.9830495672376, 'wyield_vol': 74167266.31446113, 'num_pixels': 300778.0, 'ws_id': 71}, 72: {'precip_mn': 1255.5023725750839, 'AET_mn': 742.8058918849181, 'PET_mn': 865.6742083606783, 'wyield_mn': 279.6473587431389, 'wyield_vol': 80188852.1548592, 'num_pixels': 318611.0, 'ws_id': 72}, 73: {'precip_mn': 1274.9991295067252, 'AET_mn': 803.4459341917808, 'PET_mn': 931.7715616441528, 'wyield_mn': 369.6215119253423, 'wyield_vol': 64495664.199516095, 'num_pixels': 193879.0, 'ws_id': 73}, 74: {'precip_mn': 1281.7629174711005, 'AET_mn': 855.3041412318901, 'PET_mn': 978.027201473148, 'wyield_mn': 426.3133733853614, 'wyield_vol': 101991126.18603918, 'num_pixels': 265822.0, 'ws_id': 74}, 75: {'precip_mn': 468.62367237541486, 'AET_mn': 2.040479298971547, 'PET_mn': 13.310678970952928, 'wyield_mn': 0.705490436317035, 'wyield_vol': 119306.75756835916, 'num_pixels': 187902.0, 'ws_id': 75}, 76: {'precip_mn': 1211.1397353935308, 'AET_mn': 975.1908929512183, 'PET_mn': 1191.729879912027, 'wyield_mn': 235.480547720508, 'wyield_vol': 35719738.159011826, 'num_pixels': 168543.0, 'ws_id': 76}, 80: {'precip_mn': 1201.679780782956, 'AET_mn': 952.8937940525237, 'PET_mn': 1161.365221830223, 'wyield_mn': 248.1407600985718, 'wyield_vol': 44186077.753688544, 'num_pixels': 197854.0, 'ws_id': 80}, 81: {'precip_mn': 1245.8011897136253, 'AET_mn': 1022.9199662128673, 'PET_mn': 1264.3946204335211, 'wyield_mn': 222.11845678469408, 'wyield_vol': 55066075.18870954, 'num_pixels': 275459.0, 'ws_id': 81}, 82: {'precip_mn': 1229.4272093742504, 'AET_mn': 828.9284702425192, 'PET_mn': 955.5687413319924, 'wyield_mn': 400.4356128840364, 'wyield_vol': 90431015.15458255, 'num_pixels': 250924.0, 'ws_id': 82}, 83: {'precip_mn': 1281.2245206519663, 'AET_mn': 882.0736099271468, 'PET_mn': 1067.2295171948674, 'wyield_mn': 214.65676593345506, 'wyield_vol': 64463036.73556106, 'num_pixels': 333675.0, 'ws_id': 83}, 84: {'precip_mn': 1284.0174108411943, 'AET_mn': 1073.5331730279497, 'PET_mn': 1324.8020652425585, 'wyield_mn': 210.44741925727152, 'wyield_vol': 49119690.33916272, 'num_pixels': 259340.0, 'ws_id': 84}, 85: {'precip_mn': 1316.0516675830759, 'AET_mn': 946.2007818826283, 'PET_mn': 1125.3649360729958, 'wyield_mn': 369.83889097228246, 'wyield_vol': 76331640.44999494, 'num_pixels': 229324.0, 'ws_id': 85}, 86: {'precip_mn': 1202.6856351354106, 'AET_mn': 887.9194360430893, 'PET_mn': 1023.1099057731168, 'wyield_mn': 314.3290401364006, 'wyield_vol': 29602534.580021787, 'num_pixels': 104641.0, 'ws_id': 86}, 87: {'precip_mn': 1206.393671673322, 'AET_mn': 873.1652952676127, 'PET_mn': 979.5260973719078, 'wyield_mn': 333.1676981523584, 'wyield_vol': 34421987.02031666, 'num_pixels': 114797.0, 'ws_id': 87}, 88: {'precip_mn': 1206.736292399182, 'AET_mn': 826.2095849572286, 'PET_mn': 916.0342125499775, 'wyield_mn': 380.0020116453775, 'wyield_vol': 28908045.03270346, 'num_pixels': 84526.0, 'ws_id': 88}, 90: {'precip_mn': 1223.4458698747799, 'AET_mn': 649.9403851098915, 'PET_mn': 734.4107618073159, 'wyield_mn': 573.5054921441777, 'wyield_vol': 53376614.95825233, 'num_pixels': 103412.0, 'ws_id': 90}, 91: {'precip_mn': 1222.8961717216132, 'AET_mn': 952.2014790920689, 'PET_mn': 1132.4042758714404, 'wyield_mn': 270.5264670508005, 'wyield_vol': 30460766.189632736, 'num_pixels': 125109.0, 'ws_id': 91}, 92: {'precip_mn': 1247.5172427138602, 'AET_mn': 875.6938726566935, 'PET_mn': 1058.862857188135, 'wyield_mn': 218.1248976031199, 'wyield_vol': 43350098.52466453, 'num_pixels': 220822.0, 'ws_id': 92}, 93: {'precip_mn': 1225.1731793680942, 'AET_mn': 944.8991598735217, 'PET_mn': 1134.7989310169796, 'wyield_mn': 280.2714600374922, 'wyield_vol': 26817606.49081142, 'num_pixels': 106316.0, 'ws_id': 93}, 95: {'precip_mn': 1226.275929049758, 'AET_mn': 621.4772845699297, 'PET_mn': 697.5403818732141, 'wyield_mn': 604.798563499998, 'wyield_vol': 72908769.2292065, 'num_pixels': 133945.0, 'ws_id': 95}, 97: {'precip_mn': 1215.2294639875408, 'AET_mn': 893.0590626722853, 'PET_mn': 1005.8406931907433, 'wyield_mn': 322.04728891468517, 'wyield_vol': 29314966.363308154, 'num_pixels': 101141.0, 'ws_id': 97}, 98: {'precip_mn': 1243.746925488756, 'AET_mn': 985.1797597033928, 'PET_mn': 1200.6186371037727, 'wyield_mn': 258.5501641630678, 'wyield_vol': 61589519.01046496, 'num_pixels': 264679.0, 'ws_id': 98}, 100: {'precip_mn': 1203.4823738894572, 'AET_mn': 831.0962851177659, 'PET_mn': 935.4207707184102, 'wyield_mn': 371.86360134921665, 'wyield_vol': 51401069.41465628, 'num_pixels': 153584.0, 'ws_id': 100}, 101: {'precip_mn': 1195.419498767195, 'AET_mn': 950.8571902009121, 'PET_mn': 1147.6064344568256, 'wyield_mn': 244.438345120074, 'wyield_vol': 15325697.587000351, 'num_pixels': 69664.0, 'ws_id': 101}, 102: {'precip_mn': 1213.0946320710984, 'AET_mn': 879.639559210086, 'PET_mn': 998.6069974176895, 'wyield_mn': 333.4458021573903, 'wyield_vol': 30332130.805308517, 'num_pixels': 101073.0, 'ws_id': 102}, 104: {'precip_mn': 1209.4996181124752, 'AET_mn': 832.5172206055795, 'PET_mn': 922.900013330957, 'wyield_mn': 376.9786888282073, 'wyield_vol': 53446568.2851768, 'num_pixels': 157529.0, 'ws_id': 104}, 106: {'precip_mn': 1195.6644396601123, 'AET_mn': 956.7195533298492, 'PET_mn': 1161.3633466434235, 'wyield_mn': 238.37237332283442, 'wyield_vol': 22887895.518287923, 'num_pixels': 106686.0, 'ws_id': 106}, 107: {'precip_mn': 1200.7225996805141, 'AET_mn': 917.7300152696412, 'PET_mn': 1096.444920468537, 'wyield_mn': 282.98940875050397, 'wyield_vol': 33528981.33393196, 'num_pixels': 131646.0, 'ws_id': 107}, 108: {'precip_mn': 1298.1312660912822, 'AET_mn': 756.7519337964933, 'PET_mn': 871.7813327962502, 'wyield_mn': 541.3704334145267, 'wyield_vol': 106440031.9285836, 'num_pixels': 218458.0, 'ws_id': 108}, 109: {'precip_mn': 1213.0687492626441, 'AET_mn': 936.9770841660529, 'PET_mn': 1115.5960552181461, 'wyield_mn': 276.0845693569434, 'wyield_vol': 39689089.43704611, 'num_pixels': 159730.0, 'ws_id': 109}, 110: {'precip_mn': 1234.5463949588266, 'AET_mn': 870.225957277873, 'PET_mn': 1051.589448827418, 'wyield_mn': 364.31238860991056, 'wyield_vol': 72688299.9689877, 'num_pixels': 221691.0, 'ws_id': 110}, 112: {'precip_mn': 1219.8186869245044, 'AET_mn': 881.5005717347153, 'PET_mn': 1005.3494094768143, 'wyield_mn': 338.31495448222074, 'wyield_vol': 42899283.510218136, 'num_pixels': 140892.0, 'ws_id': 112}, 113: {'precip_mn': 1227.4728884414667, 'AET_mn': 909.4594135330148, 'PET_mn': 1095.690119561452, 'wyield_mn': 318.0083968501418, 'wyield_vol': 52520231.57002959, 'num_pixels': 183504.0, 'ws_id': 113}, 114: {'precip_mn': 1237.2663242231752, 'AET_mn': 937.9282033042361, 'PET_mn': 1102.4166695496745, 'wyield_mn': 299.245743417876, 'wyield_vol': 38923372.63955261, 'num_pixels': 144524.0, 'ws_id': 114}, 115: {'precip_mn': 1202.1244766534585, 'AET_mn': 896.719415880355, 'PET_mn': 1030.7694150676778, 'wyield_mn': 305.4016761016888, 'wyield_vol': 39453071.20585578, 'num_pixels': 143538.0, 'ws_id': 115}, 116: {'precip_mn': 1198.0440484627984, 'AET_mn': 943.140973765298, 'PET_mn': 1133.2973040046702, 'wyield_mn': 254.9014680335635, 'wyield_vol': 14882830.05348846, 'num_pixels': 64874.0, 'ws_id': 116}, 117: {'precip_mn': 1205.8283536901608, 'AET_mn': 928.3936801966075, 'PET_mn': 1093.7101738903552, 'wyield_mn': 277.43224479954597, 'wyield_vol': 21983620.1050181, 'num_pixels': 88044.0, 'ws_id': 117}, 118: {'precip_mn': 1214.1668368239782, 'AET_mn': 886.177354574188, 'PET_mn': 1008.3328605137802, 'wyield_mn': 327.9852220323658, 'wyield_vol': 50163437.39556256, 'num_pixels': 169938.0, 'ws_id': 118}}
11/24/2015 10:51:58  invest_natcap.hydropower.hydropower_water_yield DEBUG    Water Scarcity Not Selected
11/24/2015 10:51:58  invest_natcap.nutrient.nutrient DEBUG    Making folder E:\InVestModelWrkspc\NutReten\WorkSpace\11_24_15Comp_Buff25\output
11/24/2015 10:51:58  invest_natcap.nutrient.nutrient DEBUG    Making folder E:\InVestModelWrkspc\NutReten\WorkSpace\11_24_15Comp_Buff25\intermediate
11/24/2015 10:52:09  pygeoprocessing.geoprocessing INFO     align_dataset_list aligning dataset 1 of 3
11/24/2015 10:52:20  pygeoprocessing.geoprocessing INFO     align_dataset_list aligning dataset 2 of 3
11/24/2015 10:52:52  invest_natcap.nutrient.nutrient INFO     calculating flow accumulation
11/24/2015 10:53:02  pygeoprocessing.routing.routing_core INFO     flow_direction_inf 41.6% complete
11/24/2015 10:53:08  pygeoprocessing.routing.routing_core INFO     flow_direction_inf 65.3% complete
11/24/2015 10:53:20  pygeoprocessing.routing.routing_core INFO     labeling flats
11/24/2015 10:53:26  pygeoprocessing.routing.routing_core INFO     draining away from higher
11/24/2015 10:53:35  pygeoprocessing.routing.routing_core INFO     draining towards lower
11/24/2015 10:53:41  pygeoprocessing.routing DEBUG    flats exist, calculating flow direction for them
11/24/2015 10:53:52  pygeoprocessing.routing.routing_core INFO     labeling flats
11/24/2015 10:53:58  pygeoprocessing.routing.routing_core INFO     draining away from higher
11/24/2015 10:54:04  pygeoprocessing.routing.routing_core INFO     draining towards lower
11/24/2015 10:54:07  pygeoprocessing.routing INFO     flats exist on second pass, must be flat areas that abut the raster edge
11/24/2015 10:54:11  pygeoprocessing.routing DEBUG    starting flow accumulation
11/24/2015 10:54:35  pygeoprocessing.geoprocessing INFO     align_dataset_list aligning dataset 1 of 4
11/24/2015 10:54:42  pygeoprocessing.geoprocessing INFO     align_dataset_list aligning dataset 2 of 4
11/24/2015 10:54:48  pygeoprocessing.geoprocessing INFO     align_dataset_list aligning dataset 3 of 4
11/24/2015 10:55:01  pygeoprocessing.routing.routing_core INFO     find outlet percent complete = 31.28, outlet_deque size = 259
11/24/2015 10:55:07  pygeoprocessing.routing.routing_core INFO     find outlet percent complete = 66.14, outlet_deque size = 2050
11/24/2015 10:55:27  pygeoprocessing.routing.routing_core INFO     calculate transport cells_to_process.size() = 11533
11/24/2015 10:55:38  invest_natcap.nutrient.nutrient INFO     Classifying streams from flow accumulation raster
11/24/2015 10:55:47  invest_natcap.nutrient.nutrient DEBUG    E:\InVestModelWrkspc\NutReten\WorkSpace\11_24_15Comp_Buff25\tmp\tmpdisgs4
11/24/2015 10:55:54  pygeoprocessing.geoprocessing WARNING  this call is vectorizing which is deprecated and slow
11/24/2015 10:55:59  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 16.71% complete
11/24/2015 10:56:04  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 32.00% complete
11/24/2015 10:56:09  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 47.76% complete
11/24/2015 10:56:14  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 62.35% complete
11/24/2015 10:56:19  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 78.35% complete
11/24/2015 10:56:24  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 95.06% complete
11/24/2015 10:56:35  pygeoprocessing.geoprocessing INFO     align_dataset_list aligning dataset 1 of 2
11/24/2015 10:56:38  pygeoprocessing.geoprocessing WARNING  this call is vectorizing which is deprecated and slow
11/24/2015 10:56:43  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 11.53% complete
11/24/2015 10:56:49  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 18.12% complete
11/24/2015 10:56:54  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 24.00% complete
11/24/2015 10:56:59  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 29.41% complete
11/24/2015 10:57:04  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 33.41% complete
11/24/2015 10:57:09  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 38.12% complete
11/24/2015 10:57:14  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 43.76% complete
11/24/2015 10:57:20  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 48.94% complete
11/24/2015 10:57:25  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 51.76% complete
11/24/2015 10:57:30  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 56.24% complete
11/24/2015 10:57:35  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 61.65% complete
11/24/2015 10:57:40  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 65.41% complete
11/24/2015 10:57:45  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 71.06% complete
11/24/2015 10:57:50  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 78.12% complete
11/24/2015 10:57:55  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 85.88% complete
11/24/2015 10:58:00  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 93.88% complete
11/24/2015 10:58:04  invest_natcap.nutrient.nutrient DEBUG    E:\InVestModelWrkspc\NutReten\WorkSpace\11_24_15Comp_Buff25\tmp\tmpdisgs4
11/24/2015 10:58:11  pygeoprocessing.geoprocessing WARNING  this call is vectorizing which is deprecated and slow
11/24/2015 10:58:16  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 16.94% complete
11/24/2015 10:58:21  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 32.71% complete
11/24/2015 10:58:26  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 48.47% complete
11/24/2015 10:58:31  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 62.82% complete
11/24/2015 10:58:36  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 78.82% complete
11/24/2015 10:58:41  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 95.76% complete
11/24/2015 10:58:52  pygeoprocessing.geoprocessing INFO     align_dataset_list aligning dataset 1 of 2
11/24/2015 10:58:55  pygeoprocessing.geoprocessing WARNING  this call is vectorizing which is deprecated and slow
11/24/2015 10:59:00  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 11.76% complete
11/24/2015 10:59:05  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 18.12% complete
11/24/2015 10:59:11  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 24.24% complete
11/24/2015 10:59:16  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 30.12% complete
11/24/2015 10:59:21  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 36.00% complete
11/24/2015 10:59:26  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 40.94% complete
11/24/2015 10:59:31  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 44.71% complete
11/24/2015 10:59:37  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 49.41% complete
11/24/2015 10:59:42  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 52.24% complete
11/24/2015 10:59:47  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 56.71% complete
11/24/2015 10:59:52  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 62.12% complete
11/24/2015 10:59:57  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 68.00% complete
11/24/2015 11:00:03  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 74.12% complete
11/24/2015 11:00:08  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 80.47% complete
11/24/2015 11:00:13  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 87.06% complete
11/24/2015 11:00:18  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 99.29% complete
11/24/2015 11:00:36  pygeoprocessing.geoprocessing INFO     align_dataset_list aligning dataset 1 of 4
11/24/2015 11:00:42  pygeoprocessing.geoprocessing INFO     align_dataset_list aligning dataset 2 of 4
11/24/2015 11:00:53  pygeoprocessing.geoprocessing INFO     align_dataset_list aligning dataset 3 of 4
11/24/2015 11:01:35  pygeoprocessing.routing.routing_core INFO     find outlet percent complete = 35.27, outlet_deque size = 293
11/24/2015 11:01:41  pygeoprocessing.routing.routing_core INFO     find outlet percent complete = 73.08, outlet_deque size = 2315
11/24/2015 11:01:59  pygeoprocessing.routing.routing_core INFO     calculate transport cells_to_process.size() = 10629
11/24/2015 11:03:18  pygeoprocessing.geoprocessing INFO     align_dataset_list aligning dataset 1 of 4
11/24/2015 11:03:24  pygeoprocessing.geoprocessing INFO     align_dataset_list aligning dataset 2 of 4
11/24/2015 11:03:30  pygeoprocessing.geoprocessing INFO     align_dataset_list aligning dataset 3 of 4
11/24/2015 11:03:32  pygeoprocessing.geoprocessing WARNING  this call is vectorizing which is deprecated and slow
11/24/2015 11:03:37  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 12.71% complete
11/24/2015 11:03:43  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 19.29% complete
11/24/2015 11:03:48  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 25.65% complete
11/24/2015 11:03:53  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 31.76% complete
11/24/2015 11:03:58  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 37.88% complete
11/24/2015 11:04:04  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 44.00% complete
11/24/2015 11:04:09  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 49.65% complete
11/24/2015 11:04:14  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 53.88% complete
11/24/2015 11:04:19  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 57.41% complete
11/24/2015 11:04:24  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 63.29% complete
11/24/2015 11:04:30  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 69.65% complete
11/24/2015 11:04:35  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 78.59% complete
11/24/2015 11:04:40  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 86.82% complete
11/24/2015 11:04:52  pygeoprocessing.geoprocessing INFO     align_dataset_list aligning dataset 1 of 4
11/24/2015 11:04:58  pygeoprocessing.geoprocessing INFO     align_dataset_list aligning dataset 2 of 4
11/24/2015 11:05:05  pygeoprocessing.geoprocessing INFO     align_dataset_list aligning dataset 3 of 4
11/24/2015 11:05:41  pygeoprocessing.routing.routing_core INFO     find outlet percent complete = 31.59, outlet_deque size = 259
11/24/2015 11:05:47  pygeoprocessing.routing.routing_core INFO     find outlet percent complete = 69.02, outlet_deque size = 2167
11/24/2015 11:06:05  pygeoprocessing.routing.routing_core INFO     calculate transport cells_to_process.size() = 10610
11/24/2015 11:06:20  pygeoprocessing.geoprocessing INFO     align_dataset_list aligning dataset 1 of 5
11/24/2015 11:06:27  pygeoprocessing.geoprocessing INFO     align_dataset_list aligning dataset 2 of 5
11/24/2015 11:06:36  pygeoprocessing.geoprocessing INFO     align_dataset_list aligning dataset 4 of 5
11/24/2015 11:07:14  pygeoprocessing.geoprocessing WARNING  this call is vectorizing which is deprecated and slow
11/24/2015 11:07:19  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 3.53% complete
11/24/2015 11:07:24  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 7.06% complete
11/24/2015 11:07:29  pygeoprocessing.geoprocessing INFO     raster stack calculation approx. 10.59% complete
11/24/2015 11:07:34  pygeoproces

Comments

  • RichRich Administrator, NatCap Staff
    Hi Lauren,

    Sorry, this is a little complicated.  First, I'm not sure what's going on with the model hanging like that.  I'd normally suggest having you send me your data so I can see if there's a bug.  But with the release of InVEST 3.3.0 we deprecated the nutrient retention model in favor of the nutrient delivery ratio model (NDR).

    If you're trying to duplicate results from a past run, I'd suggest using InVEST 3.1.3 which can always be downloaded from our InVEST archive page: http://data.naturalcapitalproject.org/invest-releases/

    Otherwise, depending on your situation, I'd recommend using the NDR model that has better science and a more stable implementation than our old nutrient model.

    Sorry for the hassle, please let me know if I can help further.
  • Hi Rich,

    Thanks for your response. I tried again running it with the InVEST 3.1.3 version, but the model got hung up in the same spot. I'm using a different computer than I used on the previous runs, but the same exact data files. Do you know if there are any issues with running this model on Windows 10 platform?

    Thanks,
    Laura
  • RichRich Administrator, NatCap Staff
    None that I know of, I use windows 10 myself.  Eyeballing your log it looks like you might have quite large rasters, no?  If so and you moved from a computer with an SSD to a platter style HDD that might be an issue.  In the case where rasters don't fit directly in memory InVEST needs to do a lot of disk i/o.  if that's the case it may take some time for large files.  Any chance you can set it to run overnight?
  • Yes, my rasters are really big. Yesterday, I actually let it run while I was at work (8+ hrs) and it was in the same place when I got back.

    The reason I want to use the nutrient retention model is to compare newly developed scenarios to scenarios run previously.

    Thanks for your help. I'll keep tinkering.
  • RichRich Administrator, NatCap Staff
    Okay, sorry Laura, I don't know what else I can do at this point... Any chance you could get that computer back? :)
Sign In or Register to comment.