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

python -u /b/s/w/ir/kitchen-checkout/build/scripts/tools/runit.py --show-path --with-third-party-lib -- python /b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/swarming/resources/collect_task.py --verbose -o /b/s/w/ir/tmp/t/tmp8CLcU3.json --task-output-dir /b/s/w/ir/tmp/t/tmpzO2sJw --merge-script /b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/swarming/resources/standard_gtest_merge.py --merge-script-stdout-file /b/s/w/ir/tmp/t/tmpe9H8u_merge_script_log --merge-additional-args '[]' --summary-json-file /b/s/w/ir/tmp/t/tmpWexyO2.json -- swarming collect -server https://chromium-swarm.appspot.com -worker 50 -task-summary-python -task-output-stdout all -verbose -requests-json /b/s/w/ir/tmp/t/tmpGCro9h.json in dir /b/s/w/ir/kitchen-workdir: allow_subannotations: True base_name: 'system_wrappers_unittests on Android' cmd: ['python', '-u', '/b/s/w/ir/kitchen-checkout/build/scripts/tools/runit.py', '--show-path', '--with-third-party-lib', '--', 'python', '/b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/swarming/resources/collect_task.py', '--verbose', '-o', '/b/s/w/ir/tmp/t/tmp8CLcU3.json', '--task-output-dir', '/b/s/w/ir/tmp/t/tmpzO2sJw', '--merge-script', '/b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/swarming/resources/standard_gtest_merge.py', '--merge-script-stdout-file', '/b/s/w/ir/tmp/t/tmpe9H8u_merge_script_log', '--merge-additional-args', '[]', '--summary-json-file', '/b/s/w/ir/tmp/t/tmpWexyO2.json', '--', 'swarming', 'collect', '-server', 'https://chromium-swarm.appspot.com', '-worker', '50', '-task-summary-python', '-task-output-stdout', 'all', '-verbose', '-requests-json', '/b/s/w/ir/tmp/t/tmpGCro9h.json'] env: {} env_prefixes: {'PATH': '/b/s/w/ir/cache/swarming_client'} env_suffixes: {} infra_step: False name: 'system_wrappers_unittests on Android' nest_level: 0 ok_ret: frozenset([0]) step_test_data: <lambda>(...) trigger_specs: () full environment: BOTO_CONFIG: /b/s/w/ir/tmp/gsutil_task/.boto BUILDBUCKET_EXPERIMENTAL: FALSE CIPD_CACHE_DIR: /b/s/cipd_cache/cache CIPD_PROTOCOL: v2 DEVSHELL_CLIENT_PORT: 40917 DOCKER_CONFIG: /b/s/w/ir/tmp/docker_cfg_task DOCKER_TMPDIR: /b/s/w/ir/tmp/docker_tmp_task GIT_CONFIG_NOSYSTEM: 1 GIT_TERMINAL_PROMPT: 0 HOME: /home/chrome-bot INFRA_GIT_WRAPPER_HOME: /b/s/w/ir/tmp/git_home_task LOGDOG_COORDINATOR_HOST: logs.chromium.org LOGDOG_STREAM_PREFIX: buildbucket/cr-buildbucket.appspot.com/8921546454537973952 LOGDOG_STREAM_PROJECT: webrtc LOGDOG_STREAM_SERVER_PATH: unix:/b/s/w/ir/tmp/ld.sock LOGNAME: chrome-bot LUCI_CONTEXT: /b/s/w/it_cQo7v/luci_context.655688381 MAC_CHROMIUM_TMPDIR: /b/s/w/ir/tmp/t MAIL: /var/mail/chrome-bot NO_GCE_CHECK: False PATH: /b/s/w/ir/cache/swarming_client:/b/s/w/ir/cipd_bin_packages:/b/s/w/ir/cipd_bin_packages/bin:/b/s/cipd_cache/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin PWD: /b/s/w/ir PYTHONIOENCODING: UTF-8 PYTHONUNBUFFERED: 1 SHELL: /bin/bash SHLVL: 1 SUDO_COMMAND: /usr/bin/python /b/s/swarming_bot.zip start_bot SUDO_GID: 0 SUDO_UID: 0 SUDO_USER: root SWARMING_BOT_ID: trusty-webrtc-d2a17ac3-us-central1-b-7dhl SWARMING_EXTERNAL_BOT_SETUP: 1 SWARMING_HEADLESS: 1 SWARMING_SERVER: https://chromium-swarm.appspot.com SWARMING_TASK_ID: 4304cc4ce8a5c911 TEMP: /b/s/w/ir/tmp/t TEMPDIR: /b/s/w/ir/tmp/t TERM: linux TMP: /b/s/w/ir/tmp/t TMPDIR: /b/s/w/ir/tmp/t USER: chrome-bot USERNAME: chrome-bot VPYTHON_VIRTUALENV_ROOT: /b/s/w/ir/cache/vpython _: /b/s/w/ir/cipd_bin_packages/vpython Set PYTHONPATH: /b/s/w/ir/kitchen-checkout/build/scripts:/b/s/w/ir/kitchen-checkout/build/site_config:/b/s/w/ir/kitchen-checkout/build/third_party:/b/s/w/ir/kitchen-checkout/build/third_party/buildbot_8_4p1:/b/s/w/ir/kitchen-checkout/build/third_party/buildbot_slave_8_4:/b/s/w/ir/kitchen-checkout/build/third_party/coverage-3.7.1:/b/s/w/ir/kitchen-checkout/build/third_party/decorator_3_3_1:/b/s/w/ir/kitchen-checkout/build/third_party/google_api_python_client:/b/s/w/ir/kitchen-checkout/build/third_party/httplib2/python2:/b/s/w/ir/kitchen-checkout/build/third_party/infra_libs:/b/s/w/ir/kitchen-checkout/build/third_party/jinja2:/b/s/w/ir/kitchen-checkout/build/third_party/markupsafe:/b/s/w/ir/kitchen-checkout/build/third_party/oauth2client:/b/s/w/ir/kitchen-checkout/build/third_party/pyasn1:/b/s/w/ir/kitchen-checkout/build/third_party/pyasn1-modules:/b/s/w/ir/kitchen-checkout/build/third_party/python-rsa:/b/s/w/ir/kitchen-checkout/build/third_party/requests_2_10_0:/b/s/w/ir/kitchen-checkout/build/third_party/setuptools-0.6c11:/b/s/w/ir/kitchen-checkout/build/third_party/sqlalchemy_0_7_1:/b/s/w/ir/kitchen-checkout/build/third_party/sqlalchemy_migrate_0_7_1:/b/s/w/ir/kitchen-checkout/build/third_party/tempita_0_5:/b/s/w/ir/kitchen-checkout/build/third_party/twisted_10_2:/b/s/w/ir/kitchen-checkout/build/third_party/uritemplate:/b/s/w/ir/kitchen-checkout/build/third_party/site-packages 2019-02-14 05:00:11,026 - root: [DEBUG] Using task_output_dir: '/b/s/w/ir/tmp/t/tmpzO2sJw' 2019-02-14 05:00:11,026 - root: [WARNING] task_output_dir '/b/s/w/ir/tmp/t/tmpzO2sJw' already exists! 2019-02-14 05:00:11,026 - root: [WARNING] task_output_dir existing content: [] 2019-02-14 05:00:11,026 - root: [INFO] collect_cmd: swarming collect -server https://chromium-swarm.appspot.com -worker 50 -task-summary-python -task-output-stdout all -verbose -requests-json /b/s/w/ir/tmp/t/tmpGCro9h.json -output-dir /b/s/w/ir/tmp/t/tmpzO2sJw -task-summary-json /b/s/w/ir/tmp/t/tmpWexyO2.json 4304e22db24d1510: exit 0 I 0.000s Main command: /b/swarming/w/ir/build/android/test_runner.py gtest --suite system_wrappers_unittests --shard-timeout=900 --output-directory /b/swarming/w/ir/out/Android64_M_Nexus5X_dbg --runtime-deps-path /b/swarming/w/ir/out/Android64_M_Nexus5X_dbg/gen.runtime/system_wrappers/system_wrappers_unittests__test_runner_script.runtime_deps -v --logcat-output-file /b/swarming/w/ioXocOUw/logcats --store-tombstones --test-launcher-summary-output=/b/swarming/w/ioXocOUw/output.json I 0.004s Main condition '<lambda>' met I 0.005s Main condition '<lambda>' met I 3.181s TimeoutThread-1-for-prepare_device(01e2253c0cc494eb) condition 'sd_card_ready' met (0.0s) I 3.758s TimeoutThread-1-for-prepare_device(01e2253c0cc494eb) condition 'pm_ready' met (0.6s) I 3.808s TimeoutThread-1-for-prepare_device(01e2253c0cc494eb) condition 'boot_completed' met (0.7s) I 13.352s TimeoutThread-1-for-individual_device_set_up(01e2253c0cc494eb) Setting permissions for org.webrtc.native_test. I 15.888s calculate_device_checksums condition '<lambda>' met (0.1s) I 17.763s list_tests(01e2253c0cc494eb) flags: I 17.763s list_tests(01e2253c0cc494eb) --gtest_list_tests I 17.773s TimeoutThread-1-for-list_tests(01e2253c0cc494eb) Large shell command will be run from file: ( p=org.webrtc.native_test;am instrument -w -e org.chromium.native_test.NativeTestInstrumentationTestRunner.ShardNanoTimeout 30000000000 -e org.chromium.native_test.NativeTestInstrumentationTestRunner.NativeTestActivity "$p".RTCNativeUnitTestActivity -e org.chromium.native_test.NativeTestInstrumentationTestRunner.StdoutFile /sdcard/temp_file-cd3323c5e96b3.gtest_out -e org.chromium.native_test.NativeTest.CommandLineFlags --gtest_list_tests "$p"/org.chromium.native_test.NativeTestInstrumentationTestRunner )>/ ... I 19.328s list_tests(01e2253c0cc494eb) /sdcard/temp_file-cd3323c5e96b3.gtest_out size on device: 1111 I 19.397s Main Using external sharding settings. This is shard 0/1 I 19.397s Main STARTING TRY #1/3 I 19.397s Main Will run 40 tests on 1 devices: 01e2253c0cc494eb W 19.445s run_tests_on_device(01e2253c0cc494eb) No tombstones to clear. I 22.875s run_tests_on_device(01e2253c0cc494eb) flags: I 22.875s run_tests_on_device(01e2253c0cc494eb) Getting logdog client. I 22.921s TimeoutThread-1-for-run_tests_on_device(01e2253c0cc494eb) Large shell command will be run from file: ( p=org.webrtc.native_test;am instrument -w -e org.chromium.native_test.NativeTestInstrumentationTestRunner.ShardNanoTimeout 900000000000 -e org.chromium.native_test.NativeTestInstrumentationTestRunner.NativeTestActivity "$p".RTCNativeUnitTestActivity -e org.chromium.native_test.NativeTestInstrumentationTestRunner.TestList /data/local/tmp/temp_file-689e48624a1f2 -e org.chromium.native_test.NativeTestInstrumentationTestRunner.StdoutFile /sdcard/temp_file-3c28be7707c01.gtest_out "$p"/org.chromium.native_test. ... I 24.348s run_tests_on_device(01e2253c0cc494eb) /sdcard/temp_file-3c28be7707c01.gtest_out size on device: 6911 I 24.527s run_tests_on_device(01e2253c0cc494eb) Logcat saved to https://luci-logdog.appspot.com/v/?s=chromium%2Fandroid%2Fswarming%2Flogcats%2F4304863456a2cc11%2F%2B%2Flogcat_logcat_-4047052468225393383_20190214T111233-UTC_01e2253c0cc494eb I 24.527s archive Writing text to logdog stream, logcat_logcat_-4047052468225393383_20190214T111233-UTC_01e2253c0cc494eb I 25.090s TimeoutThread-1-for-run_tests_on_device(01e2253c0cc494eb) Setting permissions for org.webrtc.native_test. I 27.831s run_tests_on_device(01e2253c0cc494eb) >>ScopedMainEntryLogger I 27.831s run_tests_on_device(01e2253c0cc494eb) Note: Google Test filter = ClockTest.NtpTime:MetricsDefaultTest.GetAndReset:MetricsDefaultTest.MinSample:MetricsDefaultTest.NumEvents:MetricsDefaultTest.NumSamples:MetricsDefaultTest.Overflow:MetricsDefaultTest.Reset:MetricsDefaultTest.TestMinMaxBucket:MetricsDefaultTest.Underflow:MetricsTest.InitiallyNoSamples:MetricsTest.RtcHistogramBoolean_AddSample:MetricsTest.RtcHistogramCountsSparse_AddSample:MetricsTest.RtcHistogramCounts_AddMultipleSamples:MetricsTest.RtcHistogramCounts_AddSample:MetricsTest.RtcHistogramEnumeration_AddSample:MetricsTest.RtcHistogramPercent_AddSample:MetricsTest.RtcHistogramSparse_NonConstantNameWorks:MetricsTest.RtcHistogramsCounts_AddSample:NtpTimeTest.CanExplicitlyConvertToAndFromUint64:NtpTimeTest.CanGetWhatIsSet:NtpTimeTest.CanResetValue:NtpTimeTest.NoValueMeansInvalid:NtpTimeTest.SetIsSameAs2ParameterConstructor:NtpTimeTest.ToMsMeansToNtpMilliseconds:RtpToNtpTests.AveragesErrorOut:RtpToNtpTests.FailsForNoParameters:UpdateRtcpMeasurementTests.FailsForEqualNtp:UpdateRtcpMeasurementTests.FailsForEqualTimestamp:UpdateRtcpMeasurementTests.FailsForOldNtp:UpdateRtcpMeasurementTests.FailsForOldRtpTimestamp:UpdateRtcpMeasurementTests.FailsForTooNewNtp:UpdateRtcpMeasurementTests.FailsForZeroNtp:UpdateRtcpMeasurementTests.VerifyParameters:WrapAroundTests.GracefullyHandleRtpJump:WrapAroundTests.NewRtcpWrapped:WrapAroundTests.OldRtcpWrapped_OldRtpTimestamp:WrapAroundTests.OldRtcpWrapped_OldRtpTimestamp_Wraparound_Detected:WrapAroundTests.OldRtp_NewRtcpWrapped:WrapAroundTests.OldRtp_RtcpsWrapped:WrapAroundTests.RtpWrapped I 27.831s run_tests_on_device(01e2253c0cc494eb) [==========] Running 40 tests from 7 test suites. I 27.831s run_tests_on_device(01e2253c0cc494eb) [----------] Global test environment set-up. I 27.831s run_tests_on_device(01e2253c0cc494eb) [----------] 1 test from ClockTest I 27.831s run_tests_on_device(01e2253c0cc494eb) [ RUN ] ClockTest.NtpTime I 27.831s run_tests_on_device(01e2253c0cc494eb) [ OK ] ClockTest.NtpTime (0 ms) I 27.831s run_tests_on_device(01e2253c0cc494eb) [----------] 1 test from ClockTest (1 ms total) I 27.831s run_tests_on_device(01e2253c0cc494eb) I 27.831s run_tests_on_device(01e2253c0cc494eb) [----------] 8 tests from MetricsDefaultTest I 27.831s run_tests_on_device(01e2253c0cc494eb) [ RUN ] MetricsDefaultTest.Reset I 27.831s run_tests_on_device(01e2253c0cc494eb) [ OK ] MetricsDefaultTest.Reset (0 ms) I 27.831s run_tests_on_device(01e2253c0cc494eb) [ RUN ] MetricsDefaultTest.NumSamples I 27.831s run_tests_on_device(01e2253c0cc494eb) [ OK ] MetricsDefaultTest.NumSamples (0 ms) I 27.831s run_tests_on_device(01e2253c0cc494eb) [ RUN ] MetricsDefaultTest.NumEvents I 27.831s run_tests_on_device(01e2253c0cc494eb) [ OK ] MetricsDefaultTest.NumEvents (0 ms) I 27.831s run_tests_on_device(01e2253c0cc494eb) [ RUN ] MetricsDefaultTest.MinSample I 27.831s run_tests_on_device(01e2253c0cc494eb) [ OK ] MetricsDefaultTest.MinSample (0 ms) I 27.831s run_tests_on_device(01e2253c0cc494eb) [ RUN ] MetricsDefaultTest.Overflow I 27.831s run_tests_on_device(01e2253c0cc494eb) [ OK ] MetricsDefaultTest.Overflow (0 ms) I 27.831s run_tests_on_device(01e2253c0cc494eb) [ RUN ] MetricsDefaultTest.Underflow I 27.831s run_tests_on_device(01e2253c0cc494eb) [ OK ] MetricsDefaultTest.Underflow (0 ms) I 27.831s run_tests_on_device(01e2253c0cc494eb) [ RUN ] MetricsDefaultTest.GetAndReset I 27.831s run_tests_on_device(01e2253c0cc494eb) [ OK ] MetricsDefaultTest.GetAndReset (0 ms) I 27.831s run_tests_on_device(01e2253c0cc494eb) [ RUN ] MetricsDefaultTest.TestMinMaxBucket I 27.831s run_tests_on_device(01e2253c0cc494eb) [ OK ] MetricsDefaultTest.TestMinMaxBucket (0 ms) I 27.831s run_tests_on_device(01e2253c0cc494eb) [----------] 8 tests from MetricsDefaultTest (1 ms total) I 27.831s run_tests_on_device(01e2253c0cc494eb) I 27.831s run_tests_on_device(01e2253c0cc494eb) [----------] 9 tests from MetricsTest I 27.831s run_tests_on_device(01e2253c0cc494eb) [ RUN ] MetricsTest.InitiallyNoSamples I 27.831s run_tests_on_device(01e2253c0cc494eb) [ OK ] MetricsTest.InitiallyNoSamples (0 ms) I 27.831s run_tests_on_device(01e2253c0cc494eb) [ RUN ] MetricsTest.RtcHistogramPercent_AddSample I 27.831s run_tests_on_device(01e2253c0cc494eb) [ OK ] MetricsTest.RtcHistogramPercent_AddSample (0 ms) I 27.831s run_tests_on_device(01e2253c0cc494eb) [ RUN ] MetricsTest.RtcHistogramEnumeration_AddSample I 27.831s run_tests_on_device(01e2253c0cc494eb) [ OK ] MetricsTest.RtcHistogramEnumeration_AddSample (0 ms) I 27.831s run_tests_on_device(01e2253c0cc494eb) [ RUN ] MetricsTest.RtcHistogramBoolean_AddSample I 27.831s run_tests_on_device(01e2253c0cc494eb) [ OK ] MetricsTest.RtcHistogramBoolean_AddSample (0 ms) I 27.831s run_tests_on_device(01e2253c0cc494eb) [ RUN ] MetricsTest.RtcHistogramCountsSparse_AddSample I 27.831s run_tests_on_device(01e2253c0cc494eb) [ OK ] MetricsTest.RtcHistogramCountsSparse_AddSample (0 ms) I 27.831s run_tests_on_device(01e2253c0cc494eb) [ RUN ] MetricsTest.RtcHistogramCounts_AddSample I 27.831s run_tests_on_device(01e2253c0cc494eb) [ OK ] MetricsTest.RtcHistogramCounts_AddSample (0 ms) I 27.831s run_tests_on_device(01e2253c0cc494eb) [ RUN ] MetricsTest.RtcHistogramCounts_AddMultipleSamples I 27.831s run_tests_on_device(01e2253c0cc494eb) [ OK ] MetricsTest.RtcHistogramCounts_AddMultipleSamples (0 ms) I 27.832s run_tests_on_device(01e2253c0cc494eb) [ RUN ] MetricsTest.RtcHistogramsCounts_AddSample I 27.832s run_tests_on_device(01e2253c0cc494eb) [ OK ] MetricsTest.RtcHistogramsCounts_AddSample (0 ms) I 27.832s run_tests_on_device(01e2253c0cc494eb) [ RUN ] MetricsTest.RtcHistogramSparse_NonConstantNameWorks I 27.832s run_tests_on_device(01e2253c0cc494eb) [ OK ] MetricsTest.RtcHistogramSparse_NonConstantNameWorks (0 ms) I 27.832s run_tests_on_device(01e2253c0cc494eb) [----------] 9 tests from MetricsTest (2 ms total) I 27.832s run_tests_on_device(01e2253c0cc494eb) I 27.832s run_tests_on_device(01e2253c0cc494eb) [----------] 6 tests from NtpTimeTest I 27.832s run_tests_on_device(01e2253c0cc494eb) [ RUN ] NtpTimeTest.NoValueMeansInvalid I 27.832s run_tests_on_device(01e2253c0cc494eb) [ OK ] NtpTimeTest.NoValueMeansInvalid (0 ms) I 27.832s run_tests_on_device(01e2253c0cc494eb) [ RUN ] NtpTimeTest.CanResetValue I 27.832s run_tests_on_device(01e2253c0cc494eb) [ OK ] NtpTimeTest.CanResetValue (0 ms) I 27.832s run_tests_on_device(01e2253c0cc494eb) [ RUN ] NtpTimeTest.CanGetWhatIsSet I 27.832s run_tests_on_device(01e2253c0cc494eb) [ OK ] NtpTimeTest.CanGetWhatIsSet (0 ms) I 27.832s run_tests_on_device(01e2253c0cc494eb) [ RUN ] NtpTimeTest.SetIsSameAs2ParameterConstructor I 27.832s run_tests_on_device(01e2253c0cc494eb) [ OK ] NtpTimeTest.SetIsSameAs2ParameterConstructor (0 ms) I 27.832s run_tests_on_device(01e2253c0cc494eb) [ RUN ] NtpTimeTest.ToMsMeansToNtpMilliseconds I 27.832s run_tests_on_device(01e2253c0cc494eb) [ OK ] NtpTimeTest.ToMsMeansToNtpMilliseconds (0 ms) I 27.832s run_tests_on_device(01e2253c0cc494eb) [ RUN ] NtpTimeTest.CanExplicitlyConvertToAndFromUint64 I 27.832s run_tests_on_device(01e2253c0cc494eb) [ OK ] NtpTimeTest.CanExplicitlyConvertToAndFromUint64 (0 ms) I 27.832s run_tests_on_device(01e2253c0cc494eb) [----------] 6 tests from NtpTimeTest (1 ms total) I 27.832s run_tests_on_device(01e2253c0cc494eb) I 27.832s run_tests_on_device(01e2253c0cc494eb) [----------] 7 tests from WrapAroundTests I 27.832s run_tests_on_device(01e2253c0cc494eb) [ RUN ] WrapAroundTests.OldRtcpWrapped_OldRtpTimestamp I 27.832s run_tests_on_device(01e2253c0cc494eb) [ OK ] WrapAroundTests.OldRtcpWrapped_OldRtpTimestamp (0 ms) I 27.832s run_tests_on_device(01e2253c0cc494eb) [ RUN ] WrapAroundTests.OldRtcpWrapped_OldRtpTimestamp_Wraparound_Detected I 27.832s run_tests_on_device(01e2253c0cc494eb) [ OK ] WrapAroundTests.OldRtcpWrapped_OldRtpTimestamp_Wraparound_Detected (0 ms) I 27.832s run_tests_on_device(01e2253c0cc494eb) [ RUN ] WrapAroundTests.NewRtcpWrapped I 27.832s run_tests_on_device(01e2253c0cc494eb) [ OK ] WrapAroundTests.NewRtcpWrapped (0 ms) I 27.832s run_tests_on_device(01e2253c0cc494eb) [ RUN ] WrapAroundTests.RtpWrapped I 27.832s run_tests_on_device(01e2253c0cc494eb) [ OK ] WrapAroundTests.RtpWrapped (0 ms) I 27.832s run_tests_on_device(01e2253c0cc494eb) [ RUN ] WrapAroundTests.OldRtp_RtcpsWrapped I 27.832s run_tests_on_device(01e2253c0cc494eb) [ OK ] WrapAroundTests.OldRtp_RtcpsWrapped (0 ms) I 27.832s run_tests_on_device(01e2253c0cc494eb) [ RUN ] WrapAroundTests.OldRtp_NewRtcpWrapped I 27.832s run_tests_on_device(01e2253c0cc494eb) [ OK ] WrapAroundTests.OldRtp_NewRtcpWrapped (0 ms) I 27.832s run_tests_on_device(01e2253c0cc494eb) [ RUN ] WrapAroundTests.GracefullyHandleRtpJump I 27.832s run_tests_on_device(01e2253c0cc494eb) [ OK ] WrapAroundTests.GracefullyHandleRtpJump (0 ms) I 27.832s run_tests_on_device(01e2253c0cc494eb) [----------] 7 tests from WrapAroundTests (2 ms total) I 27.832s run_tests_on_device(01e2253c0cc494eb) I 27.832s run_tests_on_device(01e2253c0cc494eb) [----------] 7 tests from UpdateRtcpMeasurementTests I 27.832s run_tests_on_device(01e2253c0cc494eb) [ RUN ] UpdateRtcpMeasurementTests.FailsForZeroNtp I 27.832s run_tests_on_device(01e2253c0cc494eb) [ OK ] UpdateRtcpMeasurementTests.FailsForZeroNtp (0 ms) I 27.832s run_tests_on_device(01e2253c0cc494eb) [ RUN ] UpdateRtcpMeasurementTests.FailsForEqualNtp I 27.832s run_tests_on_device(01e2253c0cc494eb) [ OK ] UpdateRtcpMeasurementTests.FailsForEqualNtp (0 ms) I 27.832s run_tests_on_device(01e2253c0cc494eb) [ RUN ] UpdateRtcpMeasurementTests.FailsForOldNtp I 27.832s run_tests_on_device(01e2253c0cc494eb) [ OK ] UpdateRtcpMeasurementTests.FailsForOldNtp (0 ms) I 27.832s run_tests_on_device(01e2253c0cc494eb) [ RUN ] UpdateRtcpMeasurementTests.FailsForTooNewNtp I 27.832s run_tests_on_device(01e2253c0cc494eb) [ OK ] UpdateRtcpMeasurementTests.FailsForTooNewNtp (0 ms) I 27.832s run_tests_on_device(01e2253c0cc494eb) [ RUN ] UpdateRtcpMeasurementTests.FailsForEqualTimestamp I 27.832s run_tests_on_device(01e2253c0cc494eb) [ OK ] UpdateRtcpMeasurementTests.FailsForEqualTimestamp (0 ms) I 27.832s run_tests_on_device(01e2253c0cc494eb) [ RUN ] UpdateRtcpMeasurementTests.FailsForOldRtpTimestamp I 27.832s run_tests_on_device(01e2253c0cc494eb) [ OK ] UpdateRtcpMeasurementTests.FailsForOldRtpTimestamp (0 ms) I 27.833s run_tests_on_device(01e2253c0cc494eb) [ RUN ] UpdateRtcpMeasurementTests.VerifyParameters I 27.833s run_tests_on_device(01e2253c0cc494eb) [ OK ] UpdateRtcpMeasurementTests.VerifyParameters (0 ms) I 27.833s run_tests_on_device(01e2253c0cc494eb) [----------] 7 tests from UpdateRtcpMeasurementTests (2 ms total) I 27.833s run_tests_on_device(01e2253c0cc494eb) I 27.833s run_tests_on_device(01e2253c0cc494eb) [----------] 2 tests from RtpToNtpTests I 27.833s run_tests_on_device(01e2253c0cc494eb) [ RUN ] RtpToNtpTests.FailsForNoParameters I 27.833s run_tests_on_device(01e2253c0cc494eb) [ OK ] RtpToNtpTests.FailsForNoParameters (0 ms) I 27.833s run_tests_on_device(01e2253c0cc494eb) [ RUN ] RtpToNtpTests.AveragesErrorOut I 27.833s run_tests_on_device(01e2253c0cc494eb) [ OK ] RtpToNtpTests.AveragesErrorOut (2 ms) I 27.833s run_tests_on_device(01e2253c0cc494eb) [----------] 2 tests from RtpToNtpTests (2 ms total) I 27.833s run_tests_on_device(01e2253c0cc494eb) I 27.833s run_tests_on_device(01e2253c0cc494eb) [----------] Global test environment tear-down I 27.833s run_tests_on_device(01e2253c0cc494eb) [==========] 40 tests from 7 test suites ran. (13 ms total) I 27.833s run_tests_on_device(01e2253c0cc494eb) [ PASSED ] 40 tests. I 27.833s run_tests_on_device(01e2253c0cc494eb) <<ScopedMainEntryLogger I 27.835s run_tests_on_device(01e2253c0cc494eb) Finished running tests on this device. I 27.836s Main FINISHED TRY #1/3 I 27.836s Main All tests completed. C 27.836s Main ******************************************************************************** C 27.836s Main Summary C 27.836s Main ******************************************************************************** C 27.836s Main [==========] 40 tests ran. C 27.836s Main [ PASSED ] 40 tests. C 27.836s Main ******************************************************************************** I 27.838s tear_down_device(01e2253c0cc494eb) Wrote device cache: /b/swarming/w/ir/out/Android64_M_Nexus5X_dbg/device_cache_01e2253c0cc494eb.json I 27.955s Main Generated json results file at /b/swarming/w/itc9qktj/tmpXOnPl6 I 27.955s Main Finishing archiving output. [W2019-02-14T11:12:39.053335Z 5681 0 butler.go:240] Butler Context was cancelled. Initiating shutdown. {"error":"context canceled"} INFO:devil.utils.timeout_retry:condition 'logdog_stopped' not met [E2019-02-14T11:12:39.146965Z 5681 0 main.go:205] Butler terminated with error. {"error":"context canceled"} [E2019-02-14T11:12:39.147000Z 5681 0 main.go:233] Failed to serve. original error: context canceled [I2019-02-14T11:12:39.147044Z 5681 0 main.go:349] Terminating. {"returnCode":250} INFO:devil.utils.timeout_retry:condition 'logdog_stopped' met 2019-02-14 05:00:11,462 - root: [DEBUG] Contents of task_output_dir: ['/b/s/w/ir/tmp/t/tmpzO2sJw/4304e22db24d1510'] 2019-02-14 05:00:11,462 - root: [DEBUG] Found shard_json_files: ['/b/s/w/ir/tmp/t/tmpzO2sJw/4304e22db24d1510/output.json'] 2019-02-14 05:00:11,462 - root: [INFO] merge_cmd: /b/s/w/ir/cache/vpython/95f307/bin/python /b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/swarming/resources/standard_gtest_merge.py --summary-json /b/s/w/ir/tmp/t/tmpWexyO2.json --task-output-dir /b/s/w/ir/tmp/t/tmpzO2sJw -o /b/s/w/ir/tmp/t/tmp8CLcU3.json /b/s/w/ir/tmp/t/tmpzO2sJw/4304e22db24d1510/output.json Running ['/b/s/w/ir/cache/vpython/95f307/bin/python', '/b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/swarming/resources/standard_gtest_merge.py', '--summary-json', '/b/s/w/ir/tmp/t/tmpWexyO2.json', '--task-output-dir', '/b/s/w/ir/tmp/t/tmpzO2sJw', '-o', '/b/s/w/ir/tmp/t/tmp8CLcU3.json', '/b/s/w/ir/tmp/t/tmpzO2sJw/4304e22db24d1510/output.json'] in None (env: None) Command ['/b/s/w/ir/cache/vpython/95f307/bin/python', '/b/s/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/swarming/resources/standard_gtest_merge.py', '--summary-json', '/b/s/w/ir/tmp/t/tmpWexyO2.json', '--task-output-dir', '/b/s/w/ir/tmp/t/tmpzO2sJw', '-o', '/b/s/w/ir/tmp/t/tmp8CLcU3.json', '/b/s/w/ir/tmp/t/tmpzO2sJw/4304e22db24d1510/output.json'] returned exit code 0