2021-12-27 15:37:20,119 utils.prepare_workspace(123) INFO Writing log messages to C:/Users/boxxx032/Downloads/work_space\Rangeland-Production-Model-log-2021-12-27--15_37_20.txt
2021-12-27 15:37:20,119 model._logged_target(1635) Level 100 Starting model with parameters: 
Arguments for InVEST rangeland_production.forage 0.2.1:
animal_grazing_areas_path            C:/Users/boxxx032/Documents/RPM data/animal_num_proj/rnum_ave.shp
animal_trait_path                    C:/Users/boxxx032/Documents/RPM data/animal_parameters.csv
aoi_path                             C:/Users/boxxx032/Documents/RPM data/study_area.shp
bulk_density_path                    C:/Users/boxxx032/Documents/RPM data/soil/rbulk_1530cm_mean.tif
clay_proportion_path                 C:/Users/boxxx032/Documents/RPM data/soil/rclay_1530cm_mean.tif
initial_conditions_dir               
management_threshold                 100
max_temp_dir                         C:/Users/boxxx032/Documents/ArcGIS/output_tmax/tmax_2010
min_temp_dir                         C:/Users/boxxx032/Documents/ArcGIS/output_tmin/tmin_2010
n_months                             12
n_workers                            -1
pft_initial_table                    C:/Users/boxxx032/Documents/RPM data/pft_initial_table.csv
ph_path                              C:/Users/boxxx032/Documents/RPM data/soil/rph2o_1530cm_mean.tif
precip_dir                           C:/Users/boxxx032/Documents/ArcGIS/output_prec/prec_2010
proportion_legume_path               C:/Users/boxxx032/Documents/RPM data/legumeCopy.tif
results_suffix                       
sand_proportion_path                 C:/Users/boxxx032/Documents/RPM data/soil/rsand_1530cm_mean.tif
save_sv_rasters                      False
silt_proportion_path                 C:/Users/boxxx032/Documents/RPM data/soil/rsilt_1530cm_mean.tif
site_initial_table                   C:/Users/boxxx032/Documents/RPM data/site_initial_table.csv
site_param_spatial_index_path        C:/Users/boxxx032/Documents/RPM data/site_test3.tif
site_param_table                     C:/Users/boxxx032/Documents/RPM data/site_parameters.csv
starting_month                       1
starting_year                        2010
veg_spatial_composition_path_pattern C:/Users/boxxx032/Documents/RPM data/pft <PFT>.tif
veg_trait_path                       C:/Users/boxxx032/Documents/RPM data/pft_trait.csv
workspace_dir                        C:/Users/boxxx032/Downloads/work_space

2021-12-27 15:37:20,119 forage.execute(617) INFO model execute: {'workspace_dir': 'C:/Users/boxxx032/Downloads/work_space', 'results_suffix': '', 'n_months': '12', 'starting_year': '2010', 'starting_month': '1', 'aoi_path': 'C:/Users/boxxx032/Documents/RPM data/study_area.shp', 'management_threshold': '100', 'proportion_legume_path': 'C:/Users/boxxx032/Documents/RPM data/legumeCopy.tif', 'clay_proportion_path': 'C:/Users/boxxx032/Documents/RPM data/soil/rclay_1530cm_mean.tif', 'silt_proportion_path': 'C:/Users/boxxx032/Documents/RPM data/soil/rsilt_1530cm_mean.tif', 'sand_proportion_path': 'C:/Users/boxxx032/Documents/RPM data/soil/rsand_1530cm_mean.tif', 'bulk_density_path': 'C:/Users/boxxx032/Documents/RPM data/soil/rbulk_1530cm_mean.tif', 'ph_path': 'C:/Users/boxxx032/Documents/RPM data/soil/rph2o_1530cm_mean.tif', 'precip_dir': 'C:/Users/boxxx032/Documents/ArcGIS/output_prec/prec_2010', 'min_temp_dir': 'C:/Users/boxxx032/Documents/ArcGIS/output_tmin/tmin_2010', 'max_temp_dir': 'C:/Users/boxxx032/Documents/ArcGIS/output_tmax/tmax_2010', 'site_param_spatial_index_path': 'C:/Users/boxxx032/Documents/RPM data/site_test3.tif', 'veg_spatial_composition_path_pattern': 'C:/Users/boxxx032/Documents/RPM data/pft <PFT>.tif', 'animal_grazing_areas_path': 'C:/Users/boxxx032/Documents/RPM data/animal_num_proj/rnum_ave.shp', 'site_param_table': 'C:/Users/boxxx032/Documents/RPM data/site_parameters.csv', 'veg_trait_path': 'C:/Users/boxxx032/Documents/RPM data/pft_trait.csv', 'animal_trait_path': 'C:/Users/boxxx032/Documents/RPM data/animal_parameters.csv', 'initial_conditions_dir': '', 'site_initial_table': 'C:/Users/boxxx032/Documents/RPM data/site_initial_table.csv', 'pft_initial_table': 'C:/Users/boxxx032/Documents/RPM data/pft_initial_table.csv', 'save_sv_rasters': False, 'n_workers': '-1'}
2021-12-27 15:37:20,280 forage.execute(840) INFO pixel size of aligned inputs: (0.041666666666666664, -0.04166666666666665)
2021-12-27 15:37:24,281 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:24,282 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:24,282 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-243, started daemon 11996)>
2021-12-27 15:37:24,288 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:24,288 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:24,333 geoprocessing.align_and_resize_raster_stack(795) INFO 1 of 43 aligned: aligned_rbulk_1530cm_mean.tif
2021-12-27 15:37:28,503 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:28,504 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:28,504 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-244, started daemon 1696)>
2021-12-27 15:37:28,511 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:28,512 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:28,567 geoprocessing.align_and_resize_raster_stack(795) INFO 2 of 43 aligned: aligned_rclay_1530cm_mean.tif
2021-12-27 15:37:28,674 geoprocessing.mask_raster(3204) WARNING No mask value was passed and target nodata is undefined, defaulting to 0 as the target mask value.
2021-12-27 15:37:28,684 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:28,685 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:28,685 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-245, started daemon 8484)>
2021-12-27 15:37:28,695 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:28,695 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:28,706 geoprocessing.align_and_resize_raster_stack(795) INFO 3 of 43 aligned: aligned_clip_min_wc2.1_2.5m_tmax_2010_1.tif
2021-12-27 15:37:28,792 geoprocessing.mask_raster(3204) WARNING No mask value was passed and target nodata is undefined, defaulting to 0 as the target mask value.
2021-12-27 15:37:28,801 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:28,802 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:28,802 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-246, started daemon 16844)>
2021-12-27 15:37:28,812 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:28,813 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:28,827 geoprocessing.align_and_resize_raster_stack(795) INFO 4 of 43 aligned: aligned_clip_min_wc2.1_2.5m_tmax_2010_10.tif
2021-12-27 15:37:28,913 geoprocessing.mask_raster(3204) WARNING No mask value was passed and target nodata is undefined, defaulting to 0 as the target mask value.
2021-12-27 15:37:28,922 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:28,923 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:28,923 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-247, started daemon 7540)>
2021-12-27 15:37:28,932 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:28,932 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:28,945 geoprocessing.align_and_resize_raster_stack(795) INFO 5 of 43 aligned: aligned_clip_min_wc2.1_2.5m_tmax_2010_11.tif
2021-12-27 15:37:29,030 geoprocessing.mask_raster(3204) WARNING No mask value was passed and target nodata is undefined, defaulting to 0 as the target mask value.
2021-12-27 15:37:29,039 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:29,041 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:29,041 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-248, started daemon 5800)>
2021-12-27 15:37:29,050 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:29,050 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:29,060 geoprocessing.align_and_resize_raster_stack(795) INFO 6 of 43 aligned: aligned_clip_min_wc2.1_2.5m_tmax_2010_12.tif
2021-12-27 15:37:29,141 geoprocessing.mask_raster(3204) WARNING No mask value was passed and target nodata is undefined, defaulting to 0 as the target mask value.
2021-12-27 15:37:29,149 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:29,149 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:29,149 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-249, started daemon 11676)>
2021-12-27 15:37:29,162 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:29,164 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:29,176 geoprocessing.align_and_resize_raster_stack(795) INFO 7 of 43 aligned: aligned_clip_min_wc2.1_2.5m_tmax_2010_2.tif
2021-12-27 15:37:29,261 geoprocessing.mask_raster(3204) WARNING No mask value was passed and target nodata is undefined, defaulting to 0 as the target mask value.
2021-12-27 15:37:29,269 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:29,270 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:29,270 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-250, started daemon 12544)>
2021-12-27 15:37:29,279 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:29,280 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:29,292 geoprocessing.align_and_resize_raster_stack(795) INFO 8 of 43 aligned: aligned_clip_min_wc2.1_2.5m_tmax_2010_3.tif
2021-12-27 15:37:29,677 geoprocessing.mask_raster(3204) WARNING No mask value was passed and target nodata is undefined, defaulting to 0 as the target mask value.
2021-12-27 15:37:29,688 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:29,690 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:29,690 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-251, started daemon 15532)>
2021-12-27 15:37:29,701 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:29,702 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:29,714 geoprocessing.align_and_resize_raster_stack(795) INFO 9 of 43 aligned: aligned_clip_min_wc2.1_2.5m_tmax_2010_4.tif
2021-12-27 15:37:29,795 geoprocessing.mask_raster(3204) WARNING No mask value was passed and target nodata is undefined, defaulting to 0 as the target mask value.
2021-12-27 15:37:29,804 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:29,805 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:29,805 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-252, started daemon 16136)>
2021-12-27 15:37:29,814 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:29,814 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:29,826 geoprocessing.align_and_resize_raster_stack(795) INFO 10 of 43 aligned: aligned_clip_min_wc2.1_2.5m_tmax_2010_5.tif
2021-12-27 15:37:29,906 geoprocessing.mask_raster(3204) WARNING No mask value was passed and target nodata is undefined, defaulting to 0 as the target mask value.
2021-12-27 15:37:29,916 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:29,918 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:29,918 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-253, started daemon 9300)>
2021-12-27 15:37:29,926 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:29,926 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:29,937 geoprocessing.align_and_resize_raster_stack(795) INFO 11 of 43 aligned: aligned_clip_min_wc2.1_2.5m_tmax_2010_6.tif
2021-12-27 15:37:30,019 geoprocessing.mask_raster(3204) WARNING No mask value was passed and target nodata is undefined, defaulting to 0 as the target mask value.
2021-12-27 15:37:30,029 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:30,029 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:30,030 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-254, started daemon 3152)>
2021-12-27 15:37:30,042 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:30,043 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:30,056 geoprocessing.align_and_resize_raster_stack(795) INFO 12 of 43 aligned: aligned_clip_min_wc2.1_2.5m_tmax_2010_7.tif
2021-12-27 15:37:30,146 geoprocessing.mask_raster(3204) WARNING No mask value was passed and target nodata is undefined, defaulting to 0 as the target mask value.
2021-12-27 15:37:30,155 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:30,156 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:30,156 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-255, started daemon 7436)>
2021-12-27 15:37:30,165 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:30,166 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:30,177 geoprocessing.align_and_resize_raster_stack(795) INFO 13 of 43 aligned: aligned_clip_min_wc2.1_2.5m_tmax_2010_8.tif
2021-12-27 15:37:30,264 geoprocessing.mask_raster(3204) WARNING No mask value was passed and target nodata is undefined, defaulting to 0 as the target mask value.
2021-12-27 15:37:30,273 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:30,274 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:30,274 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-256, started daemon 18216)>
2021-12-27 15:37:30,283 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:30,284 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:30,296 geoprocessing.align_and_resize_raster_stack(795) INFO 14 of 43 aligned: aligned_clip_min_wc2.1_2.5m_tmax_2010_9.tif
2021-12-27 15:37:30,382 geoprocessing.mask_raster(3204) WARNING No mask value was passed and target nodata is undefined, defaulting to 0 as the target mask value.
2021-12-27 15:37:30,395 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:30,397 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:30,397 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-257, started daemon 9256)>
2021-12-27 15:37:30,407 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:30,408 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:30,419 geoprocessing.align_and_resize_raster_stack(795) INFO 15 of 43 aligned: aligned_clip_min_wc2.1_2.5m_tmin_2010_1.tif
2021-12-27 15:37:30,500 geoprocessing.mask_raster(3204) WARNING No mask value was passed and target nodata is undefined, defaulting to 0 as the target mask value.
2021-12-27 15:37:30,510 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:30,511 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:30,511 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-258, started daemon 5000)>
2021-12-27 15:37:30,520 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:30,521 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:30,532 geoprocessing.align_and_resize_raster_stack(795) INFO 16 of 43 aligned: aligned_clip_min_wc2.1_2.5m_tmin_2010_10.tif
2021-12-27 15:37:30,618 geoprocessing.mask_raster(3204) WARNING No mask value was passed and target nodata is undefined, defaulting to 0 as the target mask value.
2021-12-27 15:37:30,627 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:30,627 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:30,627 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-259, started daemon 11836)>
2021-12-27 15:37:30,638 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:30,639 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:30,650 geoprocessing.align_and_resize_raster_stack(795) INFO 17 of 43 aligned: aligned_clip_min_wc2.1_2.5m_tmin_2010_11.tif
2021-12-27 15:37:30,734 geoprocessing.mask_raster(3204) WARNING No mask value was passed and target nodata is undefined, defaulting to 0 as the target mask value.
2021-12-27 15:37:30,743 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:30,744 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:30,744 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-260, started daemon 17772)>
2021-12-27 15:37:30,756 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:30,757 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:30,769 geoprocessing.align_and_resize_raster_stack(795) INFO 18 of 43 aligned: aligned_clip_min_wc2.1_2.5m_tmin_2010_12.tif
2021-12-27 15:37:30,849 geoprocessing.mask_raster(3204) WARNING No mask value was passed and target nodata is undefined, defaulting to 0 as the target mask value.
2021-12-27 15:37:30,859 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:30,860 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:30,860 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-261, started daemon 17224)>
2021-12-27 15:37:30,869 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:30,870 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:30,880 geoprocessing.align_and_resize_raster_stack(795) INFO 19 of 43 aligned: aligned_clip_min_wc2.1_2.5m_tmin_2010_2.tif
2021-12-27 15:37:31,200 geoprocessing.mask_raster(3204) WARNING No mask value was passed and target nodata is undefined, defaulting to 0 as the target mask value.
2021-12-27 15:37:31,209 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:31,210 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:31,210 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-262, started daemon 17324)>
2021-12-27 15:37:31,218 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:31,219 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:31,231 geoprocessing.align_and_resize_raster_stack(795) INFO 20 of 43 aligned: aligned_clip_min_wc2.1_2.5m_tmin_2010_3.tif
2021-12-27 15:37:31,327 geoprocessing.mask_raster(3204) WARNING No mask value was passed and target nodata is undefined, defaulting to 0 as the target mask value.
2021-12-27 15:37:31,336 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:31,338 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:31,338 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-263, started daemon 7136)>
2021-12-27 15:37:31,346 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:31,347 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:31,358 geoprocessing.align_and_resize_raster_stack(795) INFO 21 of 43 aligned: aligned_clip_min_wc2.1_2.5m_tmin_2010_4.tif
2021-12-27 15:37:31,438 geoprocessing.mask_raster(3204) WARNING No mask value was passed and target nodata is undefined, defaulting to 0 as the target mask value.
2021-12-27 15:37:31,450 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:31,453 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:31,454 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-264, started daemon 11700)>
2021-12-27 15:37:31,463 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:31,464 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:31,476 geoprocessing.align_and_resize_raster_stack(795) INFO 22 of 43 aligned: aligned_clip_min_wc2.1_2.5m_tmin_2010_5.tif
2021-12-27 15:37:31,557 geoprocessing.mask_raster(3204) WARNING No mask value was passed and target nodata is undefined, defaulting to 0 as the target mask value.
2021-12-27 15:37:31,566 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:31,567 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:31,567 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-265, started daemon 13364)>
2021-12-27 15:37:31,578 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:31,579 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:31,589 geoprocessing.align_and_resize_raster_stack(795) INFO 23 of 43 aligned: aligned_clip_min_wc2.1_2.5m_tmin_2010_6.tif
2021-12-27 15:37:31,672 geoprocessing.mask_raster(3204) WARNING No mask value was passed and target nodata is undefined, defaulting to 0 as the target mask value.
2021-12-27 15:37:31,680 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:31,682 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:31,682 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-266, started daemon 12224)>
2021-12-27 15:37:31,692 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:31,693 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:31,705 geoprocessing.align_and_resize_raster_stack(795) INFO 24 of 43 aligned: aligned_clip_min_wc2.1_2.5m_tmin_2010_7.tif
2021-12-27 15:37:31,795 geoprocessing.mask_raster(3204) WARNING No mask value was passed and target nodata is undefined, defaulting to 0 as the target mask value.
2021-12-27 15:37:31,804 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:31,805 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:31,805 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-267, started daemon 1172)>
2021-12-27 15:37:31,814 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:31,814 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:31,826 geoprocessing.align_and_resize_raster_stack(795) INFO 25 of 43 aligned: aligned_clip_min_wc2.1_2.5m_tmin_2010_8.tif
2021-12-27 15:37:31,906 geoprocessing.mask_raster(3204) WARNING No mask value was passed and target nodata is undefined, defaulting to 0 as the target mask value.
2021-12-27 15:37:31,914 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:31,916 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:31,917 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-268, started daemon 8648)>
2021-12-27 15:37:31,926 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:31,926 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:31,937 geoprocessing.align_and_resize_raster_stack(795) INFO 26 of 43 aligned: aligned_clip_min_wc2.1_2.5m_tmin_2010_9.tif
2021-12-27 15:37:35,430 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:35,431 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:35,432 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-269, started daemon 16616)>
2021-12-27 15:37:35,438 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:35,438 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:35,483 geoprocessing.align_and_resize_raster_stack(795) INFO 27 of 43 aligned: aligned_rph2o_1530cm_mean.tif
2021-12-27 15:37:35,560 geoprocessing.mask_raster(3204) WARNING No mask value was passed and target nodata is undefined, defaulting to 0 as the target mask value.
2021-12-27 15:37:35,569 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:35,570 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:35,571 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-270, started daemon 11400)>
2021-12-27 15:37:35,579 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:35,580 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:35,591 geoprocessing.align_and_resize_raster_stack(795) INFO 28 of 43 aligned: aligned_clip_prciwc2.1_2.5m_prec_2010_1.tif
2021-12-27 15:37:35,677 geoprocessing.mask_raster(3204) WARNING No mask value was passed and target nodata is undefined, defaulting to 0 as the target mask value.
2021-12-27 15:37:35,687 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:35,688 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:35,688 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-271, started daemon 12016)>
2021-12-27 15:37:35,698 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:35,699 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:35,709 geoprocessing.align_and_resize_raster_stack(795) INFO 29 of 43 aligned: aligned_clip_prciwc2.1_2.5m_prec_2010_2.tif
2021-12-27 15:37:35,802 geoprocessing.mask_raster(3204) WARNING No mask value was passed and target nodata is undefined, defaulting to 0 as the target mask value.
2021-12-27 15:37:35,813 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:35,813 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:35,814 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-272, started daemon 16712)>
2021-12-27 15:37:35,826 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:35,827 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:35,837 geoprocessing.align_and_resize_raster_stack(795) INFO 30 of 43 aligned: aligned_clip_prciwc2.1_2.5m_prec_2010_11.tif
2021-12-27 15:37:35,920 geoprocessing.mask_raster(3204) WARNING No mask value was passed and target nodata is undefined, defaulting to 0 as the target mask value.
2021-12-27 15:37:35,929 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:35,930 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:35,930 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-273, started daemon 4512)>
2021-12-27 15:37:35,940 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:35,941 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:35,952 geoprocessing.align_and_resize_raster_stack(795) INFO 31 of 43 aligned: aligned_clip_prciwc2.1_2.5m_prec_2010_12.tif
2021-12-27 15:37:36,044 geoprocessing.mask_raster(3204) WARNING No mask value was passed and target nodata is undefined, defaulting to 0 as the target mask value.
2021-12-27 15:37:36,053 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:36,054 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:36,054 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-274, started daemon 804)>
2021-12-27 15:37:36,063 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:36,064 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:36,076 geoprocessing.align_and_resize_raster_stack(795) INFO 32 of 43 aligned: aligned_clip_prciwc2.1_2.5m_prec_2010_3.tif
2021-12-27 15:37:36,163 geoprocessing.mask_raster(3204) WARNING No mask value was passed and target nodata is undefined, defaulting to 0 as the target mask value.
2021-12-27 15:37:36,172 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:36,174 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:36,174 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-275, started daemon 10808)>
2021-12-27 15:37:36,183 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:36,184 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:36,193 geoprocessing.align_and_resize_raster_stack(795) INFO 33 of 43 aligned: aligned_clip_prciwc2.1_2.5m_prec_2010_4.tif
2021-12-27 15:37:36,275 geoprocessing.mask_raster(3204) WARNING No mask value was passed and target nodata is undefined, defaulting to 0 as the target mask value.
2021-12-27 15:37:36,284 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:36,285 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:36,285 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-276, started daemon 17640)>
2021-12-27 15:37:36,295 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:36,296 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:36,307 geoprocessing.align_and_resize_raster_stack(795) INFO 34 of 43 aligned: aligned_clip_prciwc2.1_2.5m_prec_2010_5.tif
2021-12-27 15:37:36,393 geoprocessing.mask_raster(3204) WARNING No mask value was passed and target nodata is undefined, defaulting to 0 as the target mask value.
2021-12-27 15:37:36,401 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:36,402 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:36,402 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-277, started daemon 8476)>
2021-12-27 15:37:36,411 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:36,412 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:36,424 geoprocessing.align_and_resize_raster_stack(795) INFO 35 of 43 aligned: aligned_clip_prciwc2.1_2.5m_prec_2010_6.tif
2021-12-27 15:37:36,513 geoprocessing.mask_raster(3204) WARNING No mask value was passed and target nodata is undefined, defaulting to 0 as the target mask value.
2021-12-27 15:37:36,526 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:36,527 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:36,527 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-278, started daemon 4824)>
2021-12-27 15:37:36,537 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:36,538 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:36,549 geoprocessing.align_and_resize_raster_stack(795) INFO 36 of 43 aligned: aligned_clip_prciwc2.1_2.5m_prec_2010_7.tif
2021-12-27 15:37:36,629 geoprocessing.mask_raster(3204) WARNING No mask value was passed and target nodata is undefined, defaulting to 0 as the target mask value.
2021-12-27 15:37:36,638 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:36,639 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:36,639 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-279, started daemon 17996)>
2021-12-27 15:37:36,648 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:36,649 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:36,659 geoprocessing.align_and_resize_raster_stack(795) INFO 37 of 43 aligned: aligned_clip_prciwc2.1_2.5m_prec_2010_8.tif
2021-12-27 15:37:36,741 geoprocessing.mask_raster(3204) WARNING No mask value was passed and target nodata is undefined, defaulting to 0 as the target mask value.
2021-12-27 15:37:36,749 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:36,751 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:36,751 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-280, started daemon 16476)>
2021-12-27 15:37:36,760 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:36,761 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:36,771 geoprocessing.align_and_resize_raster_stack(795) INFO 38 of 43 aligned: aligned_clip_prciwc2.1_2.5m_prec_2010_9.tif
2021-12-27 15:37:36,858 geoprocessing.mask_raster(3204) WARNING No mask value was passed and target nodata is undefined, defaulting to 0 as the target mask value.
2021-12-27 15:37:36,867 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:36,868 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:36,868 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-281, started daemon 11100)>
2021-12-27 15:37:36,877 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:36,878 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:36,890 geoprocessing.align_and_resize_raster_stack(795) INFO 39 of 43 aligned: aligned_clip_prciwc2.1_2.5m_prec_2010_10.tif
2021-12-27 15:37:36,963 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:36,964 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:36,964 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-282, started daemon 10284)>
2021-12-27 15:37:36,969 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:36,969 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:36,976 geoprocessing.align_and_resize_raster_stack(795) INFO 40 of 43 aligned: aligned_legumeCopy.tif
2021-12-27 15:37:41,544 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:41,545 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:41,545 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-283, started daemon 8356)>
2021-12-27 15:37:41,551 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:41,551 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:41,596 geoprocessing.align_and_resize_raster_stack(795) INFO 41 of 43 aligned: aligned_rsand_1530cm_mean.tif
2021-12-27 15:37:45,961 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:45,962 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:45,962 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-284, started daemon 16260)>
2021-12-27 15:37:45,968 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:45,968 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:46,015 geoprocessing.align_and_resize_raster_stack(795) INFO 42 of 43 aligned: aligned_rsilt_1530cm_mean.tif
2021-12-27 15:37:46,105 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:46,106 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:46,106 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-285, started daemon 16464)>
2021-12-27 15:37:46,111 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:46,112 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:46,119 geoprocessing.align_and_resize_raster_stack(795) INFO 43 of 43 aligned: aligned_site_test3.tif
2021-12-27 15:37:46,120 geoprocessing.align_and_resize_raster_stack(797) INFO aligned all 43 rasters.
2021-12-27 15:37:46,349 geoprocessing.zonal_statistics(1184) DEBUG <osgeo.gdal.Dataset; proxy of <Swig Object of type 'GDALDatasetShadow *' at 0x000002075752CA50> >
2021-12-27 15:37:46,380 geoprocessing.align_and_resize_raster_stack(795) INFO 1 of 1 aligned: clipped_raster034k_wbo.tif
2021-12-27 15:37:46,380 geoprocessing.align_and_resize_raster_stack(797) INFO aligned all 1 rasters.
2021-12-27 15:37:46,388 geoprocessing.zonal_statistics(1244) INFO build a lookup of aggregate field value to FID
2021-12-27 15:37:46,399 geoprocessing.zonal_statistics(1251) INFO creating disjoint polygon set
2021-12-27 15:37:46,400 geoprocessing.calculate_disjoint_polygon_set(2183) INFO build shapely polygon list
2021-12-27 15:37:46,437 geoprocessing.calculate_disjoint_polygon_set(2204) INFO build shapely rtree index
2021-12-27 15:37:46,457 geoprocessing.calculate_disjoint_polygon_set(2219) INFO poly feature lookup 100.0% complete on animal_mgmt_copy.shp
2021-12-27 15:37:46,458 geoprocessing.calculate_disjoint_polygon_set(2221) INFO build poly intersection lookup
2021-12-27 15:37:46,601 geoprocessing.calculate_disjoint_polygon_set(2245) INFO poly intersection feature lookup 100.0% complete on animal_mgmt_copy.shp
2021-12-27 15:37:46,604 geoprocessing.calculate_disjoint_polygon_set(2279) INFO maximal subset build 100.0% complete on animal_mgmt_copy.shp
2021-12-27 15:37:46,617 geoprocessing.zonal_statistics(1274) INFO processing 8 disjoint polygon sets
2021-12-27 15:37:46,623 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 1 of 8 100.0% processed on animal_mgmt_copy.shp
2021-12-27 15:37:46,624 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 1 of 8 animal_mgmt_copy.shp
2021-12-27 15:37:46,637 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 1 of 8 animal_mgmt_copy.shp
2021-12-27 15:37:46,658 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 2 of 8 100.0% processed on animal_mgmt_copy.shp
2021-12-27 15:37:46,659 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 2 of 8 animal_mgmt_copy.shp
2021-12-27 15:37:46,668 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 2 of 8 animal_mgmt_copy.shp
2021-12-27 15:37:46,683 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 3 of 8 100.0% processed on animal_mgmt_copy.shp
2021-12-27 15:37:46,684 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 3 of 8 animal_mgmt_copy.shp
2021-12-27 15:37:46,692 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 3 of 8 animal_mgmt_copy.shp
2021-12-27 15:37:46,705 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 4 of 8 100.0% processed on animal_mgmt_copy.shp
2021-12-27 15:37:46,707 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 4 of 8 animal_mgmt_copy.shp
2021-12-27 15:37:46,713 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 4 of 8 animal_mgmt_copy.shp
2021-12-27 15:37:46,721 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 5 of 8 100.0% processed on animal_mgmt_copy.shp
2021-12-27 15:37:46,722 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 5 of 8 animal_mgmt_copy.shp
2021-12-27 15:37:46,728 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 5 of 8 animal_mgmt_copy.shp
2021-12-27 15:37:46,736 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 6 of 8 100.0% processed on animal_mgmt_copy.shp
2021-12-27 15:37:46,737 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 6 of 8 animal_mgmt_copy.shp
2021-12-27 15:37:46,742 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 6 of 8 animal_mgmt_copy.shp
2021-12-27 15:37:46,746 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 7 of 8 100.0% processed on animal_mgmt_copy.shp
2021-12-27 15:37:46,747 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 7 of 8 animal_mgmt_copy.shp
2021-12-27 15:37:46,752 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 7 of 8 animal_mgmt_copy.shp
2021-12-27 15:37:46,755 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 8 of 8 100.0% processed on animal_mgmt_copy.shp
2021-12-27 15:37:46,755 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 8 of 8 animal_mgmt_copy.shp
2021-12-27 15:37:46,760 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 8 of 8 animal_mgmt_copy.shp
2021-12-27 15:37:46,763 geoprocessing.zonal_statistics(1378) DEBUG unset_fids: 0 of 339 
2021-12-27 15:37:46,765 geoprocessing.zonal_statistics(1381) DEBUG gt [ 8.7734406e+01  4.1666668e-02  0.0000000e+00  5.2166668e+01
  0.0000000e+00 -4.1666668e-02] for ('C:/Users/boxxx032/Downloads/work_space\\aligned_inputs\\animal_spatial_index.tif', 1)
2021-12-27 15:37:46,765 geoprocessing.zonal_statistics(1460) DEBUG remaining unset_fids: 0 of 339 
2021-12-27 15:37:46,766 geoprocessing.zonal_statistics(1468) INFO all done processing polygon sets for animal_mgmt_copy.shp
2021-12-27 15:37:46,777 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:46,778 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:46,778 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-286, started daemon 10340)>
2021-12-27 15:37:46,788 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:46,790 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:47,110 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:47,110 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:47,110 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-287, started daemon 13892)>
2021-12-27 15:37:47,118 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:47,119 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:47,151 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:47,152 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:47,152 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-288, started daemon 17724)>
2021-12-27 15:37:47,160 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:47,160 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:47,179 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:47,180 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:47,180 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-289, started daemon 14244)>
2021-12-27 15:37:47,190 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:47,190 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:47,206 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:47,208 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:47,208 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-290, started daemon 10032)>
2021-12-27 15:37:47,214 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:47,215 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:47,231 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:47,232 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:47,232 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-291, started daemon 18320)>
2021-12-27 15:37:47,239 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:47,239 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:47,253 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:47,255 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:47,255 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-292, started daemon 17512)>
2021-12-27 15:37:47,263 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:47,264 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:47,281 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:47,281 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:47,282 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-293, started daemon 16808)>
2021-12-27 15:37:47,289 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:47,289 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:47,304 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:47,305 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:47,305 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-294, started daemon 17648)>
2021-12-27 15:37:47,312 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:47,314 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:47,332 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:47,333 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:47,333 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-295, started daemon 6756)>
2021-12-27 15:37:47,342 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:47,343 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:47,362 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:47,363 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:47,363 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-296, started daemon 576)>
2021-12-27 15:37:47,370 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:47,370 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:47,386 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:47,388 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:47,389 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-297, started daemon 18036)>
2021-12-27 15:37:47,395 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:47,396 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:47,411 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:47,411 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:47,412 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-298, started daemon 5052)>
2021-12-27 15:37:47,421 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:47,421 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:47,436 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:47,437 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:47,437 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-299, started daemon 14124)>
2021-12-27 15:37:47,445 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:47,446 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:47,461 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:47,463 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:47,463 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-300, started daemon 13680)>
2021-12-27 15:37:47,470 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:47,471 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:47,487 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:47,488 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:47,489 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-301, started daemon 17044)>
2021-12-27 15:37:47,496 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:47,497 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:47,516 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:47,518 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:47,518 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-302, started daemon 16192)>
2021-12-27 15:37:47,526 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:47,527 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:47,548 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:47,549 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:47,549 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-303, started daemon 10944)>
2021-12-27 15:37:47,558 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:47,559 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:47,576 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:47,578 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:47,578 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-304, started daemon 16032)>
2021-12-27 15:37:47,587 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:47,587 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:47,602 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:47,604 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:47,606 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-305, started daemon 15308)>
2021-12-27 15:37:47,613 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:47,614 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:47,630 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:47,631 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:47,631 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-306, started daemon 17708)>
2021-12-27 15:37:47,640 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:47,641 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:47,655 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:47,656 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:47,656 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-307, started daemon 3140)>
2021-12-27 15:37:47,663 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:47,664 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:47,679 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:47,681 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:47,681 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-308, started daemon 1740)>
2021-12-27 15:37:47,688 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:47,689 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:47,706 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:47,707 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:47,707 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-309, started daemon 4884)>
2021-12-27 15:37:47,716 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:47,716 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:47,732 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:47,733 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:47,733 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-310, started daemon 15656)>
2021-12-27 15:37:47,740 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:47,741 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:47,754 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:47,756 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:47,756 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-311, started daemon 17768)>
2021-12-27 15:37:47,765 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:47,765 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:47,779 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:47,781 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:47,781 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-312, started daemon 11520)>
2021-12-27 15:37:47,788 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:47,789 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:47,805 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:47,806 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:47,806 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-313, started daemon 12604)>
2021-12-27 15:37:47,813 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:47,814 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:47,829 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:47,830 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:47,830 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-314, started daemon 16012)>
2021-12-27 15:37:47,838 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:47,838 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:47,852 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:47,854 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:47,854 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-315, started daemon 17692)>
2021-12-27 15:37:47,862 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:47,863 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:47,880 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:47,882 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:47,882 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-316, started daemon 6848)>
2021-12-27 15:37:47,892 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:47,893 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:47,908 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:47,909 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:47,909 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-317, started daemon 9664)>
2021-12-27 15:37:47,917 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:47,918 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:47,933 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:47,935 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:47,935 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-318, started daemon 13448)>
2021-12-27 15:37:47,943 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:47,943 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:47,959 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:47,960 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:47,960 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-319, started daemon 17728)>
2021-12-27 15:37:47,968 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:47,969 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:47,985 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:47,986 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:47,986 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-320, started daemon 17064)>
2021-12-27 15:37:47,993 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:47,994 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:48,010 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:48,012 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:48,012 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-321, started daemon 14908)>
2021-12-27 15:37:48,021 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:48,021 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:48,037 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:48,038 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:48,038 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-322, started daemon 17252)>
2021-12-27 15:37:48,049 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:48,050 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:48,067 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:48,068 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:48,068 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-323, started daemon 13672)>
2021-12-27 15:37:48,078 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:48,078 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:48,092 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:48,093 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:48,093 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-324, started daemon 11516)>
2021-12-27 15:37:48,100 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:48,101 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:48,116 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:48,117 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:48,117 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-325, started daemon 15932)>
2021-12-27 15:37:48,126 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:48,126 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:48,142 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:48,143 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:48,143 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-326, started daemon 9376)>
2021-12-27 15:37:48,152 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:48,152 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:48,167 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:48,168 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:48,168 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-327, started daemon 11976)>
2021-12-27 15:37:48,175 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:48,176 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:48,190 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:48,191 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:48,191 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-328, started daemon 13620)>
2021-12-27 15:37:48,199 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:48,200 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:48,215 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:48,216 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:48,216 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-329, started daemon 12844)>
2021-12-27 15:37:48,224 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:48,224 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:48,240 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:48,241 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:48,241 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-330, started daemon 4892)>
2021-12-27 15:37:48,253 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:48,253 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:48,271 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:48,272 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:48,272 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-331, started daemon 7004)>
2021-12-27 15:37:48,280 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:48,281 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:48,298 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:48,299 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:48,299 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-332, started daemon 10412)>
2021-12-27 15:37:48,307 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:48,308 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:48,324 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:48,325 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:48,326 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-333, started daemon 15644)>
2021-12-27 15:37:48,334 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:48,334 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:48,350 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:48,352 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:48,352 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-334, started daemon 3328)>
2021-12-27 15:37:48,361 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:48,362 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:48,378 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:48,379 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:48,379 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-335, started daemon 10112)>
2021-12-27 15:37:48,389 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:48,390 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:48,405 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:48,406 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:48,406 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-336, started daemon 14044)>
2021-12-27 15:37:48,415 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:48,417 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:48,435 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:48,436 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:48,437 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-337, started daemon 15728)>
2021-12-27 15:37:48,445 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:48,445 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:48,460 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:48,462 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:48,462 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-338, started daemon 9136)>
2021-12-27 15:37:48,471 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:48,472 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:48,487 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:48,488 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:48,489 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-339, started daemon 16968)>
2021-12-27 15:37:48,497 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:48,497 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:48,514 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:48,515 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:48,515 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-340, started daemon 18040)>
2021-12-27 15:37:48,523 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:48,523 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:48,539 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:48,541 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:48,541 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-341, started daemon 5220)>
2021-12-27 15:37:48,549 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:48,550 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:48,565 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:48,566 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:48,566 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-342, started daemon 17616)>
2021-12-27 15:37:48,575 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:48,577 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:48,594 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:48,595 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:48,595 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-343, started daemon 12516)>
2021-12-27 15:37:48,603 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:48,604 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:48,619 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:48,620 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:48,620 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-344, started daemon 18160)>
2021-12-27 15:37:48,627 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:48,628 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:48,643 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:48,644 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:48,644 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-345, started daemon 15904)>
2021-12-27 15:37:48,652 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:48,652 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:48,667 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:48,668 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:48,668 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-346, started daemon 6928)>
2021-12-27 15:37:48,675 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:48,675 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:48,689 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:48,690 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:48,690 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-347, started daemon 3336)>
2021-12-27 15:37:48,698 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:48,698 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:48,712 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:48,713 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:48,713 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-348, started daemon 16692)>
2021-12-27 15:37:48,721 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:48,721 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:48,737 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:48,738 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:48,739 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-349, started daemon 348)>
2021-12-27 15:37:48,749 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:48,749 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:48,764 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:48,765 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:48,765 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-350, started daemon 14928)>
2021-12-27 15:37:48,771 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:48,772 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:48,785 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:48,787 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:48,787 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-351, started daemon 14420)>
2021-12-27 15:37:48,796 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:48,797 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:48,811 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:48,813 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:48,813 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-352, started daemon 17040)>
2021-12-27 15:37:48,820 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:48,821 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:48,859 forage.execute(1010) INFO Calculating field capacity and wilting point
2021-12-27 15:37:48,870 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:48,872 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:48,872 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-353, started daemon 11080)>
2021-12-27 15:37:48,880 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:48,880 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:48,910 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:48,911 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:48,911 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-354, started daemon 8912)>
2021-12-27 15:37:48,918 warnings._showwarnmsg(110) WARNING C:\RPM_0.2.1_x64\RPM-x86\rangeland_production\forage.py:1748: RuntimeWarning: overflow encountered in multiply

2021-12-27 15:37:48,920 warnings._showwarnmsg(110) WARNING C:\RPM_0.2.1_x64\RPM-x86\rangeland_production\forage.py:1748: RuntimeWarning: divide by zero encountered in true_divide

2021-12-27 15:37:48,933 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:48,934 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:48,972 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:48,974 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:48,974 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-355, started daemon 5844)>
2021-12-27 15:37:48,983 warnings._showwarnmsg(110) WARNING C:\RPM_0.2.1_x64\RPM-x86\rangeland_production\forage.py:1809: RuntimeWarning: overflow encountered in add

2021-12-27 15:37:48,998 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:48,998 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:49,032 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:49,033 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:49,033 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-356, started daemon 16556)>
2021-12-27 15:37:49,053 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:49,054 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:49,069 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:49,070 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:49,071 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-357, started daemon 3260)>
2021-12-27 15:37:49,079 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:49,079 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:49,112 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:49,115 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:49,115 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-358, started daemon 2756)>
2021-12-27 15:37:49,136 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:49,137 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:49,171 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:49,172 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:49,172 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-359, started daemon 8312)>
2021-12-27 15:37:49,191 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:49,192 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:49,209 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:49,211 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:49,211 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-360, started daemon 15448)>
2021-12-27 15:37:49,216 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:49,217 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:49,250 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:49,251 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:49,251 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-361, started daemon 2256)>
2021-12-27 15:37:49,272 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:49,273 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:49,311 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:49,313 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:49,313 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-362, started daemon 9552)>
2021-12-27 15:37:49,332 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:49,333 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:49,349 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:49,350 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:49,351 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-363, started daemon 17696)>
2021-12-27 15:37:49,358 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:49,358 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:49,389 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:49,390 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:49,390 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-364, started daemon 17660)>
2021-12-27 15:37:49,411 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:49,412 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:49,452 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:49,455 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:49,455 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-365, started daemon 15780)>
2021-12-27 15:37:49,477 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:49,478 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:49,494 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:49,495 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:49,496 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-366, started daemon 12348)>
2021-12-27 15:37:49,501 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:49,502 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:49,532 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:49,533 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:49,534 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-367, started daemon 9404)>
2021-12-27 15:37:49,555 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:49,555 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:49,590 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:49,591 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:49,591 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-368, started daemon 2288)>
2021-12-27 15:37:49,614 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:49,616 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:49,633 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:49,635 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:49,635 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-369, started daemon 16060)>
2021-12-27 15:37:49,641 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:49,642 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:49,680 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:49,681 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:49,682 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-370, started daemon 18332)>
2021-12-27 15:37:49,704 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:49,705 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:49,737 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:49,738 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:49,738 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-371, started daemon 17852)>
2021-12-27 15:37:49,759 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:49,760 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:49,777 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:49,778 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:49,779 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-372, started daemon 16520)>
2021-12-27 15:37:49,784 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:49,784 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:49,816 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:49,818 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:49,819 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-373, started daemon 11888)>
2021-12-27 15:37:49,839 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:49,840 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:49,873 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:49,874 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:49,874 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-374, started daemon 18244)>
2021-12-27 15:37:49,895 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:49,895 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:49,912 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:49,913 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:49,914 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-375, started daemon 17800)>
2021-12-27 15:37:49,921 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:49,921 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:49,950 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:49,951 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:49,951 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-376, started daemon 18140)>
2021-12-27 15:37:49,971 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:49,972 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:50,009 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:50,011 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:50,011 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-377, started daemon 12468)>
2021-12-27 15:37:50,032 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:50,033 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:50,049 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:50,050 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:50,051 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-378, started daemon 9844)>
2021-12-27 15:37:50,055 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:50,056 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:50,086 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:50,087 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:50,087 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-379, started daemon 15492)>
2021-12-27 15:37:50,109 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:50,109 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:50,147 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:50,149 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:50,150 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-380, started daemon 15736)>
2021-12-27 15:37:50,171 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:50,172 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:50,186 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:50,187 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:50,188 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-381, started daemon 1960)>
2021-12-27 15:37:50,194 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:50,195 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:50,211 forage.execute(1018) INFO Calculating persistent parameters
2021-12-27 15:37:50,223 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:50,224 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:50,224 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-382, started daemon 13340)>
2021-12-27 15:37:50,233 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:50,233 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:50,249 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:50,250 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:50,250 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-383, started daemon 14976)>
2021-12-27 15:37:50,257 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:50,259 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:50,275 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:50,276 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:50,276 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-384, started daemon 12316)>
2021-12-27 15:37:50,283 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:50,284 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:50,299 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:50,301 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:50,301 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-385, started daemon 6032)>
2021-12-27 15:37:50,310 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:50,310 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:50,326 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:50,327 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:50,327 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-386, started daemon 10152)>
2021-12-27 15:37:50,335 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:50,335 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:50,353 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:50,355 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:50,355 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-387, started daemon 17700)>
2021-12-27 15:37:50,366 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:50,367 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:50,382 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:50,382 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:50,382 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-388, started daemon 1280)>
2021-12-27 15:37:50,390 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:50,391 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:50,406 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:50,407 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:50,407 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-389, started daemon 12172)>
2021-12-27 15:37:50,417 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:50,418 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:50,434 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:50,435 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:50,435 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-390, started daemon 17784)>
2021-12-27 15:37:50,443 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:50,444 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:50,459 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:50,461 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:50,461 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-391, started daemon 1520)>
2021-12-27 15:37:50,470 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:50,471 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:50,488 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:50,490 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:50,490 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-392, started daemon 10800)>
2021-12-27 15:37:50,499 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:50,499 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:50,519 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:50,523 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:50,523 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-393, started daemon 17896)>
2021-12-27 15:37:50,527 warnings._showwarnmsg(110) WARNING C:\RPM_0.2.1_x64\RPM-x86\rangeland_production\forage.py:2050: RuntimeWarning: invalid value encountered in subtract

2021-12-27 15:37:50,535 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:50,536 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:50,559 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:50,560 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:50,560 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-394, started daemon 14576)>
2021-12-27 15:37:50,575 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:50,576 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:50,595 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:50,596 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:50,596 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-395, started daemon 15576)>
2021-12-27 15:37:50,609 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:50,609 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:50,628 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:50,629 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:50,629 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-396, started daemon 17888)>
2021-12-27 15:37:50,641 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:50,642 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:50,660 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:50,661 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:50,661 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-397, started daemon 4072)>
2021-12-27 15:37:50,674 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:50,674 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:50,693 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:50,694 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:50,694 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-398, started daemon 500)>
2021-12-27 15:37:50,706 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:50,706 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:50,723 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:50,724 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:50,724 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-399, started daemon 6940)>
2021-12-27 15:37:50,736 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:50,737 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:50,747 forage.execute(1024) INFO Calculating required ratios for structural decomposition
2021-12-27 15:37:50,758 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:50,760 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:50,760 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-400, started daemon 17572)>
2021-12-27 15:37:50,769 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:50,769 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:50,783 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:50,784 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:50,784 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-401, started daemon 11592)>
2021-12-27 15:37:50,793 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:50,793 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:50,808 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:50,809 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:50,809 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-402, started daemon 6124)>
2021-12-27 15:37:50,817 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:50,818 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:50,832 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:50,833 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:50,833 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-403, started daemon 18304)>
2021-12-27 15:37:50,842 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:50,843 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:50,867 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:50,868 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:50,868 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-404, started daemon 13912)>
2021-12-27 15:37:50,875 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:50,876 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:50,893 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:50,894 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:50,894 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-405, started daemon 6784)>
2021-12-27 15:37:50,901 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:50,902 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:50,916 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:50,918 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:50,918 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-406, started daemon 16928)>
2021-12-27 15:37:50,926 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:50,927 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:50,942 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:50,942 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:50,943 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-407, started daemon 16600)>
2021-12-27 15:37:50,951 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:50,952 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:50,966 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:50,967 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:50,968 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-408, started daemon 10924)>
2021-12-27 15:37:50,976 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:50,977 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:50,991 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:50,992 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:50,992 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-409, started daemon 16540)>
2021-12-27 15:37:51,000 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:51,000 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:51,016 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:51,018 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:51,018 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-410, started daemon 8200)>
2021-12-27 15:37:51,026 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:51,027 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:51,053 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:51,054 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:51,054 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-411, started daemon 18132)>
2021-12-27 15:37:51,061 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:51,062 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:51,078 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:51,079 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:51,079 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-412, started daemon 16872)>
2021-12-27 15:37:51,087 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:51,088 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:51,103 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:51,104 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:51,104 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-413, started daemon 16960)>
2021-12-27 15:37:51,112 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:51,113 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:51,127 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:51,129 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:51,129 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-414, started daemon 6232)>
2021-12-27 15:37:51,139 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:51,139 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:51,156 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:51,157 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:51,157 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-415, started daemon 15692)>
2021-12-27 15:37:51,167 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:51,168 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:51,184 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:51,185 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:51,185 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-416, started daemon 8060)>
2021-12-27 15:37:51,194 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:51,194 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:51,211 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:51,213 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:51,213 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-417, started daemon 7456)>
2021-12-27 15:37:51,224 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:51,224 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:51,241 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:51,242 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:51,243 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-418, started daemon 18368)>
2021-12-27 15:37:51,252 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:51,253 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:51,270 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:51,272 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:51,272 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-419, started daemon 8212)>
2021-12-27 15:37:51,281 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:51,281 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:51,299 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:51,300 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:51,300 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-420, started daemon 13012)>
2021-12-27 15:37:51,309 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:51,309 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:51,326 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:51,327 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:51,327 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-421, started daemon 17192)>
2021-12-27 15:37:51,334 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:51,334 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:51,356 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:51,357 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:51,357 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-422, started daemon 15784)>
2021-12-27 15:37:51,373 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:51,373 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:51,409 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:51,410 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:51,410 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-423, started daemon 12804)>
2021-12-27 15:37:51,440 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:51,441 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:51,457 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:51,458 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:51,458 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-424, started daemon 17664)>
2021-12-27 15:37:51,466 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:51,467 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:51,483 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:51,484 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:51,484 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-425, started daemon 15488)>
2021-12-27 15:37:51,491 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:51,492 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:51,514 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:51,515 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:51,515 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-426, started daemon 7204)>
2021-12-27 15:37:51,530 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:51,531 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:51,570 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:51,572 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:51,572 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-427, started daemon 15556)>
2021-12-27 15:37:51,602 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:51,604 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:51,620 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:51,621 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:51,621 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-428, started daemon 4508)>
2021-12-27 15:37:51,629 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:51,629 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:51,645 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:51,646 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:51,646 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-429, started daemon 18024)>
2021-12-27 15:37:51,653 geoprocessing.raster_calculator(477) INFO 100.0% complete
2021-12-27 15:37:51,654 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2021-12-27 15:37:51,722 geoprocessing.raster_calculator(391) INFO starting stats_worker
2021-12-27 15:37:51,723 threading.run(870) DEBUG stats worker PID: 10604
2021-12-27 15:37:51,723 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-430, started daemon 5428)>
2021-12-27 15:37:51,739 geoprocessing.raster_calculator(507) INFO Waiting for raster stats worker result.
2021-12-27 15:37:51,739 threading.run(870) WARNING No valid pixels were received, sending None.
2021-12-27 15:37:51,740 model._logged_target(1641) ERROR Exception while executing <function execute at 0x0000020758F4EAF8>
Traceback (most recent call last):
  File "src\rangeland_production\ui\model.py", line 1638, in _logged_target
  File "src\rangeland_production\forage.py", line 1070, in execute
  File "src\rangeland_production\forage.py", line 2628, in _yearly_tasks
  File "src\rangeland_production\forage.py", line 1345, in raster_list_sum
  File "env\lib\site-packages\pygeoprocessing\geoprocessing.py", line 438, in raster_calculator
  File "src\rangeland_production\forage.py", line 1320, in raster_sum_op
  File "<__array_function__ internals>", line 6, in isclose
  File "env\lib\site-packages\numpy\core\numeric.py", line 2288, in isclose
TypeError: ufunc 'isfinite' not supported for the input types, and the inputs could not be safely coerced to any supported types according to the casting rule ''safe''
2021-12-27 15:37:51,741 model._logged_target(1644) INFO Execution finished
2021-12-27 15:37:51,742 utils.prepare_workspace(129) INFO Elapsed time: 31.62s