Hmm. The output below has a lot of debug information, isn't very structured, and also doesn't print out all the information I need like times. Do you know if there is a way to print out more structured output that would be easier to parse?
2013-05-22 18:44:05,507 DEBUG [StreetVertexIndexServiceImpl.java:184] : Looking for/making a vertex near (-86.808826, 33.511924, NaN)
2013-05-22 18:44:05,509 DEBUG [StreetVertexIndexServiceImpl.java:232] : best stop: <1_1127> distance: 4.06123208053386
2013-05-22 18:44:05,516 DEBUG [StreetVertexIndexServiceImpl.java:243] : best street: PlainStreetEdge(<split 2 at link for 1_1127> -> <osm node 1428434714>) dist: 22.716446264581684
2013-05-22 18:44:08,044 DEBUG [StreetVertexIndexServiceImpl.java:262] : linking transit stop to street (distances are comparable)
2013-05-22 18:44:08,051 DEBUG [StreetVertexIndexServiceImpl.java:266] : returning split street
2013-05-22 18:44:08,051 DEBUG [StreetVertexIndexServiceImpl.java:184] : Looking for/making a vertex near (-86.728702, 33.448835, NaN)
2013-05-22 18:44:08,052 DEBUG [StreetVertexIndexServiceImpl.java:232] : best stop: <1_2548> distance: 61.99712885017624
2013-05-22 18:44:08,053 DEBUG [StreetVertexIndexServiceImpl.java:243] : best street: PlainStreetEdge(<split 1 at link for 1_2548> -> <osm node 56318905>) dist: 121.5473565801059
2013-05-22 18:44:08,054 DEBUG [StreetVertexIndexServiceImpl.java:262] : linking transit stop to street (distances are comparable)
2013-05-22 18:44:08,054 DEBUG [StreetVertexIndexServiceImpl.java:266] : returning split street
2013-05-22 18:44:08,055 DEBUG [RetryingPathServiceImpl.java:119] : BEGIN SUBSEARCH
2013-05-22 18:44:08,057 DEBUG [GenericAStar.java:158] : total vertices visited 284
2013-05-22 18:44:08,057 DEBUG [RetryingPathServiceImpl.java:124] : END SUBSEARCH (2 msec of 2 msec total)
2013-05-22 18:44:08,057 DEBUG [RetryingPathServiceImpl.java:171] : New trips: [1_469, 1_469, 1_469, 1_469, 1_469, 1_469, 1_469, 1_469, 1_469, 1_469, 1_469, 1_469, 1_469, 1_469, 1_469, 1_469, 1_469, 1_469, 1_469]
2013-05-22 18:44:08,058 DEBUG [RetryingPathServiceImpl.java:181] : 1 / 3 itineraries
2013-05-22 18:44:08,058 DEBUG [RetryingPathServiceImpl.java:119] : BEGIN SUBSEARCH
2013-05-22 18:44:08,065 DEBUG [GenericAStar.java:158] : total vertices visited 1749
2013-05-22 18:44:08,065 DEBUG [RetryingPathServiceImpl.java:124] : END SUBSEARCH (7 msec of 10 msec total)
2013-05-22 18:44:08,066 DEBUG [RetryingPathServiceImpl.java:171] : New trips: [1_470, 1_470, 1_470, 1_470, 1_470, 1_470, 1_470, 1_470, 1_470, 1_470, 1_470, 1_470, 1_470, 1_470, 1_470, 1_470, 1_470, 1_470, 1_470]
2013-05-22 18:44:08,066 DEBUG [RetryingPathServiceImpl.java:181] : 2 / 3 itineraries
2013-05-22 18:44:08,066 DEBUG [RetryingPathServiceImpl.java:119] : BEGIN SUBSEARCH
2013-05-22 18:44:09,592 DEBUG [GenericAStar.java:158] : total vertices visited 5277
2013-05-22 18:44:09,593 DEBUG [RetryingPathServiceImpl.java:124] : END SUBSEARCH (1527 msec of 1538 msec total)
2013-05-22 18:44:09,593 DEBUG [RetryingPathServiceImpl.java:171] : New trips: [1_471, 1_471, 1_471, 1_471, 1_471, 1_471, 1_471, 1_471, 1_471, 1_471, 1_471, 1_471, 1_471, 1_471, 1_471, 1_471, 1_471, 1_471, 1_471]
2013-05-22 18:44:09,593 DEBUG [RetryingPathServiceImpl.java:181] : 3 / 3 itineraries
2013-05-22 18:44:09,594 DEBUG [DefaultFareServiceImpl.java:269] : null best for [Ride on route 1_37 through zones null at 1369249200, classified by BUS)]
2013-05-22 18:44:09,594 WARN [DefaultFareServiceImpl.java:272] : No fare for a ride sequence: [Ride on route 1_37 through zones null at 1369249200, classified by BUS)]
2013-05-22 18:44:09,595 DEBUG [DefaultFareServiceImpl.java:269] : null best for [Ride on route 1_37 through zones null at 1369252800, classified by BUS)]
2013-05-22 18:44:09,596 WARN [DefaultFareServiceImpl.java:272] : No fare for a ride sequence: [Ride on route 1_37 through zones null at 1369252800, classified by BUS)]
2013-05-22 18:44:09,597 DEBUG [DefaultFareServiceImpl.java:269] : null best for [Ride on route 1_37 through zones null at 1369256400, classified by BUS)]
2013-05-22 18:44:09,598 WARN [DefaultFareServiceImpl.java:272] : No fare for a ride sequence: [Ride on route 1_37 through zones null at 1369256400, classified by BUS)]
2013-05-22 18:44:09,599 DEBUG [RoutingRequest.java:730] : routing context destroyed (16 temporary edges removed)