Error computing large matrices despite high maximum_routes

openrouteservice
1

I access our ORS installation via a Python script. With 144 input points, ORS produces the distance and time matrices fine. With 1,100 points it produces an error. I increased maximum_routes to 10,000,000 because it told me 1,000,000 was not enough. After reading through some forum posts, I checked that I’m using long/lat order and tried increasing maximum_search_radius a few times (all the way to 500,000!) but that did not help. On a whim, I tried also increasing maximum_routes_flexible to 1,000, but that did not resolve the error either. The terminal display and log are pasted below. I would appreciate any guidance about how to compute very large matrices with ORS. Thank you!

-david

TERMINAL

Traceback (most recent call last):
...
  File "C:\Programs\Python\Python38\lib\site-packages\openrouteservice\client.py", line 242, in _get_body
    raise exceptions.ApiError(
openrouteservice.exceptions.ApiError: 404 ({'error': {'code': 6010, 'message': 'Source point(s) [1000, ... 1178, 1179] out of bounds: 34.787792,32.079787; 34.774173,32.08703; ...; 34.783271,32.093619. Destination point(s) [1000,... 1178, 1179] out of bounds: 34.787792,32.079787; ...; 34.778994,32.088678; 34.783271,32.093619'}, 'info': {'engine': {'version': '6.4.3', 'build_date': '2021-08-02T15:28:45Z'}, 'timestamp': 1638212759295}})

LOG

…

2021-11-29 14:24:46,567 INFO [core.PrepareCore] - 0, updates:0, nodes: 427 052, shortcuts:0, dijkstras:1 628 032, t(dijk):0.74, t(period):0.0, t(lazy):0.0, t(neighbor):0.0, meanDegree:1, algo:6MB, totalMB:1646, usedMB:856

2021-11-29 14:25:05,424 INFO [core.PrepareCore] - 85 410, updates:2, nodes: 341 642, shortcuts:1 128, dijkstras:4 526 671, t(dijk):18.37, t(period):16.95, t(lazy):0.0, t(neighbor):1.49, meanDegree:1, algo:6MB, totalMB:1646, usedMB:911

2021-11-29 14:25:20,768 INFO [core.PrepareCore] - 170 820, updates:4, nodes: 256 232, shortcuts:33 706, dijkstras:6 851 279, t(dijk):32.65, t(period):30.03, t(lazy):0.0, t(neighbor):3.25, meanDegree:1, algo:6MB, totalMB:1646, usedMB:961

2021-11-29 14:25:37,810 INFO [core.PrepareCore] - 256 230, updates:6, nodes: 170 822, shortcuts:145 940, dijkstras:9 367 898, t(dijk):48.44, t(period):40.1, t(lazy):0.0, t(neighbor):8.64, meanDegree:1, algo:6MB, totalMB:1646, usedMB:1025

2021-11-29 14:25:53,042 INFO [core.PrepareCore] - 341 640, updates:8, nodes: 85 412, shortcuts:238 172, dijkstras:11 388 973, t(dijk):62.61, t(period):46.82, t(lazy):0.0, t(neighbor):15.29, meanDegree:2, algo:6MB, totalMB:1646, usedMB:1078

2021-11-29 14:26:13,227 INFO [core.PrepareCore] - took:88, new shortcuts: 371 535, prepare|fastest|car-ors, dijkstras:14342721, t(dijk):81.53, t(period):48.13, t(lazy):1.93, t(neighbor):29.4, meanDegree:4, initSize:427052, periodic:10, lazy:10, neighbor:90, totalMB:1646, usedMB:1134

2021-11-29 14:26:14,882 INFO [core.PrepareCore] - 0, updates:0, nodes: 427 052, shortcuts:0, dijkstras:1 628 032, t(dijk):0.74, t(period):0.0, t(lazy):0.0, t(neighbor):0.0, meanDegree:1, algo:6MB, totalMB:1646, usedMB:1172

2021-11-29 14:26:30,325 INFO [core.PrepareCore] - 85 410, updates:2, nodes: 341 642, shortcuts:704, dijkstras:4 530 747, t(dijk):15.05, t(period):14.09, t(lazy):0.0, t(neighbor):1.15, meanDegree:1, algo:6MB, totalMB:1646, usedMB:1225

2021-11-29 14:26:43,613 INFO [core.PrepareCore] - 170 820, updates:4, nodes: 256 232, shortcuts:42 779, dijkstras:6 966 042, t(dijk):27.29, t(period):25.37, t(lazy):0.0, t(neighbor):2.75, meanDegree:1, algo:6MB, totalMB:1646, usedMB:1277

2021-11-29 14:27:00,104 INFO [core.PrepareCore] - 256 230, updates:6, nodes: 170 822, shortcuts:157 699, dijkstras:9 666 699, t(dijk):42.49, t(period):35.17, t(lazy):0.0, t(neighbor):8.14, meanDegree:1, algo:6MB, totalMB:1646, usedMB:1342

2021-11-29 14:27:15,765 INFO [core.PrepareCore] - 341 640, updates:8, nodes: 85 412, shortcuts:272 175, dijkstras:12 235 537, t(dijk):56.9, t(period):41.65, t(lazy):0.0, t(neighbor):15.6, meanDegree:2, algo:6MB, totalMB:1646, usedMB:798

2021-11-29 14:27:45,810 INFO [core.PrepareCore] - took:92, new shortcuts: 453 666, prepare|shortest|car-ors, dijkstras:18413280, t(dijk):84.94, t(period):43.19, t(lazy):2.51, t(neighbor):38.54, meanDegree:8, initSize:427052, periodic:10, lazy:10, neighbor:90, totalMB:1646, usedMB:635

2021-11-29 14:28:41,794 INFO [routing.RoutingProfile] - [2] FlagEncoders: 1, bits used [UNKNOWN]/64.

2021-11-29 14:28:41,796 INFO [routing.RoutingProfile] - [2] Capacity: [UNKNOWN]. (edges - 570738, nodes - 427052)

2021-11-29 14:28:41,799 INFO [routing.RoutingProfile] - [2] Total time: 290.471s.

2021-11-29 14:28:41,801 INFO [routing.RoutingProfile] - [2] Finished at: 2021-11-29 14:28:41.

2021-11-29 14:28:41,803 INFO [routing.RoutingProfile] -

2021-11-29 14:28:41,819 INFO [routing.RoutingProfileManager] - Total time: 385.651s.

2021-11-29 14:28:41,821 INFO [routing.RoutingProfileManager] - ========================================================================

2021-11-29 14:28:41,828 INFO [routing.RoutingProfileManager] - ====> Recycling garbage…

2021-11-29 14:28:41,831 INFO [routing.RoutingProfileManager] - Before: Total - 1.61 GB, Free - 974.43 MB, Max: 2 GB, Used - 671.57 MB

2021-11-29 14:28:41,928 INFO [routing.RoutingProfileManager] - After: Total - 1.61 GB, Free - 1.01 GB, Max: 2 GB, Used - 614.42 MB

2021-11-29 14:28:41,930 INFO [routing.RoutingProfileManager] - ========================================================================

2021-11-29 14:28:41,931 INFO [routing.RoutingProfileManager] - ====> Memory usage by profiles:

2021-11-29 14:28:41,964 INFO [routing.RoutingProfileManager] - [1] 77.03 MB (12.5%)

2021-11-29 14:28:41,966 INFO [routing.RoutingProfileManager] - [2] 125.03 MB (20.3%)

2021-11-29 14:28:41,966 INFO [routing.RoutingProfileManager] - Total: 202.06 MB (32.9%)

2021-11-29 14:28:41,967 INFO [routing.RoutingProfileManager] - ========================================================================

2021-11-29 15:31:24,878 INFO [ors.Application] - Starting Application v6.4.3 on b900b8a9a0b8 with PID 10 (/usr/local/tomcat/webapps/ors/WEB-INF/classes started by root in /ors-core)

2021-11-29 15:31:24,883 DEBUG [ors.Application] - Running with Spring Boot v2.3.5.RELEASE, Spring v5.2.10.RELEASE

2021-11-29 15:31:24,884 INFO [ors.Application] - No active profile set, falling back to default profiles: default

2021-11-29 15:31:27,453 INFO [ors.Application] - Started Application in 3.503 seconds (JVM running for 7.046)

2021-11-29 15:31:27,579 INFO [routing.RoutingProfileManager] - Total - 1.00 GB, Free - 811.26 MB, Max: 2 GB, Used - 214.74 MB

2021-11-29 15:31:27,582 INFO [routing.RoutingProfileManager] -

2021-11-29 15:31:27,631 INFO [routing.RoutingProfileManager] - ====> Initializing profiles from ‘data/osm_file.pbf’ (1 threads) …

2021-11-29 15:31:27,631 INFO [routing.RoutingProfileManager] -

2021-11-29 15:31:27,634 INFO [routing.RoutingProfileManager] -

2021-11-29 15:31:27,661 INFO [routing.RoutingProfile] - [1] Profiles: ‘foot-walking’, location: ‘data/graphs/walking’.

2021-11-29 15:32:48,210 INFO [routing.RoutingProfile] - [1] FlagEncoders: 1, bits used [UNKNOWN]/64.

2021-11-29 15:32:48,212 INFO [routing.RoutingProfile] - [1] Capacity: [UNKNOWN]. (edges - 746015, nodes - 547120)

2021-11-29 15:32:48,215 INFO [routing.RoutingProfile] - [1] Total time: 80.554s.

2021-11-29 15:32:48,219 INFO [routing.RoutingProfile] - [1] Finished at: 2021-11-29 15:32:48.

2021-11-29 15:32:48,222 INFO [routing.RoutingProfile] -

2021-11-29 15:32:48,244 INFO [routing.RoutingProfile] - [2] Profiles: ‘driving-car’, location: ‘data/graphs/car’.

2021-11-29 15:33:42,651 INFO [core.PrepareCore] - 0, updates:0, nodes: 427 052, shortcuts:0, dijkstras:1 628 032, t(dijk):0.72, t(period):0.0, t(lazy):0.0, t(neighbor):0.0, meanDegree:1, algo:6MB, totalMB:1732, usedMB:898

2021-11-29 15:34:00,823 INFO [core.PrepareCore] - 85 410, updates:2, nodes: 341 642, shortcuts:1 128, dijkstras:4 526 671, t(dijk):17.69, t(period):16.31, t(lazy):0.0, t(neighbor):1.46, meanDegree:1, algo:6MB, totalMB:1732, usedMB:953

2021-11-29 15:34:15,937 INFO [core.PrepareCore] - 170 820, updates:4, nodes: 256 232, shortcuts:33 706, dijkstras:6 851 279, t(dijk):31.74, t(period):29.18, t(lazy):0.0, t(neighbor):3.21, meanDegree:1, algo:6MB, totalMB:1732, usedMB:1003

2021-11-29 15:34:32,522 INFO [core.PrepareCore] - 256 230, updates:6, nodes: 170 822, shortcuts:145 940, dijkstras:9 367 898, t(dijk):47.07, t(period):38.96, t(lazy):0.0, t(neighbor):8.48, meanDegree:1, algo:6MB, totalMB:1732, usedMB:1067

2021-11-29 15:34:47,267 INFO [core.PrepareCore] - 341 640, updates:8, nodes: 85 412, shortcuts:238 172, dijkstras:11 388 973, t(dijk):60.78, t(period):45.43, t(lazy):0.0, t(neighbor):14.95, meanDegree:2, algo:6MB, totalMB:1732, usedMB:1119

2021-11-29 15:35:06,551 INFO [core.PrepareCore] - took:85, new shortcuts: 371 535, prepare|fastest|car-ors, dijkstras:14342721, t(dijk):78.86, t(period):46.59, t(lazy):1.82, t(neighbor):28.5, meanDegree:4, initSize:427052, periodic:10, lazy:10, neighbor:90, totalMB:1732, usedMB:1175

2021-11-29 15:35:08,067 INFO [core.PrepareCore] - 0, updates:0, nodes: 427 052, shortcuts:0, dijkstras:1 628 032, t(dijk):0.67, t(period):0.0, t(lazy):0.0, t(neighbor):0.0, meanDegree:1, algo:6MB, totalMB:1732, usedMB:1213

2021-11-29 15:35:22,013 INFO [core.PrepareCore] - 85 410, updates:2, nodes: 341 642, shortcuts:704, dijkstras:4 530 747, t(dijk):13.57, t(period):12.74, t(lazy):0.0, t(neighbor):1.02, meanDegree:1, algo:6MB, totalMB:1732, usedMB:1265

2021-11-29 15:35:34,373 INFO [core.PrepareCore] - 170 820, updates:4, nodes: 256 232, shortcuts:42 779, dijkstras:6 966 042, t(dijk):24.94, t(period):23.18, t(lazy):0.0, t(neighbor):2.56, meanDegree:1, algo:6MB, totalMB:1732, usedMB:1317

2021-11-29 15:35:49,872 INFO [core.PrepareCore] - 256 230, updates:6, nodes: 170 822, shortcuts:157 699, dijkstras:9 666 699, t(dijk):39.21, t(period):32.52, t(lazy):0.0, t(neighbor):7.52, meanDegree:1, algo:6MB, totalMB:1732, usedMB:1382

2021-11-29 15:36:04,594 INFO [core.PrepareCore] - 341 640, updates:8, nodes: 85 412, shortcuts:272 175, dijkstras:12 235 537, t(dijk):52.74, t(period):38.63, t(lazy):0.0, t(neighbor):14.48, meanDegree:2, algo:6MB, totalMB:1732, usedMB:728

2021-11-29 15:36:32,216 INFO [core.PrepareCore] - took:85, new shortcuts: 453 666, prepare|shortest|car-ors, dijkstras:18413280, t(dijk):78.57, t(period):40.09, t(lazy):2.23, t(neighbor):35.63, meanDegree:8, initSize:427052, periodic:10, lazy:10, neighbor:90, totalMB:1732, usedMB:796

2021-11-29 15:37:22,740 INFO [routing.RoutingProfile] - [2] FlagEncoders: 1, bits used [UNKNOWN]/64.

2021-11-29 15:37:22,742 INFO [routing.RoutingProfile] - [2] Capacity: [UNKNOWN]. (edges - 570738, nodes - 427052)

2021-11-29 15:37:22,751 INFO [routing.RoutingProfile] - [2] Total time: 274.507s.

2021-11-29 15:37:22,754 INFO [routing.RoutingProfile] - [2] Finished at: 2021-11-29 15:37:22.

2021-11-29 15:37:22,755 INFO [routing.RoutingProfile] -

2021-11-29 15:37:22,776 INFO [routing.RoutingProfileManager] - Total time: 355.193s.

2021-11-29 15:37:22,777 INFO [routing.RoutingProfileManager] - ========================================================================

2021-11-29 15:37:22,785 INFO [routing.RoutingProfileManager] - ====> Recycling garbage…

2021-11-29 15:37:22,788 INFO [routing.RoutingProfileManager] - Before: Total - 1.69 GB, Free - 1.04 GB, Max: 2 GB, Used - 666.27 MB

2021-11-29 15:37:22,876 INFO [routing.RoutingProfileManager] - After: Total - 1.69 GB, Free - 1.09 GB, Max: 2 GB, Used - 613.57 MB

2021-11-29 15:37:22,877 INFO [routing.RoutingProfileManager] - ========================================================================

2021-11-29 15:37:22,878 INFO [routing.RoutingProfileManager] - ====> Memory usage by profiles:

2021-11-29 15:37:22,910 INFO [routing.RoutingProfileManager] - [1] 77.03 MB (12.6%)

2021-11-29 15:37:22,911 INFO [routing.RoutingProfileManager] - [2] 125.03 MB (20.4%)

2021-11-29 15:37:22,912 INFO [routing.RoutingProfileManager] - Total: 202.06 MB (32.9%)

2021-11-29 15:37:22,912 INFO [routing.RoutingProfileManager] - ========================================================================

2021-11-29 17:48:35,398 INFO [ors.Application] - Starting Application v6.4.3 on b900b8a9a0b8 with PID 9 (/usr/local/tomcat/webapps/ors/WEB-INF/classes started by root in /ors-core)

2021-11-29 17:48:35,403 DEBUG [ors.Application] - Running with Spring Boot v2.3.5.RELEASE, Spring v5.2.10.RELEASE

2021-11-29 17:48:35,405 INFO [ors.Application] - No active profile set, falling back to default profiles: default

2021-11-29 17:48:38,038 INFO [ors.Application] - Started Application in 3.694 seconds (JVM running for 7.349)

2021-11-29 17:48:38,157 INFO [routing.RoutingProfileManager] - Total - 1.00 GB, Free - 809.64 MB, Max: 2 GB, Used - 216.36 MB

2021-11-29 17:48:38,159 INFO [routing.RoutingProfileManager] -

2021-11-29 17:48:38,203 INFO [routing.RoutingProfileManager] - ====> Initializing profiles from ‘data/osm_file.pbf’ (1 threads) …

2021-11-29 17:48:38,204 INFO [routing.RoutingProfileManager] -

2021-11-29 17:48:38,207 INFO [routing.RoutingProfileManager] -

2021-11-29 17:48:38,235 INFO [routing.RoutingProfile] - [1] Profiles: ‘foot-walking’, location: ‘data/graphs/walking’.

2021-11-29 17:50:04,853 INFO [routing.RoutingProfile] - [1] FlagEncoders: 1, bits used [UNKNOWN]/64.

2021-11-29 17:50:04,855 INFO [routing.RoutingProfile] - [1] Capacity: [UNKNOWN]. (edges - 746015, nodes - 547120)

2021-11-29 17:50:04,860 INFO [routing.RoutingProfile] - [1] Total time: 86.624s.

2021-11-29 17:50:04,863 INFO [routing.RoutingProfile] - [1] Finished at: 2021-11-29 17:50:04.

2021-11-29 17:50:04,866 INFO [routing.RoutingProfile] -

2021-11-29 17:50:04,888 INFO [routing.RoutingProfile] - [2] Profiles: ‘driving-car’, location: ‘data/graphs/car’.

2021-11-29 17:51:03,686 INFO [core.PrepareCore] - 0, updates:0, nodes: 427 052, shortcuts:0, dijkstras:1 628 032, t(dijk):0.79, t(period):0.0, t(lazy):0.0, t(neighbor):0.0, meanDegree:1, algo:6MB, totalMB:1655, usedMB:1257

2021-11-29 17:51:23,509 INFO [core.PrepareCore] - 85 410, updates:2, nodes: 341 642, shortcuts:1 128, dijkstras:4 526 671, t(dijk):19.25, t(period):17.7, t(lazy):0.0, t(neighbor):1.68, meanDegree:1, algo:6MB, totalMB:1655, usedMB:1312

2021-11-29 17:51:39,723 INFO [core.PrepareCore] - 170 820, updates:4, nodes: 256 232, shortcuts:33 706, dijkstras:6 851 279, t(dijk):34.29, t(period):31.49, t(lazy):0.0, t(neighbor):3.56, meanDegree:1, algo:6MB, totalMB:1655, usedMB:1362

2021-11-29 17:51:57,591 INFO [core.PrepareCore] - 256 230, updates:6, nodes: 170 822, shortcuts:145 940, dijkstras:9 367 898, t(dijk):50.79, t(period):42.03, t(lazy):0.0, t(neighbor):9.24, meanDegree:1, algo:6MB, totalMB:1655, usedMB:1426

2021-11-29 17:52:13,560 INFO [core.PrepareCore] - 341 640, updates:8, nodes: 85 412, shortcuts:238 172, dijkstras:11 388 973, t(dijk):65.58, t(period):49.05, t(lazy):0.0, t(neighbor):16.24, meanDegree:2, algo:6MB, totalMB:1655, usedMB:1080

2021-11-29 17:52:33,928 INFO [core.PrepareCore] - took:92, new shortcuts: 371 535, prepare|fastest|car-ors, dijkstras:14342721, t(dijk):84.65, t(period):50.32, t(lazy):1.91, t(neighbor):30.53, meanDegree:4, initSize:427052, periodic:10, lazy:10, neighbor:90, totalMB:1655, usedMB:1135

2021-11-29 17:52:35,580 INFO [core.PrepareCore] - 0, updates:0, nodes: 427 052, shortcuts:0, dijkstras:1 628 032, t(dijk):0.73, t(period):0.0, t(lazy):0.0, t(neighbor):0.0, meanDegree:1, algo:6MB, totalMB:1655, usedMB:1174

2021-11-29 17:52:50,193 INFO [core.PrepareCore] - 85 410, updates:2, nodes: 341 642, shortcuts:704, dijkstras:4 530 747, t(dijk):14.23, t(period):13.34, t(lazy):0.0, t(neighbor):1.08, meanDegree:1, algo:6MB, totalMB:1655, usedMB:1226

2021-11-29 17:53:03,132 INFO [core.PrepareCore] - 170 820, updates:4, nodes: 256 232, shortcuts:42 779, dijkstras:6 966 042, t(dijk):26.1, t(period):24.29, t(lazy):0.0, t(neighbor):2.67, meanDegree:1, algo:6MB, totalMB:1655, usedMB:1278

2021-11-29 17:53:19,242 INFO [core.PrepareCore] - 256 230, updates:6, nodes: 170 822, shortcuts:157 699, dijkstras:9 666 699, t(dijk):40.91, t(period):33.9, t(lazy):0.0, t(neighbor):7.91, meanDegree:1, algo:6MB, totalMB:1655, usedMB:1343

2021-11-29 17:53:34,570 INFO [core.PrepareCore] - 341 640, updates:8, nodes: 85 412, shortcuts:272 175, dijkstras:12 235 537, t(dijk):55.01, t(period):40.28, t(lazy):0.0, t(neighbor):15.16, meanDegree:2, algo:6MB, totalMB:1655, usedMB:1405

2021-11-29 17:54:03,150 INFO [core.PrepareCore] - took:89, new shortcuts: 453 666, prepare|shortest|car-ors, dijkstras:18413280, t(dijk):81.72, t(period):41.82, t(lazy):2.37, t(neighbor):36.98, meanDegree:8, initSize:427052, periodic:10, lazy:10, neighbor:90, totalMB:1655, usedMB:1472

2021-11-29 17:54:56,335 INFO [routing.RoutingProfile] - [2] FlagEncoders: 1, bits used [UNKNOWN]/64.

2021-11-29 17:54:56,338 INFO [routing.RoutingProfile] - [2] Capacity: [UNKNOWN]. (edges - 570738, nodes - 427052)

2021-11-29 17:54:56,339 INFO [routing.RoutingProfile] - [2] Total time: 291.452s.

2021-11-29 17:54:56,344 INFO [routing.RoutingProfile] - [2] Finished at: 2021-11-29 17:54:56.

2021-11-29 17:54:56,350 INFO [routing.RoutingProfile] -

2021-11-29 17:54:56,366 INFO [routing.RoutingProfileManager] - Total time: 378.205s.

2021-11-29 17:54:56,370 INFO [routing.RoutingProfileManager] - ========================================================================

2021-11-29 17:54:56,379 INFO [routing.RoutingProfileManager] - ====> Recycling garbage…

2021-11-29 17:54:56,380 INFO [routing.RoutingProfileManager] - Before: Total - 1.62 GB, Free - 861.09 MB, Max: 2 GB, Used - 793.91 MB

2021-11-29 17:54:56,461 INFO [routing.RoutingProfileManager] - After: Total - 1.62 GB, Free - 1.02 GB, Max: 2 GB, Used - 613.14 MB

2021-11-29 17:54:56,463 INFO [routing.RoutingProfileManager] - ========================================================================

2021-11-29 17:54:56,464 INFO [routing.RoutingProfileManager] - ====> Memory usage by profiles:

2021-11-29 17:54:56,480 INFO [routing.RoutingProfileManager] - [1] 77.03 MB (12.6%)

2021-11-29 17:54:56,481 INFO [routing.RoutingProfileManager] - [2] 125.03 MB (20.4%)

2021-11-29 17:54:56,482 INFO [routing.RoutingProfileManager] - Total: 202.06 MB (33.0%)

2021-11-29 17:54:56,483 INFO [routing.RoutingProfileManager] - ========================================================================

2021-11-29 18:31:13,572 INFO [ors.Application] - Starting Application v6.4.3 on b900b8a9a0b8 with PID 9 (/usr/local/tomcat/webapps/ors/WEB-INF/classes started by root in /ors-core)

2021-11-29 18:31:13,576 DEBUG [ors.Application] - Running with Spring Boot v2.3.5.RELEASE, Spring v5.2.10.RELEASE

2021-11-29 18:31:13,578 INFO [ors.Application] - No active profile set, falling back to default profiles: default

2021-11-29 18:31:16,072 INFO [ors.Application] - Started Application in 3.424 seconds (JVM running for 6.887)

2021-11-29 18:31:16,207 INFO [routing.RoutingProfileManager] - Total - 1.00 GB, Free - 783.68 MB, Max: 2 GB, Used - 242.32 MB

2021-11-29 18:31:16,211 INFO [routing.RoutingProfileManager] -

2021-11-29 18:31:16,264 INFO [routing.RoutingProfileManager] - ====> Initializing profiles from ‘data/osm_file.pbf’ (1 threads) …

2021-11-29 18:31:16,265 INFO [routing.RoutingProfileManager] -

2021-11-29 18:31:16,268 INFO [routing.RoutingProfileManager] -

2021-11-29 18:31:16,297 INFO [routing.RoutingProfile] - [1] Profiles: ‘foot-walking’, location: ‘data/graphs/walking’.

2021-11-29 18:32:43,047 INFO [routing.RoutingProfile] - [1] FlagEncoders: 1, bits used [UNKNOWN]/64.

2021-11-29 18:32:43,049 INFO [routing.RoutingProfile] - [1] Capacity: [UNKNOWN]. (edges - 746015, nodes - 547120)

2021-11-29 18:32:43,052 INFO [routing.RoutingProfile] - [1] Total time: 86.755s.

2021-11-29 18:32:43,055 INFO [routing.RoutingProfile] - [1] Finished at: 2021-11-29 18:32:43.

2021-11-29 18:32:43,057 INFO [routing.RoutingProfile] -

2021-11-29 18:32:43,079 INFO [routing.RoutingProfile] - [2] Profiles: ‘driving-car’, location: ‘data/graphs/car’.

2021-11-29 18:33:42,656 INFO [core.PrepareCore] - 0, updates:0, nodes: 427 052, shortcuts:0, dijkstras:1 628 032, t(dijk):0.87, t(period):0.0, t(lazy):0.0, t(neighbor):0.0, meanDegree:1, algo:6MB, totalMB:1697, usedMB:1301

2021-11-29 18:34:02,265 INFO [core.PrepareCore] - 85 410, updates:2, nodes: 341 642, shortcuts:1 128, dijkstras:4 526 671, t(dijk):19.17, t(period):17.59, t(lazy):0.0, t(neighbor):1.58, meanDegree:1, algo:6MB, totalMB:1697, usedMB:1356

2021-11-29 18:34:19,399 INFO [core.PrepareCore] - 170 820, updates:4, nodes: 256 232, shortcuts:33 706, dijkstras:6 851 279, t(dijk):35.09, t(period):32.06, t(lazy):0.0, t(neighbor):3.65, meanDegree:1, algo:6MB, totalMB:1697, usedMB:1406

2021-11-29 18:34:39,960 INFO [core.PrepareCore] - 256 230, updates:6, nodes: 170 822, shortcuts:145 940, dijkstras:9 367 898, t(dijk):54.09, t(period):44.57, t(lazy):0.0, t(neighbor):9.89, meanDegree:1, algo:6MB, totalMB:1697, usedMB:1015

2021-11-29 18:34:57,527 INFO [core.PrepareCore] - 341 640, updates:8, nodes: 85 412, shortcuts:238 172, dijkstras:11 388 973, t(dijk):70.43, t(period):52.21, t(lazy):0.0, t(neighbor):17.62, meanDegree:2, algo:6MB, totalMB:1697, usedMB:1067

2021-11-29 18:35:20,385 INFO [core.PrepareCore] - took:99, new shortcuts: 371 535, prepare|fastest|car-ors, dijkstras:14342721, t(dijk):91.89, t(period):53.68, t(lazy):2.26, t(neighbor):33.54, meanDegree:4, initSize:427052, periodic:10, lazy:10, neighbor:90, totalMB:1697, usedMB:1122

2021-11-29 18:35:22,007 INFO [core.PrepareCore] - 0, updates:0, nodes: 427 052, shortcuts:0, dijkstras:1 628 032, t(dijk):0.7, t(period):0.0, t(lazy):0.0, t(neighbor):0.0, meanDegree:1, algo:6MB, totalMB:1697, usedMB:1161

2021-11-29 18:35:38,420 INFO [core.PrepareCore] - 85 410, updates:2, nodes: 341 642, shortcuts:704, dijkstras:4 530 747, t(dijk):15.9, t(period):14.95, t(lazy):0.0, t(neighbor):1.24, meanDegree:1, algo:6MB, totalMB:1697, usedMB:1213

2021-11-29 18:35:53,292 INFO [core.PrepareCore] - 170 820, updates:4, nodes: 256 232, shortcuts:42 779, dijkstras:6 966 042, t(dijk):29.58, t(period):27.59, t(lazy):0.0, t(neighbor):3.02, meanDegree:1, algo:6MB, totalMB:1697, usedMB:1265

2021-11-29 18:36:11,760 INFO [core.PrepareCore] - 256 230, updates:6, nodes: 170 822, shortcuts:157 699, dijkstras:9 666 699, t(dijk):46.62, t(period):38.87, t(lazy):0.0, t(neighbor):8.82, meanDegree:1, algo:6MB, totalMB:1697, usedMB:1330

2021-11-29 18:36:28,001 INFO [core.PrepareCore] - 341 640, updates:8, nodes: 85 412, shortcuts:272 175, dijkstras:12 235 537, t(dijk):61.62, t(period):45.66, t(lazy):0.0, t(neighbor):16.49, meanDegree:2, algo:6MB, totalMB:1697, usedMB:1392

2021-11-29 18:36:58,858 INFO [core.PrepareCore] - took:98, new shortcuts: 453 666, prepare|shortest|car-ors, dijkstras:18413280, t(dijk):90.58, t(period):47.25, t(lazy):2.59, t(neighbor):40.1, meanDegree:8, initSize:427052, periodic:10, lazy:10, neighbor:90, totalMB:1697, usedMB:1038

2021-11-29 18:37:54,212 INFO [routing.RoutingProfile] - [2] FlagEncoders: 1, bits used [UNKNOWN]/64.

2021-11-29 18:37:54,213 INFO [routing.RoutingProfile] - [2] Capacity: [UNKNOWN]. (edges - 570738, nodes - 427052)

2021-11-29 18:37:54,214 INFO [routing.RoutingProfile] - [2] Total time: 311.135s.

2021-11-29 18:37:54,216 INFO [routing.RoutingProfile] - [2] Finished at: 2021-11-29 18:37:54.

2021-11-29 18:37:54,218 INFO [routing.RoutingProfile] -

2021-11-29 18:37:54,236 INFO [routing.RoutingProfileManager] - Total time: 398.025s.

2021-11-29 18:37:54,238 INFO [routing.RoutingProfileManager] - ========================================================================

2021-11-29 18:37:54,246 INFO [routing.RoutingProfileManager] - ====> Recycling garbage…

2021-11-29 18:37:54,249 INFO [routing.RoutingProfileManager] - Before: Total - 1.66 GB, Free - 931.49 MB, Max: 2 GB, Used - 765.51 MB

2021-11-29 18:37:54,323 INFO [routing.RoutingProfileManager] - After: Total - 1.66 GB, Free - 1.06 GB, Max: 2 GB, Used - 613.56 MB

2021-11-29 18:37:54,325 INFO [routing.RoutingProfileManager] - ========================================================================

2021-11-29 18:37:54,326 INFO [routing.RoutingProfileManager] - ====> Memory usage by profiles:

2021-11-29 18:37:54,348 INFO [routing.RoutingProfileManager] - [1] 77.03 MB (12.6%)

2021-11-29 18:37:54,349 INFO [routing.RoutingProfileManager] - [2] 125.03 MB (20.4%)

2021-11-29 18:37:54,350 INFO [routing.RoutingProfileManager] - Total: 202.06 MB (32.9%)

2021-11-29 18:37:54,350 INFO [routing.RoutingProfileManager] - ========================================================================

2021-11-29 19:10:12,078 INFO [util.SystemMessage] - SystemMessage loaded 0 messages.

2021-11-29 19:19:43,037 INFO [ors.Application] - Starting Application v6.4.3 on b900b8a9a0b8 with PID 9 (/usr/local/tomcat/webapps/ors/WEB-INF/classes started by root in /ors-core)

2021-11-29 19:19:43,042 DEBUG [ors.Application] - Running with Spring Boot v2.3.5.RELEASE, Spring v5.2.10.RELEASE

2021-11-29 19:19:43,044 INFO [ors.Application] - No active profile set, falling back to default profiles: default

2021-11-29 19:19:45,651 INFO [ors.Application] - Started Application in 3.544 seconds (JVM running for 7.026)

2021-11-29 19:19:45,797 INFO [routing.RoutingProfileManager] - Total - 1.00 GB, Free - 806.72 MB, Max: 2 GB, Used - 219.28 MB

2021-11-29 19:19:45,799 INFO [routing.RoutingProfileManager] -

2021-11-29 19:19:45,839 INFO [routing.RoutingProfileManager] - ====> Initializing profiles from ‘data/osm_file.pbf’ (1 threads) …

2021-11-29 19:19:45,839 INFO [routing.RoutingProfileManager] -

2021-11-29 19:19:45,842 INFO [routing.RoutingProfileManager] -

2021-11-29 19:19:45,867 INFO [routing.RoutingProfile] - [1] Profiles: ‘foot-walking’, location: ‘data/graphs/walking’.

2021-11-29 19:21:08,943 INFO [routing.RoutingProfile] - [1] FlagEncoders: 1, bits used [UNKNOWN]/64.

2021-11-29 19:21:08,945 INFO [routing.RoutingProfile] - [1] Capacity: [UNKNOWN]. (edges - 746015, nodes - 547120)

2021-11-29 19:21:08,949 INFO [routing.RoutingProfile] - [1] Total time: 83.082s.

2021-11-29 19:21:08,952 INFO [routing.RoutingProfile] - [1] Finished at: 2021-11-29 19:21:08.

2021-11-29 19:21:08,954 INFO [routing.RoutingProfile] -

2021-11-29 19:21:08,977 INFO [routing.RoutingProfile] - [2] Profiles: ‘driving-car’, location: ‘data/graphs/car’.

2021-11-29 19:22:04,544 INFO [core.PrepareCore] - 0, updates:0, nodes: 427 052, shortcuts:0, dijkstras:1 628 032, t(dijk):0.7, t(period):0.0, t(lazy):0.0, t(neighbor):0.0, meanDegree:1, algo:6MB, totalMB:1849, usedMB:804

2021-11-29 19:22:22,668 INFO [core.PrepareCore] - 85 410, updates:2, nodes: 341 642, shortcuts:1 128, dijkstras:4 526 671, t(dijk):17.56, t(period):16.18, t(lazy):0.0, t(neighbor):1.52, meanDegree:1, algo:6MB, totalMB:1849, usedMB:859

2021-11-29 19:22:37,805 INFO [core.PrepareCore] - 170 820, updates:4, nodes: 256 232, shortcuts:33 706, dijkstras:6 851 279, t(dijk):31.6, t(period):29.03, t(lazy):0.0, t(neighbor):3.31, meanDegree:1, algo:6MB, totalMB:1849, usedMB:909

2021-11-29 19:22:54,430 INFO [core.PrepareCore] - 256 230, updates:6, nodes: 170 822, shortcuts:145 940, dijkstras:9 367 898, t(dijk):46.95, t(period):38.86, t(lazy):0.0, t(neighbor):8.59, meanDegree:1, algo:6MB, totalMB:1849, usedMB:973

2021-11-29 19:23:09,070 INFO [core.PrepareCore] - 341 640, updates:8, nodes: 85 412, shortcuts:238 172, dijkstras:11 388 973, t(dijk):60.53, t(period):45.22, t(lazy):0.0, t(neighbor):15.08, meanDegree:2, algo:6MB, totalMB:1849, usedMB:1026

2021-11-29 19:23:28,390 INFO [core.PrepareCore] - took:85, new shortcuts: 371 535, prepare|fastest|car-ors, dijkstras:14342721, t(dijk):78.61, t(period):46.38, t(lazy):1.81, t(neighbor):28.68, meanDegree:4, initSize:427052, periodic:10, lazy:10, neighbor:90, totalMB:1849, usedMB:1081

2021-11-29 19:23:29,946 INFO [core.PrepareCore] - 0, updates:0, nodes: 427 052, shortcuts:0, dijkstras:1 628 032, t(dijk):0.69, t(period):0.0, t(lazy):0.0, t(neighbor):0.0, meanDegree:1, algo:6MB, totalMB:1849, usedMB:1119

2021-11-29 19:23:43,410 INFO [core.PrepareCore] - 85 410, updates:2, nodes: 341 642, shortcuts:704, dijkstras:4 530 747, t(dijk):13.11, t(period):12.3, t(lazy):0.0, t(neighbor):0.97, meanDegree:1, algo:6MB, totalMB:1849, usedMB:1171

2021-11-29 19:23:55,744 INFO [core.PrepareCore] - 170 820, updates:4, nodes: 256 232, shortcuts:42 779, dijkstras:6 966 042, t(dijk):24.43, t(period):22.63, t(lazy):0.0, t(neighbor):2.59, meanDegree:1, algo:6MB, totalMB:1849, usedMB:675

2021-11-29 19:24:10,856 INFO [core.PrepareCore] - 256 230, updates:6, nodes: 170 822, shortcuts:157 699, dijkstras:9 666 699, t(dijk):38.31, t(period):31.62, t(lazy):0.0, t(neighbor):7.52, meanDegree:1, algo:6MB, totalMB:1849, usedMB:740

2021-11-29 19:24:25,275 INFO [core.PrepareCore] - 341 640, updates:8, nodes: 85 412, shortcuts:272 175, dijkstras:12 235 537, t(dijk):51.55, t(period):37.55, t(lazy):0.0, t(neighbor):14.4, meanDegree:2, algo:6MB, totalMB:1849, usedMB:802

2021-11-29 19:24:53,035 INFO [core.PrepareCore] - took:84, new shortcuts: 453 666, prepare|shortest|car-ors, dijkstras:18413280, t(dijk):77.51, t(period):39.1, t(lazy):2.28, t(neighbor):35.56, meanDegree:8, initSize:427052, periodic:10, lazy:10, neighbor:90, totalMB:1849, usedMB:869

2021-11-29 19:25:38,407 INFO [routing.RoutingProfile] - [2] FlagEncoders: 1, bits used [UNKNOWN]/64.

2021-11-29 19:25:38,408 INFO [routing.RoutingProfile] - [2] Capacity: [UNKNOWN]. (edges - 570738, nodes - 427052)

2021-11-29 19:25:38,409 INFO [routing.RoutingProfile] - [2] Total time: 269.432s.

2021-11-29 19:25:38,411 INFO [routing.RoutingProfile] - [2] Finished at: 2021-11-29 19:25:38.

2021-11-29 19:25:38,413 INFO [routing.RoutingProfile] -

2021-11-29 19:25:38,435 INFO [routing.RoutingProfileManager] - Total time: 352.635s.

2021-11-29 19:25:38,436 INFO [routing.RoutingProfileManager] - ========================================================================

2021-11-29 19:25:38,447 INFO [routing.RoutingProfileManager] - ====> Recycling garbage…

2021-11-29 19:25:38,449 INFO [routing.RoutingProfileManager] - Before: Total - 1.81 GB, Free - 1.14 GB, Max: 2 GB, Used - 682.53 MB

2021-11-29 19:25:38,536 INFO [routing.RoutingProfileManager] - After: Total - 1.81 GB, Free - 1.21 GB, Max: 2 GB, Used - 613.16 MB

2021-11-29 19:25:38,537 INFO [routing.RoutingProfileManager] - ========================================================================

2021-11-29 19:25:38,538 INFO [routing.RoutingProfileManager] - ====> Memory usage by profiles:

2021-11-29 19:25:38,571 INFO [routing.RoutingProfileManager] - [1] 77.03 MB (12.6%)

2021-11-29 19:25:38,573 INFO [routing.RoutingProfileManager] - [2] 125.03 MB (20.4%)

2021-11-29 19:25:38,573 INFO [routing.RoutingProfileManager] - Total: 202.06 MB (33.0%)

2021-11-29 19:25:38,574 INFO [routing.RoutingProfileManager] - ========================================================================

2

Hey,

what this error is telling you is that the points you are using are outside the bounds of the graph generated by your .pbf-file.

This has nothing to do with the maximum_routes (note, that if you have 1100 points, a matrix request will generate 1’100*1’100 = 1’210’000 routes, clearly more than the old limit of 1’000’000) or the maximum_search_radius.

Please check that your pbf file covers your coordinates.

Best regards

3

thank you @jschnell . You were correct! That issue is now fixed but I’ve encountered a new one, “Max retries exceeded”. Any idea? Is this because it takes the machine too long to calculate the matrix?

Traceback (most recent call last):
File “C:\Programs\Python\Python38\lib\site-packages\urllib3\connection.py”, line 169, in _new_conn
conn = connection.create_connection(
File “C:\Programs\Python\Python38\lib\site-packages\urllib3\util\connection.py”, line 96, in create_connection
raise err
File “C:\Programs\Python\Python38\lib\site-packages\urllib3\util\connection.py”, line 86, in create_connection
sock.connect(sa)
ConnectionRefusedError: [WinError 10061] No connection could be made because the target machine actively refused it

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
File “C:\Programs\Python\Python38\lib\site-packages\urllib3\connectionpool.py”, line 699, in urlopen
httplib_response = self._make_request(
File “C:\Programs\Python\Python38\lib\site-packages\urllib3\connectionpool.py”, line 394, in _make_request
conn.request(method, url, **httplib_request_kw)
File “C:\Programs\Python\Python38\lib\site-packages\urllib3\connection.py”, line 234, in request
super(HTTPConnection, self).request(method, url, body=body, headers=headers)
File “C:\Programs\Python\Python38\lib\http\client.py”, line 1255, in request
self._send_request(method, url, body, headers, encode_chunked)
File “C:\Programs\Python\Python38\lib\http\client.py”, line 1301, in _send_request
self.endheaders(body, encode_chunked=encode_chunked)
File “C:\Programs\Python\Python38\lib\http\client.py”, line 1250, in endheaders
self._send_output(message_body, encode_chunked=encode_chunked)
File “C:\Programs\Python\Python38\lib\http\client.py”, line 1010, in _send_output
self.send(msg)
File “C:\Programs\Python\Python38\lib\http\client.py”, line 950, in send
self.connect()
File “C:\Programs\Python\Python38\lib\site-packages\urllib3\connection.py”, line 200, in connect
conn = self._new_conn()
File “C:\Programs\Python\Python38\lib\site-packages\urllib3\connection.py”, line 181, in _new_conn
raise NewConnectionError(
urllib3.exceptions.NewConnectionError: <urllib3.connection.HTTPConnection object at 0x000001F2117FA280>: Failed to establish a new connection: [WinError 10061] No connection could be made because the target machine actively refused it

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
File “C:\Programs\Python\Python38\lib\site-packages\requests\adapters.py”, line 439, in send
resp = conn.urlopen(
File “C:\Programs\Python\Python38\lib\site-packages\urllib3\connectionpool.py”, line 755, in urlopen
retries = retries.increment(
File “C:\Programs\Python\Python38\lib\site-packages\urllib3\util\retry.py”, line 574, in increment
raise MaxRetryError(_pool, url, error or ResponseError(cause))
urllib3.exceptions.MaxRetryError: HTTPConnectionPool(host=‘localhost’, port=8080): Max retries exceeded with url: /ors/v2/matrix/driving-car/json (Caused by NewConnectionError(’<urllib3.connection.HTTPConnection object at 0x000001F2117FA280>: Failed to establish a new connection: [WinError 10061] No connection could be made because the target machine actively refused it’))

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
File “d:/users/davideps/from_workstation/python_code/ors_requester_bubble_Nov2021.py”, line 191, in
matrix = client.distance_matrix(
File “C:\Programs\Python\Python38\lib\site-packages\openrouteservice\client.py”, line 299, in wrapper
result = func(*args, **kwargs)
File “C:\Programs\Python\Python38\lib\site-packages\openrouteservice\distance_matrix.py”, line 116, in distance_matrix
return client.request("/v2/matrix/" + profile + ‘/json’, {}, post_json=params, dry_run=dry_run)
File “C:\Programs\Python\Python38\lib\site-packages\openrouteservice\client.py”, line 186, in request
response = requests_method(self._base_url + authed_url,
File “C:\Programs\Python\Python38\lib\site-packages\requests\sessions.py”, line 590, in post
return self.request(‘POST’, url, data=data, json=json, **kwargs)
File “C:\Programs\Python\Python38\lib\site-packages\requests\sessions.py”, line 542, in request
resp = self.send(prep, **send_kwargs)
File “C:\Programs\Python\Python38\lib\site-packages\requests\sessions.py”, line 655, in send
r = adapter.send(request, **kwargs)
File “C:\Programs\Python\Python38\lib\site-packages\requests\adapters.py”, line 516, in send
raise ConnectionError(e, request=request)
requests.exceptions.ConnectionError: HTTPConnectionPool(host=‘localhost’, port=8080): Max retries exceeded with url: /ors/v2/matrix/driving-car/json (Caused by NewConnectionError(’<urllib3.connection.HTTPConnection object at 0x000001F2117FA280>: Failed to establish a new connection: [WinError 10061] No connection could be made because the target machine actively refused it’))

4

Hey,

to me looks like you’re querying the wrong host or port.
I’d recommend trying in a browser or using curl first, to see whether you got the port and the host right - maybe something like 127.0.0.1 works?
You can query /ors/health to see whether the service is running and /ors/status to see what profiles are available.

Best regards

5

Thank you @jschnell. I don’t know what caused the connection error but I am no longer seeing it. The host and port were correct and the service was running. ORS then computed the large matrices for driving but failed for walking. I saw this post and increased the maximum_visited_nodes from 10,000,000 to 100,000,000, which solved the issue. Thank you again for your help and patience!