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/tmpffIu7M.json --task-output-dir /b/swarming/w/ir/tmp/t/tmppUyeCP --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/tmpgb94W9merge_script_log --merge-additional-args '[]' --build-properties '{"bot_id": "swarm1861-c4", "branch": "refs/heads/master", "buildbucket": {"build": {"bucket": "luci.chromium.ci", "created_by": "project:chromium", "created_ts": 1552787129703066, "id": "8918778398642642912", "project": "chromium", "tags": ["build_address:luci.chromium.ci/fuchsia-fyi-x64-rel/8963", "builder:fuchsia-fyi-x64-rel", "buildset:commit/git/ea9077b3af407be0e97766f16f773703edf4627b", "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ea9077b3af407be0e97766f16f773703edf4627b", "gitiles_ref:refs/heads/master", "scheduler_invocation_id:9084208460803399760", "scheduler_job_id:chromium/fuchsia-fyi-x64-rel", "user_agent:luci-scheduler"]}, "hostname": "cr-buildbucket.appspot.com"}, "buildername": "fuchsia-fyi-x64-rel", "buildnumber": 8963, "got_angle_revision": "38e282570676f3e434482f8a02ea539b286fd1fa", "got_dawn_revision": "931311700c7c4b76a15c23eee8792dda125fb97e", "got_nacl_revision": "1ed9878b8eb68c1e0d0268754dafcf83e747fa8f", "got_revision": "ea9077b3af407be0e97766f16f773703edf4627b", "got_revision_cp": "refs/heads/master@{#641464}", "got_swarming_client_revision": "7a61cf37d6a0163f0ec02d495289a1d038e62457", "got_v8_revision": "f413181b62ee4ad78b9ef3d58c4f395794e16bf1", "got_v8_revision_cp": "refs/heads/7.5.59@{#1}", "got_webrtc_revision": "69008a87185992aa348ece3f893f6a84f8786ae8", "got_webrtc_revision_cp": "refs/heads/master@{#27145}", "mastername": "chromium.fyi", "path_config": "generic", "recipe": "chromium", "repository": "https://chromium.googlesource.com/chromium/src.git", "revision": "ea9077b3af407be0e97766f16f773703edf4627b"}' --summary-json-file /b/swarming/w/ir/tmp/t/tmpX6sXWj.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/tmpjGDLSQ.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/tmpffIu7M.json', '--task-output-dir', '/b/swarming/w/ir/tmp/t/tmppUyeCP', '--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/tmpgb94W9merge_script_log', '--merge-additional-args', '[]', '--build-properties', '{"bot_id": "swarm1861-c4", "branch": "refs/heads/master", "buildbucket": {"build": {"bucket": "luci.chromium.ci", "created_by": "project:chromium", "created_ts": 1552787129703066, "id": "8918778398642642912", "project": "chromium", "tags": ["build_address:luci.chromium.ci/fuchsia-fyi-x64-rel/8963", "builder:fuchsia-fyi-x64-rel", "buildset:commit/git/ea9077b3af407be0e97766f16f773703edf4627b", "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ea9077b3af407be0e97766f16f773703edf4627b", "gitiles_ref:refs/heads/master", "scheduler_invocation_id:9084208460803399760", "scheduler_job_id:chromium/fuchsia-fyi-x64-rel", "user_agent:luci-scheduler"]}, "hostname": "cr-buildbucket.appspot.com"}, "buildername": "fuchsia-fyi-x64-rel", "buildnumber": 8963, "got_angle_revision": "38e282570676f3e434482f8a02ea539b286fd1fa", "got_dawn_revision": "931311700c7c4b76a15c23eee8792dda125fb97e", "got_nacl_revision": "1ed9878b8eb68c1e0d0268754dafcf83e747fa8f", "got_revision": "ea9077b3af407be0e97766f16f773703edf4627b", "got_revision_cp": "refs/heads/master@{#641464}", "got_swarming_client_revision": "7a61cf37d6a0163f0ec02d495289a1d038e62457", "got_v8_revision": "f413181b62ee4ad78b9ef3d58c4f395794e16bf1", "got_v8_revision_cp": "refs/heads/7.5.59@{#1}", "got_webrtc_revision": "69008a87185992aa348ece3f893f6a84f8786ae8", "got_webrtc_revision_cp": "refs/heads/master@{#27145}", "mastername": "chromium.fyi", "path_config": "generic", "recipe": "chromium", "repository": "https://chromium.googlesource.com/chromium/src.git", "revision": "ea9077b3af407be0e97766f16f773703edf4627b"}', '--summary-json-file', '/b/swarming/w/ir/tmp/t/tmpX6sXWj.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/tmpjGDLSQ.json'] env: {'CHROME_HEADLESS': '1'} env_prefixes: {'PATH': '/b/swarming/w/ir/cache/swarming_client'} env_suffixes: {} infra_step: False name_tokens: ('web_engine_browsertests',) 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: 35510 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/8918778398642642912 LOGDOG_STREAM_PROJECT: chromium LOGDOG_STREAM_SERVER_PATH: unix:/b/swarming/w/ir/tmp/ld.sock LOGICAL: eth0 LUCI_CONTEXT: /b/swarming/w/it_73s9g/luci_context.375824844 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: swarm1861-c4 SWARMING_EXTERNAL_BOT_SETUP: 1 SWARMING_HEADLESS: 1 SWARMING_SERVER: https://chromium-swarm.appspot.com SWARMING_TASK_ID: 43a224cff974e211 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-16 19:15:57,438 - root: [DEBUG] Using task_output_dir: '/b/swarming/w/ir/tmp/t/tmppUyeCP' 2019-03-16 19:15:57,438 - root: [WARNING] task_output_dir '/b/swarming/w/ir/tmp/t/tmppUyeCP' already exists! 2019-03-16 19:15:57,439 - root: [WARNING] task_output_dir existing content: [] 2019-03-16 19:15:57,439 - 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/tmpjGDLSQ.json -output-dir /b/swarming/w/ir/tmp/t/tmppUyeCP -task-summary-json /b/swarming/w/ir/tmp/t/tmpX6sXWj.json [D2019-03-16T19:15:57.444621-07:00 29982 0 auth.go:1265] Minting a new token {"key":"luci_ctx/be0f19ba1777094e65e00fcecc721419bd737035d053dacba0c66e92e2390551", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-03-16T19:15:57.444710-07:00 29982 0 luci_ctx.go:138] POST http://127.0.0.1:43788/rpc/LuciLocalAuthService.GetOAuthToken {"key":"luci_ctx/be0f19ba1777094e65e00fcecc721419bd737035d053dacba0c66e92e2390551", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-03-16T19:15:57.446369-07:00 29982 0 auth.go:1222] Token expires in 4m4.553669143s {"key":"luci_ctx/be0f19ba1777094e65e00fcecc721419bd737035d053dacba0c66e92e2390551", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-03-16T19:15:58.616454-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:15:59.735374-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:16:00.911448-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:16:02.290126-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:16:03.550471-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:16:04.900751-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:16:06.275647-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:16:07.665287-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:16:09.102442-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:16:10.637136-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:16:12.220009-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:16:13.901443-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:16:15.729036-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:16:17.578421-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:16:19.351890-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:16:21.246910-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:16:23.291294-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:16:25.334346-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:16:27.554680-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:16:29.733219-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:16:31.951047-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:16:34.209651-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:16:36.539720-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:16:38.969837-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:16:41.462517-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:16:44.060055-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:16:46.744913-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:16:49.611070-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:16:52.490059-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:16:55.418257-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:16:58.462655-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:17:01.587552-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:17:04.819544-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:17:08.170169-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:17:11.640988-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:17:15.240787-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:17:18.932303-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:17:22.990250-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:17:27.149048-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:17:31.236379-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:17:35.464028-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:17:39.846681-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:17:39.886887-07:00 29982 0 auth.go:1274] Refreshing the token {"key":"luci_ctx/be0f19ba1777094e65e00fcecc721419bd737035d053dacba0c66e92e2390551", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-03-16T19:17:39.886983-07:00 29982 0 luci_ctx.go:138] POST http://127.0.0.1:43788/rpc/LuciLocalAuthService.GetOAuthToken {"key":"luci_ctx/be0f19ba1777094e65e00fcecc721419bd737035d053dacba0c66e92e2390551", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-03-16T19:17:39.998514-07:00 29982 0 auth.go:1222] Token expires in 57m28.001534525s {"key":"luci_ctx/be0f19ba1777094e65e00fcecc721419bd737035d053dacba0c66e92e2390551", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-03-16T19:17:44.476989-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:17:49.172527-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:17:54.000003-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 [D2019-03-16T19:17:59.017054-07:00 29982 0 collect.go:344] Waiting task_id: 43a240ae0adcde10 43a240ae0adcde10: exit 0 Additional test environment: CHROME_DEVEL_SANDBOX=/opt/chromium/chrome_sandbox CHROME_HEADLESS=1 LANG=en_US.UTF-8 Command: bin/run_web_engine_browsertests --test-launcher-bot-mode --test-launcher-summary-output=/b/s/w/iosGpSTv/output.json 2019-03-16 19:16:06,435:INFO:root:Connecting to Fuchsia using SSH. 2019-03-16 19:16:13,618:INFO:root:Connected! 2019-03-16 19:16:13,618:INFO:root:Attaching kernel logger. [00000.000] zbi: @ 0xffffff8000a6a000 (9856728 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-5f2dd2258e6159b2f5ca75e2ccbe358e1fa782d2-dirty [00000.000] ELF build ID: fa087b26b89e407aec091d2abcc2c221a9bf149e [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: 2199802 ticks/ms [00000.428] timer features: constant_tsc 1 invariant_tsc 1 tsc_deadline 1 [00000.428] Using TSC as wallclock [00000.428] initializing kernel [00000.428] initializing mp [00000.428] initializing timers [00000.428] INIT: cpu 0, calling hook 0xffffffff0010f488 (global_prng_thread_safe) at level 0x7ffff, flags 0x1 [00000.428] INIT: cpu 0, calling hook 0xffffffff0011b790 (debuglog) at level 0x7ffff, flags 0x1 [00000.428] INIT: cpu 0, calling hook 0xffffffff001e5d10 (thread_set_priority_experiment) at level 0x7ffff, flags 0x1 [00000.428] thread set priority experiment is : ENABLED [00000.428] creating bootstrap completion thread [00000.571] top of bootstrap2() [00000.571] INIT: cpu 0, calling hook 0xffffffff00127f70 (libobject) at level 0x80000, flags 0x1 [00000.571] OOM: started thread [00000.579] INIT: cpu 0, calling hook 0xffffffff0019d880 (dpc) at level 0x80000, flags 0x1 [00000.583] Processor Model Info: type 0 family 0x6 model 0xf stepping 0 [00000.583] display_family 0x6 display_model 0x4f [00000.583] Vendor: Intel [00000.583] Microarch: Broadwell [00000.583] F/M/S: 6/4f/0 [00000.583] Brand: Intel(R) Xeon(R) CPU @ 2.20GHz [00000.583] Features: fpu sse sse2 sse3 ssse3 sse4.1 sse4.2 mmx avx avx2 fxsr pcid xsave aesni clflush [00000.583] fsgsbase tsc_adj smep smap erms rdrand rdseed syscall nx rdtscp invar_tsc tsc_deadline [00000.583] x2apic hypervisor [00000.583] initializing platform [00000.583] INIT: cpu 0, calling hook 0xffffffff0018deb4 (x86_perfmon) at level 0x90000, flags 0x1 [00000.583] x86_perfmon_init_once:497: perfmon: unexpected programmable counter width 0 in cpuid.0AH [00000.583] UART: started IRQ driven RX [00000.583] UART: started IRQ driven TX [00000.583] cpu topology: [00000.583] 0: apic id 0x0 package 0 node 0 core 0 smt 0 BSP [00000.583] 1: apic id 0x1 package 1 node 0 core 0 smt 0 [00000.583] 2: apic id 0x2 package 2 node 0 core 0 smt 0 [00000.583] 3: apic id 0x3 package 3 node 0 core 0 smt 0 [00000.583] Found 4 cpus [00000.584] booting apic ids: 0x1 0x2 0x3 [00000.646] entering scheduler on cpu 1 [00000.646] entering scheduler on cpu 2 [00000.647] entering scheduler on cpu 3 [00000.656] smbios: manufacturer="QEMU" product="Standard PC (Q35 + ICH9, 2009)" [00000.656] initializing target [00000.656] INIT: cpu 0, calling hook 0xffffffff00173bec (x86_pcie_init) at level 0xa0000, flags 0x1 [00000.656] moving to last init level [00000.656] INIT: cpu 0, calling hook 0xffffffff00102a48 (kernel_shell) at level 0xc0000, flags 0x1 [00000.656] INIT: cpu 0, calling hook 0xffffffff001fc0a8 (ktrace) at level 0xc0000, flags 0x1 [00000.722] ktrace: buffer at 0xffffff903266d000 (33554432 bytes) [00000.722] INIT: cpu 0, calling hook 0xffffffff001fdfe8 (userboot) at level 0xc0000, flags 0x1 [00000.722] userboot: ramdisk 0x967000 @ 0xffffff8000a6a000 [00000.736] userboot: userboot rodata 0 @ [0x7c3235950000,0x7c3235953000) [00000.737] userboot: userboot code 0x3000 @ [0x7c3235953000,0x7c323595e000) [00000.737] userboot: vdso/full rodata 0 @ [0x7c323595e000,0x7c3235965000) [00000.737] userboot: vdso/full code 0x7000 @ [0x7c3235965000,0x7c3235966000) [00000.737] userboot: entry point @ 0x7c3235953c90 [00000.759] userboot: option "/b/s/w/ir/third_party/fuchsia-sdk/sdk/target/x64/qemu-kernel.bin=" [00000.759] userboot: option "devmgr.epoch=1552788966" [00000.759] userboot: option "zircon.nodename=fc2413ba-70ae-4aed-9797-860cfe9d376e" [00000.759] userboot: option "TERM=dumb" [00000.759] userboot: option "kernel.serial=legacy" [00000.759] userboot: option "multiboot.boot_loader_name=qemu" [00000.802] userboot: searching bootfs for 'bin/bootsvc' [00000.802] userboot: 'bin/bootsvc' has PT_INTERP "lib/ld.so.1" [00000.802] userboot: searching bootfs for 'lib/ld.so.1' [00000.802] userboot: userboot: loaded lib/ld.so.1 at 0x5d6ecb1d0000, entry point 0x5d6ecb20d2f1 [00000.802] userboot: userboot: loaded vDSO at 0x7d4585c0f000, entry point 0x0 [00000.802] userboot: process bin/bootsvc started. [00000.802] userboot: waiting for loader-service requests... [00000.803] userboot: searching bootfs for 'lib/libasync-default.so' [00000.803] userboot: searching bootfs for 'lib/libfdio.so' [00000.804] userboot: searching bootfs for 'lib/libtrace-engine.so' [00000.804] userboot: searching bootfs for 'lib/liblaunchpad.so' [00000.804] {{{reset}}} [00000.804] {{{module:0:<application>:elf:be910e109aaa900057274be2c91b44fd9251d077}}} [00000.804] {{{mmap:0x17fbae7fd000:0x39000:load:0:rx:0}}} [00000.804] {{{mmap:0x17fbae836000:0x4000:load:0:rw:0x39000}}} [00000.804] dso: id=be910e109aaa900057274be2c91b44fd9251d077 base=0x000017fbae7fd000 name=<application> [00000.804] {{{module:0x2:<vDSO>:elf:5db6a550b7c22b4e5d9c55acc5bbc1d2583f0380}}} [00000.804] {{{mmap:0x7d4585c0f000:0x7000:load:0x2:r:0}}} [00000.804] {{{mmap:0x7d4585c16000:0x1000:load:0x2:rx:0x7000}}} [00000.804] dso: id=5db6a550b7c22b4e5d9c55acc5bbc1d2583f0380 base=0x00007d4585c0f000 name=<vDSO> [00000.804] {{{module:0x3:libasync-default.so:elf:179378e0029e9d73bcaaf61330269def68f524f0}}} [00000.804] {{{mmap:0x36eb9444b000:0x1000:load:0x3:rx:0}}} [00000.804] {{{mmap:0x36eb9444c000:0x1000:load:0x3:rw:0x1000}}} [00000.804] dso: id=179378e0029e9d73bcaaf61330269def68f524f0 base=0x000036eb9444b000 name=libasync-default.so [00000.804] {{{module:0x1:libc.so:elf:608526d5035c0cab2fdd1718baca2d9ed1f3441b}}} [00000.804] {{{mmap:0x5d6ecb1d0000:0xcb000:load:0x1:rx:0}}} [00000.804] {{{mmap:0x5d6ecb29c000:0x6000:load:0x1:rw:0xcc000}}} [00000.804] dso: id=608526d5035c0cab2fdd1718baca2d9ed1f3441b base=0x00005d6ecb1d0000 name=libc.so [00000.804] {{{module:0x4:libfdio.so:elf:b9e13f817983faaf4b0927a0a26c6ae9fc2df591}}} [00000.804] {{{mmap:0x7af71c8b000:0x32000:load:0x4:rx:0}}} [00000.804] {{{mmap:0x7af71cbe000:0x4000:load:0x4:rw:0x33000}}} [00000.804] dso: id=b9e13f817983faaf4b0927a0a26c6ae9fc2df591 base=0x000007af71c8b000 name=libfdio.so [00000.804] {{{module:0x5:libtrace-engine.so:elf:e693b8371adfd5db148ebbe7e9af42cfd4b2408f}}} [00000.804] {{{mmap:0x3c36ff840000:0x9000:load:0x5:rx:0}}} [00000.804] {{{mmap:0x3c36ff849000:0x2000:load:0x5:rw:0x9000}}} [00000.804] dso: id=e693b8371adfd5db148ebbe7e9af42cfd4b2408f base=0x00003c36ff840000 name=libtrace-engine.so [00000.804] {{{module:0x6:liblaunchpad.so:elf:7bbb5d72e7b50ffd31348f1fff0031cd559eaa75}}} [00000.804] {{{mmap:0x6846fb632000:0x7000:load:0x6:rx:0}}} [00000.804] {{{mmap:0x6846fb639000:0x2000:load:0x6:rw:0x7000}}} [00000.804] dso: id=7bbb5d72e7b50ffd31348f1fff0031cd559eaa75 base=0x00006846fb632000 name=liblaunchpad.so [00000.805] bootsvc: Starting... [00000.805] userboot: loader-service channel peer closed [00000.805] bootsvc: Creating bootfs service... [00000.805] userboot: finished! [00000.806] bootsvc: Processing bootdata... [00000.806] bootsvc: Loading boot cmdline overrides... [00000.806] bootsvc: Loading kernel VMOs... [00000.806] bootsvc: Creating loader service... [00000.806] bootsvc: Launching next process... [00000.807] bootsvc: launched bin/devcoordinator [00000.808] devcoordinator: main() [00000.809] devcoordinator: coordinator_init() [00000.809] devcoordinator: init [00000.809] devcoordinator: svc init [00000.810] devcoordinator: launch /boot/bin/svchost (svchost) OK [00000.810] devcoordinator: vfs init [00000.813] devcoordinator: launch /boot/bin/fshost (fshost) OK [00000.818] fshost: started. [00000.852] devcoordinator: launch /boot/bin/pwrbtn-monitor (pwrbtn-monitor) OK [00000.862] devcoordinator: launch /boot/bin/netsvc (netsvc) OK [00000.866] devcoordinator: launch /boot/bin/virtual-console (virtual-console) OK [00000.876] devcoordinator: no driver info in '/boot/driver/mtk-power.so' [00000.893] devcoordinator: full system required, ignoring fallback drivers until /system is loaded [00000.896] devcoordinator: launch devhost 'devhost:sys': pid=2059 [00000.900] devcoordinator: launch devhost 'devhost:test': pid=2104 [00000.902] devhost: trace provider registry begun [00000.909] devcoordinator: launch devhost 'devhost:root': pid=2182 [00000.913] devhost: trace provider registry begun [00000.913] devcoordinator: launch devhost 'devhost:misc': pid=2248 [00000.918] devhost: trace provider registry begun [00000.923] devhost: trace provider registry begun [00000.941] GetZbiMetadata metadata not found for type 524f426d, extra 0 [00000.957] acpi-bus: not using IOMMU [00000.959] GetHwProperties: No PM support [00000.960] IntelPT: No PT support [00000.960] devhost[sys/cpu-trace] bind driver '/boot/driver/cpu-trace.so' failed: -2 [00000.960] devcoordinator: rpc: bind-driver 'cpu-trace' status -2 [00000.961] [sysmem_init 28] async_get_default_dispatcher(): 0x32e8ab2930a0 [00000.961] [sysmem_bind 40] sysmem_bind() [00000.962] acpi-pwrbtn: initialized [00000.972] vc: new input device /dev/class/input/000 [00001.039] acpi: published device pci(0x3265a8f9a180), parent=sys(0x32e8ab2a10c0), handle=0x32e8ab29e300 [00001.040] acpi: failed to get PCI base bus number for device 'PCI0' (status 4294967295) [00001.043] acpi: published device rtc(0x3265a8f9a200), parent=acpi(0x32e8ab2a1300), handle=0x32e8ab29e7b0 [00001.049] devcoordinator: launch devhost 'devhost:pci#1:1234:1111': pid=2677 [00001.056] devhost: trace provider registry begun [00001.060] devcoordinator: launch devhost 'devhost:pci#2:1af4:1001': pid=2746 [00001.069] devcoordinator: launch devhost 'devhost:pci#3:8086:100e': pid=2834 [00001.069] devhost: trace provider registry begun [00001.070] acpi: published device i8042(0x3265a8f9a280), parent=acpi(0x32e8ab2a1300), handle=0x32e8ab29e840 [00001.076] devhost: trace provider registry begun [00001.078] devcoordinator: launch devhost 'devhost:pci#5:8086:2922': pid=2968 [00001.083] devhost: WARNING: driver '/boot/driver/pc-ps2.so' did not add device in bind() [00001.088] i8042 device 0xab 0x41 [00001.094] vc: new input device /dev/class/input/001 [00001.102] bochs_vbe: initialized display, 1024 x 768 (stride=1024 format=00020001) [00001.103] vc: new display device /dev/class/display-controller/000/virtcon [00001.105] vc: Successfully attached to display 1 [00001.134] devhost: trace provider registry begun [00001.135] ahci: using MSI interrupt [00001.222] i8042 device 0x00 [00001.227] hid: boot mouse hack for "hid-device-002": report count (0->1), inp sz (24->24), out sz (0->0), feat sz (0->0) [00001.236] devcoordinator: shell startup [00001.250] fshost: /dev/class/block/000: FVM? [00001.271] devcoordinator: launch /boot/bin/sh (sh:console) OK [00001.271] vc: new input device /dev/class/input/002 [00001.299] devcoordinator: launch /boot/bin/blobfs (blobfs:/blob) OK [00001.489] fshost: starting 'bin/pkgsvr' 'ac8bdc046e71a5b2101645292fd640ca71d4bfbacaacb13b59d98cc1538a8966'... [00001.507] devcoordinator: launch bin/pkgsvr (pkgfs) OK [00001.549] pkgsvr: system: will be served from ac8bdc046e71a5b2101645292fd640ca71d4bfbacaacb13b59d98cc1538a8966 [00001.549] pkgsvr: pkgfs serving index /data/pkgfs_index from blobfs /blob [00001.582] fshost: mounting minfs [00001.599] devcoordinator: launch /boot/bin/minfs (minfs:/data) OK [00001.611] devcoordinator: adding system driver 'bt_hci_fake' '/system/driver/bt-hci-fake.so' [00001.621] devcoordinator: launch /system/bin/appmgr (appmgr) OK [00001.649] devcoordinator: adding system driver 'bt_hog' '/system/driver/bt-hog.so' [00001.663] devcoordinator: adding system driver 'bt_hci_passthrough' '/system/driver/bt-hci-passthrough.so' [00001.688] devcoordinator: adding system driver 'gpu' '/system/driver/libmsd_intel.so' [00001.725] devcoordinator: adding system driver 'bt_host' '/system/driver/bt-host.so' [00001.739] devcoordinator: driver 'bt_host' added [00001.740] devcoordinator: driver 'gpu' added [00001.740] devcoordinator: driver 'bt_hog' added [00001.740] devcoordinator: driver 'bt_hci_fake' added [00001.740] devcoordinator: driver 'bt_hci_passthrough' added [00001.740] devcoordinator: fallback driver 'usb_composite' is available [00001.740] devcoordinator: driver 'usb_composite' added [00001.740] devcoordinator: fallback driver 'intel_disp' is available [00001.740] devcoordinator: driver 'intel_disp' added [00001.823] [INFO:namespace_builder.cc(93)] config-data for sysmgr [00002.533] [INFO:cobalt_main.cc(145)] Cobalt client schedule params: schedule_interval=3600 seconds, min_interval=10 seconds, initial_interval=60 seconds. [00002.620] netifc: ? /dev/class/ethernet/000 [00002.620] netifc: create 512 eth buffers [00002.626] macaddr: 52:54:00:63:5e:7b [00002.626] ip6addr: fe80::5054:4d:fe63:5e7b [00002.627] snmaddr: ff02::1:ff63:5e7b [00002.627] netsvc: using /dev/class/ethernet/000 [00002.627] netsvc: nodename='fc2413ba-70ae-4aed-9797-860cfe9d376e' [00002.627] netsvc: start [00002.671] [ERROR:garnet/lib/loader/package_loader.cc(55)] Could not open directory /pkgfs/packages/modem-mgr/0 No such file or directory [00002.703] [ERROR:garnet/bin/sysmgr/app.cc(166)] Singleton fuchsia-pkg://fuchsia.com/modem-mgr#meta/modem_mgr.cmx died [00003.357] [INFO:namespace_builder.cc(93)] config-data for crashpad_agent [00003.549] [INFO:main.cc(35)] Trace Manager starting with config: /pkg/data/tracing.config [00003.627] Starting mDNS on interface ethp0003 192.168.3.9 [00003.647] Starting mDNS on interface ethp0003 [80fe::5450:ff00:63fe:7b5e] [00003.663] [ERROR:garnet/bin/mdns/service/mdns_interface_transceiver_v6.cc(49)] Failed to set socket option IPV6_JOIN_GROUP, No such device [00003.676] mDNS: Verifying uniqueness of host name step-dime-yard-juicy.local. [00003.686] wlanstack2 [I]: Starting [00003.810] [INFO:a11y_input_system.cc(9)] Scenic accessibility input system started. [00003.811] [INFO:display_watcher.cc(40)] Scenic: Acquired display controller /dev/class/display-controller/000.(000) [00004.071] [INFO:input_interpreter.cc(1006)] hid-parser succesful for 000 with usage page 1 and usage 128 [00004.247] ERROR: setupLoaderTermPhysDevs: Failed to detect any valid GPUs in the current coinitialized repo /b/s/w/itVZ4sPE/tmpT2DW3u adding package web_engine_browsertests/0 2019/03/17 02:16:14 error removing source: Source not found 2019-03-16 19:16:15,153:INFO:root:Installing web_engine_browsertests version 0. 2019/03/17 02:16:18 Awaiting response... 2019/03/17 02:16:21 Awaiting response... 2019/03/17 02:16:24 Awaiting response... 2019/03/17 02:16:27 Awaiting response... 2019/03/17 02:16:30 Awaiting response... 2019/03/17 02:16:33 Awaiting response... 2019/03/17 02:16:36 Awaiting response... 2019/03/17 02:16:39 Awaiting response... 2019/03/17 02:16:42 Awaiting response... 2019/03/17 02:16:45 Awaiting response... 2019/03/17 02:16:48 Awaiting response... 2019/03/17 02:16:51 Awaiting response... 2019/03/17 02:16:54 Awaiting response... 2019/03/17 02:16:57 Awaiting response... 2019/03/17 02:17:00 Awaiting response... 2019/03/17 02:17:04 Awaiting response... 2019/03/17 02:17:07 Awaiting response... 2019/03/17 02:17:10 Awaiting response... 2019/03/17 02:17:13 Awaiting response... 2019/03/17 02:17:16 Awaiting response... 2019/03/17 02:17:17 Success web_engine_browsertests/0: 04ddfe92ff1b0c6724de1f9c1ba64c2266c4646a8e519621315aa8df95ee7b48 2019-03-16 19:17:18,310:INFO:root:Running application. 2019-03-16 19:17:18,314:INFO:root:Running "/b/s/w/ir/third_party/fuchsia-sdk/sdk/tools/symbolize -ids /b/s/w/ir/out/Release/gen/fuchsia/engine/web_engine_browsertests/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". nfig [00004.247] ERROR: setupLoaderTrampPhysDevs: Failed during dispatch call of 'vkEnumeratePhysicalDevices' to lower layers or loader to get count. [00004.247] [ERROR:garnet/lib/ui/gfx/gfx_system.cc(188)] No Vulkan on device, Graphics system exiting. [00004.247] [INFO:input_system.cc(245)] Scenic input system initialized. [00004.277] [ERROR:garnet/bin/ui/root_presenter/app.cc(214)] Scenic died, destroying all presentations. [00004.277] [ERROR:garnet/bin/ui/view_manager/view_registry.cc(80)] Exiting due to scene manager connection error. [00004.320] [ERROR:garnet/bin/sysmgr/app.cc(166)] Singleton fuchsia-pkg://fuchsia.com/view_manager#meta/view_manager.cmx died [00004.329] [ERROR:garnet/bin/sysmgr/app.cc(166)] Singleton fuchsia-pkg://fuchsia.com/scenic#meta/scenic.cmx died [00004.496] mDNS: Using unique host name step-dime-yard-juicy.local. [00009.117] pkgsvr: asking amber to fetch 2327 needed blobs [00071.195] pkgsvr: package activated web_engine_browsertests/0 (04ddfe92ff1b0c6724de1f9c1ba64c2266c4646a8e519621315aa8df95ee7b48) IMPORTANT DEBUGGING NOTE: each test is run inside its own process. For debugging a test inside a debugger, use the --gtest_filter=<your_test_name> flag along with either --single_process (to run the test in one launcher/browser process) or --single-process (to do the above, and also run Chrome in single-process mode). Using sharding settings from environment. This is shard 0/1 Using 2 parallel jobs. [00073.026] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00073.026] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00074.027] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00074.252] dlsvc: could not open 'libGLES_CM.so' [00074.252] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00074.265] dlsvc: could not open 'libGLES_CM.so' [00074.867] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00075.113] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00075.594] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00075.699] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00075.773] dlsvc: could not open 'libGLES_CM.so' [00075.778] dlsvc: could not open 'libGLES_CM.so' [1/30] IncognitoContextImplTest.NavigateFrame (3370 ms) [00076.143] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00076.344] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00076.402] dlsvc: could not open 'libGLES_CM.so' [00076.421] dlsvc: could not open 'libGLES_CM.so' [2/30] ContextImplTest.VerifyPersistentCookieStore (3752 ms) [00076.718] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00076.889] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00076.949] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [3/30] IncognitoContextImplTest.VerifyInMemoryCookieStore (1201 ms) [00077.483] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00077.653] dlsvc: could not open 'libGLES_CM.so' [00077.657] dlsvc: could not open 'libGLES_CM.so' [00077.834] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00077.857] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00077.897] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [4/30] FrameImplTest.NavigateFrame (1795 ms) [00078.471] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00078.611] dlsvc: could not open 'libGLES_CM.so' [00078.617] dlsvc: could not open 'libGLES_CM.so' [00078.669] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00078.935] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00078.956] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [5/30] FrameImplTest.NavigateDataFrame (2044 ms) [6/30] FrameImplTest.FrameDeletedBeforeContext (1125 ms) [00079.547] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00079.556] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00079.868] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [7/30] FrameImplTest.ContextDeletedBeforeFrame (537 ms) [00079.911] dlsvc: could not open 'libGLES_CM.so' [00079.916] dlsvc: could not open 'libGLES_CM.so' [00079.953] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [8/30] FrameImplTest.ContextDeletedBeforeFrameWithView (630 ms) [00080.133] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00080.445] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00080.631] dlsvc: could not open 'libGLES_CM.so' [00080.636] dlsvc: could not open 'libGLES_CM.so' [00081.124] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00081.142] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00081.752] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00081.881] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00081.977] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00082.023] dlsvc: could not open 'libGLES_CM.so' [00082.030] dlsvc: could not open 'libGLES_CM.so' [9/30] FrameImplTest.GoBackAndForward (2547 ms) [00082.498] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00082.727] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00082.789] dlsvc: could not open 'libGLES_CM.so' [00082.796] dlsvc: could not open 'libGLES_CM.so' [10/30] FrameImplTest.ReloadFrame (2835 ms) [00083.398] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00083.557] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00083.590] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00083.976] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00084.117] dlsvc: could not open 'libGLES_CM.so' [00084.121] dlsvc: could not open 'libGLES_CM.so' [11/30] FrameImplTest.GetVisibleEntry (1848 ms) [00084.349] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00084.581] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00084.629] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [12/30] FrameImplTest.NoNavigationObserverAttached (2214 ms) [00085.407] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00085.531] dlsvc: could not open 'libGLES_CM.so' [00085.535] dlsvc: could not open 'libGLES_CM.so' [00085.677] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00085.684] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00085.845] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00086.167] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00086.316] dlsvc: could not open 'libGLES_CM.so' [00086.332] dlsvc: could not open 'libGLES_CM.so' [13/30] FrameImplTest.ExecuteJavaScriptImmediate (2192 ms) [00086.634] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00086.939] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00086.954] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00087.229] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00087.366] dlsvc: could not open 'libGLES_CM.so' [00087.370] dlsvc: could not open 'libGLES_CM.so' [00087.552] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [14/30] FrameImplTest.ExecuteJavaScriptOnLoad (2427 ms) [00087.841] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00087.867] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00088.106] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00088.309] dlsvc: could not open 'libGLES_CM.so' [00088.319] dlsvc: could not open 'libGLES_CM.so' [15/30] FrameImplTest.ExecuteJavaScriptOnLoadVmoDestroyed (1997 ms) [00088.673] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00088.937] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00088.947] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00089.750] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00089.753] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00089.753] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00089.902] dlsvc: could not open 'libGLES_CM.so' [00089.916] dlsvc: could not open 'libGLES_CM.so' [16/30] FrameImplTest.ExecuteJavascriptOnLoadWrongOrigin (2834 ms) [00090.462] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [17/30] FrameImplTest.ExecuteJavaScriptOnLoadWildcardOrigin (2220 ms) [00090.762] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00090.806] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00091.745] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00091.870] dlsvc: could not open 'libGLES_CM.so' [00091.876] dlsvc: could not open 'libGLES_CM.so' [00092.146] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00092.181] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00092.245] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00092.549] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00092.932] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00093.010] dlsvc: could not open 'libGLES_CM.so' [00093.017] dlsvc: could not open 'libGLES_CM.so' [18/30] FrameImplTest.ExecuteMultipleJavaScriptsOnLoad (3151 ms) [19/30] FrameImplTest.ExecuteOnLoadEarlyAndLateRegistrations (2855 ms) [00093.702] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00093.710] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00094.046] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00094.298] dlsvc: could not open 'libGLES_CM.so' [00094.303] dlsvc: could not open 'libGLES_CM.so' [00094.847] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00094.963] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00095.105] dlsvc: could not open 'libGLES_CM.so' [00095.106] dlsvc: could not open 'libGLES_CM.so' [00095.345] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00095.382] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00095.389] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [20/30] FrameImplTest.ExecuteJavaScriptBadEncoding (2453 ms) [21/30] FrameImplTest.NavigationObserverDisconnected (2467 ms) [00096.140] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00096.164] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00096.967] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00097.067] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00097.100] dlsvc: could not open 'libGLES_CM.so' [00097.101] dlsvc: could not open 'libGLES_CM.so' [22/30] FrameImplTest.Stop (1170 ms) [00097.483] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00097.505] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00097.739] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00097.900] dlsvc: could not open 'libGLES_CM.so' [00097.906] dlsvc: could not open 'libGLES_CM.so' [23/30] FrameImplTest.DelayedNavigationEventAck (2264 ms) [00098.430] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00098.653] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00098.657] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00098.899] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00098.941] dlsvc: could not open 'libGLES_CM.so' [00098.949] dlsvc: could not open 'libGLES_CM.so' [00099.297] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [24/30] FrameImplTest.PostMessage (2088 ms) [00099.399] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00099.577] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [25/30] FrameImplTest.PostMessagePassMessagePort (1779 ms) [00100.351] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00100.504] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00100.582] dlsvc: could not open 'libGLES_CM.so' [00100.583] dlsvc: could not open 'libGLES_CM.so' [00100.737] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00100.765] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [26/30] FrameImplTest.PostMessageMessagePortDisconnected (2078 ms) [00101.657] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00101.778] dlsvc: could not open 'libGLES_CM.so' [00101.783] dlsvc: could not open 'libGLES_CM.so' [00101.900] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00101.944] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00101.996] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00102.255] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00102.330] dlsvc: could not open 'libGLES_CM.so' [00102.335] dlsvc: could not open 'libGLES_CM.so' [27/30] FrameImplTest.PostMessageUseContentProvidedPort (2395 ms) [00102.779] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00102.785] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00103.076] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00103.337] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00103.412] dlsvc: could not open 'libGLES_CM.so' [00103.422] dlsvc: could not open 'libGLES_CM.so' [28/30] FrameImplTest.PostMessageBadOriginDropped (21452019-03-16 19:17:51,790:INFO:root:Process exited normally with status code 0. 2019-03-16 19:17:51,791:INFO:root:Terminating kernel log reader. 2019-03-16 19:17:54,478:INFO:root:Shutting down QEMU. ms) [00103.660] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00103.912] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00103.938] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [29/30] FrameImplTest.RecreateView (2223 ms) [00104.751] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00104.851] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00104.863] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00104.876] dlsvc: could not open 'libGLES_CM.so' [00104.877] dlsvc: could not open 'libGLES_CM.so' [30/30] RequestMonitoringFrameImplBrowserTest.ExtraHeaders (1690 ms) SUCCESS: all tests passed. 2019-03-16 19:17:59,279 - root: [DEBUG] Contents of task_output_dir: ['/b/swarming/w/ir/tmp/t/tmppUyeCP/43a240ae0adcde10'] 2019-03-16 19:17:59,279 - root: [DEBUG] Found shard_json_files: ['/b/swarming/w/ir/tmp/t/tmppUyeCP/43a240ae0adcde10/output.json'] 2019-03-16 19:17:59,279 - 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": "swarm1861-c4", "branch": "refs/heads/master", "buildbucket": {"build": {"bucket": "luci.chromium.ci", "created_by": "project:chromium", "created_ts": 1552787129703066, "id": "8918778398642642912", "project": "chromium", "tags": ["build_address:luci.chromium.ci/fuchsia-fyi-x64-rel/8963", "builder:fuchsia-fyi-x64-rel", "buildset:commit/git/ea9077b3af407be0e97766f16f773703edf4627b", "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ea9077b3af407be0e97766f16f773703edf4627b", "gitiles_ref:refs/heads/master", "scheduler_invocation_id:9084208460803399760", "scheduler_job_id:chromium/fuchsia-fyi-x64-rel", "user_agent:luci-scheduler"]}, "hostname": "cr-buildbucket.appspot.com"}, "buildername": "fuchsia-fyi-x64-rel", "buildnumber": 8963, "got_angle_revision": "38e282570676f3e434482f8a02ea539b286fd1fa", "got_dawn_revision": "931311700c7c4b76a15c23eee8792dda125fb97e", "got_nacl_revision": "1ed9878b8eb68c1e0d0268754dafcf83e747fa8f", "got_revision": "ea9077b3af407be0e97766f16f773703edf4627b", "got_revision_cp": "refs/heads/master@{#641464}", "got_swarming_client_revision": "7a61cf37d6a0163f0ec02d495289a1d038e62457", "got_v8_revision": "f413181b62ee4ad78b9ef3d58c4f395794e16bf1", "got_v8_revision_cp": "refs/heads/7.5.59@{#1}", "got_webrtc_revision": "69008a87185992aa348ece3f893f6a84f8786ae8", "got_webrtc_revision_cp": "refs/heads/master@{#27145}", "mastername": "chromium.fyi", "path_config": "generic", "recipe": "chromium", "repository": "https://chromium.googlesource.com/chromium/src.git", "revision": "ea9077b3af407be0e97766f16f773703edf4627b"} --summary-json /b/swarming/w/ir/tmp/t/tmpX6sXWj.json --task-output-dir /b/swarming/w/ir/tmp/t/tmppUyeCP -o /b/swarming/w/ir/tmp/t/tmpffIu7M.json /b/swarming/w/ir/tmp/t/tmppUyeCP/43a240ae0adcde10/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": "swarm1861-c4", "branch": "refs/heads/master", "buildbucket": {"build": {"bucket": "luci.chromium.ci", "created_by": "project:chromium", "created_ts": 1552787129703066, "id": "8918778398642642912", "project": "chromium", "tags": ["build_address:luci.chromium.ci/fuchsia-fyi-x64-rel/8963", "builder:fuchsia-fyi-x64-rel", "buildset:commit/git/ea9077b3af407be0e97766f16f773703edf4627b", "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ea9077b3af407be0e97766f16f773703edf4627b", "gitiles_ref:refs/heads/master", "scheduler_invocation_id:9084208460803399760", "scheduler_job_id:chromium/fuchsia-fyi-x64-rel", "user_agent:luci-scheduler"]}, "hostname": "cr-buildbucket.appspot.com"}, "buildername": "fuchsia-fyi-x64-rel", "buildnumber": 8963, "got_angle_revision": "38e282570676f3e434482f8a02ea539b286fd1fa", "got_dawn_revision": "931311700c7c4b76a15c23eee8792dda125fb97e", "got_nacl_revision": "1ed9878b8eb68c1e0d0268754dafcf83e747fa8f", "got_revision": "ea9077b3af407be0e97766f16f773703edf4627b", "got_revision_cp": "refs/heads/master@{#641464}", "got_swarming_client_revision": "7a61cf37d6a0163f0ec02d495289a1d038e62457", "got_v8_revision": "f413181b62ee4ad78b9ef3d58c4f395794e16bf1", "got_v8_revision_cp": "refs/heads/7.5.59@{#1}", "got_webrtc_revision": "69008a87185992aa348ece3f893f6a84f8786ae8", "got_webrtc_revision_cp": "refs/heads/master@{#27145}", "mastername": "chromium.fyi", "path_config": "generic", "recipe": "chromium", "repository": "https://chromium.googlesource.com/chromium/src.git", "revision": "ea9077b3af407be0e97766f16f773703edf4627b"}', '--summary-json', '/b/swarming/w/ir/tmp/t/tmpX6sXWj.json', '--task-output-dir', '/b/swarming/w/ir/tmp/t/tmppUyeCP', '-o', '/b/swarming/w/ir/tmp/t/tmpffIu7M.json', '/b/swarming/w/ir/tmp/t/tmppUyeCP/43a240ae0adcde10/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": "swarm1861-c4", "branch": "refs/heads/master", "buildbucket": {"build": {"bucket": "luci.chromium.ci", "created_by": "project:chromium", "created_ts": 1552787129703066, "id": "8918778398642642912", "project": "chromium", "tags": ["build_address:luci.chromium.ci/fuchsia-fyi-x64-rel/8963", "builder:fuchsia-fyi-x64-rel", "buildset:commit/git/ea9077b3af407be0e97766f16f773703edf4627b", "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/ea9077b3af407be0e97766f16f773703edf4627b", "gitiles_ref:refs/heads/master", "scheduler_invocation_id:9084208460803399760", "scheduler_job_id:chromium/fuchsia-fyi-x64-rel", "user_agent:luci-scheduler"]}, "hostname": "cr-buildbucket.appspot.com"}, "buildername": "fuchsia-fyi-x64-rel", "buildnumber": 8963, "got_angle_revision": "38e282570676f3e434482f8a02ea539b286fd1fa", "got_dawn_revision": "931311700c7c4b76a15c23eee8792dda125fb97e", "got_nacl_revision": "1ed9878b8eb68c1e0d0268754dafcf83e747fa8f", "got_revision": "ea9077b3af407be0e97766f16f773703edf4627b", "got_revision_cp": "refs/heads/master@{#641464}", "got_swarming_client_revision": "7a61cf37d6a0163f0ec02d495289a1d038e62457", "got_v8_revision": "f413181b62ee4ad78b9ef3d58c4f395794e16bf1", "got_v8_revision_cp": "refs/heads/7.5.59@{#1}", "got_webrtc_revision": "69008a87185992aa348ece3f893f6a84f8786ae8", "got_webrtc_revision_cp": "refs/heads/master@{#27145}", "mastername": "chromium.fyi", "path_config": "generic", "recipe": "chromium", "repository": "https://chromium.googlesource.com/chromium/src.git", "revision": "ea9077b3af407be0e97766f16f773703edf4627b"}', '--summary-json', '/b/swarming/w/ir/tmp/t/tmpX6sXWj.json', '--task-output-dir', '/b/swarming/w/ir/tmp/t/tmppUyeCP', '-o', '/b/swarming/w/ir/tmp/t/tmpffIu7M.json', '/b/swarming/w/ir/tmp/t/tmppUyeCP/43a240ae0adcde10/output.json'] returned exit code 0