2019-06-14 23:14:42,921 utils.prepare_workspace(109) INFO Writing log messages to C:\Investdata\Carbon\Carbon_classproject\UTM\working\InVEST-InVEST-Carbon-Model-log-2019-06-14--23_14_42.txt
2019-06-14 23:14:42,926 model._logged_target(1633) Level 100 Starting model with parameters: 
Arguments for InVEST natcap.invest.carbon 3.7.0:
calc_sequestration        True
carbon_pools_path         C:/Investdata/Carbon/Carbon_classproject/UTM/carbon_pools_samp_mabira.csv
discount_rate             7
do_redd                   True
do_valuation              True
lulc_cur_path             C:/Investdata/Carbon/Carbon_classproject/UTM/lucl_curprj1.tif
lulc_cur_year             1992
lulc_fut_path             C:/Investdata/Carbon/Carbon_classproject/UTM/lulc_fut_resample.tif
lulc_fut_year             2015
lulc_redd_path            C:/Investdata/Carbon/Carbon_classproject/UTM/reddf.tif
n_workers                 -1
price_per_metric_ton_of_c 140
rate_change               2
results_suffix            _resuts140
workspace_dir             C:\Investdata\Carbon\Carbon_classproject\UTM\working

2019-06-14 23:14:42,941 carbon.execute(121) INFO Building file registry
2019-06-14 23:14:43,003 carbon.execute(166) INFO Map all carbon pools to carbon storage rasters.
2019-06-14 23:14:43,003 carbon.execute(181) INFO Mapping carbon from 'lulc_cur_path' to 'c_above_cur' scenario.
2019-06-14 23:14:43,003 carbon.execute(181) INFO Mapping carbon from 'lulc_cur_path' to 'c_below_cur' scenario.
2019-06-14 23:14:43,026 carbon.execute(181) INFO Mapping carbon from 'lulc_cur_path' to 'c_soil_cur' scenario.
2019-06-14 23:14:43,042 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-06-14 23:14:43,042 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-28, started daemon 8828)>
2019-06-14 23:14:43,042 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-06-14 23:14:43,042 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-06-14 23:14:43,056 threading.run(754) DEBUG payload is None, terminating
2019-06-14 23:14:43,056 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-06-14 23:14:43,188 carbon.execute(181) INFO Mapping carbon from 'lulc_cur_path' to 'c_dead_cur' scenario.
2019-06-14 23:14:43,203 carbon.execute(194) INFO Calculate carbon storage for 'tot_c_cur'
2019-06-14 23:14:43,226 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-06-14 23:14:43,226 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-29, started daemon 7784)>
2019-06-14 23:14:43,242 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-06-14 23:14:43,242 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-06-14 23:14:43,242 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-06-14 23:14:43,242 threading.run(754) DEBUG payload is None, terminating
2019-06-14 23:14:43,380 carbon.execute(181) INFO Mapping carbon from 'lulc_fut_path' to 'c_above_fut' scenario.
2019-06-14 23:14:43,394 carbon.execute(181) INFO Mapping carbon from 'lulc_fut_path' to 'c_below_fut' scenario.
2019-06-14 23:14:43,394 carbon.execute(181) INFO Mapping carbon from 'lulc_fut_path' to 'c_soil_fut' scenario.
2019-06-14 23:14:43,427 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-06-14 23:14:43,427 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-30, started daemon 13516)>
2019-06-14 23:14:43,427 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-06-14 23:14:43,427 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-06-14 23:14:43,427 threading.run(754) DEBUG payload is None, terminating
2019-06-14 23:14:43,427 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-06-14 23:14:43,627 carbon.execute(181) INFO Mapping carbon from 'lulc_fut_path' to 'c_dead_fut' scenario.
2019-06-14 23:14:43,642 carbon.execute(194) INFO Calculate carbon storage for 'tot_c_fut'
2019-06-14 23:14:43,665 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-06-14 23:14:43,680 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-31, started daemon 14212)>
2019-06-14 23:14:43,680 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-06-14 23:14:43,680 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-06-14 23:14:43,680 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-06-14 23:14:43,680 threading.run(754) DEBUG payload is None, terminating
2019-06-14 23:14:43,858 carbon.execute(181) INFO Mapping carbon from 'lulc_redd_path' to 'c_above_redd' scenario.
2019-06-14 23:14:43,874 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-06-14 23:14:43,874 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-32, started daemon 5520)>
2019-06-14 23:14:43,891 threading.run(754) DEBUG payload is None, terminating
2019-06-14 23:14:43,891 geoprocessing.raster_calculator(477) INFO Waiting for raster stats worker result.
2019-06-14 23:14:43,891 threading.run(754) WARNING No valid pixels were received, sending None.
2019-06-14 23:14:43,908 Task.add_task(636) ERROR Something went wrong when adding task carbon_map_c_above_redd (10), 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\carbon.py", line 304, in _generate_carbon_map
  File "Z:\opt\atlassian\pipelines\agent\build\env\lib\site-packages\pygeoprocessing\geoprocessing.py", line 1766, in reclassify_raster
  File "Z:\opt\atlassian\pipelines\agent\build\env\lib\site-packages\pygeoprocessing\geoprocessing.py", line 415, in raster_calculator
  File "Z:\opt\atlassian\pipelines\agent\build\env\lib\site-packages\pygeoprocessing\geoprocessing.py", line 1760, in _map_dataset_to_value_op
ValueError: The following 15 raster values [-3.4000000e+38  0.0000000e+00  2.6245880e-14  2.6245883e-14
  1.5274328e-07  1.5274331e-07  1.7182995e-07  1.7182998e-07
  1.7183001e-07  2.4820164e-07  3.2457325e-07  3.2457328e-07
  9.9999970e-01  9.9999976e-01  9.9999982e-01] from "C:/Investdata/Carbon/Carbon_classproject/UTM/reddf.tif" do not have corresponding entries in the ``value_map``: {1L: 592.29, 2L: 30.87, 3L: 27.0, 5L: 0.0}
2019-06-14 23:14:43,908 model._logged_target(1639) ERROR Exception while executing <function execute at 0x15D6C6B0>
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\carbon.py", line 188, in execute
  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\carbon.py", line 304, in _generate_carbon_map
  File "Z:\opt\atlassian\pipelines\agent\build\env\lib\site-packages\pygeoprocessing\geoprocessing.py", line 1766, in reclassify_raster
  File "Z:\opt\atlassian\pipelines\agent\build\env\lib\site-packages\pygeoprocessing\geoprocessing.py", line 415, in raster_calculator
  File "Z:\opt\atlassian\pipelines\agent\build\env\lib\site-packages\pygeoprocessing\geoprocessing.py", line 1760, in _map_dataset_to_value_op
ValueError: The following 15 raster values [-3.4000000e+38  0.0000000e+00  2.6245880e-14  2.6245883e-14
  1.5274328e-07  1.5274331e-07  1.7182995e-07  1.7182998e-07
  1.7183001e-07  2.4820164e-07  3.2457325e-07  3.2457328e-07
  9.9999970e-01  9.9999976e-01  9.9999982e-01] from "C:/Investdata/Carbon/Carbon_classproject/UTM/reddf.tif" do not have corresponding entries in the ``value_map``: {1L: 592.29, 2L: 30.87, 3L: 27.0, 5L: 0.0}
2019-06-14 23:14:43,908 model._logged_target(1642) INFO Execution finished
2019-06-14 23:14:43,908 utils.prepare_workspace(115) INFO Elapsed time: 0.98s