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/tmpzw2ySl.json --task-output-dir /b/swarming/w/ir/tmp/t/tmp3ZAGW8 --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/tmpJGmotsmerge_script_log --merge-additional-args '[]' --build-properties '{"bot_id": "swarm973-c4", "branch": "refs/heads/master", "buildbucket": {"build": {"bucket": "luci.chromium.ci", "created_by": "project:chromium", "created_ts": 1553443091071983, "id": "8918090573295123808", "project": "chromium", "tags": ["build_address:luci.chromium.ci/Fuchsia x64/31838", "builder:Fuchsia x64", "buildset:commit/git/d488b3fa47490675ada321f1c0c9752db0f8dc9d", "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d488b3fa47490675ada321f1c0c9752db0f8dc9d", "gitiles_ref:refs/heads/master", "scheduler_invocation_id:9083520635357756992", "scheduler_job_id:chromium/Fuchsia x64", "user_agent:luci-scheduler"]}, "hostname": "cr-buildbucket.appspot.com"}, "buildername": "Fuchsia x64", "buildnumber": 31838, "got_angle_revision": "eae464dd3092c1c09c4dc4d51a0f50ac5d007722", "got_dawn_revision": "07950e80fe36028ed9cca0a4a99a8b08e9bb4fdf", "got_nacl_revision": "de8da4841bc3d1ed020d798d5a7fd6fb3d817fa1", "got_revision": "d488b3fa47490675ada321f1c0c9752db0f8dc9d", "got_revision_cp": "refs/heads/master@{#643730}", "got_swarming_client_revision": "aa60736aded9fc32a0e21a81f5fc51f6009d01f3", "got_v8_revision": "b346546b7795350b8c910ff06f9520e7f03224f0", "got_v8_revision_cp": "refs/heads/7.5.100@{#1}", "got_webrtc_revision": "85a4a93e77b163098e91f41d8e7415181f23d02c", "got_webrtc_revision_cp": "refs/heads/master@{#27257}", "mastername": "chromium.linux", "path_config": "generic", "recipe": "chromium", "repository": "https://chromium.googlesource.com/chromium/src.git", "revision": "d488b3fa47490675ada321f1c0c9752db0f8dc9d"}' --summary-json-file /b/swarming/w/ir/tmp/t/tmpDwWOO9.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/tmpedLAty.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/tmpzw2ySl.json', '--task-output-dir', '/b/swarming/w/ir/tmp/t/tmp3ZAGW8', '--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/tmpJGmotsmerge_script_log', '--merge-additional-args', '[]', '--build-properties', '{"bot_id": "swarm973-c4", "branch": "refs/heads/master", "buildbucket": {"build": {"bucket": "luci.chromium.ci", "created_by": "project:chromium", "created_ts": 1553443091071983, "id": "8918090573295123808", "project": "chromium", "tags": ["build_address:luci.chromium.ci/Fuchsia x64/31838", "builder:Fuchsia x64", "buildset:commit/git/d488b3fa47490675ada321f1c0c9752db0f8dc9d", "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d488b3fa47490675ada321f1c0c9752db0f8dc9d", "gitiles_ref:refs/heads/master", "scheduler_invocation_id:9083520635357756992", "scheduler_job_id:chromium/Fuchsia x64", "user_agent:luci-scheduler"]}, "hostname": "cr-buildbucket.appspot.com"}, "buildername": "Fuchsia x64", "buildnumber": 31838, "got_angle_revision": "eae464dd3092c1c09c4dc4d51a0f50ac5d007722", "got_dawn_revision": "07950e80fe36028ed9cca0a4a99a8b08e9bb4fdf", "got_nacl_revision": "de8da4841bc3d1ed020d798d5a7fd6fb3d817fa1", "got_revision": "d488b3fa47490675ada321f1c0c9752db0f8dc9d", "got_revision_cp": "refs/heads/master@{#643730}", "got_swarming_client_revision": "aa60736aded9fc32a0e21a81f5fc51f6009d01f3", "got_v8_revision": "b346546b7795350b8c910ff06f9520e7f03224f0", "got_v8_revision_cp": "refs/heads/7.5.100@{#1}", "got_webrtc_revision": "85a4a93e77b163098e91f41d8e7415181f23d02c", "got_webrtc_revision_cp": "refs/heads/master@{#27257}", "mastername": "chromium.linux", "path_config": "generic", "recipe": "chromium", "repository": "https://chromium.googlesource.com/chromium/src.git", "revision": "d488b3fa47490675ada321f1c0c9752db0f8dc9d"}', '--summary-json-file', '/b/swarming/w/ir/tmp/t/tmpDwWOO9.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/tmpedLAty.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: ALL_OK 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: 39223 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/8918090573295123808 LOGDOG_STREAM_PROJECT: chromium LOGDOG_STREAM_SERVER_PATH: unix:/b/swarming/w/ir/tmp/ld.sock LOGICAL: eth0 LUCI_CONTEXT: /b/swarming/w/itVgXx3o/luci_context.649851635 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: swarm973-c4 SWARMING_EXTERNAL_BOT_SETUP: 1 SWARMING_HEADLESS: 1 SWARMING_SERVER: https://chromium-swarm.appspot.com SWARMING_TASK_ID: 43c93dfc58f42311 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-24 09:06:01,673 - root: [DEBUG] Using task_output_dir: '/b/swarming/w/ir/tmp/t/tmp3ZAGW8' 2019-03-24 09:06:01,674 - root: [WARNING] task_output_dir '/b/swarming/w/ir/tmp/t/tmp3ZAGW8' already exists! 2019-03-24 09:06:01,674 - root: [WARNING] task_output_dir existing content: [] 2019-03-24 09:06:01,674 - 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/tmpedLAty.json -output-dir /b/swarming/w/ir/tmp/t/tmp3ZAGW8 -task-summary-json /b/swarming/w/ir/tmp/t/tmpDwWOO9.json [D2019-03-24T09:06:01.683845-07:00 22750 0 auth.go:1265] Minting a new token {"key":"luci_ctx/09fb1c5d282c8178893fdb7f95ff3541e4fdd71f632ad29043e813028befc3bb", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-03-24T09:06:01.683991-07:00 22750 0 luci_ctx.go:138] POST http://127.0.0.1:59437/rpc/LuciLocalAuthService.GetOAuthToken {"key":"luci_ctx/09fb1c5d282c8178893fdb7f95ff3541e4fdd71f632ad29043e813028befc3bb", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-03-24T09:06:01.686377-07:00 22750 0 auth.go:1222] Token expires in 48m55.313647088s {"key":"luci_ctx/09fb1c5d282c8178893fdb7f95ff3541e4fdd71f632ad29043e813028befc3bb", "scopes":"https://www.googleapis.com/auth/userinfo.email"} 43c942d0c41b2510: 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 --qemu-require-kvm --test-launcher-summary-output=/b/s/w/io8RxPUQ/output.json 2019-03-24 09:03:51,358:INFO:root:Connecting to Fuchsia using SSH. 2019-03-24 09:03:58,527:INFO:root:Connected! 2019-03-24 09:03:58,527:INFO:root:Attaching kernel logger. [00000.000] zbi: @ 0xffffff8000cbb000 (12285112 bytes) [00000.000] UART: FIFO depth 16 [00000.000] PMM: boot reserve add [0x100000, 0x2f4fff] [00000.000] PMM: boot reserve add [0xcbb000, 0x1872fff] [00000.000] PMM: boot reserve marking WIRED [0x100000, 0x2f4fff] [00000.000] PMM: boot reserve marking WIRED [0xcbb000, 0x1872fff] [00000.000] [00000.000] welcome to Zircon [00000.000] [00000.000] KASLR: .text section at 0xffffffff00100000 [00000.000] INIT: cpu 0, calling hook 0xffffffff0010f204 (global_prng_seed) at level 0x30000, flags 0x1 [00000.000] initializing vm pre-heap [00000.000] VM: marking boot alloc used range [0x1873000, 0x1873040) [00000.000] INIT: cpu 0, calling hook 0xffffffff0011c4a8 (elf_build_id) at level 0x4fffe, flags 0x1 [00000.000] INIT: cpu 0, calling hook 0xffffffff0011c634 (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-3c1cfe075b76476a9fe251dcd2481084b5a339ab-dirty [00000.000] ELF build ID: 7f0327b6944cf8be832dbd515f8831ea31e4afa7 [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 0xffffffff001739c8 (x86_resource_init) at level 0x50000, flags 0x1 [00000.000] initializing vm [00000.000] VM: reserving kernel region [0xffffffff00100000, 0xffffffff00203000) flags 0x28 name 'kernel_code' [00000.000] VM: reserving kernel region [0xffffffff00203000, 0xffffffff00251000) flags 0x8 name 'kernel_rodata' [00000.000] VM: reserving kernel region [0xffffffff00251000, 0xffffffff0026b000) flags 0x18 name 'kernel_data' [00000.000] VM: reserving kernel region [0xffffffff0026b000, 0xffffffff002f5000) flags 0x18 name 'kernel_bss' [00000.000] INIT: cpu 0, calling hook 0xffffffff0017468c (display_memtype) at level 0x60001, flags 0x1 [00000.000] INIT: cpu 0, calling hook 0xffffffff001776f4 (acpi_tables) at level 0x60001, flags 0x1 [00000.000] INIT: cpu 0, calling hook 0xffffffff001727ec (hpet) at level 0x60002, flags 0x1 [00000.000] INIT: cpu 0, calling hook 0xffffffff00172bac (apic) at level 0x60002, flags 0x1 [00000.000] x2APIC enabled [00000.000] INIT: cpu 0, calling hook 0xffffffff00176ce4 (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: 2199792 ticks/ms [00000.413] timer features: constant_tsc 1 invariant_tsc 1 tsc_deadline 1 [00000.413] Using TSC as wallclock [00000.413] initializing kernel [00000.413] initializing mp [00000.413] initializing timers [00000.413] INIT: cpu 0, calling hook 0xffffffff0010f458 (global_prng_thread_safe) at level 0x7ffff, flags 0x1 [00000.413] INIT: cpu 0, calling hook 0xffffffff0011b760 (debuglog) at level 0x7ffff, flags 0x1 [00000.413] INIT: cpu 0, calling hook 0xffffffff001e80d4 (thread_set_priority_experiment) at level 0x7ffff, flags 0x1 [00000.413] thread set priority experiment is : ENABLED [00000.413] creating bootstrap completion thread [00000.560] top of bootstrap2() [00000.560] INIT: cpu 0, calling hook 0xffffffff00127f40 (libobject) at level 0x80000, flags 0x1 [00000.560] OOM: started thread [00000.567] INIT: cpu 0, calling hook 0xffffffff0019fbd0 (dpc) at level 0x80000, flags 0x1 [00000.571] Processor Model Info: type 0 family 0x6 model 0xf stepping 0 [00000.571] display_family 0x6 display_model 0x4f [00000.571] Vendor: Intel [00000.571] Microarch: Broadwell [00000.571] F/M/S: 6/4f/0 [00000.571] Brand: Intel(R) Xeon(R) CPU @ 2.20GHz [00000.571] Features: fpu sse sse2 sse3 ssse3 sse4.1 sse4.2 mmx avx avx2 fxsr pcid xsave aesni clflush [00000.571] fsgsbase tsc_adj smep smap erms rdrand rdseed syscall nx rdtscp invar_tsc tsc_deadline [00000.571] x2apic hypervisor [00000.571] initializing platform [00000.571] INIT: cpu 0, calling hook 0xffffffff00190208 (x86_perfmon) at level 0x90000, flags 0x1 [00000.571] x86_perfmon_init_once:497: perfmon: unexpected programmable counter width 0 in cpuid.0AH [00000.572] UART: started IRQ driven RX [00000.572] UART: started IRQ driven TX [00000.572] cpu topology: [00000.572] 0: apic id 0x0 package 0 node 0 core 0 smt 0 BSP [00000.572] 1: apic id 0x1 package 1 node 0 core 0 smt 0 [00000.572] 2: apic id 0x2 package 2 node 0 core 0 smt 0 [00000.572] 3: apic id 0x3 package 3 node 0 core 0 smt 0 [00000.572] Found 4 cpus [00000.573] booting apic ids: 0x1 0x2 0x3 [00000.637] entering scheduler on cpu 2 [00000.637] entering scheduler on cpu 1 [00000.637] entering scheduler on cpu 3 [00000.645] smbios: manufacturer="QEMU" product="Standard PC (Q35 + ICH9, 2009)" [00000.646] initializing target [00000.646] INIT: cpu 0, calling hook 0xffffffff00175f94 (x86_pcie_init) at level 0xa0000, flags 0x1 [00000.646] moving to last init level [00000.646] INIT: cpu 0, calling hook 0xffffffff00102a48 (kernel_shell) at level 0xc0000, flags 0x1 [00000.646] INIT: cpu 0, calling hook 0xffffffff001fe2d0 (ktrace) at level 0xc0000, flags 0x1 [00000.720] ktrace: buffer at 0xffffff9902bee000 (33554432 bytes) [00000.720] INIT: cpu 0, calling hook 0xffffffff002003ac (userboot) at level 0xc0000, flags 0x1 [00000.720] userboot: ramdisk 0xbb8000 @ 0xffffff8000cbb000 [00000.734] userboot: userboot rodata 0 @ [0x6a340a88e000,0x6a340a891000) [00000.734] userboot: userboot code 0x3000 @ [0x6a340a891000,0x6a340a89c000) [00000.734] userboot: vdso/full rodata 0 @ [0x6a340a89c000,0x6a340a8a3000) [00000.734] userboot: vdso/full code 0x7000 @ [0x6a340a8a3000,0x6a340a8a4000) [00000.734] userboot: entry point @ 0x6a340a891c90 [00000.756] userboot: option "/b/s/w/ir/third_party/fuchsia-sdk/sdk/target/x64/qemu-kernel.bin=" [00000.756] userboot: option "devmgr.epoch=1553443430" [00000.756] userboot: option "zircon.nodename=b0724061-2ccd-4479-b2b4-24fc957ef5a2" [00000.756] userboot: option "TERM=dumb" [00000.756] userboot: option "kernel.serial=legacy" [00000.756] userboot: option "multiboot.boot_loader_name=qemu" [00000.821] userboot: searching bootfs for 'bin/bootsvc' [00000.821] userboot: 'bin/bootsvc' has PT_INTERP "lib/ld.so.1" [00000.821] userboot: searching bootfs for 'lib/ld.so.1' [00000.821] userboot: userboot: loaded lib/ld.so.1 at 0x53867dcf9000, entry point 0x53867dd362f1 [00000.821] userboot: userboot: loaded vDSO at 0x7ff5c998e000, entry point 0x0 [00000.821] userboot: process bin/bootsvc started. [00000.821] userboot: waiting for loader-service requests... [00000.822] userboot: searching bootfs for 'lib/libasync-default.so' [00000.822] userboot: searching bootfs for 'lib/libfdio.so' [00000.823] userboot: searching bootfs for 'lib/libtrace-engine.so' [00000.859] userboot: searching bootfs for 'lib/liblaunchpad.so' [00000.863] {{{reset}}} [00000.863] {{{module:0:<application>:elf:94461c4cd65941a7404d06e266384194518844b8}}} [00000.863] {{{mmap:0x1d82a8d1c000:0x39000:load:0:rx:0}}} [00000.863] {{{mmap:0x1d82a8d56000:0x4000:load:0:rw:0x3a000}}} [00000.863] dso: id=94461c4cd65941a7404d06e266384194518844b8 base=0x00001d82a8d1c000 name=<application> [00000.863] {{{module:0x2:<vDSO>:elf:848e8dbd777f208c0b053b07a09f9cb75d0b733d}}} [00000.863] {{{mmap:0x7ff5c998e000:0x7000:load:0x2:r:0}}} [00000.863] {{{mmap:0x7ff5c9995000:0x1000:load:0x2:rx:0x7000}}} [00000.863] dso: id=848e8dbd777f208c0b053b07a09f9cb75d0b733d base=0x00007ff5c998e000 name=<vDSO> [00000.863] {{{module:0x3:libasync-default.so:elf:179378e0029e9d73bcaaf61330269def68f524f0}}} [00000.863] {{{mmap:0x1a62067d8000:0x1000:load:0x3:rx:0}}} [00000.863] {{{mmap:0x1a62067d9000:0x1000:load:0x3:rw:0x1000}}} [00000.863] dso: id=179378e0029e9d73bcaaf61330269def68f524f0 base=0x00001a62067d8000 name=libasync-default.so [00000.863] {{{module:0x4:libfdio.so:elf:a27501026a4678a6202cb8464fe345c9a444e528}}} [00000.863] {{{mmap:0x5a391ae43000:0x32000:load:0x4:rx:0}}} [00000.863] {{{mmap:0x5a391ae76000:0x4000:load:0x4:rw:0x33000}}} [00000.863] dso: id=a27501026a4678a6202cb8464fe345c9a444e528 base=0x00005a391ae43000 name=libfdio.so [00000.863] {{{module:0x5:libtrace-engine.so:elf:6708dd71c4aadd17f77109dcef3a7898a67909cd}}} [00000.863] {{{mmap:0x5bbd81be7000:0x9000:load:0x5:rx:0}}} [00000.863] {{{mmap:0x5bbd81bf0000:0x2000:load:0x5:rw:0x9000}}} [00000.863] dso: id=6708dd71c4aadd17f77109dcef3a7898a67909cd base=0x00005bbd81be7000 name=libtrace-engine.so [00000.863] {{{module:0x6:liblaunchpad.so:elf:7bbb5d72e7b50ffd31348f1fff0031cd559eaa75}}} [00000.863] {{{mmap:0x61b5d4db000:0x7000:load:0x6:rx:0}}} [00000.863] {{{mmap:0x61b5d4e2000:0x2000:load:0x6:rw:0x7000}}} [00000.863] dso: id=7bbb5d72e7b50ffd31348f1fff0031cd559eaa75 base=0x0000061b5d4db000 name=liblaunchpad.so [00000.863] {{{module:0x1:libc.so:elf:11b3a65a984cd4ccebf4f07537a762964656c4ac}}} [00000.863] {{{mmap:0x53867dcf9000:0xcb000:load:0x1:rx:0}}} [00000.863] {{{mmap:0x53867ddc5000:0x6000:load:0x1:rw:0xcc000}}} [00000.863] dso: id=11b3a65a984cd4ccebf4f07537a762964656c4ac base=0x000053867dcf9000 name=libc.so [00000.864] bootsvc: Starting... [00000.984] userboot: loader-service channel peer closed [00000.985] userboot: finished! [00000.993] devcoordinator: init [00001.006] fshost: started. [00001.067] devhost: trace provider registry begun [00001.082] devhost: trace provider registry begun [00001.100] devhost: trace provider registry begun [00001.101] devhost: trace provider registry begun [00001.118] acpi-bus: not using IOMMU [00001.119] GetHwProperties: No PM support [00001.119] IntelPT: No PT support [00001.121] [sysmem_init 28] async_get_default_dispatcher(): 0x3061b34a70a0 [00001.121] [sysmem_bind 40] sysmem_bind() [00001.122] acpi-pwrbtn: initialized [00001.123] vc: new input device /dev/class/input/000 [00001.195] acpi: published device pci(0x307cd2704180), parent=sys(0x3061b34b60e0), handle=0x3061b34b3300 [00001.196] acpi: failed to get PCI base bus number for device 'PCI0' (status 4294967295) [00001.207] acpi: published device rtc(0x307cd2704200), parent=acpi(0x3061b34b6380), handle=0x3061b34b37b0 [00001.208] acpi: published device i8042(0x307cd2704280), parent=acpi(0x3061b34b6380), handle=0x3061b34b3840 [00001.229] devhost: trace provider registry begun [00001.239] devhost: trace provider registry begun [00001.249] devhost: trace provider registry begun [00001.263] devhost: trace provider registry begun [00001.266] ahci: using MSI interrupt [00001.273] bochs_vbe: initialized display, 1024 x 768 (stride=1024 format=00020001) [00001.415] 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.595] pkgsvr: system: will be served from 1f3c0e015f83b4595c55580c90238394fc1de26a60ceeac09c979ba987e32b07 [00001.595] pkgsvr: pkgfs serving index /data/pkgfs_index from blobfs /blob [00001.646] devcoordinator: launch /boot/bin/svchost (svchost) OK [00001.646] devcoordinator: launch /boot/bin/fshost (fshost) OK [00001.646] devcoordinator: launch /boot/bin/pwrbtn-monitor (pwrbtn-monitor) OK [00001.646] devcoordinator: launch /boot/bin/netsvc (netsvc) OK [00001.646] devcoordinator: launch /boot/bin/virtual-console (virtual-console) OK [00001.646] devcoordinator: full system required, ignoring fallback drivers until /system is loaded [00001.646] devcoordinator: launch devhost 'devhost:sys': pid=2104 [00001.646] devcoordinator: launch devhost 'devhost:test': pid=2149 [00001.646] devcoordinator: launch devhost 'devhost:root': pid=2221 [00001.646] devcoordinator: launch devhost 'devhost:misc': pid=2276 [00001.646] devcoordinator: launch /boot/bin/sh (sh:console) OK [00001.646] devcoordinator: rpc: bind-driver 'cpu-trace' status -2 [00001.646] devcoordinator: launch devhost 'devhost:pci#1:1234:1111': pid=2787 [00001.646] devcoordinator: launch devhost 'devhost:pci#2:1af4:1001': pid=2849 [00001.646] devcoordinator: launch devhost 'devhost:pci#3:8086:100e': pid=2940 [00001.646] devcoordinator: launch devhost 'devhost:pci#5:8086:2922': pid=3033 [00001.846] [INFO:namespace_builder.cc(93)] config-data for sysmgr [00002.517] [INFO:cobalt_main.cc(145)] Cobalt client schedule params: schedule_interval=3600 seconds, min_interval=10 seconds, initial_interval=60 seconds. [00002.737] [INFO:namespace_builder.cc(93)] config-data for wlancfg [00002.847] initialized repo /b/s/w/itU0gmRm/tmp066qe8 adding package web_engine_browsertests/0 2019/03/24 16:03:58 error removing source: Source not found 2019-03-24 09:04:00,044:INFO:root:Installing web_engine_browsertests version 0. 2019/03/24 16:04:02 Awaiting response... 2019/03/24 16:04:05 Awaiting response... 2019/03/24 16:04:08 Awaiting response... 2019/03/24 16:04:11 Awaiting response... 2019/03/24 16:04:14 Awaiting response... 2019/03/24 16:04:17 Awaiting response... 2019/03/24 16:04:20 Awaiting response... 2019/03/24 16:04:23 Awaiting response... 2019/03/24 16:04:26 Awaiting response... 2019/03/24 16:04:30 Awaiting response... 2019/03/24 16:04:33 Awaiting response... 2019/03/24 16:04:36 Awaiting response... 2019/03/24 16:04:39 Awaiting response... 2019/03/24 16:04:42 Awaiting response... 2019/03/24 16:04:45 Awaiting response... 2019/03/24 16:04:48 Awaiting response... 2019/03/24 16:04:51 Awaiting response... 2019/03/24 16:04:54 Awaiting response... 2019/03/24 16:04:57 Awaiting response... 2019/03/24 16:05:00 Awaiting response... 2019/03/24 16:05:03 Awaiting response... 2019/03/24 16:05:04 Success web_engine_browsertests/0: 7ccef6c72752c6a8a12c490af34d35d8a338275d3984b0f7dc0c3ffe34290d4a 2019-03-24 09:05:06,697:INFO:root:Running application. 2019-03-24 09:05:06,700: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". netifc: ? /dev/class/ethernet/000 [00002.852] netifc: create 512 eth buffers [00002.858] macaddr: 52:54:00:63:5e:7b [00002.858] ip6addr: fe80::5054:4d:fe63:5e7b [00002.858] snmaddr: ff02::1:ff63:5e7b [00002.858] netsvc: using /dev/class/ethernet/000 [00002.858] netsvc: nodename='b0724061-2ccd-4479-b2b4-24fc957ef5a2' [00002.858] netsvc: start [00003.277] [INFO:namespace_builder.cc(93)] config-data for crashpad_agent [00003.476] [INFO:main.cc(35)] Trace Manager starting with config: /pkg/data/tracing.config [00003.551] wlanstack2 [I]: Starting [00003.680] [INFO:a11y_input_system.cc(9)] Scenic accessibility input system started. [00003.684] [INFO:display_watcher.cc(40)] Scenic: Acquired display controller /dev/class/display-controller/000.(000) [00003.860] [INFO:input_interpreter.cc(1006)] hid-parser succesful for 000 with usage page 1 and usage 128 [00004.104] ERROR: setupLoaderTermPhysDevs: Failed to detect any valid GPUs in the current config [00004.104] ERROR: setupLoaderTrampPhysDevs: Failed during dispatch call of 'vkEnumeratePhysicalDevices' to lower layers or loader to get count. [00004.104] [ERROR:garnet/lib/ui/gfx/gfx_system.cc(188)] No Vulkan on device, Graphics system exiting. [00004.104] [INFO:input_system.cc(245)] Scenic input system initialized. [00004.110] [ERROR:garnet/bin/ui/root_presenter/app.cc(207)] Scenic died, destroying all presentations. [00004.110] [ERROR:garnet/bin/ui/view_manager/view_registry.cc(80)] Exiting due to scene manager connection error. [00004.165] [ERROR:garnet/bin/sysmgr/app.cc(180)] Singleton fuchsia-pkg://fuchsia.com/view_manager#meta/view_manager.cmx died [00004.172] [ERROR:garnet/bin/sysmgr/app.cc(180)] Singleton fuchsia-pkg://fuchsia.com/scenic#meta/scenic.cmx died [00004.502] Starting mDNS on interface ethp0003 192.168.3.9 [00004.507] Starting mDNS on interface ethp0003 [80fe::5450:ff00:63fe:7b5e] [00004.508] [ERROR:garnet/bin/mdns/service/mdns_interface_transceiver_v6.cc(49)] Failed to set socket option IPV6_JOIN_GROUP, No such device [00004.509] mDNS: Verifying uniqueness of host name step-dime-yard-juicy.local. [00005.484] mDNS: Using unique host name step-dime-yard-juicy.local. [00009.097] pkgsvr: asking amber to fetch 2424 needed blobs [00074.149] pkgsvr: package activated web_engine_browsertests/0 (7ccef6c72752c6a8a12c490af34d35d8a338275d3984b0f7dc0c3ffe34290d4a) 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. [00076.366] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00076.366] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00076.558] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00076.683] dlsvc: could not open 'libGLES_CM.so' [00076.691] dlsvc: could not open 'libGLES_CM.so' [00077.426] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00077.512] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00077.555] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00077.725] dlsvc: could not open 'libGLES_CM.so' [00077.730] dlsvc: could not open 'libGLES_CM.so' [00078.025] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [1/30] IncognitoContextImplTest.NavigateFrame (2225 ms) [00078.365] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00078.421] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00078.582] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00078.652] dlsvc: could not open 'libGLES_CM.so' [00078.658] dlsvc: could not open 'libGLES_CM.so' [2/30] ContextImplTest.VerifyPersistentCookieStore (2587 ms) [00079.164] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00079.215] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00079.227] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [3/30] IncognitoContextImplTest.VerifyInMemoryCookieStore (1419 ms) [00079.890] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00080.018] dlsvc: could not open 'libGLES_CM.so' [00080.022] dlsvc: could not open 'libGLES_CM.so' [00080.093] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00080.125] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00080.211] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [4/30] FrameImplTest.NavigateFrame (2054 ms) [00081.005] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00081.108] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00081.227] dlsvc: could not open 'libGLES_CM.so' [00081.228] dlsvc: could not open 'libGLES_CM.so' [00081.251] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00081.466] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [5/30] FrameImplTest.NavigateDataFrame (2218 ms) [6/30] FrameImplTest.FrameDeletedBeforeContext (1264 ms) [00082.114] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00082.121] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00082.444] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [7/30] FrameImplTest.ContextDeletedBeforeFrame (550 ms) [00082.492] dlsvc: could not open 'libGLES_CM.so' [00082.497] dlsvc: could not open 'libGLES_CM.so' [00082.540] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [8/30] FrameImplTest.ContextDeletedBeforeFrameWithView (647 ms) [00082.668] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00082.746] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00082.794] dlsvc: could not open 'libGLES_CM.so' [00082.796] dlsvc: could not open 'libGLES_CM.so' [00083.619] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00083.678] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00083.823] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00083.993] dlsvc: could not open 'libGLES_CM.so' [00083.999] dlsvc: could not open 'libGLES_CM.so' [00084.283] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00084.448] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [9/30] FrameImplTest.ReloadFrame (2341 ms) [00085.071] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00085.327] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [10/30] FrameImplTest.GoBackAndForward (2820 ms) [00085.403] dlsvc: could not open 'libGLES_CM.so' [00085.409] dlsvc: could not open 'libGLES_CM.so' [00085.891] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00085.999] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00086.033] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00086.302] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00086.379] dlsvc: could not open 'libGLES_CM.so' [00086.386] dlsvc: could not open 'libGLES_CM.so' [11/30] FrameImplTest.GetVisibleEntry (1747 ms) [00086.853] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00086.941] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00086.965] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00087.429] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00087.587] dlsvc: could not open 'libGLES_CM.so' [00087.593] dlsvc: could not open 'libGLES_CM.so' [12/30] FrameImplTest.NoNavigationObserverAttached (2325 ms) [00087.708] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00088.035] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00088.274] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00088.553] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00088.627] dlsvc: could not open 'libGLES_CM.so' [00088.650] dlsvc: could not open 'libGLES_CM.so' [13/30] FrameImplTest.ExecuteJavaScriptImmediate (2018 ms) [00089.235] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00089.323] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00089.363] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00089.613] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00089.805] dlsvc: could not open 'libGLES_CM.so' [00089.828] dlsvc: could not open 'libGLES_CM.so' [00089.966] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00090.034] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [14/30] FrameImplTest.ExecuteJavaScriptOnLoad (2543 ms) [00090.409] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00090.559] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00090.631] dlsvc: could not open 'libGLES_CM.so' [00090.636] dlsvc: could not open 'libGLES_CM.so' [15/30] FrameImplTest.ExecuteJavaScriptOnLoadVmoDestroyed (2052 ms) [00091.110] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00091.209] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00091.213] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00091.552] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00091.665] dlsvc: could not open 'libGLES_CM.so' [00091.672] dlsvc: could not open 'libGLES_CM.so' [16/30] FrameImplTest.ExecuteJavascriptOnLoadWrongOrigin (1762 ms) [00092.069] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00092.468] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00092.514] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00093.516] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00093.719] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00093.822] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00093.831] dlsvc: could not open 'libGLES_CM.so' [00093.837] dlsvc: could not open 'libGLES_CM.so' [00094.157] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [17/30] FrameImplTest.ExecuteJavaScriptOnLoadWildcardOrigin (3713 ms) [18/30] FrameImplTest.ExecuteMultipleJavaScriptsOnLoad (2743 ms) [00094.720] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00094.784] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00094.923] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00094.993] dlsvc: could not open 'libGLES_CM.so' [00094.998] dlsvc: could not open 'libGLES_CM.so' [00095.993] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00096.047] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00096.128] dlsvc: could not open 'libGLES_CM.so' [00096.133] dlsvc: could not open 'libGLES_CM.so' [00096.331] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00096.796] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00096.839] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [19/30] FrameImplTest.ExecuteOnLoadEarlyAndLateRegistrations (3004 ms) [20/30] FrameImplTest.ExecuteJavaScriptBadEncoding (2892 ms) [00097.742] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00097.742] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00097.925] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00098.085] dlsvc: could not open 'libGLES_CM.so' [00098.095] dlsvc: could not open 'libGLES_CM.so' [00098.914] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00099.003] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00099.023] dlsvc: could not open 'libGLES_CM.so' [00099.027] dlsvc: could not open 'libGLES_CM.so' [00099.144] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00099.499] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00099.511] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [21/30] FrameImplTest.NavigationObserverDisconnected (2679 ms) [22/30] FrameImplTest.DelayedNavigationEventAck (2677 ms) [00100.401] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00100.410] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [23/30] FrameImplTest.Stop (962 ms) [00101.303] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00101.424] dlsvc: could not open 'libGLES_CM.so' [00101.426] dlsvc: could not open 'libGLES_CM.so' [00101.685] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00101.768] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00101.816] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00102.117] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00102.130] dlsvc: could not open 'libGLES_CM.so' [00102.136] dlsvc: could not open 'libGLES_CM.so' [00102.540] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00102.566] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [24/30] FrameImplTest.PostMessage (2412 ms) [00102.818] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [25/30] FrameImplTest.PostMessagePassMessagePort (2194 ms) [00103.600] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00103.743] dlsvc: could not open 'libGLES_CM.so' [00103.746] dlsvc: could not open 'libGLES_CM.so' [00103.961] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00104.037] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00104.047] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00104.857] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [002019-03-24 09:05:40,577:INFO:root:Process exited normally with status code 0. 2019-03-24 09:05:40,577:INFO:root:Terminating kernel log reader. 2019-03-24 09:05:40,735:INFO:root:Shutting down QEMU. 104.911] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00105.018] dlsvc: could not open 'libGLES_CM.so' [00105.019] dlsvc: could not open 'libGLES_CM.so' [00105.091] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [26/30] FrameImplTest.PostMessageMessagePortDisconnected (2831 ms) [27/30] FrameImplTest.PostMessageUseContentProvidedPort (2385 ms) [00105.741] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00105.788] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00105.972] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00106.100] dlsvc: could not open 'libGLES_CM.so' [00106.127] dlsvc: could not open 'libGLES_CM.so' [00106.873] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00106.952] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00107.118] dlsvc: could not open 'libGLES_CM.so' [00107.123] dlsvc: could not open 'libGLES_CM.so' [00107.207] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00107.318] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00107.639] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [28/30] FrameImplTest.RecreateView (2454 ms) [29/30] FrameImplTest.PostMessageBadOriginDropped (2743 ms) [00108.323] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00108.469] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00108.570] dlsvc: could not open 'libGLES_CM.so' [00108.578] dlsvc: could not open 'libGLES_CM.so' [00108.756] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [00108.771] pkgsvr: pkgfs:unsupported(/packages/web_engine_browsertests/0): dir unlink "webrunner.log" [30/30] RequestMonitoringFrameImplBrowserTest.ExtraHeaders (859 ms) SUCCESS: all tests passed. 2019-03-24 09:06:02,116 - root: [DEBUG] Contents of task_output_dir: ['/b/swarming/w/ir/tmp/t/tmp3ZAGW8/43c942d0c41b2510'] 2019-03-24 09:06:02,116 - root: [DEBUG] Found shard_json_files: ['/b/swarming/w/ir/tmp/t/tmp3ZAGW8/43c942d0c41b2510/output.json'] 2019-03-24 09:06:02,117 - root: [INFO] merge_cmd: /b/swarming/w/ir/cache/vpython/6e0330/bin/python /b/swarming/w/ir/cache/builder/src/testing/merge_scripts/standard_gtest_merge.py --build-properties {"bot_id": "swarm973-c4", "branch": "refs/heads/master", "buildbucket": {"build": {"bucket": "luci.chromium.ci", "created_by": "project:chromium", "created_ts": 1553443091071983, "id": "8918090573295123808", "project": "chromium", "tags": ["build_address:luci.chromium.ci/Fuchsia x64/31838", "builder:Fuchsia x64", "buildset:commit/git/d488b3fa47490675ada321f1c0c9752db0f8dc9d", "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d488b3fa47490675ada321f1c0c9752db0f8dc9d", "gitiles_ref:refs/heads/master", "scheduler_invocation_id:9083520635357756992", "scheduler_job_id:chromium/Fuchsia x64", "user_agent:luci-scheduler"]}, "hostname": "cr-buildbucket.appspot.com"}, "buildername": "Fuchsia x64", "buildnumber": 31838, "got_angle_revision": "eae464dd3092c1c09c4dc4d51a0f50ac5d007722", "got_dawn_revision": "07950e80fe36028ed9cca0a4a99a8b08e9bb4fdf", "got_nacl_revision": "de8da4841bc3d1ed020d798d5a7fd6fb3d817fa1", "got_revision": "d488b3fa47490675ada321f1c0c9752db0f8dc9d", "got_revision_cp": "refs/heads/master@{#643730}", "got_swarming_client_revision": "aa60736aded9fc32a0e21a81f5fc51f6009d01f3", "got_v8_revision": "b346546b7795350b8c910ff06f9520e7f03224f0", "got_v8_revision_cp": "refs/heads/7.5.100@{#1}", "got_webrtc_revision": "85a4a93e77b163098e91f41d8e7415181f23d02c", "got_webrtc_revision_cp": "refs/heads/master@{#27257}", "mastername": "chromium.linux", "path_config": "generic", "recipe": "chromium", "repository": "https://chromium.googlesource.com/chromium/src.git", "revision": "d488b3fa47490675ada321f1c0c9752db0f8dc9d"} --summary-json /b/swarming/w/ir/tmp/t/tmpDwWOO9.json --task-output-dir /b/swarming/w/ir/tmp/t/tmp3ZAGW8 -o /b/swarming/w/ir/tmp/t/tmpzw2ySl.json /b/swarming/w/ir/tmp/t/tmp3ZAGW8/43c942d0c41b2510/output.json Running ['/b/swarming/w/ir/cache/vpython/6e0330/bin/python', '/b/swarming/w/ir/cache/builder/src/testing/merge_scripts/standard_gtest_merge.py', '--build-properties', '{"bot_id": "swarm973-c4", "branch": "refs/heads/master", "buildbucket": {"build": {"bucket": "luci.chromium.ci", "created_by": "project:chromium", "created_ts": 1553443091071983, "id": "8918090573295123808", "project": "chromium", "tags": ["build_address:luci.chromium.ci/Fuchsia x64/31838", "builder:Fuchsia x64", "buildset:commit/git/d488b3fa47490675ada321f1c0c9752db0f8dc9d", "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d488b3fa47490675ada321f1c0c9752db0f8dc9d", "gitiles_ref:refs/heads/master", "scheduler_invocation_id:9083520635357756992", "scheduler_job_id:chromium/Fuchsia x64", "user_agent:luci-scheduler"]}, "hostname": "cr-buildbucket.appspot.com"}, "buildername": "Fuchsia x64", "buildnumber": 31838, "got_angle_revision": "eae464dd3092c1c09c4dc4d51a0f50ac5d007722", "got_dawn_revision": "07950e80fe36028ed9cca0a4a99a8b08e9bb4fdf", "got_nacl_revision": "de8da4841bc3d1ed020d798d5a7fd6fb3d817fa1", "got_revision": "d488b3fa47490675ada321f1c0c9752db0f8dc9d", "got_revision_cp": "refs/heads/master@{#643730}", "got_swarming_client_revision": "aa60736aded9fc32a0e21a81f5fc51f6009d01f3", "got_v8_revision": "b346546b7795350b8c910ff06f9520e7f03224f0", "got_v8_revision_cp": "refs/heads/7.5.100@{#1}", "got_webrtc_revision": "85a4a93e77b163098e91f41d8e7415181f23d02c", "got_webrtc_revision_cp": "refs/heads/master@{#27257}", "mastername": "chromium.linux", "path_config": "generic", "recipe": "chromium", "repository": "https://chromium.googlesource.com/chromium/src.git", "revision": "d488b3fa47490675ada321f1c0c9752db0f8dc9d"}', '--summary-json', '/b/swarming/w/ir/tmp/t/tmpDwWOO9.json', '--task-output-dir', '/b/swarming/w/ir/tmp/t/tmp3ZAGW8', '-o', '/b/swarming/w/ir/tmp/t/tmpzw2ySl.json', '/b/swarming/w/ir/tmp/t/tmp3ZAGW8/43c942d0c41b2510/output.json'] in None (env: None) Command ['/b/swarming/w/ir/cache/vpython/6e0330/bin/python', '/b/swarming/w/ir/cache/builder/src/testing/merge_scripts/standard_gtest_merge.py', '--build-properties', '{"bot_id": "swarm973-c4", "branch": "refs/heads/master", "buildbucket": {"build": {"bucket": "luci.chromium.ci", "created_by": "project:chromium", "created_ts": 1553443091071983, "id": "8918090573295123808", "project": "chromium", "tags": ["build_address:luci.chromium.ci/Fuchsia x64/31838", "builder:Fuchsia x64", "buildset:commit/git/d488b3fa47490675ada321f1c0c9752db0f8dc9d", "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d488b3fa47490675ada321f1c0c9752db0f8dc9d", "gitiles_ref:refs/heads/master", "scheduler_invocation_id:9083520635357756992", "scheduler_job_id:chromium/Fuchsia x64", "user_agent:luci-scheduler"]}, "hostname": "cr-buildbucket.appspot.com"}, "buildername": "Fuchsia x64", "buildnumber": 31838, "got_angle_revision": "eae464dd3092c1c09c4dc4d51a0f50ac5d007722", "got_dawn_revision": "07950e80fe36028ed9cca0a4a99a8b08e9bb4fdf", "got_nacl_revision": "de8da4841bc3d1ed020d798d5a7fd6fb3d817fa1", "got_revision": "d488b3fa47490675ada321f1c0c9752db0f8dc9d", "got_revision_cp": "refs/heads/master@{#643730}", "got_swarming_client_revision": "aa60736aded9fc32a0e21a81f5fc51f6009d01f3", "got_v8_revision": "b346546b7795350b8c910ff06f9520e7f03224f0", "got_v8_revision_cp": "refs/heads/7.5.100@{#1}", "got_webrtc_revision": "85a4a93e77b163098e91f41d8e7415181f23d02c", "got_webrtc_revision_cp": "refs/heads/master@{#27257}", "mastername": "chromium.linux", "path_config": "generic", "recipe": "chromium", "repository": "https://chromium.googlesource.com/chromium/src.git", "revision": "d488b3fa47490675ada321f1c0c9752db0f8dc9d"}', '--summary-json', '/b/swarming/w/ir/tmp/t/tmpDwWOO9.json', '--task-output-dir', '/b/swarming/w/ir/tmp/t/tmp3ZAGW8', '-o', '/b/swarming/w/ir/tmp/t/tmpzw2ySl.json', '/b/swarming/w/ir/tmp/t/tmp3ZAGW8/43c942d0c41b2510/output.json'] returned exit code 0