2022-10-26 22:28:59,455 (natcap.invest.utils) utils.prepare_workspace(161) Level 100 Writing log messages to [E:\TMP\ELD_Tunez\InVEST_Pruebas\5000pixels\InVEST-natcap.invest.sdr.sdr-log-2022-10-26--22_28_59.txt]
2022-10-26 22:28:59,455 (__main__) cli.main(475) Level 100 Starting model with parameters: 
Arguments for InVEST natcap.invest.sdr.sdr 3.12.0:
biophysical_table_path      E:\TMP\ELD_Tunez\InVEST\Inputs\Datos_Biofisicos_SDR_235Clases_sinP.csv
dem_path                    E:\TMP\ELD_Tunez\InVEST\Inputs\MDE_Fil.tif
drainage_path               
erodibility_path            E:\TMP\ELD_Tunez\InVEST\Inputs\k.tif
erosivity_path              E:\TMP\ELD_Tunez\InVEST\Inputs\R.tif
ic_0_param                  0.5
k_param                     2
l_max                       122
lulc_path                   E:\TMP\ELD_Tunez\InVEST\Inputs\land_use235class.tif
n_workers                   -1
results_suffix              5000px
sdr_max                     0.8
threshold_flow_accumulation 10000
watersheds_path             E:\TMP\ELD_Tunez\InVEST\Base\Hydro\Layers\W_5000.shp
workspace_dir               E:\TMP\ELD_Tunez\InVEST_Pruebas\5000pixels

2022-10-26 22:29:01,369 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(396) INFO starting stats_worker
2022-10-26 22:29:01,369 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(402) INFO started stats_worker <Thread(Thread-1 (stats_worker), started daemon 9820)>
2022-10-26 22:29:01,479 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(482) INFO 100.0% complete
2022-10-26 22:29:01,479 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(485) INFO Waiting for raster stats worker result.
2022-10-26 22:29:01,667 (pygeoprocessing.geoprocessing) geoprocessing.align_and_resize_raster_stack(824) INFO 1 of 4 aligned: aligned_dem_5000px.tif
2022-10-26 22:29:02,232 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(396) INFO starting stats_worker
2022-10-26 22:29:02,232 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(402) INFO started stats_worker <Thread(Thread-2 (stats_worker), started daemon 23276)>
2022-10-26 22:29:02,326 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(482) INFO 100.0% complete
2022-10-26 22:29:02,326 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(485) INFO Waiting for raster stats worker result.
2022-10-26 22:29:02,436 (pygeoprocessing.geoprocessing) geoprocessing.align_and_resize_raster_stack(824) INFO 2 of 4 aligned: aligned_lulc_5000px.tif
2022-10-26 22:29:03,189 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(396) INFO starting stats_worker
2022-10-26 22:29:03,189 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(402) INFO started stats_worker <Thread(Thread-3 (stats_worker), started daemon 23848)>
2022-10-26 22:29:03,252 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(482) INFO 100.0% complete
2022-10-26 22:29:03,252 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(485) INFO Waiting for raster stats worker result.
2022-10-26 22:29:03,326 (pygeoprocessing.geoprocessing) geoprocessing.align_and_resize_raster_stack(824) INFO 3 of 4 aligned: aligned_erosivity_5000px.tif
2022-10-26 22:29:04,240 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(396) INFO starting stats_worker
2022-10-26 22:29:04,240 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(402) INFO started stats_worker <Thread(Thread-4 (stats_worker), started daemon 19100)>
2022-10-26 22:29:04,366 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(482) INFO 100.0% complete
2022-10-26 22:29:04,366 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(485) INFO Waiting for raster stats worker result.
2022-10-26 22:29:04,538 (pygeoprocessing.geoprocessing) geoprocessing.align_and_resize_raster_stack(824) INFO 4 of 4 aligned: aligned_erodibility_5000px.tif
2022-10-26 22:29:04,538 (pygeoprocessing.geoprocessing) geoprocessing.align_and_resize_raster_stack(828) INFO aligned all 4 rasters.
2022-10-26 22:29:06,609 (pygeoprocessing.routing.routing) Task._call(1093) INFO (fill pits): complete
2022-10-26 22:29:07,770 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(396) INFO starting stats_worker
2022-10-26 22:29:07,770 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(402) INFO started stats_worker <Thread(Thread-5 (stats_worker), started daemon 12756)>
2022-10-26 22:29:07,990 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(482) INFO 100.0% complete
2022-10-26 22:29:07,990 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(485) INFO Waiting for raster stats worker result.
2022-10-26 22:29:13,201 (pygeoprocessing.routing.routing) Task._call(1093) INFO 100.0% complete
2022-10-26 22:29:13,311 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Calculating average aspect
2022-10-26 22:29:13,468 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 0.00% complete
2022-10-26 22:29:13,468 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 0.92% complete
2022-10-26 22:29:13,468 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 1.85% complete
2022-10-26 22:29:13,468 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 2.77% complete
2022-10-26 22:29:13,484 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 3.69% complete
2022-10-26 22:29:13,484 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 4.61% complete
2022-10-26 22:29:13,484 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 5.54% complete
2022-10-26 22:29:13,484 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 6.46% complete
2022-10-26 22:29:13,500 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 7.38% complete
2022-10-26 22:29:13,500 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 8.13% complete
2022-10-26 22:29:13,500 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 9.05% complete
2022-10-26 22:29:13,500 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 9.98% complete
2022-10-26 22:29:13,515 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 10.90% complete
2022-10-26 22:29:13,515 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 11.82% complete
2022-10-26 22:29:13,531 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 12.74% complete
2022-10-26 22:29:13,531 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 13.67% complete
2022-10-26 22:29:13,548 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 14.59% complete
2022-10-26 22:29:13,548 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 15.51% complete
2022-10-26 22:29:13,548 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 16.26% complete
2022-10-26 22:29:13,548 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 17.18% complete
2022-10-26 22:29:13,562 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 18.10% complete
2022-10-26 22:29:13,562 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 19.03% complete
2022-10-26 22:29:13,578 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 19.95% complete
2022-10-26 22:29:13,594 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 20.87% complete
2022-10-26 22:29:13,609 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 21.80% complete
2022-10-26 22:29:13,609 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 22.72% complete
2022-10-26 22:29:13,625 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 23.64% complete
2022-10-26 22:29:13,625 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 24.39% complete
2022-10-26 22:29:13,625 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 25.31% complete
2022-10-26 22:29:13,625 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 26.23% complete
2022-10-26 22:29:13,641 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 27.16% complete
2022-10-26 22:29:13,649 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 28.08% complete
2022-10-26 22:29:13,657 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 29.00% complete
2022-10-26 22:29:13,672 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 29.93% complete
2022-10-26 22:29:13,672 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 30.85% complete
2022-10-26 22:29:13,688 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 31.77% complete
2022-10-26 22:29:13,688 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 32.52% complete
2022-10-26 22:29:13,688 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 33.44% complete
2022-10-26 22:29:13,688 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 34.36% complete
2022-10-26 22:29:13,704 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 35.29% complete
2022-10-26 22:29:13,766 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 36.21% complete
2022-10-26 22:29:13,766 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 37.13% complete
2022-10-26 22:29:13,782 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 38.06% complete
2022-10-26 22:29:13,782 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 38.98% complete
2022-10-26 22:29:13,798 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 39.90% complete
2022-10-26 22:29:13,798 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 40.65% complete
2022-10-26 22:29:13,813 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 41.57% complete
2022-10-26 22:29:13,829 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 42.49% complete
2022-10-26 22:29:13,845 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 43.42% complete
2022-10-26 22:29:13,861 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 44.34% complete
2022-10-26 22:29:13,876 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 45.26% complete
2022-10-26 22:29:13,876 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 46.19% complete
2022-10-26 22:29:13,892 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 47.11% complete
2022-10-26 22:29:13,908 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 48.03% complete
2022-10-26 22:29:13,908 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 48.78% complete
2022-10-26 22:29:13,923 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 49.70% complete
2022-10-26 22:29:13,939 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 50.62% complete
2022-10-26 22:29:13,955 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 51.55% complete
2022-10-26 22:29:13,970 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 52.47% complete
2022-10-26 22:29:13,986 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 53.39% complete
2022-10-26 22:29:13,986 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 54.31% complete
2022-10-26 22:29:14,002 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 55.24% complete
2022-10-26 22:29:14,017 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 56.16% complete
2022-10-26 22:29:14,017 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 56.91% complete
2022-10-26 22:29:14,033 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 57.83% complete
2022-10-26 22:29:14,033 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 58.75% complete
2022-10-26 22:29:14,050 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 59.68% complete
2022-10-26 22:29:14,065 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 60.60% complete
2022-10-26 22:29:14,080 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 61.52% complete
2022-10-26 22:29:14,080 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 62.44% complete
2022-10-26 22:29:14,096 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 63.37% complete
2022-10-26 22:29:14,112 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 64.29% complete
2022-10-26 22:29:14,112 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 65.04% complete
2022-10-26 22:29:14,127 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 65.96% complete
2022-10-26 22:29:14,127 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 66.88% complete
2022-10-26 22:29:14,150 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 67.81% complete
2022-10-26 22:29:14,159 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 68.73% complete
2022-10-26 22:29:14,159 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 69.65% complete
2022-10-26 22:29:14,174 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 70.57% complete
2022-10-26 22:29:14,190 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 71.50% complete
2022-10-26 22:29:14,190 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 72.42% complete
2022-10-26 22:29:14,206 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 73.17% complete
2022-10-26 22:29:14,206 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 74.09% complete
2022-10-26 22:29:14,237 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 75.01% complete
2022-10-26 22:29:14,253 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 75.93% complete
2022-10-26 22:29:14,284 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 76.86% complete
2022-10-26 22:29:14,300 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 77.78% complete
2022-10-26 22:29:14,316 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 78.70% complete
2022-10-26 22:29:14,347 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 79.63% complete
2022-10-26 22:29:14,363 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 80.55% complete
2022-10-26 22:29:14,394 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 81.30% complete
2022-10-26 22:29:14,410 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 82.22% complete
2022-10-26 22:29:14,425 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 83.14% complete
2022-10-26 22:29:14,457 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 84.06% complete
2022-10-26 22:29:14,473 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 84.99% complete
2022-10-26 22:29:14,504 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 85.91% complete
2022-10-26 22:29:14,520 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 86.83% complete
2022-10-26 22:29:14,535 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 87.76% complete
2022-10-26 22:29:14,551 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 88.68% complete
2022-10-26 22:29:14,566 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 89.43% complete
2022-10-26 22:29:14,582 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 90.35% complete
2022-10-26 22:29:14,644 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 91.27% complete
2022-10-26 22:29:14,660 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 92.19% complete
2022-10-26 22:29:14,676 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 93.12% complete
2022-10-26 22:29:14,692 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 94.04% complete
2022-10-26 22:29:14,692 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 94.96% complete
2022-10-26 22:29:14,692 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 95.89% complete
2022-10-26 22:29:14,692 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 96.81% complete
2022-10-26 22:29:14,692 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 97.55% complete
2022-10-26 22:29:14,707 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 97.83% complete
2022-10-26 22:29:14,723 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 98.11% complete
2022-10-26 22:29:14,723 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 98.39% complete
2022-10-26 22:29:14,723 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 98.67% complete
2022-10-26 22:29:14,723 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 98.94% complete
2022-10-26 22:29:14,723 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 99.22% complete
2022-10-26 22:29:14,723 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 99.50% complete
2022-10-26 22:29:14,723 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 99.78% complete
2022-10-26 22:29:14,723 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Average aspect 100.00% complete
2022-10-26 22:29:19,791 (pygeoprocessing.routing.routing) Task._call(1093) INFO 100.0% complete
2022-10-26 22:29:20,041 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(396) INFO starting stats_worker
2022-10-26 22:29:20,041 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(402) INFO started stats_worker <Thread(Thread-6 (stats_worker), started daemon 20708)>
2022-10-26 22:29:21,667 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(482) INFO 100.0% complete
2022-10-26 22:29:21,667 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(485) INFO Waiting for raster stats worker result.
2022-10-26 22:29:23,972 (pygeoprocessing.routing.routing) Task._call(1093) INFO filter out incomplete divergent streams
2022-10-26 22:29:24,042 (pygeoprocessing.routing.routing) Task._call(1093) INFO 100.0% complete
2022-10-26 22:29:24,340 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(396) INFO starting stats_worker
2022-10-26 22:29:24,340 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(402) INFO started stats_worker <Thread(Thread-7 (stats_worker), started daemon 12592)>
2022-10-26 22:29:24,716 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(482) INFO 100.0% complete
2022-10-26 22:29:24,716 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(485) INFO Waiting for raster stats worker result.
2022-10-26 22:29:24,936 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(396) INFO starting stats_worker
2022-10-26 22:29:24,936 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(402) INFO started stats_worker <Thread(Thread-8 (stats_worker), started daemon 3068)>
2022-10-26 22:29:25,093 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(482) INFO 100.0% complete
2022-10-26 22:29:25,093 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(485) INFO Waiting for raster stats worker result.
2022-10-26 22:29:25,563 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(396) INFO starting stats_worker
2022-10-26 22:29:25,563 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(402) INFO started stats_worker <Thread(Thread-9 (stats_worker), started daemon 10900)>
2022-10-26 22:29:25,909 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(482) INFO 100.0% complete
2022-10-26 22:29:25,909 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(485) INFO Waiting for raster stats worker result.
2022-10-26 22:29:26,363 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(396) INFO starting stats_worker
2022-10-26 22:29:26,363 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(402) INFO started stats_worker <Thread(Thread-10 (stats_worker), started daemon 3236)>
2022-10-26 22:29:26,787 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(482) INFO 100.0% complete
2022-10-26 22:29:26,787 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(485) INFO Waiting for raster stats worker result.
2022-10-26 22:29:27,580 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(396) INFO starting stats_worker
2022-10-26 22:29:27,580 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(402) INFO started stats_worker <Thread(Thread-11 (stats_worker), started daemon 16172)>
2022-10-26 22:29:27,932 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(482) INFO 100.0% complete
2022-10-26 22:29:27,932 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(485) INFO Waiting for raster stats worker result.
2022-10-26 22:29:33,670 (pygeoprocessing.routing.routing) sdr._calculate_bar_factor(1152) INFO 100.0% complete
2022-10-26 22:29:33,702 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(396) INFO starting stats_worker
2022-10-26 22:29:33,702 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(402) INFO started stats_worker <Thread(Thread-12 (stats_worker), started daemon 15152)>
2022-10-26 22:29:34,468 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(482) INFO 100.0% complete
2022-10-26 22:29:34,469 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(485) INFO Waiting for raster stats worker result.
2022-10-26 22:29:40,671 (pygeoprocessing.routing.routing) sdr._calculate_bar_factor(1152) INFO 100.0% complete
2022-10-26 22:29:40,697 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(396) INFO starting stats_worker
2022-10-26 22:29:40,698 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(402) INFO started stats_worker <Thread(Thread-13 (stats_worker), started daemon 23424)>
2022-10-26 22:29:41,532 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(482) INFO 100.0% complete
2022-10-26 22:29:41,533 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(485) INFO Waiting for raster stats worker result.
2022-10-26 22:29:42,286 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(396) INFO starting stats_worker
2022-10-26 22:29:42,287 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(402) INFO started stats_worker <Thread(Thread-14 (stats_worker), started daemon 19036)>
2022-10-26 22:29:43,037 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(482) INFO 100.0% complete
2022-10-26 22:29:43,038 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(485) INFO Waiting for raster stats worker result.
2022-10-26 22:29:43,793 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(396) INFO starting stats_worker
2022-10-26 22:29:43,794 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(402) INFO started stats_worker <Thread(Thread-15 (stats_worker), started daemon 4472)>
2022-10-26 22:29:44,212 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(482) INFO 100.0% complete
2022-10-26 22:29:44,212 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(485) INFO Waiting for raster stats worker result.
2022-10-26 22:29:51,116 (pygeoprocessing.routing.routing) Task._call(1093) INFO 100.0% complete
2022-10-26 22:29:51,268 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(396) INFO starting stats_worker
2022-10-26 22:29:51,269 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(402) INFO started stats_worker <Thread(Thread-16 (stats_worker), started daemon 26616)>
2022-10-26 22:29:51,884 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(482) INFO 100.0% complete
2022-10-26 22:29:51,884 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(485) INFO Waiting for raster stats worker result.
2022-10-26 22:29:52,587 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(396) INFO starting stats_worker
2022-10-26 22:29:52,588 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(402) INFO started stats_worker <Thread(Thread-17 (stats_worker), started daemon 11848)>
2022-10-26 22:29:52,845 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(482) INFO 100.0% complete
2022-10-26 22:29:52,845 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(485) INFO Waiting for raster stats worker result.
2022-10-26 22:29:53,554 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(396) INFO starting stats_worker
2022-10-26 22:29:53,555 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(402) INFO started stats_worker <Thread(Thread-18 (stats_worker), started daemon 7992)>
2022-10-26 22:29:53,904 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(482) INFO 100.0% complete
2022-10-26 22:29:53,904 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(485) INFO Waiting for raster stats worker result.
2022-10-26 22:29:55,298 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(396) INFO starting stats_worker
2022-10-26 22:29:55,299 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(402) INFO started stats_worker <Thread(Thread-19 (stats_worker), started daemon 18764)>
2022-10-26 22:29:55,679 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(482) INFO 100.0% complete
2022-10-26 22:29:55,680 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(485) INFO Waiting for raster stats worker result.
2022-10-26 22:29:56,491 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Calculate sediment deposition
2022-10-26 22:29:56,577 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 0.00% complete
2022-10-26 22:29:56,584 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 0.00% complete
2022-10-26 22:29:56,589 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 0.00% complete
2022-10-26 22:29:56,653 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 0.00% complete
2022-10-26 22:29:56,722 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 0.00% complete
2022-10-26 22:29:56,763 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 0.00% complete
2022-10-26 22:29:56,785 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 0.00% complete
2022-10-26 22:29:56,788 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 0.00% complete
2022-10-26 22:29:56,790 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 0.00% complete
2022-10-26 22:29:56,792 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 0.00% complete
2022-10-26 22:29:56,795 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 0.92% complete
2022-10-26 22:29:56,816 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 1.85% complete
2022-10-26 22:29:56,901 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 2.77% complete
2022-10-26 22:29:57,048 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 3.69% complete
2022-10-26 22:29:57,216 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 4.61% complete
2022-10-26 22:29:57,312 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 5.54% complete
2022-10-26 22:29:57,371 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 6.46% complete
2022-10-26 22:29:57,378 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 7.38% complete
2022-10-26 22:29:57,384 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 0.00% complete
2022-10-26 22:29:57,391 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 1.85% complete
2022-10-26 22:29:57,397 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 3.69% complete
2022-10-26 22:29:57,475 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 5.54% complete
2022-10-26 22:29:57,642 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 7.38% complete
2022-10-26 22:29:57,768 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 9.23% complete
2022-10-26 22:29:57,870 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 11.07% complete
2022-10-26 22:29:57,888 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 12.92% complete
2022-10-26 22:29:57,891 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 14.77% complete
2022-10-26 22:29:57,893 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 0.00% complete
2022-10-26 22:29:57,896 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 2.77% complete
2022-10-26 22:29:57,898 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 5.54% complete
2022-10-26 22:29:58,003 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 8.31% complete
2022-10-26 22:29:58,165 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 11.07% complete
2022-10-26 22:29:58,284 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 13.84% complete
2022-10-26 22:29:58,423 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 16.61% complete
2022-10-26 22:29:58,529 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 19.38% complete
2022-10-26 22:29:58,553 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 22.15% complete
2022-10-26 22:29:58,556 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 0.00% complete
2022-10-26 22:29:58,560 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 3.69% complete
2022-10-26 22:29:58,586 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 7.38% complete
2022-10-26 22:29:58,706 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 11.07% complete
2022-10-26 22:29:58,813 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 14.77% complete
2022-10-26 22:29:58,930 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 18.46% complete
2022-10-26 22:29:59,068 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 22.15% complete
2022-10-26 22:29:59,199 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 25.84% complete
2022-10-26 22:29:59,277 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 29.53% complete
2022-10-26 22:29:59,280 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 0.00% complete
2022-10-26 22:29:59,416 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 4.61% complete
2022-10-26 22:29:59,444 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 9.23% complete
2022-10-26 22:29:59,565 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 13.84% complete
2022-10-26 22:29:59,687 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 18.46% complete
2022-10-26 22:29:59,814 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 23.07% complete
2022-10-26 22:29:59,949 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 27.69% complete
2022-10-26 22:30:00,097 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 32.30% complete
2022-10-26 22:30:00,167 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 36.92% complete
2022-10-26 22:30:00,170 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 0.00% complete
2022-10-26 22:30:00,356 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 5.54% complete
2022-10-26 22:30:00,489 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 11.07% complete
2022-10-26 22:30:00,631 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 16.61% complete
2022-10-26 22:30:00,760 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 22.15% complete
2022-10-26 22:30:00,900 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 27.69% complete
2022-10-26 22:30:01,016 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 33.22% complete
2022-10-26 22:30:01,172 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 38.76% complete
2022-10-26 22:30:01,251 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 44.30% complete
2022-10-26 22:30:01,254 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 0.00% complete
2022-10-26 22:30:01,338 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 6.46% complete
2022-10-26 22:30:01,474 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 12.92% complete
2022-10-26 22:30:01,612 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 19.38% complete
2022-10-26 22:30:01,734 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 25.84% complete
2022-10-26 22:30:01,877 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 32.30% complete
2022-10-26 22:30:02,013 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 38.76% complete
2022-10-26 22:30:02,166 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 45.22% complete
2022-10-26 22:30:02,218 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 51.68% complete
2022-10-26 22:30:02,279 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 0.00% complete
2022-10-26 22:30:02,365 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 7.38% complete
2022-10-26 22:30:02,524 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 14.77% complete
2022-10-26 22:30:02,874 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 22.15% complete
2022-10-26 22:30:03,038 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 29.53% complete
2022-10-26 22:30:03,188 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 36.92% complete
2022-10-26 22:30:03,361 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 44.30% complete
2022-10-26 22:30:03,605 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 51.68% complete
2022-10-26 22:30:03,823 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 59.07% complete
2022-10-26 22:30:03,925 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 0.00% complete
2022-10-26 22:30:04,083 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 8.31% complete
2022-10-26 22:30:04,247 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 16.61% complete
2022-10-26 22:30:04,392 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 24.92% complete
2022-10-26 22:30:04,539 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 33.22% complete
2022-10-26 22:30:04,662 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 41.53% complete
2022-10-26 22:30:04,762 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 49.84% complete
2022-10-26 22:30:04,844 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 58.14% complete
2022-10-26 22:30:05,018 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 66.45% complete
2022-10-26 22:30:05,068 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 0.00% complete
2022-10-26 22:30:05,180 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 9.23% complete
2022-10-26 22:30:05,377 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 18.46% complete
2022-10-26 22:30:05,513 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 27.69% complete
2022-10-26 22:30:05,588 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 36.92% complete
2022-10-26 22:30:05,591 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 46.15% complete
2022-10-26 22:30:05,614 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 55.37% complete
2022-10-26 22:30:05,632 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 64.60% complete
2022-10-26 22:30:05,663 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 73.83% complete
2022-10-26 22:30:05,666 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 0.00% complete
2022-10-26 22:30:05,706 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 10.15% complete
2022-10-26 22:30:05,845 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 20.30% complete
2022-10-26 22:30:05,859 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 30.46% complete
2022-10-26 22:30:05,879 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 40.61% complete
2022-10-26 22:30:05,883 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 50.76% complete
2022-10-26 22:30:05,887 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 60.91% complete
2022-10-26 22:30:05,891 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 71.06% complete
2022-10-26 22:30:05,895 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 81.22% complete
2022-10-26 22:30:05,898 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 0.00% complete
2022-10-26 22:30:05,900 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 11.07% complete
2022-10-26 22:30:05,911 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 22.15% complete
2022-10-26 22:30:05,913 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 33.22% complete
2022-10-26 22:30:05,915 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 44.30% complete
2022-10-26 22:30:05,917 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 55.37% complete
2022-10-26 22:30:05,918 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 66.45% complete
2022-10-26 22:30:05,920 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 77.52% complete
2022-10-26 22:30:05,922 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 88.60% complete
2022-10-26 22:30:05,924 (natcap.invest.sdr.sdr_core) Task._call(1093) INFO Sediment deposition 100% complete
2022-10-26 22:30:06,971 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(396) INFO starting stats_worker
2022-10-26 22:30:06,972 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(402) INFO started stats_worker <Thread(Thread-20 (stats_worker), started daemon 9256)>
2022-10-26 22:30:07,280 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(482) INFO 100.0% complete
2022-10-26 22:30:07,280 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(485) INFO Waiting for raster stats worker result.
2022-10-26 22:30:08,025 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(396) INFO starting stats_worker
2022-10-26 22:30:08,026 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(402) INFO started stats_worker <Thread(Thread-21 (stats_worker), started daemon 11284)>
2022-10-26 22:30:08,525 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(482) INFO 100.0% complete
2022-10-26 22:30:08,525 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(485) INFO Waiting for raster stats worker result.
2022-10-26 22:30:09,305 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(396) INFO starting stats_worker
2022-10-26 22:30:09,306 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(402) INFO started stats_worker <Thread(Thread-22 (stats_worker), started daemon 18252)>
2022-10-26 22:30:09,704 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(482) INFO 100.0% complete
2022-10-26 22:30:09,705 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(485) INFO Waiting for raster stats worker result.
2022-10-26 22:30:10,554 (pygeoprocessing.geoprocessing) geoprocessing.align_and_resize_raster_stack(824) INFO 1 of 1 aligned: clipped_raster.tif
2022-10-26 22:30:10,554 (pygeoprocessing.geoprocessing) geoprocessing.align_and_resize_raster_stack(828) INFO aligned all 1 rasters.
2022-10-26 22:30:10,575 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1275) INFO build a lookup of aggregate field value to FID
2022-10-26 22:30:10,582 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1282) INFO creating disjoint polygon set
2022-10-26 22:30:10,583 (pygeoprocessing.geoprocessing) geoprocessing.calculate_disjoint_polygon_set(2258) INFO build shapely polygon list
2022-10-26 22:30:10,634 (pygeoprocessing.geoprocessing) geoprocessing.calculate_disjoint_polygon_set(2283) INFO build shapely rtree index
2022-10-26 22:30:10,687 (pygeoprocessing.geoprocessing) geoprocessing.calculate_disjoint_polygon_set(2296) INFO poly feature lookup 100.0% complete on watershed_results_sdr_5000px.shp
2022-10-26 22:30:10,687 (pygeoprocessing.geoprocessing) geoprocessing.calculate_disjoint_polygon_set(2300) INFO build poly intersection lookup
2022-10-26 22:30:10,973 (pygeoprocessing.geoprocessing) geoprocessing.calculate_disjoint_polygon_set(2322) INFO poly intersection feature lookup 100.0% complete on watershed_results_sdr_5000px.shp
2022-10-26 22:30:10,978 (pygeoprocessing.geoprocessing) geoprocessing.calculate_disjoint_polygon_set(2356) INFO maximal subset build 100.0% complete on watershed_results_sdr_5000px.shp
2022-10-26 22:30:11,004 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1303) INFO processing 7 disjoint polygon sets
2022-10-26 22:30:11,017 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1337) INFO disjoint polygon set 1 of 7 100.0% processed on watershed_results_sdr_5000px.shp
2022-10-26 22:30:11,033 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1345) INFO rasterizing disjoint polygon set 1 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:11,175 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1363) INFO summarizing rasterized disjoint polygon set 1 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:11,371 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1337) INFO disjoint polygon set 2 of 7 100.0% processed on watershed_results_sdr_5000px.shp
2022-10-26 22:30:11,376 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1345) INFO rasterizing disjoint polygon set 2 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:11,521 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1363) INFO summarizing rasterized disjoint polygon set 2 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:11,609 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1337) INFO disjoint polygon set 3 of 7 100.0% processed on watershed_results_sdr_5000px.shp
2022-10-26 22:30:11,615 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1345) INFO rasterizing disjoint polygon set 3 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:11,783 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1363) INFO summarizing rasterized disjoint polygon set 3 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:11,852 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1337) INFO disjoint polygon set 4 of 7 100.0% processed on watershed_results_sdr_5000px.shp
2022-10-26 22:30:11,857 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1345) INFO rasterizing disjoint polygon set 4 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:11,999 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1363) INFO summarizing rasterized disjoint polygon set 4 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:12,070 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1337) INFO disjoint polygon set 5 of 7 100.0% processed on watershed_results_sdr_5000px.shp
2022-10-26 22:30:12,076 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1345) INFO rasterizing disjoint polygon set 5 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:12,217 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1363) INFO summarizing rasterized disjoint polygon set 5 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:12,282 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1337) INFO disjoint polygon set 6 of 7 100.0% processed on watershed_results_sdr_5000px.shp
2022-10-26 22:30:12,288 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1345) INFO rasterizing disjoint polygon set 6 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:12,410 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1363) INFO summarizing rasterized disjoint polygon set 6 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:12,448 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1337) INFO disjoint polygon set 7 of 7 100.0% processed on watershed_results_sdr_5000px.shp
2022-10-26 22:30:12,454 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1345) INFO rasterizing disjoint polygon set 7 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:12,590 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1363) INFO summarizing rasterized disjoint polygon set 7 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:12,622 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1500) INFO all done processing polygon sets for watershed_results_sdr_5000px.shp
2022-10-26 22:30:13,240 (pygeoprocessing.geoprocessing) geoprocessing.align_and_resize_raster_stack(824) INFO 1 of 1 aligned: clipped_raster.tif
2022-10-26 22:30:13,240 (pygeoprocessing.geoprocessing) geoprocessing.align_and_resize_raster_stack(828) INFO aligned all 1 rasters.
2022-10-26 22:30:13,262 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1275) INFO build a lookup of aggregate field value to FID
2022-10-26 22:30:13,272 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1282) INFO creating disjoint polygon set
2022-10-26 22:30:13,273 (pygeoprocessing.geoprocessing) geoprocessing.calculate_disjoint_polygon_set(2258) INFO build shapely polygon list
2022-10-26 22:30:13,323 (pygeoprocessing.geoprocessing) geoprocessing.calculate_disjoint_polygon_set(2283) INFO build shapely rtree index
2022-10-26 22:30:13,373 (pygeoprocessing.geoprocessing) geoprocessing.calculate_disjoint_polygon_set(2296) INFO poly feature lookup 100.0% complete on watershed_results_sdr_5000px.shp
2022-10-26 22:30:13,374 (pygeoprocessing.geoprocessing) geoprocessing.calculate_disjoint_polygon_set(2300) INFO build poly intersection lookup
2022-10-26 22:30:13,656 (pygeoprocessing.geoprocessing) geoprocessing.calculate_disjoint_polygon_set(2322) INFO poly intersection feature lookup 100.0% complete on watershed_results_sdr_5000px.shp
2022-10-26 22:30:13,659 (pygeoprocessing.geoprocessing) geoprocessing.calculate_disjoint_polygon_set(2356) INFO maximal subset build 100.0% complete on watershed_results_sdr_5000px.shp
2022-10-26 22:30:13,677 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1303) INFO processing 7 disjoint polygon sets
2022-10-26 22:30:13,685 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1337) INFO disjoint polygon set 1 of 7 100.0% processed on watershed_results_sdr_5000px.shp
2022-10-26 22:30:13,691 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1345) INFO rasterizing disjoint polygon set 1 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:13,835 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1363) INFO summarizing rasterized disjoint polygon set 1 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:14,002 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1337) INFO disjoint polygon set 2 of 7 100.0% processed on watershed_results_sdr_5000px.shp
2022-10-26 22:30:14,008 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1345) INFO rasterizing disjoint polygon set 2 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:14,156 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1363) INFO summarizing rasterized disjoint polygon set 2 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:14,241 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1337) INFO disjoint polygon set 3 of 7 100.0% processed on watershed_results_sdr_5000px.shp
2022-10-26 22:30:14,247 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1345) INFO rasterizing disjoint polygon set 3 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:14,396 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1363) INFO summarizing rasterized disjoint polygon set 3 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:14,488 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1337) INFO disjoint polygon set 4 of 7 100.0% processed on watershed_results_sdr_5000px.shp
2022-10-26 22:30:14,494 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1345) INFO rasterizing disjoint polygon set 4 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:14,665 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1363) INFO summarizing rasterized disjoint polygon set 4 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:14,746 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1337) INFO disjoint polygon set 5 of 7 100.0% processed on watershed_results_sdr_5000px.shp
2022-10-26 22:30:14,752 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1345) INFO rasterizing disjoint polygon set 5 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:14,895 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1363) INFO summarizing rasterized disjoint polygon set 5 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:14,952 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1337) INFO disjoint polygon set 6 of 7 100.0% processed on watershed_results_sdr_5000px.shp
2022-10-26 22:30:14,958 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1345) INFO rasterizing disjoint polygon set 6 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:15,105 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1363) INFO summarizing rasterized disjoint polygon set 6 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:15,158 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1337) INFO disjoint polygon set 7 of 7 100.0% processed on watershed_results_sdr_5000px.shp
2022-10-26 22:30:15,167 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1345) INFO rasterizing disjoint polygon set 7 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:15,312 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1363) INFO summarizing rasterized disjoint polygon set 7 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:15,347 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1500) INFO all done processing polygon sets for watershed_results_sdr_5000px.shp
2022-10-26 22:30:15,927 (pygeoprocessing.geoprocessing) geoprocessing.align_and_resize_raster_stack(824) INFO 1 of 1 aligned: clipped_raster.tif
2022-10-26 22:30:15,927 (pygeoprocessing.geoprocessing) geoprocessing.align_and_resize_raster_stack(828) INFO aligned all 1 rasters.
2022-10-26 22:30:15,949 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1275) INFO build a lookup of aggregate field value to FID
2022-10-26 22:30:15,956 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1282) INFO creating disjoint polygon set
2022-10-26 22:30:15,957 (pygeoprocessing.geoprocessing) geoprocessing.calculate_disjoint_polygon_set(2258) INFO build shapely polygon list
2022-10-26 22:30:16,007 (pygeoprocessing.geoprocessing) geoprocessing.calculate_disjoint_polygon_set(2283) INFO build shapely rtree index
2022-10-26 22:30:16,051 (pygeoprocessing.geoprocessing) geoprocessing.calculate_disjoint_polygon_set(2296) INFO poly feature lookup 100.0% complete on watershed_results_sdr_5000px.shp
2022-10-26 22:30:16,051 (pygeoprocessing.geoprocessing) geoprocessing.calculate_disjoint_polygon_set(2300) INFO build poly intersection lookup
2022-10-26 22:30:16,381 (pygeoprocessing.geoprocessing) geoprocessing.calculate_disjoint_polygon_set(2322) INFO poly intersection feature lookup 100.0% complete on watershed_results_sdr_5000px.shp
2022-10-26 22:30:16,386 (pygeoprocessing.geoprocessing) geoprocessing.calculate_disjoint_polygon_set(2356) INFO maximal subset build 100.0% complete on watershed_results_sdr_5000px.shp
2022-10-26 22:30:16,413 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1303) INFO processing 7 disjoint polygon sets
2022-10-26 22:30:16,424 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1337) INFO disjoint polygon set 1 of 7 100.0% processed on watershed_results_sdr_5000px.shp
2022-10-26 22:30:16,441 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1345) INFO rasterizing disjoint polygon set 1 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:16,598 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1363) INFO summarizing rasterized disjoint polygon set 1 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:16,806 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1337) INFO disjoint polygon set 2 of 7 100.0% processed on watershed_results_sdr_5000px.shp
2022-10-26 22:30:16,812 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1345) INFO rasterizing disjoint polygon set 2 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:16,961 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1363) INFO summarizing rasterized disjoint polygon set 2 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:17,062 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1337) INFO disjoint polygon set 3 of 7 100.0% processed on watershed_results_sdr_5000px.shp
2022-10-26 22:30:17,068 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1345) INFO rasterizing disjoint polygon set 3 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:17,219 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1363) INFO summarizing rasterized disjoint polygon set 3 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:17,294 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1337) INFO disjoint polygon set 4 of 7 100.0% processed on watershed_results_sdr_5000px.shp
2022-10-26 22:30:17,301 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1345) INFO rasterizing disjoint polygon set 4 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:17,468 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1363) INFO summarizing rasterized disjoint polygon set 4 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:17,531 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1337) INFO disjoint polygon set 5 of 7 100.0% processed on watershed_results_sdr_5000px.shp
2022-10-26 22:30:17,537 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1345) INFO rasterizing disjoint polygon set 5 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:17,706 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1363) INFO summarizing rasterized disjoint polygon set 5 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:17,769 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1337) INFO disjoint polygon set 6 of 7 100.0% processed on watershed_results_sdr_5000px.shp
2022-10-26 22:30:17,776 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1345) INFO rasterizing disjoint polygon set 6 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:17,919 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1363) INFO summarizing rasterized disjoint polygon set 6 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:17,980 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1337) INFO disjoint polygon set 7 of 7 100.0% processed on watershed_results_sdr_5000px.shp
2022-10-26 22:30:17,986 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1345) INFO rasterizing disjoint polygon set 7 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:18,134 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1363) INFO summarizing rasterized disjoint polygon set 7 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:18,173 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1500) INFO all done processing polygon sets for watershed_results_sdr_5000px.shp
2022-10-26 22:30:18,826 (pygeoprocessing.geoprocessing) geoprocessing.align_and_resize_raster_stack(824) INFO 1 of 1 aligned: clipped_raster.tif
2022-10-26 22:30:18,827 (pygeoprocessing.geoprocessing) geoprocessing.align_and_resize_raster_stack(828) INFO aligned all 1 rasters.
2022-10-26 22:30:18,845 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1275) INFO build a lookup of aggregate field value to FID
2022-10-26 22:30:18,852 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1282) INFO creating disjoint polygon set
2022-10-26 22:30:18,853 (pygeoprocessing.geoprocessing) geoprocessing.calculate_disjoint_polygon_set(2258) INFO build shapely polygon list
2022-10-26 22:30:18,906 (pygeoprocessing.geoprocessing) geoprocessing.calculate_disjoint_polygon_set(2283) INFO build shapely rtree index
2022-10-26 22:30:18,960 (pygeoprocessing.geoprocessing) geoprocessing.calculate_disjoint_polygon_set(2296) INFO poly feature lookup 100.0% complete on watershed_results_sdr_5000px.shp
2022-10-26 22:30:18,960 (pygeoprocessing.geoprocessing) geoprocessing.calculate_disjoint_polygon_set(2300) INFO build poly intersection lookup
2022-10-26 22:30:19,407 (pygeoprocessing.geoprocessing) geoprocessing.calculate_disjoint_polygon_set(2322) INFO poly intersection feature lookup 100.0% complete on watershed_results_sdr_5000px.shp
2022-10-26 22:30:19,411 (pygeoprocessing.geoprocessing) geoprocessing.calculate_disjoint_polygon_set(2356) INFO maximal subset build 100.0% complete on watershed_results_sdr_5000px.shp
2022-10-26 22:30:19,440 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1303) INFO processing 7 disjoint polygon sets
2022-10-26 22:30:19,456 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1337) INFO disjoint polygon set 1 of 7 100.0% processed on watershed_results_sdr_5000px.shp
2022-10-26 22:30:19,463 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1345) INFO rasterizing disjoint polygon set 1 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:19,640 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1363) INFO summarizing rasterized disjoint polygon set 1 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:19,838 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1337) INFO disjoint polygon set 2 of 7 100.0% processed on watershed_results_sdr_5000px.shp
2022-10-26 22:30:19,844 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1345) INFO rasterizing disjoint polygon set 2 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:20,011 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1363) INFO summarizing rasterized disjoint polygon set 2 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:20,092 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1337) INFO disjoint polygon set 3 of 7 100.0% processed on watershed_results_sdr_5000px.shp
2022-10-26 22:30:20,098 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1345) INFO rasterizing disjoint polygon set 3 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:20,239 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1363) INFO summarizing rasterized disjoint polygon set 3 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:20,319 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1337) INFO disjoint polygon set 4 of 7 100.0% processed on watershed_results_sdr_5000px.shp
2022-10-26 22:30:20,325 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1345) INFO rasterizing disjoint polygon set 4 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:20,483 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1363) INFO summarizing rasterized disjoint polygon set 4 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:20,564 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1337) INFO disjoint polygon set 5 of 7 100.0% processed on watershed_results_sdr_5000px.shp
2022-10-26 22:30:20,570 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1345) INFO rasterizing disjoint polygon set 5 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:20,748 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1363) INFO summarizing rasterized disjoint polygon set 5 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:20,832 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1337) INFO disjoint polygon set 6 of 7 100.0% processed on watershed_results_sdr_5000px.shp
2022-10-26 22:30:20,839 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1345) INFO rasterizing disjoint polygon set 6 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:20,972 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1363) INFO summarizing rasterized disjoint polygon set 6 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:21,021 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1337) INFO disjoint polygon set 7 of 7 100.0% processed on watershed_results_sdr_5000px.shp
2022-10-26 22:30:21,028 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1345) INFO rasterizing disjoint polygon set 7 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:21,166 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1363) INFO summarizing rasterized disjoint polygon set 7 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:21,208 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1500) INFO all done processing polygon sets for watershed_results_sdr_5000px.shp
2022-10-26 22:30:21,819 (pygeoprocessing.geoprocessing) geoprocessing.align_and_resize_raster_stack(824) INFO 1 of 1 aligned: clipped_raster.tif
2022-10-26 22:30:21,819 (pygeoprocessing.geoprocessing) geoprocessing.align_and_resize_raster_stack(828) INFO aligned all 1 rasters.
2022-10-26 22:30:21,841 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1275) INFO build a lookup of aggregate field value to FID
2022-10-26 22:30:21,848 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1282) INFO creating disjoint polygon set
2022-10-26 22:30:21,849 (pygeoprocessing.geoprocessing) geoprocessing.calculate_disjoint_polygon_set(2258) INFO build shapely polygon list
2022-10-26 22:30:21,905 (pygeoprocessing.geoprocessing) geoprocessing.calculate_disjoint_polygon_set(2283) INFO build shapely rtree index
2022-10-26 22:30:21,955 (pygeoprocessing.geoprocessing) geoprocessing.calculate_disjoint_polygon_set(2296) INFO poly feature lookup 100.0% complete on watershed_results_sdr_5000px.shp
2022-10-26 22:30:21,955 (pygeoprocessing.geoprocessing) geoprocessing.calculate_disjoint_polygon_set(2300) INFO build poly intersection lookup
2022-10-26 22:30:22,232 (pygeoprocessing.geoprocessing) geoprocessing.calculate_disjoint_polygon_set(2322) INFO poly intersection feature lookup 100.0% complete on watershed_results_sdr_5000px.shp
2022-10-26 22:30:22,235 (pygeoprocessing.geoprocessing) geoprocessing.calculate_disjoint_polygon_set(2356) INFO maximal subset build 100.0% complete on watershed_results_sdr_5000px.shp
2022-10-26 22:30:22,256 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1303) INFO processing 7 disjoint polygon sets
2022-10-26 22:30:22,265 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1337) INFO disjoint polygon set 1 of 7 100.0% processed on watershed_results_sdr_5000px.shp
2022-10-26 22:30:22,272 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1345) INFO rasterizing disjoint polygon set 1 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:22,417 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1363) INFO summarizing rasterized disjoint polygon set 1 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:22,594 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1337) INFO disjoint polygon set 2 of 7 100.0% processed on watershed_results_sdr_5000px.shp
2022-10-26 22:30:22,601 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1345) INFO rasterizing disjoint polygon set 2 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:22,754 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1363) INFO summarizing rasterized disjoint polygon set 2 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:22,833 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1337) INFO disjoint polygon set 3 of 7 100.0% processed on watershed_results_sdr_5000px.shp
2022-10-26 22:30:22,839 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1345) INFO rasterizing disjoint polygon set 3 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:22,985 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1363) INFO summarizing rasterized disjoint polygon set 3 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:23,059 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1337) INFO disjoint polygon set 4 of 7 100.0% processed on watershed_results_sdr_5000px.shp
2022-10-26 22:30:23,064 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1345) INFO rasterizing disjoint polygon set 4 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:23,213 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1363) INFO summarizing rasterized disjoint polygon set 4 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:23,281 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1337) INFO disjoint polygon set 5 of 7 100.0% processed on watershed_results_sdr_5000px.shp
2022-10-26 22:30:23,287 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1345) INFO rasterizing disjoint polygon set 5 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:23,459 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1363) INFO summarizing rasterized disjoint polygon set 5 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:23,525 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1337) INFO disjoint polygon set 6 of 7 100.0% processed on watershed_results_sdr_5000px.shp
2022-10-26 22:30:23,532 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1345) INFO rasterizing disjoint polygon set 6 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:23,685 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1363) INFO summarizing rasterized disjoint polygon set 6 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:23,728 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1337) INFO disjoint polygon set 7 of 7 100.0% processed on watershed_results_sdr_5000px.shp
2022-10-26 22:30:23,734 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1345) INFO rasterizing disjoint polygon set 7 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:23,861 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1363) INFO summarizing rasterized disjoint polygon set 7 of 7 watershed_results_sdr_5000px.shp
2022-10-26 22:30:23,897 (pygeoprocessing.geoprocessing) geoprocessing.zonal_statistics(1500) INFO all done processing polygon sets for watershed_results_sdr_5000px.shp
2022-10-26 22:30:24,176 (natcap.invest.utils) utils.prepare_workspace(169) INFO Elapsed time: 1m 24.72s
2022-10-26 22:30:24,177 (natcap.invest.utils) utils.prepare_workspace(172) INFO Execution finished