2022-02-01 15:09:49,949 utils.prepare_workspace(123) INFO Writing log messages to /Users/raphaelle/Documents/InVEST/NCP101_SDR_output/InVEST-Sediment-Delivery-Ratio-Model-(SDR)-log-2022-02-01--15_09_49.txt
2022-02-01 15:09:49,950 model._logged_target(1646) Level 100 Starting model with parameters: 
Arguments for InVEST natcap.invest.sdr.sdr 3.9.0:
biophysical_table_path      /Users/raphaelle/Documents/InVEST/NCP101_SDR_sample_data/SDR_input_Gura/InVEST_lulc_biophysical_table_gura.csv
dem_path                    /Users/raphaelle/Documents/InVEST/NCP101_SDR_sample_data/SDR_input_Gura/DEM_gura.tif
drainage_path               
erodibility_path            /Users/raphaelle/Documents/InVEST/NCP101_SDR_sample_data/SDR_input_Gura/erodibility_gura.tif
erosivity_path              /Users/raphaelle/Documents/InVEST/NCP101_SDR_sample_data/SDR_input_Gura/erosivity_gura.tif
ic_0_param                  0.5
k_param                     2
lulc_path                   /Users/raphaelle/Documents/InVEST/NCP101_SDR_sample_data/SDR_input_Gura/LULC_gura.tif
n_workers                   -1
results_suffix              Gura
sdr_max                     0.8
threshold_flow_accumulation 1000
watersheds_path             /Users/raphaelle/Documents/InVEST/NCP101_SDR_sample_data/SDR_input_Gura/subwatersheds_gura.shp
workspace_dir               /Users/raphaelle/Documents/InVEST/NCP101_SDR_output

2022-02-01 15:09:50,384 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-02-01 15:09:50,386 threading.run(870) DEBUG stats worker PID: 91447
2022-02-01 15:09:50,386 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-231, started daemon 123145386909696)>
2022-02-01 15:09:50,426 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-02-01 15:09:50,427 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-02-01 15:09:50,457 geoprocessing.align_and_resize_raster_stack(795) INFO 1 of 4 aligned: aligned_dem_Gura.tif
2022-02-01 15:09:50,657 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-02-01 15:09:50,657 threading.run(870) DEBUG stats worker PID: 91447
2022-02-01 15:09:50,658 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-232, started daemon 123145386909696)>
2022-02-01 15:09:50,692 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-02-01 15:09:50,693 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-02-01 15:09:50,730 geoprocessing.align_and_resize_raster_stack(795) INFO 2 of 4 aligned: aligned_lulc_Gura.tif
2022-02-01 15:09:51,023 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-02-01 15:09:51,024 threading.run(870) DEBUG stats worker PID: 91447
2022-02-01 15:09:51,025 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-233, started daemon 123145386909696)>
2022-02-01 15:09:51,068 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-02-01 15:09:51,069 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-02-01 15:09:51,140 geoprocessing.align_and_resize_raster_stack(795) INFO 3 of 4 aligned: aligned_erosivity_Gura.tif
2022-02-01 15:09:51,315 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-02-01 15:09:51,316 threading.run(870) DEBUG stats worker PID: 91447
2022-02-01 15:09:51,316 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-234, started daemon 123145386909696)>
2022-02-01 15:09:51,353 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-02-01 15:09:51,354 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-02-01 15:09:51,393 geoprocessing.align_and_resize_raster_stack(795) INFO 4 of 4 aligned: aligned_erodibility_Gura.tif
2022-02-01 15:09:51,394 geoprocessing.align_and_resize_raster_stack(797) INFO aligned all 4 rasters.
2022-02-01 15:09:51,774 Task._call(1234) INFO 100.0% complete
2022-02-01 15:09:51,920 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-02-01 15:09:51,921 threading.run(870) DEBUG stats worker PID: 91447
2022-02-01 15:09:51,921 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-235, started daemon 123145386909696)>
2022-02-01 15:09:51,994 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-02-01 15:09:51,995 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-02-01 15:09:53,156 Task._call(1234) INFO 100.0% complete
2022-02-01 15:09:53,170 Task._call(1234) INFO Calculating average aspect
2022-02-01 15:09:53,252 Task._call(1234) INFO Average aspect 0.00% complete
2022-02-01 15:09:53,265 Task._call(1234) INFO Average aspect 5.65% complete
2022-02-01 15:09:53,281 Task._call(1234) INFO Average aspect 11.30% complete
2022-02-01 15:09:53,292 Task._call(1234) INFO Average aspect 16.95% complete
2022-02-01 15:09:53,302 Task._call(1234) INFO Average aspect 22.61% complete
2022-02-01 15:09:53,308 Task._call(1234) INFO Average aspect 28.26% complete
2022-02-01 15:09:53,318 Task._call(1234) INFO Average aspect 33.91% complete
2022-02-01 15:09:53,331 Task._call(1234) INFO Average aspect 39.56% complete
2022-02-01 15:09:53,341 Task._call(1234) INFO Average aspect 42.74% complete
2022-02-01 15:09:53,356 Task._call(1234) INFO Average aspect 48.39% complete
2022-02-01 15:09:53,371 Task._call(1234) INFO Average aspect 54.04% complete
2022-02-01 15:09:53,386 Task._call(1234) INFO Average aspect 59.69% complete
2022-02-01 15:09:53,401 Task._call(1234) INFO Average aspect 65.34% complete
2022-02-01 15:09:53,414 Task._call(1234) INFO Average aspect 70.99% complete
2022-02-01 15:09:53,428 Task._call(1234) INFO Average aspect 76.65% complete
2022-02-01 15:09:53,444 Task._call(1234) INFO Average aspect 82.30% complete
2022-02-01 15:09:53,454 Task._call(1234) INFO Average aspect 85.48% complete
2022-02-01 15:09:53,458 Task._call(1234) INFO Average aspect 87.40% complete
2022-02-01 15:09:53,461 Task._call(1234) INFO Average aspect 89.32% complete
2022-02-01 15:09:53,468 Task._call(1234) INFO Average aspect 91.24% complete
2022-02-01 15:09:53,478 Task._call(1234) INFO Average aspect 93.16% complete
2022-02-01 15:09:53,486 Task._call(1234) INFO Average aspect 95.08% complete
2022-02-01 15:09:53,492 Task._call(1234) INFO Average aspect 97.00% complete
2022-02-01 15:09:53,497 Task._call(1234) INFO Average aspect 98.92% complete
2022-02-01 15:09:53,501 Task._call(1234) INFO Average aspect 100.00% complete
2022-02-01 15:09:53,623 Task._call(1234) DEBUG creating target flow accum raster layer
2022-02-01 15:09:53,730 Task._call(1234) DEBUG creating visited raster layer
2022-02-01 15:09:53,742 Task._call(1234) DEBUG starting search
2022-02-01 15:09:54,569 Task._call(1234) INFO 100.0% complete
2022-02-01 15:09:54,638 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-02-01 15:09:54,639 threading.run(870) DEBUG stats worker PID: 91447
2022-02-01 15:09:54,640 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-236, started daemon 123145386909696)>
2022-02-01 15:09:54,951 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-02-01 15:09:54,952 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-02-01 15:09:55,423 Task._call(1234) INFO filter out incomplete divergent streams
2022-02-01 15:09:55,443 Task._call(1234) INFO 100.0% complete
2022-02-01 15:09:55,471 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-02-01 15:09:55,473 threading.run(870) DEBUG stats worker PID: 91447
2022-02-01 15:09:55,473 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-237, started daemon 123145386909696)>
2022-02-01 15:09:55,560 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-02-01 15:09:55,561 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-02-01 15:09:55,613 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-02-01 15:09:55,613 threading.run(870) DEBUG stats worker PID: 91447
2022-02-01 15:09:55,613 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-238, started daemon 123145386909696)>
2022-02-01 15:09:55,647 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-02-01 15:09:55,648 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-02-01 15:09:55,707 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-02-01 15:09:55,708 threading.run(870) DEBUG stats worker PID: 91447
2022-02-01 15:09:55,708 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-239, started daemon 123145386909696)>
2022-02-01 15:09:55,804 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-02-01 15:09:55,806 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-02-01 15:09:55,905 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-02-01 15:09:55,906 threading.run(870) DEBUG stats worker PID: 91447
2022-02-01 15:09:55,906 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-240, started daemon 123145386909696)>
2022-02-01 15:09:56,051 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-02-01 15:09:56,052 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-02-01 15:09:56,169 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-02-01 15:09:56,170 threading.run(870) DEBUG stats worker PID: 91447
2022-02-01 15:09:56,170 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-241, started daemon 123145386909696)>
2022-02-01 15:09:56,273 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-02-01 15:09:56,286 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-02-01 15:09:56,566 sdr._calculate_bar_factor(1088) DEBUG doing flow accumulation mfd on /Users/raphaelle/Documents/InVEST/NCP101_SDR_output/intermediate_outputs/w_threshold_Gura.tif
2022-02-01 15:09:56,566 sdr._calculate_bar_factor(1096) DEBUG creating target flow accum raster layer
2022-02-01 15:09:56,870 sdr._calculate_bar_factor(1096) DEBUG creating visited raster layer
2022-02-01 15:09:56,906 sdr._calculate_bar_factor(1096) DEBUG starting search
2022-02-01 15:09:57,976 sdr._calculate_bar_factor(1096) INFO 100.0% complete
2022-02-01 15:09:57,988 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-02-01 15:09:57,989 threading.run(870) DEBUG stats worker PID: 91447
2022-02-01 15:09:57,989 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-242, started daemon 123145386909696)>
2022-02-01 15:09:58,161 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-02-01 15:09:58,161 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-02-01 15:09:58,265 sdr._calculate_bar_factor(1088) DEBUG doing flow accumulation mfd on /Users/raphaelle/Documents/InVEST/NCP101_SDR_output/intermediate_outputs/slope_threshold_Gura.tif
2022-02-01 15:09:58,266 sdr._calculate_bar_factor(1096) DEBUG creating target flow accum raster layer
2022-02-01 15:09:58,440 sdr._calculate_bar_factor(1096) DEBUG creating visited raster layer
2022-02-01 15:09:58,468 sdr._calculate_bar_factor(1096) DEBUG starting search
2022-02-01 15:09:59,627 sdr._calculate_bar_factor(1096) INFO 100.0% complete
2022-02-01 15:09:59,639 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-02-01 15:09:59,640 threading.run(870) DEBUG stats worker PID: 91447
2022-02-01 15:09:59,640 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-243, started daemon 123145386909696)>
2022-02-01 15:09:59,855 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-02-01 15:09:59,856 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-02-01 15:10:00,010 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-02-01 15:10:00,011 threading.run(870) DEBUG stats worker PID: 91447
2022-02-01 15:10:00,012 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-244, started daemon 123145386909696)>
2022-02-01 15:10:00,182 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-02-01 15:10:00,184 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-02-01 15:10:00,316 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-02-01 15:10:00,317 threading.run(870) DEBUG stats worker PID: 91447
2022-02-01 15:10:00,318 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-245, started daemon 123145386373120)>
2022-02-01 15:10:00,452 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-02-01 15:10:00,453 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-02-01 15:10:01,316 Task._call(1234) INFO 100.0% complete
2022-02-01 15:10:01,361 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-02-01 15:10:01,362 threading.run(870) DEBUG stats worker PID: 91447
2022-02-01 15:10:01,362 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-246, started daemon 123145386373120)>
2022-02-01 15:10:01,507 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-02-01 15:10:01,508 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-02-01 15:10:01,628 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-02-01 15:10:01,630 threading.run(870) DEBUG stats worker PID: 91447
2022-02-01 15:10:01,630 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-247, started daemon 123145386373120)>
2022-02-01 15:10:01,687 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-02-01 15:10:01,687 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-02-01 15:10:01,804 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-02-01 15:10:01,805 threading.run(870) DEBUG stats worker PID: 91447
2022-02-01 15:10:01,805 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-248, started daemon 123145386373120)>
2022-02-01 15:10:01,963 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-02-01 15:10:01,967 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-02-01 15:10:02,114 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-02-01 15:10:02,115 threading.run(870) DEBUG stats worker PID: 91447
2022-02-01 15:10:02,115 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-249, started daemon 123145386373120)>
2022-02-01 15:10:02,185 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-02-01 15:10:02,186 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-02-01 15:10:02,281 Task._call(1234) INFO Calculate sediment deposition
2022-02-01 15:10:02,327 Task._call(1234) INFO Sediment deposition 0.00% complete
2022-02-01 15:10:02,502 Task._call(1234) INFO Sediment deposition 0.00% complete
2022-02-01 15:10:02,578 Task._call(1234) INFO Sediment deposition 0.00% complete
2022-02-01 15:10:02,661 Task._call(1234) INFO Sediment deposition 0.00% complete
2022-02-01 15:10:02,705 Task._call(1234) INFO Sediment deposition 0.00% complete
2022-02-01 15:10:02,712 Task._call(1234) INFO Sediment deposition 0.00% complete
2022-02-01 15:10:02,765 Task._call(1234) INFO Sediment deposition 0.00% complete
2022-02-01 15:10:02,843 Task._call(1234) INFO Sediment deposition 0.00% complete
2022-02-01 15:10:02,879 Task._call(1234) INFO Sediment deposition 0.00% complete
2022-02-01 15:10:02,914 Task._call(1234) INFO Sediment deposition 5.65% complete
2022-02-01 15:10:02,947 Task._call(1234) INFO Sediment deposition 11.30% complete
2022-02-01 15:10:03,060 Task._call(1234) INFO Sediment deposition 16.95% complete
2022-02-01 15:10:03,207 Task._call(1234) INFO Sediment deposition 22.61% complete
2022-02-01 15:10:03,321 Task._call(1234) INFO Sediment deposition 28.26% complete
2022-02-01 15:10:03,415 Task._call(1234) INFO Sediment deposition 33.91% complete
2022-02-01 15:10:03,476 Task._call(1234) INFO Sediment deposition 39.56% complete
2022-02-01 15:10:03,498 Task._call(1234) INFO Sediment deposition 0.00% complete
2022-02-01 15:10:03,501 Task._call(1234) INFO Sediment deposition 11.30% complete
2022-02-01 15:10:03,503 Task._call(1234) INFO Sediment deposition 22.61% complete
2022-02-01 15:10:03,507 Task._call(1234) INFO Sediment deposition 33.91% complete
2022-02-01 15:10:03,544 Task._call(1234) INFO Sediment deposition 45.21% complete
2022-02-01 15:10:03,584 Task._call(1234) INFO Sediment deposition 56.51% complete
2022-02-01 15:10:03,601 Task._call(1234) INFO Sediment deposition 67.82% complete
2022-02-01 15:10:03,605 Task._call(1234) INFO Sediment deposition 79.12% complete
2022-02-01 15:10:03,607 Task._call(1234) INFO Sediment deposition 100% complete
2022-02-01 15:10:03,862 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-02-01 15:10:03,866 threading.run(870) DEBUG stats worker PID: 91447
2022-02-01 15:10:03,866 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-250, started daemon 123145386373120)>
2022-02-01 15:10:04,082 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-02-01 15:10:04,083 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-02-01 15:10:04,194 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-02-01 15:10:04,196 threading.run(870) DEBUG stats worker PID: 91447
2022-02-01 15:10:04,196 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-251, started daemon 123145386373120)>
2022-02-01 15:10:04,251 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-02-01 15:10:04,253 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-02-01 15:10:05,100 Task._call(1234) INFO 100.0% complete
2022-02-01 15:10:05,150 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-02-01 15:10:05,151 threading.run(870) DEBUG stats worker PID: 91447
2022-02-01 15:10:05,151 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-252, started daemon 123145386373120)>
2022-02-01 15:10:05,256 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-02-01 15:10:05,256 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-02-01 15:10:05,399 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-02-01 15:10:05,407 threading.run(870) DEBUG stats worker PID: 91447
2022-02-01 15:10:05,407 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-253, started daemon 123145386373120)>
2022-02-01 15:10:05,537 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-02-01 15:10:05,538 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-02-01 15:10:05,719 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-02-01 15:10:05,721 threading.run(870) DEBUG stats worker PID: 91447
2022-02-01 15:10:05,721 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-254, started daemon 123145386373120)>
2022-02-01 15:10:05,788 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-02-01 15:10:05,790 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-02-01 15:10:05,966 geoprocessing.raster_calculator(391) INFO starting stats_worker
2022-02-01 15:10:05,967 threading.run(870) DEBUG stats worker PID: 91447
2022-02-01 15:10:05,967 geoprocessing.raster_calculator(397) INFO started stats_worker <Thread(Thread-255, started daemon 123145386373120)>
2022-02-01 15:10:06,156 geoprocessing.raster_calculator(477) INFO 100.0% complete
2022-02-01 15:10:06,157 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result.
2022-02-01 15:10:06,255 utils._log_gdal_errors(80) WARNING [errno 1] Value 2200.76999999999998 of field area_ha of feature 0 not successfully written. Possibly due to too larger number with respect to field width
2022-02-01 15:10:06,256 utils._log_gdal_errors(80) WARNING [errno 1] Value 918.383000000000038 of field area_ha of feature 1 not successfully written. Possibly due to too larger number with respect to field width
2022-02-01 15:10:06,256 utils._log_gdal_errors(80) WARNING [errno 1] Value 1149.70000000000005 of field area_ha of feature 2 not successfully written. Possibly due to too larger number with respect to field width
2022-02-01 15:10:06,257 utils._log_gdal_errors(80) WARNING [errno 1] Value 2413.98000000000002 of field area_ha of feature 3 not successfully written. Possibly due to too larger number with respect to field width
2022-02-01 15:10:06,258 utils._log_gdal_errors(80) WARNING [errno 1] Value 3958.80999999999995 of field area_ha of feature 4 not successfully written. Possibly due to too larger number with respect to field width
2022-02-01 15:10:06,259 geoprocessing.zonal_statistics(1184) DEBUG <osgeo.gdal.Dataset; proxy of <Swig Object of type 'GDALDatasetShadow *' at 0x7f8780096930> >
2022-02-01 15:10:06,499 geoprocessing.align_and_resize_raster_stack(795) INFO 1 of 1 aligned: clipped_rasterh8dqfosp.tif
2022-02-01 15:10:06,500 geoprocessing.align_and_resize_raster_stack(797) INFO aligned all 1 rasters.
2022-02-01 15:10:06,501 geoprocessing.zonal_statistics(1244) INFO build a lookup of aggregate field value to FID
2022-02-01 15:10:06,502 geoprocessing.zonal_statistics(1251) INFO creating disjoint polygon set
2022-02-01 15:10:06,504 geoprocessing.calculate_disjoint_polygon_set(2183) INFO build shapely polygon list
2022-02-01 15:10:06,514 geoprocessing.calculate_disjoint_polygon_set(2204) INFO build shapely rtree index
2022-02-01 15:10:06,517 geoprocessing.calculate_disjoint_polygon_set(2219) INFO poly feature lookup 100.0% complete on watershed_results_sdr_Gura.shp
2022-02-01 15:10:06,518 geoprocessing.calculate_disjoint_polygon_set(2221) INFO build poly intersection lookup
2022-02-01 15:10:06,535 geoprocessing.calculate_disjoint_polygon_set(2245) INFO poly intersection feature lookup 100.0% complete on watershed_results_sdr_Gura.shp
2022-02-01 15:10:06,536 geoprocessing.calculate_disjoint_polygon_set(2279) INFO maximal subset build 100.0% complete on watershed_results_sdr_Gura.shp
2022-02-01 15:10:06,550 geoprocessing.zonal_statistics(1274) INFO processing 4 disjoint polygon sets
2022-02-01 15:10:06,552 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 1 of 4 100.0% processed on watershed_results_sdr_Gura.shp
2022-02-01 15:10:06,557 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 1 of 4 watershed_results_sdr_Gura.shp
2022-02-01 15:10:06,626 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 1 of 4 watershed_results_sdr_Gura.shp
2022-02-01 15:10:06,687 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 2 of 4 100.0% processed on watershed_results_sdr_Gura.shp
2022-02-01 15:10:06,689 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 2 of 4 watershed_results_sdr_Gura.shp
2022-02-01 15:10:06,724 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 2 of 4 watershed_results_sdr_Gura.shp
2022-02-01 15:10:06,752 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 3 of 4 100.0% processed on watershed_results_sdr_Gura.shp
2022-02-01 15:10:06,755 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 3 of 4 watershed_results_sdr_Gura.shp
2022-02-01 15:10:06,795 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 3 of 4 watershed_results_sdr_Gura.shp
2022-02-01 15:10:06,824 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 4 of 4 100.0% processed on watershed_results_sdr_Gura.shp
2022-02-01 15:10:06,826 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 4 of 4 watershed_results_sdr_Gura.shp
2022-02-01 15:10:06,862 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 4 of 4 watershed_results_sdr_Gura.shp
2022-02-01 15:10:06,952 geoprocessing.zonal_statistics(1378) DEBUG unset_fids: 0 of 5 
2022-02-01 15:10:06,954 geoprocessing.zonal_statistics(1381) DEBUG gt [ 2.4898066e+05  1.5000000e+01  0.0000000e+00  9.9418670e+06
  0.0000000e+00 -1.5000000e+01] for ('/Users/raphaelle/Documents/InVEST/NCP101_SDR_output/usle_Gura.tif', 1)
2022-02-01 15:10:06,957 geoprocessing.zonal_statistics(1460) DEBUG remaining unset_fids: 0 of 5 
2022-02-01 15:10:06,958 geoprocessing.zonal_statistics(1468) INFO all done processing polygon sets for watershed_results_sdr_Gura.shp
2022-02-01 15:10:06,964 geoprocessing.zonal_statistics(1184) DEBUG <osgeo.gdal.Dataset; proxy of <Swig Object of type 'GDALDatasetShadow *' at 0x7f877f59ded0> >
2022-02-01 15:10:07,143 geoprocessing.align_and_resize_raster_stack(795) INFO 1 of 1 aligned: clipped_rasterzfual2_8.tif
2022-02-01 15:10:07,144 geoprocessing.align_and_resize_raster_stack(797) INFO aligned all 1 rasters.
2022-02-01 15:10:07,145 geoprocessing.zonal_statistics(1244) INFO build a lookup of aggregate field value to FID
2022-02-01 15:10:07,146 geoprocessing.zonal_statistics(1251) INFO creating disjoint polygon set
2022-02-01 15:10:07,147 geoprocessing.calculate_disjoint_polygon_set(2183) INFO build shapely polygon list
2022-02-01 15:10:07,148 geoprocessing.calculate_disjoint_polygon_set(2204) INFO build shapely rtree index
2022-02-01 15:10:07,152 geoprocessing.calculate_disjoint_polygon_set(2219) INFO poly feature lookup 100.0% complete on watershed_results_sdr_Gura.shp
2022-02-01 15:10:07,152 geoprocessing.calculate_disjoint_polygon_set(2221) INFO build poly intersection lookup
2022-02-01 15:10:07,169 geoprocessing.calculate_disjoint_polygon_set(2245) INFO poly intersection feature lookup 100.0% complete on watershed_results_sdr_Gura.shp
2022-02-01 15:10:07,171 geoprocessing.calculate_disjoint_polygon_set(2279) INFO maximal subset build 100.0% complete on watershed_results_sdr_Gura.shp
2022-02-01 15:10:07,183 geoprocessing.zonal_statistics(1274) INFO processing 4 disjoint polygon sets
2022-02-01 15:10:07,183 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 1 of 4 100.0% processed on watershed_results_sdr_Gura.shp
2022-02-01 15:10:07,189 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 1 of 4 watershed_results_sdr_Gura.shp
2022-02-01 15:10:07,225 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 1 of 4 watershed_results_sdr_Gura.shp
2022-02-01 15:10:07,289 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 2 of 4 100.0% processed on watershed_results_sdr_Gura.shp
2022-02-01 15:10:07,291 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 2 of 4 watershed_results_sdr_Gura.shp
2022-02-01 15:10:07,323 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 2 of 4 watershed_results_sdr_Gura.shp
2022-02-01 15:10:07,344 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 3 of 4 100.0% processed on watershed_results_sdr_Gura.shp
2022-02-01 15:10:07,348 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 3 of 4 watershed_results_sdr_Gura.shp
2022-02-01 15:10:07,383 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 3 of 4 watershed_results_sdr_Gura.shp
2022-02-01 15:10:07,409 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 4 of 4 100.0% processed on watershed_results_sdr_Gura.shp
2022-02-01 15:10:07,412 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 4 of 4 watershed_results_sdr_Gura.shp
2022-02-01 15:10:07,450 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 4 of 4 watershed_results_sdr_Gura.shp
2022-02-01 15:10:07,490 geoprocessing.zonal_statistics(1378) DEBUG unset_fids: 0 of 5 
2022-02-01 15:10:07,491 geoprocessing.zonal_statistics(1381) DEBUG gt [ 2.4898066e+05  1.5000000e+01  0.0000000e+00  9.9418670e+06
  0.0000000e+00 -1.5000000e+01] for ('/Users/raphaelle/Documents/InVEST/NCP101_SDR_output/sed_export_Gura.tif', 1)
2022-02-01 15:10:07,492 geoprocessing.zonal_statistics(1460) DEBUG remaining unset_fids: 0 of 5 
2022-02-01 15:10:07,492 geoprocessing.zonal_statistics(1468) INFO all done processing polygon sets for watershed_results_sdr_Gura.shp
2022-02-01 15:10:07,499 geoprocessing.zonal_statistics(1184) DEBUG <osgeo.gdal.Dataset; proxy of <Swig Object of type 'GDALDatasetShadow *' at 0x7f877f59ded0> >
2022-02-01 15:10:07,699 geoprocessing.align_and_resize_raster_stack(795) INFO 1 of 1 aligned: clipped_rasterzaj121xz.tif
2022-02-01 15:10:07,700 geoprocessing.align_and_resize_raster_stack(797) INFO aligned all 1 rasters.
2022-02-01 15:10:07,701 geoprocessing.zonal_statistics(1244) INFO build a lookup of aggregate field value to FID
2022-02-01 15:10:07,702 geoprocessing.zonal_statistics(1251) INFO creating disjoint polygon set
2022-02-01 15:10:07,703 geoprocessing.calculate_disjoint_polygon_set(2183) INFO build shapely polygon list
2022-02-01 15:10:07,707 geoprocessing.calculate_disjoint_polygon_set(2204) INFO build shapely rtree index
2022-02-01 15:10:07,713 geoprocessing.calculate_disjoint_polygon_set(2219) INFO poly feature lookup 100.0% complete on watershed_results_sdr_Gura.shp
2022-02-01 15:10:07,714 geoprocessing.calculate_disjoint_polygon_set(2221) INFO build poly intersection lookup
2022-02-01 15:10:07,723 geoprocessing.calculate_disjoint_polygon_set(2245) INFO poly intersection feature lookup 100.0% complete on watershed_results_sdr_Gura.shp
2022-02-01 15:10:07,724 geoprocessing.calculate_disjoint_polygon_set(2279) INFO maximal subset build 100.0% complete on watershed_results_sdr_Gura.shp
2022-02-01 15:10:07,732 geoprocessing.zonal_statistics(1274) INFO processing 4 disjoint polygon sets
2022-02-01 15:10:07,733 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 1 of 4 100.0% processed on watershed_results_sdr_Gura.shp
2022-02-01 15:10:07,736 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 1 of 4 watershed_results_sdr_Gura.shp
2022-02-01 15:10:07,771 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 1 of 4 watershed_results_sdr_Gura.shp
2022-02-01 15:10:07,822 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 2 of 4 100.0% processed on watershed_results_sdr_Gura.shp
2022-02-01 15:10:07,823 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 2 of 4 watershed_results_sdr_Gura.shp
2022-02-01 15:10:07,854 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 2 of 4 watershed_results_sdr_Gura.shp
2022-02-01 15:10:07,872 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 3 of 4 100.0% processed on watershed_results_sdr_Gura.shp
2022-02-01 15:10:07,874 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 3 of 4 watershed_results_sdr_Gura.shp
2022-02-01 15:10:07,907 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 3 of 4 watershed_results_sdr_Gura.shp
2022-02-01 15:10:07,931 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 4 of 4 100.0% processed on watershed_results_sdr_Gura.shp
2022-02-01 15:10:07,933 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 4 of 4 watershed_results_sdr_Gura.shp
2022-02-01 15:10:07,967 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 4 of 4 watershed_results_sdr_Gura.shp
2022-02-01 15:10:07,988 geoprocessing.zonal_statistics(1378) DEBUG unset_fids: 0 of 5 
2022-02-01 15:10:07,989 geoprocessing.zonal_statistics(1381) DEBUG gt [ 2.4898066e+05  1.5000000e+01  0.0000000e+00  9.9418670e+06
  0.0000000e+00 -1.5000000e+01] for ('/Users/raphaelle/Documents/InVEST/NCP101_SDR_output/sed_retention_Gura.tif', 1)
2022-02-01 15:10:07,989 geoprocessing.zonal_statistics(1460) DEBUG remaining unset_fids: 0 of 5 
2022-02-01 15:10:07,989 geoprocessing.zonal_statistics(1468) INFO all done processing polygon sets for watershed_results_sdr_Gura.shp
2022-02-01 15:10:07,994 geoprocessing.zonal_statistics(1184) DEBUG <osgeo.gdal.Dataset; proxy of <Swig Object of type 'GDALDatasetShadow *' at 0x7f877f59ded0> >
2022-02-01 15:10:08,157 geoprocessing.align_and_resize_raster_stack(795) INFO 1 of 1 aligned: clipped_rasterl8ullnw8.tif
2022-02-01 15:10:08,158 geoprocessing.align_and_resize_raster_stack(797) INFO aligned all 1 rasters.
2022-02-01 15:10:08,159 geoprocessing.zonal_statistics(1244) INFO build a lookup of aggregate field value to FID
2022-02-01 15:10:08,160 geoprocessing.zonal_statistics(1251) INFO creating disjoint polygon set
2022-02-01 15:10:08,161 geoprocessing.calculate_disjoint_polygon_set(2183) INFO build shapely polygon list
2022-02-01 15:10:08,166 geoprocessing.calculate_disjoint_polygon_set(2204) INFO build shapely rtree index
2022-02-01 15:10:08,169 geoprocessing.calculate_disjoint_polygon_set(2219) INFO poly feature lookup 100.0% complete on watershed_results_sdr_Gura.shp
2022-02-01 15:10:08,170 geoprocessing.calculate_disjoint_polygon_set(2221) INFO build poly intersection lookup
2022-02-01 15:10:08,183 geoprocessing.calculate_disjoint_polygon_set(2245) INFO poly intersection feature lookup 100.0% complete on watershed_results_sdr_Gura.shp
2022-02-01 15:10:08,184 geoprocessing.calculate_disjoint_polygon_set(2279) INFO maximal subset build 100.0% complete on watershed_results_sdr_Gura.shp
2022-02-01 15:10:08,193 geoprocessing.zonal_statistics(1274) INFO processing 4 disjoint polygon sets
2022-02-01 15:10:08,195 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 1 of 4 100.0% processed on watershed_results_sdr_Gura.shp
2022-02-01 15:10:08,201 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 1 of 4 watershed_results_sdr_Gura.shp
2022-02-01 15:10:08,244 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 1 of 4 watershed_results_sdr_Gura.shp
2022-02-01 15:10:08,292 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 2 of 4 100.0% processed on watershed_results_sdr_Gura.shp
2022-02-01 15:10:08,294 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 2 of 4 watershed_results_sdr_Gura.shp
2022-02-01 15:10:08,322 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 2 of 4 watershed_results_sdr_Gura.shp
2022-02-01 15:10:08,342 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 3 of 4 100.0% processed on watershed_results_sdr_Gura.shp
2022-02-01 15:10:08,344 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 3 of 4 watershed_results_sdr_Gura.shp
2022-02-01 15:10:08,378 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 3 of 4 watershed_results_sdr_Gura.shp
2022-02-01 15:10:08,399 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 4 of 4 100.0% processed on watershed_results_sdr_Gura.shp
2022-02-01 15:10:08,401 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 4 of 4 watershed_results_sdr_Gura.shp
2022-02-01 15:10:08,430 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 4 of 4 watershed_results_sdr_Gura.shp
2022-02-01 15:10:08,454 geoprocessing.zonal_statistics(1378) DEBUG unset_fids: 0 of 5 
2022-02-01 15:10:08,455 geoprocessing.zonal_statistics(1381) DEBUG gt [ 2.4898066e+05  1.5000000e+01  0.0000000e+00  9.9418670e+06
  0.0000000e+00 -1.5000000e+01] for ('/Users/raphaelle/Documents/InVEST/NCP101_SDR_output/sed_deposition_Gura.tif', 1)
2022-02-01 15:10:08,456 geoprocessing.zonal_statistics(1460) DEBUG remaining unset_fids: 0 of 5 
2022-02-01 15:10:08,457 geoprocessing.zonal_statistics(1468) INFO all done processing polygon sets for watershed_results_sdr_Gura.shp
2022-02-01 15:10:08,463 utils._log_gdal_errors(80) WARNING [errno 1] Value 2200.76999999999998 of field area_ha of feature 0 not successfully written. Possibly due to too larger number with respect to field width
2022-02-01 15:10:08,464 utils._log_gdal_errors(80) WARNING [errno 1] Value 918.383000000000038 of field area_ha of feature 1 not successfully written. Possibly due to too larger number with respect to field width
2022-02-01 15:10:08,465 utils._log_gdal_errors(80) WARNING [errno 1] Value 1149.70000000000005 of field area_ha of feature 2 not successfully written. Possibly due to too larger number with respect to field width
2022-02-01 15:10:08,467 utils._log_gdal_errors(80) WARNING [errno 1] Value 2413.98000000000002 of field area_ha of feature 3 not successfully written. Possibly due to too larger number with respect to field width
2022-02-01 15:10:08,468 utils._log_gdal_errors(80) WARNING [errno 1] Value 3958.80999999999995 of field area_ha of feature 4 not successfully written. Possibly due to too larger number with respect to field width
2022-02-01 15:10:08,475 model._logged_target(1655) INFO Execution finished
2022-02-01 15:10:08,477 utils.prepare_workspace(129) INFO Elapsed time: 18.53s