2022-01-03 20:32:01,411 utils.prepare_workspace(123) INFO Writing log messages to C:/Users/boxxx032/Downloads/rmp_work_space\Rangeland-Production-Model-log-2022-01-03--20_32_01.txt
2022-01-03 20:32:01,411 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/s_rbulk_1530cm_mean.tif
clay_proportion_path                 C:/Users/boxxx032/Documents/RPM data/soil/s_rclay_1530cm_mean.tif
initial_conditions_dir               
management_threshold                 100
max_temp_dir                         C:/Users/boxxx032/Documents/RPM data/output_tmax/tmax_2010
min_temp_dir                         C:/Users/boxxx032/Documents/RPM data/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/s_rph2o_1530cm_mean.tif
precip_dir                           C:/Users/boxxx032/Documents/RPM data/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/s_rsand_1530cm_mean.tif
save_sv_rasters                      True
silt_proportion_path                 C:/Users/boxxx032/Documents/RPM data/soil/s_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/rmp_work_space

2022-01-03 20:32:01,418 forage.execute(617) INFO model execute: {'workspace_dir': 'C:/Users/boxxx032/Downloads/rmp_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/s_rclay_1530cm_mean.tif', 'silt_proportion_path': 'C:/Users/boxxx032/Documents/RPM data/soil/s_rsilt_1530cm_mean.tif', 'sand_proportion_path': 'C:/Users/boxxx032/Documents/RPM data/soil/s_rsand_1530cm_mean.tif', 'bulk_density_path': 'C:/Users/boxxx032/Documents/RPM data/soil/s_rbulk_1530cm_mean.tif', 'ph_path': 'C:/Users/boxxx032/Documents/RPM data/soil/s_rph2o_1530cm_mean.tif', 'precip_dir': 'C:/Users/boxxx032/Documents/RPM data/output_prec/prec_2010', 'min_temp_dir': 'C:/Users/boxxx032/Documents/RPM data/output_tmin/tmin_2010', 'max_temp_dir': 'C:/Users/boxxx032/Documents/RPM data/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': True, 'n_workers': '-1'}
2022-01-03 20:32:01,565 forage.execute(840) INFO pixel size of aligned inputs: (0.041666666666666664, -0.04166666666666665)
2022-01-03 20:32:05,021 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:05,022 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:05,022 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-416, started daemon 22352)>
2022-01-03 20:32:05,030 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:05,031 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:05,075 geoprocessing.align_and_resize_raster_stack(795) INFO 1 of 43 aligned: aligned_s_rbulk_1530cm_mean.tif
2022-01-03 20:32:08,738 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:08,739 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:08,739 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-417, started daemon 3504)>
2022-01-03 20:32:08,745 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:08,747 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:08,786 geoprocessing.align_and_resize_raster_stack(795) INFO 2 of 43 aligned: aligned_s_rclay_1530cm_mean.tif
2022-01-03 20:32:08,867 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:08,868 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:08,868 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-418, started daemon 22024)>
2022-01-03 20:32:08,876 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:08,877 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:08,890 geoprocessing.align_and_resize_raster_stack(795) INFO 3 of 43 aligned: aligned_s_clip_min_wc2.1_2.5m_tmax_2010_1.tif
2022-01-03 20:32:08,970 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:08,971 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:08,971 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-419, started daemon 20040)>
2022-01-03 20:32:08,977 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:08,978 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:08,990 geoprocessing.align_and_resize_raster_stack(795) INFO 4 of 43 aligned: aligned_s_clip_min_wc2.1_2.5m_tmax_2010_10.tif
2022-01-03 20:32:09,074 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:09,075 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:09,075 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-420, started daemon 21368)>
2022-01-03 20:32:09,082 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:09,082 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:09,093 geoprocessing.align_and_resize_raster_stack(795) INFO 5 of 43 aligned: aligned_s_clip_min_wc2.1_2.5m_tmax_2010_11.tif
2022-01-03 20:32:09,171 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:09,172 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:09,172 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-421, started daemon 20892)>
2022-01-03 20:32:09,179 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:09,179 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:09,189 geoprocessing.align_and_resize_raster_stack(795) INFO 6 of 43 aligned: aligned_s_clip_min_wc2.1_2.5m_tmax_2010_12.tif
2022-01-03 20:32:09,279 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:09,281 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:09,281 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-422, started daemon 1936)>
2022-01-03 20:32:09,287 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:09,288 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:09,300 geoprocessing.align_and_resize_raster_stack(795) INFO 7 of 43 aligned: aligned_s_clip_min_wc2.1_2.5m_tmax_2010_2.tif
2022-01-03 20:32:09,383 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:09,383 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:09,383 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-423, started daemon 3140)>
2022-01-03 20:32:09,391 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:09,392 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:09,405 geoprocessing.align_and_resize_raster_stack(795) INFO 8 of 43 aligned: aligned_s_clip_min_wc2.1_2.5m_tmax_2010_3.tif
2022-01-03 20:32:09,485 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:09,487 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:09,487 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-424, started daemon 13448)>
2022-01-03 20:32:09,493 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:09,493 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:09,504 geoprocessing.align_and_resize_raster_stack(795) INFO 9 of 43 aligned: aligned_s_clip_min_wc2.1_2.5m_tmax_2010_4.tif
2022-01-03 20:32:09,584 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:09,585 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:09,585 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-425, started daemon 19868)>
2022-01-03 20:32:09,592 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:09,592 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:09,602 geoprocessing.align_and_resize_raster_stack(795) INFO 10 of 43 aligned: aligned_s_clip_min_wc2.1_2.5m_tmax_2010_5.tif
2022-01-03 20:32:09,683 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:09,685 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:09,685 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-426, started daemon 18216)>
2022-01-03 20:32:09,692 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:09,693 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:09,705 geoprocessing.align_and_resize_raster_stack(795) INFO 11 of 43 aligned: aligned_s_clip_min_wc2.1_2.5m_tmax_2010_6.tif
2022-01-03 20:32:09,789 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:09,790 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:09,790 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-427, started daemon 18792)>
2022-01-03 20:32:09,797 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:09,798 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:09,808 geoprocessing.align_and_resize_raster_stack(795) INFO 12 of 43 aligned: aligned_s_clip_min_wc2.1_2.5m_tmax_2010_7.tif
2022-01-03 20:32:09,885 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:09,886 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:09,886 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-428, started daemon 17720)>
2022-01-03 20:32:09,893 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:09,893 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:09,904 geoprocessing.align_and_resize_raster_stack(795) INFO 13 of 43 aligned: aligned_s_clip_min_wc2.1_2.5m_tmax_2010_8.tif
2022-01-03 20:32:09,991 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:09,992 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:09,992 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-429, started daemon 10152)>
2022-01-03 20:32:09,998 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:09,998 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:10,009 geoprocessing.align_and_resize_raster_stack(795) INFO 14 of 43 aligned: aligned_s_clip_min_wc2.1_2.5m_tmax_2010_9.tif
2022-01-03 20:32:10,370 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:10,371 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:10,371 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-430, started daemon 18832)>
2022-01-03 20:32:10,377 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:10,378 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:10,391 geoprocessing.align_and_resize_raster_stack(795) INFO 15 of 43 aligned: aligned_s_clip_min_wc2.1_2.5m_tmin_2010_1.tif
2022-01-03 20:32:10,489 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:10,490 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:10,490 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-431, started daemon 16588)>
2022-01-03 20:32:10,496 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:10,497 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:10,507 geoprocessing.align_and_resize_raster_stack(795) INFO 16 of 43 aligned: aligned_s_clip_min_wc2.1_2.5m_tmin_2010_10.tif
2022-01-03 20:32:10,586 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:10,587 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:10,587 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-432, started daemon 17012)>
2022-01-03 20:32:10,593 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:10,593 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:10,604 geoprocessing.align_and_resize_raster_stack(795) INFO 17 of 43 aligned: aligned_s_clip_min_wc2.1_2.5m_tmin_2010_11.tif
2022-01-03 20:32:10,689 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:10,691 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:10,691 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-433, started daemon 14100)>
2022-01-03 20:32:10,698 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:10,698 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:10,711 geoprocessing.align_and_resize_raster_stack(795) INFO 18 of 43 aligned: aligned_s_clip_min_wc2.1_2.5m_tmin_2010_12.tif
2022-01-03 20:32:10,797 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:10,798 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:10,799 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-434, started daemon 17008)>
2022-01-03 20:32:10,807 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:10,808 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:10,818 geoprocessing.align_and_resize_raster_stack(795) INFO 19 of 43 aligned: aligned_s_clip_min_wc2.1_2.5m_tmin_2010_2.tif
2022-01-03 20:32:10,899 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:10,900 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:10,901 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-435, started daemon 17312)>
2022-01-03 20:32:10,907 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:10,908 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:10,922 geoprocessing.align_and_resize_raster_stack(795) INFO 20 of 43 aligned: aligned_s_clip_min_wc2.1_2.5m_tmin_2010_3.tif
2022-01-03 20:32:11,005 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:11,006 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:11,006 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-436, started daemon 21768)>
2022-01-03 20:32:11,012 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:11,012 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:11,024 geoprocessing.align_and_resize_raster_stack(795) INFO 21 of 43 aligned: aligned_s_clip_min_wc2.1_2.5m_tmin_2010_4.tif
2022-01-03 20:32:11,105 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:11,106 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:11,106 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-437, started daemon 18356)>
2022-01-03 20:32:11,114 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:11,114 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:11,127 geoprocessing.align_and_resize_raster_stack(795) INFO 22 of 43 aligned: aligned_s_clip_min_wc2.1_2.5m_tmin_2010_5.tif
2022-01-03 20:32:11,218 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:11,219 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:11,219 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-438, started daemon 21724)>
2022-01-03 20:32:11,227 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:11,228 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:11,240 geoprocessing.align_and_resize_raster_stack(795) INFO 23 of 43 aligned: aligned_s_clip_min_wc2.1_2.5m_tmin_2010_6.tif
2022-01-03 20:32:11,332 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:11,333 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:11,333 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-439, started daemon 13236)>
2022-01-03 20:32:11,342 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:11,343 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:11,353 geoprocessing.align_and_resize_raster_stack(795) INFO 24 of 43 aligned: aligned_s_clip_min_wc2.1_2.5m_tmin_2010_7.tif
2022-01-03 20:32:11,447 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:11,448 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:11,449 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-440, started daemon 8692)>
2022-01-03 20:32:11,457 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:11,457 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:11,469 geoprocessing.align_and_resize_raster_stack(795) INFO 25 of 43 aligned: aligned_s_clip_min_wc2.1_2.5m_tmin_2010_8.tif
2022-01-03 20:32:11,558 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:11,559 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:11,559 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-441, started daemon 1648)>
2022-01-03 20:32:11,566 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:11,567 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:11,579 geoprocessing.align_and_resize_raster_stack(795) INFO 26 of 43 aligned: aligned_s_clip_min_wc2.1_2.5m_tmin_2010_9.tif
2022-01-03 20:32:14,686 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:14,686 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:14,687 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-442, started daemon 21192)>
2022-01-03 20:32:14,694 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:14,694 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:14,758 geoprocessing.align_and_resize_raster_stack(795) INFO 27 of 43 aligned: aligned_s_rph2o_1530cm_mean.tif
2022-01-03 20:32:14,851 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:14,852 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:14,852 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-443, started daemon 15800)>
2022-01-03 20:32:14,861 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:14,862 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:14,872 geoprocessing.align_and_resize_raster_stack(795) INFO 28 of 43 aligned: aligned_s_clip_prciwc2.1_2.5m_prec_2010_1.tif
2022-01-03 20:32:14,952 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:14,953 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:14,953 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-444, started daemon 5520)>
2022-01-03 20:32:14,960 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:14,960 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:14,970 geoprocessing.align_and_resize_raster_stack(795) INFO 29 of 43 aligned: aligned_s_clip_prciwc2.1_2.5m_prec_2010_2.tif
2022-01-03 20:32:15,058 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:15,060 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:15,060 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-445, started daemon 18700)>
2022-01-03 20:32:15,068 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:15,068 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:15,080 geoprocessing.align_and_resize_raster_stack(795) INFO 30 of 43 aligned: aligned_s_clip_prciwc2.1_2.5m_prec_2010_11.tif
2022-01-03 20:32:15,161 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:15,162 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:15,162 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-446, started daemon 17588)>
2022-01-03 20:32:15,168 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:15,169 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:15,182 geoprocessing.align_and_resize_raster_stack(795) INFO 31 of 43 aligned: aligned_s_clip_prciwc2.1_2.5m_prec_2010_12.tif
2022-01-03 20:32:15,268 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:15,270 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:15,270 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-447, started daemon 20260)>
2022-01-03 20:32:15,277 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:15,277 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:15,289 geoprocessing.align_and_resize_raster_stack(795) INFO 32 of 43 aligned: aligned_s_clip_prciwc2.1_2.5m_prec_2010_3.tif
2022-01-03 20:32:15,648 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:15,649 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:15,649 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-448, started daemon 18084)>
2022-01-03 20:32:15,656 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:15,656 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:15,668 geoprocessing.align_and_resize_raster_stack(795) INFO 33 of 43 aligned: aligned_s_clip_prciwc2.1_2.5m_prec_2010_4.tif
2022-01-03 20:32:15,752 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:15,754 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:15,754 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-449, started daemon 17028)>
2022-01-03 20:32:15,762 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:15,762 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:15,774 geoprocessing.align_and_resize_raster_stack(795) INFO 34 of 43 aligned: aligned_s_clip_prciwc2.1_2.5m_prec_2010_5.tif
2022-01-03 20:32:15,856 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:15,859 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:15,859 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-450, started daemon 19724)>
2022-01-03 20:32:15,868 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:15,869 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:15,880 geoprocessing.align_and_resize_raster_stack(795) INFO 35 of 43 aligned: aligned_s_clip_prciwc2.1_2.5m_prec_2010_6.tif
2022-01-03 20:32:15,959 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:15,960 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:15,960 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-451, started daemon 21740)>
2022-01-03 20:32:15,966 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:15,967 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:15,977 geoprocessing.align_and_resize_raster_stack(795) INFO 36 of 43 aligned: aligned_s_clip_prciwc2.1_2.5m_prec_2010_7.tif
2022-01-03 20:32:16,063 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:16,064 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:16,065 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-452, started daemon 2592)>
2022-01-03 20:32:16,072 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:16,073 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:16,089 geoprocessing.align_and_resize_raster_stack(795) INFO 37 of 43 aligned: aligned_s_clip_prciwc2.1_2.5m_prec_2010_8.tif
2022-01-03 20:32:16,190 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:16,191 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:16,192 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-453, started daemon 16464)>
2022-01-03 20:32:16,199 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:16,200 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:16,215 geoprocessing.align_and_resize_raster_stack(795) INFO 38 of 43 aligned: aligned_s_clip_prciwc2.1_2.5m_prec_2010_9.tif
2022-01-03 20:32:16,323 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:16,325 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:16,326 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-454, started daemon 2460)>
2022-01-03 20:32:16,340 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:16,341 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:16,356 geoprocessing.align_and_resize_raster_stack(795) INFO 39 of 43 aligned: aligned_s_clip_prciwc2.1_2.5m_prec_2010_10.tif
2022-01-03 20:32:16,432 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:16,433 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:16,433 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-455, started daemon 10980)>
2022-01-03 20:32:16,438 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:16,438 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:16,444 geoprocessing.align_and_resize_raster_stack(795) INFO 40 of 43 aligned: aligned_legumeCopy.tif
2022-01-03 20:32:20,224 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:20,225 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:20,225 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-456, started daemon 21036)>
2022-01-03 20:32:20,234 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:20,235 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:20,281 geoprocessing.align_and_resize_raster_stack(795) INFO 41 of 43 aligned: aligned_s_rsand_1530cm_mean.tif
2022-01-03 20:32:24,316 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:24,317 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:24,317 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-457, started daemon 9096)>
2022-01-03 20:32:24,324 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:24,324 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:24,365 geoprocessing.align_and_resize_raster_stack(795) INFO 42 of 43 aligned: aligned_s_rsilt_1530cm_mean.tif
2022-01-03 20:32:24,446 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:24,448 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:24,449 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-458, started daemon 12880)>
2022-01-03 20:32:24,454 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:24,455 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:24,462 geoprocessing.align_and_resize_raster_stack(795) INFO 43 of 43 aligned: aligned_site_test3.tif
2022-01-03 20:32:24,462 geoprocessing.align_and_resize_raster_stack(797) INFO aligned all 43 rasters.
2022-01-03 20:32:24,680 geoprocessing.zonal_statistics(1184) DEBUG <osgeo.gdal.Dataset; proxy of <Swig Object of type 'GDALDatasetShadow *' at 0x000001C0BD4C2060> >
2022-01-03 20:32:24,708 geoprocessing.align_and_resize_raster_stack(795) INFO 1 of 1 aligned: clipped_rasteraunwbb4o.tif
2022-01-03 20:32:24,708 geoprocessing.align_and_resize_raster_stack(797) INFO aligned all 1 rasters.
2022-01-03 20:32:24,714 geoprocessing.zonal_statistics(1244) INFO build a lookup of aggregate field value to FID
2022-01-03 20:32:24,724 geoprocessing.zonal_statistics(1251) INFO creating disjoint polygon set
2022-01-03 20:32:24,725 geoprocessing.calculate_disjoint_polygon_set(2183) INFO build shapely polygon list
2022-01-03 20:32:24,759 geoprocessing.calculate_disjoint_polygon_set(2204) INFO build shapely rtree index
2022-01-03 20:32:24,780 geoprocessing.calculate_disjoint_polygon_set(2219) INFO poly feature lookup 100.0% complete on animal_mgmt_copy.shp
2022-01-03 20:32:24,780 geoprocessing.calculate_disjoint_polygon_set(2221) INFO build poly intersection lookup
2022-01-03 20:32:24,925 geoprocessing.calculate_disjoint_polygon_set(2245) INFO poly intersection feature lookup 100.0% complete on animal_mgmt_copy.shp
2022-01-03 20:32:24,927 geoprocessing.calculate_disjoint_polygon_set(2279) INFO maximal subset build 100.0% complete on animal_mgmt_copy.shp
2022-01-03 20:32:24,941 geoprocessing.zonal_statistics(1274) INFO processing 8 disjoint polygon sets
2022-01-03 20:32:24,948 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 1 of 8 100.0% processed on animal_mgmt_copy.shp
2022-01-03 20:32:24,949 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 1 of 8 animal_mgmt_copy.shp
2022-01-03 20:32:24,964 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 1 of 8 animal_mgmt_copy.shp
2022-01-03 20:32:24,988 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 2 of 8 100.0% processed on animal_mgmt_copy.shp
2022-01-03 20:32:24,988 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 2 of 8 animal_mgmt_copy.shp
2022-01-03 20:32:24,998 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 2 of 8 animal_mgmt_copy.shp
2022-01-03 20:32:25,014 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 3 of 8 100.0% processed on animal_mgmt_copy.shp
2022-01-03 20:32:25,015 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 3 of 8 animal_mgmt_copy.shp
2022-01-03 20:32:25,025 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 3 of 8 animal_mgmt_copy.shp
2022-01-03 20:32:25,038 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 4 of 8 100.0% processed on animal_mgmt_copy.shp
2022-01-03 20:32:25,039 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 4 of 8 animal_mgmt_copy.shp
2022-01-03 20:32:25,047 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 4 of 8 animal_mgmt_copy.shp
2022-01-03 20:32:25,057 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 5 of 8 100.0% processed on animal_mgmt_copy.shp
2022-01-03 20:32:25,057 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 5 of 8 animal_mgmt_copy.shp
2022-01-03 20:32:25,064 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 5 of 8 animal_mgmt_copy.shp
2022-01-03 20:32:25,070 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 6 of 8 100.0% processed on animal_mgmt_copy.shp
2022-01-03 20:32:25,071 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 6 of 8 animal_mgmt_copy.shp
2022-01-03 20:32:25,076 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 6 of 8 animal_mgmt_copy.shp
2022-01-03 20:32:25,081 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 7 of 8 100.0% processed on animal_mgmt_copy.shp
2022-01-03 20:32:25,082 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 7 of 8 animal_mgmt_copy.shp
2022-01-03 20:32:25,087 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 7 of 8 animal_mgmt_copy.shp
2022-01-03 20:32:25,090 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 8 of 8 100.0% processed on animal_mgmt_copy.shp
2022-01-03 20:32:25,091 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 8 of 8 animal_mgmt_copy.shp
2022-01-03 20:32:25,095 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 8 of 8 animal_mgmt_copy.shp
2022-01-03 20:32:25,098 geoprocessing.zonal_statistics(1378) DEBUG unset_fids: 0 of 339 
2022-01-03 20:32:25,101 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/rmp_work_space\\aligned_inputs\\animal_spatial_index.tif', 1)
2022-01-03 20:32:25,102 geoprocessing.zonal_statistics(1460) DEBUG remaining unset_fids: 0 of 339 
2022-01-03 20:32:25,102 geoprocessing.zonal_statistics(1468) INFO all done processing polygon sets for animal_mgmt_copy.shp
2022-01-03 20:32:25,113 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:25,114 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:25,114 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-459, started daemon 6700)>
2022-01-03 20:32:25,127 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:25,128 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:25,153 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:25,154 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:25,154 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-460, started daemon 15784)>
2022-01-03 20:32:25,163 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:25,164 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:25,195 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:25,196 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:25,196 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-461, started daemon 18064)>
2022-01-03 20:32:25,205 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:25,205 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:25,220 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:25,222 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:25,222 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-462, started daemon 4620)>
2022-01-03 20:32:25,229 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:25,230 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:25,245 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:25,247 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:25,247 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-463, started daemon 21856)>
2022-01-03 20:32:25,255 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:25,256 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:25,273 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:25,275 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:25,275 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-464, started daemon 16068)>
2022-01-03 20:32:25,283 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:25,283 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:25,300 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:25,301 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:25,302 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-465, started daemon 1756)>
2022-01-03 20:32:25,311 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:25,311 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:25,330 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:25,331 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:25,332 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-466, started daemon 17868)>
2022-01-03 20:32:25,340 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:25,341 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:25,360 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:25,361 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:25,361 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-467, started daemon 12056)>
2022-01-03 20:32:25,370 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:25,371 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:25,386 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:25,387 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:25,387 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-468, started daemon 20660)>
2022-01-03 20:32:25,395 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:25,396 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:25,411 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:25,412 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:25,412 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-469, started daemon 14044)>
2022-01-03 20:32:25,420 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:25,421 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:25,437 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:25,437 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:25,437 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-470, started daemon 21944)>
2022-01-03 20:32:25,445 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:25,445 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:25,461 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:25,462 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:25,462 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-471, started daemon 20632)>
2022-01-03 20:32:25,470 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:25,470 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:25,517 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:25,518 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:25,518 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-472, started daemon 12600)>
2022-01-03 20:32:25,529 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:25,530 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:25,547 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:25,548 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:25,548 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-473, started daemon 15652)>
2022-01-03 20:32:25,555 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:25,556 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:25,571 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:25,572 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:25,572 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-474, started daemon 22092)>
2022-01-03 20:32:25,580 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:25,580 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:25,598 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:25,599 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:25,599 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-475, started daemon 19900)>
2022-01-03 20:32:25,609 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:25,610 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:25,625 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:25,626 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:25,626 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-476, started daemon 15340)>
2022-01-03 20:32:25,636 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:25,637 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:25,653 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:25,654 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:25,654 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-477, started daemon 19408)>
2022-01-03 20:32:25,664 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:25,665 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:25,683 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:25,685 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:25,685 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-478, started daemon 22376)>
2022-01-03 20:32:25,694 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:25,695 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:25,712 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:25,713 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:25,713 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-479, started daemon 15040)>
2022-01-03 20:32:25,721 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:25,721 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:25,738 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:25,739 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:25,739 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-480, started daemon 21784)>
2022-01-03 20:32:25,747 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:25,747 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:25,762 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:25,764 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:25,764 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-481, started daemon 19988)>
2022-01-03 20:32:25,773 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:25,774 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:25,789 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:25,789 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:25,790 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-482, started daemon 18260)>
2022-01-03 20:32:25,798 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:25,799 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:25,819 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:25,820 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:25,820 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-483, started daemon 14648)>
2022-01-03 20:32:25,830 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:25,831 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:25,847 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:25,848 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:25,848 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-484, started daemon 2632)>
2022-01-03 20:32:25,855 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:25,856 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:25,871 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:25,872 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:25,872 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-485, started daemon 21804)>
2022-01-03 20:32:25,879 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:25,879 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:25,894 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:25,895 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:25,895 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-486, started daemon 10140)>
2022-01-03 20:32:25,902 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:25,903 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:25,917 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:25,918 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:25,918 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-487, started daemon 6492)>
2022-01-03 20:32:25,926 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:25,927 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:25,940 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:25,941 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:25,941 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-488, started daemon 22404)>
2022-01-03 20:32:25,949 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:25,949 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:25,967 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:25,968 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:25,968 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-489, started daemon 22456)>
2022-01-03 20:32:25,976 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:25,976 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:25,991 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:25,992 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:25,992 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-490, started daemon 13964)>
2022-01-03 20:32:25,999 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:26,000 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:26,016 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:26,017 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:26,018 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-491, started daemon 8160)>
2022-01-03 20:32:26,024 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:26,025 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:26,041 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:26,043 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:26,043 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-492, started daemon 19312)>
2022-01-03 20:32:26,054 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:26,055 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:26,069 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:26,070 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:26,070 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-493, started daemon 6424)>
2022-01-03 20:32:26,078 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:26,078 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:26,092 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:26,093 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:26,093 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-494, started daemon 18584)>
2022-01-03 20:32:26,101 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:26,101 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:26,118 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:26,119 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:26,119 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-495, started daemon 2364)>
2022-01-03 20:32:26,126 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:26,127 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:26,141 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:26,142 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:26,143 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-496, started daemon 20928)>
2022-01-03 20:32:26,151 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:26,151 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:26,165 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:26,166 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:26,167 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-497, started daemon 7812)>
2022-01-03 20:32:26,176 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:26,177 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:26,192 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:26,194 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:26,194 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-498, started daemon 2228)>
2022-01-03 20:32:26,206 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:26,207 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:26,222 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:26,223 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:26,223 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-499, started daemon 19872)>
2022-01-03 20:32:26,231 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:26,231 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:26,246 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:26,248 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:26,248 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-500, started daemon 18072)>
2022-01-03 20:32:26,257 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:26,257 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:26,280 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:26,282 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:26,282 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-501, started daemon 7136)>
2022-01-03 20:32:26,290 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:26,290 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:26,305 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:26,307 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:26,307 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-502, started daemon 9080)>
2022-01-03 20:32:26,314 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:26,315 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:26,330 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:26,332 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:26,332 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-503, started daemon 8368)>
2022-01-03 20:32:26,340 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:26,341 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:26,356 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:26,358 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:26,359 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-504, started daemon 19036)>
2022-01-03 20:32:26,372 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:26,372 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:26,390 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:26,391 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:26,391 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-505, started daemon 18420)>
2022-01-03 20:32:26,400 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:26,401 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:26,417 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:26,418 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:26,418 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-506, started daemon 18828)>
2022-01-03 20:32:26,427 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:26,427 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:26,444 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:26,446 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:26,446 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-507, started daemon 16832)>
2022-01-03 20:32:26,455 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:26,456 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:26,471 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:26,473 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:26,473 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-508, started daemon 20064)>
2022-01-03 20:32:26,481 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:26,483 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:26,500 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:26,501 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:26,501 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-509, started daemon 14608)>
2022-01-03 20:32:26,510 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:26,511 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:26,528 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:26,529 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:26,529 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-510, started daemon 15244)>
2022-01-03 20:32:26,540 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:26,541 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:26,555 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:26,557 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:26,557 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-511, started daemon 6780)>
2022-01-03 20:32:26,564 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:26,565 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:26,581 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:26,582 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:26,582 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-512, started daemon 8432)>
2022-01-03 20:32:26,590 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:26,591 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:26,605 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:26,606 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:26,606 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-513, started daemon 20652)>
2022-01-03 20:32:26,614 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:26,614 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:26,627 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:26,629 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:26,629 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-514, started daemon 548)>
2022-01-03 20:32:26,637 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:26,638 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:26,652 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:26,653 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:26,653 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-515, started daemon 19836)>
2022-01-03 20:32:26,660 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:26,661 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:26,676 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:26,678 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:26,678 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-516, started daemon 20252)>
2022-01-03 20:32:26,685 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:26,685 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:26,698 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:26,699 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:26,699 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-517, started daemon 3036)>
2022-01-03 20:32:26,707 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:26,708 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:26,721 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:26,722 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:26,722 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-518, started daemon 1848)>
2022-01-03 20:32:26,732 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:26,733 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:26,749 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:26,750 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:26,750 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-519, started daemon 7872)>
2022-01-03 20:32:26,757 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:26,758 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:26,772 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:26,773 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:26,773 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-520, started daemon 20484)>
2022-01-03 20:32:26,780 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:26,781 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:26,794 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:26,796 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:26,796 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-521, started daemon 20804)>
2022-01-03 20:32:26,803 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:26,803 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:26,818 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:26,819 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:26,819 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-522, started daemon 21964)>
2022-01-03 20:32:26,827 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:26,828 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:26,842 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:26,843 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:26,843 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-523, started daemon 6136)>
2022-01-03 20:32:26,851 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:26,851 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:26,867 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:26,868 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:26,868 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-524, started daemon 22508)>
2022-01-03 20:32:26,876 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:26,877 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:26,894 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:26,897 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:26,897 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-525, started daemon 13216)>
2022-01-03 20:32:26,907 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:26,908 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:26,945 forage.execute(1010) INFO Calculating field capacity and wilting point
2022-01-03 20:32:26,955 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:26,956 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:26,957 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-526, started daemon 11520)>
2022-01-03 20:32:26,965 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:26,965 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:26,997 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:26,998 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:26,998 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-527, started daemon 10876)>
2022-01-03 20:32:27,004 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

2022-01-03 20:32:27,018 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:27,019 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:27,052 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:27,054 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:27,054 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-528, started daemon 19704)>
2022-01-03 20:32:27,081 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:27,082 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:27,117 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:27,119 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:27,119 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-529, started daemon 6388)>
2022-01-03 20:32:27,140 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:27,141 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:27,157 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:27,157 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:27,158 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-530, started daemon 5048)>
2022-01-03 20:32:27,162 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:27,163 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:27,195 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:27,196 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:27,196 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-531, started daemon 19984)>
2022-01-03 20:32:27,217 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:27,218 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:27,256 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:27,257 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:27,257 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-532, started daemon 20412)>
2022-01-03 20:32:27,278 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:27,279 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:27,296 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:27,298 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:27,298 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-533, started daemon 8612)>
2022-01-03 20:32:27,303 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:27,304 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:27,335 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:27,336 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:27,336 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-534, started daemon 17996)>
2022-01-03 20:32:27,355 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:27,356 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:27,389 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:27,390 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:27,391 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-535, started daemon 4384)>
2022-01-03 20:32:27,410 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:27,411 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:27,429 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:27,431 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:27,431 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-536, started daemon 19964)>
2022-01-03 20:32:27,439 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:27,440 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:27,471 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:27,472 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:27,472 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-537, started daemon 17172)>
2022-01-03 20:32:27,492 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:27,493 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:27,525 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:27,526 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:27,526 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-538, started daemon 22484)>
2022-01-03 20:32:27,549 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:27,550 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:27,567 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:27,569 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:27,569 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-539, started daemon 12888)>
2022-01-03 20:32:27,573 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:27,575 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:27,609 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:27,611 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:27,611 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-540, started daemon 14288)>
2022-01-03 20:32:27,630 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:27,630 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:27,668 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:27,669 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:27,670 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-541, started daemon 22340)>
2022-01-03 20:32:27,692 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:27,693 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:27,711 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:27,712 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:27,712 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-542, started daemon 2068)>
2022-01-03 20:32:27,718 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:27,719 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:27,751 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:27,752 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:27,753 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-543, started daemon 22224)>
2022-01-03 20:32:27,777 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:27,778 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:27,811 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:27,812 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:27,813 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-544, started daemon 22220)>
2022-01-03 20:32:27,834 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:27,834 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:27,851 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:27,852 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:27,852 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-545, started daemon 22112)>
2022-01-03 20:32:27,859 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:27,860 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:27,888 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:27,890 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:27,890 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-546, started daemon 8764)>
2022-01-03 20:32:27,911 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:27,911 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:27,948 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:27,949 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:27,949 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-547, started daemon 4776)>
2022-01-03 20:32:27,972 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:27,972 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:27,990 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:27,991 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:27,992 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-548, started daemon 5516)>
2022-01-03 20:32:27,996 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:27,997 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:28,026 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:28,027 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:28,027 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-549, started daemon 20644)>
2022-01-03 20:32:28,049 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:28,050 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:28,083 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:28,084 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:28,084 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-550, started daemon 2880)>
2022-01-03 20:32:28,103 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:28,104 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:28,120 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:28,122 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:28,122 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-551, started daemon 21928)>
2022-01-03 20:32:28,128 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:28,129 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:28,163 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:28,165 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:28,165 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-552, started daemon 17668)>
2022-01-03 20:32:28,186 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:28,186 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:28,219 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:28,220 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:28,220 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-553, started daemon 5816)>
2022-01-03 20:32:28,242 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:28,243 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:28,259 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:28,260 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:28,260 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-554, started daemon 21836)>
2022-01-03 20:32:28,267 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:28,268 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:28,283 forage.execute(1018) INFO Calculating persistent parameters
2022-01-03 20:32:28,299 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:28,302 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:28,302 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-555, started daemon 22276)>
2022-01-03 20:32:28,312 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:28,313 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:28,329 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:28,330 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:28,330 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-556, started daemon 19924)>
2022-01-03 20:32:28,337 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:28,338 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:28,353 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:28,354 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:28,354 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-557, started daemon 21960)>
2022-01-03 20:32:28,364 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:28,364 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:28,378 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:28,378 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:28,379 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-558, started daemon 22252)>
2022-01-03 20:32:28,386 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:28,387 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:28,403 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:28,404 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:28,404 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-559, started daemon 12000)>
2022-01-03 20:32:28,411 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:28,411 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:28,428 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:28,429 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:28,429 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-560, started daemon 1224)>
2022-01-03 20:32:28,437 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:28,438 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:28,452 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:28,454 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:28,454 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-561, started daemon 18848)>
2022-01-03 20:32:28,464 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:28,464 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:28,479 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:28,480 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:28,480 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-562, started daemon 21824)>
2022-01-03 20:32:28,490 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:28,491 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:28,508 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:28,509 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:28,509 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-563, started daemon 12552)>
2022-01-03 20:32:28,518 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:28,519 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:28,536 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:28,538 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:28,538 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-564, started daemon 21636)>
2022-01-03 20:32:28,547 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:28,547 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:28,562 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:28,564 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:28,564 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-565, started daemon 7640)>
2022-01-03 20:32:28,572 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:28,573 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:28,588 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:28,589 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:28,589 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-566, started daemon 4640)>
2022-01-03 20:32:28,593 warnings._showwarnmsg(110) WARNING C:\RPM_0.2.1_x64\RPM-x86\rangeland_production\forage.py:2050: RuntimeWarning: invalid value encountered in subtract

2022-01-03 20:32:28,601 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:28,601 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:28,624 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:28,626 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:28,626 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-567, started daemon 16624)>
2022-01-03 20:32:28,643 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:28,644 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:28,664 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:28,664 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:28,664 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-568, started daemon 18604)>
2022-01-03 20:32:28,677 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:28,678 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:28,695 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:28,696 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:28,696 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-569, started daemon 11004)>
2022-01-03 20:32:28,709 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:28,709 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:28,727 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:28,728 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:28,728 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-570, started daemon 4392)>
2022-01-03 20:32:28,740 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:28,740 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:28,758 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:28,759 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:28,759 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-571, started daemon 19156)>
2022-01-03 20:32:28,771 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:28,772 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:28,790 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:28,791 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:28,791 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-572, started daemon 21700)>
2022-01-03 20:32:28,801 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:28,801 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:28,813 forage.execute(1024) INFO Calculating required ratios for structural decomposition
2022-01-03 20:32:28,823 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:28,824 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:28,824 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-573, started daemon 17144)>
2022-01-03 20:32:28,835 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:28,836 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:28,853 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:28,854 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:28,854 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-574, started daemon 13192)>
2022-01-03 20:32:28,862 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:28,863 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:28,878 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:28,879 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:28,879 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-575, started daemon 22076)>
2022-01-03 20:32:28,886 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:28,887 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:28,902 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:28,903 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:28,903 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-576, started daemon 14080)>
2022-01-03 20:32:28,911 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:28,911 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:28,927 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:28,928 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:28,929 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-577, started daemon 19432)>
2022-01-03 20:32:28,935 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:28,936 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:28,950 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:28,953 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:28,953 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-578, started daemon 18568)>
2022-01-03 20:32:28,961 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:28,961 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:28,975 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:28,977 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:28,977 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-579, started daemon 20556)>
2022-01-03 20:32:28,986 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:28,987 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:29,003 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:29,005 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:29,005 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-580, started daemon 22016)>
2022-01-03 20:32:29,016 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:29,017 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:29,032 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:29,033 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:29,033 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-581, started daemon 13924)>
2022-01-03 20:32:29,041 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:29,041 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:29,056 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:29,058 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:29,058 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-582, started daemon 18840)>
2022-01-03 20:32:29,065 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:29,066 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:29,081 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:29,082 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:29,082 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-583, started daemon 15300)>
2022-01-03 20:32:29,089 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:29,090 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:29,105 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:29,106 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:29,106 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-584, started daemon 19320)>
2022-01-03 20:32:29,115 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:29,116 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:29,130 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:29,131 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:29,131 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-585, started daemon 15048)>
2022-01-03 20:32:29,140 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:29,141 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:29,156 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:29,157 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:29,157 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-586, started daemon 15768)>
2022-01-03 20:32:29,164 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:29,165 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:29,186 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:29,188 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:29,188 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-587, started daemon 20292)>
2022-01-03 20:32:29,196 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:29,197 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:29,215 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:29,215 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:29,215 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-588, started daemon 16308)>
2022-01-03 20:32:29,224 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:29,224 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:29,240 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:29,242 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:29,242 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-589, started daemon 5220)>
2022-01-03 20:32:29,251 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:29,252 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:29,270 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:29,271 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:29,271 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-590, started daemon 20588)>
2022-01-03 20:32:29,281 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:29,281 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:29,298 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:29,299 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:29,300 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-591, started daemon 9424)>
2022-01-03 20:32:29,308 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:29,309 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:29,323 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:29,324 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:29,324 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-592, started daemon 21780)>
2022-01-03 20:32:29,335 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:29,337 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:29,355 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:29,360 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:29,360 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-593, started daemon 3048)>
2022-01-03 20:32:29,370 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:29,375 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:29,392 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:29,448 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:29,448 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-594, started daemon 12396)>
2022-01-03 20:32:29,458 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:29,460 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:29,511 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:29,625 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:29,625 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-595, started daemon 22512)>
2022-01-03 20:32:29,647 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:29,648 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:29,687 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:29,688 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:29,689 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-596, started daemon 16356)>
2022-01-03 20:32:29,729 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:29,729 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:29,746 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:29,748 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:29,748 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-597, started daemon 22292)>
2022-01-03 20:32:29,756 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:29,757 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:29,773 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:29,774 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:29,774 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-598, started daemon 11124)>
2022-01-03 20:32:29,783 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:29,783 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:29,804 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:29,805 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:29,805 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-599, started daemon 8804)>
2022-01-03 20:32:29,820 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:29,821 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:29,862 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:29,864 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:29,864 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-600, started daemon 12540)>
2022-01-03 20:32:29,901 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:29,901 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:29,916 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:29,917 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:29,918 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-601, started daemon 16476)>
2022-01-03 20:32:29,927 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:29,927 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:29,941 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:29,942 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:29,942 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-602, started daemon 7720)>
2022-01-03 20:32:29,949 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:29,950 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:30,016 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:30,017 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:30,017 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-603, started daemon 10952)>
2022-01-03 20:32:30,095 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:30,095 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:30,114 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:30,116 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:30,116 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-604, started daemon 21220)>
2022-01-03 20:32:30,123 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:30,124 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:30,139 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:30,140 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:30,141 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-605, started daemon 18392)>
2022-01-03 20:32:30,147 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:30,148 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:30,168 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:30,170 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:30,171 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-606, started daemon 1188)>
2022-01-03 20:32:30,183 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:30,183 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:30,204 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:30,206 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:30,206 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-607, started daemon 22476)>
2022-01-03 20:32:30,213 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:30,214 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:30,230 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:30,232 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:30,233 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-608, started daemon 18500)>
2022-01-03 20:32:30,243 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:30,244 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:30,259 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:30,261 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:30,261 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-609, started daemon 21852)>
2022-01-03 20:32:30,270 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:30,270 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:30,286 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:30,288 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:30,288 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-610, started daemon 21764)>
2022-01-03 20:32:30,297 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:30,298 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:30,312 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:30,313 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:30,313 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-611, started daemon 1568)>
2022-01-03 20:32:30,321 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:30,322 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:30,338 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:30,338 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:30,339 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-612, started daemon 20676)>
2022-01-03 20:32:30,346 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:30,347 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:30,364 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:30,365 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:30,365 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-613, started daemon 18144)>
2022-01-03 20:32:30,372 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:30,372 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:30,386 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:30,387 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:30,387 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-614, started daemon 20152)>
2022-01-03 20:32:30,398 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:30,399 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:30,415 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:30,416 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:30,416 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-615, started daemon 7036)>
2022-01-03 20:32:30,425 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:30,425 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:30,439 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:30,440 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:30,440 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-616, started daemon 8036)>
2022-01-03 20:32:30,449 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:30,449 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:30,464 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:30,465 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:30,465 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-617, started daemon 16132)>
2022-01-03 20:32:30,472 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:30,473 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:30,488 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:30,489 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:30,489 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-618, started daemon 20968)>
2022-01-03 20:32:30,498 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:30,499 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:30,514 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:30,516 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:30,516 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-619, started daemon 16416)>
2022-01-03 20:32:30,524 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:30,525 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:30,539 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:30,540 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:30,540 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-620, started daemon 22240)>
2022-01-03 20:32:30,549 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:30,550 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:30,566 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-01-03 20:32:30,567 threading.run(870) DEBUG stats worker PID: 19056
2022-01-03 20:32:30,567 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-621, started daemon 9672)>
2022-01-03 20:32:30,578 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-01-03 20:32:30,579 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-01-03 20:32:30,585 model._logged_target(1641) ERROR Exception while executing <function execute at 0x000001C0BAAFEAF8>
Traceback (most recent call last):
  File "src\rangeland_production\ui\model.py", line 1638, in _logged_target
  File "src\rangeland_production\forage.py", line 1109, in execute
  File "src\rangeland_production\forage.py", line 5553, in _soil_water
  File "src\rangeland_production\forage.py", line 1542, in weighted_state_variable_sum
  File "src\rangeland_production\forage.py", line 1340, in raster_list_sum
  File "env\lib\site-packages\pygeoprocessing\geoprocessing.py", line 159, in raster_calculator
ValueError: `base_raster_path_band_const_list` is empty and should have at least one value.
2022-01-03 20:32:30,587 model._logged_target(1644) INFO Execution finished
2022-01-03 20:32:30,587 utils.prepare_workspace(129) INFO Elapsed time: 29.18s