2020-10-12 12:07:04,429 utils.prepare_workspace(111) INFO Writing log messages to F:/INVEST/SDR\InVEST-Sediment-Delivery-Ratio-Model-(SDR)-log-2020-10-12--12_07_04.txt 2020-10-12 12:07:04,430 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/R2015.tif ic_0_param 0.628 k_param 1.5 lulc_path F:/INVEST/WaterYield/LU2015.tif n_workers -1 results_suffix 2015_NSH sdr_max 0.8 threshold_flow_accumulation 1000 watersheds_path F:/INVEST/WaterYield/Watershed.shp workspace_dir F:/INVEST/SDR 2020-10-12 12:07:11,822 geoprocessing.raster_calculator(375) INFO starting stats_worker 2020-10-12 12:07:11,823 geoprocessing.raster_calculator(381) INFO started stats_worker 2020-10-12 12:07:12,932 geoprocessing.raster_calculator(454) INFO 100.0%% complete 2020-10-12 12:07:12,932 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate 2020-10-12 12:07:12,932 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result. 2020-10-12 12:07:12,966 threading.run(870) DEBUG payload is None, terminating 2020-10-12 12:07:13,312 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 4 aligned: aligned_dem_2015_NSH.tif 2020-10-12 12:07:18,248 geoprocessing.raster_calculator(375) INFO starting stats_worker 2020-10-12 12:07:18,249 geoprocessing.raster_calculator(381) INFO started stats_worker 2020-10-12 12:07:19,574 geoprocessing.raster_calculator(454) INFO 100.0%% complete 2020-10-12 12:07:19,574 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate 2020-10-12 12:07:19,574 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result. 2020-10-12 12:07:19,631 threading.run(870) DEBUG payload is None, terminating 2020-10-12 12:07:19,805 geoprocessing.align_and_resize_raster_stack(752) INFO 2 of 4 aligned: aligned_lulc_2015_NSH.tif 2020-10-12 12:07:29,617 geoprocessing.raster_calculator(375) INFO starting stats_worker 2020-10-12 12:07:29,619 geoprocessing.raster_calculator(381) INFO started stats_worker 2020-10-12 12:07:33,167 geoprocessing.raster_calculator(454) INFO 100.0%% complete 2020-10-12 12:07:33,167 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate 2020-10-12 12:07:33,168 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result. 2020-10-12 12:07:33,262 threading.run(870) DEBUG payload is None, terminating 2020-10-12 12:07:33,619 geoprocessing.align_and_resize_raster_stack(752) INFO 3 of 4 aligned: aligned_erosivity_2015_NSH.tif 2020-10-12 12:07:39,523 geoprocessing.raster_calculator(375) INFO starting stats_worker 2020-10-12 12:07:39,524 geoprocessing.raster_calculator(381) INFO started stats_worker 2020-10-12 12:07:40,896 geoprocessing.raster_calculator(454) INFO 100.0%% complete 2020-10-12 12:07:40,896 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate 2020-10-12 12:07:40,897 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result. 2020-10-12 12:07:41,106 threading.run(870) DEBUG payload is None, terminating 2020-10-12 12:07:41,338 geoprocessing.align_and_resize_raster_stack(752) INFO 4 of 4 aligned: aligned_erodibility_2015_NSH.tif 2020-10-12 12:07:41,339 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 4 rasters. 2020-10-12 12:08:27,401 geoprocessing.raster_calculator(375) INFO starting stats_worker 2020-10-12 12:08:27,402 geoprocessing.raster_calculator(381) INFO started stats_worker 2020-10-12 12:08:29,906 geoprocessing.raster_calculator(454) INFO 100.0%% complete 2020-10-12 12:08:29,907 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate 2020-10-12 12:08:29,907 threading.run(870) DEBUG payload is None, terminating 2020-10-12 12:08:29,907 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result. 2020-10-12 12:08:30,162 geoprocessing.raster_calculator(375) INFO starting stats_worker 2020-10-12 12:08:30,163 geoprocessing.raster_calculator(381) INFO started stats_worker 2020-10-12 12:08:31,356 geoprocessing.raster_calculator(454) INFO 100.0%% complete 2020-10-12 12:08:31,356 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate 2020-10-12 12:08:31,356 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result. 2020-10-12 12:08:31,415 threading.run(870) DEBUG payload is None, terminating 2020-10-12 12:08:32,041 geoprocessing.raster_calculator(375) INFO starting stats_worker 2020-10-12 12:08:32,042 geoprocessing.raster_calculator(381) INFO started stats_worker 2020-10-12 12:08:34,316 geoprocessing.raster_calculator(454) INFO 100.0%% complete 2020-10-12 12:08:34,316 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate 2020-10-12 12:08:34,316 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result. 2020-10-12 12:08:34,316 threading.run(870) DEBUG payload is None, terminating 2020-10-12 12:08:36,371 geoprocessing.raster_calculator(375) INFO starting stats_worker 2020-10-12 12:08:36,372 geoprocessing.raster_calculator(381) INFO started stats_worker 2020-10-12 12:08:41,373 geoprocessing.(451) INFO 88.0% complete 2020-10-12 12:08:41,966 geoprocessing.raster_calculator(454) INFO 100.0%% complete 2020-10-12 12:08:41,966 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate 2020-10-12 12:08:41,966 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result. 2020-10-12 12:08:41,966 threading.run(870) DEBUG payload is None, terminating 2020-10-12 12:08:48,677 geoprocessing.raster_calculator(375) INFO starting stats_worker 2020-10-12 12:08:48,679 geoprocessing.raster_calculator(381) INFO started stats_worker 2020-10-12 12:08:52,517 geoprocessing.raster_calculator(454) INFO 100.0%% complete 2020-10-12 12:08:52,518 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate 2020-10-12 12:08:52,518 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result. 2020-10-12 12:08:52,518 threading.run(870) DEBUG payload is None, terminating 2020-10-12 12:09:08,119 sdr._calculate_bar_factor(1078) DEBUG doing flow accumulation mfd on F:/INVEST/SDR\intermediate_outputs\w_threshold_2015_NSH.tif 2020-10-12 12:09:08,119 sdr._calculate_bar_factor(1086) DEBUG creating target flow accum raster layer 2020-10-12 12:09:11,365 sdr._calculate_bar_factor(1086) DEBUG creating visited raster layer 2020-10-12 12:09:11,438 sdr._calculate_bar_factor(1086) DEBUG starting search 2020-10-12 12:09:13,999 sdr._calculate_bar_factor(1086) INFO mfd flow accum 3.6% complete 2020-10-12 12:09:20,139 sdr._calculate_bar_factor(1086) INFO mfd flow accum 4.9% complete 2020-10-12 12:09:25,999 sdr._calculate_bar_factor(1086) INFO mfd flow accum 12.0% complete 2020-10-12 12:09:31,999 sdr._calculate_bar_factor(1086) INFO mfd flow accum 18.6% complete 2020-10-12 12:09:37,999 sdr._calculate_bar_factor(1086) INFO mfd flow accum 23.6% complete 2020-10-12 12:09:43,999 sdr._calculate_bar_factor(1086) INFO mfd flow accum 29.5% complete 2020-10-12 12:09:50,177 sdr._calculate_bar_factor(1086) INFO mfd flow accum 35.7% complete 2020-10-12 12:09:56,000 sdr._calculate_bar_factor(1086) INFO mfd flow accum 40.9% complete 2020-10-12 12:10:01,999 sdr._calculate_bar_factor(1086) INFO mfd flow accum 47.7% complete 2020-10-12 12:10:08,023 sdr._calculate_bar_factor(1086) INFO mfd flow accum 53.0% complete 2020-10-12 12:10:13,999 sdr._calculate_bar_factor(1086) INFO mfd flow accum 60.3% complete 2020-10-12 12:10:15,760 sdr._calculate_bar_factor(1086) INFO 100.0% complete 2020-10-12 12:10:15,772 geoprocessing.raster_calculator(375) INFO starting stats_worker 2020-10-12 12:10:15,774 geoprocessing.raster_calculator(381) INFO started stats_worker 2020-10-12 12:10:20,989 geoprocessing.(451) INFO 44.2% complete 2020-10-12 12:10:25,850 geoprocessing.raster_calculator(454) INFO 100.0%% complete 2020-10-12 12:10:25,850 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate 2020-10-12 12:10:25,850 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result. 2020-10-12 12:10:25,851 threading.run(870) DEBUG payload is None, terminating 2020-10-12 12:10:41,424 geoprocessing.raster_calculator(375) INFO starting stats_worker 2020-10-12 12:10:41,425 geoprocessing.raster_calculator(381) INFO started stats_worker 2020-10-12 12:10:46,448 geoprocessing.(451) INFO 17.1% complete 2020-10-12 12:10:51,451 geoprocessing.(451) INFO 28.7% complete 2020-10-12 12:10:56,665 geoprocessing.(451) INFO 34.9% complete 2020-10-12 12:11:01,732 geoprocessing.(451) INFO 53.1% complete 2020-10-12 12:11:06,735 geoprocessing.(451) INFO 76.6% complete 2020-10-12 12:11:09,598 geoprocessing.raster_calculator(454) INFO 100.0%% complete 2020-10-12 12:11:09,598 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate 2020-10-12 12:11:09,598 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result. 2020-10-12 12:11:09,599 threading.run(870) DEBUG payload is None, terminating 2020-10-12 12:11:11,029 geoprocessing.raster_calculator(375) INFO starting stats_worker 2020-10-12 12:11:11,030 geoprocessing.raster_calculator(381) INFO started stats_worker 2020-10-12 12:11:13,844 geoprocessing.raster_calculator(454) INFO 100.0%% complete 2020-10-12 12:11:13,844 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate 2020-10-12 12:11:13,844 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result. 2020-10-12 12:11:13,844 threading.run(870) DEBUG payload is None, terminating 2020-10-12 12:11:21,010 Task._call(1069) INFO 13.6% complete 2020-10-12 12:11:28,384 Task._call(1069) INFO 18.1% complete 2020-10-12 12:11:34,017 Task._call(1069) INFO 27.1% complete 2020-10-12 12:11:40,372 Task._call(1069) INFO 36.1% complete 2020-10-12 12:11:46,048 Task._call(1069) INFO 40.6% complete 2020-10-12 12:11:52,396 Task._call(1069) INFO 45.1% complete 2020-10-12 12:11:58,025 Task._call(1069) INFO 54.2% complete 2020-10-12 12:12:04,007 Task._call(1069) INFO 63.2% complete 2020-10-12 12:12:10,735 Task._call(1069) INFO 72.2% complete 2020-10-12 12:12:16,020 Task._call(1069) INFO 85.7% complete 2020-10-12 12:12:17,304 Task._call(1069) INFO 100.0% complete 2020-10-12 12:12:20,739 geoprocessing.raster_calculator(375) INFO starting stats_worker 2020-10-12 12:12:20,740 geoprocessing.raster_calculator(381) INFO started stats_worker 2020-10-12 12:12:26,237 geoprocessing.(451) INFO 76.2% complete 2020-10-12 12:12:29,179 geoprocessing.raster_calculator(454) INFO 100.0%% complete 2020-10-12 12:12:29,180 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate 2020-10-12 12:12:29,180 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result. 2020-10-12 12:12:29,180 threading.run(870) DEBUG payload is None, terminating 2020-10-12 12:12:31,099 geoprocessing.raster_calculator(375) INFO starting stats_worker 2020-10-12 12:12:31,100 geoprocessing.raster_calculator(381) INFO started stats_worker 2020-10-12 12:12:34,627 geoprocessing.raster_calculator(454) INFO 100.0%% complete 2020-10-12 12:12:34,628 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate 2020-10-12 12:12:34,628 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result. 2020-10-12 12:12:34,628 threading.run(870) DEBUG payload is None, terminating 2020-10-12 12:12:36,602 geoprocessing.raster_calculator(375) INFO starting stats_worker 2020-10-12 12:12:36,604 geoprocessing.raster_calculator(381) INFO started stats_worker 2020-10-12 12:12:41,606 geoprocessing.(451) INFO 84.3% complete 2020-10-12 12:12:42,216 geoprocessing.raster_calculator(454) INFO 100.0%% complete 2020-10-12 12:12:42,216 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate 2020-10-12 12:12:42,217 threading.run(870) DEBUG payload is None, terminating 2020-10-12 12:12:42,217 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result. 2020-10-12 12:12:44,427 geoprocessing.raster_calculator(375) INFO starting stats_worker 2020-10-12 12:12:44,428 geoprocessing.raster_calculator(381) INFO started stats_worker 2020-10-12 12:12:49,511 geoprocessing.(451) INFO 54.3% complete 2020-10-12 12:12:54,715 geoprocessing.(451) INFO 85.3% complete 2020-10-12 12:12:56,015 geoprocessing.raster_calculator(454) INFO 100.0%% complete 2020-10-12 12:12:56,015 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate 2020-10-12 12:12:56,015 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result. 2020-10-12 12:12:56,016 threading.run(870) DEBUG payload is None, terminating 2020-10-12 12:12:58,272 Task._call(1069) INFO calculate sediment deposition 2020-10-12 12:12:58,304 Task._call(1069) INFO 0.00% complete 2020-10-12 12:12:58,309 Task._call(1069) INFO 0.13% complete 2020-10-12 12:12:58,312 Task._call(1069) INFO 0.25% complete 2020-10-12 12:12:58,315 Task._call(1069) INFO 0.38% complete 2020-10-12 12:12:58,319 Task._call(1069) INFO 0.51% complete 2020-10-12 12:12:58,323 Task._call(1069) INFO 0.64% complete 2020-10-12 12:12:58,326 Task._call(1069) INFO 0.77% complete 2020-10-12 12:12:58,330 Task._call(1069) INFO 0.89% complete 2020-10-12 12:12:58,333 Task._call(1069) INFO 1.02% complete 2020-10-12 12:12:58,337 Task._call(1069) INFO 1.15% complete 2020-10-12 12:12:58,341 Task._call(1069) INFO 1.28% complete 2020-10-12 12:12:58,345 Task._call(1069) INFO 1.40% complete 2020-10-12 12:12:58,348 Task._call(1069) INFO 1.53% complete 2020-10-12 12:12:58,401 Task._call(1069) INFO 1.66% complete 2020-10-12 12:12:58,457 Task._call(1069) INFO 1.79% complete 2020-10-12 12:12:58,486 Task._call(1069) INFO 1.91% complete 2020-10-12 12:12:58,490 Task._call(1069) INFO 2.04% complete 2020-10-12 12:12:58,494 Task._call(1069) INFO 2.17% complete 2020-10-12 12:12:58,498 Task._call(1069) INFO 2.30% complete 2020-10-12 12:12:58,502 Task._call(1069) INFO 2.42% complete 2020-10-12 12:12:58,506 Task._call(1069) INFO 2.55% complete 2020-10-12 12:12:58,509 Task._call(1069) INFO 2.68% complete 2020-10-12 12:12:58,513 Task._call(1069) INFO 2.81% complete 2020-10-12 12:12:58,517 Task._call(1069) INFO 2.93% complete 2020-10-12 12:12:58,521 Task._call(1069) INFO 3.06% complete 2020-10-12 12:12:58,525 Task._call(1069) INFO 3.19% complete 2020-10-12 12:12:58,529 Task._call(1069) INFO 3.32% complete 2020-10-12 12:12:58,533 Task._call(1069) INFO 3.45% complete 2020-10-12 12:12:58,537 Task._call(1069) INFO 3.57% complete 2020-10-12 12:12:58,541 Task._call(1069) INFO 3.70% complete 2020-10-12 12:12:58,545 Task._call(1069) INFO 3.83% complete 2020-10-12 12:12:58,549 Task._call(1069) INFO 3.96% complete 2020-10-12 12:12:58,553 Task._call(1069) INFO 4.08% complete 2020-10-12 12:12:58,557 Task._call(1069) INFO 4.21% complete 2020-10-12 12:12:58,560 Task._call(1069) INFO 4.34% complete 2020-10-12 12:12:58,564 Task._call(1069) INFO 4.47% complete 2020-10-12 12:12:58,566 Task._call(1069) INFO 4.49% complete 2020-10-12 12:12:58,570 Task._call(1069) INFO 0.25% complete 2020-10-12 12:12:58,574 Task._call(1069) INFO 0.51% complete 2020-10-12 12:12:58,578 Task._call(1069) INFO 0.77% complete 2020-10-12 12:12:58,582 Task._call(1069) INFO 1.02% complete 2020-10-12 12:12:58,586 Task._call(1069) INFO 1.28% complete 2020-10-12 12:12:58,590 Task._call(1069) INFO 1.53% complete 2020-10-12 12:12:58,594 Task._call(1069) INFO 1.79% complete 2020-10-12 12:12:58,598 Task._call(1069) INFO 2.04% complete 2020-10-12 12:12:58,602 Task._call(1069) INFO 2.30% complete 2020-10-12 12:12:58,606 Task._call(1069) INFO 2.56% complete 2020-10-12 12:12:58,637 Task._call(1069) INFO 2.81% complete 2020-10-12 12:12:58,661 Task._call(1069) INFO 3.07% complete 2020-10-12 12:12:58,771 Task._call(1069) INFO 3.32% complete 2020-10-12 12:12:58,879 Task._call(1069) INFO 3.58% complete 2020-10-12 12:12:59,505 Task._call(1069) INFO 3.83% complete 2020-10-12 12:12:59,957 Task._call(1069) INFO 4.09% complete 2020-10-12 12:13:00,041 Task._call(1069) INFO 4.35% complete 2020-10-12 12:13:00,045 Task._call(1069) INFO 4.60% complete 2020-10-12 12:13:00,050 Task._call(1069) INFO 4.86% complete 2020-10-12 12:13:00,097 Task._call(1069) INFO 5.11% complete 2020-10-12 12:13:00,194 Task._call(1069) INFO 5.37% complete 2020-10-12 12:13:00,754 Task._call(1069) INFO 5.63% complete 2020-10-12 12:13:01,203 Task._call(1069) INFO 5.88% complete 2020-10-12 12:13:01,297 Task._call(1069) INFO 6.14% complete 2020-10-12 12:13:01,329 Task._call(1069) INFO 6.39% complete 2020-10-12 12:13:01,349 Task._call(1069) INFO 6.65% complete 2020-10-12 12:13:01,353 Task._call(1069) INFO 6.90% complete 2020-10-12 12:13:01,357 Task._call(1069) INFO 7.16% complete 2020-10-12 12:13:01,361 Task._call(1069) INFO 7.42% complete 2020-10-12 12:13:01,365 Task._call(1069) INFO 7.67% complete 2020-10-12 12:13:01,369 Task._call(1069) INFO 7.93% complete 2020-10-12 12:13:01,373 Task._call(1069) INFO 8.18% complete 2020-10-12 12:13:01,377 Task._call(1069) INFO 8.44% complete 2020-10-12 12:13:01,381 Task._call(1069) INFO 8.69% complete 2020-10-12 12:13:01,385 Task._call(1069) INFO 8.95% complete 2020-10-12 12:13:01,386 Task._call(1069) INFO 9.01% complete 2020-10-12 12:13:01,390 Task._call(1069) INFO 0.38% complete 2020-10-12 12:13:01,394 Task._call(1069) INFO 0.77% complete 2020-10-12 12:13:01,398 Task._call(1069) INFO 1.15% complete 2020-10-12 12:13:01,402 Task._call(1069) INFO 1.53% complete 2020-10-12 12:13:01,406 Task._call(1069) INFO 1.92% complete 2020-10-12 12:13:01,409 Task._call(1069) INFO 2.30% complete 2020-10-12 12:13:01,413 Task._call(1069) INFO 2.69% complete 2020-10-12 12:13:01,418 Task._call(1069) INFO 3.07% complete 2020-10-12 12:13:01,422 Task._call(1069) INFO 3.45% complete 2020-10-12 12:13:01,444 Task._call(1069) INFO 3.84% complete 2020-10-12 12:13:01,536 Task._call(1069) INFO 4.22% complete 2020-10-12 12:13:01,642 Task._call(1069) INFO 4.60% complete 2020-10-12 12:13:01,959 Task._call(1069) INFO 4.99% complete 2020-10-12 12:13:02,080 Task._call(1069) INFO 5.37% complete 2020-10-12 12:13:02,175 Task._call(1069) INFO 5.76% complete 2020-10-12 12:13:02,276 Task._call(1069) INFO 6.14% complete 2020-10-12 12:13:02,361 Task._call(1069) INFO 6.52% complete 2020-10-12 12:13:02,419 Task._call(1069) INFO 6.91% complete 2020-10-12 12:13:02,476 Task._call(1069) INFO 7.29% complete 2020-10-12 12:13:02,574 Task._call(1069) INFO 7.68% complete 2020-10-12 12:13:02,690 Task._call(1069) INFO 8.06% complete 2020-10-12 12:13:02,791 Task._call(1069) INFO 8.44% complete 2020-10-12 12:13:02,921 Task._call(1069) INFO 8.83% complete 2020-10-12 12:13:03,022 Task._call(1069) INFO 9.21% complete 2020-10-12 12:13:03,139 Task._call(1069) INFO 9.59% complete 2020-10-12 12:13:03,240 Task._call(1069) INFO 9.98% complete 2020-10-12 12:13:03,313 Task._call(1069) INFO 10.36% complete 2020-10-12 12:13:03,339 Task._call(1069) INFO 10.75% complete 2020-10-12 12:13:03,391 Task._call(1069) INFO 11.13% complete 2020-10-12 12:13:03,414 Task._call(1069) INFO 11.51% complete 2020-10-12 12:13:03,468 Task._call(1069) INFO 11.90% complete 2020-10-12 12:13:03,560 Task._call(1069) INFO 12.28% complete 2020-10-12 12:13:03,583 Task._call(1069) INFO 12.67% complete 2020-10-12 12:13:03,587 Task._call(1069) INFO 13.05% complete 2020-10-12 12:13:03,591 Task._call(1069) INFO 13.43% complete 2020-10-12 12:13:03,593 Task._call(1069) INFO 13.52% complete 2020-10-12 12:13:03,597 Task._call(1069) INFO 0.51% complete 2020-10-12 12:13:03,601 Task._call(1069) INFO 1.02% complete 2020-10-12 12:13:03,605 Task._call(1069) INFO 1.53% complete 2020-10-12 12:13:03,610 Task._call(1069) INFO 2.05% complete 2020-10-12 12:13:03,614 Task._call(1069) INFO 2.56% complete 2020-10-12 12:13:03,618 Task._call(1069) INFO 3.07% complete 2020-10-12 12:13:03,622 Task._call(1069) INFO 3.58% complete 2020-10-12 12:13:03,689 Task._call(1069) INFO 4.09% complete 2020-10-12 12:13:03,772 Task._call(1069) INFO 4.61% complete 2020-10-12 12:13:03,883 Task._call(1069) INFO 5.12% complete 2020-10-12 12:13:04,059 Task._call(1069) INFO 5.63% complete 2020-10-12 12:13:04,219 Task._call(1069) INFO 6.14% complete 2020-10-12 12:13:04,345 Task._call(1069) INFO 6.65% complete 2020-10-12 12:13:04,602 Task._call(1069) INFO 7.17% complete 2020-10-12 12:13:04,753 Task._call(1069) INFO 7.68% complete 2020-10-12 12:13:04,872 Task._call(1069) INFO 8.19% complete 2020-10-12 12:13:05,053 Task._call(1069) INFO 8.70% complete 2020-10-12 12:13:05,206 Task._call(1069) INFO 9.21% complete 2020-10-12 12:13:05,403 Task._call(1069) INFO 9.73% complete 2020-10-12 12:13:05,588 Task._call(1069) INFO 10.24% complete 2020-10-12 12:13:05,740 Task._call(1069) INFO 10.75% complete 2020-10-12 12:13:06,432 Task._call(1069) INFO 11.26% complete 2020-10-12 12:13:06,592 Task._call(1069) INFO 11.77% complete 2020-10-12 12:13:06,840 Task._call(1069) INFO 12.29% complete 2020-10-12 12:13:07,007 Task._call(1069) INFO 12.80% complete 2020-10-12 12:13:07,634 Task._call(1069) INFO 13.31% complete 2020-10-12 12:13:07,814 Task._call(1069) INFO 13.82% complete 2020-10-12 12:13:07,977 Task._call(1069) INFO 14.33% complete 2020-10-12 12:13:08,134 Task._call(1069) INFO 14.85% complete 2020-10-12 12:13:08,294 Task._call(1069) INFO 15.36% complete 2020-10-12 12:13:08,463 Task._call(1069) INFO 15.87% complete 2020-10-12 12:13:10,026 Task._call(1069) INFO 16.38% complete 2020-10-12 12:13:10,504 Task._call(1069) INFO 16.89% complete 2020-10-12 12:13:10,531 Task._call(1069) INFO 17.40% complete 2020-10-12 12:13:10,535 Task._call(1069) INFO 17.92% complete 2020-10-12 12:13:10,537 Task._call(1069) INFO 18.03% complete 2020-10-12 12:13:10,541 Task._call(1069) INFO 0.64% complete 2020-10-12 12:13:10,545 Task._call(1069) INFO 1.28% complete 2020-10-12 12:13:10,549 Task._call(1069) INFO 1.92% complete 2020-10-12 12:13:10,554 Task._call(1069) INFO 2.56% complete 2020-10-12 12:13:10,558 Task._call(1069) INFO 3.20% complete 2020-10-12 12:13:10,562 Task._call(1069) INFO 3.84% complete 2020-10-12 12:13:10,646 Task._call(1069) INFO 4.48% complete 2020-10-12 12:13:10,824 Task._call(1069) INFO 5.12% complete 2020-10-12 12:13:10,996 Task._call(1069) INFO 5.76% complete 2020-10-12 12:13:11,889 Task._call(1069) INFO 6.40% complete 2020-10-12 12:13:12,044 Task._call(1069) INFO 7.04% complete 2020-10-12 12:13:12,205 Task._call(1069) INFO 7.68% complete 2020-10-12 12:13:12,391 Task._call(1069) INFO 8.32% complete 2020-10-12 12:13:12,738 Task._call(1069) INFO 8.96% complete 2020-10-12 12:13:12,871 Task._call(1069) INFO 9.60% complete 2020-10-12 12:13:13,338 Task._call(1069) INFO 10.24% complete 2020-10-12 12:13:13,588 Task._call(1069) INFO 10.88% complete 2020-10-12 12:13:13,749 Task._call(1069) INFO 11.52% complete 2020-10-12 12:13:14,496 Task._call(1069) INFO 12.16% complete 2020-10-12 12:13:14,656 Task._call(1069) INFO 12.80% complete 2020-10-12 12:13:14,823 Task._call(1069) INFO 13.44% complete 2020-10-12 12:13:15,037 Task._call(1069) INFO 14.08% complete 2020-10-12 12:13:15,229 Task._call(1069) INFO 14.72% complete 2020-10-12 12:13:15,370 Task._call(1069) INFO 15.36% complete 2020-10-12 12:13:15,575 Task._call(1069) INFO 16.00% complete 2020-10-12 12:13:15,769 Task._call(1069) INFO 16.64% complete 2020-10-12 12:13:15,950 Task._call(1069) INFO 17.28% complete 2020-10-12 12:13:16,171 Task._call(1069) INFO 17.92% complete 2020-10-12 12:13:16,324 Task._call(1069) INFO 18.56% complete 2020-10-12 12:13:16,562 Task._call(1069) INFO 19.20% complete 2020-10-12 12:13:16,702 Task._call(1069) INFO 19.84% complete 2020-10-12 12:13:16,896 Task._call(1069) INFO 20.48% complete 2020-10-12 12:13:17,013 Task._call(1069) INFO 21.12% complete 2020-10-12 12:13:17,017 Task._call(1069) INFO 21.76% complete 2020-10-12 12:13:17,021 Task._call(1069) INFO 22.40% complete 2020-10-12 12:13:17,023 Task._call(1069) INFO 22.54% complete 2020-10-12 12:13:17,027 Task._call(1069) INFO 0.77% complete 2020-10-12 12:13:17,031 Task._call(1069) INFO 1.53% complete 2020-10-12 12:13:17,035 Task._call(1069) INFO 2.30% complete 2020-10-12 12:13:17,039 Task._call(1069) INFO 3.07% complete 2020-10-12 12:13:17,043 Task._call(1069) INFO 3.84% complete 2020-10-12 12:13:17,047 Task._call(1069) INFO 4.61% complete 2020-10-12 12:13:17,213 Task._call(1069) INFO 5.37% complete 2020-10-12 12:13:17,408 Task._call(1069) INFO 6.14% complete 2020-10-12 12:13:17,584 Task._call(1069) INFO 6.91% complete 2020-10-12 12:13:17,729 Task._call(1069) INFO 7.68% complete 2020-10-12 12:13:17,908 Task._call(1069) INFO 8.45% complete 2020-10-12 12:13:18,685 Task._call(1069) INFO 9.22% complete 2020-10-12 12:13:19,154 Task._call(1069) INFO 9.98% complete 2020-10-12 12:13:19,613 Task._call(1069) INFO 10.75% complete 2020-10-12 12:13:19,831 Task._call(1069) INFO 11.52% complete 2020-10-12 12:13:19,968 Task._call(1069) INFO 12.29% complete 2020-10-12 12:13:20,037 Task._call(1069) INFO 13.06% complete 2020-10-12 12:13:20,214 Task._call(1069) INFO 13.82% complete 2020-10-12 12:13:20,343 Task._call(1069) INFO 14.59% complete 2020-10-12 12:13:20,557 Task._call(1069) INFO 15.36% complete 2020-10-12 12:13:21,416 Task._call(1069) INFO 16.13% complete 2020-10-12 12:13:21,663 Task._call(1069) INFO 16.90% complete 2020-10-12 12:13:21,846 Task._call(1069) INFO 17.67% complete 2020-10-12 12:13:22,021 Task._call(1069) INFO 18.43% complete 2020-10-12 12:13:22,226 Task._call(1069) INFO 19.20% complete 2020-10-12 12:13:22,383 Task._call(1069) INFO 19.97% complete 2020-10-12 12:13:22,553 Task._call(1069) INFO 20.74% complete 2020-10-12 12:13:22,737 Task._call(1069) INFO 21.51% complete 2020-10-12 12:13:22,951 Task._call(1069) INFO 22.27% complete 2020-10-12 12:13:23,115 Task._call(1069) INFO 23.04% complete 2020-10-12 12:13:23,302 Task._call(1069) INFO 23.81% complete 2020-10-12 12:13:23,476 Task._call(1069) INFO 24.58% complete 2020-10-12 12:13:23,560 Task._call(1069) INFO 25.35% complete 2020-10-12 12:13:23,564 Task._call(1069) INFO 26.12% complete 2020-10-12 12:13:23,568 Task._call(1069) INFO 26.88% complete 2020-10-12 12:13:23,570 Task._call(1069) INFO 27.05% complete 2020-10-12 12:13:23,575 Task._call(1069) INFO 0.89% complete 2020-10-12 12:13:23,579 Task._call(1069) INFO 1.79% complete 2020-10-12 12:13:23,583 Task._call(1069) INFO 2.69% complete 2020-10-12 12:13:23,587 Task._call(1069) INFO 3.58% complete 2020-10-12 12:13:23,591 Task._call(1069) INFO 4.48% complete 2020-10-12 12:13:23,669 Task._call(1069) INFO 5.37% complete 2020-10-12 12:13:23,890 Task._call(1069) INFO 6.27% complete 2020-10-12 12:13:24,099 Task._call(1069) INFO 7.17% complete 2020-10-12 12:13:24,384 Task._call(1069) INFO 8.06% complete 2020-10-12 12:13:24,665 Task._call(1069) INFO 8.96% complete 2020-10-12 12:13:27,417 Task._call(1069) INFO 9.86% complete 2020-10-12 12:13:27,585 Task._call(1069) INFO 10.75% complete 2020-10-12 12:13:27,765 Task._call(1069) INFO 11.65% complete 2020-10-12 12:13:27,947 Task._call(1069) INFO 12.54% complete 2020-10-12 12:13:28,099 Task._call(1069) INFO 13.44% complete 2020-10-12 12:13:28,326 Task._call(1069) INFO 14.34% complete 2020-10-12 12:13:28,451 Task._call(1069) INFO 15.23% complete 2020-10-12 12:13:28,567 Task._call(1069) INFO 16.13% complete 2020-10-12 12:13:28,705 Task._call(1069) INFO 17.03% complete 2020-10-12 12:13:28,903 Task._call(1069) INFO 17.92% complete 2020-10-12 12:13:29,096 Task._call(1069) INFO 18.82% complete 2020-10-12 12:13:29,484 Task._call(1069) INFO 19.72% complete 2020-10-12 12:13:29,653 Task._call(1069) INFO 20.61% complete 2020-10-12 12:13:29,847 Task._call(1069) INFO 21.51% complete 2020-10-12 12:13:30,085 Task._call(1069) INFO 22.40% complete 2020-10-12 12:13:30,281 Task._call(1069) INFO 23.30% complete 2020-10-12 12:13:30,858 Task._call(1069) INFO 24.20% complete 2020-10-12 12:13:31,086 Task._call(1069) INFO 25.09% complete 2020-10-12 12:13:31,292 Task._call(1069) INFO 25.99% complete 2020-10-12 12:13:31,464 Task._call(1069) INFO 26.89% complete 2020-10-12 12:13:31,851 Task._call(1069) INFO 27.78% complete 2020-10-12 12:13:31,938 Task._call(1069) INFO 28.68% complete 2020-10-12 12:13:32,023 Task._call(1069) INFO 29.57% complete 2020-10-12 12:13:32,027 Task._call(1069) INFO 30.47% complete 2020-10-12 12:13:32,031 Task._call(1069) INFO 31.37% complete 2020-10-12 12:13:32,033 Task._call(1069) INFO 31.57% complete 2020-10-12 12:13:32,037 Task._call(1069) INFO 1.02% complete 2020-10-12 12:13:32,040 Task._call(1069) INFO 2.04% complete 2020-10-12 12:13:32,044 Task._call(1069) INFO 3.07% complete 2020-10-12 12:13:32,093 Task._call(1069) INFO 4.09% complete 2020-10-12 12:13:32,199 Task._call(1069) INFO 5.12% complete 2020-10-12 12:13:32,371 Task._call(1069) INFO 6.14% complete 2020-10-12 12:13:32,621 Task._call(1069) INFO 7.17% complete 2020-10-12 12:13:32,780 Task._call(1069) INFO 8.19% complete 2020-10-12 12:13:32,960 Task._call(1069) INFO 9.22% complete 2020-10-12 12:13:33,110 Task._call(1069) INFO 10.24% complete 2020-10-12 12:13:34,099 Task._call(1069) INFO 11.26% complete 2020-10-12 12:13:34,314 Task._call(1069) INFO 12.29% complete 2020-10-12 12:13:35,367 Task._call(1069) INFO 13.31% complete 2020-10-12 12:13:35,572 Task._call(1069) INFO 14.34% complete 2020-10-12 12:13:36,782 Task._call(1069) INFO 15.36% complete 2020-10-12 12:13:36,971 Task._call(1069) INFO 16.39% complete 2020-10-12 12:13:37,123 Task._call(1069) INFO 17.41% complete 2020-10-12 12:13:37,304 Task._call(1069) INFO 18.44% complete 2020-10-12 12:13:37,489 Task._call(1069) INFO 19.46% complete 2020-10-12 12:13:37,680 Task._call(1069) INFO 20.48% complete 2020-10-12 12:13:37,891 Task._call(1069) INFO 21.51% complete 2020-10-12 12:13:38,116 Task._call(1069) INFO 22.53% complete 2020-10-12 12:13:38,275 Task._call(1069) INFO 23.56% complete 2020-10-12 12:13:38,472 Task._call(1069) INFO 24.58% complete 2020-10-12 12:13:38,647 Task._call(1069) INFO 25.61% complete 2020-10-12 12:13:39,835 Task._call(1069) INFO 26.63% complete 2020-10-12 12:13:39,980 Task._call(1069) INFO 27.66% complete 2020-10-12 12:13:40,163 Task._call(1069) INFO 28.68% complete 2020-10-12 12:13:40,342 Task._call(1069) INFO 29.70% complete 2020-10-12 12:13:40,516 Task._call(1069) INFO 30.73% complete 2020-10-12 12:13:41,158 Task._call(1069) INFO 31.75% complete 2020-10-12 12:13:41,163 Task._call(1069) INFO 32.78% complete 2020-10-12 12:13:41,167 Task._call(1069) INFO 33.80% complete 2020-10-12 12:13:41,171 Task._call(1069) INFO 34.83% complete 2020-10-12 12:13:41,175 Task._call(1069) INFO 35.85% complete 2020-10-12 12:13:41,178 Task._call(1069) INFO 36.08% complete 2020-10-12 12:13:41,182 Task._call(1069) INFO 1.15% complete 2020-10-12 12:13:41,563 Task._call(1069) INFO 2.30% complete 2020-10-12 12:13:41,656 Task._call(1069) INFO 3.45% complete 2020-10-12 12:13:41,809 Task._call(1069) INFO 4.61% complete 2020-10-12 12:13:42,006 Task._call(1069) INFO 5.76% complete 2020-10-12 12:13:42,183 Task._call(1069) INFO 6.91% complete 2020-10-12 12:13:42,400 Task._call(1069) INFO 8.06% complete 2020-10-12 12:13:45,552 Task._call(1069) INFO 9.22% complete 2020-10-12 12:13:47,491 Task._call(1069) INFO 10.37% complete 2020-10-12 12:13:47,699 Task._call(1069) INFO 11.52% complete 2020-10-12 12:13:47,876 Task._call(1069) INFO 12.67% complete 2020-10-12 12:13:50,133 Task._call(1069) INFO 13.83% complete 2020-10-12 12:13:50,301 Task._call(1069) INFO 14.98% complete 2020-10-12 12:13:50,483 Task._call(1069) INFO 16.13% complete 2020-10-12 12:13:50,674 Task._call(1069) INFO 17.28% complete 2020-10-12 12:13:50,808 Task._call(1069) INFO 18.44% complete 2020-10-12 12:13:51,134 Task._call(1069) INFO 19.59% complete 2020-10-12 12:13:51,375 Task._call(1069) INFO 20.74% complete 2020-10-12 12:13:51,519 Task._call(1069) INFO 21.89% complete 2020-10-12 12:13:51,710 Task._call(1069) INFO 23.05% complete 2020-10-12 12:13:51,892 Task._call(1069) INFO 24.20% complete 2020-10-12 12:13:52,061 Task._call(1069) INFO 25.35% complete 2020-10-12 12:13:53,261 Task._call(1069) INFO 26.50% complete 2020-10-12 12:13:53,431 Task._call(1069) INFO 27.66% complete 2020-10-12 12:13:54,251 Task._call(1069) INFO 28.81% complete 2020-10-12 12:13:54,433 Task._call(1069) INFO 29.96% complete 2020-10-12 12:13:54,641 Task._call(1069) INFO 31.11% complete 2020-10-12 12:13:54,837 Task._call(1069) INFO 32.27% complete 2020-10-12 12:13:54,994 Task._call(1069) INFO 33.42% complete 2020-10-12 12:13:55,403 Task._call(1069) INFO 34.57% complete 2020-10-12 12:13:55,437 Task._call(1069) INFO 35.72% complete 2020-10-12 12:13:55,441 Task._call(1069) INFO 36.88% complete 2020-10-12 12:13:55,446 Task._call(1069) INFO 38.03% complete 2020-10-12 12:13:55,450 Task._call(1069) INFO 39.18% complete 2020-10-12 12:13:55,454 Task._call(1069) INFO 40.33% complete 2020-10-12 12:13:55,456 Task._call(1069) INFO 40.59% complete 2020-10-12 12:13:55,460 Task._call(1069) INFO 1.28% complete 2020-10-12 12:13:55,585 Task._call(1069) INFO 2.56% complete 2020-10-12 12:13:55,781 Task._call(1069) INFO 3.84% complete 2020-10-12 12:13:56,015 Task._call(1069) INFO 5.12% complete 2020-10-12 12:13:56,208 Task._call(1069) INFO 6.40% complete 2020-10-12 12:13:56,392 Task._call(1069) INFO 7.68% complete 2020-10-12 12:13:56,547 Task._call(1069) INFO 8.96% complete 2020-10-12 12:13:56,733 Task._call(1069) INFO 10.24% complete 2020-10-12 12:13:56,879 Task._call(1069) INFO 11.52% complete 2020-10-12 12:13:57,085 Task._call(1069) INFO 12.80% complete 2020-10-12 12:13:57,339 Task._call(1069) INFO 14.08% complete 2020-10-12 12:13:57,531 Task._call(1069) INFO 15.36% complete 2020-10-12 12:13:58,493 Task._call(1069) INFO 16.64% complete 2020-10-12 12:13:58,641 Task._call(1069) INFO 17.92% complete 2020-10-12 12:13:58,844 Task._call(1069) INFO 19.20% complete 2020-10-12 12:14:01,195 Task._call(1069) INFO 20.49% complete 2020-10-12 12:14:01,382 Task._call(1069) INFO 21.77% complete 2020-10-12 12:14:01,586 Task._call(1069) INFO 23.05% complete 2020-10-12 12:14:01,770 Task._call(1069) INFO 24.33% complete 2020-10-12 12:14:01,967 Task._call(1069) INFO 25.61% complete 2020-10-12 12:14:02,137 Task._call(1069) INFO 26.89% complete 2020-10-12 12:14:02,278 Task._call(1069) INFO 28.17% complete 2020-10-12 12:14:02,468 Task._call(1069) INFO 29.45% complete 2020-10-12 12:14:02,710 Task._call(1069) INFO 30.73% complete 2020-10-12 12:14:02,893 Task._call(1069) INFO 32.01% complete 2020-10-12 12:14:03,101 Task._call(1069) INFO 33.29% complete 2020-10-12 12:14:03,729 Task._call(1069) INFO 34.57% complete 2020-10-12 12:14:03,993 Task._call(1069) INFO 35.85% complete 2020-10-12 12:14:04,174 Task._call(1069) INFO 37.13% complete 2020-10-12 12:14:04,351 Task._call(1069) INFO 38.41% complete 2020-10-12 12:14:04,510 Task._call(1069) INFO 39.70% complete 2020-10-12 12:14:04,537 Task._call(1069) INFO 40.98% complete 2020-10-12 12:14:04,542 Task._call(1069) INFO 42.26% complete 2020-10-12 12:14:04,546 Task._call(1069) INFO 43.54% complete 2020-10-12 12:14:04,550 Task._call(1069) INFO 44.82% complete 2020-10-12 12:14:04,552 Task._call(1069) INFO 45.10% complete 2020-10-12 12:14:04,643 Task._call(1069) INFO 1.40% complete 2020-10-12 12:14:04,820 Task._call(1069) INFO 2.81% complete 2020-10-12 12:14:05,197 Task._call(1069) INFO 4.22% complete 2020-10-12 12:14:05,340 Task._call(1069) INFO 5.63% complete 2020-10-12 12:14:05,517 Task._call(1069) INFO 7.04% complete 2020-10-12 12:14:05,772 Task._call(1069) INFO 8.45% complete 2020-10-12 12:14:05,963 Task._call(1069) INFO 9.86% complete 2020-10-12 12:14:06,133 Task._call(1069) INFO 11.26% complete 2020-10-12 12:14:06,341 Task._call(1069) INFO 12.67% complete 2020-10-12 12:14:06,512 Task._call(1069) INFO 14.08% complete 2020-10-12 12:14:06,733 Task._call(1069) INFO 15.49% complete 2020-10-12 12:14:06,948 Task._call(1069) INFO 16.90% complete 2020-10-12 12:14:07,051 Task._call(1069) INFO 18.31% complete 2020-10-12 12:14:07,262 Task._call(1069) INFO 19.72% complete 2020-10-12 12:14:07,722 Task._call(1069) INFO 21.13% complete 2020-10-12 12:14:07,907 Task._call(1069) INFO 22.53% complete 2020-10-12 12:14:08,172 Task._call(1069) INFO 23.94% complete 2020-10-12 12:14:08,364 Task._call(1069) INFO 25.35% complete 2020-10-12 12:14:08,521 Task._call(1069) INFO 26.76% complete 2020-10-12 12:14:12,238 Task._call(1069) INFO 28.17% complete 2020-10-12 12:14:12,376 Task._call(1069) INFO 29.58% complete 2020-10-12 12:14:13,443 Task._call(1069) INFO 30.99% complete 2020-10-12 12:14:13,629 Task._call(1069) INFO 32.40% complete 2020-10-12 12:14:13,786 Task._call(1069) INFO 33.81% complete 2020-10-12 12:14:13,955 Task._call(1069) INFO 35.21% complete 2020-10-12 12:14:14,427 Task._call(1069) INFO 36.62% complete 2020-10-12 12:14:14,586 Task._call(1069) INFO 38.03% complete 2020-10-12 12:14:14,768 Task._call(1069) INFO 39.44% complete 2020-10-12 12:14:15,000 Task._call(1069) INFO 40.85% complete 2020-10-12 12:14:15,206 Task._call(1069) INFO 42.26% complete 2020-10-12 12:14:15,333 Task._call(1069) INFO 43.67% complete 2020-10-12 12:14:17,991 Task._call(1069) INFO 45.08% complete 2020-10-12 12:14:17,995 Task._call(1069) INFO 46.48% complete 2020-10-12 12:14:18,177 Task._call(1069) INFO 47.89% complete 2020-10-12 12:14:18,182 Task._call(1069) INFO 49.30% complete 2020-10-12 12:14:18,184 Task._call(1069) INFO 49.62% complete 2020-10-12 12:14:18,380 Task._call(1069) INFO 1.53% complete 2020-10-12 12:14:18,570 Task._call(1069) INFO 3.07% complete 2020-10-12 12:14:18,922 Task._call(1069) INFO 4.60% complete 2020-10-12 12:14:19,121 Task._call(1069) INFO 6.14% complete 2020-10-12 12:14:19,296 Task._call(1069) INFO 7.68% complete 2020-10-12 12:14:19,495 Task._call(1069) INFO 9.22% complete 2020-10-12 12:14:19,679 Task._call(1069) INFO 10.75% complete 2020-10-12 12:14:19,894 Task._call(1069) INFO 12.29% complete 2020-10-12 12:14:20,041 Task._call(1069) INFO 13.83% complete 2020-10-12 12:14:20,252 Task._call(1069) INFO 15.36% complete 2020-10-12 12:14:20,437 Task._call(1069) INFO 16.90% complete 2020-10-12 12:14:20,602 Task._call(1069) INFO 18.44% complete 2020-10-12 12:14:20,820 Task._call(1069) INFO 19.97% complete 2020-10-12 12:14:21,035 Task._call(1069) INFO 21.51% complete 2020-10-12 12:14:21,252 Task._call(1069) INFO 23.05% complete 2020-10-12 12:14:21,412 Task._call(1069) INFO 24.58% complete 2020-10-12 12:14:21,629 Task._call(1069) INFO 26.12% complete 2020-10-12 12:14:21,806 Task._call(1069) INFO 27.66% complete 2020-10-12 12:14:22,056 Task._call(1069) INFO 29.20% complete 2020-10-12 12:14:22,197 Task._call(1069) INFO 30.73% complete 2020-10-12 12:14:22,598 Task._call(1069) INFO 32.27% complete 2020-10-12 12:14:22,715 Task._call(1069) INFO 33.81% complete 2020-10-12 12:14:22,933 Task._call(1069) INFO 35.34% complete 2020-10-12 12:14:23,156 Task._call(1069) INFO 36.88% complete 2020-10-12 12:14:23,343 Task._call(1069) INFO 38.42% complete 2020-10-12 12:14:23,529 Task._call(1069) INFO 39.95% complete 2020-10-12 12:14:23,717 Task._call(1069) INFO 41.49% complete 2020-10-12 12:14:23,872 Task._call(1069) INFO 43.03% complete 2020-10-12 12:14:24,141 Task._call(1069) INFO 44.56% complete 2020-10-12 12:14:24,295 Task._call(1069) INFO 46.10% complete 2020-10-12 12:14:24,478 Task._call(1069) INFO 47.64% complete 2020-10-12 12:14:24,622 Task._call(1069) INFO 49.17% complete 2020-10-12 12:14:24,629 Task._call(1069) INFO 50.71% complete 2020-10-12 12:14:24,633 Task._call(1069) INFO 52.25% complete 2020-10-12 12:14:24,637 Task._call(1069) INFO 53.79% complete 2020-10-12 12:14:24,638 Task._call(1069) INFO 54.13% complete 2020-10-12 12:14:24,986 Task._call(1069) INFO 1.66% complete 2020-10-12 12:14:25,178 Task._call(1069) INFO 3.32% complete 2020-10-12 12:14:25,352 Task._call(1069) INFO 4.99% complete 2020-10-12 12:14:25,540 Task._call(1069) INFO 6.65% complete 2020-10-12 12:14:25,726 Task._call(1069) INFO 8.32% complete 2020-10-12 12:14:25,905 Task._call(1069) INFO 9.98% complete 2020-10-12 12:14:26,162 Task._call(1069) INFO 11.65% complete 2020-10-12 12:14:26,428 Task._call(1069) INFO 13.31% complete 2020-10-12 12:14:27,030 Task._call(1069) INFO 14.98% complete 2020-10-12 12:14:27,225 Task._call(1069) INFO 16.64% complete 2020-10-12 12:14:27,483 Task._call(1069) INFO 18.31% complete 2020-10-12 12:14:28,368 Task._call(1069) INFO 19.97% complete 2020-10-12 12:14:29,347 Task._call(1069) INFO 21.64% complete 2020-10-12 12:14:29,516 Task._call(1069) INFO 23.30% complete 2020-10-12 12:14:29,678 Task._call(1069) INFO 24.97% complete 2020-10-12 12:14:29,852 Task._call(1069) INFO 26.63% complete 2020-10-12 12:14:30,030 Task._call(1069) INFO 28.30% complete 2020-10-12 12:14:30,710 Task._call(1069) INFO 29.96% complete 2020-10-12 12:14:31,168 Task._call(1069) INFO 31.63% complete 2020-10-12 12:14:31,326 Task._call(1069) INFO 33.29% complete 2020-10-12 12:14:31,502 Task._call(1069) INFO 34.96% complete 2020-10-12 12:14:31,611 Task._call(1069) INFO 36.62% complete 2020-10-12 12:14:32,119 Task._call(1069) INFO 38.29% complete 2020-10-12 12:14:32,396 Task._call(1069) INFO 39.95% complete 2020-10-12 12:14:32,563 Task._call(1069) INFO 41.62% complete 2020-10-12 12:14:32,731 Task._call(1069) INFO 43.28% complete 2020-10-12 12:14:32,921 Task._call(1069) INFO 44.95% complete 2020-10-12 12:14:33,130 Task._call(1069) INFO 46.61% complete 2020-10-12 12:14:33,282 Task._call(1069) INFO 48.28% complete 2020-10-12 12:14:33,505 Task._call(1069) INFO 49.94% complete 2020-10-12 12:14:33,714 Task._call(1069) INFO 51.61% complete 2020-10-12 12:14:33,901 Task._call(1069) INFO 53.27% complete 2020-10-12 12:14:33,984 Task._call(1069) INFO 54.94% complete 2020-10-12 12:14:33,988 Task._call(1069) INFO 56.60% complete 2020-10-12 12:14:33,992 Task._call(1069) INFO 58.27% complete 2020-10-12 12:14:33,994 Task._call(1069) INFO 58.64% complete 2020-10-12 12:14:34,188 Task._call(1069) INFO 1.79% complete 2020-10-12 12:14:34,456 Task._call(1069) INFO 3.58% complete 2020-10-12 12:14:35,378 Task._call(1069) INFO 5.37% complete 2020-10-12 12:14:35,531 Task._call(1069) INFO 7.17% complete 2020-10-12 12:14:36,791 Task._call(1069) INFO 8.96% complete 2020-10-12 12:14:36,952 Task._call(1069) INFO 10.75% complete 2020-10-12 12:14:37,177 Task._call(1069) INFO 12.54% complete 2020-10-12 12:14:37,337 Task._call(1069) INFO 14.34% complete 2020-10-12 12:14:37,836 Task._call(1069) INFO 16.13% complete 2020-10-12 12:14:38,003 Task._call(1069) INFO 17.92% complete 2020-10-12 12:14:38,204 Task._call(1069) INFO 19.72% complete 2020-10-12 12:14:38,347 Task._call(1069) INFO 21.51% complete 2020-10-12 12:14:38,582 Task._call(1069) INFO 23.30% complete 2020-10-12 12:14:38,733 Task._call(1069) INFO 25.10% complete 2020-10-12 12:14:38,952 Task._call(1069) INFO 26.89% complete 2020-10-12 12:14:39,115 Task._call(1069) INFO 28.68% complete 2020-10-12 12:14:39,300 Task._call(1069) INFO 30.48% complete 2020-10-12 12:14:40,578 Task._call(1069) INFO 32.27% complete 2020-10-12 12:14:40,791 Task._call(1069) INFO 34.06% complete 2020-10-12 12:14:40,968 Task._call(1069) INFO 35.86% complete 2020-10-12 12:14:41,122 Task._call(1069) INFO 37.65% complete 2020-10-12 12:14:42,363 Task._call(1069) INFO 39.44% complete 2020-10-12 12:14:42,443 Task._call(1069) INFO 41.23% complete 2020-10-12 12:14:42,858 Task._call(1069) INFO 43.03% complete 2020-10-12 12:14:43,053 Task._call(1069) INFO 44.82% complete 2020-10-12 12:14:43,299 Task._call(1069) INFO 46.61% complete 2020-10-12 12:14:43,498 Task._call(1069) INFO 48.41% complete 2020-10-12 12:14:43,671 Task._call(1069) INFO 50.20% complete 2020-10-12 12:14:43,875 Task._call(1069) INFO 51.99% complete 2020-10-12 12:14:44,882 Task._call(1069) INFO 53.79% complete 2020-10-12 12:14:45,040 Task._call(1069) INFO 55.58% complete 2020-10-12 12:14:45,249 Task._call(1069) INFO 57.37% complete 2020-10-12 12:14:46,464 Task._call(1069) INFO 59.17% complete 2020-10-12 12:14:46,499 Task._call(1069) INFO 60.96% complete 2020-10-12 12:14:46,503 Task._call(1069) INFO 62.75% complete 2020-10-12 12:14:46,505 Task._call(1069) INFO 63.15% complete 2020-10-12 12:14:46,805 Task._call(1069) INFO 1.91% complete 2020-10-12 12:14:46,877 Task._call(1069) INFO 3.83% complete 2020-10-12 12:14:46,965 Task._call(1069) INFO 5.76% complete 2020-10-12 12:14:47,105 Task._call(1069) INFO 7.68% complete 2020-10-12 12:14:47,255 Task._call(1069) INFO 9.60% complete 2020-10-12 12:14:48,907 Task._call(1069) INFO 11.52% complete 2020-10-12 12:14:49,050 Task._call(1069) INFO 13.44% complete 2020-10-12 12:14:49,195 Task._call(1069) INFO 15.36% complete 2020-10-12 12:14:51,928 Task._call(1069) INFO 17.28% complete 2020-10-12 12:14:52,110 Task._call(1069) INFO 19.20% complete 2020-10-12 12:14:52,287 Task._call(1069) INFO 21.13% complete 2020-10-12 12:14:52,563 Task._call(1069) INFO 23.05% complete 2020-10-12 12:14:52,725 Task._call(1069) INFO 24.97% complete 2020-10-12 12:14:52,912 Task._call(1069) INFO 26.89% complete 2020-10-12 12:14:53,048 Task._call(1069) INFO 28.81% complete 2020-10-12 12:14:53,210 Task._call(1069) INFO 30.73% complete 2020-10-12 12:14:53,774 Task._call(1069) INFO 32.65% complete 2020-10-12 12:14:54,202 Task._call(1069) INFO 34.57% complete 2020-10-12 12:14:54,344 Task._call(1069) INFO 36.50% complete 2020-10-12 12:14:54,486 Task._call(1069) INFO 38.42% complete 2020-10-12 12:14:54,795 Task._call(1069) INFO 40.34% complete 2020-10-12 12:14:56,116 Task._call(1069) INFO 42.26% complete 2020-10-12 12:14:56,826 Task._call(1069) INFO 44.18% complete 2020-10-12 12:14:56,935 Task._call(1069) INFO 46.10% complete 2020-10-12 12:14:57,585 Task._call(1069) INFO 48.02% complete 2020-10-12 12:14:57,863 Task._call(1069) INFO 49.94% complete 2020-10-12 12:14:58,030 Task._call(1069) INFO 51.87% complete 2020-10-12 12:14:58,223 Task._call(1069) INFO 53.79% complete 2020-10-12 12:14:58,418 Task._call(1069) INFO 55.71% complete 2020-10-12 12:14:58,599 Task._call(1069) INFO 57.63% complete 2020-10-12 12:14:58,766 Task._call(1069) INFO 59.55% complete 2020-10-12 12:14:59,552 Task._call(1069) INFO 61.47% complete 2020-10-12 12:14:59,626 Task._call(1069) INFO 63.39% complete 2020-10-12 12:14:59,630 Task._call(1069) INFO 65.31% complete 2020-10-12 12:14:59,635 Task._call(1069) INFO 67.24% complete 2020-10-12 12:14:59,637 Task._call(1069) INFO 67.66% complete 2020-10-12 12:14:59,641 Task._call(1069) INFO 2.04% complete 2020-10-12 12:14:59,646 Task._call(1069) INFO 4.09% complete 2020-10-12 12:14:59,650 Task._call(1069) INFO 6.14% complete 2020-10-12 12:14:59,767 Task._call(1069) INFO 8.19% complete 2020-10-12 12:15:00,142 Task._call(1069) INFO 10.24% complete 2020-10-12 12:15:01,030 Task._call(1069) INFO 12.29% complete 2020-10-12 12:15:01,335 Task._call(1069) INFO 14.34% complete 2020-10-12 12:15:01,568 Task._call(1069) INFO 16.39% complete 2020-10-12 12:15:02,500 Task._call(1069) INFO 18.44% complete 2020-10-12 12:15:02,921 Task._call(1069) INFO 20.49% complete 2020-10-12 12:15:04,838 Task._call(1069) INFO 22.53% complete 2020-10-12 12:15:05,004 Task._call(1069) INFO 24.58% complete 2020-10-12 12:15:05,296 Task._call(1069) INFO 26.63% complete 2020-10-12 12:15:05,445 Task._call(1069) INFO 28.68% complete 2020-10-12 12:15:05,596 Task._call(1069) INFO 30.73% complete 2020-10-12 12:15:06,579 Task._call(1069) INFO 32.78% complete 2020-10-12 12:15:06,750 Task._call(1069) INFO 34.83% complete 2020-10-12 12:15:06,988 Task._call(1069) INFO 36.88% complete 2020-10-12 12:15:07,168 Task._call(1069) INFO 38.93% complete 2020-10-12 12:15:09,997 Task._call(1069) INFO 40.98% complete 2020-10-12 12:15:10,044 Task._call(1069) INFO 43.03% complete 2020-10-12 12:15:10,114 Task._call(1069) INFO 45.08% complete 2020-10-12 12:15:10,210 Task._call(1069) INFO 47.13% complete 2020-10-12 12:15:10,252 Task._call(1069) INFO 49.18% complete 2020-10-12 12:15:10,402 Task._call(1069) INFO 51.23% complete 2020-10-12 12:15:10,571 Task._call(1069) INFO 53.28% complete 2020-10-12 12:15:10,735 Task._call(1069) INFO 55.32% complete 2020-10-12 12:15:10,934 Task._call(1069) INFO 57.37% complete 2020-10-12 12:15:11,134 Task._call(1069) INFO 59.42% complete 2020-10-12 12:15:11,353 Task._call(1069) INFO 61.47% complete 2020-10-12 12:15:11,549 Task._call(1069) INFO 63.52% complete 2020-10-12 12:15:12,385 Task._call(1069) INFO 65.57% complete 2020-10-12 12:15:12,553 Task._call(1069) INFO 67.62% complete 2020-10-12 12:15:12,619 Task._call(1069) INFO 69.67% complete 2020-10-12 12:15:12,623 Task._call(1069) INFO 71.72% complete 2020-10-12 12:15:12,624 Task._call(1069) INFO 72.18% complete 2020-10-12 12:15:12,628 Task._call(1069) INFO 2.17% complete 2020-10-12 12:15:12,632 Task._call(1069) INFO 4.35% complete 2020-10-12 12:15:12,636 Task._call(1069) INFO 6.52% complete 2020-10-12 12:15:12,640 Task._call(1069) INFO 8.70% complete 2020-10-12 12:15:12,764 Task._call(1069) INFO 10.88% complete 2020-10-12 12:15:13,717 Task._call(1069) INFO 13.06% complete 2020-10-12 12:15:13,890 Task._call(1069) INFO 15.23% complete 2020-10-12 12:15:14,084 Task._call(1069) INFO 17.41% complete 2020-10-12 12:15:16,426 Task._call(1069) INFO 19.59% complete 2020-10-12 12:15:16,586 Task._call(1069) INFO 21.77% complete 2020-10-12 12:15:16,793 Task._call(1069) INFO 23.94% complete 2020-10-12 12:15:16,962 Task._call(1069) INFO 26.12% complete 2020-10-12 12:15:17,172 Task._call(1069) INFO 28.30% complete 2020-10-12 12:15:17,324 Task._call(1069) INFO 30.48% complete 2020-10-12 12:15:17,523 Task._call(1069) INFO 32.65% complete 2020-10-12 12:15:18,062 Task._call(1069) INFO 34.83% complete 2020-10-12 12:15:18,245 Task._call(1069) INFO 37.01% complete 2020-10-12 12:15:18,380 Task._call(1069) INFO 39.19% complete 2020-10-12 12:15:18,447 Task._call(1069) INFO 41.36% complete 2020-10-12 12:15:18,452 Task._call(1069) INFO 43.54% complete 2020-10-12 12:15:18,456 Task._call(1069) INFO 45.72% complete 2020-10-12 12:15:18,460 Task._call(1069) INFO 47.90% complete 2020-10-12 12:15:18,465 Task._call(1069) INFO 50.07% complete 2020-10-12 12:15:18,469 Task._call(1069) INFO 52.25% complete 2020-10-12 12:15:19,436 Task._call(1069) INFO 54.43% complete 2020-10-12 12:15:19,711 Task._call(1069) INFO 56.61% complete 2020-10-12 12:15:20,599 Task._call(1069) INFO 58.78% complete 2020-10-12 12:15:20,791 Task._call(1069) INFO 60.96% complete 2020-10-12 12:15:20,966 Task._call(1069) INFO 63.14% complete 2020-10-12 12:15:21,141 Task._call(1069) INFO 65.32% complete 2020-10-12 12:15:21,339 Task._call(1069) INFO 67.49% complete 2020-10-12 12:15:22,289 Task._call(1069) INFO 69.67% complete 2020-10-12 12:15:22,438 Task._call(1069) INFO 71.85% complete 2020-10-12 12:15:22,599 Task._call(1069) INFO 74.03% complete 2020-10-12 12:15:22,675 Task._call(1069) INFO 76.20% complete 2020-10-12 12:15:22,678 Task._call(1069) INFO 76.69% complete 2020-10-12 12:15:22,682 Task._call(1069) INFO 2.30% complete 2020-10-12 12:15:22,686 Task._call(1069) INFO 4.60% complete 2020-10-12 12:15:22,690 Task._call(1069) INFO 6.91% complete 2020-10-12 12:15:22,694 Task._call(1069) INFO 9.21% complete 2020-10-12 12:15:22,698 Task._call(1069) INFO 11.52% complete 2020-10-12 12:15:22,702 Task._call(1069) INFO 13.82% complete 2020-10-12 12:15:23,322 Task._call(1069) INFO 16.13% complete 2020-10-12 12:15:23,612 Task._call(1069) INFO 18.44% complete 2020-10-12 12:15:23,800 Task._call(1069) INFO 20.74% complete 2020-10-12 12:15:25,025 Task._call(1069) INFO 23.05% complete 2020-10-12 12:15:25,814 Task._call(1069) INFO 25.35% complete 2020-10-12 12:15:26,026 Task._call(1069) INFO 27.66% complete 2020-10-12 12:15:26,213 Task._call(1069) INFO 29.96% complete 2020-10-12 12:15:26,381 Task._call(1069) INFO 32.27% complete 2020-10-12 12:15:26,530 Task._call(1069) INFO 34.57% complete 2020-10-12 12:15:26,911 Task._call(1069) INFO 36.88% complete 2020-10-12 12:15:27,062 Task._call(1069) INFO 39.19% complete 2020-10-12 12:15:27,181 Task._call(1069) INFO 41.49% complete 2020-10-12 12:15:27,206 Task._call(1069) INFO 43.80% complete 2020-10-12 12:15:27,210 Task._call(1069) INFO 46.10% complete 2020-10-12 12:15:27,215 Task._call(1069) INFO 48.41% complete 2020-10-12 12:15:27,219 Task._call(1069) INFO 50.71% complete 2020-10-12 12:15:27,224 Task._call(1069) INFO 53.02% complete 2020-10-12 12:15:27,228 Task._call(1069) INFO 55.33% complete 2020-10-12 12:15:27,278 Task._call(1069) INFO 57.63% complete 2020-10-12 12:15:27,450 Task._call(1069) INFO 59.94% complete 2020-10-12 12:15:27,594 Task._call(1069) INFO 62.24% complete 2020-10-12 12:15:27,795 Task._call(1069) INFO 64.55% complete 2020-10-12 12:15:27,984 Task._call(1069) INFO 66.85% complete 2020-10-12 12:15:28,184 Task._call(1069) INFO 69.16% complete 2020-10-12 12:15:28,431 Task._call(1069) INFO 71.46% complete 2020-10-12 12:15:28,607 Task._call(1069) INFO 73.77% complete 2020-10-12 12:15:28,786 Task._call(1069) INFO 76.08% complete 2020-10-12 12:15:29,025 Task._call(1069) INFO 78.38% complete 2020-10-12 12:15:29,143 Task._call(1069) INFO 80.69% complete 2020-10-12 12:15:29,170 Task._call(1069) INFO 81.20% complete 2020-10-12 12:15:29,174 Task._call(1069) INFO 2.42% complete 2020-10-12 12:15:29,178 Task._call(1069) INFO 4.86% complete 2020-10-12 12:15:29,183 Task._call(1069) INFO 7.29% complete 2020-10-12 12:15:29,187 Task._call(1069) INFO 9.73% complete 2020-10-12 12:15:29,191 Task._call(1069) INFO 12.16% complete 2020-10-12 12:15:29,195 Task._call(1069) INFO 14.59% complete 2020-10-12 12:15:29,365 Task._call(1069) INFO 17.03% complete 2020-10-12 12:15:30,583 Task._call(1069) INFO 19.46% complete 2020-10-12 12:15:31,185 Task._call(1069) INFO 21.89% complete 2020-10-12 12:15:31,376 Task._call(1069) INFO 24.33% complete 2020-10-12 12:15:31,563 Task._call(1069) INFO 26.76% complete 2020-10-12 12:15:31,721 Task._call(1069) INFO 29.20% complete 2020-10-12 12:15:31,911 Task._call(1069) INFO 31.63% complete 2020-10-12 12:15:34,321 Task._call(1069) INFO 34.06% complete 2020-10-12 12:15:34,558 Task._call(1069) INFO 36.50% complete 2020-10-12 12:15:34,744 Task._call(1069) INFO 38.93% complete 2020-10-12 12:15:34,935 Task._call(1069) INFO 41.36% complete 2020-10-12 12:15:34,941 Task._call(1069) INFO 43.80% complete 2020-10-12 12:15:34,945 Task._call(1069) INFO 46.23% complete 2020-10-12 12:15:34,949 Task._call(1069) INFO 48.66% complete 2020-10-12 12:15:34,953 Task._call(1069) INFO 51.10% complete 2020-10-12 12:15:34,957 Task._call(1069) INFO 53.53% complete 2020-10-12 12:15:34,961 Task._call(1069) INFO 55.97% complete 2020-10-12 12:15:34,965 Task._call(1069) INFO 58.40% complete 2020-10-12 12:15:34,969 Task._call(1069) INFO 60.83% complete 2020-10-12 12:15:35,045 Task._call(1069) INFO 63.27% complete 2020-10-12 12:15:35,206 Task._call(1069) INFO 65.70% complete 2020-10-12 12:15:35,491 Task._call(1069) INFO 68.13% complete 2020-10-12 12:15:35,681 Task._call(1069) INFO 70.57% complete 2020-10-12 12:15:35,868 Task._call(1069) INFO 73.00% complete 2020-10-12 12:15:36,063 Task._call(1069) INFO 75.44% complete 2020-10-12 12:15:36,257 Task._call(1069) INFO 77.87% complete 2020-10-12 12:15:36,429 Task._call(1069) INFO 80.30% complete 2020-10-12 12:15:36,620 Task._call(1069) INFO 82.74% complete 2020-10-12 12:15:36,851 Task._call(1069) INFO 85.17% complete 2020-10-12 12:15:36,893 Task._call(1069) INFO 85.71% complete 2020-10-12 12:15:36,898 Task._call(1069) INFO 2.55% complete 2020-10-12 12:15:36,902 Task._call(1069) INFO 5.11% complete 2020-10-12 12:15:36,906 Task._call(1069) INFO 7.68% complete 2020-10-12 12:15:36,910 Task._call(1069) INFO 10.24% complete 2020-10-12 12:15:36,913 Task._call(1069) INFO 12.80% complete 2020-10-12 12:15:36,918 Task._call(1069) INFO 15.36% complete 2020-10-12 12:15:36,996 Task._call(1069) INFO 17.92% complete 2020-10-12 12:15:37,425 Task._call(1069) INFO 20.48% complete 2020-10-12 12:15:37,593 Task._call(1069) INFO 23.05% complete 2020-10-12 12:15:37,744 Task._call(1069) INFO 25.61% complete 2020-10-12 12:15:37,903 Task._call(1069) INFO 28.17% complete 2020-10-12 12:15:38,464 Task._call(1069) INFO 30.73% complete 2020-10-12 12:15:38,664 Task._call(1069) INFO 33.29% complete 2020-10-12 12:15:40,184 Task._call(1069) INFO 35.86% complete 2020-10-12 12:15:40,522 Task._call(1069) INFO 38.42% complete 2020-10-12 12:15:40,666 Task._call(1069) INFO 40.98% complete 2020-10-12 12:15:40,772 Task._call(1069) INFO 43.54% complete 2020-10-12 12:15:40,774 Task._call(1069) INFO 46.10% complete 2020-10-12 12:15:40,778 Task._call(1069) INFO 48.66% complete 2020-10-12 12:15:40,782 Task._call(1069) INFO 51.23% complete 2020-10-12 12:15:40,786 Task._call(1069) INFO 53.79% complete 2020-10-12 12:15:40,790 Task._call(1069) INFO 56.35% complete 2020-10-12 12:15:40,795 Task._call(1069) INFO 58.91% complete 2020-10-12 12:15:40,799 Task._call(1069) INFO 61.47% complete 2020-10-12 12:15:40,804 Task._call(1069) INFO 64.04% complete 2020-10-12 12:15:40,808 Task._call(1069) INFO 66.60% complete 2020-10-12 12:15:40,966 Task._call(1069) INFO 69.16% complete 2020-10-12 12:15:41,119 Task._call(1069) INFO 71.72% complete 2020-10-12 12:15:41,192 Task._call(1069) INFO 74.28% complete 2020-10-12 12:15:41,390 Task._call(1069) INFO 76.84% complete 2020-10-12 12:15:41,582 Task._call(1069) INFO 79.41% complete 2020-10-12 12:15:41,765 Task._call(1069) INFO 81.97% complete 2020-10-12 12:15:41,920 Task._call(1069) INFO 84.53% complete 2020-10-12 12:15:42,154 Task._call(1069) INFO 87.09% complete 2020-10-12 12:15:42,280 Task._call(1069) INFO 89.65% complete 2020-10-12 12:15:42,292 Task._call(1069) INFO 90.22% complete 2020-10-12 12:15:42,296 Task._call(1069) INFO 2.68% complete 2020-10-12 12:15:42,301 Task._call(1069) INFO 5.37% complete 2020-10-12 12:15:42,305 Task._call(1069) INFO 8.06% complete 2020-10-12 12:15:42,309 Task._call(1069) INFO 10.75% complete 2020-10-12 12:15:42,314 Task._call(1069) INFO 13.44% complete 2020-10-12 12:15:42,318 Task._call(1069) INFO 16.13% complete 2020-10-12 12:15:42,322 Task._call(1069) INFO 18.82% complete 2020-10-12 12:15:42,446 Task._call(1069) INFO 21.51% complete 2020-10-12 12:15:42,537 Task._call(1069) INFO 24.20% complete 2020-10-12 12:15:42,822 Task._call(1069) INFO 26.89% complete 2020-10-12 12:15:42,897 Task._call(1069) INFO 29.58% complete 2020-10-12 12:15:44,207 Task._call(1069) INFO 32.27% complete 2020-10-12 12:15:44,306 Task._call(1069) INFO 34.96% complete 2020-10-12 12:15:44,310 Task._call(1069) INFO 37.65% complete 2020-10-12 12:15:44,407 Task._call(1069) INFO 40.34% complete 2020-10-12 12:15:44,491 Task._call(1069) INFO 43.03% complete 2020-10-12 12:15:44,604 Task._call(1069) INFO 45.72% complete 2020-10-12 12:15:44,608 Task._call(1069) INFO 48.41% complete 2020-10-12 12:15:44,612 Task._call(1069) INFO 51.10% complete 2020-10-12 12:15:44,616 Task._call(1069) INFO 53.79% complete 2020-10-12 12:15:44,620 Task._call(1069) INFO 56.48% complete 2020-10-12 12:15:44,624 Task._call(1069) INFO 59.17% complete 2020-10-12 12:15:44,628 Task._call(1069) INFO 61.86% complete 2020-10-12 12:15:44,633 Task._call(1069) INFO 64.55% complete 2020-10-12 12:15:44,637 Task._call(1069) INFO 67.24% complete 2020-10-12 12:15:44,641 Task._call(1069) INFO 69.93% complete 2020-10-12 12:15:44,779 Task._call(1069) INFO 72.62% complete 2020-10-12 12:15:45,058 Task._call(1069) INFO 75.31% complete 2020-10-12 12:15:45,083 Task._call(1069) INFO 78.00% complete 2020-10-12 12:15:45,138 Task._call(1069) INFO 80.69% complete 2020-10-12 12:15:45,302 Task._call(1069) INFO 83.38% complete 2020-10-12 12:15:45,512 Task._call(1069) INFO 86.07% complete 2020-10-12 12:15:45,681 Task._call(1069) INFO 88.76% complete 2020-10-12 12:15:45,961 Task._call(1069) INFO 91.45% complete 2020-10-12 12:15:46,090 Task._call(1069) INFO 94.14% complete 2020-10-12 12:15:46,092 Task._call(1069) INFO 94.74% complete 2020-10-12 12:15:46,096 Task._call(1069) INFO 2.81% complete 2020-10-12 12:15:46,100 Task._call(1069) INFO 5.63% complete 2020-10-12 12:15:46,104 Task._call(1069) INFO 8.44% complete 2020-10-12 12:15:46,109 Task._call(1069) INFO 11.26% complete 2020-10-12 12:15:46,113 Task._call(1069) INFO 14.08% complete 2020-10-12 12:15:46,117 Task._call(1069) INFO 16.90% complete 2020-10-12 12:15:46,122 Task._call(1069) INFO 19.72% complete 2020-10-12 12:15:46,126 Task._call(1069) INFO 22.53% complete 2020-10-12 12:15:46,130 Task._call(1069) INFO 25.35% complete 2020-10-12 12:15:46,135 Task._call(1069) INFO 28.17% complete 2020-10-12 12:15:46,139 Task._call(1069) INFO 30.99% complete 2020-10-12 12:15:46,143 Task._call(1069) INFO 33.81% complete 2020-10-12 12:15:46,148 Task._call(1069) INFO 36.62% complete 2020-10-12 12:15:46,152 Task._call(1069) INFO 39.44% complete 2020-10-12 12:15:46,156 Task._call(1069) INFO 42.26% complete 2020-10-12 12:15:46,160 Task._call(1069) INFO 45.08% complete 2020-10-12 12:15:46,165 Task._call(1069) INFO 47.90% complete 2020-10-12 12:15:46,169 Task._call(1069) INFO 50.71% complete 2020-10-12 12:15:46,174 Task._call(1069) INFO 53.53% complete 2020-10-12 12:15:46,178 Task._call(1069) INFO 56.35% complete 2020-10-12 12:15:46,182 Task._call(1069) INFO 59.17% complete 2020-10-12 12:15:46,186 Task._call(1069) INFO 61.99% complete 2020-10-12 12:15:46,190 Task._call(1069) INFO 64.80% complete 2020-10-12 12:15:46,194 Task._call(1069) INFO 67.62% complete 2020-10-12 12:15:46,199 Task._call(1069) INFO 70.44% complete 2020-10-12 12:15:46,203 Task._call(1069) INFO 73.26% complete 2020-10-12 12:15:46,207 Task._call(1069) INFO 76.08% complete 2020-10-12 12:15:46,335 Task._call(1069) INFO 78.89% complete 2020-10-12 12:15:46,446 Task._call(1069) INFO 81.71% complete 2020-10-12 12:15:46,450 Task._call(1069) INFO 84.53% complete 2020-10-12 12:15:46,487 Task._call(1069) INFO 87.35% complete 2020-10-12 12:15:46,595 Task._call(1069) INFO 90.17% complete 2020-10-12 12:15:46,675 Task._call(1069) INFO 92.99% complete 2020-10-12 12:15:46,693 Task._call(1069) INFO 95.80% complete 2020-10-12 12:15:46,697 Task._call(1069) INFO 98.62% complete 2020-10-12 12:15:46,699 Task._call(1069) INFO 99.25% complete 2020-10-12 12:15:46,700 Task._call(1069) INFO 2.83% complete 2020-10-12 12:15:46,701 Task._call(1069) INFO 5.67% complete 2020-10-12 12:15:46,702 Task._call(1069) INFO 8.50% complete 2020-10-12 12:15:46,703 Task._call(1069) INFO 11.34% complete 2020-10-12 12:15:46,704 Task._call(1069) INFO 14.18% complete 2020-10-12 12:15:46,705 Task._call(1069) INFO 17.02% complete 2020-10-12 12:15:46,706 Task._call(1069) INFO 19.86% complete 2020-10-12 12:15:46,706 Task._call(1069) INFO 22.70% complete 2020-10-12 12:15:46,707 Task._call(1069) INFO 25.54% complete 2020-10-12 12:15:46,709 Task._call(1069) INFO 28.37% complete 2020-10-12 12:15:46,709 Task._call(1069) INFO 31.21% complete 2020-10-12 12:15:46,710 Task._call(1069) INFO 34.05% complete 2020-10-12 12:15:46,710 Task._call(1069) INFO 36.89% complete 2020-10-12 12:15:46,711 Task._call(1069) INFO 39.73% complete 2020-10-12 12:15:46,712 Task._call(1069) INFO 42.57% complete 2020-10-12 12:15:46,715 Task._call(1069) INFO 45.41% complete 2020-10-12 12:15:46,716 Task._call(1069) INFO 48.24% complete 2020-10-12 12:15:46,717 Task._call(1069) INFO 51.08% complete 2020-10-12 12:15:46,719 Task._call(1069) INFO 53.92% complete 2020-10-12 12:15:46,720 Task._call(1069) INFO 56.76% complete 2020-10-12 12:15:46,721 Task._call(1069) INFO 59.60% complete 2020-10-12 12:15:46,722 Task._call(1069) INFO 62.44% complete 2020-10-12 12:15:46,723 Task._call(1069) INFO 65.28% complete 2020-10-12 12:15:46,724 Task._call(1069) INFO 68.11% complete 2020-10-12 12:15:46,725 Task._call(1069) INFO 70.95% complete 2020-10-12 12:15:46,726 Task._call(1069) INFO 73.79% complete 2020-10-12 12:15:46,727 Task._call(1069) INFO 76.63% complete 2020-10-12 12:15:46,735 Task._call(1069) INFO 79.47% complete 2020-10-12 12:15:46,739 Task._call(1069) INFO 82.31% complete 2020-10-12 12:15:46,739 Task._call(1069) INFO 85.15% complete 2020-10-12 12:15:46,740 Task._call(1069) INFO 87.98% complete 2020-10-12 12:15:46,741 Task._call(1069) INFO 90.82% complete 2020-10-12 12:15:46,742 Task._call(1069) INFO 93.66% complete 2020-10-12 12:15:46,743 Task._call(1069) INFO 96.50% complete 2020-10-12 12:15:46,744 Task._call(1069) INFO 99.34% complete 2020-10-12 12:15:46,745 Task._call(1069) INFO 100% complete 2020-10-12 12:15:51,344 geoprocessing.raster_calculator(375) INFO starting stats_worker 2020-10-12 12:15:51,345 geoprocessing.raster_calculator(381) INFO started stats_worker 2020-10-12 12:15:56,352 geoprocessing.(451) INFO 74.1% complete 2020-10-12 12:16:00,083 geoprocessing.raster_calculator(454) INFO 100.0%% complete 2020-10-12 12:16:00,083 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate 2020-10-12 12:16:00,084 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result. 2020-10-12 12:16:00,084 threading.run(870) DEBUG payload is None, terminating 2020-10-12 12:16:12,067 Task._call(1069) INFO 13.6% complete 2020-10-12 12:16:18,750 Task._call(1069) INFO 22.6% complete 2020-10-12 12:16:24,041 Task._call(1069) INFO 31.6% complete 2020-10-12 12:16:30,093 Task._call(1069) INFO 40.6% complete 2020-10-12 12:16:36,521 Task._call(1069) INFO 49.6% complete 2020-10-12 12:16:42,052 Task._call(1069) INFO 54.2% complete 2020-10-12 12:16:48,372 Task._call(1069) INFO 63.2% complete 2020-10-12 12:16:54,059 Task._call(1069) INFO 72.2% complete 2020-10-12 12:17:00,631 Task._call(1069) INFO 100.0% complete 2020-10-12 12:17:22,495 geoprocessing.raster_calculator(375) INFO starting stats_worker 2020-10-12 12:17:22,496 geoprocessing.raster_calculator(381) INFO started stats_worker 2020-10-12 12:17:27,805 geoprocessing.(451) INFO 51.7% complete 2020-10-12 12:17:32,946 geoprocessing.(451) INFO 65.5% complete 2020-10-12 12:17:36,164 geoprocessing.raster_calculator(454) INFO 100.0%% complete 2020-10-12 12:17:36,165 geoprocessing.raster_calculator(457) INFO signaling stats worker to terminate 2020-10-12 12:17:36,165 geoprocessing.raster_calculator(459) INFO Waiting for raster stats worker result. 2020-10-12 12:17:36,165 threading.run(870) DEBUG payload is None, terminating 2020-10-12 12:17:37,029 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 12:17:37,037 geoprocessing.zonal_statistics(1129) DEBUG > 2020-10-12 12:17:41,284 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 1 aligned: clipped_rasterpnlh1svi.tif 2020-10-12 12:17:41,284 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 1 rasters. 2020-10-12 12:17:41,285 geoprocessing.zonal_statistics(1189) INFO build a lookup of aggregate field value to FID 2020-10-12 12:17:41,285 geoprocessing.zonal_statistics(1196) INFO creating disjoint polygon set 2020-10-12 12:17:41,286 geoprocessing.calculate_disjoint_polygon_set(2082) INFO build shapely polygon list 2020-10-12 12:17:41,288 geoprocessing.calculate_disjoint_polygon_set(2096) INFO build shapely rtree index 2020-10-12 12:17:41,289 geoprocessing.calculate_disjoint_polygon_set(2111) INFO poly feature lookup 100.0% complete on watershed_results_sdr_2015_NSH.shp 2020-10-12 12:17:41,289 geoprocessing.calculate_disjoint_polygon_set(2113) INFO build poly intersection lookup 2020-10-12 12:17:41,289 geoprocessing.calculate_disjoint_polygon_set(2137) INFO poly intersection feature lookup 100.0% complete on watershed_results_sdr_2015_NSH.shp 2020-10-12 12:17:41,289 geoprocessing.calculate_disjoint_polygon_set(2171) INFO maximal subset build 100.0% complete on watershed_results_sdr_2015_NSH.shp 2020-10-12 12:17:41,308 geoprocessing.zonal_statistics(1219) INFO processing 1 disjoint polygon sets 2020-10-12 12:17:41,308 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 1 of 1 100.0% processed on watershed_results_sdr_2015_NSH.shp 2020-10-12 12:17:41,658 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 1 of 1 watershed_results_sdr_2015_NSH.shp 2020-10-12 12:17:43,299 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 1 of 1 watershed_results_sdr_2015_NSH.shp 2020-10-12 12:17:45,153 geoprocessing.zonal_statistics(1322) DEBUG unset_fids: 0 of 1 2020-10-12 12:17:45,158 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_2015_NSH.tif', 1) 2020-10-12 12:17:45,158 geoprocessing.zonal_statistics(1399) DEBUG remaining unset_fids: 0 of 1 2020-10-12 12:17:45,158 geoprocessing.zonal_statistics(1407) INFO all done processing polygon sets for watershed_results_sdr_2015_NSH.shp 2020-10-12 12:17:45,178 geoprocessing.zonal_statistics(1129) DEBUG > 2020-10-12 12:17:49,439 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 1 aligned: clipped_rasterkj9nocd_.tif 2020-10-12 12:17:49,440 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 1 rasters. 2020-10-12 12:17:49,442 geoprocessing.zonal_statistics(1189) INFO build a lookup of aggregate field value to FID 2020-10-12 12:17:49,442 geoprocessing.zonal_statistics(1196) INFO creating disjoint polygon set 2020-10-12 12:17:49,442 geoprocessing.calculate_disjoint_polygon_set(2082) INFO build shapely polygon list 2020-10-12 12:17:49,443 geoprocessing.calculate_disjoint_polygon_set(2096) INFO build shapely rtree index 2020-10-12 12:17:49,444 geoprocessing.calculate_disjoint_polygon_set(2111) INFO poly feature lookup 100.0% complete on watershed_results_sdr_2015_NSH.shp 2020-10-12 12:17:49,444 geoprocessing.calculate_disjoint_polygon_set(2113) INFO build poly intersection lookup 2020-10-12 12:17:49,444 geoprocessing.calculate_disjoint_polygon_set(2137) INFO poly intersection feature lookup 100.0% complete on watershed_results_sdr_2015_NSH.shp 2020-10-12 12:17:49,444 geoprocessing.calculate_disjoint_polygon_set(2171) INFO maximal subset build 100.0% complete on watershed_results_sdr_2015_NSH.shp 2020-10-12 12:17:49,462 geoprocessing.zonal_statistics(1219) INFO processing 1 disjoint polygon sets 2020-10-12 12:17:49,463 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 1 of 1 100.0% processed on watershed_results_sdr_2015_NSH.shp 2020-10-12 12:17:49,812 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 1 of 1 watershed_results_sdr_2015_NSH.shp 2020-10-12 12:17:51,747 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 1 of 1 watershed_results_sdr_2015_NSH.shp 2020-10-12 12:17:53,638 geoprocessing.zonal_statistics(1322) DEBUG unset_fids: 0 of 1 2020-10-12 12:17:53,643 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_2015_NSH.tif', 1) 2020-10-12 12:17:53,643 geoprocessing.zonal_statistics(1399) DEBUG remaining unset_fids: 0 of 1 2020-10-12 12:17:53,643 geoprocessing.zonal_statistics(1407) INFO all done processing polygon sets for watershed_results_sdr_2015_NSH.shp 2020-10-12 12:17:53,665 geoprocessing.zonal_statistics(1129) DEBUG > 2020-10-12 12:17:57,864 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 1 aligned: clipped_raster1xtp0vor.tif 2020-10-12 12:17:57,865 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 1 rasters. 2020-10-12 12:17:57,865 geoprocessing.zonal_statistics(1189) INFO build a lookup of aggregate field value to FID 2020-10-12 12:17:57,866 geoprocessing.zonal_statistics(1196) INFO creating disjoint polygon set 2020-10-12 12:17:57,866 geoprocessing.calculate_disjoint_polygon_set(2082) INFO build shapely polygon list 2020-10-12 12:17:57,868 geoprocessing.calculate_disjoint_polygon_set(2096) INFO build shapely rtree index 2020-10-12 12:17:57,869 geoprocessing.calculate_disjoint_polygon_set(2111) INFO poly feature lookup 100.0% complete on watershed_results_sdr_2015_NSH.shp 2020-10-12 12:17:57,869 geoprocessing.calculate_disjoint_polygon_set(2113) INFO build poly intersection lookup 2020-10-12 12:17:57,869 geoprocessing.calculate_disjoint_polygon_set(2137) INFO poly intersection feature lookup 100.0% complete on watershed_results_sdr_2015_NSH.shp 2020-10-12 12:17:57,869 geoprocessing.calculate_disjoint_polygon_set(2171) INFO maximal subset build 100.0% complete on watershed_results_sdr_2015_NSH.shp 2020-10-12 12:17:57,887 geoprocessing.zonal_statistics(1219) INFO processing 1 disjoint polygon sets 2020-10-12 12:17:57,887 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 1 of 1 100.0% processed on watershed_results_sdr_2015_NSH.shp 2020-10-12 12:17:58,236 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 1 of 1 watershed_results_sdr_2015_NSH.shp 2020-10-12 12:17:59,861 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 1 of 1 watershed_results_sdr_2015_NSH.shp 2020-10-12 12:18:01,685 geoprocessing.zonal_statistics(1322) DEBUG unset_fids: 0 of 1 2020-10-12 12:18:01,690 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_2015_NSH.tif', 1) 2020-10-12 12:18:01,690 geoprocessing.zonal_statistics(1399) DEBUG remaining unset_fids: 0 of 1 2020-10-12 12:18:01,690 geoprocessing.zonal_statistics(1407) INFO all done processing polygon sets for watershed_results_sdr_2015_NSH.shp 2020-10-12 12:18:01,711 geoprocessing.zonal_statistics(1129) DEBUG > 2020-10-12 12:18:05,950 geoprocessing.align_and_resize_raster_stack(752) INFO 1 of 1 aligned: clipped_rastercyms38x5.tif 2020-10-12 12:18:05,951 geoprocessing.align_and_resize_raster_stack(754) INFO aligned all 1 rasters. 2020-10-12 12:18:05,952 geoprocessing.zonal_statistics(1189) INFO build a lookup of aggregate field value to FID 2020-10-12 12:18:05,952 geoprocessing.zonal_statistics(1196) INFO creating disjoint polygon set 2020-10-12 12:18:05,952 geoprocessing.calculate_disjoint_polygon_set(2082) INFO build shapely polygon list 2020-10-12 12:18:05,954 geoprocessing.calculate_disjoint_polygon_set(2096) INFO build shapely rtree index 2020-10-12 12:18:05,955 geoprocessing.calculate_disjoint_polygon_set(2111) INFO poly feature lookup 100.0% complete on watershed_results_sdr_2015_NSH.shp 2020-10-12 12:18:05,955 geoprocessing.calculate_disjoint_polygon_set(2113) INFO build poly intersection lookup 2020-10-12 12:18:05,955 geoprocessing.calculate_disjoint_polygon_set(2137) INFO poly intersection feature lookup 100.0% complete on watershed_results_sdr_2015_NSH.shp 2020-10-12 12:18:05,955 geoprocessing.calculate_disjoint_polygon_set(2171) INFO maximal subset build 100.0% complete on watershed_results_sdr_2015_NSH.shp 2020-10-12 12:18:05,974 geoprocessing.zonal_statistics(1219) INFO processing 1 disjoint polygon sets 2020-10-12 12:18:05,974 geoprocessing.zonal_statistics(1253) INFO disjoint polygon set 1 of 1 100.0% processed on watershed_results_sdr_2015_NSH.shp 2020-10-12 12:18:06,319 geoprocessing.zonal_statistics(1261) INFO rasterizing disjoint polygon set 1 of 1 watershed_results_sdr_2015_NSH.shp 2020-10-12 12:18:08,151 geoprocessing.zonal_statistics(1279) INFO summarizing rasterized disjoint polygon set 1 of 1 watershed_results_sdr_2015_NSH.shp 2020-10-12 12:18:10,006 geoprocessing.zonal_statistics(1322) DEBUG unset_fids: 0 of 1 2020-10-12 12:18:10,011 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_2015_NSH.tif', 1) 2020-10-12 12:18:10,011 geoprocessing.zonal_statistics(1399) DEBUG remaining unset_fids: 0 of 1 2020-10-12 12:18:10,011 geoprocessing.zonal_statistics(1407) INFO all done processing polygon sets for watershed_results_sdr_2015_NSH.shp 2020-10-12 12:18:10,210 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 12:18:11,090 model._logged_target(1638) INFO Execution finished 2020-10-12 12:18:11,092 utils.prepare_workspace(117) INFO Elapsed time: 11m 6.659999999999968s