2022-07-28 22:59:30,704 (natcap.invest.utils) utils.prepare_workspace(162) Level 100 Writing log messages to [C:\Users\vicke\Documents\rencap\sydwater-mat-map\delineate_watersheds\InVEST-natcap.invest.delineateit.delineateit-log-2022-07-28--22_59_30.txt]
2022-07-28 22:59:30,704 (__main__) cli.main(475) Level 100 Starting model with parameters: 
Arguments for InVEST natcap.invest.delineateit.delineateit 3.11.0:
dem_path              C:\Users\vicke\Documents\rencap\sydwater-mat-map\delineate_watersheds\dem2.tif
detect_pour_points    False
flow_threshold        5000
n_workers             -1
outlet_vector_path    C:\Users\vicke\Documents\rencap\sydwater-mat-map\delineate_watersheds\pour_point_1.gpkg
results_suffix        run2
skip_invalid_geometry False
snap_distance         500
snap_points           True
workspace_dir         C:\Users\vicke\Documents\rencap\sydwater-mat-map\delineate_watersheds

2022-07-28 22:59:30,910 (pygeoprocessing.routing.routing) Task._call(1093) INFO (fill pits): complete
2022-07-28 22:59:31,135 (pygeoprocessing.routing.routing) Task._call(1093) INFO (flow dir d8): complete
2022-07-28 22:59:31,176 (natcap.invest.delineateit.delineateit) delineateit.preprocess_geometries(401) INFO Checking 1 geometries from source vector
2022-07-28 22:59:31,177 (natcap.invest.delineateit.delineateit) delineateit.preprocess_geometries(464) INFO 1 features copied to preprocessed_geometries_run2.gpkg from the original 1 features
2022-07-28 22:59:31,309 (pygeoprocessing.routing.routing) Task._call(1093) INFO 100.0% complete
2022-07-28 22:59:31,324 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(396) INFO starting stats_worker
2022-07-28 22:59:31,325 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(402) INFO started stats_worker <Thread(Thread-1 (stats_worker), started daemon 15940)>
2022-07-28 22:59:31,340 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(482) INFO 100.0% complete
2022-07-28 22:59:31,340 (pygeoprocessing.geoprocessing) geoprocessing.raster_calculator(485) INFO Waiting for raster stats worker result.
2022-07-28 22:59:31,390 (pygeoprocessing.routing.watershed) Task._call(1093) INFO Delineating watersheds
2022-07-28 22:59:31,397 (pygeoprocessing.routing.watershed) Task._call(1093) INFO Delineating watershed 1 of 1 (ws_id 2)
2022-07-28 22:59:31,412 (pygeoprocessing.routing.watershed) Task._call(1093) INFO Finished delineating 1 watersheds
2022-07-28 22:59:31,413 (pygeoprocessing.routing.watershed) Task._call(1093) INFO Consolidating 1 fragments and copying field values to watersheds layer.
2022-07-28 22:59:31,427 (pygeoprocessing.routing.watershed) Task._call(1093) INFO Finished vector consolidation
2022-07-28 22:59:31,433 (pygeoprocessing.routing.watershed) Task._call(1093) INFO Watershed delineation complete
2022-07-28 22:59:31,436 (natcap.invest.utils) utils.prepare_workspace(170) INFO Elapsed time: 0.73s
2022-07-28 22:59:31,436 (natcap.invest.utils) utils.prepare_workspace(173) INFO Execution finished