2020-09-17 10:41:15,653 utils.prepare_workspace(109) INFO Writing log messages to K:\JISUAN\SDR\output2018\1003\InVEST-Sediment-Delivery-Ratio-Model-(SDR)-log-2020-09-17--10_41_15.txt
2020-09-17 10:41:15,655 model._logged_target(1633) Level 100 Starting model with parameters: 
Arguments for InVEST natcap.invest.sdr 3.7.0:
biophysical_table_path      K:/JISUAN/SDR/2018.csv
dem_path                    K:/JISUAN/DEM.tif
drainage_path               K:/JISUAN/SDR/input/2018/2018Dlayer.tif
erodibility_path            K:/JISUAN/SDR/input/K.tif
erosivity_path              K:/JISUAN/SDR/2018R.tif
ic_0_param                  0.5
k_param                     2
lulc_path                   K:/JISUAN/SDR/input/2018/100/2018lucc.tif
n_workers                   -1
results_suffix              
sdr_max                     0.8
threshold_flow_accumulation 1000
watersheds_path             K:/JISUAN/SDR/input/watershed.shp
workspace_dir               K:\JISUAN\SDR\output2018\1003

2020-09-17 10:41:18,516 geoprocessing.align_and_resize_raster_stack(729) INFO n_workers > 1 (2) so starting a processes pool.
2020-09-17 10:41:23,115 geoprocessing.align_and_resize_raster_stack(779) INFO 1 of 5 aligned: aligned_dem.tif
2020-09-17 10:41:23,509 geoprocessing.align_and_resize_raster_stack(779) INFO 2 of 5 aligned: aligned_lulc.tif
2020-09-17 10:41:27,079 geoprocessing.align_and_resize_raster_stack(779) INFO 3 of 5 aligned: aligned_erosivity.tif
2020-09-17 10:41:27,079 geoprocessing.align_and_resize_raster_stack(779) INFO 4 of 5 aligned: aligned_erodibility.tif
2020-09-17 10:41:27,081 geoprocessing.align_and_resize_raster_stack(779) INFO 5 of 5 aligned: aligned_drainage.tif
2020-09-17 10:41:27,163 geoprocessing.align_and_resize_raster_stack(788) INFO aligned all 5 rasters.
2020-09-17 10:41:33,463 Task._call(1068) INFO 26.0% complete
2020-09-17 10:41:39,138 Task._call(1068) INFO 32.5% complete
2020-09-17 10:41:45,055 Task._call(1068) INFO 78.0% complete
2020-09-17 10:41:52,339 Task._call(1068) INFO 91.0% complete
2020-09-17 10:41:52,973 Task._call(1068) INFO 100.0% complete
2020-09-17 10:41:56,224 geoprocessing.raster_calculator(366) INFO starting stats_worker
2020-09-17 10:41:56,226 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-42, started daemon 1540)>
2020-09-17 10:41:56,631 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2020-09-17 10:41:56,632 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2020-09-17 10:41:56,632 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2020-09-17 10:41:56,632 threading.run(754) DEBUG payload is None, terminating
2020-09-17 10:42:03,125 Task._call(1068) INFO 26.0% complete
2020-09-17 10:42:09,198 Task._call(1068) INFO 58.5% complete
2020-09-17 10:42:15,118 Task._call(1068) INFO 78.0% complete
2020-09-17 10:42:21,026 Task._call(1068) INFO 100.0% complete
2020-09-17 10:42:27,035 Task._call(1068) INFO 65.0% complete
2020-09-17 10:42:30,240 Task._call(1068) INFO 100.0% complete
2020-09-17 10:42:30,437 geoprocessing.raster_calculator(366) INFO starting stats_worker
2020-09-17 10:42:30,438 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-43, started daemon 8436)>
2020-09-17 10:42:34,823 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2020-09-17 10:42:34,823 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2020-09-17 10:42:34,823 threading.run(754) DEBUG payload is None, terminating
2020-09-17 10:42:34,823 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2020-09-17 10:42:39,223 Task._call(1068) INFO filter out incomplete divergent streams
2020-09-17 10:42:39,438 Task._call(1068) INFO 100.0% complete
2020-09-17 10:42:39,459 geoprocessing.raster_calculator(366) INFO starting stats_worker
2020-09-17 10:42:39,459 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-44, started daemon 5840)>
2020-09-17 10:42:39,609 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2020-09-17 10:42:39,609 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2020-09-17 10:42:39,609 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2020-09-17 10:42:39,611 threading.run(754) DEBUG payload is None, terminating
2020-09-17 10:42:39,796 geoprocessing.raster_calculator(366) INFO starting stats_worker
2020-09-17 10:42:39,796 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-45, started daemon 2792)>
2020-09-17 10:42:40,299 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2020-09-17 10:42:40,299 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2020-09-17 10:42:40,299 threading.run(754) DEBUG payload is None, terminating
2020-09-17 10:42:40,299 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2020-09-17 10:42:40,947 geoprocessing.raster_calculator(366) INFO starting stats_worker
2020-09-17 10:42:40,948 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-46, started daemon 8832)>
2020-09-17 10:42:41,164 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2020-09-17 10:42:41,164 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2020-09-17 10:42:41,164 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2020-09-17 10:42:41,164 threading.run(754) DEBUG payload is None, terminating
2020-09-17 10:42:41,792 geoprocessing.raster_calculator(366) INFO starting stats_worker
2020-09-17 10:42:41,792 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-47, started daemon 9972)>
2020-09-17 10:42:42,293 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2020-09-17 10:42:42,295 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2020-09-17 10:42:42,295 threading.run(754) DEBUG payload is None, terminating
2020-09-17 10:42:42,295 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2020-09-17 10:42:43,016 geoprocessing.raster_calculator(366) INFO starting stats_worker
2020-09-17 10:42:43,016 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-48, started daemon 9620)>
2020-09-17 10:42:44,592 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2020-09-17 10:42:44,592 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2020-09-17 10:42:44,592 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2020-09-17 10:42:44,594 threading.run(754) DEBUG payload is None, terminating
2020-09-17 10:42:45,269 geoprocessing.raster_calculator(366) INFO starting stats_worker
2020-09-17 10:42:45,269 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-49, started daemon 9696)>
2020-09-17 10:42:46,260 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2020-09-17 10:42:46,260 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2020-09-17 10:42:46,262 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2020-09-17 10:42:46,262 threading.run(754) DEBUG payload is None, terminating
2020-09-17 10:42:47,200 sdr._calculate_bar_factor(858) DEBUG doing flow accumulation mfd on K:\JISUAN\SDR\output2018\1003\intermediate_outputs\w_threshold.tif
2020-09-17 10:42:53,234 sdr._calculate_bar_factor(866) INFO 65.0% complete
2020-09-17 10:42:58,052 sdr._calculate_bar_factor(866) INFO 100.0% complete
2020-09-17 10:42:58,068 geoprocessing.raster_calculator(366) INFO starting stats_worker
2020-09-17 10:42:58,069 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-50, started daemon 9728)>
2020-09-17 10:43:00,279 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2020-09-17 10:43:00,280 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2020-09-17 10:43:00,280 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2020-09-17 10:43:00,280 threading.run(754) DEBUG payload is None, terminating
2020-09-17 10:43:00,963 sdr._calculate_bar_factor(858) DEBUG doing flow accumulation mfd on K:\JISUAN\SDR\output2018\1003\intermediate_outputs\slope_threshold.tif
2020-09-17 10:43:06,427 sdr._calculate_bar_factor(866) INFO 65.0% complete
2020-09-17 10:43:11,614 sdr._calculate_bar_factor(866) INFO 100.0% complete
2020-09-17 10:43:11,628 geoprocessing.raster_calculator(366) INFO starting stats_worker
2020-09-17 10:43:11,630 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-51, started daemon 10116)>
2020-09-17 10:43:13,986 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2020-09-17 10:43:13,986 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2020-09-17 10:43:13,987 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2020-09-17 10:43:13,987 threading.run(754) DEBUG payload is None, terminating
2020-09-17 10:43:14,762 geoprocessing.raster_calculator(366) INFO starting stats_worker
2020-09-17 10:43:14,762 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-52, started daemon 1432)>
2020-09-17 10:43:16,586 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2020-09-17 10:43:16,586 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2020-09-17 10:43:16,586 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2020-09-17 10:43:16,588 threading.run(754) DEBUG payload is None, terminating
2020-09-17 10:43:17,201 geoprocessing.raster_calculator(366) INFO starting stats_worker
2020-09-17 10:43:17,203 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-53, started daemon 5368)>
2020-09-17 10:43:18,078 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2020-09-17 10:43:18,078 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2020-09-17 10:43:18,078 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2020-09-17 10:43:18,078 threading.run(754) DEBUG payload is None, terminating
2020-09-17 10:43:25,101 Task._call(1068) INFO 71.5% complete
2020-09-17 10:43:28,105 Task._call(1068) INFO 100.0% complete
2020-09-17 10:43:28,322 geoprocessing.raster_calculator(366) INFO starting stats_worker
2020-09-17 10:43:28,322 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-54, started daemon 9888)>
2020-09-17 10:43:29,816 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2020-09-17 10:43:29,816 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2020-09-17 10:43:29,818 threading.run(754) DEBUG payload is None, terminating
2020-09-17 10:43:29,816 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2020-09-17 10:43:30,605 geoprocessing.raster_calculator(366) INFO starting stats_worker
2020-09-17 10:43:30,607 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-55, started daemon 9124)>
2020-09-17 10:43:31,207 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2020-09-17 10:43:31,207 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2020-09-17 10:43:31,207 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2020-09-17 10:43:31,207 threading.run(754) DEBUG payload is None, terminating
2020-09-17 10:43:32,480 geoprocessing.raster_calculator(366) INFO starting stats_worker
2020-09-17 10:43:32,480 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-56, started daemon 10056)>
2020-09-17 10:43:33,411 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2020-09-17 10:43:33,411 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2020-09-17 10:43:33,411 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2020-09-17 10:43:33,411 threading.run(754) DEBUG payload is None, terminating
2020-09-17 10:43:34,428 geoprocessing.raster_calculator(366) INFO starting stats_worker
2020-09-17 10:43:34,430 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-57, started daemon 1148)>
2020-09-17 10:43:35,753 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2020-09-17 10:43:35,753 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2020-09-17 10:43:35,755 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2020-09-17 10:43:35,755 threading.run(754) DEBUG payload is None, terminating
2020-09-17 10:43:36,476 geoprocessing.raster_calculator(366) INFO starting stats_worker
2020-09-17 10:43:36,476 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-58, started daemon 8540)>
2020-09-17 10:43:36,812 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2020-09-17 10:43:36,813 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2020-09-17 10:43:36,813 threading.run(754) DEBUG payload is None, terminating
2020-09-17 10:43:36,813 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2020-09-17 10:43:44,006 Task._call(1068) INFO 65.0% complete
2020-09-17 10:43:47,415 Task._call(1068) INFO 100.0% complete
2020-09-17 10:43:47,625 geoprocessing.raster_calculator(366) INFO starting stats_worker
2020-09-17 10:43:47,625 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-59, started daemon 8916)>
2020-09-17 10:43:49,032 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2020-09-17 10:43:49,032 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2020-09-17 10:43:49,032 threading.run(754) DEBUG payload is None, terminating
2020-09-17 10:43:49,032 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2020-09-17 10:43:49,924 geoprocessing.raster_calculator(366) INFO starting stats_worker
2020-09-17 10:43:49,924 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-60, started daemon 9380)>
2020-09-17 10:43:51,423 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2020-09-17 10:43:51,424 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2020-09-17 10:43:51,424 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2020-09-17 10:43:51,424 threading.run(754) DEBUG payload is None, terminating
2020-09-17 10:43:52,197 geoprocessing.raster_calculator(366) INFO starting stats_worker
2020-09-17 10:43:52,198 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-61, started daemon 10040)>
2020-09-17 10:43:52,802 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2020-09-17 10:43:52,802 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2020-09-17 10:43:52,802 threading.run(754) DEBUG payload is None, terminating
2020-09-17 10:43:52,802 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2020-09-17 10:43:54,200 geoprocessing.raster_calculator(366) INFO starting stats_worker
2020-09-17 10:43:54,200 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-62, started daemon 9692)>
2020-09-17 10:43:55,977 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2020-09-17 10:43:55,979 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2020-09-17 10:43:55,979 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2020-09-17 10:43:55,979 threading.run(754) DEBUG payload is None, terminating
2020-09-17 10:43:56,437 geoprocessing.zonal_statistics(1152) DEBUG <osgeo.gdal.Dataset; proxy of <Swig Object of type 'GDALDatasetShadow *' at 0x183E6C68> >
2020-09-17 10:43:56,440 geoprocessing.align_and_resize_raster_stack(752) DEBUG n_workers == 1 so a threadpool is sufficient
2020-09-17 10:43:58,579 geoprocessing.align_and_resize_raster_stack(779) INFO 1 of 1 aligned: clipped_rasteru7cup4.tif
2020-09-17 10:43:58,680 geoprocessing.align_and_resize_raster_stack(788) INFO aligned all 1 rasters.
2020-09-17 10:43:58,684 geoprocessing.zonal_statistics(1212) INFO build a lookup of aggregate field value to FID
2020-09-17 10:43:58,686 geoprocessing.zonal_statistics(1219) INFO creating disjoint polygon set
2020-09-17 10:43:58,687 geoprocessing.calculate_disjoint_polygon_set(2048) INFO build shapely polygon list
2020-09-17 10:43:58,694 geoprocessing.calculate_disjoint_polygon_set(2057) INFO build shapely rtree index
2020-09-17 10:43:58,766 geoprocessing.calculate_disjoint_polygon_set(2072) INFO poly feature lookup 100.0% complete on watershed.shp
2020-09-17 10:43:58,766 geoprocessing.calculate_disjoint_polygon_set(2074) INFO build poly intersection lookup
2020-09-17 10:43:58,767 geoprocessing.calculate_disjoint_polygon_set(2098) INFO poly intersection feature lookup 100.0% complete on watershed.shp
2020-09-17 10:43:58,767 geoprocessing.calculate_disjoint_polygon_set(2132) INFO maximal subset build 100.0% complete on watershed.shp
2020-09-17 10:43:58,776 geoprocessing.zonal_statistics(1239) INFO processing 1 disjoint polygon sets
2020-09-17 10:43:58,778 geoprocessing.zonal_statistics(1273) INFO disjoint polygon set 1 of 1 100.0% processed on watershed.shp
2020-09-17 10:43:58,805 geoprocessing.zonal_statistics(1281) INFO rasterizing disjoint polygon set 1 of 1 watershed.shp
2020-09-17 10:43:59,578 geoprocessing.zonal_statistics(1299) INFO summarizing rasterized disjoint polygon set 1 of 1 watershed.shp
2020-09-17 10:44:00,038 geoprocessing.zonal_statistics(1343) DEBUG unset_fids: 0 of 1 
2020-09-17 10:44:00,039 geoprocessing.zonal_statistics(1346) DEBUG gt [ 4.7522280e+06  1.0000000e+02  0.0000000e+00  4.8381525e+06
  0.0000000e+00 -1.0000000e+02] for (u'K:\\JISUAN\\SDR\\output2018\\1003\\usle.tif', 1)
2020-09-17 10:44:00,039 geoprocessing.zonal_statistics(1420) DEBUG remaining unset_fids: 0 of 1 
2020-09-17 10:44:00,039 geoprocessing.zonal_statistics(1428) INFO all done processing polygon sets for watershed.shp
2020-09-17 10:44:00,056 geoprocessing.zonal_statistics(1152) DEBUG <osgeo.gdal.Dataset; proxy of <Swig Object of type 'GDALDatasetShadow *' at 0x10CFB368> >
2020-09-17 10:44:00,061 geoprocessing.align_and_resize_raster_stack(752) DEBUG n_workers == 1 so a threadpool is sufficient
2020-09-17 10:44:02,081 geoprocessing.align_and_resize_raster_stack(779) INFO 1 of 1 aligned: clipped_rasterskrre3.tif
2020-09-17 10:44:02,180 geoprocessing.align_and_resize_raster_stack(788) INFO aligned all 1 rasters.
2020-09-17 10:44:02,184 geoprocessing.zonal_statistics(1212) INFO build a lookup of aggregate field value to FID
2020-09-17 10:44:02,186 geoprocessing.zonal_statistics(1219) INFO creating disjoint polygon set
2020-09-17 10:44:02,187 geoprocessing.calculate_disjoint_polygon_set(2048) INFO build shapely polygon list
2020-09-17 10:44:02,190 geoprocessing.calculate_disjoint_polygon_set(2057) INFO build shapely rtree index
2020-09-17 10:44:02,240 geoprocessing.calculate_disjoint_polygon_set(2072) INFO poly feature lookup 100.0% complete on watershed.shp
2020-09-17 10:44:02,240 geoprocessing.calculate_disjoint_polygon_set(2074) INFO build poly intersection lookup
2020-09-17 10:44:02,242 geoprocessing.calculate_disjoint_polygon_set(2098) INFO poly intersection feature lookup 100.0% complete on watershed.shp
2020-09-17 10:44:02,242 geoprocessing.calculate_disjoint_polygon_set(2132) INFO maximal subset build 100.0% complete on watershed.shp
2020-09-17 10:44:02,250 geoprocessing.zonal_statistics(1239) INFO processing 1 disjoint polygon sets
2020-09-17 10:44:02,252 geoprocessing.zonal_statistics(1273) INFO disjoint polygon set 1 of 1 100.0% processed on watershed.shp
2020-09-17 10:44:02,279 geoprocessing.zonal_statistics(1281) INFO rasterizing disjoint polygon set 1 of 1 watershed.shp
2020-09-17 10:44:03,033 geoprocessing.zonal_statistics(1299) INFO summarizing rasterized disjoint polygon set 1 of 1 watershed.shp
2020-09-17 10:44:03,496 geoprocessing.zonal_statistics(1343) DEBUG unset_fids: 0 of 1 
2020-09-17 10:44:03,496 geoprocessing.zonal_statistics(1346) DEBUG gt [ 4.7522280e+06  1.0000000e+02  0.0000000e+00  4.8381525e+06
  0.0000000e+00 -1.0000000e+02] for (u'K:\\JISUAN\\SDR\\output2018\\1003\\sed_export.tif', 1)
2020-09-17 10:44:03,496 geoprocessing.zonal_statistics(1420) DEBUG remaining unset_fids: 0 of 1 
2020-09-17 10:44:03,496 geoprocessing.zonal_statistics(1428) INFO all done processing polygon sets for watershed.shp
2020-09-17 10:44:03,513 geoprocessing.zonal_statistics(1152) DEBUG <osgeo.gdal.Dataset; proxy of <Swig Object of type 'GDALDatasetShadow *' at 0x10CFB368> >
2020-09-17 10:44:03,517 geoprocessing.align_and_resize_raster_stack(752) DEBUG n_workers == 1 so a threadpool is sufficient
2020-09-17 10:44:05,555 geoprocessing.align_and_resize_raster_stack(779) INFO 1 of 1 aligned: clipped_rasterq9nfv3.tif
2020-09-17 10:44:05,655 geoprocessing.align_and_resize_raster_stack(788) INFO aligned all 1 rasters.
2020-09-17 10:44:05,658 geoprocessing.zonal_statistics(1212) INFO build a lookup of aggregate field value to FID
2020-09-17 10:44:05,660 geoprocessing.zonal_statistics(1219) INFO creating disjoint polygon set
2020-09-17 10:44:05,661 geoprocessing.calculate_disjoint_polygon_set(2048) INFO build shapely polygon list
2020-09-17 10:44:05,667 geoprocessing.calculate_disjoint_polygon_set(2057) INFO build shapely rtree index
2020-09-17 10:44:05,713 geoprocessing.calculate_disjoint_polygon_set(2072) INFO poly feature lookup 100.0% complete on watershed.shp
2020-09-17 10:44:05,713 geoprocessing.calculate_disjoint_polygon_set(2074) INFO build poly intersection lookup
2020-09-17 10:44:05,713 geoprocessing.calculate_disjoint_polygon_set(2098) INFO poly intersection feature lookup 100.0% complete on watershed.shp
2020-09-17 10:44:05,713 geoprocessing.calculate_disjoint_polygon_set(2132) INFO maximal subset build 100.0% complete on watershed.shp
2020-09-17 10:44:05,721 geoprocessing.zonal_statistics(1239) INFO processing 1 disjoint polygon sets
2020-09-17 10:44:05,723 geoprocessing.zonal_statistics(1273) INFO disjoint polygon set 1 of 1 100.0% processed on watershed.shp
2020-09-17 10:44:05,749 geoprocessing.zonal_statistics(1281) INFO rasterizing disjoint polygon set 1 of 1 watershed.shp
2020-09-17 10:44:06,509 geoprocessing.zonal_statistics(1299) INFO summarizing rasterized disjoint polygon set 1 of 1 watershed.shp
2020-09-17 10:44:06,970 geoprocessing.zonal_statistics(1343) DEBUG unset_fids: 0 of 1 
2020-09-17 10:44:06,970 geoprocessing.zonal_statistics(1346) DEBUG gt [ 4.7522280e+06  1.0000000e+02  0.0000000e+00  4.8381525e+06
  0.0000000e+00 -1.0000000e+02] for (u'K:\\JISUAN\\SDR\\output2018\\1003\\sed_retention.tif', 1)
2020-09-17 10:44:06,970 geoprocessing.zonal_statistics(1420) DEBUG remaining unset_fids: 0 of 1 
2020-09-17 10:44:06,970 geoprocessing.zonal_statistics(1428) INFO all done processing polygon sets for watershed.shp
2020-09-17 10:44:07,154 utils._log_gdal_errors(66) WARNING [errno 1] One or several characters couldn't be converted correctly from UTF-8 to ISO-8859-1.  This warning will not be emitted anymore.
2020-09-17 10:44:07,186 model._logged_target(1642) INFO Execution finished
2020-09-17 10:44:07,187 utils.prepare_workspace(115) INFO Elapsed time: 2m 51.53s