Back to build
Raw log | Switch to full mode | Login

{ "shards": [ { "bot_dimensions": [ { "key": "android_devices", "value": [ "1" ] }, { "key": "caches", "value": [ "swarming_module_cache_vpython" ] }, { "key": "cpu_governor", "value": [ "powersave" ] }, { "key": "device_gms_core_version", "value": [ "6.7.79" ] }, { "key": "device_os", "value": [ "L", "LMY48I" ] }, { "key": "device_os_flavor", "value": [ "google" ] }, { "key": "device_playstore_version", "value": [ "5.2.13" ] }, { "key": "device_type", "value": [ "hammerhead" ] }, { "key": "gce", "value": [ "0" ] }, { "key": "id", "value": [ "build134-b1--device6" ] }, { "key": "inside_docker", "value": [ "1", "stock" ] }, { "key": "locale", "value": [ "en_US.UTF-8" ] }, { "key": "os", "value": [ "Android" ] }, { "key": "pool", "value": [ "Chrome" ] }, { "key": "python", "value": [ "2.7.12" ] }, { "key": "server_version", "value": [ "4267-53b17d5" ] }, { "key": "temp_band", "value": [ "<30" ] }, { "key": "zone", "value": [ "us", "us-mtv", "us-mtv-chops", "us-mtv-chops-b", "us-mtv-chops-b-1" ] } ], "bot_id": "build134-b1--device6", "bot_version": "bd68e04e43fc4d1b7b5b65890a29c5d5bae55ab1423851733f92abd7e13dda14", "cipd_pins": { "client_package": { "package_name": "infra/tools/cipd/linux-amd64", "version": "nFftZl2kFquaI2EUmPGwzbc5sIbCocl9L55UeASqlLsC" }, "packages": [ { "package_name": "infra/python/cpython/linux-amd64", "path": ".swarming_module", "version": "46c0c897ca0f053799ee41fd148bb7a47232df47" }, { "package_name": "infra/tools/luci/logdog/butler/linux-amd64", "path": ".swarming_module", "version": "2737ea8ed9b958f4d5aa9ffe106115a649ada241" }, { "package_name": "infra/tools/luci/vpython-native/linux-amd64", "path": ".swarming_module", "version": "tfTmX4aGCfFtfQucj_nEfwiNisK0RmpKrITkZMBBq3cC" }, { "package_name": "infra/tools/luci/vpython/linux-amd64", "path": ".swarming_module", "version": "bmnYyAg9mbM_538s1dIP1hZZmOtc8C7ssF41ztN2cr0C" }, { "package_name": "infra/tools/luci/logdog/butler/linux-amd64", "path": "bin", "version": "a57ad614c01fec9fa4259473c8ea3fd992d7c349" } ] }, "completed_ts": "2019-04-23T14:47:07.432082", "cost_saved_usd": 0.0033191223180660955, "created_ts": "2019-04-23T15:29:35.943568", "deduped_from": "446329366c8c9311", "duration": 19.398934841156006, "modified_ts": "2019-04-23T15:29:35.954271", "name": "latency_unittests on Android device Nexus 5/Android/52dd2302cd/Lollipop Phone Tester/21521", "output": "/b/swarming/swarming_bot.1.zip/third_party/requests/__init__.py:83: RequestsDependencyWarning: Old version of cryptography ([1, 2, 3]) may cause slowdown.\nAdditional test environment:\n CHROME_DEVEL_SANDBOX=/opt/chromium/chrome_sandbox\n CHROME_HEADLESS=1\n LANG=en_US.UTF-8\nCommand: /b/swarming/w/ir/.swarming_module_cache/vpython/3bcbb9/bin/python ../../build/android/test_wrapper/logdog_wrapper.py --target latency_unittests --logdog-bin-cmd ../../bin/logdog_butler --store-tombstones --gs-results-bucket=chromium-result-details --recover-devices --test-launcher-summary-output=/b/swarming/w/iozreFPq/output.json\n\nI 0.000s Main command: bin/../../../build/android/test_runner.py gtest --output-directory bin/../. --runtime-deps-path bin/../gen.runtime/ui/latency/latency_unittests__test_runner_script.runtime_deps --suite latency_unittests -v --store-tombstones --gs-results-bucket=chromium-result-details --recover-devices --test-launcher-summary-output=/b/swarming/w/iozreFPq/output.json\nI 0.006s Main condition '<lambda>' met\nI 0.008s Main condition '<lambda>' met\nI 3.129s TimeoutThread-1-for-prepare_device(027d5cd80ac5a2e3) condition 'sd_card_ready' met (0.0s)\nI 3.401s TimeoutThread-1-for-prepare_device(027d5cd80ac5a2e3) condition 'pm_ready' met (0.3s)\nI 3.418s TimeoutThread-1-for-prepare_device(027d5cd80ac5a2e3) condition 'boot_completed' met (0.3s)\nI 10.244s calculate_device_checksums condition '<lambda>' met (0.1s)\nI 11.694s list_tests(027d5cd80ac5a2e3) flags:\nI 11.694s list_tests(027d5cd80ac5a2e3) --gtest_list_tests\nI 12.863s list_tests(027d5cd80ac5a2e3) /storage/emulated/legacy/temp_file-20bdacbb0e3ae.gtest_out size on device: 2455\nI 12.999s Main Using external sharding settings. This is shard 0/1\nI 12.999s Main STARTING TRY #1/3\nI 12.999s Main Will run 79 tests on 1 devices: 027d5cd80ac5a2e3\nW 13.066s run_tests_on_device(027d5cd80ac5a2e3) No tombstones to clear.\nI 14.064s run_tests_on_device(027d5cd80ac5a2e3) flags:\nI 14.065s run_tests_on_device(027d5cd80ac5a2e3) Getting logdog client.\nI 16.387s run_tests_on_device(027d5cd80ac5a2e3) /storage/emulated/legacy/temp_file-414de11241e20.gtest_out size on device: 15558\nI 16.519s run_tests_on_device(027d5cd80ac5a2e3) Logcat saved to https://luci-logdog.appspot.com/v/?s=chromium%2Fandroid%2Fswarming%2Flogcats%2F446329366c8c9311%2F%2B%2Flogcat_logcat_-8211614896469903446_20190423T144702-UTC_027d5cd80ac5a2e3\nI 16.519s archive Writing text to logdog stream, logcat_logcat_-8211614896469903446_20190423T144702-UTC_027d5cd80ac5a2e3\nI 16.885s run_tests_on_device(027d5cd80ac5a2e3) >>ScopedMainEntryLogger\nI 16.885s run_tests_on_device(027d5cd80ac5a2e3) Note: Google Test filter = AverageLagTrackerTest.ChangeDirectionInFrame:AverageLagTrackerTest.ImperfectScrollPrediction:AverageLagTrackerTest.LargerLatency:AverageLagTrackerTest.NoScrollPrediction:AverageLagTrackerTest.OneSecondInterval:AverageLagTrackerTest.ScrollPrediction:AverageLagTrackerTest.TwoLatencyInfoInSameFrame:FrameMetricsFixedPointTest.Accumulator96bAddSub:FrameMetricsFixedPointTest.Accumulator96bConstructor:FrameMetricsFixedPointTest.Accumulator96bPrecision:FrameMetricsFixedPointTest.kFixedPointMultiplier:FrameMetricsFixedPointTest.kFixedPointRootMultiplier:FrameMetricsFixedPointTest.kFixedPointRootMultiplierSqrt:FrameMetricsFixedPointTest.kFixedPointRootShift:FrameMetricsHistogramsTest.RatioBoundaries:FrameMetricsHistogramsTest.RatioBoundariesDirect:FrameMetricsHistogramsTest.RatioPercentiles50th:FrameMetricsHistogramsTest.RatioPercentiles99th:FrameMetricsHistogramsTest.ResultsAreNonZeroWithSamplesOfZero:FrameMetricsHistogramsTest.ResultsAreZeroWithoutSamples:FrameMetricsHistogramsTest.VSyncBoundaries:FrameMetricsHistogramsTest.VSyncBoundariesDirect:FrameMetricsHistogramsTest.VSyncPercentiles50th:FrameMetricsHistogramsTest.VSyncPercentiles99th:FrameMetricsTest.AccelerationThresholds:FrameMetricsTest.LatencyAccelerationSaturatesOnOverflow:FrameMetricsTest.LatencySaturatesOnOverflow:FrameMetricsTest.LatencySpeedSaturatesOnOverflow:FrameMetricsTest.LatencyThresholds:FrameMetricsTest.PercentilesLatencyBasic:FrameMetricsTest.PercentilesSkipBasic:FrameMetricsTest.PerfectLatencyScores:FrameMetricsTest.PerfectSmoothnessScores:FrameMetricsTest.SawToothShapedLatency:FrameMetricsTest.SawToothShapedSkips:FrameMetricsTest.SkipSaturatesOnOverflow:FrameMetricsTest.SkipThresholds:FrameMetricsTest.SpeedThresholds:FrameMetricsTest.SquareRootApproximation:FrameMetricsTest.StartNewReportPeriodAvoidsOverflowForAcceleration:FrameMetricsTest.StartNewReportPeriodAvoidsOverflowForLatency:FrameMetricsTest.StartNewReportPeriodAvoidsOverflowForSkips:FrameMetricsTest.StartNewReportPeriodAvoidsOverflowForSpeed:FrameMetricsTest.WorstWindowsRangesUpdateCorrectly:FrameMetricsWindowedAnalyzerTest.AllResultsDifferent:FrameMetricsWindowedAnalyzerTest.AllResultsTheSame:FrameMetricsWindowedAnalyzerTest.BadFirstSamples:FrameMetricsWindowedAnalyzerTest.NoAccumulatedPrecisionErrorBasic:FrameMetricsWindowedAnalyzerTest.NoAccumulatedPrecisionErrorBig:FrameMetricsWindowedAnalyzerTest.NoAccumulatedPrecisionErrorBigger:FrameMetricsWindowedAnalyzerTest.ResetWorstValues:FrameMetricsWindowedAnalyzerTest.SmallSampleSize:LatencyInfoParamTraitsTest.Basic:LatencyInfoParamTraitsTest.InvalidData:LatencyInfoTest.AddTwoSeparateEvent:LatencyInfoTest.CoalesceTwoGSU:SkippedFrameTrackerTest.MultipleWillProduceBeforeDidProduce:SkippedFrameTrackerTest.NoFrameProduced:SkippedFrameTrackerTest.NoSkips_ActiveIdleActive_BeginThenWill:SkippedFrameTrackerTest.NoSkips_ActiveIdleActive_FramePulledAfterPush:SkippedFrameTrackerTest.NoSkips_ActiveIdleActive_FramePulledBeforeBF:SkippedFrameTrackerTest.NoSkips_ActiveIdleActive_FramePulledIsPush:SkippedFrameTrackerTest.NoSkips_ActiveIdleActive_JumpInIdle:SkippedFrameTrackerTest.NoSkips_ActiveIdleActive_WillThenBegin:SkippedFrameTrackerTest.NoSkips_BeginThenWill:SkippedFrameTrackerTest.NoSkips_WillThenBegin:SkippedFrameTrackerTest.PulledFramesNotRecorded:SkippedFrameTrackerTest.Skips_DroppedBeginFrames:SkippedFrameTrackerTest.Skips_DroppedBeginFrames_JitterRoundsDown:SkippedFrameTrackerTest.Skips_DroppedBeginFrames_JitterRoundsUp:SkippedFrameTrackerTest.Skips_ProducedOverMultipleBeginFrames:SkippedFrameTrackerTest.WillNotProduceFrame:SkippedFrameTrackerTest.WillNotProduceFrame2:StreamAnalyzerTest.AllResultsDifferent:StreamAnalyzerTest.AllResultsTheSame:StreamAnalyzerTest.PercentilesModifiedByClient:StreamAnalyzerTest.Precision:StreamAnalyzerTest.SamplesForwardedToHistogram:StructTraitsTest.LatencyInfo\nI 16.885s run_tests_on_device(027d5cd80ac5a2e3) [==========] Running 79 tests from 10 test suites.\nI 16.885s run_tests_on_device(027d5cd80ac5a2e3) [----------] Global test environment set-up.\nI 16.885s run_tests_on_device(027d5cd80ac5a2e3) [----------] 7 tests from AverageLagTrackerTest\nI 16.885s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] AverageLagTrackerTest.OneSecondInterval\nI 16.885s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] AverageLagTrackerTest.OneSecondInterval (2 ms)\nI 16.885s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] AverageLagTrackerTest.LargerLatency\nI 16.886s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] AverageLagTrackerTest.LargerLatency (0 ms)\nI 16.886s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] AverageLagTrackerTest.TwoLatencyInfoInSameFrame\nI 16.886s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] AverageLagTrackerTest.TwoLatencyInfoInSameFrame (1 ms)\nI 16.886s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] AverageLagTrackerTest.ChangeDirectionInFrame\nI 16.886s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] AverageLagTrackerTest.ChangeDirectionInFrame (1 ms)\nI 16.886s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] AverageLagTrackerTest.NoScrollPrediction\nI 16.886s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] AverageLagTrackerTest.NoScrollPrediction (0 ms)\nI 16.886s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] AverageLagTrackerTest.ScrollPrediction\nI 16.886s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] AverageLagTrackerTest.ScrollPrediction (1 ms)\nI 16.886s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] AverageLagTrackerTest.ImperfectScrollPrediction\nI 16.886s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] AverageLagTrackerTest.ImperfectScrollPrediction (1 ms)\nI 16.886s run_tests_on_device(027d5cd80ac5a2e3) [----------] 7 tests from AverageLagTrackerTest (6 ms total)\nI 16.886s run_tests_on_device(027d5cd80ac5a2e3) \nI 16.886s run_tests_on_device(027d5cd80ac5a2e3) [----------] 7 tests from FrameMetricsFixedPointTest\nI 16.886s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsFixedPointTest.kFixedPointMultiplier\nI 16.886s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsFixedPointTest.kFixedPointMultiplier (1 ms)\nI 16.886s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsFixedPointTest.kFixedPointRootMultiplier\nI 16.886s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsFixedPointTest.kFixedPointRootMultiplier (0 ms)\nI 16.886s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsFixedPointTest.kFixedPointRootMultiplierSqrt\nI 16.886s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsFixedPointTest.kFixedPointRootMultiplierSqrt (1 ms)\nI 16.886s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsFixedPointTest.kFixedPointRootShift\nI 16.886s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsFixedPointTest.kFixedPointRootShift (0 ms)\nI 16.887s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsFixedPointTest.Accumulator96bConstructor\nI 16.887s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsFixedPointTest.Accumulator96bConstructor (0 ms)\nI 16.887s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsFixedPointTest.Accumulator96bAddSub\nI 16.887s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsFixedPointTest.Accumulator96bAddSub (1 ms)\nI 16.887s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsFixedPointTest.Accumulator96bPrecision\nI 16.887s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsFixedPointTest.Accumulator96bPrecision (0 ms)\nI 16.887s run_tests_on_device(027d5cd80ac5a2e3) [----------] 7 tests from FrameMetricsFixedPointTest (4 ms total)\nI 16.887s run_tests_on_device(027d5cd80ac5a2e3) \nI 16.887s run_tests_on_device(027d5cd80ac5a2e3) [----------] 20 tests from FrameMetricsTest\nI 16.887s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsTest.PerfectSmoothnessScores\nI 16.887s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsTest.PerfectSmoothnessScores (6 ms)\nI 16.887s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsTest.PerfectLatencyScores\nI 16.887s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsTest.PerfectLatencyScores (6 ms)\nI 16.887s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsTest.SawToothShapedSkips\nI 16.887s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsTest.SawToothShapedSkips (11 ms)\nI 16.887s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsTest.SawToothShapedLatency\nI 16.887s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsTest.SawToothShapedLatency (14 ms)\nI 16.887s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsTest.SkipSaturatesOnOverflow\nI 16.887s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsTest.SkipSaturatesOnOverflow (13 ms)\nI 16.887s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsTest.LatencySaturatesOnOverflow\nI 16.887s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsTest.LatencySaturatesOnOverflow (11 ms)\nI 16.887s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsTest.LatencySpeedSaturatesOnOverflow\nI 16.888s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsTest.LatencySpeedSaturatesOnOverflow (30 ms)\nI 16.888s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsTest.LatencyAccelerationSaturatesOnOverflow\nI 16.888s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsTest.LatencyAccelerationSaturatesOnOverflow (32 ms)\nI 16.888s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsTest.SkipThresholds\nI 16.888s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsTest.SkipThresholds (66 ms)\nI 16.888s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsTest.LatencyThresholds\nI 16.888s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsTest.LatencyThresholds (64 ms)\nI 16.888s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsTest.SpeedThresholds\nI 16.888s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsTest.SpeedThresholds (131 ms)\nI 16.888s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsTest.AccelerationThresholds\nI 16.888s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsTest.AccelerationThresholds (133 ms)\nI 16.888s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsTest.PercentilesSkipBasic\nI 16.888s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsTest.PercentilesSkipBasic (0 ms)\nI 16.888s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsTest.PercentilesLatencyBasic\nI 16.888s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsTest.PercentilesLatencyBasic (1 ms)\nI 16.888s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsTest.WorstWindowsRangesUpdateCorrectly\nI 16.888s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsTest.WorstWindowsRangesUpdateCorrectly (6 ms)\nI 16.888s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsTest.StartNewReportPeriodAvoidsOverflowForSkips\nI 16.888s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsTest.StartNewReportPeriodAvoidsOverflowForSkips (1 ms)\nI 16.888s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsTest.StartNewReportPeriodAvoidsOverflowForLatency\nI 16.888s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsTest.StartNewReportPeriodAvoidsOverflowForLatency (29 ms)\nI 16.888s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsTest.StartNewReportPeriodAvoidsOverflowForSpeed\nI 16.888s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsTest.StartNewReportPeriodAvoidsOverflowForSpeed (23 ms)\nI 16.889s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsTest.StartNewReportPeriodAvoidsOverflowForAcceleration\nI 16.889s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsTest.StartNewReportPeriodAvoidsOverflowForAcceleration (22 ms)\nI 16.889s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsTest.SquareRootApproximation\nI 16.889s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsTest.SquareRootApproximation (1 ms)\nI 16.889s run_tests_on_device(027d5cd80ac5a2e3) [----------] 20 tests from FrameMetricsTest (602 ms total)\nI 16.889s run_tests_on_device(027d5cd80ac5a2e3) \nI 16.889s run_tests_on_device(027d5cd80ac5a2e3) [----------] 10 tests from FrameMetricsHistogramsTest\nI 16.889s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsHistogramsTest.RatioBoundariesDirect\nI 16.889s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsHistogramsTest.RatioBoundariesDirect (1 ms)\nI 16.889s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsHistogramsTest.VSyncBoundariesDirect\nI 16.889s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsHistogramsTest.VSyncBoundariesDirect (0 ms)\nI 16.889s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsHistogramsTest.ResultsAreZeroWithoutSamples\nI 16.889s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsHistogramsTest.ResultsAreZeroWithoutSamples (0 ms)\nI 16.889s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsHistogramsTest.ResultsAreNonZeroWithSamplesOfZero\nI 16.889s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsHistogramsTest.ResultsAreNonZeroWithSamplesOfZero (1 ms)\nI 16.889s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsHistogramsTest.RatioBoundaries\nI 16.889s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsHistogramsTest.RatioBoundaries (3 ms)\nI 16.889s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsHistogramsTest.VSyncBoundaries\nI 16.889s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsHistogramsTest.VSyncBoundaries (7 ms)\nI 16.889s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsHistogramsTest.RatioPercentiles50th\nI 16.889s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsHistogramsTest.RatioPercentiles50th (2 ms)\nI 16.889s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsHistogramsTest.RatioPercentiles99th\nI 16.889s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsHistogramsTest.RatioPercentiles99th (1 ms)\nI 16.890s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsHistogramsTest.VSyncPercentiles50th\nI 16.890s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsHistogramsTest.VSyncPercentiles50th (2 ms)\nI 16.890s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsHistogramsTest.VSyncPercentiles99th\nI 16.890s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsHistogramsTest.VSyncPercentiles99th (2 ms)\nI 16.890s run_tests_on_device(027d5cd80ac5a2e3) [----------] 10 tests from FrameMetricsHistogramsTest (21 ms total)\nI 16.890s run_tests_on_device(027d5cd80ac5a2e3) \nI 16.890s run_tests_on_device(027d5cd80ac5a2e3) [----------] 2 tests from LatencyInfoTest\nI 16.890s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] LatencyInfoTest.AddTwoSeparateEvent\nI 16.890s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] LatencyInfoTest.AddTwoSeparateEvent (0 ms)\nI 16.890s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] LatencyInfoTest.CoalesceTwoGSU\nI 16.890s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] LatencyInfoTest.CoalesceTwoGSU (1 ms)\nI 16.890s run_tests_on_device(027d5cd80ac5a2e3) [----------] 2 tests from LatencyInfoTest (1 ms total)\nI 16.890s run_tests_on_device(027d5cd80ac5a2e3) \nI 16.890s run_tests_on_device(027d5cd80ac5a2e3) [----------] 17 tests from SkippedFrameTrackerTest\nI 16.890s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] SkippedFrameTrackerTest.NoSkips_BeginThenWill\nI 16.890s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] SkippedFrameTrackerTest.NoSkips_BeginThenWill (1 ms)\nI 16.890s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] SkippedFrameTrackerTest.NoSkips_WillThenBegin\nI 16.890s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] SkippedFrameTrackerTest.NoSkips_WillThenBegin (0 ms)\nI 16.890s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] SkippedFrameTrackerTest.Skips_ProducedOverMultipleBeginFrames\nI 16.890s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] SkippedFrameTrackerTest.Skips_ProducedOverMultipleBeginFrames (0 ms)\nI 16.890s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] SkippedFrameTrackerTest.Skips_DroppedBeginFrames\nI 16.890s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] SkippedFrameTrackerTest.Skips_DroppedBeginFrames (1 ms)\nI 16.890s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] SkippedFrameTrackerTest.Skips_DroppedBeginFrames_JitterRoundsDown\nI 16.891s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] SkippedFrameTrackerTest.Skips_DroppedBeginFrames_JitterRoundsDown (0 ms)\nI 16.891s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] SkippedFrameTrackerTest.Skips_DroppedBeginFrames_JitterRoundsUp\nI 16.891s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] SkippedFrameTrackerTest.Skips_DroppedBeginFrames_JitterRoundsUp (1 ms)\nI 16.891s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] SkippedFrameTrackerTest.NoSkips_ActiveIdleActive_WillThenBegin\nI 16.891s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] SkippedFrameTrackerTest.NoSkips_ActiveIdleActive_WillThenBegin (1 ms)\nI 16.891s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] SkippedFrameTrackerTest.NoSkips_ActiveIdleActive_BeginThenWill\nI 16.891s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] SkippedFrameTrackerTest.NoSkips_ActiveIdleActive_BeginThenWill (1 ms)\nI 16.891s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] SkippedFrameTrackerTest.NoSkips_ActiveIdleActive_JumpInIdle\nI 16.891s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] SkippedFrameTrackerTest.NoSkips_ActiveIdleActive_JumpInIdle (1 ms)\nI 16.891s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] SkippedFrameTrackerTest.WillNotProduceFrame\nI 16.891s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] SkippedFrameTrackerTest.WillNotProduceFrame (0 ms)\nI 16.891s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] SkippedFrameTrackerTest.WillNotProduceFrame2\nI 16.891s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] SkippedFrameTrackerTest.WillNotProduceFrame2 (1 ms)\nI 16.891s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] SkippedFrameTrackerTest.PulledFramesNotRecorded\nI 16.891s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] SkippedFrameTrackerTest.PulledFramesNotRecorded (1 ms)\nI 16.891s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] SkippedFrameTrackerTest.MultipleWillProduceBeforeDidProduce\nI 16.891s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] SkippedFrameTrackerTest.MultipleWillProduceBeforeDidProduce (0 ms)\nI 16.891s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] SkippedFrameTrackerTest.NoSkips_ActiveIdleActive_FramePulledBeforeBF\nI 16.891s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] SkippedFrameTrackerTest.NoSkips_ActiveIdleActive_FramePulledBeforeBF (1 ms)\nI 16.891s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] SkippedFrameTrackerTest.NoSkips_ActiveIdleActive_FramePulledAfterPush\nI 16.891s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] SkippedFrameTrackerTest.NoSkips_ActiveIdleActive_FramePulledAfterPush (0 ms)\nI 16.891s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] SkippedFrameTrackerTest.NoSkips_ActiveIdleActive_FramePulledIsPush\nI 16.891s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] SkippedFrameTrackerTest.NoSkips_ActiveIdleActive_FramePulledIsPush (0 ms)\nI 16.892s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] SkippedFrameTrackerTest.NoFrameProduced\nI 16.892s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] SkippedFrameTrackerTest.NoFrameProduced (1 ms)\nI 16.892s run_tests_on_device(027d5cd80ac5a2e3) [----------] 17 tests from SkippedFrameTrackerTest (12 ms total)\nI 16.892s run_tests_on_device(027d5cd80ac5a2e3) \nI 16.892s run_tests_on_device(027d5cd80ac5a2e3) [----------] 5 tests from StreamAnalyzerTest\nI 16.892s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] StreamAnalyzerTest.AllResultsTheSame\nI 16.892s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] StreamAnalyzerTest.AllResultsTheSame (78 ms)\nI 16.892s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] StreamAnalyzerTest.AllResultsDifferent\nI 16.892s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] StreamAnalyzerTest.AllResultsDifferent (19 ms)\nI 16.892s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] StreamAnalyzerTest.SamplesForwardedToHistogram\nI 16.892s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] StreamAnalyzerTest.SamplesForwardedToHistogram (0 ms)\nI 16.892s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] StreamAnalyzerTest.PercentilesModifiedByClient\nI 16.892s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] StreamAnalyzerTest.PercentilesModifiedByClient (1 ms)\nI 16.892s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] StreamAnalyzerTest.Precision\nI 16.892s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] StreamAnalyzerTest.Precision (187 ms)\nI 16.892s run_tests_on_device(027d5cd80ac5a2e3) [----------] 5 tests from StreamAnalyzerTest (285 ms total)\nI 16.892s run_tests_on_device(027d5cd80ac5a2e3) \nI 16.892s run_tests_on_device(027d5cd80ac5a2e3) [----------] 8 tests from FrameMetricsWindowedAnalyzerTest\nI 16.892s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsWindowedAnalyzerTest.AllResultsTheSame\nI 16.892s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsWindowedAnalyzerTest.AllResultsTheSame (32 ms)\nI 16.892s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsWindowedAnalyzerTest.AllResultsDifferent\nI 16.892s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsWindowedAnalyzerTest.AllResultsDifferent (1 ms)\nI 16.892s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsWindowedAnalyzerTest.SmallSampleSize\nI 16.893s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsWindowedAnalyzerTest.SmallSampleSize (0 ms)\nI 16.893s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsWindowedAnalyzerTest.BadFirstSamples\nI 16.893s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsWindowedAnalyzerTest.BadFirstSamples (0 ms)\nI 16.893s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsWindowedAnalyzerTest.ResetWorstValues\nI 16.893s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsWindowedAnalyzerTest.ResetWorstValues (1 ms)\nI 16.893s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsWindowedAnalyzerTest.NoAccumulatedPrecisionErrorBasic\nI 16.893s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsWindowedAnalyzerTest.NoAccumulatedPrecisionErrorBasic (2 ms)\nI 16.893s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsWindowedAnalyzerTest.NoAccumulatedPrecisionErrorBig\nI 16.893s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsWindowedAnalyzerTest.NoAccumulatedPrecisionErrorBig (2 ms)\nI 16.893s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] FrameMetricsWindowedAnalyzerTest.NoAccumulatedPrecisionErrorBigger\nI 16.893s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] FrameMetricsWindowedAnalyzerTest.NoAccumulatedPrecisionErrorBigger (2 ms)\nI 16.893s run_tests_on_device(027d5cd80ac5a2e3) [----------] 8 tests from FrameMetricsWindowedAnalyzerTest (42 ms total)\nI 16.893s run_tests_on_device(027d5cd80ac5a2e3) \nI 16.893s run_tests_on_device(027d5cd80ac5a2e3) [----------] 2 tests from LatencyInfoParamTraitsTest\nI 16.893s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] LatencyInfoParamTraitsTest.Basic\nI 16.893s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] LatencyInfoParamTraitsTest.Basic (1 ms)\nI 16.893s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] LatencyInfoParamTraitsTest.InvalidData\nI 16.893s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] LatencyInfoParamTraitsTest.InvalidData (0 ms)\nI 16.893s run_tests_on_device(027d5cd80ac5a2e3) [----------] 2 tests from LatencyInfoParamTraitsTest (2 ms total)\nI 16.893s run_tests_on_device(027d5cd80ac5a2e3) \nI 16.893s run_tests_on_device(027d5cd80ac5a2e3) [----------] 1 test from StructTraitsTest\nI 16.893s run_tests_on_device(027d5cd80ac5a2e3) [ RUN ] StructTraitsTest.LatencyInfo\nI 16.893s run_tests_on_device(027d5cd80ac5a2e3) [ OK ] StructTraitsTest.LatencyInfo (3 ms)\nI 16.894s run_tests_on_device(027d5cd80ac5a2e3) [----------] 1 test from StructTraitsTest (3 ms total)\nI 16.894s run_tests_on_device(027d5cd80ac5a2e3) \nI 16.894s run_tests_on_device(027d5cd80ac5a2e3) [----------] Global test environment tear-down\nI 16.894s run_tests_on_device(027d5cd80ac5a2e3) [==========] 79 tests from 10 test suites ran. (980 ms total)\nI 16.894s run_tests_on_device(027d5cd80ac5a2e3) [ PASSED ] 79 tests.\nI 16.894s run_tests_on_device(027d5cd80ac5a2e3) <<ScopedMainEntryLogger\nI 16.899s run_tests_on_device(027d5cd80ac5a2e3) Finished running tests on this device.\nI 16.908s Main FINISHED TRY #1/3\nI 16.908s Main All tests completed.\nC 16.908s Main ********************************************************************************\nC 16.908s Main Summary\nC 16.908s Main ********************************************************************************\nC 16.909s Main [==========] 79 tests ran.\nC 16.909s Main [ PASSED ] 79 tests.\nC 16.909s Main ********************************************************************************\nI 16.910s tear_down_device(027d5cd80ac5a2e3) Wrote device cache: /b/swarming/w/ir/out/Debug/device_cache_027d5cd80ac5a2e3.json\nI 16.914s Main Generated json results file at /b/swarming/w/itxV8ZE_/tmpHTagaq\nI 16.915s Main Finishing archiving output.\n[W2019-04-23T14:47:05.360703Z 214 0 butler.go:240] Butler Context was cancelled. Initiating shutdown. {\"error\":\"context canceled\"}\nINFO:devil.utils.timeout_retry:condition 'logdog_stopped' not met\n[E2019-04-23T14:47:05.406791Z 214 0 main.go:205] Butler terminated with error. {\"error\":\"context canceled\"}\n[E2019-04-23T14:47:05.406839Z 214 0 main.go:233] Failed to serve.\noriginal error: context canceled\n\n[I2019-04-23T14:47:05.406960Z 214 0 main.go:349] Terminating. {\"returnCode\":250}\nINFO:devil.utils.timeout_retry:condition 'logdog_stopped' met\n", "outputs_ref": { "isolated": "ff5d300c9997f3c8220908ed346d2587d3a33d6f", "isolatedserver": "https://isolateserver.appspot.com", "namespace": "default-gzip" }, "run_id": "446329366c8c9311", "server_versions": [ "4267-53b17d5" ], "started_ts": "2019-04-23T14:46:38.774510", "state": "COMPLETED", "tags": [ "build_is_experimental:false", "buildername:Lollipop Phone Tester", "buildnumber:21521", "data:52dd2302cd3d383657e8c25f51c3406170f0451d", "device_os:LMY48I", "device_type:hammerhead", "master:chromium.android", "name:latency_unittests", "os:Android", "pool:Chrome", "priority:25", "project:chromium", "purpose:CI", "purpose:luci", "purpose:post-commit", "service_account:none", "slavename:swarm98-c4", "spec_name:chromium.ci:Lollipop Phone Tester", "stepname:latency_unittests on Android device Nexus 5", "swarming.pool.template:none", "swarming.pool.version:6b864aae429ce845be54cda3f4ca1317a092e4bf", "user:None" ], "task_id": "4463a296d250d810" } ] }