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/chromium_swarming/resources/collect_task.py --verbose -o /b/s/w/ir/tmp/t/tmpOILF6p.json --task-output-dir /b/s/w/ir/tmp/t/tmp7A6zAG --merge-script /b/s/w/ir/cache/builder/src/testing/merge_scripts/standard_gtest_merge.py --merge-script-stdout-file /b/s/w/ir/tmp/t/tmpbyzLj9merge_script_log --merge-additional-args '[]' --summary-json-file /b/s/w/ir/tmp/t/tmpNdnYKU.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/tmpM6QGW4.json in dir /b/s/w/ir/k: allow_subannotations: True 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/chromium_swarming/resources/collect_task.py', '--verbose', '-o', '/b/s/w/ir/tmp/t/tmpOILF6p.json', '--task-output-dir', '/b/s/w/ir/tmp/t/tmp7A6zAG', '--merge-script', '/b/s/w/ir/cache/builder/src/testing/merge_scripts/standard_gtest_merge.py', '--merge-script-stdout-file', '/b/s/w/ir/tmp/t/tmpbyzLj9merge_script_log', '--merge-additional-args', '[]', '--summary-json-file', '/b/s/w/ir/tmp/t/tmpNdnYKU.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/tmpM6QGW4.json'] env: {} env_prefixes: {'PATH': '/b/s/w/ir/cache/swarming_client'} env_suffixes: {} infra_step: False name_tokens: ('rtc_stats_unittests on Android',) ok_ret: ALL_OK step_test_data: gen_default_step_test_data(...) 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: 43521 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/8915831987787988352 LOGDOG_STREAM_PROJECT: webrtc LOGDOG_STREAM_SERVER_PATH: unix:/b/s/w/ir/tmp/ld.sock LOGNAME: chrome-bot LUCI_CONTEXT: /b/s/w/itr_FUQF/luci_context.331872650 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/k 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: luci-webrtc-ci-trusty-2-fp05 SWARMING_EXTERNAL_BOT_SETUP: 1 SWARMING_HEADLESS: 1 SWARMING_SERVER: https://chromium-swarm.appspot.com SWARMING_TASK_ID: 4449a0bcc0135711 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-04-18 07:28:29,021 - root: [DEBUG] Using task_output_dir: '/b/s/w/ir/tmp/t/tmp7A6zAG' 2019-04-18 07:28:29,021 - root: [WARNING] task_output_dir '/b/s/w/ir/tmp/t/tmp7A6zAG' already exists! 2019-04-18 07:28:29,022 - root: [WARNING] task_output_dir existing content: [] 2019-04-18 07:28:29,022 - 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/tmpM6QGW4.json -output-dir /b/s/w/ir/tmp/t/tmp7A6zAG -task-summary-json /b/s/w/ir/tmp/t/tmpNdnYKU.json [D2019-04-18T07:28:29.031216-07:00 21691 0 auth.go:1265] Minting a new token {"key":"luci_ctx/46c1f9f164fce7cbfddf7a7bff844471f5661795b80e890c7d3ba502883c3e11", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-04-18T07:28:29.031296-07:00 21691 0 luci_ctx.go:138] POST http://127.0.0.1:42314/rpc/LuciLocalAuthService.GetOAuthToken {"key":"luci_ctx/46c1f9f164fce7cbfddf7a7bff844471f5661795b80e890c7d3ba502883c3e11", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-04-18T07:28:29.034403-07:00 21691 0 auth.go:1222] Token expires in 25m19.965627759s {"key":"luci_ctx/46c1f9f164fce7cbfddf7a7bff844471f5661795b80e890c7d3ba502883c3e11", "scopes":"https://www.googleapis.com/auth/userinfo.email"} 4449a6e331653c10: exit 0 I 0.000s Main command: bin/../../../build/android/test_runner.py gtest --output-directory bin/../. --runtime-deps-path bin/../gen.runtime/stats/rtc_stats_unittests__test_runner_script.runtime_deps --suite rtc_stats_unittests -v --logcat-output-file /b/swarming/w/iotLyA96/logcats --store-tombstones --test-launcher-summary-output=/b/swarming/w/iotLyA96/output.json I 0.007s Main condition '<lambda>' met I 0.008s Main condition '<lambda>' met I 3.193s TimeoutThread-1-for-prepare_device(01f078c9e9c34d96) condition 'sd_card_ready' met (0.0s) I 3.779s TimeoutThread-1-for-prepare_device(01f078c9e9c34d96) condition 'pm_ready' met (0.6s) I 3.832s TimeoutThread-1-for-prepare_device(01f078c9e9c34d96) condition 'boot_completed' met (0.7s) I 13.388s TimeoutThread-1-for-individual_device_set_up(01f078c9e9c34d96) Setting permissions for org.webrtc.native_test. I 15.989s calculate_device_checksums condition '<lambda>' met (0.1s) I 18.381s list_tests(01f078c9e9c34d96) flags: I 18.382s list_tests(01f078c9e9c34d96) --gtest_list_tests I 18.399s TimeoutThread-1-for-list_tests(01f078c9e9c34d96) 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-2d0829be60ad9.gtest_out -e org.chromium.native_test.NativeTest.CommandLineFlags --gtest_list_tests "$p"/org.chromium.native_test.NativeTestInstrumentationTestRunner )>/ ... I 20.120s list_tests(01f078c9e9c34d96) /sdcard/temp_file-2d0829be60ad9.gtest_out size on device: 258 I 20.195s Main Using external sharding settings. This is shard 0/1 I 20.195s Main STARTING TRY #1/3 I 20.196s Main Will run 10 tests on 1 devices: 01f078c9e9c34d96 W 20.295s run_tests_on_device(01f078c9e9c34d96) No tombstones to clear. I 22.830s run_tests_on_device(01f078c9e9c34d96) flags: I 22.830s run_tests_on_device(01f078c9e9c34d96) Getting logdog client. I 22.952s TimeoutThread-1-for-run_tests_on_device(01f078c9e9c34d96) Large shell command will be run from file: ( p=org.webrtc.native_test;am instrument -w -e org.chromium.native_test.NativeTestInstrumentationTestRunner.ShardNanoTimeout 120000000000 -e org.chromium.native_test.NativeTestInstrumentationTestRunner.NativeTestActivity "$p".RTCNativeUnitTestActivity -e org.chromium.native_test.NativeTestInstrumentationTestRunner.TestList /data/local/tmp/temp_file-9670b8417ed58 -e org.chromium.native_test.NativeTestInstrumentationTestRunner.StdoutFile /sdcard/temp_file-8f1484228c1e1.gtest_out "$p"/org.chromium.native_test. ... I 24.373s run_tests_on_device(01f078c9e9c34d96) /sdcard/temp_file-8f1484228c1e1.gtest_out size on device: 2053 I 24.510s archive Writing text to logdog stream, logcat_logcat_3319692404600788449_20190418T064052-UTC_01f078c9e9c34d96 I 24.511s run_tests_on_device(01f078c9e9c34d96) Logcat saved to https://luci-logdog.appspot.com/v/?s=chromium%2Fandroid%2Fswarming%2Flogcats%2F4447fe2ce71ba611%2F%2B%2Flogcat_logcat_3319692404600788449_20190418T064052-UTC_01f078c9e9c34d96 I 25.099s TimeoutThread-1-for-run_tests_on_device(01f078c9e9c34d96) Setting permissions for org.webrtc.native_test. I 27.784s run_tests_on_device(01f078c9e9c34d96) >>ScopedMainEntryLogger I 27.784s run_tests_on_device(01f078c9e9c34d96) Note: Google Test filter = RTCStatsReport.AddAndGetStats:RTCStatsReport.StatsOrder:RTCStatsReport.Take:RTCStatsReport.TakeMembersFrom:RTCStatsTest.EqualityOperator:RTCStatsTest.IsStandardized:RTCStatsTest.NonStandardGroupId:RTCStatsTest.RTCStatsAndMembers:RTCStatsTest.RTCStatsGrandChild:RTCStatsTest.RTCStatsPrintsValidJson I 27.784s run_tests_on_device(01f078c9e9c34d96) [==========] Running 10 tests from 2 test suites. I 27.784s run_tests_on_device(01f078c9e9c34d96) [----------] Global test environment set-up. I 27.784s run_tests_on_device(01f078c9e9c34d96) [----------] 4 tests from RTCStatsReport I 27.784s run_tests_on_device(01f078c9e9c34d96) [ RUN ] RTCStatsReport.AddAndGetStats I 27.785s run_tests_on_device(01f078c9e9c34d96) [ OK ] RTCStatsReport.AddAndGetStats (0 ms) I 27.785s run_tests_on_device(01f078c9e9c34d96) [ RUN ] RTCStatsReport.StatsOrder I 27.785s run_tests_on_device(01f078c9e9c34d96) [ OK ] RTCStatsReport.StatsOrder (0 ms) I 27.785s run_tests_on_device(01f078c9e9c34d96) [ RUN ] RTCStatsReport.Take I 27.785s run_tests_on_device(01f078c9e9c34d96) [ OK ] RTCStatsReport.Take (0 ms) I 27.785s run_tests_on_device(01f078c9e9c34d96) [ RUN ] RTCStatsReport.TakeMembersFrom I 27.785s run_tests_on_device(01f078c9e9c34d96) [ OK ] RTCStatsReport.TakeMembersFrom (0 ms) I 27.785s run_tests_on_device(01f078c9e9c34d96) [----------] 4 tests from RTCStatsReport (0 ms total) I 27.785s run_tests_on_device(01f078c9e9c34d96) I 27.785s run_tests_on_device(01f078c9e9c34d96) [----------] 6 tests from RTCStatsTest I 27.785s run_tests_on_device(01f078c9e9c34d96) [ RUN ] RTCStatsTest.RTCStatsAndMembers I 27.785s run_tests_on_device(01f078c9e9c34d96) [ OK ] RTCStatsTest.RTCStatsAndMembers (0 ms) I 27.785s run_tests_on_device(01f078c9e9c34d96) [ RUN ] RTCStatsTest.EqualityOperator I 27.785s run_tests_on_device(01f078c9e9c34d96) [ OK ] RTCStatsTest.EqualityOperator (0 ms) I 27.786s run_tests_on_device(01f078c9e9c34d96) [ RUN ] RTCStatsTest.RTCStatsGrandChild I 27.786s run_tests_on_device(01f078c9e9c34d96) [ OK ] RTCStatsTest.RTCStatsGrandChild (0 ms) I 27.786s run_tests_on_device(01f078c9e9c34d96) [ RUN ] RTCStatsTest.RTCStatsPrintsValidJson I 27.786s run_tests_on_device(01f078c9e9c34d96) {"type":"test-stats","id":"statsId","timestamp":42,"mBool":true,"mInt32":123,"mInt64":1.234567890123457e+18,"mDouble":123.4567890123457,"mString":"123","mSequenceBool":[],"mSequenceInt32":[1],"mSequenceInt64":[-1.234567890123457e+18,1,1.234567890123457e+18],"mSequenceDouble":[123.4567890123457,1234567890123.457],"mSequenceString":["four"]} I 27.786s run_tests_on_device(01f078c9e9c34d96) [ OK ] RTCStatsTest.RTCStatsPrintsValidJson (1 ms) I 27.786s run_tests_on_device(01f078c9e9c34d96) [ RUN ] RTCStatsTest.IsStandardized I 27.786s run_tests_on_device(01f078c9e9c34d96) [ OK ] RTCStatsTest.IsStandardized (0 ms) I 27.786s run_tests_on_device(01f078c9e9c34d96) [ RUN ] RTCStatsTest.NonStandardGroupId I 27.786s run_tests_on_device(01f078c9e9c34d96) [ OK ] RTCStatsTest.NonStandardGroupId (0 ms) I 27.786s run_tests_on_device(01f078c9e9c34d96) [----------] 6 tests from RTCStatsTest (1 ms total) I 27.786s run_tests_on_device(01f078c9e9c34d96) I 27.786s run_tests_on_device(01f078c9e9c34d96) [----------] Global test environment tear-down I 27.786s run_tests_on_device(01f078c9e9c34d96) [==========] 10 tests from 2 test suites ran. (4 ms total) I 27.787s run_tests_on_device(01f078c9e9c34d96) [ PASSED ] 10 tests. I 27.787s run_tests_on_device(01f078c9e9c34d96) <<ScopedMainEntryLogger I 27.789s run_tests_on_device(01f078c9e9c34d96) Finished running tests on this device. I 27.812s Main FINISHED TRY #1/3 I 27.812s Main All tests completed. C 27.812s Main ******************************************************************************** C 27.813s Main Summary C 27.813s Main ******************************************************************************** C 27.813s Main [==========] 10 tests ran. C 27.813s Main [ PASSED ] 10 tests. C 27.813s Main ******************************************************************************** I 27.815s tear_down_device(01f078c9e9c34d96) Wrote device cache: /b/swarming/w/ir/out/Android32_M_Nexus5X_dbg/device_cache_01f078c9e9c34d96.json I 27.983s Main Generated json results file at /b/swarming/w/it9Tr2V6/tmpG9HHHa I 27.984s Main Finishing archiving output. [W2019-04-18T06:40:58.033042Z 17569 0 butler.go:240] Butler Context was cancelled. Initiating shutdown. {"error":"context canceled"} INFO:devil.utils.timeout_retry:condition 'logdog_stopped' not met [E2019-04-18T06:40:58.056629Z 17569 0 main.go:205] Butler terminated with error. {"error":"context canceled"} [E2019-04-18T06:40:58.056710Z 17569 0 main.go:233] Failed to serve. original error: context canceled [I2019-04-18T06:40:58.057032Z 17569 0 main.go:349] Terminating. {"returnCode":250} INFO:devil.utils.timeout_retry:condition 'logdog_stopped' met 2019-04-18 07:28:29,500 - root: [DEBUG] Contents of task_output_dir: ['/b/s/w/ir/tmp/t/tmp7A6zAG/4449a6e331653c10'] 2019-04-18 07:28:29,500 - root: [DEBUG] Found shard_json_files: ['/b/s/w/ir/tmp/t/tmp7A6zAG/4449a6e331653c10/output.json'] 2019-04-18 07:28:29,500 - root: [INFO] merge_cmd: /b/s/w/ir/cache/vpython/95f307/bin/python /b/s/w/ir/cache/builder/src/testing/merge_scripts/standard_gtest_merge.py --summary-json /b/s/w/ir/tmp/t/tmpNdnYKU.json --task-output-dir /b/s/w/ir/tmp/t/tmp7A6zAG -o /b/s/w/ir/tmp/t/tmpOILF6p.json /b/s/w/ir/tmp/t/tmp7A6zAG/4449a6e331653c10/output.json Running ['/b/s/w/ir/cache/vpython/95f307/bin/python', '/b/s/w/ir/cache/builder/src/testing/merge_scripts/standard_gtest_merge.py', '--summary-json', '/b/s/w/ir/tmp/t/tmpNdnYKU.json', '--task-output-dir', '/b/s/w/ir/tmp/t/tmp7A6zAG', '-o', '/b/s/w/ir/tmp/t/tmpOILF6p.json', '/b/s/w/ir/tmp/t/tmp7A6zAG/4449a6e331653c10/output.json'] in None (env: None) Command ['/b/s/w/ir/cache/vpython/95f307/bin/python', '/b/s/w/ir/cache/builder/src/testing/merge_scripts/standard_gtest_merge.py', '--summary-json', '/b/s/w/ir/tmp/t/tmpNdnYKU.json', '--task-output-dir', '/b/s/w/ir/tmp/t/tmp7A6zAG', '-o', '/b/s/w/ir/tmp/t/tmpOILF6p.json', '/b/s/w/ir/tmp/t/tmp7A6zAG/4449a6e331653c10/output.json'] returned exit code 0