[
https://issues.apache.org/jira/browse/HTRACE-301?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=15008472#comment-15008472
]
Masatake Iwasaki commented on HTRACE-301:
-----------------------------------------
{noformat}
2015-11-17T18:51:18+09:00 T: Reading HRPC request header from [::1]:35554
2015-11-17T18:51:18+09:00 D: hrpc writeSpansHandler: received 15 span(s).
defaultTrid =
2015-11-17T18:51:18+09:00 T: writing span 0:
{"a":"aadcd868ce028477f86e430140149b03","b":115936908113921818,"e":8255086731649237043,"d":"getFileDescriptors","p":[],"r":"tracer340339578"}
2015-11-17T18:51:18+09:00 T: writing span 1:
{"a":"66ef5ff344e2836aec0ffb909dcd8918","b":3060133434563208130,"e":1616787010324494480,"d":"getFileDescriptors","p":["aadcd868ce028477f86e430140149b03"],"r":"tracer1139536169"}
2015-11-17T18:51:18+09:00 T: writing span 2:
{"a":"d77cb3598cbdec63f55f21a35f9e959c","b":1094213293765634518,"e":2444613489581602865,"d":"getFileDescriptors","p":["66ef5ff344e2836aec0ffb909dcd8918"],"r":"tracer233594447"}
2015-11-17T18:51:18+09:00 T: writing span 3:
{"a":"9a9e75dfc1f92abebd4fc4bea85b4392","b":2422890555144810725,"e":235941726421551426,"d":"getFileDescriptors","p":[],"r":"tracer232736174"}
2015-11-17T18:51:18+09:00 T: writing span 4:
{"a":"a83f3ad817fed170fc9cd85d973cb88c","b":5742057364418735842,"e":7300407502866239361,"d":"getFileDescriptors","p":["66ef5ff344e2836aec0ffb909dcd8918"],"r":"tracer1743801447"}
2015-11-17T18:51:18+09:00 T: writing span 5:
{"a":"22ba3d50557698caac767182a7190c7e","b":330335489858925535,"e":6526279427665156762,"d":"getFileDescriptors","p":[],"r":"tracer246288426"}
2015-11-17T18:51:18+09:00 T: writing span 6:
{"a":"67c36c0adbf5e4e5fb9fec27693da4b4","b":633351616201784522,"e":6551999922021939836,"d":"getFileDescriptors","p":["d77cb3598cbdec63f55f21a35f9e959c"],"r":"tracer516405596"}
2015-11-17T18:51:18+09:00 T: writing span 7:
{"a":"41a1480d889b5b5da6edada82c67d23c","b":4731411350918337549,"e":5938847274935192364,"d":"getFileDescriptors","p":["a83f3ad817fed170fc9cd85d973cb88c"],"r":"tracer4888147"}
2015-11-17T18:51:18+09:00 T: writing span 8:
{"a":"bf4d1ae8d4576a3f2cb0bb5fa19f045d","b":454521195915519802,"e":6073688426416379901,"d":"getFileDescriptors","p":["a83f3ad817fed170fc9cd85d973cb88c"],"r":"tracer2107516569"}
2015-11-17T18:51:18+09:00 T: writing span 9:
{"a":"a509438d3430bf4fb1dddd5855e83536","b":623275800845869471,"e":7811439893822484840,"d":"getFileDescriptors","p":[],"r":"tracer1301607040"}
2015-11-17T18:51:18+09:00 T: writing span 10:
{"a":"ce15b02a7ab66ab2cd021b115d96cef2","b":1525156618095896088,"e":5991061593840447097,"d":"getFileDescriptors","p":["d77cb3598cbdec63f55f21a35f9e959c"],"r":"tracer1664662730"}
2015-11-17T18:51:18+09:00 T: writing span 11:
{"a":"ec8e24b5dd31619d66720198b0c00400","b":2682237963765731239,"e":3095998503871832551,"d":"getFileDescriptors","p":["41a1480d889b5b5da6edada82c67d23c"],"r":"tracer1962169969"}
2015-11-17T18:51:18+09:00 T: writing span 12:
{"a":"41848a01f842174410897cc1f63f22f4","b":997231122608079442,"e":3280220515023291282,"d":"getFileDescriptors","p":["22ba3d50557698caac767182a7190c7e"],"r":"tracer806738987"}
2015-11-17T18:51:18+09:00 T: writing span 13:
{"a":"f7806d3bf19c0f3278ca1550c4f0c13b","b":6936227793071536736,"e":8971715207120715805,"d":"getFileDescriptors","p":["9a9e75dfc1f92abebd4fc4bea85b4392"],"r":"tracer774592464"}
2015-11-17T18:51:18+09:00 T: writing span 14:
{"a":"da86028058477ba7e64ec7b8d4a0b9e4","b":1943358486163955042,"e":4871850970522417487,"d":"getFileDescriptors","p":["66ef5ff344e2836aec0ffb909dcd8918"],"r":"tracer635662453"}
2015-11-17T18:51:18+09:00 T: Shard processor for
/tmp/TestClientOperations2836375240/db wrote span
{"a":"9a9e75dfc1f92abebd4fc4bea85b4392","b":2422890555144810725,"e":235941726421551426,"d":"getFileDescriptors","p":[],"r":"tracer232736174"}.
2015-11-17T18:51:18+09:00 D: Error reading header bytes: EOF
2015-11-17T18:51:18+09:00 T: Shard processor for
/tmp/TestClientOperations2836375240/db wrote span
{"a":"67c36c0adbf5e4e5fb9fec27693da4b4","b":633351616201784522,"e":6551999922021939836,"d":"getFileDescriptors","p":["d77cb3598cbdec63f55f21a35f9e959c"],"r":"tracer516405596"}.
2015-11-17T18:51:18+09:00 T: Shard processor for
/tmp/TestClientOperations2836375240/db wrote span
{"a":"ec8e24b5dd31619d66720198b0c00400","b":2682237963765731239,"e":3095998503871832551,"d":"getFileDescriptors","p":["41a1480d889b5b5da6edada82c67d23c"],"r":"tracer1962169969"}.
2015-11-17T18:51:18+09:00 T: Shard processor for
/tmp/TestClientOperations2836375240/db wrote span
{"a":"41848a01f842174410897cc1f63f22f4","b":997231122608079442,"e":3280220515023291282,"d":"getFileDescriptors","p":["22ba3d50557698caac767182a7190c7e"],"r":"tracer806738987"}.
2015-11-17T18:51:18+09:00 T: Shard processor for
/tmp/TestClientOperations2836375240/db wrote span
{"a":"f7806d3bf19c0f3278ca1550c4f0c13b","b":6936227793071536736,"e":8971715207120715805,"d":"getFileDescriptors","p":["9a9e75dfc1f92abebd4fc4bea85b4392"],"r":"tracer774592464"}.
2015-11-17T18:51:18+09:00 D:
findSidHandler(sid=aadcd868ce028477f86e430140149b03)
2015-11-17T18:51:18+09:00 E: Shard(/tmp/TestClientOperations1732476877/db):
FindSpan(aadcd868ce028477f86e430140149b03) decode error: EOF decoding []
2015-11-17T18:51:18+09:00 I: No such span as aadcd868ce028477f86e430140149b03
2015-11-17T18:51:18+09:00 I: Closing MiniHTraced TestClientOperations
2015-11-17T18:51:18+09:00 D: DatastoreHeartbeater: exiting.
2015-11-17T18:51:18+09:00 I: Waiting for /tmp/TestClientOperations1732476877/db
to exit...
2015-11-17T18:51:18+09:00 T: Shard processor for
/tmp/TestClientOperations1732476877/db wrote span
{"a":"aadcd868ce028477f86e430140149b03","b":115936908113921818,"e":8255086731649237043,"d":"getFileDescriptors","p":[],"r":"tracer340339578"}.
2015-11-17T18:51:18+09:00 T: Shard processor for
/tmp/TestClientOperations1732476877/db wrote span
{"a":"66ef5ff344e2836aec0ffb909dcd8918","b":3060133434563208130,"e":1616787010324494480,"d":"getFileDescriptors","p":["aadcd868ce028477f86e430140149b03"],"r":"tracer1139536169"}.
2015-11-17T18:51:18+09:00 T: Shard processor for
/tmp/TestClientOperations1732476877/db wrote span
{"a":"d77cb3598cbdec63f55f21a35f9e959c","b":1094213293765634518,"e":2444613489581602865,"d":"getFileDescriptors","p":["66ef5ff344e2836aec0ffb909dcd8918"],"r":"tracer233594447"}.
2015-11-17T18:51:18+09:00 T: Shard processor for
/tmp/TestClientOperations1732476877/db wrote span
{"a":"a83f3ad817fed170fc9cd85d973cb88c","b":5742057364418735842,"e":7300407502866239361,"d":"getFileDescriptors","p":["66ef5ff344e2836aec0ffb909dcd8918"],"r":"tracer1743801447"}.
2015-11-17T18:51:18+09:00 T: Shard processor for
/tmp/TestClientOperations1732476877/db wrote span
{"a":"22ba3d50557698caac767182a7190c7e","b":330335489858925535,"e":6526279427665156762,"d":"getFileDescriptors","p":[],"r":"tracer246288426"}.
2015-11-17T18:51:18+09:00 T: Shard processor for
/tmp/TestClientOperations1732476877/db wrote span
{"a":"41a1480d889b5b5da6edada82c67d23c","b":4731411350918337549,"e":5938847274935192364,"d":"getFileDescriptors","p":["a83f3ad817fed170fc9cd85d973cb88c"],"r":"tracer4888147"}.
2015-11-17T18:51:18+09:00 T: Shard processor for
/tmp/TestClientOperations1732476877/db wrote span
{"a":"bf4d1ae8d4576a3f2cb0bb5fa19f045d","b":454521195915519802,"e":6073688426416379901,"d":"getFileDescriptors","p":["a83f3ad817fed170fc9cd85d973cb88c"],"r":"tracer2107516569"}.
2015-11-17T18:51:18+09:00 T: Shard processor for
/tmp/TestClientOperations1732476877/db wrote span
{"a":"a509438d3430bf4fb1dddd5855e83536","b":623275800845869471,"e":7811439893822484840,"d":"getFileDescriptors","p":[],"r":"tracer1301607040"}.
2015-11-17T18:51:18+09:00 T: Shard processor for
/tmp/TestClientOperations1732476877/db wrote span
{"a":"ce15b02a7ab66ab2cd021b115d96cef2","b":1525156618095896088,"e":5991061593840447097,"d":"getFileDescriptors","p":["d77cb3598cbdec63f55f21a35f9e959c"],"r":"tracer1664662730"}.
2015-11-17T18:51:18+09:00 T: Shard processor for
/tmp/TestClientOperations1732476877/db wrote span
{"a":"da86028058477ba7e64ec7b8d4a0b9e4","b":1943358486163955042,"e":4871850970522417487,"d":"getFileDescriptors","p":["66ef5ff344e2836aec0ffb909dcd8918"],"r":"tracer635662453"}.
2015-11-17T18:51:18+09:00 I: Shard processor for
/tmp/TestClientOperations1732476877/db exiting.
2015-11-17T18:51:18+09:00 I: Closed /tmp/TestClientOperations1732476877/db...
2015-11-17T18:51:18+09:00 I: Waiting for /tmp/TestClientOperations2836375240/db
to exit...
2015-11-17T18:51:18+09:00 I: Shard processor for
/tmp/TestClientOperations2836375240/db exiting.
2015-11-17T18:51:18+09:00 I: Closed /tmp/TestClientOperations2836375240/db...
2015-11-17T18:51:18+09:00 D: ReaperHeartbeater: exiting.
2015-11-17T18:51:18+09:00 I: Exiting Reaper goroutine.
2015-11-17T18:51:18+09:00 T: MetricsSink: shutting down cleanly.
2015-11-17T18:51:18+09:00 I: MetricsSink: stopping service goroutine.
2015-11-17T18:51:18+09:00 I: Removing /tmp/TestClientOperations1732476877...
2015-11-17T18:51:18+09:00 I: Removing /tmp/TestClientOperations2836375240...
2015-11-17T18:51:18+09:00 I: Finished closing MiniHTraced TestClientOperations
{noformat}
> TestClientOperations should wait for spans to be written before querying them
> -----------------------------------------------------------------------------
>
> Key: HTRACE-301
> URL: https://issues.apache.org/jira/browse/HTRACE-301
> Project: HTrace
> Issue Type: Bug
> Components: htraced
> Reporter: Masatake Iwasaki
> Assignee: Masatake Iwasaki
> Priority: Minor
>
> TestClientOperations intermittently fails due to missing span. According to
> the test log, FindSpan was called before the span is written to datastore.
--
This message was sent by Atlassian JIRA
(v6.3.4#6332)
