Clearer Startup/Request Logs for Long-Running Processes #11
Description
There's no indicator of progress or potential startup time for very long running requests like getting collisions or road usage:
Bottle v0.12.19 server starting up (using WSGIRefServer())...
Listening on http://0.0.0.0:8081/
Hit Ctrl-C to quit.
2021-11-08 07:27:11,186|DEBUG| Cleaning memory: bounding_graph_gdfs_cache
2021-11-08 07:27:11,193|DEBUG| Cleaning memory: utils_bounding_graph_gdfs_cache
2021-11-08 07:27:11,210|DEBUG| Cleaned memory, reduced utils_bounding_graph_gdfs_cache by 0.0% (10111 -> 10111)
2021-11-08 07:27:12,871|INFO| Getting collision GeoJSON
2021-11-08 07:27:14,270|DEBUG| Cleaned memory, reduced bounding_graph_gdfs_cache by 0.19081743716796368% (211653613 -> 212057485)
2021-11-08 07:27:14,579|DEBUG| Process collisions geojson: {'county': 'carlow', 'vehicle_type': 'bicycle'}
2021-11-08 07:27:14,580|DEBUG| Filtering from 68936
2021-11-08 07:27:14,661|DEBUG| Process collisions geojson: {'county': 'cavan', 'vehicle_type': 'bicycle'}
2021-11-08 07:27:14,661|DEBUG| Filtering from 68936
2021-11-08 07:27:14,744|DEBUG| Process collisions geojson: {'county': 'clare', 'vehicle_type': 'bicycle'}
2021-11-08 07:27:14,744|DEBUG| Filtering from 68936
2021-11-08 07:27:14,826|DEBUG| Process collisions geojson: {'county': 'cork', 'vehicle_type': 'bicycle'}
2021-11-08 07:27:14,826|DEBUG| Filtering from 68936
2021-11-08 07:27:14,925|DEBUG| Loading cache /opt/via/cache/network_cache/0.0.34/bbox/-8.92_52.14
2021-11-08 07:27:14,925|DEBUG| Size of network cache: 0.001278MB
2021-11-08 07:27:14,925|DEBUG| Loading cache /opt/via/cache/network_cache/0.0.34/bbox/-8.9_51.61
2021-11-08 07:27:14,926|DEBUG| Size of network cache: 0.001278MB
2021-11-08 07:27:14,926|DEBUG| Loading cache /opt/via/cache/network_cache/0.0.34/bbox/-9.51_52.64
2021-11-08 07:27:14,926|DEBUG| Size of network cache: 0.001278MB
2021-11-08 07:27:14,926|DEBUG| Loading cache /opt/via/cache/network_cache/0.0.34/bbox/-9.42_52.82
2021-11-08 07:27:14,926|DEBUG| Size of network cache: 0.001278MB
2021-11-08 07:27:14,926|DEBUG| Loading cache /opt/via/cache/network_cache/0.0.34/bbox/-6.94_52.83
2021-11-08 07:27:14,926|DEBUG| Size of network cache: 0.001278MB
2021-11-08 07:27:14,926|DEBUG| Loading cache /opt/via/cache/network_cache/0.0.34/bbox/-8.48_51.8
2021-11-08 07:27:14,927|DEBUG| Size of network cache: 0.001278MB
2021-11-08 07:27:16,021|DEBUG| Loading cache /opt/via/cache/network_cache/0.0.34/bbox/-9.57_51.75
2021-11-08 07:27:16,021|DEBUG| Size of network cache: 0.001278MB
2021-11-08 07:27:16,021|DEBUG| Loading cache /opt/via/cache/network_cache/0.0.34/bbox/-9.11_51.94
2021-11-08 07:27:16,021|DEBUG| Size of network cache: 0.001278MB
2021-11-08 07:27:16,022|DEBUG| Loading cache /opt/via/cache/network_cache/0.0.34/bbox/-7.25_53.88
2021-11-08 07:27:16,022|DEBUG| Size of network cache: 0.001278MB
2021-11-08 07:27:16,022|DEBUG| Loading cache /opt/via/cache/network_cache/0.0.34/bbox/-8.28_52.14
2021-11-08 07:27:16,022|DEBUG| Size of network cache: 0.001278MB
2021-11-08 07:27:16,022|DEBUG| Loading cache /opt/via/cache/network_cache/0.0.34/bbox/-9.53_51.62
2021-11-08 07:27:16,023|DEBUG| Size of network cache: 0.001278MB
2021-11-08 07:27:16,023|DEBUG| Loading cache /opt/via/cache/network_cache/0.0.34/bbox/-9.22_52.98
2021-11-08 07:27:16,023|DEBUG| Size of network cache: 0.001278MB
2021-11-08 07:27:16,023|DEBUG| Loading cache /opt/via/cache/network_cache/0.0.34/bbox/-8.8_51.78
2021-11-08 07:27:16,023|DEBUG| Size of network cache: 0.001278MB
2021-11-08 07:27:16,023|DEBUG| Loading cache /opt/via/cache/network_cache/0.0.34/bbox/-7.46_54.01
2021-11-08 07:27:16,024|DEBUG| Size of network cache: 0.001278MB
2021-11-08 07:27:16,024|DEBUG| Loading cache /opt/via/cache/network_cache/0.0.34/bbox/-8.83_51.93
2021-11-08 07:27:16,024|DEBUG| Size of network cache: 0.001278MB
2021-11-08 07:27:16,024|DEBUG| Loading cache /opt/via/cache/network_cache/0.0.34/bbox/-8.8_52.68
2021-11-08 07:27:16,024|DEBUG| Size of network cache: 0.001278MB
2021-11-08 07:27:16,024|DEBUG| Loading cache /opt/via/cache/network_cache/0.0.34/bbox/-8.92_52.7
2021-11-08 07:27:16,025|DEBUG| Size of network cache: 0.001278MB
2021-11-08 07:27:17,800|DEBUG| Missing 6.25% of collisions
2021-11-08 07:27:17,801|DEBUG| Not all collision networks present, need to preload
2021-11-08 07:27:17,809|DEBUG| Graph too large to load, splitting: {'north': 52.35658503808958, 'south': 51.49104336270747, 'east': -7.859655060267838, 'west': -10.056558701878998}
2021-11-08 07:27:17,815|DEBUG| 83b03c60272b63c3184c2dd12f4210a8: Using a larger network rather than generating
2021-11-08 07:27:17,818|DEBUG| Graph too large to load, splitting: {'north': 52.32888334458676, 'south': 51.93083610424762, 'east': -7.882596667296009, 'west': -8.933531686209644}
2021-11-08 07:27:17,819|DEBUG| dba807c6937e7f711b7a76eff30dc9ff: Using a larger network rather than generating
2021-11-08 07:27:17,820|DEBUG| f6a1e2613b6b8a66f089f30c15f117c0: Using a larger network rather than generating
2021-11-08 07:27:17,820|DEBUG| 137728f8622e7a46d3b6b0e897835d3b: Using a larger network rather than generating
2021-11-08 07:27:17,821|DEBUG| 642c2df71ec317771b585002bb67303a: Using a larger network rather than generating
2021-11-08 07:27:17,824|DEBUG| Graph too large to load, splitting: {'north': 51.90480260972643, 'south': 51.54919162987699, 'east': -8.956816483596784, 'west': -9.937913353721903}
2021-11-08 07:27:17,824|DEBUG| 444c8a8c22c212ff8534e63185efb283: Using a larger network rather than generating
2021-11-08 07:27:17,825|DEBUG| b15dc70099f9aa1f346dcfc304de43f9: Using a larger network rather than generating
2021-11-08 07:27:17,826|DEBUG| e1619e85a7099c87f7bea71a1d08d3ba: Using a larger network rather than generating
2021-11-08 07:27:17,833|DEBUG| Graph too large to load, splitting: {'north': 51.92390997707972, 'south': 51.591968335839795, 'east': -8.040191538997508, 'west': -8.933588356123924}
2021-11-08 07:27:17,838|DEBUG| 6c4a72c980f63ef364cccabe55f8f93d: Using a larger network rather than generating
2021-11-08 07:27:17,845|DEBUG| 3de2263aaf6d40b2e198d3998e24ab6c: Using a larger network rather than generating
2021-11-08 07:27:17,847|DEBUG| 95841d471b7746d8554482a960ac84d6: Using a larger network rather than generating
2021-11-08 07:27:18,127|DEBUG| Cleaning memory: bbox
2021-11-08 07:27:44,350|DEBUG| Cleaned memory, reduced bbox by 0.0% (557380058 -> 557380058)
2021-11-08 07:27:44,629|INFO| Loading cache /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.85_51.72.pickle
2021-11-08 07:27:44,630|DEBUG| Creating cache dir: /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.85_51.72.pickle
2021-11-08 07:27:44,630|INFO| Saving cache /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.85_51.72.pickle
2021-11-08 07:27:44,630|DEBUG| Creating cache dir: /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.85_51.72.pickle
2021-11-08 07:27:44,630|DEBUG| Size of /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.85_51.72.pickle cache: 6.4e-05MB
2021-11-08 07:27:44,630|INFO| Saving cache /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.85_51.72.pickle
2021-11-08 07:27:48,136|INFO| Loading cache /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.9_51.61.pickle
2021-11-08 07:27:48,137|DEBUG| Creating cache dir: /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.9_51.61.pickle
2021-11-08 07:27:48,137|INFO| Saving cache /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.9_51.61.pickle
2021-11-08 07:27:48,137|DEBUG| Creating cache dir: /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.9_51.61.pickle
2021-11-08 07:27:48,137|DEBUG| Size of /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.9_51.61.pickle cache: 6.4e-05MB
2021-11-08 07:27:48,137|INFO| Saving cache /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.9_51.61.pickle
2021-11-08 07:27:52,032|INFO| Loading cache /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.49_51.89.pickle
2021-11-08 07:27:52,033|DEBUG| Creating cache dir: /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.49_51.89.pickle
2021-11-08 07:27:52,033|INFO| Saving cache /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.49_51.89.pickle
2021-11-08 07:27:52,033|DEBUG| Creating cache dir: /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.49_51.89.pickle
2021-11-08 07:27:52,033|DEBUG| Size of /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.49_51.89.pickle cache: 6.4e-05MB
2021-11-08 07:27:52,033|INFO| Saving cache /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.49_51.89.pickle
2021-11-08 07:27:52,217|DEBUG| Saving cache /opt/via/cache/edge_cache/0.0.34/cache.json
2021-11-08 07:28:00,415|INFO| Loading cache /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.8_51.78.pickle
2021-11-08 07:28:00,415|DEBUG| Creating cache dir: /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.8_51.78.pickle
2021-11-08 07:28:00,415|INFO| Saving cache /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.8_51.78.pickle
2021-11-08 07:28:00,415|DEBUG| Creating cache dir: /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.8_51.78.pickle
2021-11-08 07:28:00,416|DEBUG| Size of /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.8_51.78.pickle cache: 6.4e-05MB
2021-11-08 07:28:00,416|INFO| Saving cache /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.8_51.78.pickle
2021-11-08 07:28:01,580|INFO| Loading cache /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-9.56_51.76.pickle
2021-11-08 07:28:01,580|DEBUG| Creating cache dir: /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-9.56_51.76.pickle
2021-11-08 07:28:01,580|INFO| Saving cache /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-9.56_51.76.pickle
2021-11-08 07:28:01,580|DEBUG| Creating cache dir: /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-9.56_51.76.pickle
2021-11-08 07:28:01,580|DEBUG| Size of /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-9.56_51.76.pickle cache: 6.4e-05MB
2021-11-08 07:28:01,581|INFO| Saving cache /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-9.56_51.76.pickle
2021-11-08 07:28:01,650|INFO| Loading cache /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-9.57_51.75.pickle
2021-11-08 07:28:01,650|DEBUG| Creating cache dir: /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-9.57_51.75.pickle
2021-11-08 07:28:01,650|INFO| Saving cache /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-9.57_51.75.pickle
2021-11-08 07:28:01,651|DEBUG| Creating cache dir: /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-9.57_51.75.pickle
2021-11-08 07:28:01,651|DEBUG| Size of /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-9.57_51.75.pickle cache: 6.4e-05MB
2021-11-08 07:28:01,651|INFO| Saving cache /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-9.57_51.75.pickle
2021-11-08 07:28:02,245|DEBUG| Saving cache /opt/via/cache/edge_cache/0.0.34/cache.json
2021-11-08 07:28:06,191|INFO| Loading cache /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.47_51.9.pickle
2021-11-08 07:28:06,192|DEBUG| Creating cache dir: /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.47_51.9.pickle
2021-11-08 07:28:06,192|INFO| Saving cache /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.47_51.9.pickle
2021-11-08 07:28:06,192|DEBUG| Creating cache dir: /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.47_51.9.pickle
2021-11-08 07:28:06,192|DEBUG| Size of /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.47_51.9.pickle cache: 6.4e-05MB
2021-11-08 07:28:06,192|INFO| Saving cache /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.47_51.9.pickle
2021-11-08 07:28:21,788|INFO| Loading cache /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.48_51.8.pickle
2021-11-08 07:28:21,788|DEBUG| Creating cache dir: /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.48_51.8.pickle
2021-11-08 07:28:21,788|INFO| Saving cache /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.48_51.8.pickle
2021-11-08 07:28:21,788|DEBUG| Creating cache dir: /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.48_51.8.pickle
2021-11-08 07:28:21,789|DEBUG| Size of /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.48_51.8.pickle cache: 6.4e-05MB
2021-11-08 07:28:21,789|INFO| Saving cache /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.48_51.8.pickle
2021-11-08 07:28:22,272|DEBUG| Saving cache /opt/via/cache/edge_cache/0.0.34/cache.json
2021-11-08 07:28:24,562|ERROR| Could not update edge: 3825199915 7242444302 (utils.py:481)
2021-11-08 07:28:24,619|INFO| Loading cache /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.99_52.12.pickle
2021-11-08 07:28:24,620|DEBUG| Creating cache dir: /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.99_52.12.pickle
2021-11-08 07:28:24,620|INFO| Saving cache /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.99_52.12.pickle
2021-11-08 07:28:24,620|DEBUG| Creating cache dir: /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.99_52.12.pickle
2021-11-08 07:28:24,620|DEBUG| Size of /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.99_52.12.pickle cache: 6.4e-05MB
2021-11-08 07:28:24,620|INFO| Saving cache /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.99_52.12.pickle
2021-11-08 07:28:24,824|INFO| Loading cache /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-9.11_51.94.pickle
2021-11-08 07:28:24,824|DEBUG| Creating cache dir: /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-9.11_51.94.pickle
2021-11-08 07:28:24,824|INFO| Saving cache /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-9.11_51.94.pickle
2021-11-08 07:28:24,824|DEBUG| Creating cache dir: /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-9.11_51.94.pickle
2021-11-08 07:28:24,825|DEBUG| Size of /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-9.11_51.94.pickle cache: 6.4e-05MB
2021-11-08 07:28:24,825|INFO| Saving cache /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-9.11_51.94.pickle
2021-11-08 07:28:24,971|INFO| Loading cache /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-9.46_51.64.pickle
2021-11-08 07:28:24,972|DEBUG| Creating cache dir: /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-9.46_51.64.pickle
2021-11-08 07:28:24,972|INFO| Saving cache /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-9.46_51.64.pickle
2021-11-08 07:28:24,972|DEBUG| Creating cache dir: /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-9.46_51.64.pickle
2021-11-08 07:28:24,972|DEBUG| Size of /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-9.46_51.64.pickle cache: 6.4e-05MB
2021-11-08 07:28:24,972|INFO| Saving cache /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-9.46_51.64.pickle
2021-11-08 07:28:25,341|INFO| Loading cache /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-9.53_51.62.pickle
2021-11-08 07:28:25,341|DEBUG| Creating cache dir: /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-9.53_51.62.pickle
2021-11-08 07:28:25,341|INFO| Saving cache /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-9.53_51.62.pickle
2021-11-08 07:28:25,341|DEBUG| Creating cache dir: /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-9.53_51.62.pickle
2021-11-08 07:28:25,341|DEBUG| Size of /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-9.53_51.62.pickle cache: 6.4e-05MB
2021-11-08 07:28:25,341|INFO| Saving cache /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-9.53_51.62.pickle
2021-11-08 07:28:27,623|INFO| Loading cache /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-9.09_51.59.pickle
2021-11-08 07:28:27,624|DEBUG| Creating cache dir: /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-9.09_51.59.pickle
2021-11-08 07:28:27,624|INFO| Saving cache /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-9.09_51.59.pickle
2021-11-08 07:28:27,624|DEBUG| Creating cache dir: /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-9.09_51.59.pickle
2021-11-08 07:28:27,624|DEBUG| Size of /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-9.09_51.59.pickle cache: 6.4e-05MB
2021-11-08 07:28:27,625|INFO| Saving cache /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-9.09_51.59.pickle
2021-11-08 07:28:32,304|DEBUG| Saving cache /opt/via/cache/edge_cache/0.0.34/cache.json
2021-11-08 07:28:37,152|INFO| Loading cache /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-9.44_51.55.pickle
2021-11-08 07:28:37,153|DEBUG| Creating cache dir: /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-9.44_51.55.pickle
2021-11-08 07:28:37,153|INFO| Saving cache /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-9.44_51.55.pickle
2021-11-08 07:28:37,153|DEBUG| Creating cache dir: /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-9.44_51.55.pickle
2021-11-08 07:28:37,154|DEBUG| Size of /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-9.44_51.55.pickle cache: 6.4e-05MB
2021-11-08 07:28:37,154|INFO| Saving cache /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-9.44_51.55.pickle
2021-11-08 07:28:39,173|INFO| Loading cache /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.65_52.14.pickle
2021-11-08 07:28:39,174|DEBUG| Creating cache dir: /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.65_52.14.pickle
2021-11-08 07:28:39,174|INFO| Saving cache /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.65_52.14.pickle
2021-11-08 07:28:39,174|DEBUG| Creating cache dir: /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.65_52.14.pickle
2021-11-08 07:28:39,174|DEBUG| Size of /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.65_52.14.pickle cache: 6.4e-05MB
2021-11-08 07:28:39,175|INFO| Saving cache /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.65_52.14.pickle
2021-11-08 07:28:39,871|INFO| Loading cache /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.92_52.14.pickle
2021-11-08 07:28:39,871|DEBUG| Creating cache dir: /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.92_52.14.pickle
2021-11-08 07:28:39,871|INFO| Saving cache /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.92_52.14.pickle
2021-11-08 07:28:39,871|DEBUG| Creating cache dir: /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.92_52.14.pickle
2021-11-08 07:28:39,872|DEBUG| Size of /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.92_52.14.pickle cache: 6.4e-05MB
2021-11-08 07:28:39,872|INFO| Saving cache /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.92_52.14.pickle
2021-11-08 07:28:40,089|INFO| Loading cache /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.28_52.25.pickle
2021-11-08 07:28:40,090|DEBUG| Creating cache dir: /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.28_52.25.pickle
2021-11-08 07:28:40,090|INFO| Saving cache /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.28_52.25.pickle
2021-11-08 07:28:40,090|DEBUG| Creating cache dir: /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.28_52.25.pickle
2021-11-08 07:28:40,090|DEBUG| Size of /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.28_52.25.pickle cache: 6.4e-05MB
2021-11-08 07:28:40,090|INFO| Saving cache /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.28_52.25.pickle
2021-11-08 07:28:40,860|INFO| Loading cache /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.28_52.14.pickle
2021-11-08 07:28:40,860|DEBUG| Creating cache dir: /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.28_52.14.pickle
2021-11-08 07:28:40,860|INFO| Saving cache /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.28_52.14.pickle
2021-11-08 07:28:40,860|DEBUG| Creating cache dir: /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.28_52.14.pickle
2021-11-08 07:28:40,861|DEBUG| Size of /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.28_52.14.pickle cache: 6.4e-05MB
2021-11-08 07:28:40,861|INFO| Saving cache /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.28_52.14.pickle
2021-11-08 07:28:41,149|INFO| Loading cache /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.66_52.11.pickle
2021-11-08 07:28:41,149|DEBUG| Creating cache dir: /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.66_52.11.pickle
2021-11-08 07:28:41,149|INFO| Saving cache /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.66_52.11.pickle
2021-11-08 07:28:41,150|DEBUG| Creating cache dir: /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.66_52.11.pickle
2021-11-08 07:28:41,150|DEBUG| Size of /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.66_52.11.pickle cache: 6.4e-05MB
2021-11-08 07:28:41,150|INFO| Saving cache /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.66_52.11.pickle
2021-11-08 07:28:41,510|INFO| Loading cache /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.83_51.93.pickle
2021-11-08 07:28:41,510|DEBUG| Creating cache dir: /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.83_51.93.pickle
2021-11-08 07:28:41,510|INFO| Saving cache /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.83_51.93.pickle
2021-11-08 07:28:41,510|DEBUG| Creating cache dir: /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.83_51.93.pickle
2021-11-08 07:28:41,511|DEBUG| Size of /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.83_51.93.pickle cache: 6.4e-05MB
2021-11-08 07:28:41,511|INFO| Saving cache /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.83_51.93.pickle
2021-11-08 07:28:42,819|DEBUG| Saving cache /opt/via/cache/edge_cache/0.0.34/cache.json
2021-11-08 07:28:42,895|INFO| Loading cache /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.34_52.02.pickle
2021-11-08 07:28:42,895|DEBUG| Creating cache dir: /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.34_52.02.pickle
2021-11-08 07:28:42,896|INFO| Saving cache /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.34_52.02.pickle
2021-11-08 07:28:42,896|DEBUG| Creating cache dir: /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.34_52.02.pickle
2021-11-08 07:28:42,896|DEBUG| Size of /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.34_52.02.pickle cache: 6.4e-05MB
2021-11-08 07:28:42,897|INFO| Saving cache /opt/via/cache/utils_bounding_graph_gdfs_cache/0.0.34/-8.34_52.02.pickle
2021-11-08 07:28:43,744|INFO| Loading cache /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.34_51.96.pickle
2021-11-08 07:28:43,745|DEBUG| Creating cache dir: /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.34_51.96.pickle
2021-11-08 07:28:43,745|INFO| Saving cache /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.34_51.96.pickle
2021-11-08 07:28:43,745|DEBUG| Creating cache dir: /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.34_51.96.pickle
2021-11-08 07:28:43,745|DEBUG| Size of /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.34_51.96.pickle cache: 6.4e-05MB
2021-11-08 07:28:43,745|INFO| Saving cache /opt/via/cache/bounding_graph_gdfs_cache/0.0.34/-8.34_51.96.pickle
2021-11-08 07:28:43,876|DEBUG| Process collisions geojson: {'county': 'donegal', 'vehicle_type': 'bicycle'}
2021-11-08 07:28:43,877|DEBUG| Filtering from 68936
2021-11-08 07:28:43,962|DEBUG| Not all collision networks present, need to preload
2021-11-08 07:28:43,963|DEBUG| Graph too large to load, splitting: {'north': 55.33782002817492, 'south': 54.46628997331396, 'east': -7.130829925916827, 'west': -8.570970063801855}
2021-11-08 07:28:43,965|DEBUG| No split necessary in loading graph: {'north': 55.047931698944666, 'south': 54.984993329439106, 'east': -7.889615074661523, 'west': -8.289393354844638}
2021-11-08 07:28:46,482|DEBUG| Loading cache /opt/via/cache/network_cache/0.0.34/bbox/-8.29_54.99
2021-11-08 07:28:46,483|DEBUG| Saving cache /opt/via/cache/network_cache/0.0.34/bbox/-8.29_54.99
2021-11-08 07:28:46,483|DEBUG| Size of network cache: 6.4e-05MB
2021-11-08 07:28:46,483|DEBUG| Saving cache /opt/via/cache/network_cache/0.0.34/bbox/-8.29_54.99
2021-11-08 07:28:46,495|DEBUG| Saving cache /opt/via/cache/network_cache/0.0.34/bbox/-8.29_54.99
2021-11-08 07:28:46,498|DEBUG| Graph too large to load, splitting: {'north': 55.29344337543216, 'south': 54.93024163708898, 'east': -7.30803109007215, 'west': -7.805118385732386}
2021-11-08 07:28:46,499|DEBUG| No split necessary in loading graph: {'north': 55.231803365748306, 'south': 55.12538502242572, 'east': -7.343299980466534, 'west': -7.456248259506901}
2021-11-08 07:28:48,888|DEBUG| Loading cache /opt/via/cache/network_cache/0.0.34/bbox/-7.46_55.13
2021-11-08 07:28:48,888|DEBUG| Saving cache /opt/via/cache/network_cache/0.0.34/bbox/-7.46_55.13
2021-11-08 07:28:48,888|DEBUG| Size of network cache: 6.4e-05MB
2021-11-08 07:28:48,889|DEBUG| Saving cache /opt/via/cache/network_cache/0.0.34/bbox/-7.46_55.13
2021-11-08 07:28:48,909|DEBUG| Saving cache /opt/via/cache/network_cache/0.0.34/bbox/-7.46_55.13
2021-11-08 07:28:48,913|DEBUG| No split necessary in loading graph: {'north': 55.033641571264695, 'south': 54.9433846254374, 'east': -7.681801694970624, 'west': -7.775819016231037}
2021-11-08 07:28:53,735|DEBUG| Saving cache /opt/via/cache/edge_cache/0.0.34/cache.json
2021-11-08 07:28:56,460|DEBUG| Loading cache /opt/via/cache/network_cache/0.0.34/bbox/-7.78_54.94
2021-11-08 07:28:56,460|DEBUG| Saving cache /opt/via/cache/network_cache/0.0.34/bbox/-7.78_54.94
2021-11-08 07:28:56,460|DEBUG| Size of network cache: 6.4e-05MB
2021-11-08 07:28:56,462|DEBUG| Saving cache /opt/via/cache/network_cache/0.0.34/bbox/-7.78_54.94
2021-11-08 07:28:56,638|DEBUG| Saving cache /opt/via/cache/network_cache/0.0.34/bbox/-7.78_54.94
2021-11-08 07:28:56,640|DEBUG| No split necessary in loading graph: {'north': 55.06406941921861, 'south': 54.95397164775477, 'east': -7.316539658395471, 'west': -7.410108271063607}
2021-11-08 07:29:04,712|DEBUG| Loading cache /opt/via/cache/network_cache/0.0.34/bbox/-7.41_54.95
2021-11-08 07:29:04,712|DEBUG| Saving cache /opt/via/cache/network_cache/0.0.34/bbox/-7.41_54.95
2021-11-08 07:29:04,712|DEBUG| Size of network cache: 6.4e-05MB
2021-11-08 07:29:04,713|DEBUG| Saving cache /opt/via/cache/network_cache/0.0.34/bbox/-7.41_54.95
2021-11-08 07:29:04,863|DEBUG| Saving cache /opt/via/cache/network_cache/0.0.34/bbox/-7.41_54.95
2021-11-08 07:29:04,867|DEBUG| Graph too large to load, splitting: {'north': 54.830551923857, 'south': 54.474256570467105, 'east': -8.08217470988112, 'west': -8.44836829380953}
2021-11-08 07:29:04,869|DEBUG| No split necessary in loading graph: {'north': 54.63932731241769, 'south': 54.630416696626014, 'east': -8.355219953931657, 'west': -8.443520069188402}
2021-11-08 07:29:06,471|DEBUG| Loading cache /opt/via/cache/network_cache/0.0.34/bbox/-8.44_54.64
2021-11-08 07:29:06,471|DEBUG| Saving cache /opt/via/cache/network_cache/0.0.34/bbox/-8.44_54.64
2021-11-08 07:29:06,471|DEBUG| Size of network cache: 6.4e-05MB
2021-11-08 07:29:06,472|DEBUG| Saving cache /opt/via/cache/network_cache/0.0.34/bbox/-8.44_54.64
2021-11-08 07:29:06,474|DEBUG| Saving cache /opt/via/cache/network_cache/0.0.34/bbox/-8.44_54.64
2021-11-08 07:29:06,476|DEBUG| No split necessary in loading graph: {'north': 54.65274332507752, 'south': 54.48423996009379, 'east': -8.111200000766162, 'west': -8.258763362754513}
2021-11-08 07:29:11,039|DEBUG| Loading cache /opt/via/cache/network_cache/0.0.34/bbox/-8.26_54.48
2021-11-08 07:29:11,039|DEBUG| Saving cache /opt/via/cache/network_cache/0.0.34/bbox/-8.26_54.48
2021-11-08 07:29:11,040|DEBUG| Size of network cache: 6.4e-05MB
2021-11-08 07:29:11,040|DEBUG| Saving cache /opt/via/cache/network_cache/0.0.34/bbox/-8.26_54.48
2021-11-08 07:29:11,082|DEBUG| Saving cache /opt/via/cache/network_cache/0.0.34/bbox/-8.26_54.48
2021-11-08 07:29:11,085|DEBUG| No split necessary in loading graph: {'north': 54.87238336849285, 'south': 54.79426499089533, 'east': -7.483720100256736, 'west': -7.768064943651572}
2021-11-08 07:29:11,226|DEBUG| Cleaning memory: utils_bounding_graph_gdfs_cache
2021-11-08 07:29:14,859|DEBUG| Cleaning memory: bounding_graph_gdfs_cache
2021-11-08 07:29:22,544|DEBUG| Loading cache /opt/via/cache/network_cache/0.0.34/bbox/-7.77_54.79
2021-11-08 07:29:22,545|DEBUG| Saving cache /opt/via/cache/network_cache/0.0.34/bbox/-7.77_54.79
2021-11-08 07:29:22,545|DEBUG| Size of network cache: 6.4e-05MB
2021-11-08 07:29:22,545|DEBUG| Saving cache /opt/via/cache/network_cache/0.0.34/bbox/-7.77_54.79
2021-11-08 07:29:22,585|DEBUG| Saving cache /opt/via/cache/network_cache/0.0.34/bbox/-7.77_54.79
2021-11-08 07:29:44,351|DEBUG| Cleaning memory: bbox
2021-11-08 07:30:08,847|DEBUG| Cleaned memory, reduced bbox by 0.0% (584825656 -> 584825656)
2021-11-08 07:32:08,847|DEBUG| Cleaning memory: bbox
2021-11-08 07:32:21,463|DEBUG| Cleaned memory, reduced bbox by -99.9904734001615% (584825656 -> 55714)
2021-11-08 07:34:21,463|DEBUG| Cleaning memory: bbox
2021-11-08 07:34:21,470|DEBUG| Cleaned memory, reduced bbox by 0.0% (55714 -> 55714)
2021-11-08 07:36:21,471|DEBUG| Cleaning memory: bbox
2021-11-08 07:36:21,474|DEBUG| Cleaned memory, reduced bbox by 0.0% (55714 -> 55714)
It seems like it's progressing by doing a ctrl + f for county, but it would be real nice to have a message logged now and again indicating what % of the way towards startup it is or similar.