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

python -u /b/swarming/w/ir/kitchen-checkout/build/scripts/tools/runit.py --show-path --with-third-party-lib -- python /b/swarming/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/collect_task.py --verbose -o /b/swarming/w/ir/tmp/t/tmpA1ZyxE.json --task-output-dir /b/swarming/w/ir/tmp/t/tmpuaa_mN --merge-script /b/swarming/w/ir/cache/builder/src/testing/merge_scripts/standard_gtest_merge.py --merge-script-stdout-file /b/swarming/w/ir/tmp/t/tmpAqX6bImerge_script_log --merge-additional-args '[]' --build-properties '{"bot_id": "swarm1860-c4", "branch": "refs/heads/master", "buildbucket": {"build": {"bucket": "luci.chromium.ci", "created_by": "project:chromium", "created_ts": 1552917615408364, "id": "8918641574464341200", "project": "chromium", "tags": ["build_address:luci.chromium.ci/fuchsia-fyi-x64-dbg/5879", "builder:fuchsia-fyi-x64-dbg", "buildset:commit/git/0fc91f390b416bba3b8d13dd89698b2bd18355c1", "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0fc91f390b416bba3b8d13dd89698b2bd18355c1", "gitiles_ref:refs/heads/master", "scheduler_invocation_id:9084071636522365152", "scheduler_job_id:chromium/fuchsia-fyi-x64-dbg", "user_agent:luci-scheduler"]}, "hostname": "cr-buildbucket.appspot.com"}, "buildername": "fuchsia-fyi-x64-dbg", "buildnumber": 5879, "got_angle_revision": "38e282570676f3e434482f8a02ea539b286fd1fa", "got_dawn_revision": "991ab98f113f5f51ec87d767b45523b19cd15b9e", "got_nacl_revision": "1ed9878b8eb68c1e0d0268754dafcf83e747fa8f", "got_revision": "0fc91f390b416bba3b8d13dd89698b2bd18355c1", "got_revision_cp": "refs/heads/master@{#641578}", "got_swarming_client_revision": "7a61cf37d6a0163f0ec02d495289a1d038e62457", "got_v8_revision": "f413181b62ee4ad78b9ef3d58c4f395794e16bf1", "got_v8_revision_cp": "refs/heads/7.5.59@{#1}", "got_webrtc_revision": "df644be3536b61256ab9e22bb0891a8b6a1d01c2", "got_webrtc_revision_cp": "refs/heads/master@{#27155}", "mastername": "chromium.fyi", "path_config": "generic", "recipe": "chromium", "repository": "https://chromium.googlesource.com/chromium/src.git", "revision": "0fc91f390b416bba3b8d13dd89698b2bd18355c1"}' --summary-json-file /b/swarming/w/ir/tmp/t/tmprdgW8t.json -- swarming collect -server https://chromium-swarm.appspot.com -worker 50 -task-summary-python -task-output-stdout all -verbose -requests-json /b/swarming/w/ir/tmp/t/tmpmXHl6j.json in dir /b/swarming/w/ir/k: allow_subannotations: True cmd: ['python', '-u', '/b/swarming/w/ir/kitchen-checkout/build/scripts/tools/runit.py', '--show-path', '--with-third-party-lib', '--', 'python', '/b/swarming/w/ir/kitchen-checkout/build/scripts/slave/recipe_modules/chromium_swarming/resources/collect_task.py', '--verbose', '-o', '/b/swarming/w/ir/tmp/t/tmpA1ZyxE.json', '--task-output-dir', '/b/swarming/w/ir/tmp/t/tmpuaa_mN', '--merge-script', '/b/swarming/w/ir/cache/builder/src/testing/merge_scripts/standard_gtest_merge.py', '--merge-script-stdout-file', '/b/swarming/w/ir/tmp/t/tmpAqX6bImerge_script_log', '--merge-additional-args', '[]', '--build-properties', '{"bot_id": "swarm1860-c4", "branch": "refs/heads/master", "buildbucket": {"build": {"bucket": "luci.chromium.ci", "created_by": "project:chromium", "created_ts": 1552917615408364, "id": "8918641574464341200", "project": "chromium", "tags": ["build_address:luci.chromium.ci/fuchsia-fyi-x64-dbg/5879", "builder:fuchsia-fyi-x64-dbg", "buildset:commit/git/0fc91f390b416bba3b8d13dd89698b2bd18355c1", "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0fc91f390b416bba3b8d13dd89698b2bd18355c1", "gitiles_ref:refs/heads/master", "scheduler_invocation_id:9084071636522365152", "scheduler_job_id:chromium/fuchsia-fyi-x64-dbg", "user_agent:luci-scheduler"]}, "hostname": "cr-buildbucket.appspot.com"}, "buildername": "fuchsia-fyi-x64-dbg", "buildnumber": 5879, "got_angle_revision": "38e282570676f3e434482f8a02ea539b286fd1fa", "got_dawn_revision": "991ab98f113f5f51ec87d767b45523b19cd15b9e", "got_nacl_revision": "1ed9878b8eb68c1e0d0268754dafcf83e747fa8f", "got_revision": "0fc91f390b416bba3b8d13dd89698b2bd18355c1", "got_revision_cp": "refs/heads/master@{#641578}", "got_swarming_client_revision": "7a61cf37d6a0163f0ec02d495289a1d038e62457", "got_v8_revision": "f413181b62ee4ad78b9ef3d58c4f395794e16bf1", "got_v8_revision_cp": "refs/heads/7.5.59@{#1}", "got_webrtc_revision": "df644be3536b61256ab9e22bb0891a8b6a1d01c2", "got_webrtc_revision_cp": "refs/heads/master@{#27155}", "mastername": "chromium.fyi", "path_config": "generic", "recipe": "chromium", "repository": "https://chromium.googlesource.com/chromium/src.git", "revision": "0fc91f390b416bba3b8d13dd89698b2bd18355c1"}', '--summary-json-file', '/b/swarming/w/ir/tmp/t/tmprdgW8t.json', '--', 'swarming', 'collect', '-server', 'https://chromium-swarm.appspot.com', '-worker', '50', '-task-summary-python', '-task-output-stdout', 'all', '-verbose', '-requests-json', '/b/swarming/w/ir/tmp/t/tmpmXHl6j.json'] env: {'CHROME_HEADLESS': '1'} env_prefixes: {'PATH': '/b/swarming/w/ir/cache/swarming_client'} env_suffixes: {} infra_step: False name_tokens: ('web_runner_integration_tests',) ok_ret: frozenset([0]) step_test_data: <lambda>(...) trigger_specs: () full environment: ADDRFAM: inet BOTO_CONFIG: /b/swarming/w/ir/tmp/gsutil_task/.boto BUILDBUCKET_EXPERIMENTAL: FALSE CHROME_HEADLESS: 1 CIPD_CACHE_DIR: /b/swarming/cipd_cache/cache CIPD_PROTOCOL: v2 DEVSHELL_CLIENT_PORT: 39289 DOCKER_CONFIG: /b/swarming/w/ir/tmp/docker_cfg_task DOCKER_TMPDIR: /b/swarming/w/ir/tmp/docker_tmp_task GIT_CONFIG_NOSYSTEM: 1 GIT_TERMINAL_PROMPT: 0 HOME: /home/chrome-bot IFACE: eth0 INFRA_GIT_WRAPPER_HOME: /b/swarming/w/ir/tmp/git_home_task LOGDOG_COORDINATOR_HOST: logs.chromium.org LOGDOG_STREAM_PREFIX: buildbucket/cr-buildbucket.appspot.com/8918641574464341200 LOGDOG_STREAM_PROJECT: chromium LOGDOG_STREAM_SERVER_PATH: unix:/b/swarming/w/ir/tmp/ld.sock LOGICAL: eth0 LUCI_CONTEXT: /b/swarming/w/itCEEPb3/luci_context.492740787 MAC_CHROMIUM_TMPDIR: /b/swarming/w/ir/tmp/t METHOD: dhcp NO_GCE_CHECK: False PATH: /b/swarming/w/ir/cache/swarming_client:/b/swarming/w/ir/cipd_bin_packages:/b/swarming/w/ir/cipd_bin_packages/bin:/b/swarming/cipd_cache/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin PWD: /b/swarming/w/ir/k PYTHONIOENCODING: UTF-8 PYTHONUNBUFFERED: 1 ROOT_SETUP_LOG: /var/log/messages/chromebuild/root-setup.log SHLVL: 1 STARTUP_LOG: /var/log/messages/chromebuild/startup.log SWARMING_BOT_ID: swarm1860-c4 SWARMING_EXTERNAL_BOT_SETUP: 1 SWARMING_HEADLESS: 1 SWARMING_SERVER: https://chromium-swarm.appspot.com SWARMING_TASK_ID: 43a9ebdd005ebe11 TEMP: /b/swarming/w/ir/tmp/t TEMPDIR: /b/swarming/w/ir/tmp/t TERM: linux TMP: /b/swarming/w/ir/tmp/t TMPDIR: /b/swarming/w/ir/tmp/t UPSTART_EVENTS: net-device-up UPSTART_INSTANCE: UPSTART_JOB: chromebuild-startup USER: chrome-bot USERNAME: chrome-bot VPYTHON_VIRTUALENV_ROOT: /b/swarming/w/ir/cache/vpython _: /b/swarming/w/ir/cipd_bin_packages/vpython Set PYTHONPATH: /b/swarming/w/ir/kitchen-checkout/build/scripts:/b/swarming/w/ir/kitchen-checkout/build/site_config:/b/swarming/w/ir/kitchen-checkout/build/third_party:/b/swarming/w/ir/kitchen-checkout/build/third_party/buildbot_8_4p1:/b/swarming/w/ir/kitchen-checkout/build/third_party/buildbot_slave_8_4:/b/swarming/w/ir/kitchen-checkout/build/third_party/coverage-3.7.1:/b/swarming/w/ir/kitchen-checkout/build/third_party/decorator_3_3_1:/b/swarming/w/ir/kitchen-checkout/build/third_party/google_api_python_client:/b/swarming/w/ir/kitchen-checkout/build/third_party/httplib2/python2:/b/swarming/w/ir/kitchen-checkout/build/third_party/infra_libs:/b/swarming/w/ir/kitchen-checkout/build/third_party/jinja2:/b/swarming/w/ir/kitchen-checkout/build/third_party/markupsafe:/b/swarming/w/ir/kitchen-checkout/build/third_party/oauth2client:/b/swarming/w/ir/kitchen-checkout/build/third_party/pyasn1:/b/swarming/w/ir/kitchen-checkout/build/third_party/pyasn1-modules:/b/swarming/w/ir/kitchen-checkout/build/third_party/python-rsa:/b/swarming/w/ir/kitchen-checkout/build/third_party/requests_2_10_0:/b/swarming/w/ir/kitchen-checkout/build/third_party/setuptools-0.6c11:/b/swarming/w/ir/kitchen-checkout/build/third_party/sqlalchemy_0_7_1:/b/swarming/w/ir/kitchen-checkout/build/third_party/sqlalchemy_migrate_0_7_1:/b/swarming/w/ir/kitchen-checkout/build/third_party/tempita_0_5:/b/swarming/w/ir/kitchen-checkout/build/third_party/twisted_10_2:/b/swarming/w/ir/kitchen-checkout/build/third_party/uritemplate:/b/swarming/w/ir/kitchen-checkout/build/third_party/site-packages 2019-03-18 08:41:21,200 - root: [DEBUG] Using task_output_dir: '/b/swarming/w/ir/tmp/t/tmpuaa_mN' 2019-03-18 08:41:21,201 - root: [WARNING] task_output_dir '/b/swarming/w/ir/tmp/t/tmpuaa_mN' already exists! 2019-03-18 08:41:21,201 - root: [WARNING] task_output_dir existing content: [] 2019-03-18 08:41:21,201 - 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/swarming/w/ir/tmp/t/tmpmXHl6j.json -output-dir /b/swarming/w/ir/tmp/t/tmpuaa_mN -task-summary-json /b/swarming/w/ir/tmp/t/tmprdgW8t.json [D2019-03-18T08:41:21.208517-07:00 8790 0 auth.go:1265] Minting a new token {"key":"luci_ctx/d1734d574e3ddd6af42f752dcba6650d6eefe8caa504ef3b3179de03e608f5f0", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-03-18T08:41:21.208602-07:00 8790 0 luci_ctx.go:138] POST http://127.0.0.1:40727/rpc/LuciLocalAuthService.GetOAuthToken {"key":"luci_ctx/d1734d574e3ddd6af42f752dcba6650d6eefe8caa504ef3b3179de03e608f5f0", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-03-18T08:41:21.210824-07:00 8790 0 auth.go:1222] Token expires in 28m0.789245256s {"key":"luci_ctx/d1734d574e3ddd6af42f752dcba6650d6eefe8caa504ef3b3179de03e608f5f0", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-03-18T08:41:22.430045-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:41:23.595866-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:41:24.918294-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:41:26.170842-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:41:27.440208-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:41:28.738785-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:41:30.152837-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:41:31.646739-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:41:33.200877-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:41:34.770653-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:41:36.475516-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:41:38.417533-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:41:40.094401-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:41:41.891339-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:41:43.782566-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:41:45.645029-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:41:47.578983-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:41:49.636711-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:41:51.677678-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:41:53.831304-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:41:56.014403-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:41:58.263599-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:42:00.592333-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:42:03.399487-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:42:05.964876-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:42:08.648786-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:42:11.359218-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:42:14.183867-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:42:17.126634-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:42:20.117973-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:42:23.351171-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:42:26.579366-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:42:29.876424-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:42:33.260042-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:42:36.793733-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:42:40.460956-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [W2019-03-18T08:42:40.462935-07:00 8790 0 retry.go:41] operation failed transiently {"error":"googleapi: got HTTP response code 502 with body: <!DOCTYPE html>\n<html lang=en>\n <meta charset=utf-8>\n <meta name=viewport content=\"initial-scale=1, minimum-scale=1, width=device-width\">\n <title>Error 502 (Server Error)!!1</title>\n <style>\n *{margin:0;padding:0}html,code{font:15px/22px arial,sans-serif}html{background:#fff;color:#222;padding:15px}body{margin:7% auto 0;max-width:390px;min-height:180px;padding:30px 0 15px}* > body{background:url(//www.google.com/images/errors/robot.png) 100% 5px no-repeat;padding-right:205px}p{margin:11px 0 22px;overflow:hidden}ins{color:#777;text-decoration:none}a img{border:0}@media screen and (max-width:772px){body{background:none;margin-top:0;max-width:none;padding-right:0}}#logo{background:url(//www.google.com/images/branding/googlelogo/1x/googlelogo_color_150x54dp.png) no-repeat;margin-left:-5px}@media only screen and (min-resolution:192dpi){#logo{background:url(//www.google.com/images/branding/googlelogo/2x/googlelogo_color_150x54dp.png) no-repeat 0% 0%/100% 100%;-moz-border-image:url(//www.google.com/images/branding/googlelogo/2x/googlelogo_color_150x54dp.png) 0}}@media only screen and (-webkit-min-device-pixel-ratio:2){#logo{background:url(//www.google.com/images/branding/googlelogo/2x/googlelogo_color_150x54dp.png) no-repeat;-webkit-background-size:100% 100%}}#logo{display:inline-block;height:54px;width:150px}\n </style>\n <a href=//www.google.com/><span id=logo aria-label=Google></span></a>\n <p><b>502.</b> <ins>That’s an error.</ins>\n <p>The server encountered a temporary error and could not complete your request.<p>Please try again in 30 seconds. <ins>That’s all we know.</ins>\n", "delay":"200ms", "opname":"GetTaskResult"} [D2019-03-18T08:42:44.422276-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:42:48.327249-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:42:52.395687-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:42:56.556997-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:43:00.866503-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:43:05.361571-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:43:09.954471-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:43:14.753936-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:43:19.862274-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:43:25.038185-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:43:30.481541-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:43:35.980303-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:43:41.615807-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:43:47.503150-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:43:53.690090-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:44:00.012727-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:44:06.578560-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:44:13.334344-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:44:20.283852-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:44:27.510765-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:44:34.873745-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:44:42.456021-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:44:50.467785-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:44:58.628807-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:45:07.445901-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:45:16.098339-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:45:25.078013-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:45:34.362269-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:45:43.896234-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 [D2019-03-18T08:45:53.761808-07:00 8790 0 collect.go:344] Waiting task_id: 43aa48669ec5fd10 43aa48669ec5fd10: exit 0 1 files remaining... Additional test environment: CHROME_DEVEL_SANDBOX=/opt/chromium/chrome_sandbox CHROME_HEADLESS=1 LANG=en_US.UTF-8 Command: bin/run_web_runner_integration_tests --test-launcher-bot-mode --test-launcher-summary-output=/b/s/w/io1HOHJ1/output.json 2019-03-18 08:42:12,266:INFO:root:Connecting to Fuchsia using SSH. 2019-03-18 08:42:26,651:INFO:root:Connected! 2019-03-18 08:42:26,652:INFO:root:Attaching kernel logger. [00000.000] zbi: @ 0xffffff8000a6a000 (9859064 bytes) [00000.000] UART: FIFO depth 16 [00000.000] PMM: boot reserve add [0x100000, 0x2e4fff] [00000.000] PMM: boot reserve add [0xa6a000, 0x13d0fff] [00000.000] PMM: boot reserve marking WIRED [0x100000, 0x2e4fff] [00000.000] PMM: boot reserve marking WIRED [0xa6a000, 0x13d0fff] [00000.000] [00000.000] welcome to Zircon [00000.000] [00000.000] KASLR: .text section at 0xffffffff00100000 [00000.000] INIT: cpu 0, calling hook 0xffffffff0010f234 (global_prng_seed) at level 0x30000, flags 0x1 [00000.000] initializing vm pre-heap [00000.000] VM: marking boot alloc used range [0x13d1000, 0x13d1040) [00000.000] INIT: cpu 0, calling hook 0xffffffff0011c4d8 (elf_build_id) at level 0x4fffe, flags 0x1 [00000.000] INIT: cpu 0, calling hook 0xffffffff0011c664 (version) at level 0x4ffff, flags 0x1 [00000.000] version: [00000.000] arch: x86 [00000.000] platform: XXX [00000.000] target: XXX [00000.000] project: x64 [00000.000] buildid: git-f06654a350f387bb10b0cacb33f90aaba07a8f37-dirty [00000.000] ELF build ID: 23c5e0f51c6dbc28cc446022f0cfcb98db30de88 [00000.000] initializing heap [00000.000] INIT: cpu 0, calling hook 0xffffffff00101f78 (console) at level 0x50000, flags 0x1 [00000.000] INIT: cpu 0, calling hook 0xffffffff00171648 (x86_resource_init) at level 0x50000, flags 0x1 [00000.000] initializing vm [00000.000] VM: reserving kernel region [0xffffffff00100000, 0xffffffff00201000) flags 0x28 name 'kernel_code' [00000.000] VM: reserving kernel region [0xffffffff00201000, 0xffffffff0024f000) flags 0x8 name 'kernel_rodata' [00000.000] VM: reserving kernel region [0xffffffff0024f000, 0xffffffff0025f000) flags 0x18 name 'kernel_data' [00000.000] VM: reserving kernel region [0xffffffff0025f000, 0xffffffff002e5000) flags 0x18 name 'kernel_bss' [00000.000] INIT: cpu 0, calling hook 0xffffffff0017230c (display_memtype) at level 0x60001, flags 0x1 [00000.000] INIT: cpu 0, calling hook 0xffffffff0017534c (acpi_tables) at level 0x60001, flags 0x1 [00000.000] INIT: cpu 0, calling hook 0xffffffff0017046c (hpet) at level 0x60002, flags 0x1 [00000.000] INIT: cpu 0, calling hook 0xffffffff0017082c (apic) at level 0x60002, flags 0x1 [00000.000] x2APIC enabled [00000.000] INIT: cpu 0, calling hook 0xffffffff0017493c (timer) at level 0x60003, flags 0x1 [00000.000] pvclock: Clocksource is stable [00000.000] HPET frequency: 100000 ticks/ms [00000.000] pvclock: Fetching TSC frequency [00000.000] TSC frequency: 2199798 ticks/ms [00001.023] timer features: constant_tsc 1 invariant_tsc 1 tsc_deadline 1 [00001.023] Using TSC as wallclock [00001.023] initializing kernel [00001.023] initializing mp [00001.023] initializing timers [00001.023] INIT: cpu 0, calling hook 0xffffffff0010f488 (global_prng_thread_safe) at level 0x7ffff, flags 0x1 [00001.023] INIT: cpu 0, calling hook 0xffffffff0011b790 (debuglog) at level 0x7ffff, flags 0x1 [00001.023] INIT: cpu 0, calling hook 0xffffffff001e5d10 (thread_set_priority_experiment) at level 0x7ffff, flags 0x1 [00001.023] thread set priority experiment is : ENABLED [00001.023] creating bootstrap completion thread [00001.160] top of bootstrap2() [00001.160] INIT: cpu 0, calling hook 0xffffffff00127f70 (libobject) at level 0x80000, flags 0x1 [00001.160] OOM: started thread [00001.167] INIT: cpu 0, calling hook 0xffffffff0019d880 (dpc) at level 0x80000, flags 0x1 [00001.171] Processor Model Info: type 0 family 0x6 model 0xf stepping 0 [00001.171] display_family 0x6 display_model 0x4f [00001.171] Vendor: Intel [00001.171] Microarch: Broadwell [00001.171] F/M/S: 6/4f/0 [00001.171] Brand: Intel(R) Xeon(R) CPU @ 2.20GHz [00001.171] Features: fpu sse sse2 sse3 ssse3 sse4.1 sse4.2 mmx avx avx2 fxsr pcid xsave aesni clflush [00001.171] fsgsbase tsc_adj smep smap erms rdrand rdseed syscall nx rdtscp invar_tsc tsc_deadline [00001.171] x2apic hypervisor [00001.171] initializing platform [00001.171] INIT: cpu 0, calling hook 0xffffffff0018deb4 (x86_perfmon) at level 0x90000, flags 0x1 [00001.171] x86_perfmon_init_once:497: perfmon: unexpected programmable counter width 0 in cpuid.0AH [00001.172] UART: started IRQ driven RX [00001.172] UART: started IRQ driven TX [00001.172] cpu topology: [00001.172] 0: apic id 0x0 package 0 node 0 core 0 smt 0 BSP [00001.172] 1: apic id 0x1 package 1 node 0 core 0 smt 0 [00001.172] 2: apic id 0x2 package 2 node 0 core 0 smt 0 [00001.172] 3: apic id 0x3 package 3 node 0 core 0 smt 0 [00001.172] Found 4 cpus [00001.172] booting apic ids: 0x1 0x2 0x3 [00001.232] entering scheduler on cpu 2 [00001.233] entering scheduler on cpu 1 [00001.233] entering scheduler on cpu 3 [00001.242] smbios: manufacturer="QEMU" product="Standard PC (Q35 + ICH9, 2009)" [00001.242] initializing target [00001.242] INIT: cpu 0, calling hook 0xffffffff00173bec (x86_pcie_init) at level 0xa0000, flags 0x1 [00001.242] moving to last init level [00001.242] INIT: cpu 0, calling hook 0xffffffff00102a48 (kernel_shell) at level 0xc0000, flags 0x1 [00001.242] INIT: cpu 0, calling hook 0xffffffff001fc0a8 (ktrace) at level 0xc0000, flags 0x1 [00001.286] ktrace: buffer at 0xffffff957b9b6000 (33554432 bytes) [00001.286] INIT: cpu 0, calling hook 0xffffffff001fdfe8 (userboot) at level 0xc0000, flags 0x1 [00001.286] userboot: ramdisk 0x967000 @ 0xffffff8000a6a000 [00001.300] userboot: userboot rodata 0 @ [0x7ce78d9d6000,0x7ce78d9d9000) [00001.300] userboot: userboot code 0x3000 @ [0x7ce78d9d9000,0x7ce78d9e4000) [00001.300] userboot: vdso/full rodata 0 @ [0x7ce78d9e4000,0x7ce78d9eb000) [00001.300] userboot: vdso/full code 0x7000 @ [0x7ce78d9eb000,0x7ce78d9ec000) [00001.300] userboot: entry point @ 0x7ce78d9d9c90 [00001.321] userboot: option "/b/s/w/ir/third_party/fuchsia-sdk/sdk/target/x64/qemu-kernel.bin=" [00001.321] userboot: option "devmgr.epoch=1552923718" [00001.321] userboot: option "zircon.nodename=865fe21d-9376-4e5b-a64c-373b373f4e12" [00001.321] userboot: option "TERM=dumb" [00001.321] userboot: option "kernel.serial=legacy" [00001.321] userboot: option "multiboot.boot_loader_name=qemu" [00001.357] userboot: searching bootfs for 'bin/bootsvc' [00001.357] userboot: 'bin/bootsvc' has PT_INTERP "lib/ld.so.1" [00001.357] userboot: searching bootfs for 'lib/ld.so.1' [00001.357] userboot: userboot: loaded lib/ld.so.1 at 0x5bd56d869000, entry point 0x5bd56d8a62f1 [00001.357] userboot: userboot: loaded vDSO at 0x66778add3000, entry point 0x0 [00001.357] userboot: process bin/bootsvc started. [00001.357] userboot: waiting for loader-service requests... [00001.384] userboot: searching bootfs for 'lib/libasync-default.so' [00001.384] userboot: searching bootfs for 'lib/libfdio.so' [00001.384] userboot: searching bootfs for 'lib/libtrace-engine.so' [00001.385] userboot: searching bootfs for 'lib/liblaunchpad.so' [00001.400] {{{reset}}} [00001.400] {{{module:0:<application>:elf:64913060bc657fc459f4ab5301a5cb477104c184}}} [00001.400] {{{mmap:0x5dd418172000:0x39000:load:0:rx:0}}} [00001.400] {{{mmap:0x5dd4181ab000:0x4000:load:0:rw:0x39000}}} [00001.400] dso: id=64913060bc657fc459f4ab5301a5cb477104c184 base=0x00005dd418172000 name=<application> [00001.400] {{{module:0x2:<vDSO>:elf:5db6a550b7c22b4e5d9c55acc5bbc1d2583f0380}}} [00001.400] {{{mmap:0x66778add3000:0x7000:load:0x2:r:0}}} [00001.400] {{{mmap:0x66778adda000:0x1000:load:0x2:rx:0x7000}}} [00001.400] dso: id=5db6a550b7c22b4e5d9c55acc5bbc1d2583f0380 base=0x000066778add3000 name=<vDSO> [00001.400] {{{module:0x3:libasync-default.so:elf:179378e0029e9d73bcaaf61330269def68f524f0}}} [00001.400] {{{mmap:0x38b38f50000:0x1000:load:0x3:rx:0}}} [00001.400] {{{mmap:0x38b38f51000:0x1000:load:0x3:rw:0x1000}}} [00001.400] dso: id=179378e0029e9d73bcaaf61330269def68f524f0 base=0x0000038b38f50000 name=libasync-default.so [00001.400] {{{module:0x1:libc.so:elf:608526d5035c0cab2fdd1718baca2d9ed1f3441b}}} [00001.400] {{{mmap:0x5bd56d869000:0xcb000:load:0x1:rx:0}}} [00001.400] {{{mmap:0x5bd56d935000:0x6000:load:0x1:rw:0xcc000}}} [00001.400] dso: id=608526d5035c0cab2fdd1718baca2d9ed1f3441b base=0x00005bd56d869000 name=libc.so [00001.400] {{{module:0x4:libfdio.so:elf:b8b2c5016c76236a2d5415d1063cfcebb8fb5101}}} [00001.400] {{{mmap:0x7b4ce11fa000:0x32000:load:0x4:rx:0}}} [00001.400] {{{mmap:0x7b4ce122d000:0x4000:load:0x4:rw:0x33000}}} [00001.400] dso: id=b8b2c5016c76236a2d5415d1063cfcebb8fb5101 base=0x00007b4ce11fa000 name=libfdio.so [00001.400] {{{module:0x5:libtrace-engine.so:elf:0f28e2222418dde22d6dd647ddb13d0d42ff310a}}} [00001.400] {{{mmap:0x7f9bd838f000:0x9000:load:0x5:rx:0}}} [00001.400] {{{mmap:0x7f9bd8398000:0x2000:load:0x5:rw:0x9000}}} [00001.400] dso: id=0f28e2222418dde22d6dd647ddb13d0d42ff310a base=0x00007f9bd838f000 name=libtrace-engine.so [00001.400] {{{module:0x6:liblaunchpad.so:elf:7bbb5d72e7b50ffd31348f1fff0031cd559eaa75}}} [00001.400] {{{mmap:0x39ed2752a000:0x7000:load:0x6:rx:0}}} [00001.400] {{{mmap:0x39ed27531000:0x2000:load:0x6:rw:0x7000}}} [00001.400] dso: id=7bbb5d72e7b50ffd31348f1fff0031cd559eaa75 base=0x000039ed2752a000 name=liblaunchpad.so [00001.401] bootsvc: Starting... [00001.401] userboot: loader-service channel peer closed [00001.401] bootsvc: Creating bootfs service... [00001.401] userboot: finished! [00001.533] bootsvc: Processing bootdata... [00001.533] bootsvc: Loading boot cmdline overrides... [00001.533] bootsvc: Loading kernel VMOs... [00001.533] bootsvc: Creating loader service... [00001.533] bootsvc: Launching next process... [00001.548] bootsvc: launched bin/devcoordinator [00001.553] devcoordinator: main() [00001.554] devcoordinator: coordinator_init() [00001.554] devcoordinator: init [00001.554] devcoordinator: svc init [00001.555] devcoordinator: launch /boot/bin/svchost (svchost) OK [00001.555] devcoordinator: vfs init [00001.575] devcoordinator: launch /boot/bin/fshost (fshost) OK [00001.576] devcoordinator: shell startup [00001.583] fshost: started. [00001.592] devcoordinator: launch /boot/bin/netsvc (netsvc) OK [00001.599] devcoordinator: launch /boot/bin/pwrbtn-monitor (pwrbtn-monitor) OK [00001.607] devcoordinator: launch /boot/bin/virtual-console (virtual-console) OK [00001.632] devcoordinator: no driver info in '/boot/driver/mtk-power.so' [00001.652] devcoordinator: full system required, ignoring fallback drivers until /system is loaded [00001.658] devcoordinator: launch devhost 'devhost:sys': pid=2083 [00001.667] devcoordinator: launch devhost 'devhost:test': pid=2138 [00001.672] devhost: trace provider registry begun [00001.676] devhost: trace provider registry begun [00001.677] devcoordinator: launch devhost 'devhost:root': pid=2232 [00001.680] devcoordinator: launch devhost 'devhost:misc': pid=2294 [00001.696] devhost: trace provider registry begun [00001.719] GetZbiMetadata metadata not found for type 524f426d, extra 0 [00001.731] devhost: trace provider registry begun [00001.746] devcoordinator: launch /boot/bin/sh (sh:console) OK [00001.753] acpi-bus: not using IOMMU [00001.757] GetHwProperties: No PM support [00001.757] IntelPT: No PT support [00001.757] devhost[sys/cpu-trace] bind driver '/boot/driver/cpu-trace.so' failed: -2 [00001.767] devcoordinator: rpc: bind-driver 'cpu-trace' status -2 [00001.769] [sysmem_init 28] async_get_default_dispatcher(): 0x2cb3dc4840a0 [00001.769] [sysmem_bind 40] sysmem_bind() [00001.778] acpi-pwrbtn: initialized [00001.787] vc: new input device /dev/class/input/000 [00001.850] acpi: published device pci(0x2c82361a0180), parent=sys(0x2cb3dc4920c0), handle=0x2cb3dc48f300 [00001.850] acpi: failed to get PCI base bus number for device 'PCI0' (status 4294967295) [00001.863] acpi: published device rtc(0x2c82361a0200), parent=acpi(0x2cb3dc492300), handle=0x2cb3dc48f7b0 [00001.865] acpi: published device i8042(0x2c82361a0280), parent=acpi(0x2cb3dc492300), handle=0x2cb3dc48f840 [00001.879] devcoordinator: launch devhost 'devhost:pci#1:1234:1111': pid=2781 [00001.889] devcoordinator: launch devhost 'devhost:pci#2:1af4:1001': pid=2843 [00001.900] devhost: trace provider registry begun [00001.907] devcoordinator: launch devhost 'devhost:pci#3:8086:100e': pid=2922 [00001.928] devhost: trace provider registry begun [00001.935] devhost: trace provider registry begun [00001.938] devcoordinator: launch devhost 'devhost:pci#5:8086:2922': pid=3034 [00001.945] devhost: WARNING: driver '/boot/driver/pc-ps2.so' did not add device in bind() [00001.946] devhost: trace provider registry begun [00001.956] i8042 device 0xab 0x41 [00001.965] ahci: using MSI interrupt [00001.970] vc: new input device /dev/class/input/001 [00001.980] bochs_vbe: initialized display, 1024 x 768 (stride=1024 format=00020001) [00001.989] vc: new display device /dev/class/display-controller/000/virtcon [00002.000] vc: Successfully attached to display 1 [00002.016] fshost: /dev/class/block/000: FVM? [00002.042] devcoordinator: launch /boot/bin/blobfs (blobfs:/blob) OK [00002.115] i8042 device 0x00 [00002.118] hid: boot mouse hack for "hid-device-002": report count (0->1), inp sz (24->24), out sz (0->0), feat sz (0->0) [00002.135] vc: new input device /dev/class/input/002 [00003.014] netifc: ? /dev/class/ethernet/000 [00003.021] netifc: create 512 eth buffers [00003.025] macaddr: 52:54:00:63:5e:7b [00003.025] ip6addr: fe80::5054:4d:fe63:5e7b [00003.025] snmaddr: ff02::1:ff63:5e7b [00003.025] netsvc: using /dev/class/ethernet/000 [00003.025] netsvc: nodename='865fe21d-9376-4e5b-a64c-373b373f4e12' [00003.025] netsvc: start [00005.636] fshost: starting 'bin/pkgsvr' '991b7b9c21d69fa223ae98733f40cadfcb4cf2ef6900596fa39f889061bea000'... [00005.681] devcoordinator: launch bin/pkgsvr (pkgfs) OK [00005.782] pkgsvr: system: will be served from 991b7b9c21d69fa223ae98733f40cadfcb4cf2ef6900596fa39f889061bea000 [00005.783] pkgsvr: pkgfs serving index /data/pkgfs_index from blobfs /blob [00005.796] fshost: mounting minfs [00005.806] devcoordinator: launch /boot/bin/minfs (minfs:/data) OK [00005.860] devcoordinator: adding system driver 'mt8167s_gpu' '/system/driver/libmsd_img_rgx_mtk.so' [00005.870] devcoordinator: launch /system/bin/appmgr (appmgr) OK [00005.955] devcoordinator: adding system driver 'ralink' '/system/driver/ralink.so' [00006.002] devcoordinator: adding system driver 'wlan' '/system/driver/wlanif.so' [00006.093] devcoordinator: adding system driver 'qmi_usb' '/system/driver/qmi-transport.so' [00006.181] devcoordinator: adding system driver 'usb_video' '/system/driver/usb_video.so' [00006.331] devcoordinator: adding system driver 'wlan' '/system/driver/wlan.so' [00006.394] devcoordinator: adding system driver 'virtual_audio' '/system/driver/virtual_audio_driver.so' [00006.409] devcoordinator: adding system driver 'wlantapctl' '/system/driver/wlantap.so' [00006.468] devcoordinator: adding system driver 'bt_hci_intel' '/system/driver/bt-hci-intel.so' [00006.506] devcoordinator: adding system driver 'bt_hog' '/system/driver/bt-hog.so' [00006.558] devcoordinator: adding system driver 'rtl88xx' '/system/driver/rtl88xx.so' [00006.592] devcoordinator: adding system driver 'brcmfmac' '/system/driver/brcmfmac.so' [00006.686] devcoordinator: adding system driver 'wlan' '/system/driver/wlanphy.so' [00006.736] devcoordinator: adding system driver 'qmi_fake' '/system/driver/qmi-fake.so' [00006.797] devcoordinator: adding system driver 'bt_hci_atheros' '/system/driver/bt-hci-atheros.so' [00006.798] [INFO:namespace_builder.cc(93)] config-data for sysmgr [00006.887] devcoordinator: adding system driver 'bt_host' '/system/driver/bt-host.so' [00006.956] devcoordinator: adding system driver 'gpu' '/system/driver/libmsd_intel.so' [00006.993] devcoordinator: adding system driver 'ath10k_pci' '/system/driver/ath10k.so' [00007.029] devcoordinator: adding system driver 'bt_hci_fake' '/system/driver/bt-hci-fake.so' [00007.039] devcoordinator: adding system driver 'bt_hci_passthrough' '/system/driver/bt-hci-passthrough.so' [00007.040] devcoordinator: driver 'bt_hci_fake' added [00007.040] devcoordinator: driver 'ath10k_pci' added [00007.040] devcoordinator: driver 'gpu' added [00007.040] devcoordinator: driver 'bt_host' added [00007.040] devcoordinator: driver 'bt_hci_atheros' added [00007.040] devcoordinator: driver 'qmi_fake' added [00007.040] devcoordinator: driver 'wlan' added [00007.040] devcoordinator: driver 'brcmfmac' added [00007.040] devcoordinator: driver 'rtl88xx' added [00007.040] devcoordinator: driver 'bt_hog' added [00007.040] devinitialized repo /b/s/w/itnwRPv1/tmpvtKfjk adding package web_runner_integration_tests/0 initialized repo /b/s/w/itnwRPv1/tmpvtKfjk adding package chromium/0 initialized repo /b/s/w/itnwRPv1/tmpvtKfjk adding package web_runner/0 2019/03/18 15:42:29 error removing source: Source not found 2019-03-18 08:42:30,150:INFO:root:Installing web_runner_integration_tests version 0. 2019/03/18 15:42:32 Awaiting response... 2019/03/18 15:42:35 Awaiting response... 2019/03/18 15:42:38 Awaiting response... 2019/03/18 15:42:41 Awaiting response... 2019/03/18 15:42:44 Awaiting response... 2019/03/18 15:42:48 Awaiting response... 2019/03/18 15:42:51 Awaiting response... 2019/03/18 15:42:54 Awaiting response... 2019/03/18 15:42:57 Awaiting response... 2019/03/18 15:43:00 Awaiting response... 2019/03/18 15:43:03 Awaiting response... 2019/03/18 15:43:06 Awaiting response... 2019/03/18 15:43:09 Awaiting response... 2019/03/18 15:43:13 Awaiting response... 2019/03/18 15:43:16 Awaiting response... 2019/03/18 15:43:19 Awaiting response... 2019/03/18 15:43:22 Awaiting response... 2019/03/18 15:43:25 Awaiting response... 2019/03/18 15:43:28 Awaiting response... 2019/03/18 15:43:31 Awaiting response... 2019/03/18 15:43:34 Awaiting response... 2019/03/18 15:43:37 Awaiting response... 2019/03/18 15:43:40 Awaiting response... 2019/03/18 15:43:43 Awaiting response... 2019/03/18 15:43:46 Awaiting response... 2019/03/18 15:43:49 Awaiting response... 2019/03/18 15:43:52 Awaiting response... 2019/03/18 15:43:56 Awaiting response... 2019/03/18 15:43:59 Awaiting response... 2019/03/18 15:44:02 Awaiting response... 2019/03/18 15:44:05 Awaiting response... 2019/03/18 15:44:08 Awaiting response... 2019/03/18 15:44:11 Awaiting response... 2019/03/18 15:44:14 Awaiting response... 2019/03/18 15:44:18 Awaiting response... 2019/03/18 15:44:21 Awaiting response... 2019/03/18 15:44:24 Awaiting response... 2019/03/18 15:44:27 Awaiting response... 2019/03/18 15:44:30 Awaiting response... 2019/03/18 15:44:33 Awaiting response... 2019/03/18 15:44:36 Awaiting response... 2019/03/18 15:44:39 Awaiting response... 2019/03/18 15:44:43 Awaiting response... 2019/03/18 15:44:46 Awaiting response... 2019/03/18 15:44:49 Awaiting response... 2019/03/18 15:44:52 Awaiting response... 2019/03/18 15:44:55 Awaiting response... 2019/03/18 15:44:58 Awaiting response... 2019/03/18 15:45:01 Awaiting response... 2019/03/18 15:45:04 Awaiting response... 2019/03/18 15:45:07 Awaiting response... 2019/03/18 15:45:11 Awaiting response... 2019/03/18 15:45:14 Awaiting response... 2019/03/18 15:45:17 Awaiting response... 2019/03/18 15:45:20 Awaiting response... 2019/03/18 15:45:23 Awaiting response... 2019/03/18 15:45:26 Awaiting response... 2019/03/18 15:45:29 Awaiting response... 2019/03/18 15:45:30 Success web_runner_integration_tests/0: b3fc951d761a09b7ce1dcc6dd919b7e306cd9ac7876280c50886091a214a9231 2019-03-18 08:45:32,719:INFO:root:Installing chromium version 0. 2019/03/18 15:45:32 Success chromium/0: c259b956012926aa97f62af12b5eeb32d1051270aa6a92ee9e52edea20c113fe 2019-03-18 08:45:33,896:INFO:root:Installing web_runner version 0. 2019/03/18 15:45:33 Success web_runner/0: 0bade711c68b44d7c690ea77b6a93f62141c95f400bef17ffbab5ff1ca568f2a 2019-03-18 08:45:34,360:INFO:root:Running application. 2019-03-18 08:45:34,363:INFO:root:Running "/b/s/w/ir/third_party/fuchsia-sdk/sdk/tools/symbolize -ids /b/s/w/ir/out/Debug/gen/fuchsia/runners/web_runner_integration_tests/ids.txt -ids-rel -llvm-symbolizer /b/s/w/ir/third_party/fuchsia-sdk/sdk/../../llvm-build/Release+Asserts/bin/llvm-symbolizer -build-id-dir /b/s/w/ir/third_party/fuchsia-sdk/sdk/.build-id". coordinator: driver 'bt_hci_intel' added [00007.040] devcoordinator: driver 'wlan' added [00007.040] devcoordinator: driver 'usb_video' added [00007.040] devcoordinator: driver 'qmi_usb' added [00007.040] devcoordinator: driver 'wlan' added [00007.040] devcoordinator: driver 'ralink' added [00007.040] devcoordinator: driver 'mt8167s_gpu' added [00007.040] devcoordinator: driver 'bt_hci_passthrough' added [00007.040] devcoordinator: fallback driver 'usb_composite' is available [00007.040] devcoordinator: driver 'usb_composite' added [00007.040] devcoordinator: fallback driver 'intel_disp' is available [00007.040] devcoordinator: driver 'intel_disp' added [00008.070] [INFO:cobalt_main.cc(145)] Cobalt client schedule params: schedule_interval=3600 seconds, min_interval=10 seconds, initial_interval=60 seconds. [00008.584] [INFO:namespace_builder.cc(93)] config-data for wlancfg [00009.573] [INFO:namespace_builder.cc(93)] config-data for crashpad_agent [00009.779] [INFO:main.cc(35)] Trace Manager starting with config: /pkg/data/tracing.config [00009.927] wlanstack2 [I]: Starting [00010.347] [INFO:a11y_input_system.cc(9)] Scenic accessibility input system started. [00010.355] [INFO:display_watcher.cc(40)] Scenic: Acquired display controller /dev/class/display-controller/000.(000) [00010.803] [INFO:input_interpreter.cc(1006)] hid-parser succesful for 000 with usage page 1 and usage 128 [00010.884] Starting mDNS on interface ethp0003 192.168.3.9 [00010.893] Starting mDNS on interface ethp0003 [80fe::5450:ff00:63fe:7b5e] [00010.903] [ERROR:garnet/bin/mdns/service/mdns_interface_transceiver_v6.cc(49)] Failed to set socket option IPV6_JOIN_GROUP, No such device [00010.910] mDNS: Verifying uniqueness of host name step-dime-yard-juicy.local. [00011.096] ERROR: setupLoaderTermPhysDevs: Failed to detect any valid GPUs in the current config [00011.096] ERROR: setupLoaderTrampPhysDevs: Failed during dispatch call of 'vkEnumeratePhysicalDevices' to lower layers or loader to get count. [00011.096] [ERROR:garnet/lib/ui/gfx/gfx_system.cc(188)] No Vulkan on device, Graphics system exiting. [00011.096] [INFO:input_system.cc(245)] Scenic input system initialized. [00011.118] [ERROR:garnet/bin/ui/view_manager/view_registry.cc(80)] Exiting due to scene manager connection error. [00011.118] [ERROR:garnet/bin/ui/root_presenter/app.cc(214)] Scenic died, destroying all presentations. [00011.153] [ERROR:garnet/bin/sysmgr/app.cc(166)] Singleton fuchsia-pkg://fuchsia.com/view_manager#meta/view_manager.cmx died [00011.153] [ERROR:garnet/bin/sysmgr/app.cc(166)] Singleton fuchsia-pkg://fuchsia.com/scenic#meta/scenic.cmx died [00011.912] mDNS: Using unique host name step-dime-yard-juicy.local. [00015.542] pkgsvr: asking amber to fetch 2533 needed blobs [00196.363] pkgsvr: package activated web_runner_integration_tests/0 (b3fc951d761a09b7ce1dcc6dd919b7e306cd9ac7876280c50886091a214a9231) [00197.943] pkgsvr: asking amber to fetch 3 needed blobs [00198.808] pkgsvr: package activated chromium/0 (c259b956012926aa97f62af12b5eeb32d1051270aa6a92ee9e52edea20c113fe) [00198.950] pkgsvr: asking amber to fetch 1 needed blobs [00199.159] pkgsvr: package activated web_runner/0 (0bade711c68b44d7c690ea77b6a93f62141c95f400bef17ffbab5ff1ca568f2a) IMPORTANT DEBUGGING NOTE: batches of tests are run inside their own process. For debugging a test inside a debugger, use the --gtest_filter=<your_test_name> flag along with --single-process-tests. Using sharding settings from environment. This is shard 0/1 Using 4 parallel jobs. [00206.632] pkgsvr: pkgfs:unsupported(/packages/chromium/0): dir unlink "webrunner.log" [00206.698] [133764:804322011:0318/154540.815089:ERROR:base_paths_fuchsia.cc(21)] Not implemented reached in bool base::PathProviderFuchsia(int, base::FilePath *) for FILE_MODULE. [00206.720] [133764:795921681:0318/154540.828683:ERROR:platform_thread_fuchsia.cc(42)] Not implemented reached in static void base::PlatformThread::SetCurrentThreadPriorityImpl(base::ThreadPriority) [00206.829] [133764:795921681:0318/154540.945462:ERROR:web_engine_url_request_context_getter.cc(602019-03-18 08:45:44,620:INFO:root:Process exited normally with status code 0. 2019-03-18 08:45:44,620:INFO:root:Terminating kernel log reader. 2019-03-18 08:45:45,257:INFO:root:Shutting down QEMU. )] Not implemented reached in virtual net::URLRequestContext *WebEngineURLRequestContextGetter::GetURLRequestContext()Persistent cookie st [00206.829] ore is NOT encrypted! [00206.899] [133764:786995519:0318/154541.015449:ERROR:child_process_launcher_helper_fuchsia.cc(22)] Not implemented reached in void content::internal::ChildProcessLauncherHelper::SetProcessPriorityOnLauncherThread(base::Process, const [00206.899] content::ChildProcessLauncherPriority &) [00207.933] pkgsvr: pkgfs:unsupported(/packages/chromium/0): dir unlink "webrunner.log" [00207.946] [134998:773336513:0318/154542.063075:ERROR:base_paths_fuchsia.cc(21)] Not implemented reached in bool base::PathProviderFuchsia(int, base::FilePath *) for FILE_MODULE. [00207.960] [134998:773336513:0318/154542.076424:ERROR:platform_thread_fuchsia.cc(42)] Not implemented reached in static void base::PlatformThread::SetCurrentThreadPriorityImpl(base::ThreadPriority) [00207.971] [134998:773336513:0318/154542.087567:ERROR:gpu_info_collector_fuchsia.cc(22)] Not implemented reached in bool gpu::CollectBasicGraphicsInfo(gpu::GPUInfo *) [00207.974] [134998:773336513:0318/154542.090620:ERROR:egl_util.cc(60)] Failed to load GLES library: libswiftshader_libGLESv2.so: open library: FILE_ERROR_NOT_FOUND [00208.011] [134998:773336513:0318/154542.128290:ERROR:viz_main_impl.cc(170)] Exiting GPU process due to errors during initialization [00208.495] pkgsvr: pkgfs:unsupported(/packages/chromium/0): dir unlink "webrunner.log" [00208.495] pkgsvr: pkgfs:unsupported(/packages/chromium/0): dir unlink "webrunner.log" [00208.518] [135181:2132067825:0318/154542.634631:ERROR:base_paths_fuchsia.cc(21)] Not implemented reached in bool base::PathProviderFuchsia(int, base::FilePath *) for FILE_MODULE. [00208.518] [135254:1228731271:0318/154542.635198:ERROR:base_paths_fuchsia.cc(21)] Not implemented reached in bool base::PathProviderFuchsia(int, base::FilePath *) for FILE_MODULE. [00208.732] [135254:1222440447:0318/154542.848769:ERROR:platform_thread_fuchsia.cc(42)] Not implemented reached in static void base::PlatformThread::SetCurrentThreadPriorityImpl(base::ThreadPriority) [00208.732] [135181:2129970207:0318/154542.848827:ERROR:platform_thread_fuchsia.cc(42)] Not implemented reached in static void base::PlatformThread::SetCurrentThreadPriorityImpl(base::ThreadPriority) [00208.783] [133764:804322011:0318/154542.899821:ERROR:time_zone_monitor_fuchsia.cc(30)] Not implemented reached in static std::unique_ptr<TimeZoneMonitor> device::TimeZoneMonitor::Create(scoped_refptr<base::SequencedTaskRunner>) [00208.900] pkgsvr: pkgfs:unsupported(/packages/chromium/0): dir unlink "webrunner.log" [00208.916] [138737:782664831:0318/154543.032833:ERROR:base_paths_fuchsia.cc(21)] Not implemented reached in bool base::PathProviderFuchsia(int, base::FilePath *) for FILE_MODULE. [00208.928] [138737:782664831:0318/154543.045072:ERROR:platform_thread_fuchsia.cc(42)] Not implemented reached in static void base::PlatformThread::SetCurrentThreadPriorityImpl(base::ThreadPriority) [00208.939] [138737:782664831:0318/154543.055987:ERROR:gpu_info_collector_fuchsia.cc(22)] Not implemented reached in bool gpu::CollectBasicGraphicsInfo(gpu::GPUInfo *) [00208.952] [138737:782664831:0318/154543.068844:ERROR:egl_util.cc(60)] Failed to load GLES library: libswiftshader_libGLESv2.so: open library: FILE_ERROR_NOT_FOUND [00209.039] [133764:795921681:0318/154543.156047:ERROR:gpu_process_host.cc(560)] BrowserMainRunner::ExitedMainMessageLoop() [00209.259] [133764:795921681:0318/154543.375879:WARNING:discardable_shared_memory_manager.cc(410)] Some MojoDiscardableSharedMemoryManagerImpls are still alive. They will be leaked. [1/1] WebRunnerSmokeTest.RequestHtmlAndImage (4102 ms) SUCCESS: all tests passed. Tests took 5 seconds. 2019-03-18 08:45:54,144 - root: [DEBUG] Contents of task_output_dir: ['/b/swarming/w/ir/tmp/t/tmpuaa_mN/43aa48669ec5fd10'] 2019-03-18 08:45:54,145 - root: [DEBUG] Found shard_json_files: ['/b/swarming/w/ir/tmp/t/tmpuaa_mN/43aa48669ec5fd10/output.json'] 2019-03-18 08:45:54,145 - root: [INFO] merge_cmd: /b/swarming/w/ir/cache/vpython/ba947a/bin/python /b/swarming/w/ir/cache/builder/src/testing/merge_scripts/standard_gtest_merge.py --build-properties {"bot_id": "swarm1860-c4", "branch": "refs/heads/master", "buildbucket": {"build": {"bucket": "luci.chromium.ci", "created_by": "project:chromium", "created_ts": 1552917615408364, "id": "8918641574464341200", "project": "chromium", "tags": ["build_address:luci.chromium.ci/fuchsia-fyi-x64-dbg/5879", "builder:fuchsia-fyi-x64-dbg", "buildset:commit/git/0fc91f390b416bba3b8d13dd89698b2bd18355c1", "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0fc91f390b416bba3b8d13dd89698b2bd18355c1", "gitiles_ref:refs/heads/master", "scheduler_invocation_id:9084071636522365152", "scheduler_job_id:chromium/fuchsia-fyi-x64-dbg", "user_agent:luci-scheduler"]}, "hostname": "cr-buildbucket.appspot.com"}, "buildername": "fuchsia-fyi-x64-dbg", "buildnumber": 5879, "got_angle_revision": "38e282570676f3e434482f8a02ea539b286fd1fa", "got_dawn_revision": "991ab98f113f5f51ec87d767b45523b19cd15b9e", "got_nacl_revision": "1ed9878b8eb68c1e0d0268754dafcf83e747fa8f", "got_revision": "0fc91f390b416bba3b8d13dd89698b2bd18355c1", "got_revision_cp": "refs/heads/master@{#641578}", "got_swarming_client_revision": "7a61cf37d6a0163f0ec02d495289a1d038e62457", "got_v8_revision": "f413181b62ee4ad78b9ef3d58c4f395794e16bf1", "got_v8_revision_cp": "refs/heads/7.5.59@{#1}", "got_webrtc_revision": "df644be3536b61256ab9e22bb0891a8b6a1d01c2", "got_webrtc_revision_cp": "refs/heads/master@{#27155}", "mastername": "chromium.fyi", "path_config": "generic", "recipe": "chromium", "repository": "https://chromium.googlesource.com/chromium/src.git", "revision": "0fc91f390b416bba3b8d13dd89698b2bd18355c1"} --summary-json /b/swarming/w/ir/tmp/t/tmprdgW8t.json --task-output-dir /b/swarming/w/ir/tmp/t/tmpuaa_mN -o /b/swarming/w/ir/tmp/t/tmpA1ZyxE.json /b/swarming/w/ir/tmp/t/tmpuaa_mN/43aa48669ec5fd10/output.json Running ['/b/swarming/w/ir/cache/vpython/ba947a/bin/python', '/b/swarming/w/ir/cache/builder/src/testing/merge_scripts/standard_gtest_merge.py', '--build-properties', '{"bot_id": "swarm1860-c4", "branch": "refs/heads/master", "buildbucket": {"build": {"bucket": "luci.chromium.ci", "created_by": "project:chromium", "created_ts": 1552917615408364, "id": "8918641574464341200", "project": "chromium", "tags": ["build_address:luci.chromium.ci/fuchsia-fyi-x64-dbg/5879", "builder:fuchsia-fyi-x64-dbg", "buildset:commit/git/0fc91f390b416bba3b8d13dd89698b2bd18355c1", "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0fc91f390b416bba3b8d13dd89698b2bd18355c1", "gitiles_ref:refs/heads/master", "scheduler_invocation_id:9084071636522365152", "scheduler_job_id:chromium/fuchsia-fyi-x64-dbg", "user_agent:luci-scheduler"]}, "hostname": "cr-buildbucket.appspot.com"}, "buildername": "fuchsia-fyi-x64-dbg", "buildnumber": 5879, "got_angle_revision": "38e282570676f3e434482f8a02ea539b286fd1fa", "got_dawn_revision": "991ab98f113f5f51ec87d767b45523b19cd15b9e", "got_nacl_revision": "1ed9878b8eb68c1e0d0268754dafcf83e747fa8f", "got_revision": "0fc91f390b416bba3b8d13dd89698b2bd18355c1", "got_revision_cp": "refs/heads/master@{#641578}", "got_swarming_client_revision": "7a61cf37d6a0163f0ec02d495289a1d038e62457", "got_v8_revision": "f413181b62ee4ad78b9ef3d58c4f395794e16bf1", "got_v8_revision_cp": "refs/heads/7.5.59@{#1}", "got_webrtc_revision": "df644be3536b61256ab9e22bb0891a8b6a1d01c2", "got_webrtc_revision_cp": "refs/heads/master@{#27155}", "mastername": "chromium.fyi", "path_config": "generic", "recipe": "chromium", "repository": "https://chromium.googlesource.com/chromium/src.git", "revision": "0fc91f390b416bba3b8d13dd89698b2bd18355c1"}', '--summary-json', '/b/swarming/w/ir/tmp/t/tmprdgW8t.json', '--task-output-dir', '/b/swarming/w/ir/tmp/t/tmpuaa_mN', '-o', '/b/swarming/w/ir/tmp/t/tmpA1ZyxE.json', '/b/swarming/w/ir/tmp/t/tmpuaa_mN/43aa48669ec5fd10/output.json'] in None (env: None) Command ['/b/swarming/w/ir/cache/vpython/ba947a/bin/python', '/b/swarming/w/ir/cache/builder/src/testing/merge_scripts/standard_gtest_merge.py', '--build-properties', '{"bot_id": "swarm1860-c4", "branch": "refs/heads/master", "buildbucket": {"build": {"bucket": "luci.chromium.ci", "created_by": "project:chromium", "created_ts": 1552917615408364, "id": "8918641574464341200", "project": "chromium", "tags": ["build_address:luci.chromium.ci/fuchsia-fyi-x64-dbg/5879", "builder:fuchsia-fyi-x64-dbg", "buildset:commit/git/0fc91f390b416bba3b8d13dd89698b2bd18355c1", "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0fc91f390b416bba3b8d13dd89698b2bd18355c1", "gitiles_ref:refs/heads/master", "scheduler_invocation_id:9084071636522365152", "scheduler_job_id:chromium/fuchsia-fyi-x64-dbg", "user_agent:luci-scheduler"]}, "hostname": "cr-buildbucket.appspot.com"}, "buildername": "fuchsia-fyi-x64-dbg", "buildnumber": 5879, "got_angle_revision": "38e282570676f3e434482f8a02ea539b286fd1fa", "got_dawn_revision": "991ab98f113f5f51ec87d767b45523b19cd15b9e", "got_nacl_revision": "1ed9878b8eb68c1e0d0268754dafcf83e747fa8f", "got_revision": "0fc91f390b416bba3b8d13dd89698b2bd18355c1", "got_revision_cp": "refs/heads/master@{#641578}", "got_swarming_client_revision": "7a61cf37d6a0163f0ec02d495289a1d038e62457", "got_v8_revision": "f413181b62ee4ad78b9ef3d58c4f395794e16bf1", "got_v8_revision_cp": "refs/heads/7.5.59@{#1}", "got_webrtc_revision": "df644be3536b61256ab9e22bb0891a8b6a1d01c2", "got_webrtc_revision_cp": "refs/heads/master@{#27155}", "mastername": "chromium.fyi", "path_config": "generic", "recipe": "chromium", "repository": "https://chromium.googlesource.com/chromium/src.git", "revision": "0fc91f390b416bba3b8d13dd89698b2bd18355c1"}', '--summary-json', '/b/swarming/w/ir/tmp/t/tmprdgW8t.json', '--task-output-dir', '/b/swarming/w/ir/tmp/t/tmpuaa_mN', '-o', '/b/swarming/w/ir/tmp/t/tmpA1ZyxE.json', '/b/swarming/w/ir/tmp/t/tmpuaa_mN/43aa48669ec5fd10/output.json'] returned exit code 0