[KUDU-2719] TraceEventCallbackTest.TraceEventCallbackAndRecordingDuration is flaky

• Type: Bug
• Status: Resolved
• Priority: Major
• Resolution: Cannot Reproduce
• Affects Version/s: 1.9.0
• Fix Version/s: NA
• Component/s: test
• Assignee: Unassigned
• Reporter: Adar Dembo
• Created: 28/Feb/19 02:37
• Updated: 03/Jun/20 18:12
• Resolved: 03/Jun/20 18:12

Description

Saw this while testing 1.9.0 on an el7 box. I was running the ctest -j`nproc` in slow mode.

[ RUN      ] TraceEventCallbackTest.TraceEventCallbackAndRecordingDuration
I0227 17:35:24.391515 20474 trace-test.cc:360] {"traceEvents": [
{"cat":"callback","pid":20474,"tid":-2080536576,"ts":58585214259738,"ph":"X","name":"duration2","a
rgs":{},"tts":3231804},{"cat":"__metadata","pid":20474,"tid":0,"ts":0,"ph":"M","name":"num_cpus","
args":{"number":88},"tts":0},{"cat":"__metadata","pid":20474,"tid":2134910720,"ts":0,"ph":"M","nam
e":"thread_name","args":{"name":"kernel-watcher"},"tts":0},{"cat":"__metadata","pid":20474,"tid":2
101339904,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces,sampler"},"tts":0},{"cat
":"__metadata","pid":20474,"tid":2109732608,"ts":0,"ph":"M","name":"thread_name","args":{"name":"g
en-traces"},"tts":0},{"cat":"__metadata","pid":20474,"tid":2126518016,"ts":0,"ph":"M","name":"thre
ad_name","args":{"name":"gen-traces"},"tts":0},{"cat":"__metadata","pid":20474,"tid":2118125312,"t
s":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces"},"tts":0}]}
/data/1/apache-kudu-1.9.0/src/kudu/util/trace-test.cc:635: Failure
      Expected: 6u
      Which is: 6
To be equal to: collected_events_names_.size()
      Which is: 8

Issue Links

• is related to: KUDU-2556 TraceEventCallbackTest.TraceEventCallbackWhileFull is flake in ASAN