2020-10-12 22:25:03,425 utils.prepare_workspace(111) INFO Writing log messages to F:/INVEST/SDR\InVEST-Sediment-Delivery-Ratio-Model-(SDR)-log-2020-10-12--22_25_03.txt 2020-10-12 22:25:03,426 model._logged_target(1629) Level 100 Starting model with parameters: Arguments for InVEST natcap.invest.sdr.sdr 3.8.2: biophysical_table_path F:/INVEST/WaterYield/biophysical_table_sdr2.csv dem_path F:/INVEST/WaterYield/DEM.tif drainage_path erodibility_path F:/INVEST/WaterYield/SDR/Soil_K.tif erosivity_path F:/INVEST/WaterYield/SDR/R2018.tif ic_0_param 0.5 k_param 2 lulc_path F:/INVEST/WaterYield/LU2018.tif n_workers -1 results_suffix 2018_NSH2 sdr_max 0.8 threshold_flow_accumulation 1000 watersheds_path F:/INVEST/WaterYield/Watershed.shp workspace_dir F:/INVEST/SDR 2020-10-12 22:26:29,556 Task._call(1069) INFO 0.0% complete 2020-10-12 22:26:35,055 Task._call(1069) INFO 13.5% complete 2020-10-12 22:26:40,999 Task._call(1069) INFO 18.1% complete 2020-10-12 22:26:48,689 Task._call(1069) INFO 22.6% complete 2020-10-12 22:26:54,002 Task._call(1069) INFO 31.6% complete 2020-10-12 22:27:00,091 Task._call(1069) INFO 45.1% complete 2020-10-12 22:27:06,491 Task._call(1069) INFO 49.7% complete 2020-10-12 22:27:12,023 Task._call(1069) INFO 54.2% complete 2020-10-12 22:27:18,243 Task._call(1069) INFO 58.7% complete 2020-10-12 22:27:24,061 Task._call(1069) INFO 67.7% complete 2020-10-12 22:27:30,077 Task._call(1069) INFO 76.7% complete 2020-10-12 22:27:33,818 Task._call(1069) INFO 100.0% complete 2020-10-12 22:27:43,797 geoprocessing.raster_calculator(375) INFO starting stats_worker 2020-10-12 22:27:43,798 geoprocessing.raster_calculator(381) INFO started stats_worker 2020-10-12 22:27:47,272 geoprocessing.raster_calculator(454) INFO 100.0%% complete 2020-10-12 22:27:47,272 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate 2020-10-12 22:27:47,272 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result. 2020-10-12 22:27:47,272 threading.run(870) DEBUG payload is None, terminating 2020-10-12 22:27:49,252 geoprocessing.raster_calculator(375) INFO starting stats_worker 2020-10-12 22:27:49,253 geoprocessing.raster_calculator(381) INFO started stats_worker 2020-10-12 22:27:53,574 geoprocessing.raster_calculator(454) INFO 100.0%% complete 2020-10-12 22:27:53,575 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate 2020-10-12 22:27:53,575 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result. 2020-10-12 22:27:53,575 threading.run(870) DEBUG payload is None, terminating 2020-10-12 22:27:55,514 geoprocessing.raster_calculator(375) INFO starting stats_worker 2020-10-12 22:27:55,515 geoprocessing.raster_calculator(381) INFO started stats_worker 2020-10-12 22:27:59,898 geoprocessing.raster_calculator(454) INFO 100.0%% complete 2020-10-12 22:27:59,898 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate 2020-10-12 22:27:59,898 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result. 2020-10-12 22:27:59,898 threading.run(870) DEBUG payload is None, terminating 2020-10-12 22:28:01,548 Task._call(1069) INFO calculate sediment deposition 2020-10-12 22:28:01,585 Task._call(1069) INFO 0.00% complete 2020-10-12 22:28:01,588 Task._call(1069) INFO 0.13% complete 2020-10-12 22:28:01,592 Task._call(1069) INFO 0.25% complete 2020-10-12 22:28:01,595 Task._call(1069) INFO 0.38% complete 2020-10-12 22:28:01,599 Task._call(1069) INFO 0.51% complete 2020-10-12 22:28:01,602 Task._call(1069) INFO 0.64% complete 2020-10-12 22:28:01,605 Task._call(1069) INFO 0.77% complete 2020-10-12 22:28:01,609 Task._call(1069) INFO 0.89% complete 2020-10-12 22:28:01,612 Task._call(1069) INFO 1.02% complete 2020-10-12 22:28:01,616 Task._call(1069) INFO 1.15% complete 2020-10-12 22:28:01,620 Task._call(1069) INFO 1.28% complete 2020-10-12 22:28:01,623 Task._call(1069) INFO 1.40% complete 2020-10-12 22:28:01,627 Task._call(1069) INFO 1.53% complete 2020-10-12 22:28:01,681 Task._call(1069) INFO 1.66% complete 2020-10-12 22:28:01,741 Task._call(1069) INFO 1.79% complete 2020-10-12 22:28:01,773 Task._call(1069) INFO 1.91% complete 2020-10-12 22:28:01,777 Task._call(1069) INFO 2.04% complete 2020-10-12 22:28:01,781 Task._call(1069) INFO 2.17% complete 2020-10-12 22:28:01,785 Task._call(1069) INFO 2.30% complete 2020-10-12 22:28:01,789 Task._call(1069) INFO 2.42% complete 2020-10-12 22:28:01,793 Task._call(1069) INFO 2.55% complete 2020-10-12 22:28:01,796 Task._call(1069) INFO 2.68% complete 2020-10-12 22:28:01,801 Task._call(1069) INFO 2.81% complete 2020-10-12 22:28:01,805 Task._call(1069) INFO 2.93% complete 2020-10-12 22:28:01,809 Task._call(1069) INFO 3.06% complete 2020-10-12 22:28:01,813 Task._call(1069) INFO 3.19% complete 2020-10-12 22:28:01,817 Task._call(1069) INFO 3.32% complete 2020-10-12 22:28:01,821 Task._call(1069) INFO 3.45% complete 2020-10-12 22:28:01,825 Task._call(1069) INFO 3.57% complete 2020-10-12 22:28:01,829 Task._call(1069) INFO 3.70% complete 2020-10-12 22:28:01,834 Task._call(1069) INFO 3.83% complete 2020-10-12 22:28:01,838 Task._call(1069) INFO 3.96% complete 2020-10-12 22:28:01,842 Task._call(1069) INFO 4.08% complete 2020-10-12 22:28:01,846 Task._call(1069) INFO 4.21% complete 2020-10-12 22:28:01,851 Task._call(1069) INFO 4.34% complete 2020-10-12 22:28:01,855 Task._call(1069) INFO 4.47% complete 2020-10-12 22:28:01,857 Task._call(1069) INFO 4.49% complete 2020-10-12 22:28:01,861 Task._call(1069) INFO 0.25% complete 2020-10-12 22:28:01,864 Task._call(1069) INFO 0.51% complete 2020-10-12 22:28:01,869 Task._call(1069) INFO 0.77% complete 2020-10-12 22:28:01,873 Task._call(1069) INFO 1.02% complete 2020-10-12 22:28:01,877 Task._call(1069) INFO 1.28% complete 2020-10-12 22:28:01,881 Task._call(1069) INFO 1.53% complete 2020-10-12 22:28:01,886 Task._call(1069) INFO 1.79% complete 2020-10-12 22:28:01,890 Task._call(1069) INFO 2.04% complete 2020-10-12 22:28:01,894 Task._call(1069) INFO 2.30% complete 2020-10-12 22:28:01,899 Task._call(1069) INFO 2.56% complete 2020-10-12 22:28:01,929 Task._call(1069) INFO 2.81% complete 2020-10-12 22:28:01,954 Task._call(1069) INFO 3.07% complete 2020-10-12 22:28:02,069 Task._call(1069) INFO 3.32% complete 2020-10-12 22:28:02,183 Task._call(1069) INFO 3.58% complete 2020-10-12 22:28:02,272 Task._call(1069) INFO 3.83% complete 2020-10-12 22:28:02,375 Task._call(1069) INFO 4.09% complete 2020-10-12 22:28:02,397 Task._call(1069) INFO 4.35% complete 2020-10-12 22:28:02,401 Task._call(1069) INFO 4.60% complete 2020-10-12 22:28:02,406 Task._call(1069) INFO 4.86% complete 2020-10-12 22:28:02,450 Task._call(1069) INFO 5.11% complete 2020-10-12 22:28:02,548 Task._call(1069) INFO 5.37% complete 2020-10-12 22:28:02,998 Task._call(1069) INFO 5.63% complete 2020-10-12 22:28:03,344 Task._call(1069) INFO 5.88% complete 2020-10-12 22:28:03,429 Task._call(1069) INFO 6.14% complete 2020-10-12 22:28:03,463 Task._call(1069) INFO 6.39% complete 2020-10-12 22:28:03,483 Task._call(1069) INFO 6.65% complete 2020-10-12 22:28:03,488 Task._call(1069) INFO 6.90% complete 2020-10-12 22:28:03,492 Task._call(1069) INFO 7.16% complete 2020-10-12 22:28:03,496 Task._call(1069) INFO 7.42% complete 2020-10-12 22:28:03,501 Task._call(1069) INFO 7.67% complete 2020-10-12 22:28:03,505 Task._call(1069) INFO 7.93% complete 2020-10-12 22:28:03,509 Task._call(1069) INFO 8.18% complete 2020-10-12 22:28:03,513 Task._call(1069) INFO 8.44% complete 2020-10-12 22:28:03,517 Task._call(1069) INFO 8.69% complete 2020-10-12 22:28:03,522 Task._call(1069) INFO 8.95% complete 2020-10-12 22:28:03,524 Task._call(1069) INFO 9.01% complete 2020-10-12 22:28:03,528 Task._call(1069) INFO 0.38% complete 2020-10-12 22:28:03,532 Task._call(1069) INFO 0.77% complete 2020-10-12 22:28:03,537 Task._call(1069) INFO 1.15% complete 2020-10-12 22:28:03,541 Task._call(1069) INFO 1.53% complete 2020-10-12 22:28:03,545 Task._call(1069) INFO 1.92% complete 2020-10-12 22:28:03,549 Task._call(1069) INFO 2.30% complete 2020-10-12 22:28:03,554 Task._call(1069) INFO 2.69% complete 2020-10-12 22:28:03,559 Task._call(1069) INFO 3.07% complete 2020-10-12 22:28:03,563 Task._call(1069) INFO 3.45% complete 2020-10-12 22:28:03,586 Task._call(1069) INFO 3.84% complete 2020-10-12 22:28:03,687 Task._call(1069) INFO 4.22% complete 2020-10-12 22:28:03,800 Task._call(1069) INFO 4.60% complete 2020-10-12 22:28:04,379 Task._call(1069) INFO 4.99% complete 2020-10-12 22:28:04,503 Task._call(1069) INFO 5.37% complete 2020-10-12 22:28:04,601 Task._call(1069) INFO 5.76% complete 2020-10-12 22:28:04,701 Task._call(1069) INFO 6.14% complete 2020-10-12 22:28:04,784 Task._call(1069) INFO 6.52% complete 2020-10-12 22:28:04,839 Task._call(1069) INFO 6.91% complete 2020-10-12 22:28:04,897 Task._call(1069) INFO 7.29% complete 2020-10-12 22:28:05,003 Task._call(1069) INFO 7.68% complete 2020-10-12 22:28:05,122 Task._call(1069) INFO 8.06% complete 2020-10-12 22:28:05,230 Task._call(1069) INFO 8.44% complete 2020-10-12 22:28:05,392 Task._call(1069) INFO 8.83% complete 2020-10-12 22:28:05,497 Task._call(1069) INFO 9.21% complete 2020-10-12 22:28:05,619 Task._call(1069) INFO 9.59% complete 2020-10-12 22:28:05,724 Task._call(1069) INFO 9.98% complete 2020-10-12 22:28:05,777 Task._call(1069) INFO 10.36% complete 2020-10-12 22:28:05,797 Task._call(1069) INFO 10.75% complete 2020-10-12 22:28:05,849 Task._call(1069) INFO 11.13% complete 2020-10-12 22:28:05,872 Task._call(1069) INFO 11.51% complete 2020-10-12 22:28:05,928 Task._call(1069) INFO 11.90% complete 2020-10-12 22:28:06,025 Task._call(1069) INFO 12.28% complete 2020-10-12 22:28:06,047 Task._call(1069) INFO 12.67% complete 2020-10-12 22:28:06,051 Task._call(1069) INFO 13.05% complete 2020-10-12 22:28:06,055 Task._call(1069) INFO 13.43% complete 2020-10-12 22:28:06,057 Task._call(1069) INFO 13.52% complete 2020-10-12 22:28:06,062 Task._call(1069) INFO 0.51% complete 2020-10-12 22:28:06,066 Task._call(1069) INFO 1.02% complete 2020-10-12 22:28:06,070 Task._call(1069) INFO 1.53% complete 2020-10-12 22:28:06,075 Task._call(1069) INFO 2.05% complete 2020-10-12 22:28:06,079 Task._call(1069) INFO 2.56% complete 2020-10-12 22:28:06,083 Task._call(1069) INFO 3.07% complete 2020-10-12 22:28:06,087 Task._call(1069) INFO 3.58% complete 2020-10-12 22:28:06,154 Task._call(1069) INFO 4.09% complete 2020-10-12 22:28:06,217 Task._call(1069) INFO 4.61% complete 2020-10-12 22:28:06,331 Task._call(1069) INFO 5.12% complete 2020-10-12 22:28:06,475 Task._call(1069) INFO 5.63% complete 2020-10-12 22:28:06,754 Task._call(1069) INFO 6.14% complete 2020-10-12 22:28:06,888 Task._call(1069) INFO 6.65% complete 2020-10-12 22:28:07,045 Task._call(1069) INFO 7.17% complete 2020-10-12 22:28:07,179 Task._call(1069) INFO 7.68% complete 2020-10-12 22:28:07,307 Task._call(1069) INFO 8.19% complete 2020-10-12 22:28:07,500 Task._call(1069) INFO 8.70% complete 2020-10-12 22:28:07,658 Task._call(1069) INFO 9.21% complete 2020-10-12 22:28:07,862 Task._call(1069) INFO 9.73% complete 2020-10-12 22:28:08,090 Task._call(1069) INFO 10.24% complete 2020-10-12 22:28:08,651 Task._call(1069) INFO 10.75% complete 2020-10-12 22:28:08,857 Task._call(1069) INFO 11.26% complete 2020-10-12 22:28:09,021 Task._call(1069) INFO 11.77% complete 2020-10-12 22:28:09,215 Task._call(1069) INFO 12.29% complete 2020-10-12 22:28:10,350 Task._call(1069) INFO 12.80% complete 2020-10-12 22:28:10,570 Task._call(1069) INFO 13.31% complete 2020-10-12 22:28:10,981 Task._call(1069) INFO 13.82% complete 2020-10-12 22:28:11,352 Task._call(1069) INFO 14.33% complete 2020-10-12 22:28:11,530 Task._call(1069) INFO 14.85% complete 2020-10-12 22:28:11,715 Task._call(1069) INFO 15.36% complete 2020-10-12 22:28:11,907 Task._call(1069) INFO 15.87% complete 2020-10-12 22:28:12,207 Task._call(1069) INFO 16.38% complete 2020-10-12 22:28:12,358 Task._call(1069) INFO 16.89% complete 2020-10-12 22:28:12,387 Task._call(1069) INFO 17.40% complete 2020-10-12 22:28:12,391 Task._call(1069) INFO 17.92% complete 2020-10-12 22:28:12,393 Task._call(1069) INFO 18.03% complete 2020-10-12 22:28:12,397 Task._call(1069) INFO 0.64% complete 2020-10-12 22:28:12,402 Task._call(1069) INFO 1.28% complete 2020-10-12 22:28:12,406 Task._call(1069) INFO 1.92% complete 2020-10-12 22:28:12,410 Task._call(1069) INFO 2.56% complete 2020-10-12 22:28:12,415 Task._call(1069) INFO 3.20% complete 2020-10-12 22:28:12,419 Task._call(1069) INFO 3.84% complete 2020-10-12 22:28:12,553 Task._call(1069) INFO 4.48% complete 2020-10-12 22:28:12,740 Task._call(1069) INFO 5.12% complete 2020-10-12 22:28:12,923 Task._call(1069) INFO 5.76% complete 2020-10-12 22:28:13,170 Task._call(1069) INFO 6.40% complete 2020-10-12 22:28:13,325 Task._call(1069) INFO 7.04% complete 2020-10-12 22:28:14,026 Task._call(1069) INFO 7.68% complete 2020-10-12 22:28:14,223 Task._call(1069) INFO 8.32% complete 2020-10-12 22:28:14,577 Task._call(1069) INFO 8.96% complete 2020-10-12 22:28:14,718 Task._call(1069) INFO 9.60% complete 2020-10-12 22:28:15,292 Task._call(1069) INFO 10.24% complete 2020-10-12 22:28:15,511 Task._call(1069) INFO 10.88% complete 2020-10-12 22:28:15,831 Task._call(1069) INFO 11.52% complete 2020-10-12 22:28:16,081 Task._call(1069) INFO 12.16% complete 2020-10-12 22:28:16,757 Task._call(1069) INFO 12.80% complete 2020-10-12 22:28:16,936 Task._call(1069) INFO 13.44% complete 2020-10-12 22:28:17,162 Task._call(1069) INFO 14.08% complete 2020-10-12 22:28:17,368 Task._call(1069) INFO 14.72% complete 2020-10-12 22:28:17,516 Task._call(1069) INFO 15.36% complete 2020-10-12 22:28:18,543 Task._call(1069) INFO 16.00% complete 2020-10-12 22:28:19,052 Task._call(1069) INFO 16.64% complete 2020-10-12 22:28:19,771 Task._call(1069) INFO 17.28% complete 2020-10-12 22:28:19,942 Task._call(1069) INFO 17.92% complete 2020-10-12 22:28:20,098 Task._call(1069) INFO 18.56% complete 2020-10-12 22:28:20,325 Task._call(1069) INFO 19.20% complete 2020-10-12 22:28:20,466 Task._call(1069) INFO 19.84% complete 2020-10-12 22:28:20,672 Task._call(1069) INFO 20.48% complete 2020-10-12 22:28:20,796 Task._call(1069) INFO 21.12% complete 2020-10-12 22:28:20,800 Task._call(1069) INFO 21.76% complete 2020-10-12 22:28:20,804 Task._call(1069) INFO 22.40% complete 2020-10-12 22:28:20,806 Task._call(1069) INFO 22.54% complete 2020-10-12 22:28:20,810 Task._call(1069) INFO 0.77% complete 2020-10-12 22:28:20,815 Task._call(1069) INFO 1.53% complete 2020-10-12 22:28:20,820 Task._call(1069) INFO 2.30% complete 2020-10-12 22:28:20,824 Task._call(1069) INFO 3.07% complete 2020-10-12 22:28:20,827 Task._call(1069) INFO 3.84% complete 2020-10-12 22:28:20,831 Task._call(1069) INFO 4.61% complete 2020-10-12 22:28:21,002 Task._call(1069) INFO 5.37% complete 2020-10-12 22:28:21,208 Task._call(1069) INFO 6.14% complete 2020-10-12 22:28:21,391 Task._call(1069) INFO 6.91% complete 2020-10-12 22:28:21,544 Task._call(1069) INFO 7.68% complete 2020-10-12 22:28:22,113 Task._call(1069) INFO 8.45% complete 2020-10-12 22:28:22,346 Task._call(1069) INFO 9.22% complete 2020-10-12 22:28:22,879 Task._call(1069) INFO 9.98% complete 2020-10-12 22:28:23,181 Task._call(1069) INFO 10.75% complete 2020-10-12 22:28:23,387 Task._call(1069) INFO 11.52% complete 2020-10-12 22:28:23,531 Task._call(1069) INFO 12.29% complete 2020-10-12 22:28:23,604 Task._call(1069) INFO 13.06% complete 2020-10-12 22:28:23,789 Task._call(1069) INFO 13.82% complete 2020-10-12 22:28:23,927 Task._call(1069) INFO 14.59% complete 2020-10-12 22:28:24,154 Task._call(1069) INFO 15.36% complete 2020-10-12 22:28:24,418 Task._call(1069) INFO 16.13% complete 2020-10-12 22:28:24,664 Task._call(1069) INFO 16.90% complete 2020-10-12 22:28:24,851 Task._call(1069) INFO 17.67% complete 2020-10-12 22:28:25,034 Task._call(1069) INFO 18.43% complete 2020-10-12 22:28:25,262 Task._call(1069) INFO 19.20% complete 2020-10-12 22:28:25,426 Task._call(1069) INFO 19.97% complete 2020-10-12 22:28:25,604 Task._call(1069) INFO 20.74% complete 2020-10-12 22:28:25,795 Task._call(1069) INFO 21.51% complete 2020-10-12 22:28:26,019 Task._call(1069) INFO 22.27% complete 2020-10-12 22:28:26,192 Task._call(1069) INFO 23.04% complete 2020-10-12 22:28:26,391 Task._call(1069) INFO 23.81% complete 2020-10-12 22:28:26,630 Task._call(1069) INFO 24.58% complete 2020-10-12 22:28:26,719 Task._call(1069) INFO 25.35% complete 2020-10-12 22:28:26,723 Task._call(1069) INFO 26.12% complete 2020-10-12 22:28:26,727 Task._call(1069) INFO 26.88% complete 2020-10-12 22:28:26,729 Task._call(1069) INFO 27.05% complete 2020-10-12 22:28:26,734 Task._call(1069) INFO 0.89% complete 2020-10-12 22:28:26,738 Task._call(1069) INFO 1.79% complete 2020-10-12 22:28:26,742 Task._call(1069) INFO 2.69% complete 2020-10-12 22:28:26,747 Task._call(1069) INFO 3.58% complete 2020-10-12 22:28:26,751 Task._call(1069) INFO 4.48% complete 2020-10-12 22:28:26,835 Task._call(1069) INFO 5.37% complete 2020-10-12 22:28:27,068 Task._call(1069) INFO 6.27% complete 2020-10-12 22:28:27,688 Task._call(1069) INFO 7.17% complete 2020-10-12 22:28:27,974 Task._call(1069) INFO 8.06% complete 2020-10-12 22:28:28,268 Task._call(1069) INFO 8.96% complete 2020-10-12 22:28:28,889 Task._call(1069) INFO 9.86% complete 2020-10-12 22:28:29,041 Task._call(1069) INFO 10.75% complete 2020-10-12 22:28:29,314 Task._call(1069) INFO 11.65% complete 2020-10-12 22:28:29,528 Task._call(1069) INFO 12.54% complete 2020-10-12 22:28:29,692 Task._call(1069) INFO 13.44% complete 2020-10-12 22:28:30,709 Task._call(1069) INFO 14.34% complete 2020-10-12 22:28:30,833 Task._call(1069) INFO 15.23% complete 2020-10-12 22:28:30,955 Task._call(1069) INFO 16.13% complete 2020-10-12 22:28:31,102 Task._call(1069) INFO 17.03% complete 2020-10-12 22:28:31,645 Task._call(1069) INFO 17.92% complete 2020-10-12 22:28:31,838 Task._call(1069) INFO 18.82% complete 2020-10-12 22:28:32,172 Task._call(1069) INFO 19.72% complete 2020-10-12 22:28:32,351 Task._call(1069) INFO 20.61% complete 2020-10-12 22:28:32,556 Task._call(1069) INFO 21.51% complete 2020-10-12 22:28:32,740 Task._call(1069) INFO 22.40% complete 2020-10-12 22:28:33,398 Task._call(1069) INFO 23.30% complete 2020-10-12 22:28:33,568 Task._call(1069) INFO 24.20% complete 2020-10-12 22:28:33,806 Task._call(1069) INFO 25.09% complete 2020-10-12 22:28:34,155 Task._call(1069) INFO 25.99% complete 2020-10-12 22:28:34,334 Task._call(1069) INFO 26.89% complete 2020-10-12 22:28:34,909 Task._call(1069) INFO 27.78% complete 2020-10-12 22:28:35,001 Task._call(1069) INFO 28.68% complete 2020-10-12 22:28:35,144 Task._call(1069) INFO 29.57% complete 2020-10-12 22:28:35,149 Task._call(1069) INFO 30.47% complete 2020-10-12 22:28:35,153 Task._call(1069) INFO 31.37% complete 2020-10-12 22:28:35,155 Task._call(1069) INFO 31.57% complete 2020-10-12 22:28:35,160 Task._call(1069) INFO 1.02% complete 2020-10-12 22:28:35,164 Task._call(1069) INFO 2.04% complete 2020-10-12 22:28:35,168 Task._call(1069) INFO 3.07% complete 2020-10-12 22:28:35,222 Task._call(1069) INFO 4.09% complete 2020-10-12 22:28:36,146 Task._call(1069) INFO 5.12% complete 2020-10-12 22:28:36,331 Task._call(1069) INFO 6.14% complete 2020-10-12 22:28:36,597 Task._call(1069) INFO 7.17% complete 2020-10-12 22:28:36,790 Task._call(1069) INFO 8.19% complete 2020-10-12 22:28:36,978 Task._call(1069) INFO 9.22% complete 2020-10-12 22:28:37,128 Task._call(1069) INFO 10.24% complete 2020-10-12 22:28:37,315 Task._call(1069) INFO 11.26% complete 2020-10-12 22:28:37,536 Task._call(1069) INFO 12.29% complete 2020-10-12 22:28:37,694 Task._call(1069) INFO 13.31% complete 2020-10-12 22:28:37,907 Task._call(1069) INFO 14.34% complete 2020-10-12 22:28:38,855 Task._call(1069) INFO 15.36% complete 2020-10-12 22:28:39,092 Task._call(1069) INFO 16.39% complete 2020-10-12 22:28:39,254 Task._call(1069) INFO 17.41% complete 2020-10-12 22:28:40,130 Task._call(1069) INFO 18.44% complete 2020-10-12 22:28:40,327 Task._call(1069) INFO 19.46% complete 2020-10-12 22:28:40,480 Task._call(1069) INFO 20.48% complete 2020-10-12 22:28:40,703 Task._call(1069) INFO 21.51% complete 2020-10-12 22:28:40,983 Task._call(1069) INFO 22.53% complete 2020-10-12 22:28:41,130 Task._call(1069) INFO 23.56% complete 2020-10-12 22:28:41,331 Task._call(1069) INFO 24.58% complete 2020-10-12 22:28:41,514 Task._call(1069) INFO 25.61% complete 2020-10-12 22:28:41,726 Task._call(1069) INFO 26.63% complete 2020-10-12 22:28:41,878 Task._call(1069) INFO 27.66% complete 2020-10-12 22:28:42,073 Task._call(1069) INFO 28.68% complete 2020-10-12 22:28:42,270 Task._call(1069) INFO 29.70% complete 2020-10-12 22:28:42,453 Task._call(1069) INFO 30.73% complete 2020-10-12 22:28:43,053 Task._call(1069) INFO 31.75% complete 2020-10-12 22:28:43,058 Task._call(1069) INFO 32.78% complete 2020-10-12 22:28:43,062 Task._call(1069) INFO 33.80% complete 2020-10-12 22:28:43,066 Task._call(1069) INFO 34.83% complete 2020-10-12 22:28:43,070 Task._call(1069) INFO 35.85% complete 2020-10-12 22:28:43,072 Task._call(1069) INFO 36.08% complete 2020-10-12 22:28:43,076 Task._call(1069) INFO 1.15% complete 2020-10-12 22:28:43,152 Task._call(1069) INFO 2.30% complete 2020-10-12 22:28:43,251 Task._call(1069) INFO 3.45% complete 2020-10-12 22:28:43,414 Task._call(1069) INFO 4.61% complete 2020-10-12 22:28:43,623 Task._call(1069) INFO 5.76% complete 2020-10-12 22:28:43,796 Task._call(1069) INFO 6.91% complete 2020-10-12 22:28:44,011 Task._call(1069) INFO 8.06% complete 2020-10-12 22:28:47,399 Task._call(1069) INFO 9.22% complete 2020-10-12 22:28:47,575 Task._call(1069) INFO 10.37% complete 2020-10-12 22:28:47,786 Task._call(1069) INFO 11.52% complete 2020-10-12 22:28:48,011 Task._call(1069) INFO 12.67% complete 2020-10-12 22:28:48,190 Task._call(1069) INFO 13.83% complete 2020-10-12 22:28:48,371 Task._call(1069) INFO 14.98% complete 2020-10-12 22:28:48,563 Task._call(1069) INFO 16.13% complete 2020-10-12 22:28:48,764 Task._call(1069) INFO 17.28% complete 2020-10-12 22:28:48,911 Task._call(1069) INFO 18.44% complete 2020-10-12 22:28:49,096 Task._call(1069) INFO 19.59% complete 2020-10-12 22:28:50,103 Task._call(1069) INFO 20.74% complete 2020-10-12 22:28:50,253 Task._call(1069) INFO 21.89% complete 2020-10-12 22:28:50,465 Task._call(1069) INFO 23.05% complete 2020-10-12 22:28:50,657 Task._call(1069) INFO 24.20% complete 2020-10-12 22:28:50,870 Task._call(1069) INFO 25.35% complete 2020-10-12 22:28:51,070 Task._call(1069) INFO 26.50% complete 2020-10-12 22:28:51,253 Task._call(1069) INFO 27.66% complete 2020-10-12 22:28:51,473 Task._call(1069) INFO 28.81% complete 2020-10-12 22:28:51,695 Task._call(1069) INFO 29.96% complete 2020-10-12 22:28:51,919 Task._call(1069) INFO 31.11% complete 2020-10-12 22:28:52,125 Task._call(1069) INFO 32.27% complete 2020-10-12 22:28:52,292 Task._call(1069) INFO 33.42% complete 2020-10-12 22:28:52,722 Task._call(1069) INFO 34.57% complete 2020-10-12 22:28:52,759 Task._call(1069) INFO 35.72% complete 2020-10-12 22:28:52,764 Task._call(1069) INFO 36.88% complete 2020-10-12 22:28:52,768 Task._call(1069) INFO 38.03% complete 2020-10-12 22:28:52,773 Task._call(1069) INFO 39.18% complete 2020-10-12 22:28:52,777 Task._call(1069) INFO 40.33% complete 2020-10-12 22:28:52,779 Task._call(1069) INFO 40.59% complete 2020-10-12 22:28:52,783 Task._call(1069) INFO 1.28% complete 2020-10-12 22:28:52,921 Task._call(1069) INFO 2.56% complete 2020-10-12 22:28:53,131 Task._call(1069) INFO 3.84% complete 2020-10-12 22:28:53,300 Task._call(1069) INFO 5.12% complete 2020-10-12 22:28:53,496 Task._call(1069) INFO 6.40% complete 2020-10-12 22:28:53,728 Task._call(1069) INFO 7.68% complete 2020-10-12 22:28:53,891 Task._call(1069) INFO 8.96% complete 2020-10-12 22:28:54,085 Task._call(1069) INFO 10.24% complete 2020-10-12 22:28:54,245 Task._call(1069) INFO 11.52% complete 2020-10-12 22:28:55,154 Task._call(1069) INFO 12.80% complete 2020-10-12 22:28:55,352 Task._call(1069) INFO 14.08% complete 2020-10-12 22:28:55,553 Task._call(1069) INFO 15.36% complete 2020-10-12 22:28:57,255 Task._call(1069) INFO 16.64% complete 2020-10-12 22:28:57,411 Task._call(1069) INFO 17.92% complete 2020-10-12 22:28:57,680 Task._call(1069) INFO 19.20% complete 2020-10-12 22:28:57,866 Task._call(1069) INFO 20.49% complete 2020-10-12 22:28:58,047 Task._call(1069) INFO 21.77% complete 2020-10-12 22:28:58,262 Task._call(1069) INFO 23.05% complete 2020-10-12 22:28:59,435 Task._call(1069) INFO 24.33% complete 2020-10-12 22:28:59,644 Task._call(1069) INFO 25.61% complete 2020-10-12 22:29:00,498 Task._call(1069) INFO 26.89% complete 2020-10-12 22:29:00,648 Task._call(1069) INFO 28.17% complete 2020-10-12 22:29:00,849 Task._call(1069) INFO 29.45% complete 2020-10-12 22:29:01,193 Task._call(1069) INFO 30.73% complete 2020-10-12 22:29:01,383 Task._call(1069) INFO 32.01% complete 2020-10-12 22:29:01,603 Task._call(1069) INFO 33.29% complete 2020-10-12 22:29:02,281 Task._call(1069) INFO 34.57% complete 2020-10-12 22:29:02,524 Task._call(1069) INFO 35.85% complete 2020-10-12 22:29:02,717 Task._call(1069) INFO 37.13% complete 2020-10-12 22:29:02,903 Task._call(1069) INFO 38.41% complete 2020-10-12 22:29:03,069 Task._call(1069) INFO 39.70% complete 2020-10-12 22:29:03,099 Task._call(1069) INFO 40.98% complete 2020-10-12 22:29:03,103 Task._call(1069) INFO 42.26% complete 2020-10-12 22:29:03,107 Task._call(1069) INFO 43.54% complete 2020-10-12 22:29:03,111 Task._call(1069) INFO 44.82% complete 2020-10-12 22:29:03,113 Task._call(1069) INFO 45.10% complete 2020-10-12 22:29:03,207 Task._call(1069) INFO 1.40% complete 2020-10-12 22:29:03,394 Task._call(1069) INFO 2.81% complete 2020-10-12 22:29:03,785 Task._call(1069) INFO 4.22% complete 2020-10-12 22:29:05,583 Task._call(1069) INFO 5.63% complete 2020-10-12 22:29:05,749 Task._call(1069) INFO 7.04% complete 2020-10-12 22:29:05,920 Task._call(1069) INFO 8.45% complete 2020-10-12 22:29:06,098 Task._call(1069) INFO 9.86% complete 2020-10-12 22:29:06,281 Task._call(1069) INFO 11.26% complete 2020-10-12 22:29:06,502 Task._call(1069) INFO 12.67% complete 2020-10-12 22:29:07,619 Task._call(1069) INFO 14.08% complete 2020-10-12 22:29:07,855 Task._call(1069) INFO 15.49% complete 2020-10-12 22:29:08,032 Task._call(1069) INFO 16.90% complete 2020-10-12 22:29:08,378 Task._call(1069) INFO 18.31% complete 2020-10-12 22:29:08,603 Task._call(1069) INFO 19.72% complete 2020-10-12 22:29:08,881 Task._call(1069) INFO 21.13% complete 2020-10-12 22:29:09,074 Task._call(1069) INFO 22.53% complete 2020-10-12 22:29:09,281 Task._call(1069) INFO 23.94% complete 2020-10-12 22:29:11,462 Task._call(1069) INFO 25.35% complete 2020-10-12 22:29:11,628 Task._call(1069) INFO 26.76% complete 2020-10-12 22:29:11,801 Task._call(1069) INFO 28.17% complete 2020-10-12 22:29:11,939 Task._call(1069) INFO 29.58% complete 2020-10-12 22:29:12,614 Task._call(1069) INFO 30.99% complete 2020-10-12 22:29:12,807 Task._call(1069) INFO 32.40% complete 2020-10-12 22:29:12,971 Task._call(1069) INFO 33.81% complete 2020-10-12 22:29:13,150 Task._call(1069) INFO 35.21% complete 2020-10-12 22:29:13,378 Task._call(1069) INFO 36.62% complete 2020-10-12 22:29:13,561 Task._call(1069) INFO 38.03% complete 2020-10-12 22:29:13,752 Task._call(1069) INFO 39.44% complete 2020-10-12 22:29:13,999 Task._call(1069) INFO 40.85% complete 2020-10-12 22:29:14,212 Task._call(1069) INFO 42.26% complete 2020-10-12 22:29:14,348 Task._call(1069) INFO 43.67% complete 2020-10-12 22:29:14,654 Task._call(1069) INFO 45.08% complete 2020-10-12 22:29:14,658 Task._call(1069) INFO 46.48% complete 2020-10-12 22:29:14,663 Task._call(1069) INFO 47.89% complete 2020-10-12 22:29:14,667 Task._call(1069) INFO 49.30% complete 2020-10-12 22:29:14,669 Task._call(1069) INFO 49.62% complete 2020-10-12 22:29:14,880 Task._call(1069) INFO 1.53% complete 2020-10-12 22:29:15,079 Task._call(1069) INFO 3.07% complete 2020-10-12 22:29:15,551 Task._call(1069) INFO 4.60% complete 2020-10-12 22:29:15,759 Task._call(1069) INFO 6.14% complete 2020-10-12 22:29:15,943 Task._call(1069) INFO 7.68% complete 2020-10-12 22:29:17,322 Task._call(1069) INFO 9.22% complete 2020-10-12 22:29:18,126 Task._call(1069) INFO 10.75% complete 2020-10-12 22:29:18,410 Task._call(1069) INFO 12.29% complete 2020-10-12 22:29:18,564 Task._call(1069) INFO 13.83% complete 2020-10-12 22:29:18,789 Task._call(1069) INFO 15.36% complete 2020-10-12 22:29:18,980 Task._call(1069) INFO 16.90% complete 2020-10-12 22:29:19,156 Task._call(1069) INFO 18.44% complete 2020-10-12 22:29:19,385 Task._call(1069) INFO 19.97% complete 2020-10-12 22:29:19,694 Task._call(1069) INFO 21.51% complete 2020-10-12 22:29:19,915 Task._call(1069) INFO 23.05% complete 2020-10-12 22:29:20,082 Task._call(1069) INFO 24.58% complete 2020-10-12 22:29:21,027 Task._call(1069) INFO 26.12% complete 2020-10-12 22:29:21,217 Task._call(1069) INFO 27.66% complete 2020-10-12 22:29:21,431 Task._call(1069) INFO 29.20% complete 2020-10-12 22:29:21,580 Task._call(1069) INFO 30.73% complete 2020-10-12 22:29:21,850 Task._call(1069) INFO 32.27% complete 2020-10-12 22:29:21,974 Task._call(1069) INFO 33.81% complete 2020-10-12 22:29:22,205 Task._call(1069) INFO 35.34% complete 2020-10-12 22:29:22,445 Task._call(1069) INFO 36.88% complete 2020-10-12 22:29:22,642 Task._call(1069) INFO 38.42% complete 2020-10-12 22:29:22,841 Task._call(1069) INFO 39.95% complete 2020-10-12 22:29:24,272 Task._call(1069) INFO 41.49% complete 2020-10-12 22:29:24,440 Task._call(1069) INFO 43.03% complete 2020-10-12 22:29:24,664 Task._call(1069) INFO 44.56% complete 2020-10-12 22:29:26,361 Task._call(1069) INFO 46.10% complete 2020-10-12 22:29:27,186 Task._call(1069) INFO 47.64% complete 2020-10-12 22:29:27,287 Task._call(1069) INFO 49.17% complete 2020-10-12 22:29:27,294 Task._call(1069) INFO 50.71% complete 2020-10-12 22:29:27,298 Task._call(1069) INFO 52.25% complete 2020-10-12 22:29:27,302 Task._call(1069) INFO 53.79% complete 2020-10-12 22:29:27,304 Task._call(1069) INFO 54.13% complete 2020-10-12 22:29:27,540 Task._call(1069) INFO 1.66% complete 2020-10-12 22:29:27,738 Task._call(1069) INFO 3.32% complete 2020-10-12 22:29:29,032 Task._call(1069) INFO 4.99% complete 2020-10-12 22:29:29,231 Task._call(1069) INFO 6.65% complete 2020-10-12 22:29:29,427 Task._call(1069) INFO 8.32% complete 2020-10-12 22:29:29,615 Task._call(1069) INFO 9.98% complete 2020-10-12 22:29:29,818 Task._call(1069) INFO 11.65% complete 2020-10-12 22:29:29,973 Task._call(1069) INFO 13.31% complete 2020-10-12 22:29:30,163 Task._call(1069) INFO 14.98% complete 2020-10-12 22:29:31,416 Task._call(1069) INFO 16.64% complete 2020-10-12 22:29:31,598 Task._call(1069) INFO 18.31% complete 2020-10-12 22:29:31,783 Task._call(1069) INFO 19.97% complete 2020-10-12 22:29:31,985 Task._call(1069) INFO 21.64% complete 2020-10-12 22:29:33,763 Task._call(1069) INFO 23.30% complete 2020-10-12 22:29:33,932 Task._call(1069) INFO 24.97% complete 2020-10-12 22:29:34,114 Task._call(1069) INFO 26.63% complete 2020-10-12 22:29:34,300 Task._call(1069) INFO 28.30% complete 2020-10-12 22:29:34,470 Task._call(1069) INFO 29.96% complete 2020-10-12 22:29:34,748 Task._call(1069) INFO 31.63% complete 2020-10-12 22:29:34,915 Task._call(1069) INFO 33.29% complete 2020-10-12 22:29:35,799 Task._call(1069) INFO 34.96% complete 2020-10-12 22:29:35,914 Task._call(1069) INFO 36.62% complete 2020-10-12 22:29:36,060 Task._call(1069) INFO 38.29% complete 2020-10-12 22:29:36,350 Task._call(1069) INFO 39.95% complete 2020-10-12 22:29:36,526 Task._call(1069) INFO 41.62% complete 2020-10-12 22:29:36,704 Task._call(1069) INFO 43.28% complete 2020-10-12 22:29:38,573 Task._call(1069) INFO 44.95% complete 2020-10-12 22:29:38,798 Task._call(1069) INFO 46.61% complete 2020-10-12 22:29:38,958 Task._call(1069) INFO 48.28% complete 2020-10-12 22:29:39,160 Task._call(1069) INFO 49.94% complete 2020-10-12 22:29:39,390 Task._call(1069) INFO 51.61% complete 2020-10-12 22:29:39,585 Task._call(1069) INFO 53.27% complete 2020-10-12 22:29:39,672 Task._call(1069) INFO 54.94% complete 2020-10-12 22:29:39,676 Task._call(1069) INFO 56.60% complete 2020-10-12 22:29:39,680 Task._call(1069) INFO 58.27% complete 2020-10-12 22:29:39,682 Task._call(1069) INFO 58.64% complete 2020-10-12 22:29:39,883 Task._call(1069) INFO 1.79% complete 2020-10-12 22:29:40,155 Task._call(1069) INFO 3.58% complete 2020-10-12 22:29:41,176 Task._call(1069) INFO 5.37% complete 2020-10-12 22:29:42,584 Task._call(1069) INFO 7.17% complete 2020-10-12 22:29:42,835 Task._call(1069) INFO 8.96% complete 2020-10-12 22:29:42,994 Task._call(1069) INFO 10.75% complete 2020-10-12 22:29:43,228 Task._call(1069) INFO 12.54% complete 2020-10-12 22:29:43,394 Task._call(1069) INFO 14.34% complete 2020-10-12 22:29:43,763 Task._call(1069) INFO 16.13% complete 2020-10-12 22:29:43,941 Task._call(1069) INFO 17.92% complete 2020-10-12 22:29:44,150 Task._call(1069) INFO 19.72% complete 2020-10-12 22:29:44,300 Task._call(1069) INFO 21.51% complete 2020-10-12 22:29:44,480 Task._call(1069) INFO 23.30% complete 2020-10-12 22:29:44,653 Task._call(1069) INFO 25.10% complete 2020-10-12 22:29:44,881 Task._call(1069) INFO 26.89% complete 2020-10-12 22:29:45,102 Task._call(1069) INFO 28.68% complete 2020-10-12 22:29:45,293 Task._call(1069) INFO 30.48% complete 2020-10-12 22:29:45,520 Task._call(1069) INFO 32.27% complete 2020-10-12 22:29:45,745 Task._call(1069) INFO 34.06% complete 2020-10-12 22:29:45,933 Task._call(1069) INFO 35.86% complete 2020-10-12 22:29:46,095 Task._call(1069) INFO 37.65% complete 2020-10-12 22:29:46,277 Task._call(1069) INFO 39.44% complete 2020-10-12 22:29:46,361 Task._call(1069) INFO 41.23% complete 2020-10-12 22:29:46,825 Task._call(1069) INFO 43.03% complete 2020-10-12 22:29:47,029 Task._call(1069) INFO 44.82% complete 2020-10-12 22:29:47,235 Task._call(1069) INFO 46.61% complete 2020-10-12 22:29:47,584 Task._call(1069) INFO 48.41% complete 2020-10-12 22:29:47,764 Task._call(1069) INFO 50.20% complete 2020-10-12 22:29:47,976 Task._call(1069) INFO 51.99% complete 2020-10-12 22:29:48,194 Task._call(1069) INFO 53.79% complete 2020-10-12 22:29:48,353 Task._call(1069) INFO 55.58% complete 2020-10-12 22:29:48,574 Task._call(1069) INFO 57.37% complete 2020-10-12 22:29:48,681 Task._call(1069) INFO 59.17% complete 2020-10-12 22:29:48,709 Task._call(1069) INFO 60.96% complete 2020-10-12 22:29:48,713 Task._call(1069) INFO 62.75% complete 2020-10-12 22:29:48,715 Task._call(1069) INFO 63.15% complete 2020-10-12 22:29:48,808 Task._call(1069) INFO 1.91% complete 2020-10-12 22:29:48,883 Task._call(1069) INFO 3.83% complete 2020-10-12 22:29:48,978 Task._call(1069) INFO 5.76% complete 2020-10-12 22:29:49,125 Task._call(1069) INFO 7.68% complete 2020-10-12 22:29:49,281 Task._call(1069) INFO 9.60% complete 2020-10-12 22:29:51,163 Task._call(1069) INFO 11.52% complete 2020-10-12 22:29:52,517 Task._call(1069) INFO 13.44% complete 2020-10-12 22:29:52,669 Task._call(1069) INFO 15.36% complete 2020-10-12 22:29:52,888 Task._call(1069) INFO 17.28% complete 2020-10-12 22:29:53,067 Task._call(1069) INFO 19.20% complete 2020-10-12 22:29:53,242 Task._call(1069) INFO 21.13% complete 2020-10-12 22:29:53,527 Task._call(1069) INFO 23.05% complete 2020-10-12 22:29:53,698 Task._call(1069) INFO 24.97% complete 2020-10-12 22:29:53,890 Task._call(1069) INFO 26.89% complete 2020-10-12 22:29:55,008 Task._call(1069) INFO 28.81% complete 2020-10-12 22:29:55,176 Task._call(1069) INFO 30.73% complete 2020-10-12 22:29:57,023 Task._call(1069) INFO 32.65% complete 2020-10-12 22:29:57,348 Task._call(1069) INFO 34.57% complete 2020-10-12 22:29:57,494 Task._call(1069) INFO 36.50% complete 2020-10-12 22:29:57,642 Task._call(1069) INFO 38.42% complete 2020-10-12 22:29:57,873 Task._call(1069) INFO 40.34% complete 2020-10-12 22:29:58,066 Task._call(1069) INFO 42.26% complete 2020-10-12 22:29:58,233 Task._call(1069) INFO 44.18% complete 2020-10-12 22:29:58,341 Task._call(1069) INFO 46.10% complete 2020-10-12 22:29:58,510 Task._call(1069) INFO 48.02% complete 2020-10-12 22:29:58,735 Task._call(1069) INFO 49.94% complete 2020-10-12 22:29:58,907 Task._call(1069) INFO 51.87% complete 2020-10-12 22:29:59,109 Task._call(1069) INFO 53.79% complete 2020-10-12 22:29:59,308 Task._call(1069) INFO 55.71% complete 2020-10-12 22:29:59,497 Task._call(1069) INFO 57.63% complete 2020-10-12 22:29:59,672 Task._call(1069) INFO 59.55% complete 2020-10-12 22:29:59,904 Task._call(1069) INFO 61.47% complete 2020-10-12 22:29:59,979 Task._call(1069) INFO 63.39% complete 2020-10-12 22:29:59,984 Task._call(1069) INFO 65.31% complete 2020-10-12 22:29:59,988 Task._call(1069) INFO 67.24% complete 2020-10-12 22:29:59,990 Task._call(1069) INFO 67.66% complete 2020-10-12 22:29:59,994 Task._call(1069) INFO 2.04% complete 2020-10-12 22:29:59,998 Task._call(1069) INFO 4.09% complete 2020-10-12 22:30:00,003 Task._call(1069) INFO 6.14% complete 2020-10-12 22:30:00,125 Task._call(1069) INFO 8.19% complete 2020-10-12 22:30:00,517 Task._call(1069) INFO 10.24% complete 2020-10-12 22:30:00,682 Task._call(1069) INFO 12.29% complete 2020-10-12 22:30:00,980 Task._call(1069) INFO 14.34% complete 2020-10-12 22:30:01,221 Task._call(1069) INFO 16.39% complete 2020-10-12 22:30:01,422 Task._call(1069) INFO 18.44% complete 2020-10-12 22:30:01,866 Task._call(1069) INFO 20.49% complete 2020-10-12 22:30:02,066 Task._call(1069) INFO 22.53% complete 2020-10-12 22:30:03,081 Task._call(1069) INFO 24.58% complete 2020-10-12 22:30:03,932 Task._call(1069) INFO 26.63% complete 2020-10-12 22:30:04,090 Task._call(1069) INFO 28.68% complete 2020-10-12 22:30:04,250 Task._call(1069) INFO 30.73% complete 2020-10-12 22:30:04,509 Task._call(1069) INFO 32.78% complete 2020-10-12 22:30:04,668 Task._call(1069) INFO 34.83% complete 2020-10-12 22:30:04,917 Task._call(1069) INFO 36.88% complete 2020-10-12 22:30:05,093 Task._call(1069) INFO 38.93% complete 2020-10-12 22:30:05,381 Task._call(1069) INFO 40.98% complete 2020-10-12 22:30:05,429 Task._call(1069) INFO 43.03% complete 2020-10-12 22:30:05,493 Task._call(1069) INFO 45.08% complete 2020-10-12 22:30:05,589 Task._call(1069) INFO 47.13% complete 2020-10-12 22:30:05,632 Task._call(1069) INFO 49.18% complete 2020-10-12 22:30:05,783 Task._call(1069) INFO 51.23% complete 2020-10-12 22:30:05,958 Task._call(1069) INFO 53.28% complete 2020-10-12 22:30:06,127 Task._call(1069) INFO 55.32% complete 2020-10-12 22:30:06,340 Task._call(1069) INFO 57.37% complete 2020-10-12 22:30:06,543 Task._call(1069) INFO 59.42% complete 2020-10-12 22:30:06,771 Task._call(1069) INFO 61.47% complete 2020-10-12 22:30:06,973 Task._call(1069) INFO 63.52% complete 2020-10-12 22:30:07,155 Task._call(1069) INFO 65.57% complete 2020-10-12 22:30:07,384 Task._call(1069) INFO 67.62% complete 2020-10-12 22:30:07,452 Task._call(1069) INFO 69.67% complete 2020-10-12 22:30:07,456 Task._call(1069) INFO 71.72% complete 2020-10-12 22:30:07,458 Task._call(1069) INFO 72.18% complete 2020-10-12 22:30:07,462 Task._call(1069) INFO 2.17% complete 2020-10-12 22:30:07,467 Task._call(1069) INFO 4.35% complete 2020-10-12 22:30:07,471 Task._call(1069) INFO 6.52% complete 2020-10-12 22:30:07,475 Task._call(1069) INFO 8.70% complete 2020-10-12 22:30:07,605 Task._call(1069) INFO 10.88% complete 2020-10-12 22:30:07,724 Task._call(1069) INFO 13.06% complete 2020-10-12 22:30:07,903 Task._call(1069) INFO 15.23% complete 2020-10-12 22:30:08,107 Task._call(1069) INFO 17.41% complete 2020-10-12 22:30:08,299 Task._call(1069) INFO 19.59% complete 2020-10-12 22:30:08,465 Task._call(1069) INFO 21.77% complete 2020-10-12 22:30:08,664 Task._call(1069) INFO 23.94% complete 2020-10-12 22:30:08,837 Task._call(1069) INFO 26.12% complete 2020-10-12 22:30:09,848 Task._call(1069) INFO 28.30% complete 2020-10-12 22:30:10,013 Task._call(1069) INFO 30.48% complete 2020-10-12 22:30:10,222 Task._call(1069) INFO 32.65% complete 2020-10-12 22:30:10,886 Task._call(1069) INFO 34.83% complete 2020-10-12 22:30:11,078 Task._call(1069) INFO 37.01% complete 2020-10-12 22:30:11,220 Task._call(1069) INFO 39.19% complete 2020-10-12 22:30:11,289 Task._call(1069) INFO 41.36% complete 2020-10-12 22:30:11,293 Task._call(1069) INFO 43.54% complete 2020-10-12 22:30:11,298 Task._call(1069) INFO 45.72% complete 2020-10-12 22:30:11,302 Task._call(1069) INFO 47.90% complete 2020-10-12 22:30:11,306 Task._call(1069) INFO 50.07% complete 2020-10-12 22:30:11,311 Task._call(1069) INFO 52.25% complete 2020-10-12 22:30:11,410 Task._call(1069) INFO 54.43% complete 2020-10-12 22:30:11,703 Task._call(1069) INFO 56.61% complete 2020-10-12 22:30:11,862 Task._call(1069) INFO 58.78% complete 2020-10-12 22:30:13,947 Task._call(1069) INFO 60.96% complete 2020-10-12 22:30:14,133 Task._call(1069) INFO 63.14% complete 2020-10-12 22:30:14,380 Task._call(1069) INFO 65.32% complete 2020-10-12 22:30:14,593 Task._call(1069) INFO 67.49% complete 2020-10-12 22:30:14,808 Task._call(1069) INFO 69.67% complete 2020-10-12 22:30:14,965 Task._call(1069) INFO 71.85% complete 2020-10-12 22:30:15,138 Task._call(1069) INFO 74.03% complete 2020-10-12 22:30:15,218 Task._call(1069) INFO 76.20% complete 2020-10-12 22:30:15,220 Task._call(1069) INFO 76.69% complete 2020-10-12 22:30:15,225 Task._call(1069) INFO 2.30% complete 2020-10-12 22:30:15,229 Task._call(1069) INFO 4.60% complete 2020-10-12 22:30:15,233 Task._call(1069) INFO 6.91% complete 2020-10-12 22:30:15,237 Task._call(1069) INFO 9.21% complete 2020-10-12 22:30:15,241 Task._call(1069) INFO 11.52% complete 2020-10-12 22:30:15,245 Task._call(1069) INFO 13.82% complete 2020-10-12 22:30:15,435 Task._call(1069) INFO 16.13% complete 2020-10-12 22:30:15,733 Task._call(1069) INFO 18.44% complete 2020-10-12 22:30:16,830 Task._call(1069) INFO 20.74% complete 2020-10-12 22:30:17,116 Task._call(1069) INFO 23.05% complete 2020-10-12 22:30:18,684 Task._call(1069) INFO 25.35% complete 2020-10-12 22:30:18,903 Task._call(1069) INFO 27.66% complete 2020-10-12 22:30:19,102 Task._call(1069) INFO 29.96% complete 2020-10-12 22:30:19,281 Task._call(1069) INFO 32.27% complete 2020-10-12 22:30:19,437 Task._call(1069) INFO 34.57% complete 2020-10-12 22:30:19,645 Task._call(1069) INFO 36.88% complete 2020-10-12 22:30:19,804 Task._call(1069) INFO 39.19% complete 2020-10-12 22:30:19,930 Task._call(1069) INFO 41.49% complete 2020-10-12 22:30:20,221 Task._call(1069) INFO 43.80% complete 2020-10-12 22:30:20,225 Task._call(1069) INFO 46.10% complete 2020-10-12 22:30:20,230 Task._call(1069) INFO 48.41% complete 2020-10-12 22:30:20,234 Task._call(1069) INFO 50.71% complete 2020-10-12 22:30:20,238 Task._call(1069) INFO 53.02% complete 2020-10-12 22:30:20,243 Task._call(1069) INFO 55.33% complete 2020-10-12 22:30:20,294 Task._call(1069) INFO 57.63% complete 2020-10-12 22:30:20,479 Task._call(1069) INFO 59.94% complete 2020-10-12 22:30:20,630 Task._call(1069) INFO 62.24% complete 2020-10-12 22:30:20,841 Task._call(1069) INFO 64.55% complete 2020-10-12 22:30:21,037 Task._call(1069) INFO 66.85% complete 2020-10-12 22:30:21,226 Task._call(1069) INFO 69.16% complete 2020-10-12 22:30:21,498 Task._call(1069) INFO 71.46% complete 2020-10-12 22:30:21,693 Task._call(1069) INFO 73.77% complete 2020-10-12 22:30:21,893 Task._call(1069) INFO 76.08% complete 2020-10-12 22:30:22,157 Task._call(1069) INFO 78.38% complete 2020-10-12 22:30:22,289 Task._call(1069) INFO 80.69% complete 2020-10-12 22:30:22,318 Task._call(1069) INFO 81.20% complete 2020-10-12 22:30:22,323 Task._call(1069) INFO 2.42% complete 2020-10-12 22:30:22,327 Task._call(1069) INFO 4.86% complete 2020-10-12 22:30:22,332 Task._call(1069) INFO 7.29% complete 2020-10-12 22:30:22,337 Task._call(1069) INFO 9.73% complete 2020-10-12 22:30:22,341 Task._call(1069) INFO 12.16% complete 2020-10-12 22:30:22,346 Task._call(1069) INFO 14.59% complete 2020-10-12 22:30:22,531 Task._call(1069) INFO 17.03% complete 2020-10-12 22:30:23,677 Task._call(1069) INFO 19.46% complete 2020-10-12 22:30:23,917 Task._call(1069) INFO 21.89% complete 2020-10-12 22:30:24,137 Task._call(1069) INFO 24.33% complete 2020-10-12 22:30:24,347 Task._call(1069) INFO 26.76% complete 2020-10-12 22:30:24,520 Task._call(1069) INFO 29.20% complete 2020-10-12 22:30:24,722 Task._call(1069) INFO 31.63% complete 2020-10-12 22:30:25,121 Task._call(1069) INFO 34.06% complete 2020-10-12 22:30:25,355 Task._call(1069) INFO 36.50% complete 2020-10-12 22:30:26,435 Task._call(1069) INFO 38.93% complete 2020-10-12 22:30:26,630 Task._call(1069) INFO 41.36% complete 2020-10-12 22:30:26,636 Task._call(1069) INFO 43.80% complete 2020-10-12 22:30:26,641 Task._call(1069) INFO 46.23% complete 2020-10-12 22:30:26,645 Task._call(1069) INFO 48.66% complete 2020-10-12 22:30:26,649 Task._call(1069) INFO 51.10% complete 2020-10-12 22:30:26,653 Task._call(1069) INFO 53.53% complete 2020-10-12 22:30:26,658 Task._call(1069) INFO 55.97% complete 2020-10-12 22:30:26,663 Task._call(1069) INFO 58.40% complete 2020-10-12 22:30:26,667 Task._call(1069) INFO 60.83% complete 2020-10-12 22:30:26,749 Task._call(1069) INFO 63.27% complete 2020-10-12 22:30:27,034 Task._call(1069) INFO 65.70% complete 2020-10-12 22:30:27,344 Task._call(1069) INFO 68.13% complete 2020-10-12 22:30:27,558 Task._call(1069) INFO 70.57% complete 2020-10-12 22:30:27,764 Task._call(1069) INFO 73.00% complete 2020-10-12 22:30:27,983 Task._call(1069) INFO 75.44% complete 2020-10-12 22:30:28,203 Task._call(1069) INFO 77.87% complete 2020-10-12 22:30:28,862 Task._call(1069) INFO 80.30% complete 2020-10-12 22:30:29,074 Task._call(1069) INFO 82.74% complete 2020-10-12 22:30:29,285 Task._call(1069) INFO 85.17% complete 2020-10-12 22:30:29,325 Task._call(1069) INFO 85.71% complete 2020-10-12 22:30:29,329 Task._call(1069) INFO 2.55% complete 2020-10-12 22:30:29,334 Task._call(1069) INFO 5.11% complete 2020-10-12 22:30:29,339 Task._call(1069) INFO 7.68% complete 2020-10-12 22:30:29,343 Task._call(1069) INFO 10.24% complete 2020-10-12 22:30:29,347 Task._call(1069) INFO 12.80% complete 2020-10-12 22:30:29,352 Task._call(1069) INFO 15.36% complete 2020-10-12 22:30:29,436 Task._call(1069) INFO 17.92% complete 2020-10-12 22:30:30,741 Task._call(1069) INFO 20.48% complete 2020-10-12 22:30:30,919 Task._call(1069) INFO 23.05% complete 2020-10-12 22:30:33,196 Task._call(1069) INFO 25.61% complete 2020-10-12 22:30:33,370 Task._call(1069) INFO 28.17% complete 2020-10-12 22:30:33,620 Task._call(1069) INFO 30.73% complete 2020-10-12 22:30:33,818 Task._call(1069) INFO 33.29% complete 2020-10-12 22:30:34,286 Task._call(1069) INFO 35.86% complete 2020-10-12 22:30:35,744 Task._call(1069) INFO 38.42% complete 2020-10-12 22:30:35,897 Task._call(1069) INFO 40.98% complete 2020-10-12 22:30:36,010 Task._call(1069) INFO 43.54% complete 2020-10-12 22:30:36,012 Task._call(1069) INFO 46.10% complete 2020-10-12 22:30:36,016 Task._call(1069) INFO 48.66% complete 2020-10-12 22:30:36,021 Task._call(1069) INFO 51.23% complete 2020-10-12 22:30:36,025 Task._call(1069) INFO 53.79% complete 2020-10-12 22:30:36,029 Task._call(1069) INFO 56.35% complete 2020-10-12 22:30:36,034 Task._call(1069) INFO 58.91% complete 2020-10-12 22:30:36,038 Task._call(1069) INFO 61.47% complete 2020-10-12 22:30:36,042 Task._call(1069) INFO 64.04% complete 2020-10-12 22:30:36,047 Task._call(1069) INFO 66.60% complete 2020-10-12 22:30:36,215 Task._call(1069) INFO 69.16% complete 2020-10-12 22:30:36,379 Task._call(1069) INFO 71.72% complete 2020-10-12 22:30:36,456 Task._call(1069) INFO 74.28% complete 2020-10-12 22:30:37,106 Task._call(1069) INFO 76.84% complete 2020-10-12 22:30:37,309 Task._call(1069) INFO 79.41% complete 2020-10-12 22:30:37,903 Task._call(1069) INFO 81.97% complete 2020-10-12 22:30:39,532 Task._call(1069) INFO 84.53% complete 2020-10-12 22:30:39,781 Task._call(1069) INFO 87.09% complete 2020-10-12 22:30:39,917 Task._call(1069) INFO 89.65% complete 2020-10-12 22:30:39,930 Task._call(1069) INFO 90.22% complete 2020-10-12 22:30:39,935 Task._call(1069) INFO 2.68% complete 2020-10-12 22:30:39,939 Task._call(1069) INFO 5.37% complete 2020-10-12 22:30:39,943 Task._call(1069) INFO 8.06% complete 2020-10-12 22:30:39,948 Task._call(1069) INFO 10.75% complete 2020-10-12 22:30:39,953 Task._call(1069) INFO 13.44% complete 2020-10-12 22:30:39,957 Task._call(1069) INFO 16.13% complete 2020-10-12 22:30:39,962 Task._call(1069) INFO 18.82% complete 2020-10-12 22:30:40,105 Task._call(1069) INFO 21.51% complete 2020-10-12 22:30:40,201 Task._call(1069) INFO 24.20% complete 2020-10-12 22:30:40,500 Task._call(1069) INFO 26.89% complete 2020-10-12 22:30:40,576 Task._call(1069) INFO 29.58% complete 2020-10-12 22:30:41,969 Task._call(1069) INFO 32.27% complete 2020-10-12 22:30:42,075 Task._call(1069) INFO 34.96% complete 2020-10-12 22:30:42,079 Task._call(1069) INFO 37.65% complete 2020-10-12 22:30:42,169 Task._call(1069) INFO 40.34% complete 2020-10-12 22:30:42,257 Task._call(1069) INFO 43.03% complete 2020-10-12 22:30:42,377 Task._call(1069) INFO 45.72% complete 2020-10-12 22:30:42,381 Task._call(1069) INFO 48.41% complete 2020-10-12 22:30:42,385 Task._call(1069) INFO 51.10% complete 2020-10-12 22:30:42,389 Task._call(1069) INFO 53.79% complete 2020-10-12 22:30:42,393 Task._call(1069) INFO 56.48% complete 2020-10-12 22:30:42,397 Task._call(1069) INFO 59.17% complete 2020-10-12 22:30:42,401 Task._call(1069) INFO 61.86% complete 2020-10-12 22:30:42,405 Task._call(1069) INFO 64.55% complete 2020-10-12 22:30:42,409 Task._call(1069) INFO 67.24% complete 2020-10-12 22:30:42,413 Task._call(1069) INFO 69.93% complete 2020-10-12 22:30:42,554 Task._call(1069) INFO 72.62% complete 2020-10-12 22:30:42,861 Task._call(1069) INFO 75.31% complete 2020-10-12 22:30:42,888 Task._call(1069) INFO 78.00% complete 2020-10-12 22:30:42,946 Task._call(1069) INFO 80.69% complete 2020-10-12 22:30:43,120 Task._call(1069) INFO 83.38% complete 2020-10-12 22:30:43,342 Task._call(1069) INFO 86.07% complete 2020-10-12 22:30:43,521 Task._call(1069) INFO 88.76% complete 2020-10-12 22:30:43,675 Task._call(1069) INFO 91.45% complete 2020-10-12 22:30:43,808 Task._call(1069) INFO 94.14% complete 2020-10-12 22:30:43,811 Task._call(1069) INFO 94.74% complete 2020-10-12 22:30:43,815 Task._call(1069) INFO 2.81% complete 2020-10-12 22:30:43,819 Task._call(1069) INFO 5.63% complete 2020-10-12 22:30:43,824 Task._call(1069) INFO 8.44% complete 2020-10-12 22:30:43,828 Task._call(1069) INFO 11.26% complete 2020-10-12 22:30:43,833 Task._call(1069) INFO 14.08% complete 2020-10-12 22:30:43,837 Task._call(1069) INFO 16.90% complete 2020-10-12 22:30:43,842 Task._call(1069) INFO 19.72% complete 2020-10-12 22:30:43,846 Task._call(1069) INFO 22.53% complete 2020-10-12 22:30:43,851 Task._call(1069) INFO 25.35% complete 2020-10-12 22:30:43,855 Task._call(1069) INFO 28.17% complete 2020-10-12 22:30:43,860 Task._call(1069) INFO 30.99% complete 2020-10-12 22:30:43,865 Task._call(1069) INFO 33.81% complete 2020-10-12 22:30:43,869 Task._call(1069) INFO 36.62% complete 2020-10-12 22:30:43,874 Task._call(1069) INFO 39.44% complete 2020-10-12 22:30:43,878 Task._call(1069) INFO 42.26% complete 2020-10-12 22:30:43,883 Task._call(1069) INFO 45.08% complete 2020-10-12 22:30:43,887 Task._call(1069) INFO 47.90% complete 2020-10-12 22:30:43,892 Task._call(1069) INFO 50.71% complete 2020-10-12 22:30:43,897 Task._call(1069) INFO 53.53% complete 2020-10-12 22:30:43,901 Task._call(1069) INFO 56.35% complete 2020-10-12 22:30:43,906 Task._call(1069) INFO 59.17% complete 2020-10-12 22:30:43,910 Task._call(1069) INFO 61.99% complete 2020-10-12 22:30:43,915 Task._call(1069) INFO 64.80% complete 2020-10-12 22:30:43,919 Task._call(1069) INFO 67.62% complete 2020-10-12 22:30:43,924 Task._call(1069) INFO 70.44% complete 2020-10-12 22:30:43,928 Task._call(1069) INFO 73.26% complete 2020-10-12 22:30:43,932 Task._call(1069) INFO 76.08% complete 2020-10-12 22:30:44,101 Task._call(1069) INFO 78.89% complete 2020-10-12 22:30:44,223 Task._call(1069) INFO 81.71% complete 2020-10-12 22:30:44,228 Task._call(1069) INFO 84.53% complete 2020-10-12 22:30:44,268 Task._call(1069) INFO 87.35% complete 2020-10-12 22:30:44,387 Task._call(1069) INFO 90.17% complete 2020-10-12 22:30:44,473 Task._call(1069) INFO 92.99% complete 2020-10-12 22:30:44,492 Task._call(1069) INFO 95.80% complete 2020-10-12 22:30:44,498 Task._call(1069) INFO 98.62% complete 2020-10-12 22:30:44,499 Task._call(1069) INFO 99.25% complete 2020-10-12 22:30:44,501 Task._call(1069) INFO 2.83% complete 2020-10-12 22:30:44,502 Task._call(1069) INFO 5.67% complete 2020-10-12 22:30:44,503 Task._call(1069) INFO 8.50% complete 2020-10-12 22:30:44,504 Task._call(1069) INFO 11.34% complete 2020-10-12 22:30:44,505 Task._call(1069) INFO 14.18% complete 2020-10-12 22:30:44,506 Task._call(1069) INFO 17.02% complete 2020-10-12 22:30:44,507 Task._call(1069) INFO 19.86% complete 2020-10-12 22:30:44,508 Task._call(1069) INFO 22.70% complete 2020-10-12 22:30:44,509 Task._call(1069) INFO 25.54% complete 2020-10-12 22:30:44,511 Task._call(1069) INFO 28.37% complete 2020-10-12 22:30:44,512 Task._call(1069) INFO 31.21% complete 2020-10-12 22:30:44,512 Task._call(1069) INFO 34.05% complete 2020-10-12 22:30:44,513 Task._call(1069) INFO 36.89% complete 2020-10-12 22:30:44,515 Task._call(1069) INFO 39.73% complete 2020-10-12 22:30:44,516 Task._call(1069) INFO 42.57% complete 2020-10-12 22:30:44,517 Task._call(1069) INFO 45.41% complete 2020-10-12 22:30:44,518 Task._call(1069) INFO 48.24% complete 2020-10-12 22:30:44,519 Task._call(1069) INFO 51.08% complete 2020-10-12 22:30:44,520 Task._call(1069) INFO 53.92% complete 2020-10-12 22:30:44,521 Task._call(1069) INFO 56.76% complete 2020-10-12 22:30:44,522 Task._call(1069) INFO 59.60% complete 2020-10-12 22:30:44,523 Task._call(1069) INFO 62.44% complete 2020-10-12 22:30:44,524 Task._call(1069) INFO 65.28% complete 2020-10-12 22:30:44,525 Task._call(1069) INFO 68.11% complete 2020-10-12 22:30:44,526 Task._call(1069) INFO 70.95% complete 2020-10-12 22:30:44,527 Task._call(1069) INFO 73.79% complete 2020-10-12 22:30:44,528 Task._call(1069) INFO 76.63% complete 2020-10-12 22:30:44,536 Task._call(1069) INFO 79.47% complete 2020-10-12 22:30:44,541 Task._call(1069) INFO 82.31% complete 2020-10-12 22:30:44,542 Task._call(1069) INFO 85.15% complete 2020-10-12 22:30:44,543 Task._call(1069) INFO 87.98% complete 2020-10-12 22:30:44,544 Task._call(1069) INFO 90.82% complete 2020-10-12 22:30:44,546 Task._call(1069) INFO 93.66% complete 2020-10-12 22:30:44,547 Task._call(1069) INFO 96.50% complete 2020-10-12 22:30:44,547 Task._call(1069) INFO 99.34% complete 2020-10-12 22:30:44,548 Task._call(1069) INFO 100% complete 2020-10-12 22:30:49,104 geoprocessing.raster_calculator(375) INFO starting stats_worker 2020-10-12 22:30:49,105 geoprocessing.raster_calculator(381) INFO started stats_worker 2020-10-12 22:30:54,105 geoprocessing.(451) INFO 88.6% complete 2020-10-12 22:30:54,596 geoprocessing.raster_calculator(454) INFO 100.0%% complete 2020-10-12 22:30:54,596 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate 2020-10-12 22:30:54,597 threading.run(870) DEBUG payload is None, terminating 2020-10-12 22:30:54,597 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result. 2020-10-12 22:31:04,011 Task._call(1069) INFO 13.6% complete 2020-10-12 22:31:10,160 Task._call(1069) INFO 22.6% complete 2020-10-12 22:31:16,204 Task._call(1069) INFO 31.6% complete 2020-10-12 22:31:22,023 Task._call(1069) INFO 40.6% complete 2020-10-12 22:31:28,038 Task._call(1069) INFO 45.1% complete 2020-10-12 22:31:34,052 Task._call(1069) INFO 54.2% complete 2020-10-12 22:31:40,613 Task._call(1069) INFO 63.2% complete 2020-10-12 22:31:46,021 Task._call(1069) INFO 72.2% complete 2020-10-12 22:31:52,006 Task._call(1069) INFO 94.8% complete 2020-10-12 22:31:52,662 Task._call(1069) INFO 100.0% complete 2020-10-12 22:32:09,626 geoprocessing.raster_calculator(375) INFO starting stats_worker 2020-10-12 22:32:09,627 geoprocessing.raster_calculator(381) INFO started stats_worker 2020-10-12 22:32:12,917 geoprocessing.raster_calculator(454) INFO 100.0%% complete 2020-10-12 22:32:12,918 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate 2020-10-12 22:32:12,918 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result. 2020-10-12 22:32:12,918 threading.run(870) DEBUG payload is None, terminating 2020-10-12 22:32:19,793 geoprocessing.raster_calculator(375) INFO starting stats_worker 2020-10-12 22:32:19,794 geoprocessing.raster_calculator(381) INFO started stats_worker 2020-10-12 22:32:24,794 geoprocessing.(451) INFO 69.5% complete 2020-10-12 22:32:26,971 geoprocessing.raster_calculator(454) INFO 100.0%% complete 2020-10-12 22:32:26,972 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate 2020-10-12 22:32:26,972 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result. 2020-10-12 22:32:26,972 threading.run(870) DEBUG payload is None, terminating 2020-10-12 22:32:27,688 utils._log_gdal_errors(68) WARNING [errno 1] Value 27890537427.7000008 of field Shape_Area of feature 0 not successfully written. Possibly due to too larger number with respect to field width 2020-10-12 22:32:28,257 geoprocessing.zonal_statistics(1129) DEBUG > 2020-10-12 22:32:32,737 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 1 aligned: clipped_raster56vtwm8w.tif 2020-10-12 22:32:32,738 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 1 rasters. 2020-10-12 22:32:32,739 geoprocessing.zonal_statistics(1189) INFO build a lookup of aggregate field value to FID 2020-10-12 22:32:32,739 geoprocessing.zonal_statistics(1196) INFO creating disjoint polygon set 2020-10-12 22:32:32,740 geoprocessing.calculate_disjoint_polygon_set(2082) INFO build shapely polygon list 2020-10-12 22:32:32,743 geoprocessing.calculate_disjoint_polygon_set(2096) INFO build shapely rtree index 2020-10-12 22:32:32,743 geoprocessing.calculate_disjoint_polygon_set(2111) INFO poly feature lookup 100.0% complete on watershed_results_sdr_2018_NSH2.shp 2020-10-12 22:32:32,743 geoprocessing.calculate_disjoint_polygon_set(2113) INFO build poly intersection lookup 2020-10-12 22:32:32,744 geoprocessing.calculate_disjoint_polygon_set(2137) INFO poly intersection feature lookup 100.0% complete on watershed_results_sdr_2018_NSH2.shp 2020-10-12 22:32:32,744 geoprocessing.calculate_disjoint_polygon_set(2171) INFO maximal subset build 100.0% complete on watershed_results_sdr_2018_NSH2.shp 2020-10-12 22:32:32,764 geoprocessing.zonal_statistics(1219) INFO processing 1 disjoint polygon sets 2020-10-12 22:32:32,765 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 1 of 1 100.0% processed on watershed_results_sdr_2018_NSH2.shp 2020-10-12 22:32:33,166 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 1 of 1 watershed_results_sdr_2018_NSH2.shp 2020-10-12 22:32:34,926 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 1 of 1 watershed_results_sdr_2018_NSH2.shp 2020-10-12 22:32:36,827 geoprocessing.zonal_statistics(1322) DEBUG unset_fids: 0 of 1 2020-10-12 22:32:36,832 geoprocessing.zonal_statistics(1325) DEBUG gt [ 3.0632259e+05 3.0000000e+01 0.0000000e+00 3.9823748e+06 0.0000000e+00 -3.0000000e+01] for ('F:/INVEST/SDR\\usle_2018_NSH2.tif', 1) 2020-10-12 22:32:36,832 geoprocessing.zonal_statistics(1399) DEBUG remaining unset_fids: 0 of 1 2020-10-12 22:32:36,832 geoprocessing.zonal_statistics(1407) INFO all done processing polygon sets for watershed_results_sdr_2018_NSH2.shp 2020-10-12 22:32:36,874 geoprocessing.zonal_statistics(1129) DEBUG > 2020-10-12 22:32:41,989 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 1 aligned: clipped_rasterj3w2zcg1.tif 2020-10-12 22:32:41,990 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 1 rasters. 2020-10-12 22:32:41,990 geoprocessing.zonal_statistics(1189) INFO build a lookup of aggregate field value to FID 2020-10-12 22:32:41,991 geoprocessing.zonal_statistics(1196) INFO creating disjoint polygon set 2020-10-12 22:32:41,991 geoprocessing.calculate_disjoint_polygon_set(2082) INFO build shapely polygon list 2020-10-12 22:32:41,993 geoprocessing.calculate_disjoint_polygon_set(2096) INFO build shapely rtree index 2020-10-12 22:32:41,994 geoprocessing.calculate_disjoint_polygon_set(2111) INFO poly feature lookup 100.0% complete on watershed_results_sdr_2018_NSH2.shp 2020-10-12 22:32:41,994 geoprocessing.calculate_disjoint_polygon_set(2113) INFO build poly intersection lookup 2020-10-12 22:32:41,994 geoprocessing.calculate_disjoint_polygon_set(2137) INFO poly intersection feature lookup 100.0% complete on watershed_results_sdr_2018_NSH2.shp 2020-10-12 22:32:41,994 geoprocessing.calculate_disjoint_polygon_set(2171) INFO maximal subset build 100.0% complete on watershed_results_sdr_2018_NSH2.shp 2020-10-12 22:32:42,015 geoprocessing.zonal_statistics(1219) INFO processing 1 disjoint polygon sets 2020-10-12 22:32:42,016 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 1 of 1 100.0% processed on watershed_results_sdr_2018_NSH2.shp 2020-10-12 22:32:42,417 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 1 of 1 watershed_results_sdr_2018_NSH2.shp 2020-10-12 22:32:44,183 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 1 of 1 watershed_results_sdr_2018_NSH2.shp 2020-10-12 22:32:46,084 geoprocessing.zonal_statistics(1322) DEBUG unset_fids: 0 of 1 2020-10-12 22:32:46,090 geoprocessing.zonal_statistics(1325) DEBUG gt [ 3.0632259e+05 3.0000000e+01 0.0000000e+00 3.9823748e+06 0.0000000e+00 -3.0000000e+01] for ('F:/INVEST/SDR\\sed_export_2018_NSH2.tif', 1) 2020-10-12 22:32:46,090 geoprocessing.zonal_statistics(1399) DEBUG remaining unset_fids: 0 of 1 2020-10-12 22:32:46,090 geoprocessing.zonal_statistics(1407) INFO all done processing polygon sets for watershed_results_sdr_2018_NSH2.shp 2020-10-12 22:32:46,132 geoprocessing.zonal_statistics(1129) DEBUG > 2020-10-12 22:32:50,647 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 1 aligned: clipped_raster_jg7q7oc.tif 2020-10-12 22:32:50,647 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 1 rasters. 2020-10-12 22:32:50,648 geoprocessing.zonal_statistics(1189) INFO build a lookup of aggregate field value to FID 2020-10-12 22:32:50,648 geoprocessing.zonal_statistics(1196) INFO creating disjoint polygon set 2020-10-12 22:32:50,650 geoprocessing.calculate_disjoint_polygon_set(2082) INFO build shapely polygon list 2020-10-12 22:32:50,652 geoprocessing.calculate_disjoint_polygon_set(2096) INFO build shapely rtree index 2020-10-12 22:32:50,652 geoprocessing.calculate_disjoint_polygon_set(2111) INFO poly feature lookup 100.0% complete on watershed_results_sdr_2018_NSH2.shp 2020-10-12 22:32:50,652 geoprocessing.calculate_disjoint_polygon_set(2113) INFO build poly intersection lookup 2020-10-12 22:32:50,652 geoprocessing.calculate_disjoint_polygon_set(2137) INFO poly intersection feature lookup 100.0% complete on watershed_results_sdr_2018_NSH2.shp 2020-10-12 22:32:50,653 geoprocessing.calculate_disjoint_polygon_set(2171) INFO maximal subset build 100.0% complete on watershed_results_sdr_2018_NSH2.shp 2020-10-12 22:32:50,672 geoprocessing.zonal_statistics(1219) INFO processing 1 disjoint polygon sets 2020-10-12 22:32:50,673 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 1 of 1 100.0% processed on watershed_results_sdr_2018_NSH2.shp 2020-10-12 22:32:51,073 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 1 of 1 watershed_results_sdr_2018_NSH2.shp 2020-10-12 22:32:52,894 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 1 of 1 watershed_results_sdr_2018_NSH2.shp 2020-10-12 22:32:54,798 geoprocessing.zonal_statistics(1322) DEBUG unset_fids: 0 of 1 2020-10-12 22:32:54,803 geoprocessing.zonal_statistics(1325) DEBUG gt [ 3.0632259e+05 3.0000000e+01 0.0000000e+00 3.9823748e+06 0.0000000e+00 -3.0000000e+01] for ('F:/INVEST/SDR\\sed_retention_2018_NSH2.tif', 1) 2020-10-12 22:32:54,803 geoprocessing.zonal_statistics(1399) DEBUG remaining unset_fids: 0 of 1 2020-10-12 22:32:54,804 geoprocessing.zonal_statistics(1407) INFO all done processing polygon sets for watershed_results_sdr_2018_NSH2.shp 2020-10-12 22:32:54,845 geoprocessing.zonal_statistics(1129) DEBUG > 2020-10-12 22:32:59,559 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 1 aligned: clipped_rasterhfzdwd7t.tif 2020-10-12 22:32:59,560 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 1 rasters. 2020-10-12 22:32:59,560 geoprocessing.zonal_statistics(1189) INFO build a lookup of aggregate field value to FID 2020-10-12 22:32:59,561 geoprocessing.zonal_statistics(1196) INFO creating disjoint polygon set 2020-10-12 22:32:59,561 geoprocessing.calculate_disjoint_polygon_set(2082) INFO build shapely polygon list 2020-10-12 22:32:59,564 geoprocessing.calculate_disjoint_polygon_set(2096) INFO build shapely rtree index 2020-10-12 22:32:59,564 geoprocessing.calculate_disjoint_polygon_set(2111) INFO poly feature lookup 100.0% complete on watershed_results_sdr_2018_NSH2.shp 2020-10-12 22:32:59,564 geoprocessing.calculate_disjoint_polygon_set(2113) INFO build poly intersection lookup 2020-10-12 22:32:59,565 geoprocessing.calculate_disjoint_polygon_set(2137) INFO poly intersection feature lookup 100.0% complete on watershed_results_sdr_2018_NSH2.shp 2020-10-12 22:32:59,565 geoprocessing.calculate_disjoint_polygon_set(2171) INFO maximal subset build 100.0% complete on watershed_results_sdr_2018_NSH2.shp 2020-10-12 22:32:59,586 geoprocessing.zonal_statistics(1219) INFO processing 1 disjoint polygon sets 2020-10-12 22:32:59,586 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 1 of 1 100.0% processed on watershed_results_sdr_2018_NSH2.shp 2020-10-12 22:32:59,990 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 1 of 1 watershed_results_sdr_2018_NSH2.shp 2020-10-12 22:33:01,755 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 1 of 1 watershed_results_sdr_2018_NSH2.shp 2020-10-12 22:33:03,669 geoprocessing.zonal_statistics(1322) DEBUG unset_fids: 0 of 1 2020-10-12 22:33:03,674 geoprocessing.zonal_statistics(1325) DEBUG gt [ 3.0632259e+05 3.0000000e+01 0.0000000e+00 3.9823748e+06 0.0000000e+00 -3.0000000e+01] for ('F:/INVEST/SDR\\sed_deposition_2018_NSH2.tif', 1) 2020-10-12 22:33:03,674 geoprocessing.zonal_statistics(1399) DEBUG remaining unset_fids: 0 of 1 2020-10-12 22:33:03,674 geoprocessing.zonal_statistics(1407) INFO all done processing polygon sets for watershed_results_sdr_2018_NSH2.shp 2020-10-12 22:33:03,715 utils._log_gdal_errors(68) WARNING [errno 1] Value 27890537427.7000008 of field Shape_Area of feature 0 not successfully written. Possibly due to too larger number with respect to field width 2020-10-12 22:33:04,437 model._logged_target(1638) INFO Execution finished 2020-10-12 22:33:04,439 utils.prepare_workspace(117) INFO Elapsed time: 8m 1.009999999999991s