[==========] Running 31 tests from 3 test suites.
[----------] Global test environment set-up.
[----------] 14 tests from TraceTest
[ RUN      ] TraceTest.TestBasic
[       OK ] TraceTest.TestBasic (1 ms)
[ RUN      ] TraceTest.TestAttach
[       OK ] TraceTest.TestAttach (0 ms)
[ RUN      ] TraceTest.TestChildTrace
[       OK ] TraceTest.TestChildTrace (0 ms)
[ RUN      ] TraceTest.TestChromeTracing
WARNING: Logging before InitGoogleLogging() is written to STDERR
W20260812 08:03:55.087949 16106 thread.cc:527] Waited for 1000ms trying to join with gen-traces (tid 16113)
W20260812 08:03:56.088269 16106 thread.cc:527] Waited for 2000ms trying to join with gen-traces (tid 16113)
W20260812 08:03:57.088521 16106 thread.cc:527] Waited for 3000ms trying to join with gen-traces (tid 16113)
W20260812 08:03:58.088758 16106 thread.cc:527] Waited for 4000ms trying to join with gen-traces (tid 16113)
W20260812 08:03:59.088979 16106 thread.cc:527] Waited for 5000ms trying to join with gen-traces (tid 16113)
W20260812 08:04:00.089210 16106 thread.cc:527] Waited for 6000ms trying to join with gen-traces (tid 16113)
I20260812 08:04:00.496095 16106 trace-test.cc:187] Trace performance: 624153 traces/sec
[       OK ] TraceTest.TestChromeTracing (7059 ms)
[ RUN      ] TraceTest.TestTraceFromExitedThread
I20260812 08:04:01.147728 16106 trace-test.cc:215] {"traceEvents": [
{"cat":"test","pid":16106,"tid":-1355213056,"ts":1952394768489,"ph":"X","name":"foo","args":{"thread_id":1},"dur":95,"tdur":90,"tts":85},{"cat":"test","pid":16106,"tid":-1355213056,"ts":1952394768586,"ph":"X","name":"foo","args":{"thread_id":1},"dur":3,"tdur":3,"tts":178},{"cat":"test","pid":16106,"tid":-1355213056,"ts":1952394768590,"ph":"X","name":"foo","args":{"thread_id":1},"dur":3,"tdur":2,"tts":182},{"cat":"test","pid":16106,"tid":-1355213056,"ts":1952394768593,"ph":"X","name":"foo","args":{"thread_id":1},"dur":3,"tdur":1,"tts":186},{"cat":"test","pid":16106,"tid":-1355213056,"ts":1952394768597,"ph":"X","name":"foo","args":{"thread_id":1},"dur":3,"tdur":2,"tts":189},{"cat":"test","pid":16106,"tid":-1355213056,"ts":1952394768600,"ph":"X","name":"foo","args":{"thread_id":1},"dur":3,"tdur":1,"tts":193},{"cat":"test","pid":16106,"tid":-1355213056,"ts":1952394768603,"ph":"X","name":"foo","args":{"thread_id":1},"dur":3,"tdur":2,"tts":196},{"cat":"test","pid":16106,"tid":-1355213056,"ts":1952394768607,"ph":"X","name":"foo","args":{"thread_id":1},"dur":3,"tdur":2,"tts":199},{"cat":"test","pid":16106,"tid":-1355213056,"ts":1952394768610,"ph":"X","name":"foo","args":{"thread_id":1},"dur":3,"tdur":1,"tts":203},{"cat":"test","pid":16106,"tid":-1355213056,"ts":1952394768613,"ph":"X","name":"foo","args":{"thread_id":1},"dur":3,"tdur":2,"tts":206},{"cat":"__metadata","pid":16106,"tid":0,"ts":0,"ph":"M","name":"num_cpus","args":{"number":4},"tts":0},{"cat":"__metadata","pid":16106,"tid":-1338427648,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces"},"tts":0},{"cat":"__metadata","pid":16106,"tid":-1346820352,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces"},"tts":0},{"cat":"__metadata","pid":16106,"tid":-1355213056,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces"},"tts":0},{"cat":"__metadata","pid":16106,"tid":-1330034944,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces"},"tts":0}]}
[       OK ] TraceTest.TestTraceFromExitedThread (1 ms)
[ RUN      ] TraceTest.TestWideSpan
[       OK ] TraceTest.TestWideSpan (12 ms)
[ RUN      ] TraceTest.TestJsonEncodingString
[       OK ] TraceTest.TestJsonEncodingString (0 ms)
[ RUN      ] TraceTest.TestStartAndStopCollection
[       OK ] TraceTest.TestStartAndStopCollection (1762 ms)
[ RUN      ] TraceTest.TestChromeSampling
[       OK ] TraceTest.TestChromeSampling (112 ms)
[ RUN      ] TraceTest.TestVLogTrace
I20260812 08:04:03.036916 16106 trace-test.cc:815] hello world
[       OK ] TraceTest.TestVLogTrace (0 ms)
[ RUN      ] TraceTest.TestVLogTraceLazyEvaluation
I20260812 08:04:03.037412 16106 trace-test.cc:841] function-result
[       OK ] TraceTest.TestVLogTraceLazyEvaluation (0 ms)
[ RUN      ] TraceTest.TestVLogAndEchoToConsole
I20260812 08:04:03.037827 16106 trace-test.cc:851] hello world
E20260812 08:04:03.037962 16106 trace_event_impl.cc:1892] : [0;31mvlog[test], {msg:"I20260812 08:04:03.037827 16106 trace-test.cc:851] hello world"}[0;m
[       OK ] TraceTest.TestVLogAndEchoToConsole (0 ms)
[ RUN      ] TraceTest.TestTraceMetrics
[       OK ] TraceTest.TestTraceMetrics (101 ms)
[ RUN      ] TraceTest.TestTraceFromVanillaThreads
[       OK ] TraceTest.TestTraceFromVanillaThreads (52 ms)
[----------] 14 tests from TraceTest (9107 ms total)

[----------] 7 tests from TraceEventCallbackTest
[ RUN      ] TraceEventCallbackTest.TraceEventCallback
[       OK ] TraceEventCallbackTest.TraceEventCallback (6 ms)
[ RUN      ] TraceEventCallbackTest.TraceEventCallbackWhileFull
[       OK ] TraceEventCallbackTest.TraceEventCallbackWhileFull (983 ms)
[ RUN      ] TraceEventCallbackTest.TraceEventCallbackAndRecording1
I20260812 08:04:04.182729 16106 trace-test.cc:368] {"traceEvents": [
{"cat":"recording","pid":16106,"tid":-1225933568,"ts":1952397803626,"ph":"i","name":"yes","args":{},"tts":2992106,"s":"g"},{"cat":"recording","pid":16106,"tid":-1225933568,"ts":1952397803695,"ph":"i","name":"yes","args":{},"tts":2992174,"s":"g"},{"cat":"__metadata","pid":16106,"tid":0,"ts":0,"ph":"M","name":"num_cpus","args":{"number":4},"tts":0},{"cat":"__metadata","pid":16106,"tid":-1338427648,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces"},"tts":0},{"cat":"__metadata","pid":16106,"tid":-1346820352,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces"},"tts":0},{"cat":"__metadata","pid":16106,"tid":-1355213056,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces,sampler"},"tts":0},{"cat":"__metadata","pid":16106,"tid":-1330034944,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces"},"tts":0}]}
[       OK ] TraceEventCallbackTest.TraceEventCallbackAndRecording1 (0 ms)
[ RUN      ] TraceEventCallbackTest.TraceEventCallbackAndRecording2
I20260812 08:04:04.183579 16106 trace-test.cc:368] {"traceEvents": [
{"cat":"recording","pid":16106,"tid":-1225933568,"ts":1952397804522,"ph":"i","name":"yes","args":{},"tts":2993002,"s":"g"},{"cat":"__metadata","pid":16106,"tid":0,"ts":0,"ph":"M","name":"num_cpus","args":{"number":4},"tts":0},{"cat":"__metadata","pid":16106,"tid":-1338427648,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces"},"tts":0},{"cat":"__metadata","pid":16106,"tid":-1346820352,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces"},"tts":0},{"cat":"__metadata","pid":16106,"tid":-1355213056,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces,sampler"},"tts":0},{"cat":"__metadata","pid":16106,"tid":-1330034944,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces"},"tts":0}]}
[       OK ] TraceEventCallbackTest.TraceEventCallbackAndRecording2 (0 ms)
[ RUN      ] TraceEventCallbackTest.TraceEventCallbackAndRecording3
I20260812 08:04:04.184358 16106 trace-test.cc:368] {"traceEvents": [
{"cat":"recording","pid":16106,"tid":-1225933568,"ts":1952397805164,"ph":"i","name":"yes","args":{},"tts":2993644,"s":"g"},{"cat":"recording","pid":16106,"tid":-1225933568,"ts":1952397805287,"ph":"i","name":"yes","args":{},"tts":2993767,"s":"g"},{"cat":"recording","pid":16106,"tid":-1225933568,"ts":1952397805346,"ph":"i","name":"yes","args":{},"tts":2993825,"s":"g"},{"cat":"__metadata","pid":16106,"tid":0,"ts":0,"ph":"M","name":"num_cpus","args":{"number":4},"tts":0},{"cat":"__metadata","pid":16106,"tid":-1338427648,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces"},"tts":0},{"cat":"__metadata","pid":16106,"tid":-1346820352,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces"},"tts":0},{"cat":"__metadata","pid":16106,"tid":-1355213056,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces,sampler"},"tts":0},{"cat":"__metadata","pid":16106,"tid":-1330034944,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces"},"tts":0}]}
[       OK ] TraceEventCallbackTest.TraceEventCallbackAndRecording3 (0 ms)
[ RUN      ] TraceEventCallbackTest.TraceEventCallbackAndRecording4
I20260812 08:04:04.185103 16106 trace-test.cc:368] {"traceEvents": [
{"cat":"recording","pid":16106,"tid":-1225933568,"ts":1952397805911,"ph":"i","name":"yes","args":{},"tts":2994391,"s":"g"},{"cat":"recording","pid":16106,"tid":-1225933568,"ts":1952397806022,"ph":"i","name":"yes","args":{},"tts":2994501,"s":"g"},{"cat":"__metadata","pid":16106,"tid":0,"ts":0,"ph":"M","name":"num_cpus","args":{"number":4},"tts":0},{"cat":"__metadata","pid":16106,"tid":-1338427648,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces"},"tts":0},{"cat":"__metadata","pid":16106,"tid":-1346820352,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces"},"tts":0},{"cat":"__metadata","pid":16106,"tid":-1355213056,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces,sampler"},"tts":0},{"cat":"__metadata","pid":16106,"tid":-1330034944,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces"},"tts":0}]}
[       OK ] TraceEventCallbackTest.TraceEventCallbackAndRecording4 (0 ms)
[ RUN      ] TraceEventCallbackTest.TraceEventCallbackAndRecordingDuration
I20260812 08:04:04.185986 16106 trace-test.cc:368] {"traceEvents": [
{"cat":"callback","pid":16106,"tid":-1225933568,"ts":1952397806947,"ph":"X","name":"duration2","args":{},"tts":2995427},{"cat":"__metadata","pid":16106,"tid":0,"ts":0,"ph":"M","name":"num_cpus","args":{"number":4},"tts":0},{"cat":"__metadata","pid":16106,"tid":-1338427648,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces"},"tts":0},{"cat":"__metadata","pid":16106,"tid":-1346820352,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces"},"tts":0},{"cat":"__metadata","pid":16106,"tid":-1355213056,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces,sampler"},"tts":0},{"cat":"__metadata","pid":16106,"tid":-1330034944,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces"},"tts":0}]}
[       OK ] TraceEventCallbackTest.TraceEventCallbackAndRecordingDuration (0 ms)
[----------] 7 tests from TraceEventCallbackTest (994 ms total)

[----------] 10 tests from TraceEventSyntheticDelayTest
[ RUN      ] TraceEventSyntheticDelayTest.StaticDelay
[       OK ] TraceEventSyntheticDelayTest.StaticDelay (0 ms)
[ RUN      ] TraceEventSyntheticDelayTest.OneShotDelay
[       OK ] TraceEventSyntheticDelayTest.OneShotDelay (0 ms)
[ RUN      ] TraceEventSyntheticDelayTest.AlternatingDelay
[       OK ] TraceEventSyntheticDelayTest.AlternatingDelay (0 ms)
[ RUN      ] TraceEventSyntheticDelayTest.AsyncDelay
[       OK ] TraceEventSyntheticDelayTest.AsyncDelay (0 ms)
[ RUN      ] TraceEventSyntheticDelayTest.AsyncDelayExceeded
[       OK ] TraceEventSyntheticDelayTest.AsyncDelayExceeded (0 ms)
[ RUN      ] TraceEventSyntheticDelayTest.AsyncDelayNoActivation
[       OK ] TraceEventSyntheticDelayTest.AsyncDelayNoActivation (0 ms)
[ RUN      ] TraceEventSyntheticDelayTest.AsyncDelayNested
[       OK ] TraceEventSyntheticDelayTest.AsyncDelayNested (0 ms)
[ RUN      ] TraceEventSyntheticDelayTest.AsyncDelayUnbalanced
[       OK ] TraceEventSyntheticDelayTest.AsyncDelayUnbalanced (0 ms)
[ RUN      ] TraceEventSyntheticDelayTest.ResetDelays
[       OK ] TraceEventSyntheticDelayTest.ResetDelays (0 ms)
[ RUN      ] TraceEventSyntheticDelayTest.BeginParallel
[       OK ] TraceEventSyntheticDelayTest.BeginParallel (0 ms)
[----------] 10 tests from TraceEventSyntheticDelayTest (3 ms total)

[----------] Global test environment tear-down
[==========] 31 tests from 3 test suites ran. (10105 ms total)
[  PASSED  ] 31 tests.
