2022-04-01 16:10:46,835 utils.prepare_workspace(123) INFO Writing log messages to C:/Users/tershya/OneDrive - Western Washington University/Research/NDR Workspace/Model 2/Output_2015\InVEST-Nutrient-Delivery-Ratio-Model-(NDR)-log-2022-04-01--16_10_46.txt 2022-04-01 16:10:46,836 model._logged_target(1646) Level 100 Starting model with parameters: Arguments for InVEST natcap.invest.ndr.ndr 3.9.0: biophysical_table_path C:/Users/tershya/OneDrive - Western Washington University/Research/NDR Workspace/Model 2/Model2_BiophysicalTable.csv calc_n True calc_p False dem_path C:/Users/tershya/OneDrive - Western Washington University/Research/NDR Workspace/Model 1/DEM_AGREE.tif k_param 2 lulc_path C:/Users/tershya/OneDrive - Western Washington University/Research/NDR Workspace/Model 2/Model2_LULC_Nooksack.tif n_workers -1 results_suffix M2.2015 runoff_proxy_path C:/Users/tershya/OneDrive - Western Washington University/Research/NDR Workspace/Subwatersheds_Model2/Precipitation/Avrg.Annual.Precipt.mm.c2015_GRIDMET.tif subsurface_critical_length_n 0 subsurface_critical_length_p subsurface_eff_n 0 subsurface_eff_p threshold_flow_accumulation 2000 watersheds_path C:/Users/tershya/OneDrive - Western Washington University/Research/NDR Workspace/Model 2/NRW_Boundary/NRW_Boundary.shp workspace_dir C:/Users/tershya/OneDrive - Western Washington University/Research/NDR Workspace/Model 2/Output_2015 2022-04-01 16:10:47,044 utils._log_gdal_errors(80) WARNING [errno 1] Value 2041099877.57999992 of field Shape_Area of feature 0 not successfully written. Possibly due to too larger number with respect to field width 2022-04-01 16:10:50,929 geoprocessing.raster_calculator(391) INFO starting stats_worker 2022-04-01 16:10:50,930 threading.run(870) DEBUG stats worker PID: 11392 2022-04-01 16:10:50,930 geoprocessing.raster_calculator(397) INFO started stats_worker 2022-04-01 16:10:52,157 geoprocessing.raster_calculator(477) INFO 100.0% complete 2022-04-01 16:10:52,157 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2022-04-01 16:10:53,217 geoprocessing.align_and_resize_raster_stack(795) INFO 1 of 3 aligned: aligned_dem_M2.2015.tif 2022-04-01 16:10:54,620 geoprocessing.mask_raster(3204) WARNING No mask value was passed and target nodata is undefined, defaulting to 0 as the target mask value. 2022-04-01 16:10:54,638 geoprocessing.raster_calculator(391) INFO starting stats_worker 2022-04-01 16:10:54,638 threading.run(870) DEBUG stats worker PID: 11392 2022-04-01 16:10:54,638 geoprocessing.raster_calculator(397) INFO started stats_worker 2022-04-01 16:10:55,119 geoprocessing.raster_calculator(477) INFO 100.0% complete 2022-04-01 16:10:55,119 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2022-04-01 16:10:55,303 geoprocessing.align_and_resize_raster_stack(795) INFO 2 of 3 aligned: aligned_lulc_M2.2015.tif 2022-04-01 16:10:58,871 geoprocessing.raster_calculator(391) INFO starting stats_worker 2022-04-01 16:10:58,871 threading.run(870) DEBUG stats worker PID: 11392 2022-04-01 16:10:58,871 geoprocessing.raster_calculator(397) INFO started stats_worker 2022-04-01 16:11:00,036 geoprocessing.raster_calculator(477) INFO 100.0% complete 2022-04-01 16:11:00,037 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2022-04-01 16:11:01,243 geoprocessing.align_and_resize_raster_stack(795) INFO 3 of 3 aligned: aligned_runoff_proxy_M2.2015.tif 2022-04-01 16:11:01,243 geoprocessing.align_and_resize_raster_stack(797) INFO aligned all 3 rasters. 2022-04-01 16:11:07,006 Task._call(1234) INFO 27.2% complete 2022-04-01 16:11:13,002 Task._call(1234) INFO 54.3% complete 2022-04-01 16:11:16,965 Task._call(1234) INFO 100.0% complete 2022-04-01 16:11:22,019 Task._call(1234) INFO 13.6% complete 2022-04-01 16:11:28,028 Task._call(1234) INFO 31.7% complete 2022-04-01 16:11:34,065 Task._call(1234) INFO 45.3% complete 2022-04-01 16:11:40,031 Task._call(1234) INFO 81.5% complete 2022-04-01 16:11:41,659 Task._call(1234) INFO 100.0% complete 2022-04-01 16:11:41,673 Task._call(1234) DEBUG creating target flow accum raster layer 2022-04-01 16:11:43,575 Task._call(1234) DEBUG creating visited raster layer 2022-04-01 16:11:43,592 Task._call(1234) DEBUG starting search 2022-04-01 16:11:47,000 Task._call(1234) INFO mfd flow accum 7.5% complete 2022-04-01 16:11:53,000 Task._call(1234) INFO mfd flow accum 24.6% complete 2022-04-01 16:11:59,000 Task._call(1234) INFO mfd flow accum 40.4% complete 2022-04-01 16:12:02,133 Task._call(1234) INFO 100.0% complete 2022-04-01 16:12:08,525 Task._call(1234) INFO 40.7% complete 2022-04-01 16:12:12,021 Task._call(1234) INFO filter out incomplete divergent streams 2022-04-01 16:12:12,317 Task._call(1234) INFO 100.0% complete 2022-04-01 16:12:14,752 geoprocessing.raster_calculator(391) INFO starting stats_worker 2022-04-01 16:12:14,754 threading.run(870) DEBUG stats worker PID: 11392 2022-04-01 16:12:14,754 geoprocessing.raster_calculator(397) INFO started stats_worker 2022-04-01 16:12:15,662 geoprocessing.raster_calculator(477) INFO 100.0% complete 2022-04-01 16:12:15,663 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2022-04-01 16:12:17,693 geoprocessing.raster_calculator(391) INFO starting stats_worker 2022-04-01 16:12:17,695 threading.run(870) DEBUG stats worker PID: 11392 2022-04-01 16:12:17,695 geoprocessing.raster_calculator(397) INFO started stats_worker 2022-04-01 16:12:18,903 geoprocessing.raster_calculator(477) INFO 100.0% complete 2022-04-01 16:12:18,904 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2022-04-01 16:12:19,484 Task._call(1234) DEBUG creating target flow accum raster layer 2022-04-01 16:12:21,108 Task._call(1234) DEBUG creating visited raster layer 2022-04-01 16:12:21,127 Task._call(1234) DEBUG starting search 2022-04-01 16:12:25,144 Task._call(1234) INFO mfd flow accum 8.6% complete 2022-04-01 16:12:31,013 Task._call(1234) INFO mfd flow accum 23.9% complete 2022-04-01 16:12:37,000 Task._call(1234) INFO mfd flow accum 38.9% complete 2022-04-01 16:12:40,665 Task._call(1234) INFO 100.0% complete 2022-04-01 16:12:40,733 geoprocessing.raster_calculator(391) INFO starting stats_worker 2022-04-01 16:12:40,734 threading.run(870) DEBUG stats worker PID: 11392 2022-04-01 16:12:40,735 geoprocessing.raster_calculator(397) INFO started stats_worker 2022-04-01 16:12:44,292 geoprocessing.raster_calculator(477) INFO 100.0% complete 2022-04-01 16:12:44,293 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2022-04-01 16:12:45,812 geoprocessing.raster_calculator(391) INFO starting stats_worker 2022-04-01 16:12:45,813 threading.run(870) DEBUG stats worker PID: 11392 2022-04-01 16:12:45,813 geoprocessing.raster_calculator(397) INFO started stats_worker 2022-04-01 16:12:48,393 geoprocessing.raster_calculator(477) INFO 100.0% complete 2022-04-01 16:12:48,393 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2022-04-01 16:12:49,756 geoprocessing.raster_calculator(391) INFO starting stats_worker 2022-04-01 16:12:49,757 threading.run(870) DEBUG stats worker PID: 11392 2022-04-01 16:12:49,757 geoprocessing.raster_calculator(397) INFO started stats_worker 2022-04-01 16:12:50,786 geoprocessing.raster_calculator(477) INFO 100.0% complete 2022-04-01 16:12:50,786 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2022-04-01 16:12:57,096 Task._call(1234) INFO 27.2% complete 2022-04-01 16:13:03,036 Task._call(1234) INFO 45.3% complete 2022-04-01 16:13:09,006 Task._call(1234) INFO 90.5% complete 2022-04-01 16:13:09,648 Task._call(1234) INFO 100.0% complete 2022-04-01 16:13:15,030 Task._call(1234) INFO 31.7% complete 2022-04-01 16:13:21,009 Task._call(1234) INFO 58.8% complete 2022-04-01 16:13:24,486 Task._call(1234) INFO 100.0% complete 2022-04-01 16:13:24,525 geoprocessing.raster_calculator(391) INFO starting stats_worker 2022-04-01 16:13:24,526 threading.run(870) DEBUG stats worker PID: 11392 2022-04-01 16:13:24,526 geoprocessing.raster_calculator(397) INFO started stats_worker 2022-04-01 16:13:27,286 geoprocessing.raster_calculator(477) INFO 100.0% complete 2022-04-01 16:13:27,286 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2022-04-01 16:13:28,603 geoprocessing.raster_calculator(391) INFO starting stats_worker 2022-04-01 16:13:28,604 threading.run(870) DEBUG stats worker PID: 11392 2022-04-01 16:13:28,605 geoprocessing.raster_calculator(397) INFO started stats_worker 2022-04-01 16:13:29,769 geoprocessing.raster_calculator(477) INFO 100.0% complete 2022-04-01 16:13:29,769 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2022-04-01 16:13:30,672 geoprocessing.raster_calculator(391) INFO starting stats_worker 2022-04-01 16:13:30,672 threading.run(870) DEBUG stats worker PID: 11392 2022-04-01 16:13:30,672 geoprocessing.raster_calculator(397) INFO started stats_worker 2022-04-01 16:13:32,013 geoprocessing.raster_calculator(477) INFO 100.0% complete 2022-04-01 16:13:32,014 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2022-04-01 16:13:32,707 geoprocessing.raster_calculator(391) INFO starting stats_worker 2022-04-01 16:13:32,708 threading.run(870) DEBUG stats worker PID: 11392 2022-04-01 16:13:32,708 geoprocessing.raster_calculator(397) INFO started stats_worker 2022-04-01 16:13:34,906 geoprocessing.raster_calculator(477) INFO 100.0% complete 2022-04-01 16:13:34,907 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2022-04-01 16:13:35,631 geoprocessing.raster_calculator(391) INFO starting stats_worker 2022-04-01 16:13:35,632 threading.run(870) DEBUG stats worker PID: 11392 2022-04-01 16:13:35,632 geoprocessing.raster_calculator(397) INFO started stats_worker 2022-04-01 16:13:38,057 geoprocessing.raster_calculator(477) INFO 100.0% complete 2022-04-01 16:13:38,057 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2022-04-01 16:13:38,603 geoprocessing.raster_calculator(391) INFO starting stats_worker 2022-04-01 16:13:38,604 threading.run(870) DEBUG stats worker PID: 11392 2022-04-01 16:13:38,604 geoprocessing.raster_calculator(397) INFO started stats_worker 2022-04-01 16:13:40,356 geoprocessing.raster_calculator(477) INFO 100.0% complete 2022-04-01 16:13:40,357 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2022-04-01 16:13:41,036 geoprocessing.raster_calculator(391) INFO starting stats_worker 2022-04-01 16:13:41,037 threading.run(870) DEBUG stats worker PID: 11392 2022-04-01 16:13:41,037 geoprocessing.raster_calculator(397) INFO started stats_worker 2022-04-01 16:13:42,891 geoprocessing.raster_calculator(477) INFO 100.0% complete 2022-04-01 16:13:42,891 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2022-04-01 16:13:43,470 geoprocessing.raster_calculator(391) INFO starting stats_worker 2022-04-01 16:13:43,470 threading.run(870) DEBUG stats worker PID: 11392 2022-04-01 16:13:43,470 geoprocessing.raster_calculator(397) INFO started stats_worker 2022-04-01 16:13:46,243 geoprocessing.raster_calculator(477) INFO 100.0% complete 2022-04-01 16:13:46,243 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2022-04-01 16:14:08,390 geoprocessing.raster_calculator(391) INFO starting stats_worker 2022-04-01 16:14:08,391 threading.run(870) DEBUG stats worker PID: 11392 2022-04-01 16:14:08,391 geoprocessing.raster_calculator(397) INFO started stats_worker 2022-04-01 16:14:10,109 geoprocessing.raster_calculator(477) INFO 100.0% complete 2022-04-01 16:14:10,109 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2022-04-01 16:14:11,316 geoprocessing.raster_calculator(391) INFO starting stats_worker 2022-04-01 16:14:11,317 threading.run(870) DEBUG stats worker PID: 11392 2022-04-01 16:14:11,317 geoprocessing.raster_calculator(397) INFO started stats_worker 2022-04-01 16:14:13,185 geoprocessing.raster_calculator(477) INFO 100.0% complete 2022-04-01 16:14:13,185 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2022-04-01 16:14:14,073 geoprocessing.raster_calculator(391) INFO starting stats_worker 2022-04-01 16:14:14,074 threading.run(870) DEBUG stats worker PID: 11392 2022-04-01 16:14:14,075 geoprocessing.raster_calculator(397) INFO started stats_worker 2022-04-01 16:14:17,211 geoprocessing.raster_calculator(477) INFO 100.0% complete 2022-04-01 16:14:17,211 geoprocessing.raster_calculator(480) INFO Waiting for raster stats worker result. 2022-04-01 16:14:18,584 geoprocessing.zonal_statistics(1184) DEBUG > 2022-04-01 16:14:21,331 geoprocessing.align_and_resize_raster_stack(795) INFO 1 of 1 aligned: clipped_raster1y7d10cr.tif 2022-04-01 16:14:21,331 geoprocessing.align_and_resize_raster_stack(797) INFO aligned all 1 rasters. 2022-04-01 16:14:21,623 geoprocessing.zonal_statistics(1244) INFO build a lookup of aggregate field value to FID 2022-04-01 16:14:21,623 geoprocessing.zonal_statistics(1251) INFO creating disjoint polygon set 2022-04-01 16:14:21,623 geoprocessing.calculate_disjoint_polygon_set(2183) INFO build shapely polygon list 2022-04-01 16:14:21,630 geoprocessing.calculate_disjoint_polygon_set(2204) INFO build shapely rtree index 2022-04-01 16:14:21,631 geoprocessing.calculate_disjoint_polygon_set(2219) INFO poly feature lookup 100.0% complete on watershed_results_ndr_M2.2015.shp 2022-04-01 16:14:21,631 geoprocessing.calculate_disjoint_polygon_set(2221) INFO build poly intersection lookup 2022-04-01 16:14:21,631 geoprocessing.calculate_disjoint_polygon_set(2245) INFO poly intersection feature lookup 100.0% complete on watershed_results_ndr_M2.2015.shp 2022-04-01 16:14:21,631 geoprocessing.calculate_disjoint_polygon_set(2279) INFO maximal subset build 100.0% complete on watershed_results_ndr_M2.2015.shp 2022-04-01 16:14:21,642 geoprocessing.zonal_statistics(1274) INFO processing 1 disjoint polygon sets 2022-04-01 16:14:21,643 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 1 of 1 100.0% processed on watershed_results_ndr_M2.2015.shp 2022-04-01 16:14:21,659 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 1 of 1 watershed_results_ndr_M2.2015.shp 2022-04-01 16:14:22,353 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 1 of 1 watershed_results_ndr_M2.2015.shp 2022-04-01 16:14:23,584 geoprocessing.zonal_statistics(1378) DEBUG unset_fids: 0 of 1 2022-04-01 16:14:23,585 geoprocessing.zonal_statistics(1381) DEBUG gt [ 3.4381594e+05 9.9974604e+00 0.0000000e+00 4.1786641e+05 0.0000000e+00 -9.9974604e+00] for ('C:/Users/tershya/OneDrive - Western Washington University/Research/NDR Workspace/Model 2/Output_2015\\n_export_M2.2015.tif', 1) 2022-04-01 16:14:23,585 geoprocessing.zonal_statistics(1460) DEBUG remaining unset_fids: 0 of 1 2022-04-01 16:14:23,585 geoprocessing.zonal_statistics(1468) INFO all done processing polygon sets for watershed_results_ndr_M2.2015.shp 2022-04-01 16:14:23,622 geoprocessing.zonal_statistics(1184) DEBUG > 2022-04-01 16:14:25,352 geoprocessing.align_and_resize_raster_stack(795) INFO 1 of 1 aligned: clipped_rasterxzcs9jq5.tif 2022-04-01 16:14:25,353 geoprocessing.align_and_resize_raster_stack(797) INFO aligned all 1 rasters. 2022-04-01 16:14:25,359 geoprocessing.zonal_statistics(1244) INFO build a lookup of aggregate field value to FID 2022-04-01 16:14:25,360 geoprocessing.zonal_statistics(1251) INFO creating disjoint polygon set 2022-04-01 16:14:25,361 geoprocessing.calculate_disjoint_polygon_set(2183) INFO build shapely polygon list 2022-04-01 16:14:25,367 geoprocessing.calculate_disjoint_polygon_set(2204) INFO build shapely rtree index 2022-04-01 16:14:25,368 geoprocessing.calculate_disjoint_polygon_set(2219) INFO poly feature lookup 100.0% complete on watershed_results_ndr_M2.2015.shp 2022-04-01 16:14:25,368 geoprocessing.calculate_disjoint_polygon_set(2221) INFO build poly intersection lookup 2022-04-01 16:14:25,368 geoprocessing.calculate_disjoint_polygon_set(2245) INFO poly intersection feature lookup 100.0% complete on watershed_results_ndr_M2.2015.shp 2022-04-01 16:14:25,368 geoprocessing.calculate_disjoint_polygon_set(2279) INFO maximal subset build 100.0% complete on watershed_results_ndr_M2.2015.shp 2022-04-01 16:14:25,380 geoprocessing.zonal_statistics(1274) INFO processing 1 disjoint polygon sets 2022-04-01 16:14:25,381 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 1 of 1 100.0% processed on watershed_results_ndr_M2.2015.shp 2022-04-01 16:14:25,396 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 1 of 1 watershed_results_ndr_M2.2015.shp 2022-04-01 16:14:26,082 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 1 of 1 watershed_results_ndr_M2.2015.shp 2022-04-01 16:14:26,999 geoprocessing.zonal_statistics(1378) DEBUG unset_fids: 0 of 1 2022-04-01 16:14:27,000 geoprocessing.zonal_statistics(1381) DEBUG gt [ 3.4381594e+05 9.9974604e+00 0.0000000e+00 4.1786641e+05 0.0000000e+00 -9.9974604e+00] for ('C:/Users/tershya/OneDrive - Western Washington University/Research/NDR Workspace/Model 2/Output_2015\\intermediate_outputs\\surface_load_n_M2.2015.tif', 1) 2022-04-01 16:14:27,000 geoprocessing.zonal_statistics(1460) DEBUG remaining unset_fids: 0 of 1 2022-04-01 16:14:27,000 geoprocessing.zonal_statistics(1468) INFO all done processing polygon sets for watershed_results_ndr_M2.2015.shp 2022-04-01 16:14:27,031 geoprocessing.zonal_statistics(1184) DEBUG > 2022-04-01 16:14:28,646 geoprocessing.align_and_resize_raster_stack(795) INFO 1 of 1 aligned: clipped_raster8tviqr8d.tif 2022-04-01 16:14:28,646 geoprocessing.align_and_resize_raster_stack(797) INFO aligned all 1 rasters. 2022-04-01 16:14:28,652 geoprocessing.zonal_statistics(1244) INFO build a lookup of aggregate field value to FID 2022-04-01 16:14:28,653 geoprocessing.zonal_statistics(1251) INFO creating disjoint polygon set 2022-04-01 16:14:28,654 geoprocessing.calculate_disjoint_polygon_set(2183) INFO build shapely polygon list 2022-04-01 16:14:28,660 geoprocessing.calculate_disjoint_polygon_set(2204) INFO build shapely rtree index 2022-04-01 16:14:28,661 geoprocessing.calculate_disjoint_polygon_set(2219) INFO poly feature lookup 100.0% complete on watershed_results_ndr_M2.2015.shp 2022-04-01 16:14:28,661 geoprocessing.calculate_disjoint_polygon_set(2221) INFO build poly intersection lookup 2022-04-01 16:14:28,661 geoprocessing.calculate_disjoint_polygon_set(2245) INFO poly intersection feature lookup 100.0% complete on watershed_results_ndr_M2.2015.shp 2022-04-01 16:14:28,662 geoprocessing.calculate_disjoint_polygon_set(2279) INFO maximal subset build 100.0% complete on watershed_results_ndr_M2.2015.shp 2022-04-01 16:14:28,672 geoprocessing.zonal_statistics(1274) INFO processing 1 disjoint polygon sets 2022-04-01 16:14:28,672 geoprocessing.zonal_statistics(1309) INFO disjoint polygon set 1 of 1 100.0% processed on watershed_results_ndr_M2.2015.shp 2022-04-01 16:14:28,688 geoprocessing.zonal_statistics(1317) INFO rasterizing disjoint polygon set 1 of 1 watershed_results_ndr_M2.2015.shp 2022-04-01 16:14:29,386 geoprocessing.zonal_statistics(1335) INFO summarizing rasterized disjoint polygon set 1 of 1 watershed_results_ndr_M2.2015.shp 2022-04-01 16:14:30,225 geoprocessing.zonal_statistics(1378) DEBUG unset_fids: 0 of 1 2022-04-01 16:14:30,225 geoprocessing.zonal_statistics(1381) DEBUG gt [ 3.4381594e+05 9.9974604e+00 0.0000000e+00 4.1786641e+05 0.0000000e+00 -9.9974604e+00] for ('C:/Users/tershya/OneDrive - Western Washington University/Research/NDR Workspace/Model 2/Output_2015\\intermediate_outputs\\sub_load_n_M2.2015.tif', 1) 2022-04-01 16:14:30,225 geoprocessing.zonal_statistics(1460) DEBUG remaining unset_fids: 0 of 1 2022-04-01 16:14:30,225 geoprocessing.zonal_statistics(1468) INFO all done processing polygon sets for watershed_results_ndr_M2.2015.shp 2022-04-01 16:14:30,295 ndr.execute(705) INFO Writing summaries to output shapefile 2022-04-01 16:14:30,303 utils._log_gdal_errors(80) WARNING [errno 1] Value 2041099877.57999992 of field Shape_Area of feature 0 not successfully written. Possibly due to too larger number with respect to field width 2022-04-01 16:14:30,304 ndr.execute(710) INFO NDR complete! 2022-04-01 16:14:30,304 ndr.execute(711) INFO _ _ ____ ____ 2022-04-01 16:14:30,304 ndr.execute(712) INFO | \ |"| | _"\U | _"\ u 2022-04-01 16:14:30,304 ndr.execute(713) INFO <| \| |>/| | | |\| |_) |/ 2022-04-01 16:14:30,305 ndr.execute(714) INFO U| |\ |uU| |_| |\| _ < 2022-04-01 16:14:30,305 ndr.execute(715) INFO |_| \_| |____/ u|_| \_\ 2022-04-01 16:14:30,305 ndr.execute(716) INFO || \\,-.|||_ // \\_ 2022-04-01 16:14:30,305 ndr.execute(717) INFO (_") (_/(__)_) (__) (__) 2022-04-01 16:14:30,305 model._logged_target(1655) INFO Execution finished 2022-04-01 16:14:30,306 utils.prepare_workspace(129) INFO Elapsed time: 3m 43.47s