[==========] 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 06:37:39.082151  1994 thread.cc:527] Waited for 1000ms trying to join with gen-traces (tid 2002)
W20260812 06:37:40.082444  1994 thread.cc:527] Waited for 2000ms trying to join with gen-traces (tid 2002)
W20260812 06:37:41.082669  1994 thread.cc:527] Waited for 3000ms trying to join with gen-traces (tid 2002)
W20260812 06:37:42.082907  1994 thread.cc:527] Waited for 4000ms trying to join with gen-traces (tid 2002)
W20260812 06:37:43.083155  1994 thread.cc:527] Waited for 5000ms trying to join with gen-traces (tid 2002)
W20260812 06:37:44.083380  1994 thread.cc:527] Waited for 6000ms trying to join with gen-traces (tid 2002)
I20260812 06:37:44.310949  1994 trace-test.cc:187] Trace performance: 642122 traces/sec
[       OK ] TraceTest.TestChromeTracing (6868 ms)
[ RUN      ] TraceTest.TestTraceFromExitedThread
I20260812 06:37:44.950799  1994 trace-test.cc:215] {"traceEvents": [
{"cat":"test","pid":1994,"tid":-2067937536,"ts":1947081677944,"ph":"X","name":"foo","args":{"thread_id":1},"dur":94,"tdur":88,"tts":88},{"cat":"test","pid":1994,"tid":-2067937536,"ts":1947081678040,"ph":"X","name":"foo","args":{"thread_id":1},"dur":3,"tdur":2,"tts":180},{"cat":"test","pid":1994,"tid":-2067937536,"ts":1947081678044,"ph":"X","name":"foo","args":{"thread_id":1},"dur":3,"tdur":2,"tts":184},{"cat":"test","pid":1994,"tid":-2067937536,"ts":1947081678047,"ph":"X","name":"foo","args":{"thread_id":1},"dur":3,"tdur":2,"tts":187},{"cat":"test","pid":1994,"tid":-2067937536,"ts":1947081678050,"ph":"X","name":"foo","args":{"thread_id":1},"dur":3,"tdur":1,"tts":191},{"cat":"test","pid":1994,"tid":-2067937536,"ts":1947081678054,"ph":"X","name":"foo","args":{"thread_id":1},"dur":3,"tdur":2,"tts":194},{"cat":"test","pid":1994,"tid":-2067937536,"ts":1947081678057,"ph":"X","name":"foo","args":{"thread_id":1},"dur":3,"tdur":2,"tts":197},{"cat":"test","pid":1994,"tid":-2067937536,"ts":1947081678060,"ph":"X","name":"foo","args":{"thread_id":1},"dur":3,"tdur":1,"tts":201},{"cat":"test","pid":1994,"tid":-2067937536,"ts":1947081678064,"ph":"X","name":"foo","args":{"thread_id":1},"dur":3,"tdur":2,"tts":204},{"cat":"test","pid":1994,"tid":-2067937536,"ts":1947081678067,"ph":"X","name":"foo","args":{"thread_id":1},"dur":3,"tdur":2,"tts":207},{"cat":"__metadata","pid":1994,"tid":0,"ts":0,"ph":"M","name":"num_cpus","args":{"number":4},"tts":0},{"cat":"__metadata","pid":1994,"tid":-2067937536,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces"},"tts":0},{"cat":"__metadata","pid":1994,"tid":-2042759424,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces"},"tts":0},{"cat":"__metadata","pid":1994,"tid":-2059544832,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces"},"tts":0},{"cat":"__metadata","pid":1994,"tid":-2051152128,"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 (1776 ms)
[ RUN      ] TraceTest.TestChromeSampling
[       OK ] TraceTest.TestChromeSampling (111 ms)
[ RUN      ] TraceTest.TestVLogTrace
I20260812 06:37:46.853740  1994 trace-test.cc:815] hello world
[       OK ] TraceTest.TestVLogTrace (1 ms)
[ RUN      ] TraceTest.TestVLogTraceLazyEvaluation
I20260812 06:37:46.854329  1994 trace-test.cc:841] function-result
[       OK ] TraceTest.TestVLogTraceLazyEvaluation (0 ms)
[ RUN      ] TraceTest.TestVLogAndEchoToConsole
I20260812 06:37:46.854872  1994 trace-test.cc:851] hello world
E20260812 06:37:46.855050  1994 trace_event_impl.cc:1892] : [0;31mvlog[test], {msg:"I20260812 06:37:46.854872  1994 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 (51 ms)
[----------] 14 tests from TraceTest (8929 ms total)

[----------] 7 tests from TraceEventCallbackTest
[ RUN      ] TraceEventCallbackTest.TraceEventCallback
[       OK ] TraceEventCallbackTest.TraceEventCallback (5 ms)
[ RUN      ] TraceEventCallbackTest.TraceEventCallbackWhileFull
[       OK ] TraceEventCallbackTest.TraceEventCallbackWhileFull (913 ms)
[ RUN      ] TraceEventCallbackTest.TraceEventCallbackAndRecording1
I20260812 06:37:47.927673  1994 trace-test.cc:368] {"traceEvents": [
{"cat":"recording","pid":1994,"tid":-1938658048,"ts":1947084654993,"ph":"i","name":"yes","args":{},"tts":2918487,"s":"g"},{"cat":"recording","pid":1994,"tid":-1938658048,"ts":1947084655060,"ph":"i","name":"yes","args":{},"tts":2918555,"s":"g"},{"cat":"__metadata","pid":1994,"tid":0,"ts":0,"ph":"M","name":"num_cpus","args":{"number":4},"tts":0},{"cat":"__metadata","pid":1994,"tid":-2067937536,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces,sampler"},"tts":0},{"cat":"__metadata","pid":1994,"tid":-2042759424,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces"},"tts":0},{"cat":"__metadata","pid":1994,"tid":-2059544832,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces"},"tts":0},{"cat":"__metadata","pid":1994,"tid":-2051152128,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces"},"tts":0}]}
[       OK ] TraceEventCallbackTest.TraceEventCallbackAndRecording1 (0 ms)
[ RUN      ] TraceEventCallbackTest.TraceEventCallbackAndRecording2
I20260812 06:37:47.928577  1994 trace-test.cc:368] {"traceEvents": [
{"cat":"recording","pid":1994,"tid":-1938658048,"ts":1947084655883,"ph":"i","name":"yes","args":{},"tts":2919378,"s":"g"},{"cat":"__metadata","pid":1994,"tid":0,"ts":0,"ph":"M","name":"num_cpus","args":{"number":4},"tts":0},{"cat":"__metadata","pid":1994,"tid":-2067937536,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces,sampler"},"tts":0},{"cat":"__metadata","pid":1994,"tid":-2042759424,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces"},"tts":0},{"cat":"__metadata","pid":1994,"tid":-2059544832,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces"},"tts":0},{"cat":"__metadata","pid":1994,"tid":-2051152128,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces"},"tts":0}]}
[       OK ] TraceEventCallbackTest.TraceEventCallbackAndRecording2 (0 ms)
[ RUN      ] TraceEventCallbackTest.TraceEventCallbackAndRecording3
I20260812 06:37:47.929435  1994 trace-test.cc:368] {"traceEvents": [
{"cat":"recording","pid":1994,"tid":-1938658048,"ts":1947084656635,"ph":"i","name":"yes","args":{},"tts":2920130,"s":"g"},{"cat":"recording","pid":1994,"tid":-1938658048,"ts":1947084656745,"ph":"i","name":"yes","args":{},"tts":2920239,"s":"g"},{"cat":"recording","pid":1994,"tid":-1938658048,"ts":1947084656838,"ph":"i","name":"yes","args":{},"tts":2920332,"s":"g"},{"cat":"__metadata","pid":1994,"tid":0,"ts":0,"ph":"M","name":"num_cpus","args":{"number":4},"tts":0},{"cat":"__metadata","pid":1994,"tid":-2067937536,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces,sampler"},"tts":0},{"cat":"__metadata","pid":1994,"tid":-2042759424,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces"},"tts":0},{"cat":"__metadata","pid":1994,"tid":-2059544832,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces"},"tts":0},{"cat":"__metadata","pid":1994,"tid":-2051152128,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces"},"tts":0}]}
[       OK ] TraceEventCallbackTest.TraceEventCallbackAndRecording3 (0 ms)
[ RUN      ] TraceEventCallbackTest.TraceEventCallbackAndRecording4
I20260812 06:37:47.930269  1994 trace-test.cc:368] {"traceEvents": [
{"cat":"recording","pid":1994,"tid":-1938658048,"ts":1947084657500,"ph":"i","name":"yes","args":{},"tts":2920995,"s":"g"},{"cat":"recording","pid":1994,"tid":-1938658048,"ts":1947084657616,"ph":"i","name":"yes","args":{},"tts":2921110,"s":"g"},{"cat":"__metadata","pid":1994,"tid":0,"ts":0,"ph":"M","name":"num_cpus","args":{"number":4},"tts":0},{"cat":"__metadata","pid":1994,"tid":-2067937536,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces,sampler"},"tts":0},{"cat":"__metadata","pid":1994,"tid":-2042759424,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces"},"tts":0},{"cat":"__metadata","pid":1994,"tid":-2059544832,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces"},"tts":0},{"cat":"__metadata","pid":1994,"tid":-2051152128,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces"},"tts":0}]}
[       OK ] TraceEventCallbackTest.TraceEventCallbackAndRecording4 (0 ms)
[ RUN      ] TraceEventCallbackTest.TraceEventCallbackAndRecordingDuration
I20260812 06:37:47.931120  1994 trace-test.cc:368] {"traceEvents": [
{"cat":"callback","pid":1994,"tid":-1938658048,"ts":1947084658479,"ph":"X","name":"duration2","args":{},"tts":2921974},{"cat":"__metadata","pid":1994,"tid":0,"ts":0,"ph":"M","name":"num_cpus","args":{"number":4},"tts":0},{"cat":"__metadata","pid":1994,"tid":-2067937536,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces,sampler"},"tts":0},{"cat":"__metadata","pid":1994,"tid":-2042759424,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces"},"tts":0},{"cat":"__metadata","pid":1994,"tid":-2059544832,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces"},"tts":0},{"cat":"__metadata","pid":1994,"tid":-2051152128,"ts":0,"ph":"M","name":"thread_name","args":{"name":"gen-traces"},"tts":0}]}
[       OK ] TraceEventCallbackTest.TraceEventCallbackAndRecordingDuration (0 ms)
[----------] 7 tests from TraceEventCallbackTest (923 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. (9856 ms total)
[  PASSED  ] 31 tests.
