2023-01-31 09:00:25,695 (natcap.invest.utils) utils.prepare_workspace(161) Level 100 Writing log messages to [D:\Invest\Cooling_Output\InVEST-natcap.invest.urban_cooling_model-log-2023-01-31--09_00_25.txt] 2023-01-31 09:00:25,696 (__main__) cli.main(475) Level 100 Starting model with parameters: Arguments for InVEST natcap.invest.urban_cooling_model 3.12.0: aoi_vector_path D:\Invest\Layers_Input\dtla extent\dtla.shp avg_rel_humidity biophysical_table_path D:\Invest\Layers_Input\Environatlas_biophysical_table.csv building_vector_path cc_method factors cc_weight_albedo cc_weight_eti cc_weight_shade do_energy_valuation False do_productivity_valuation False energy_consumption_table_path green_area_cooling_distance 450 lulc_raster_path D:\Invest\Layers_Input\Retired_LULC\Environatlas_MULC_DTLA_2m.tif n_workers -1 ref_eto_raster_path D:\Invest\Layers_Input\Nibble_ECO3E1.tif results_suffix POC5 t_air_average_radius 550 t_ref 20 uhi_max 2 workspace_dir D:\Invest\Cooling_Output 2023-01-31 09:00:25,748 (natcap.invest.urban_cooling_model) urban_cooling_model.execute(308) INFO Starting Urban Cooling Model 2023-01-31 09:00:26,838 (pygeoprocessing.geoprocessing) geoprocessing.align_and_resize_raster_stack(824) INFO 1 of 2 aligned: lulc_POC5.tif 2023-01-31 09:00:28,787 (pygeoprocessing.geoprocessing) geoprocessing.align_and_resize_raster_stack(824) INFO 2 of 2 aligned: ref_eto_POC5.tif 2023-01-31 09:00:28,787 (pygeoprocessing.geoprocessing) geoprocessing.align_and_resize_raster_stack(828) INFO aligned all 2 rasters. 2023-01-31 09:00:29,072 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(396) INFO starting stats_worker 2023-01-31 09:00:29,073 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(402) INFO started stats_worker 2023-01-31 09:00:29,427 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(482) INFO 100.0% complete 2023-01-31 09:00:29,428 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(485) INFO Waiting for raster stats worker result. 2023-01-31 09:00:29,729 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(396) INFO starting stats_worker 2023-01-31 09:00:29,730 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(402) INFO started stats_worker 2023-01-31 09:00:30,038 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(482) INFO 100.0% complete 2023-01-31 09:00:30,038 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(485) INFO Waiting for raster stats worker result. 2023-01-31 09:00:30,321 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(396) INFO starting stats_worker 2023-01-31 09:00:30,323 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(402) INFO started stats_worker 2023-01-31 09:00:30,631 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(482) INFO 100.0% complete 2023-01-31 09:00:30,632 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(485) INFO Waiting for raster stats worker result. 2023-01-31 09:00:30,891 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(396) INFO starting stats_worker 2023-01-31 09:00:30,892 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(402) INFO started stats_worker 2023-01-31 09:00:31,178 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(482) INFO 100.0% complete 2023-01-31 09:00:31,179 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(485) INFO Waiting for raster stats worker result. 2023-01-31 09:00:31,452 (natcap.invest.urban_cooling_model) urban_cooling_model.convolve_2d_by_exponential(1394) INFO Starting a convolution over D:\Invest\Cooling_Output\intermediate\green_area_POC5.tif with a decay distance of 225 2023-01-31 09:00:31,868 (pygeoprocessing.geoprocessing) geoprocessing.convolve_2d(2681) INFO starting convolve 2023-01-31 09:00:31,918 (pygeoprocessing.geoprocessing) geoprocessing.convolve_2d(2729) INFO 9801 sent to workers, wait for worker results 2023-01-31 09:00:36,901 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 1.3% complete on cc_park_POC5.tif 2023-01-31 09:00:41,937 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 2.6% complete on cc_park_POC5.tif 2023-01-31 09:00:46,979 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 3.8% complete on cc_park_POC5.tif 2023-01-31 09:00:51,990 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 5.2% complete on cc_park_POC5.tif 2023-01-31 09:00:56,998 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 6.4% complete on cc_park_POC5.tif 2023-01-31 09:01:02,035 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 7.7% complete on cc_park_POC5.tif 2023-01-31 09:01:07,041 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 9.1% complete on cc_park_POC5.tif 2023-01-31 09:01:12,070 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 10.5% complete on cc_park_POC5.tif 2023-01-31 09:01:17,073 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 12.0% complete on cc_park_POC5.tif 2023-01-31 09:01:22,073 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 13.4% complete on cc_park_POC5.tif 2023-01-31 09:01:27,109 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 14.9% complete on cc_park_POC5.tif 2023-01-31 09:01:32,140 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 16.3% complete on cc_park_POC5.tif 2023-01-31 09:01:37,143 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 17.8% complete on cc_park_POC5.tif 2023-01-31 09:01:42,159 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 19.2% complete on cc_park_POC5.tif 2023-01-31 09:01:47,185 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 20.7% complete on cc_park_POC5.tif 2023-01-31 09:01:52,200 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 22.1% complete on cc_park_POC5.tif 2023-01-31 09:01:57,231 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 23.6% complete on cc_park_POC5.tif 2023-01-31 09:02:02,253 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 25.0% complete on cc_park_POC5.tif 2023-01-31 09:02:07,286 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 26.5% complete on cc_park_POC5.tif 2023-01-31 09:02:12,325 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 27.9% complete on cc_park_POC5.tif 2023-01-31 09:02:17,329 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 29.3% complete on cc_park_POC5.tif 2023-01-31 09:02:22,336 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 30.7% complete on cc_park_POC5.tif 2023-01-31 09:02:27,352 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 32.1% complete on cc_park_POC5.tif 2023-01-31 09:02:32,371 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 33.6% complete on cc_park_POC5.tif 2023-01-31 09:02:37,395 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 35.0% complete on cc_park_POC5.tif 2023-01-31 09:02:42,405 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 36.4% complete on cc_park_POC5.tif 2023-01-31 09:02:47,411 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 37.9% complete on cc_park_POC5.tif 2023-01-31 09:02:52,422 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 39.3% complete on cc_park_POC5.tif 2023-01-31 09:02:57,447 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 40.8% complete on cc_park_POC5.tif 2023-01-31 09:03:02,452 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 42.2% complete on cc_park_POC5.tif 2023-01-31 09:03:07,470 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 43.6% complete on cc_park_POC5.tif 2023-01-31 09:03:12,492 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 45.0% complete on cc_park_POC5.tif 2023-01-31 09:03:17,517 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 46.5% complete on cc_park_POC5.tif 2023-01-31 09:03:22,542 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 48.0% complete on cc_park_POC5.tif 2023-01-31 09:03:27,577 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 49.5% complete on cc_park_POC5.tif 2023-01-31 09:03:32,613 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 50.7% complete on cc_park_POC5.tif 2023-01-31 09:03:37,639 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 52.1% complete on cc_park_POC5.tif 2023-01-31 09:03:42,643 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 53.2% complete on cc_park_POC5.tif 2023-01-31 09:03:47,656 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 54.4% complete on cc_park_POC5.tif 2023-01-31 09:03:52,678 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 55.6% complete on cc_park_POC5.tif 2023-01-31 09:03:57,679 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 56.7% complete on cc_park_POC5.tif 2023-01-31 09:04:02,688 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 58.0% complete on cc_park_POC5.tif 2023-01-31 09:04:07,699 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 59.2% complete on cc_park_POC5.tif 2023-01-31 09:04:12,708 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 60.5% complete on cc_park_POC5.tif 2023-01-31 09:04:17,716 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 62.1% complete on cc_park_POC5.tif 2023-01-31 09:04:22,735 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 63.5% complete on cc_park_POC5.tif 2023-01-31 09:04:27,783 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 64.9% complete on cc_park_POC5.tif 2023-01-31 09:04:32,789 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 66.4% complete on cc_park_POC5.tif 2023-01-31 09:04:37,806 (pygeoprocessing.geoprocessing) geoprocessing.(2789) INFO convolution worker approximately 68.8% complete on cc_park_POC5.tif 2023-01-31 09:04:40,617 (pygeoprocessing.geoprocessing) geoprocessing.convolve_2d(2795) INFO convolution worker 100.0% complete on cc_park_POC5.tif 2023-01-31 09:04:41,184 (pygeoprocessing.geoprocessing) geoprocessing.convolve_2d(2803) INFO need to normalize result so nodata values are not included 2023-01-31 09:04:42,394 (pygeoprocessing.geoprocessing) geoprocessing.convolve_2d(2842) INFO convolution nodata normalize 100.0% complete on cc_park_POC5.tif 2023-01-31 09:04:43,250 (taskgraph.Task) Task.add_task(706) ERROR Something went wrong when adding task calculate T air (5), terminating taskgraph. Traceback (most recent call last): File "taskgraph\Task.py", line 674, in add_task File "taskgraph\Task.py", line 1093, in _call File "natcap\invest\urban_cooling_model.py", line 1406, in convolve_2d_by_exponential File "shutil.py", line 749, in rmtree File "shutil.py", line 623, in _rmtree_unsafe File "shutil.py", line 621, in _rmtree_unsafe PermissionError: [WinError 5] Access is denied: 'D:\\Invest\\Cooling_Output\\intermediate\\tmpedtyzico' 2023-01-31 09:04:43,252 (natcap.invest.utils) utils.prepare_workspace(166) ERROR Exception while executing natcap.invest.urban_cooling_model Traceback (most recent call last): File "natcap\invest\utils.py", line 164, in prepare_workspace File "invest\cli.py", line 486, in main File "natcap\invest\urban_cooling_model.py", line 419, in execute File "taskgraph\Task.py", line 674, in add_task File "taskgraph\Task.py", line 1093, in _call File "natcap\invest\urban_cooling_model.py", line 1406, in convolve_2d_by_exponential File "shutil.py", line 749, in rmtree File "shutil.py", line 623, in _rmtree_unsafe File "shutil.py", line 621, in _rmtree_unsafe PermissionError: [WinError 5] Access is denied: 'D:\\Invest\\Cooling_Output\\intermediate\\tmpedtyzico' 2023-01-31 09:04:43,255 (natcap.invest.utils) utils.prepare_workspace(169) INFO Elapsed time: 4m 17.560000000000002s 2023-01-31 09:04:43,255 (natcap.invest.utils) utils.prepare_workspace(172) INFO Execution finished 2023-01-31 09:04:43,255 (natcap.invest.utils) utils.sandbox_tempdir(298) ERROR Could not remove sandbox D:\Invest\Cooling_Output\tmp9hqj4r3p Traceback (most recent call last): File "natcap\invest\utils.py", line 293, in sandbox_tempdir File "natcap\invest\utils.py", line 164, in prepare_workspace File "invest\cli.py", line 486, in main File "natcap\invest\urban_cooling_model.py", line 419, in execute File "taskgraph\Task.py", line 674, in add_task File "taskgraph\Task.py", line 1093, in _call File "natcap\invest\urban_cooling_model.py", line 1406, in convolve_2d_by_exponential File "shutil.py", line 749, in rmtree File "shutil.py", line 623, in _rmtree_unsafe File "shutil.py", line 621, in _rmtree_unsafe PermissionError: [WinError 5] Access is denied: 'D:\\Invest\\Cooling_Output\\intermediate\\tmpedtyzico' During handling of the above exception, another exception occurred: Traceback (most recent call last): File "natcap\invest\utils.py", line 296, in sandbox_tempdir File "shutil.py", line 749, in rmtree File "shutil.py", line 623, in _rmtree_unsafe File "shutil.py", line 621, in _rmtree_unsafe PermissionError: [WinError 5] Access is denied: 'D:\\Invest\\Cooling_Output\\tmp9hqj4r3p'