2019-07-29 16:21:32,114 utils.prepare_workspace(109) INFO Writing log messages to C:\Users\audiae\Documents\pollination_workspace2019072901\InVEST-Crop-Pollination-log-2019-07-29--16_21_32.txt
2019-07-29 16:21:32,115 model._logged_target(1633) Level 100 Starting model with parameters: 
Arguments for InVEST natcap.invest.pollination 3.7.0:
farm_vector_path                 
guild_table_path                 C:/Users/audiae/Documents/Project/InVEST/Pollination/GuildTableRelSpp.csv
landcover_biophysical_table_path C:/Users/audiae/Documents/Project/InVEST/Pollination/PollinatorBioPhys.csv
landcover_raster_path            C:/Users/audiae/Documents/Project/GISData/NationalLandCover/NLC16Clip.tif
n_workers                        -1
results_suffix                   
workspace_dir                    C:\Users\audiae\Documents\pollination_workspace2019072901

2019-07-29 16:21:32,140 pollination._parse_scenario_variables(878) INFO Checking to make sure guild table has all expected headers
2019-07-29 16:21:32,187 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-07-29 16:21:32,187 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-236, started daemon 15540)>
2019-07-29 16:21:33,734 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-07-29 16:21:33,734 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-07-29 16:21:33,734 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-07-29 16:21:33,734 threading.run(754) DEBUG payload is None, terminating
2019-07-29 16:21:34,286 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-07-29 16:21:34,286 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-237, started daemon 10032)>
2019-07-29 16:21:35,232 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-07-29 16:21:35,233 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-07-29 16:21:35,233 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-07-29 16:21:35,233 threading.run(754) DEBUG payload is None, terminating
2019-07-29 16:21:35,533 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-07-29 16:21:35,533 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-238, started daemon 15580)>
2019-07-29 16:21:37,122 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-07-29 16:21:37,122 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-07-29 16:21:37,122 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-07-29 16:21:37,122 threading.run(754) DEBUG payload is None, terminating
2019-07-29 16:21:37,750 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-07-29 16:21:37,750 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-239, started daemon 15968)>
2019-07-29 16:21:39,276 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-07-29 16:21:39,276 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-07-29 16:21:39,276 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-07-29 16:21:39,276 threading.run(754) DEBUG payload is None, terminating
2019-07-29 16:21:39,839 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-07-29 16:21:39,842 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-240, started daemon 10140)>
2019-07-29 16:21:40,750 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-07-29 16:21:40,750 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-07-29 16:21:40,750 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-07-29 16:21:40,750 threading.run(754) DEBUG payload is None, terminating
2019-07-29 16:21:41,048 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-07-29 16:21:41,048 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-241, started daemon 10936)>
2019-07-29 16:21:42,624 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-07-29 16:21:42,627 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-07-29 16:21:42,627 threading.run(754) DEBUG payload is None, terminating
2019-07-29 16:21:42,627 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-07-29 16:21:43,240 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-07-29 16:21:43,240 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-242, started daemon 4412)>
2019-07-29 16:21:45,759 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-07-29 16:21:45,759 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-07-29 16:21:45,759 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-07-29 16:21:45,759 threading.run(754) DEBUG payload is None, terminating
2019-07-29 16:21:49,091 geoprocessing.convolve_2d(2380) INFO starting convolve
2019-07-29 16:21:54,130 geoprocessing.<lambda>(2484) INFO convolution worker approximately 19.4% complete on floral_resources_agopostemon.tif
2019-07-29 16:21:59,197 geoprocessing.<lambda>(2484) INFO convolution worker approximately 35.4% complete on floral_resources_agopostemon.tif
2019-07-29 16:22:04,263 geoprocessing.<lambda>(2484) INFO convolution worker approximately 48.3% complete on floral_resources_agopostemon.tif
2019-07-29 16:22:09,276 geoprocessing.<lambda>(2484) INFO convolution worker approximately 59.4% complete on floral_resources_agopostemon.tif
2019-07-29 16:22:14,349 geoprocessing.<lambda>(2484) INFO convolution worker approximately 69.7% complete on floral_resources_agopostemon.tif
2019-07-29 16:22:19,427 geoprocessing.<lambda>(2484) INFO convolution worker approximately 80.5% complete on floral_resources_agopostemon.tif
2019-07-29 16:22:24,473 geoprocessing.<lambda>(2484) INFO convolution worker approximately 92.2% complete on floral_resources_agopostemon.tif
2019-07-29 16:22:26,885 geoprocessing.convolve_2d(2489) INFO convolution worker 100.0% complete on floral_resources_agopostemon.tif
2019-07-29 16:22:27,703 geoprocessing.convolve_2d(2494) INFO need to normalize result so nodata values are not included
2019-07-29 16:22:29,507 geoprocessing.<lambda>(2526) INFO convolution nodata normalizer approximately 43.4% complete on floral_resources_agopostemon.tif
2019-07-29 16:22:34,546 geoprocessing.<lambda>(2526) INFO convolution nodata normalizer approximately 71.7% complete on floral_resources_agopostemon.tif
2019-07-29 16:22:39,548 geoprocessing.<lambda>(2526) INFO convolution nodata normalizer approximately 92.0% complete on floral_resources_agopostemon.tif
2019-07-29 16:22:41,657 geoprocessing.convolve_2d(2533) INFO convolution nodata normalize 100.0% complete on floral_resources_agopostemon.tif
2019-07-29 16:22:43,969 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-07-29 16:22:43,969 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-244, started daemon 4604)>
2019-07-29 16:22:48,971 geoprocessing.<lambda>(442) INFO 88.3% complete
2019-07-29 16:22:50,071 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-07-29 16:22:50,071 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-07-29 16:22:50,072 threading.run(754) DEBUG payload is None, terminating
2019-07-29 16:22:50,071 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-07-29 16:22:53,733 geoprocessing.convolve_2d(2380) INFO starting convolve
2019-07-29 16:22:58,769 geoprocessing.<lambda>(2484) INFO convolution worker approximately 18.6% complete on convolve_ps_agopostemon.tif
2019-07-29 16:23:03,779 geoprocessing.<lambda>(2484) INFO convolution worker approximately 33.0% complete on convolve_ps_agopostemon.tif
2019-07-29 16:23:08,796 geoprocessing.<lambda>(2484) INFO convolution worker approximately 46.0% complete on convolve_ps_agopostemon.tif
2019-07-29 16:23:13,849 geoprocessing.<lambda>(2484) INFO convolution worker approximately 57.1% complete on convolve_ps_agopostemon.tif
2019-07-29 16:23:18,908 geoprocessing.<lambda>(2484) INFO convolution worker approximately 68.1% complete on convolve_ps_agopostemon.tif
2019-07-29 16:23:23,957 geoprocessing.<lambda>(2484) INFO convolution worker approximately 78.9% complete on convolve_ps_agopostemon.tif
2019-07-29 16:23:28,959 geoprocessing.<lambda>(2484) INFO convolution worker approximately 90.0% complete on convolve_ps_agopostemon.tif
2019-07-29 16:23:32,371 geoprocessing.convolve_2d(2489) INFO convolution worker 100.0% complete on convolve_ps_agopostemon.tif
2019-07-29 16:23:33,144 geoprocessing.convolve_2d(2494) INFO need to normalize result so nodata values are not included
2019-07-29 16:23:33,964 geoprocessing.<lambda>(2526) INFO convolution nodata normalizer approximately 29.1% complete on convolve_ps_agopostemon.tif
2019-07-29 16:23:39,012 geoprocessing.<lambda>(2526) INFO convolution nodata normalizer approximately 68.6% complete on convolve_ps_agopostemon.tif
2019-07-29 16:23:44,028 geoprocessing.<lambda>(2526) INFO convolution nodata normalizer approximately 92.5% complete on convolve_ps_agopostemon.tif
2019-07-29 16:23:45,848 geoprocessing.convolve_2d(2533) INFO convolution nodata normalize 100.0% complete on convolve_ps_agopostemon.tif
2019-07-29 16:23:47,928 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-07-29 16:23:47,930 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-246, started daemon 9660)>
2019-07-29 16:23:52,944 geoprocessing.<lambda>(442) INFO 77.9% complete
2019-07-29 16:23:54,917 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-07-29 16:23:54,917 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-07-29 16:23:54,917 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-07-29 16:23:54,917 threading.run(754) DEBUG payload is None, terminating
2019-07-29 16:23:55,204 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-07-29 16:23:55,204 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-247, started daemon 2328)>
2019-07-29 16:23:58,423 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-07-29 16:23:58,423 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-07-29 16:23:58,423 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-07-29 16:23:58,424 threading.run(754) DEBUG payload is None, terminating
2019-07-29 16:23:58,566 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-07-29 16:23:58,568 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-248, started daemon 15540)>
2019-07-29 16:24:03,572 geoprocessing.<lambda>(442) INFO 81.0% complete
2019-07-29 16:24:05,206 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-07-29 16:24:05,207 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-07-29 16:24:05,207 threading.run(754) DEBUG payload is None, terminating
2019-07-29 16:24:05,207 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-07-29 16:24:05,463 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-07-29 16:24:05,463 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-249, started daemon 5688)>
2019-07-29 16:24:10,464 geoprocessing.<lambda>(442) INFO 81.7% complete
2019-07-29 16:24:12,424 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-07-29 16:24:12,424 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-07-29 16:24:12,424 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-07-29 16:24:12,424 threading.run(754) DEBUG payload is None, terminating
2019-07-29 16:24:16,270 geoprocessing.convolve_2d(2380) INFO starting convolve
2019-07-29 16:24:21,299 geoprocessing.<lambda>(2484) INFO convolution worker approximately 19.0% complete on convolve_ps_lassioglossum.tif
2019-07-29 16:24:26,352 geoprocessing.<lambda>(2484) INFO convolution worker approximately 35.2% complete on convolve_ps_lassioglossum.tif
2019-07-29 16:24:31,410 geoprocessing.<lambda>(2484) INFO convolution worker approximately 48.4% complete on convolve_ps_lassioglossum.tif
2019-07-29 16:24:36,467 geoprocessing.<lambda>(2484) INFO convolution worker approximately 59.8% complete on convolve_ps_lassioglossum.tif
2019-07-29 16:24:41,493 geoprocessing.<lambda>(2484) INFO convolution worker approximately 71.0% complete on convolve_ps_lassioglossum.tif
2019-07-29 16:24:46,515 geoprocessing.<lambda>(2484) INFO convolution worker approximately 82.2% complete on convolve_ps_lassioglossum.tif
2019-07-29 16:24:51,568 geoprocessing.<lambda>(2484) INFO convolution worker approximately 94.1% complete on convolve_ps_lassioglossum.tif
2019-07-29 16:24:53,176 geoprocessing.convolve_2d(2489) INFO convolution worker 100.0% complete on convolve_ps_lassioglossum.tif
2019-07-29 16:24:54,003 geoprocessing.convolve_2d(2494) INFO need to normalize result so nodata values are not included
2019-07-29 16:24:56,569 geoprocessing.<lambda>(2526) INFO convolution nodata normalizer approximately 48.5% complete on convolve_ps_lassioglossum.tif
2019-07-29 16:25:01,576 geoprocessing.<lambda>(2526) INFO convolution nodata normalizer approximately 77.3% complete on convolve_ps_lassioglossum.tif
2019-07-29 16:25:06,421 geoprocessing.convolve_2d(2533) INFO convolution nodata normalize 100.0% complete on convolve_ps_lassioglossum.tif
2019-07-29 16:25:08,463 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-07-29 16:25:08,463 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-251, started daemon 2972)>
2019-07-29 16:25:13,493 geoprocessing.<lambda>(442) INFO 78.3% complete
2019-07-29 16:25:15,427 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-07-29 16:25:15,427 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-07-29 16:25:15,428 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-07-29 16:25:15,428 threading.run(754) DEBUG payload is None, terminating
2019-07-29 16:25:15,684 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-07-29 16:25:15,686 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-252, started daemon 7356)>
2019-07-29 16:25:20,688 geoprocessing.<lambda>(442) INFO 77.6% complete
2019-07-29 16:25:22,727 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-07-29 16:25:22,727 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-07-29 16:25:22,729 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-07-29 16:25:22,729 threading.run(754) DEBUG payload is None, terminating
2019-07-29 16:25:22,987 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-07-29 16:25:22,987 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-253, started daemon 15052)>
2019-07-29 16:25:27,989 geoprocessing.<lambda>(442) INFO 77.3% complete
2019-07-29 16:25:30,030 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-07-29 16:25:30,030 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-07-29 16:25:30,030 threading.run(754) DEBUG payload is None, terminating
2019-07-29 16:25:30,030 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-07-29 16:25:30,286 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-07-29 16:25:30,286 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-254, started daemon 14496)>
2019-07-29 16:25:32,926 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-07-29 16:25:32,926 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-07-29 16:25:32,926 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-07-29 16:25:32,927 threading.run(754) DEBUG payload is None, terminating
2019-07-29 16:25:36,270 geoprocessing.convolve_2d(2380) INFO starting convolve
2019-07-29 16:25:41,302 geoprocessing.<lambda>(2484) INFO convolution worker approximately 5.0% complete on floral_resources_melissodes.tif
2019-07-29 16:25:46,315 geoprocessing.<lambda>(2484) INFO convolution worker approximately 10.2% complete on floral_resources_melissodes.tif
2019-07-29 16:25:51,342 geoprocessing.<lambda>(2484) INFO convolution worker approximately 15.0% complete on floral_resources_melissodes.tif
2019-07-29 16:25:56,358 geoprocessing.<lambda>(2484) INFO convolution worker approximately 20.1% complete on floral_resources_melissodes.tif
2019-07-29 16:26:01,401 geoprocessing.<lambda>(2484) INFO convolution worker approximately 24.9% complete on floral_resources_melissodes.tif
2019-07-29 16:26:06,431 geoprocessing.<lambda>(2484) INFO convolution worker approximately 29.6% complete on floral_resources_melissodes.tif
2019-07-29 16:26:11,444 geoprocessing.<lambda>(2484) INFO convolution worker approximately 34.2% complete on floral_resources_melissodes.tif
2019-07-29 16:26:16,461 geoprocessing.<lambda>(2484) INFO convolution worker approximately 38.7% complete on floral_resources_melissodes.tif
2019-07-29 16:26:21,492 geoprocessing.<lambda>(2484) INFO convolution worker approximately 43.1% complete on floral_resources_melissodes.tif
2019-07-29 16:26:26,506 geoprocessing.<lambda>(2484) INFO convolution worker approximately 47.3% complete on floral_resources_melissodes.tif
2019-07-29 16:26:31,543 geoprocessing.<lambda>(2484) INFO convolution worker approximately 51.3% complete on floral_resources_melissodes.tif
2019-07-29 16:26:36,572 geoprocessing.<lambda>(2484) INFO convolution worker approximately 55.3% complete on floral_resources_melissodes.tif
2019-07-29 16:26:41,605 geoprocessing.<lambda>(2484) INFO convolution worker approximately 59.3% complete on floral_resources_melissodes.tif
2019-07-29 16:26:46,634 geoprocessing.<lambda>(2484) INFO convolution worker approximately 63.4% complete on floral_resources_melissodes.tif
2019-07-29 16:26:51,663 geoprocessing.<lambda>(2484) INFO convolution worker approximately 67.5% complete on floral_resources_melissodes.tif
2019-07-29 16:26:56,668 geoprocessing.<lambda>(2484) INFO convolution worker approximately 71.5% complete on floral_resources_melissodes.tif
2019-07-29 16:27:01,710 geoprocessing.<lambda>(2484) INFO convolution worker approximately 75.6% complete on floral_resources_melissodes.tif
2019-07-29 16:27:06,726 geoprocessing.<lambda>(2484) INFO convolution worker approximately 79.6% complete on floral_resources_melissodes.tif
2019-07-29 16:27:11,740 geoprocessing.<lambda>(2484) INFO convolution worker approximately 83.7% complete on floral_resources_melissodes.tif
2019-07-29 16:27:16,765 geoprocessing.<lambda>(2484) INFO convolution worker approximately 87.9% complete on floral_resources_melissodes.tif
2019-07-29 16:27:21,812 geoprocessing.<lambda>(2484) INFO convolution worker approximately 92.1% complete on floral_resources_melissodes.tif
2019-07-29 16:27:26,842 geoprocessing.<lambda>(2484) INFO convolution worker approximately 96.3% complete on floral_resources_melissodes.tif
2019-07-29 16:27:29,190 geoprocessing.convolve_2d(2489) INFO convolution worker 100.0% complete on floral_resources_melissodes.tif
2019-07-29 16:27:29,990 geoprocessing.convolve_2d(2494) INFO need to normalize result so nodata values are not included
2019-07-29 16:27:31,855 geoprocessing.<lambda>(2526) INFO convolution nodata normalizer approximately 43.4% complete on floral_resources_melissodes.tif
2019-07-29 16:27:36,880 geoprocessing.<lambda>(2526) INFO convolution nodata normalizer approximately 73.5% complete on floral_resources_melissodes.tif
2019-07-29 16:27:41,914 geoprocessing.<lambda>(2526) INFO convolution nodata normalizer approximately 96.2% complete on floral_resources_melissodes.tif
2019-07-29 16:27:43,028 geoprocessing.convolve_2d(2533) INFO convolution nodata normalize 100.0% complete on floral_resources_melissodes.tif
2019-07-29 16:27:45,451 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-07-29 16:27:45,453 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-256, started daemon 15160)>
2019-07-29 16:27:50,457 geoprocessing.<lambda>(442) INFO 76.8% complete
2019-07-29 16:27:53,118 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-07-29 16:27:53,118 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-07-29 16:27:53,118 threading.run(754) DEBUG payload is None, terminating
2019-07-29 16:27:53,118 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-07-29 16:27:56,888 geoprocessing.convolve_2d(2380) INFO starting convolve
2019-07-29 16:28:01,918 geoprocessing.<lambda>(2484) INFO convolution worker approximately 5.1% complete on convolve_ps_melissodes.tif
2019-07-29 16:28:06,944 geoprocessing.<lambda>(2484) INFO convolution worker approximately 10.4% complete on convolve_ps_melissodes.tif
2019-07-29 16:28:11,989 geoprocessing.<lambda>(2484) INFO convolution worker approximately 15.3% complete on convolve_ps_melissodes.tif
2019-07-29 16:28:17,019 geoprocessing.<lambda>(2484) INFO convolution worker approximately 20.2% complete on convolve_ps_melissodes.tif
2019-07-29 16:28:22,049 geoprocessing.<lambda>(2484) INFO convolution worker approximately 25.0% complete on convolve_ps_melissodes.tif
2019-07-29 16:28:27,069 geoprocessing.<lambda>(2484) INFO convolution worker approximately 29.8% complete on convolve_ps_melissodes.tif
2019-07-29 16:28:32,078 geoprocessing.<lambda>(2484) INFO convolution worker approximately 34.6% complete on convolve_ps_melissodes.tif
2019-07-29 16:28:37,102 geoprocessing.<lambda>(2484) INFO convolution worker approximately 38.8% complete on convolve_ps_melissodes.tif
2019-07-29 16:28:42,131 geoprocessing.<lambda>(2484) INFO convolution worker approximately 43.1% complete on convolve_ps_melissodes.tif
2019-07-29 16:28:47,173 geoprocessing.<lambda>(2484) INFO convolution worker approximately 47.5% complete on convolve_ps_melissodes.tif
2019-07-29 16:28:52,236 geoprocessing.<lambda>(2484) INFO convolution worker approximately 51.6% complete on convolve_ps_melissodes.tif
2019-07-29 16:28:57,256 geoprocessing.<lambda>(2484) INFO convolution worker approximately 55.6% complete on convolve_ps_melissodes.tif
2019-07-29 16:29:02,311 geoprocessing.<lambda>(2484) INFO convolution worker approximately 59.7% complete on convolve_ps_melissodes.tif
2019-07-29 16:29:07,365 geoprocessing.<lambda>(2484) INFO convolution worker approximately 63.8% complete on convolve_ps_melissodes.tif
2019-07-29 16:29:12,378 geoprocessing.<lambda>(2484) INFO convolution worker approximately 67.9% complete on convolve_ps_melissodes.tif
2019-07-29 16:29:17,451 geoprocessing.<lambda>(2484) INFO convolution worker approximately 71.8% complete on convolve_ps_melissodes.tif
2019-07-29 16:29:22,482 geoprocessing.<lambda>(2484) INFO convolution worker approximately 75.8% complete on convolve_ps_melissodes.tif
2019-07-29 16:29:27,529 geoprocessing.<lambda>(2484) INFO convolution worker approximately 79.8% complete on convolve_ps_melissodes.tif
2019-07-29 16:29:32,546 geoprocessing.<lambda>(2484) INFO convolution worker approximately 83.9% complete on convolve_ps_melissodes.tif
2019-07-29 16:29:37,578 geoprocessing.<lambda>(2484) INFO convolution worker approximately 88.1% complete on convolve_ps_melissodes.tif
2019-07-29 16:29:42,605 geoprocessing.<lambda>(2484) INFO convolution worker approximately 92.3% complete on convolve_ps_melissodes.tif
2019-07-29 16:29:47,631 geoprocessing.<lambda>(2484) INFO convolution worker approximately 96.4% complete on convolve_ps_melissodes.tif
2019-07-29 16:29:49,865 geoprocessing.convolve_2d(2489) INFO convolution worker 100.0% complete on convolve_ps_melissodes.tif
2019-07-29 16:29:50,665 geoprocessing.convolve_2d(2494) INFO need to normalize result so nodata values are not included
2019-07-29 16:29:52,640 geoprocessing.<lambda>(2526) INFO convolution nodata normalizer approximately 43.9% complete on convolve_ps_melissodes.tif
2019-07-29 16:29:57,660 geoprocessing.<lambda>(2526) INFO convolution nodata normalizer approximately 75.9% complete on convolve_ps_melissodes.tif
2019-07-29 16:30:02,586 geoprocessing.convolve_2d(2533) INFO convolution nodata normalize 100.0% complete on convolve_ps_melissodes.tif
2019-07-29 16:30:04,367 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-07-29 16:30:04,367 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-258, started daemon 13884)>
2019-07-29 16:30:09,382 geoprocessing.<lambda>(442) INFO 72.4% complete
2019-07-29 16:30:12,108 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-07-29 16:30:12,108 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-07-29 16:30:12,109 threading.run(754) DEBUG payload is None, terminating
2019-07-29 16:30:12,109 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-07-29 16:30:12,351 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-07-29 16:30:12,351 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-259, started daemon 11340)>
2019-07-29 16:30:16,059 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-07-29 16:30:16,059 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-07-29 16:30:16,061 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-07-29 16:30:16,061 threading.run(754) DEBUG payload is None, terminating
2019-07-29 16:30:16,194 geoprocessing.raster_calculator(366) INFO starting stats_worker
2019-07-29 16:30:16,196 geoprocessing.raster_calculator(372) INFO started stats_worker <Thread(Thread-260, started daemon 13232)>
2019-07-29 16:30:21,204 geoprocessing.<lambda>(442) INFO 71.4% complete
2019-07-29 16:30:24,040 geoprocessing.raster_calculator(445) INFO 100.0%% complete
2019-07-29 16:30:24,040 geoprocessing.raster_calculator(448) INFO signaling stats worker to terminate
2019-07-29 16:30:24,040 geoprocessing.raster_calculator(450) INFO Waiting for raster stats worker result.
2019-07-29 16:30:24,040 threading.run(754) DEBUG payload is None, terminating
2019-07-29 16:30:24,332 model._logged_target(1642) INFO Execution finished
2019-07-29 16:30:24,332 utils.prepare_workspace(115) INFO Elapsed time: 8m 52.22s