### GET /api/slow/80 (the service sleeps 80 ms)
HTTP 200  body=slept 80
01 REQUEST  filter:raw(order 5)            before chain
02 REQUEST  filter:accessLog(order 10)     before chain GET /api/slow/80
03 REQUEST  servlet:DispatcherServlet      doDispatch start
04 REQUEST  interceptor:A                  preHandle  handler=HandlerMethod HelloController#slow
05 REQUEST  interceptor:B                  preHandle  handler=HandlerMethod HelloController#slow
06 REQUEST  aspect:controller              before HelloController#slow args=[80]
07 REQUEST  aspect:service                 before GreetingService#slow args=[80]
08 REQUEST  aspect:service                 after  GreetingService#slow returned=slept 80
09 REQUEST  aspect:controller              after  HelloController#slow returned=slept 80 (String)
10 REQUEST  interceptor:B                  postHandle status=200 modelAndView=null
11 REQUEST  interceptor:A                  postHandle status=200 modelAndView=null
12 REQUEST  interceptor:B                  afterCompletion ex=null
13 REQUEST  interceptor:A                  afterCompletion ex=null
14 REQUEST  servlet:DispatcherServlet      doDispatch end
15 REQUEST  filter:accessLog(order 10)     after chain status=200 asyncStarted=false
16 REQUEST  filter:raw(order 5)            after chain

### Elapsed time seen by each layer, milliseconds (indicative: timing varies run to run)
filter (accessLog)             87 ms
interceptor (timing)           86 ms
aspect around controller       80 ms
aspect around service          80 ms
