通過上一篇《分散式服務跟蹤（入門）》的例子，我們已經通過Spring Cloud Sleuth往微服務應用中添加了實現分散式跟蹤具備的基本要素。下面通過本文來詳細說說實現分散式服務跟蹤的一些要點。

分散式系統中的服務跟蹤在理論上並不複雜，它主要包括下面兩個關鍵點：

• 為了實現請求跟蹤，當請求傳送到分散式系統的入口端點時，只需要服務跟蹤框架為該請求建立一個唯一的跟蹤標識，同時在分散式系統內部流轉的時候，框架始終保持傳遞該唯一標識，直到返回給請求方為止，這個唯一標識就是前文中提到的 TraceID。通過 TraceID的記錄，我們就能將所有請求過程日誌關聯起來。

• 為了統計各處理單元的時間延遲，當請求達到各個服務元件時，或是處理邏輯到達某個狀態時，也通過一個唯一標識來標記它的開始、具體過程以及結束，該標識就是我們前文中提到的Span ID，對於每個Span來說，它必須有開始和結束兩個節點，通過記錄開始Span和結束Span的時間戳，就能統計出該Span的時間延遲，除了時間戳記錄之外，它還可以包含一些其他元資料，比如：事件名稱、請求資訊等。

在快速入門示例中，我們輕鬆實現了日誌級別的跟蹤資訊接入，這完全歸功於 spring-cloud-starter-sleuth元件的實現。在Spring Boot應用中，通過在工程中引入 spring-cloud-starter-sleuth依賴之後， 它會自動的為當前應用構建起各通訊通道的跟蹤機制，比如：

• 通過諸如RabbitMQ、Kafka（或者其他任何Spring Cloud Stream繫結器實現的訊息中介軟體）傳遞的請求

• 通過Zuul代理傳遞的請求

• 通過 RestTemplate發起的請求

在快速入門示例中，由於 trace-1對 trace-2發起的請求是通過 RestTemplate實現的，所以 spring

• X-B3-TraceId：一條請求鏈路（Trace）的唯一標識，必須值

• X-B3-SpanId：一個工作單元（Span）的唯一標識，必須值

• X-B3-ParentSpanId:：標識當前工作單元所屬的上一個工作單元，Root Span（請求鏈路的第一個工作單元）的該值為空

• X-B3-Sampled：是否被抽樣輸出的標誌，1表示需要被輸出，0表示不需要被輸出

• X-Span-Name：工作單元的名稱

我們可以通過對 trace-2的實現做一些修改來輸出這些頭部資訊，具體如下：

1. @RequestMapping(value ="/trace-2", method =RequestMethod.GET)

2. publicString trace(HttpServletRequest request){

3.    logger.info("===<call trace-2, TraceId={}, SpanId={}>===",

4.            request.getHeader("X-B3-TraceId"), request.getHeader("X-B3-SpanId"));

5. return"Trace";

6. }

通過上面的改造，我們再執行快速入門的示例內容，併發起對 trace-1的介面訪問，我們可以得到如下輸出內容。其中在 trace-2的控制檯中，輸出了當前正在處理的 TraceID和 SpanId資訊。

1. -- trace-1

2. INFO [trace-1,a6e9175ffd5d2c88,8524f519b8a9e399,true]10532---[nio-9101-exec-2] icationEnhancerBySpringCGLIB27aa9624 :===<call trace-1>===

3. -- trace-2

4. INFO [trace-2,a6e9175ffd5d2c88,ce60dcf1e2ed918f,true]1208---[nio-9102-exec-3] icationEnhancerBySpringCGLIBa7d84797 :===<call trace-2,TraceId=a6e9175ffd5d2c88,SpanId=be4949ec115e554e>===

為了更直觀的觀察跟蹤資訊，我們還可以在 application.properties中增加下面的配置：

1. logging.level.org.springframework.web.servlet.DispatcherServlet=DEBUG

通過將Spring MVC的請求分發日誌級別調整為 DEBUG級別，我們可以看到更多跟蹤資訊：

1. -- trace-1

2. 2016-11-2709:26:52.663 DEBUG [trace-1,a6e9175ffd5d2c88,a6e9175ffd5d2c88,true]10532---[nio-9101-exec-2] o.s.web.servlet.DispatcherServlet:DispatcherServlet with name 'dispatcherServlet' processing GET request for[/trace-1]

3. 2016-11-2709:26:52.666 DEBUG [trace-1,a6e9175ffd5d2c88,a6e9175ffd5d2c88,true]10532---[nio-9101-exec-2] o.s.web.servlet.DispatcherServlet:Last-Modified value for[/trace-1] is:-1

4. 2016-11-2709:26:52.685 DEBUG [trace-1,a6e9175ffd5d2c88,8524f519b8a9e399,true]10532---[nio-9101-exec-2] o.s.web.servlet.DispatcherServlet:NullModelAndView returned to DispatcherServlet with name 'dispatcherServlet': assuming HandlerAdapter completed request handling

5. 2016-11-2709:26:52.685 DEBUG [trace-1,a6e9175ffd5d2c88,a6e9175ffd5d2c88,true]10532---[nio-9101-exec-2] o.s.web.servlet.DispatcherServlet:Successfully completed request

6. -- trace-2

7. 2016-11-2709:26:52.673 DEBUG [trace-2,a6e9175ffd5d2c88,be4949ec115e554e,true]1208---[nio-9102-exec-3] o.s.web.servlet.DispatcherServlet:DispatcherServlet with name 'dispatcherServlet' processing GET request for[/trace-2]

8. 2016-11-2709:26:52.679 DEBUG [trace-2,a6e9175ffd5d2c88,be4949ec115e554e,true]1208---[nio-9102-exec-3] o.s.web.servlet.DispatcherServlet:Last-Modified value for[/trace-2] is:-1

9. 2016-11-2709:26:52.682 DEBUG [trace-2,a6e9175ffd5d2c88,ce60dcf1e2ed918f,true]1208---[nio-9102-exec-3] o.s.web.servlet.DispatcherServlet:NullModelAndView returned to DispatcherServlet with name 'dispatcherServlet': assuming HandlerAdapter completed request handling

10. 2016-11-2709:26:52.683 DEBUG [trace-2,a6e9175ffd5d2c88,be4949ec115e554e,true]1208---[nio-9102-exec-3] o.s.web.servlet.DispatcherServlet:Successfully completed request

完整示例：

讀者可以根據喜好選擇下面的兩個倉庫中檢視 trace-1和 trace-2兩個專案：

• Github：https://github.com/dyc87112/SpringCloud-Learning/

• Gitee：https://gitee.com/didispace/SpringCloud-Learning/

如果您對這些感興趣，歡迎star、follow、收藏、轉發給予支援！

本文內容部分節選自我的《Spring Cloud微服務實戰》，但對依賴的Spring Boot和Spring Cloud版本做了升級。