[SCB-293] trace integration test has small probability lost data.

• Type: Bug
• Status: Closed
• Priority: Major
• Resolution: Fixed
• Fix Version/s: java-chassis-1.0.0-m1
• Component/s: Java-Chassis
• Assignee: Yang Bo
• Reporter: Jimin Wu
• Created: 23/Jan/18 09:04
• Updated: 27/Mar/18 06:56
• Resolved: 27/Mar/18 06:56

Description

05:06:38,161 [ebc2a9a79ef653e3/5c77469686c84d3f/1b6b1907ad44e15d] INFO [pool-2-thread-3] tracing.TracedPojoImpl (TracedPojoImpl.java:31) - in /pojo
Jan 22, 2018 5:06:38 AM okhttp3.mockwebserver.MockWebServer$3 processOneRequest
INFO: MockWebServer[32871] received request: POST /api/v2/spans HTTP/1.1 and responded: HTTP/1.1 202 OK
05:06:39,314 // INFO [main] tracing.TracingTestBase (TracingTestBase.java:73) - Received log: 05:06:37,269 [ebc2a9a79ef653e3/ebc2a9a79ef653e3/] INFO [pool-2-thread-1] tracing.SomeTracedController (SomeTracedController.java:47) - in /hello

05:06:39,315 // INFO [main] tracing.TracingTestBase (TracingTestBase.java:73) - Received log: 05:06:37,293 [ebc2a9a79ef653e3/62b00f7ba98331ac/ebc2a9a79ef653e3] INFO [pool-2-thread-1] tracing.SlowRepoImpl (SlowRepoImpl.java:34) - in /crawl

05:06:39,315 // INFO [main] tracing.TracingTestBase (TracingTestBase.java:73) - Received log: 05:06:37,929 [ebc2a9a79ef653e3/1b6b1907ad44e15d/ebc2a9a79ef653e3] INFO [pool-2-thread-2] tracing.TracedJaxrsController (TracedJaxrsController.java:51) - in /bonjour

05:06:39,316 // INFO [main] tracing.TracingTestBase (TracingTestBase.java:73) - Received log: 05:06:38,161 [ebc2a9a79ef653e3/5c77469686c84d3f/1b6b1907ad44e15d] INFO [pool-2-thread-3] tracing.TracedPojoImpl (TracedPojoImpl.java:31) - in /pojo

05:06:39,317 // INFO [main] tracing.TracingTestBase (TracingTestBase.java:79) - rest url:http://localhost:32871/api/v2/trace/\{traceId}
05:06:39,322 // INFO [main] tracing.TracingTestBase (TracingTestBase.java:84) - Received trace json: [{"traceId":"ebc2a9a79ef653e3","parentId":"ebc2a9a79ef653e3","id":"62b00f7ba98331ac","name":"crawl","timestamp":1516597597292328,"duration":24877,"localEndpoint":

{"serviceName":"tracing-service","ipv4":"10.20.0.41"}

,"tags":{"call.path":"public abstract java.lang.String org.apache.servicecomb.tests.tracing.SlowRepo.crawl() throws java.lang.InterruptedException"}},{"traceId":"ebc2a9a79ef653e3","parentId":"1b6b1907ad44e15d","id":"5c77469686c84d3f","kind":"SERVER","name":"post","timestamp":1516597598138483,"duration":27967,"localEndpoint":

{"serviceName":"tracing-service","ipv4":"10.20.0.41"}

,"tags":{"http.path":"/pojo"}}]
05:06:39,326 // INFO [main] tracing.TracingTestBase (TracingTestBase.java:89) - Received value public abstract java.lang.String org.apache.servicecomb.tests.tracing.SlowRepo.crawl() throws java.lang.InterruptedException
05:06:39,327 // INFO [main] tracing.TracingTestBase (TracingTestBase.java:89) - Received value /pojo
05:06:39,327 // INFO [main] tracing.TracingTestBase (TracingTestBase.java:90) - values: /hello,public abstract java.lang.String org.apache.servicecomb.tests.tracing.SlowRepo.crawl() throws java.lang.InterruptedException,/bonjour,/pojo
Jan 22, 2018 5:06:39 AM okhttp3.mockwebserver.MockWebServer$3 processOneRequest
INFO: MockWebServer[32871] received request: GET /api/v2/trace/ebc2a9a79ef653e3 HTTP/1.1 and responded: HTTP/1.1 200 OK
Jan 22, 2018 5:06:39 AM okhttp3.mockwebserver.MockWebServer$3 processOneRequest
INFO: MockWebServer[32871] received request: POST /api/v2/spans HTTP/1.1 and responded: HTTP/1.1 202 OK
Jan 22, 2018 5:06:39 AM okhttp3.mockwebserver.MockWebServer$2 acceptConnections
INFO: MockWebServer[32871] done accepting connections: Socket closed
Tests run: 1, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 12.203 sec <<< FAILURE! - in org.apache.servicecomb.tests.tracing.ZipkinTracingIntegrationTest
sendsTracingToConfiguredAddress(org.apache.servicecomb.tests.tracing.ZipkinTracingIntegrationTest) Time elapsed: 10.252 sec <<< FAILURE!
java.lang.AssertionError:
Expected: iterable over ["/hello", "public abstract java.lang.String org.apache.servicecomb.tests.tracing.SlowRepo.crawl() throws java.lang.InterruptedException", "/bonjour", "/pojo"] in any order
but: No item matches: "/hello", "/bonjour" in ["public abstract java.lang.String org.apache.servicecomb.tests.tracing.SlowRepo.crawl() throws java.lang.InterruptedException", "/pojo"]
at org.hamcrest.MatcherAssert.assertThat(MatcherAssert.java:20)
at org.apache.servicecomb.tests.tracing.TracingTestBase.assertThatSpansReceivedByZipkin(TracingTestBase.java:91)
at org.apache.servicecomb.tests.tracing.ZipkinTracingIntegrationTest.sendsTracingToConfiguredAddress(ZipkinTracingIntegrationTest.java:51)

05:06:39,416 // INFO [Thread-4] support.AbstractApplicationContext (AbstractApplicationContext.java:987) - Closing

Issue Links

• links to: GitHub Pull Request #534