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

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-05-25 02:41:56,373 - root: [DEBUG] Using task_output_dir: '/b/swarming/w/ir/tmp/t/tmpDuGrGf' 2019-05-25 02:41:56,373 - root: [WARNING] task_output_dir '/b/swarming/w/ir/tmp/t/tmpDuGrGf' already exists! 2019-05-25 02:41:56,373 - root: [WARNING] task_output_dir existing content: [] 2019-05-25 02:41:56,373 - 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/tmp7ux_pT.json -output-dir /b/swarming/w/ir/tmp/t/tmpDuGrGf -task-summary-json /b/swarming/w/ir/tmp/t/tmpfvppuc.json [D2019-05-25T02:41:56.387236-07:00 18194 0 auth.go:1265] Minting a new token {"key":"luci_ctx/ae69508c3810ba7331dd94bce798cb9bb0b6ce97993f4646e9ed0ce28fe31ecd", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-05-25T02:41:56.387352-07:00 18194 0 luci_ctx.go:138] POST http://127.0.0.1:53300/rpc/LuciLocalAuthService.GetOAuthToken {"key":"luci_ctx/ae69508c3810ba7331dd94bce798cb9bb0b6ce97993f4646e9ed0ce28fe31ecd", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-05-25T02:41:56.389783-07:00 18194 0 auth.go:1222] Token expires in 43m4.610245652s {"key":"luci_ctx/ae69508c3810ba7331dd94bce798cb9bb0b6ce97993f4646e9ed0ce28fe31ecd", "scopes":"https://www.googleapis.com/auth/userinfo.email"} 45072d7cc7a70010: 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/iorA_oga/output.json 2019-05-25 02:39:35,976:INFO:root:Connecting to Fuchsia using SSH. 2019-05-25 02:39:43,218:INFO:root:Connected! 2019-05-25 02:39:43,219:INFO:root:Attaching kernel logger. [00000.000] zbi: @ 0xffffff8000d28000 (12731768 bytes) [00000.000] UART: FIFO depth 16 [00000.000] PMM: boot reserve add [0x100000, 0x311fff] [00000.000] PMM: boot reserve add [0xd28000, 0x194cfff] [00000.000] PMM: boot reserve marking WIRED [0x100000, 0x311fff] [00000.000] PMM: boot reserve marking WIRED [0xd28000, 0x194cfff] [00000.000] [00000.000] welcome to Zircon [00000.000] [00000.000] KASLR: .text section at 0xffffffff00100000 [00000.000] INIT: cpu 0, calling hook 0xffffffff001183f4 (global_prng_seed) at level 0x30000, flags 0x1 [00000.000] initializing vm pre-heap [00000.000] VM: marking boot alloc used range [0x194d000, 0x194d040) [00000.000] INIT: cpu 0, calling hook 0xffffffff00125650 (elf_build_id) at level 0x4fffe, flags 0x1 [00000.000] INIT: cpu 0, calling hook 0xffffffff001257dc (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-80c53851eb71b568b7e6640123d3c341405e913b-dirty [00000.000] ELF build ID: e18c341cd5203fa6d21937035cf874594130b58e [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 0xffffffff001872ec (x86_resource_init) at level 0x50000, flags 0x1 [00000.000] initializing vm [00000.000] VM: reserving kernel region [0xffffffff00100000, 0xffffffff00229000) flags 0x28 name 'kernel_code' [00000.000] VM: reserving kernel region [0xffffffff00229000, 0xffffffff0027c000) flags 0x8 name 'kernel_rodata' [00000.000] VM: reserving kernel region [0xffffffff0027c000, 0xffffffff00284000) flags 0x18 name 'kernel_data' [00000.000] VM: reserving kernel region [0xffffffff00284000, 0xffffffff00312000) flags 0x18 name 'kernel_bss' [00000.000] INIT: cpu 0, calling hook 0xffffffff00188010 (display_memtype) at level 0x60001, flags 0x1 [00000.000] INIT: cpu 0, calling hook 0xffffffff0018b538 (acpi_tables) at level 0x60001, flags 0x1 [00000.000] INIT: cpu 0, calling hook 0xffffffff00186014 (hpet) at level 0x60002, flags 0x1 [00000.000] INIT: cpu 0, calling hook 0xffffffff00186430 (apic) at level 0x60002, flags 0x1 [00000.000] x2APIC enabled [00000.000] INIT: cpu 0, calling hook 0xffffffff001ad2a8 (system_topology_init) at level 0x60002, flags 0x1 [00000.000] WARNING: unable to find any cache levels. [00000.000] WARNING: unable to find any cache levels. [00000.000] Could not find SRAT table. ACPICA returned: 5 [00000.000] INIT: cpu 0, calling hook 0xffffffff0018ab28 (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: 2199810 ticks/ms [00000.426] timer features: constant_tsc 1 invariant_tsc 1 tsc_deadline 1 [00000.426] Using TSC as wallclock [00000.426] INIT: cpu 0, calling hook 0xffffffff002190c4 (percpu_heap_init) at level 0x60003, flags 0x1 [00000.426] initializing kernel [00000.426] initializing mp [00000.426] INIT: cpu 0, calling hook 0xffffffff00118648 (global_prng_thread_safe) at level 0x7ffff, flags 0x1 [00000.426] creating bootstrap completion thread [00000.426] top of bootstrap2() [00000.426] INIT: cpu 0, calling hook 0xffffffff00134cac (libobject) at level 0x80000, flags 0x1 [00000.426] OOM: started thread [00000.426] INIT: cpu 0, calling hook 0xffffffff00215ed4 (dpc) at level 0x80000, flags 0x1 [00000.426] Processor Model Info: type 0 family 0x6 model 0xf stepping 0 [00000.426] display_family 0x6 display_model 0x4f [00000.426] Vendor: Intel [00000.426] Microarch: Broadwell [00000.426] F/M/S: 6/4f/0 [00000.426] patch_level: 0 [00000.426] Brand: Intel(R) Xeon(R) CPU @ 2.20GHz [00000.426] Features: fpu sse sse2 sse3 ssse3 sse4.1 sse4.2 mmx avx avx2 fxsr pcid xsave aesni clflush [00000.426] fsgsbase tsc_adj smep smap erms rdrand rdseed syscall nx rdtscp invar_tsc tsc_deadline [00000.426] x2apic hypervisor [00000.426] Properties: meltdown l1tf pcid_good [00000.426] initializing platform [00000.426] INIT: cpu 0, calling hook 0xffffffff001a692c (x86_perfmon) at level 0x90000, flags 0x1 [00000.426] x86_perfmon_init_once:454: perfmon: unexpected programmable counter width 0 in cpuid.0AH [00000.427] UART: started IRQ driven RX [00000.427] UART: started IRQ driven TX [00000.427] cpu topology: [00000.427] 0: apic id 0x0 BSP [00000.427] 1: apic id 0x1 [00000.427] 2: apic id 0x2 [00000.427] 3: apic id 0x3 [00000.427] Found 4 cpus [00000.427] booting apic ids: 0x1 0x2 0x3 [00000.440] entering scheduler on cpu 2 [00000.440] entering scheduler on cpu 3 [00000.440] entering scheduler on cpu 1 [00000.441] smbios: manufacturer="QEMU" product="Standard PC (Q35 + ICH9, 2009)" [00000.441] initializing target [00000.441] INIT: cpu 0, calling hook 0xffffffff00189ccc (x86_pcie_init) at level 0xa0000, flags 0x1 [00000.442] moving to last init level [00000.442] INIT: cpu 0, calling hook 0xffffffff001248a8 (debuglog) at level 0xb0000, flags 0x1 [00000.442] INIT: cpu 0, calling hook 0xffffffff001798f0 (kcounters) at level 0xb0000, flags 0x1 [00000.442] INIT: cpu 0, calling hook 0xffffffff00102aa0 (kernel_shell) at level 0xc0000, flags 0x1 [00000.442] INIT: cpu 0, calling hook 0xffffffff002128f8 (ktrace) at level 0xc0000, flags 0x1 [00000.778] ktrace: buffer at 0xffffff97158bf000 (33554432 bytes) [00000.778] INIT: cpu 0, calling hook 0xffffffff00225f08 (userboot) at level 0xc0000, flags 0x1 [00000.778] userboot: ramdisk 0xc25000 @ 0xffffff8000d28000 [00000.793] userboot: userboot rodata 0 @ [0x4f24131b8000,0x4f24131bb000) [00000.793] userboot: userboot code 0x3000 @ [0x4f24131bb000,0x4f24131c6000) [00000.793] userboot: vdso/full rodata 0 @ [0x4f24131c6000,0x4f24131cd000) [00000.793] userboot: vdso/full code 0x7000 @ [0x4f24131cd000,0x4f24131ce000) [00000.793] userboot: entry point @ 0x4f24131bbc90 [00000.815] userboot: option "/b/s/w/ir/third_party/fuchsia-sdk/sdk/target/x64/qemu-kernel.bin=" [00000.815] userboot: option "devmgr.epoch=1558777175" [00000.815] userboot: option "TERM=dumb" [00000.815] userboot: option "kernel.serial=legacy" [00000.815] userboot: option "multiboot.boot_loader_name=qemu" [00000.848] userboot: searching bootfs for 'bin/bootsvc' [00000.848] userboot: 'bin/bootsvc' has PT_INTERP "lib/ld.so.1" [00000.848] userboot: searching bootfs for 'lib/ld.so.1' [00000.848] userboot: userboot: loaded lib/ld.so.1 at 0x736a45966000, entry point 0x736a45984880 [00000.848] userboot: userboot: loaded vDSO at 0x4d8df2980000, entry point 0x4d8df2987947 [00000.848] userboot: process bin/bootsvc started. [00000.848] userboot: waiting for loader-service requests... [00000.849] userboot: searching bootfs for 'lib/libfdio.so' [00000.849] userboot: searching bootfs for 'lib/libtrace-engine.so' [00000.884] userboot: searching bootfs for 'lib/libasync-default.so' [00000.885] userboot: searching bootfs for 'lib/liblaunchpad.so' [00000.885] userboot: searching bootfs for 'lib/libc++.so.2' [00000.885] userboot: searching bootfs for 'lib/libc++abi.so.1' [00000.901] userboot: searching bootfs for 'lib/libunwind.so.1' [00000.905] {{{reset}}} [00000.905] {{{module:0:<application>:elf:cb41ad5ff525119d5fb38913fececc6a4a9a91fe}}} [00000.905] {{{mmap:0x25f33c365000:0x3b000:load:0:rx:0}}} [00000.905] {{{mmap:0x25f33c3a1000:0x4000:load:0:rw:0x3c000}}} [00000.905] dso: id=cb41ad5ff525119d5fb38913fececc6a4a9a91fe base=0x000025f33c365000 name=<application> [00000.905] {{{module:0x2:<vDSO>:elf:2ddcbe6a7f3c2077addc9a53695676646bee1abf}}} [00000.905] {{{mmap:0x4d8df2980000:0x7000:load:0x2:r:0}}} [00000.905] {{{mmap:0x4d8df2987000:0x1000:load:0x2:rx:0x7000}}} [00000.905] dso: id=2ddcbe6a7f3c2077addc9a53695676646bee1abf base=0x00004d8df2980000 name=<vDSO> [00000.905] {{{module:0x3:libfdio.so:elf:5f05a9dbbc82442cfe83235ad3b42ba339cb2cff}}} [00000.905] {{{mmap:0x1b5c5aaf1000:0x39000:load:0x3:rx:0}}} [00000.905] {{{mmap:0x1b5c5ab2a000:0x6000:load:0x3:rw:0x39000}}} [00000.905] dso: id=5f05a9dbbc82442cfe83235ad3b42ba339cb2cff base=0x00001b5c5aaf1000 name=libfdio.so [00000.905] {{{module:0x4:libtrace-engine.so:elf:36b9f8765d0109090c021638f2101b253564162e}}} [00000.905] {{{mmap:0xb562831c000:0x9000:load:0x4:rx:0}}} [00000.905] {{{mmap:0xb5628325000:0x2000:load:0x4:rw:0x9000}}} [00000.905] dso: id=36b9f8765d0109090c021638f2101b253564162e base=0x00000b562831c000 name=libtrace-engine.so [00000.905] {{{module:0x5:libasync-default.so:elf:ecb2bce2176d0a131210570fb2d3e094fa6e1d49}}} [00000.905] {{{mmap:0x49145ddc1000:0x1000:load:0x5:rx:0}}} [00000.905] {{{mmap:0x49145ddc2000:0x1000:load:0x5:rw:0x1000}}} [00000.905] dso: id=ecb2bce2176d0a131210570fb2d3e094fa6e1d49 base=0x000049145ddc1000 name=libasync-default.so [00000.905] {{{module:0x6:liblaunchpad.so:elf:4aa3c1bd360c85e919db0aeb1ff6d7adb1818ed0}}} [00000.905] {{{mmap:0x77f39184d000:0x7000:load:0x6:rx:0}}} [00000.905] {{{mmap:0x77f391854000:0x2000:load:0x6:rw:0x7000}}} [00000.905] dso: id=4aa3c1bd360c85e919db0aeb1ff6d7adb1818ed0 base=0x000077f39184d000 name=liblaunchpad.so [00000.905] {{{module:0x1:libc.so:elf:2d3da7ba6f1127c094f287551e1f1415d351c2ac}}} [00000.905] {{{mmap:0x736a45966000:0xa6000:load:0x1:rx:0}}} [00000.905] {{{mmap:0x736a45a0c000:0x5000:load:0x1:rw:0xa6000}}} [00000.905] dso: id=2d3da7ba6f1127c094f287551e1f1415d351c2ac base=0x0000736a45966000 name=libc.so [00000.905] {{{module:0x7:libc++.so.2:elf:c40e3db9703e022b}}} [00000.905] {{{mmap:0x62b5092d9000:0x53000:load:0x7:r:0}}} [00000.905] {{{mmap:0x62b50932c000:0x7e000:load:0x7:rx:0x53000}}} [00000.905] {{{mmap:0x62b5093aa000:0x5000:load:0x7:rw:0xd1000}}} [00000.905] {{{mmap:0x62b5093af000:0x3000:load:0x7:rw:0xd6000}}} [00000.905] dso: id=c40e3db9703e022b base=0x000062b5092d9000 name=libc++.so.2 [00000.905] {{{module:0x8:libc++abi.so.1:elf:7dd43029d9b84d36}}} [00000.905] {{{mmap:0x6945c6c04000:0x16000:load:0x8:r:0}}} [00000.905] {{{mmap:0x6945c6c1a000:0x23000:load:0x8:rx:0x16000}}} [00000.905] {{{mmap:0x6945c6c3d000:0x3000:load:0x8:rw:0x39000}}} [00000.905] {{{mmap:0x6945c6c40000:0x1000:load:0x8:rw:0x3c000}}} [00000.905] dso: id=7dd43029d9b84d36 base=0x00006945c6c04000 name=libc++abi.so.1 [00000.905] {{{module:0x9:libunwind.so.1:elf:53013261665bb49a}}} [00000.905] {{{mmap:0x1e0db7209000:0x6000:load:0x9:r:0}}} [00000.905] {{{mmap:0x1e0db720f000:0x8000:load:0x9:rx:0x6000}}} [00000.905] {{{mmap:0x1e0db7217000:0x1000:load:0x9:rw:0xe000}}} [00000.905] {{{mmap:0x1e0db7218000:0x1000:load:0x9:rw:0xf000}}} [00000.905] dso: id=53013261665bb49a base=0x00001e0db7209000 name=libunwind.so.1 [00000.906] bootsvc: Starting... [00000.906] userboot: loader-service channel peer closed [00000.907] userboot: finished! [00001.113] bootsvc: Creating bootfs service... [00001.117] bootsvc: Retrieving boot image... [00001.117] bootsvc: Decommitted BOOTDATA VMO from 0 to 0xc24000 [00001.117] bootsvc: Loading boot arguments... [00001.117] bootsvc: Creating svcfs service... [00001.117] bootsvc: Loading kernel VMOs... [00001.117] bootsvc: Creating loader service... [00001.117] bootsvc: Launching next process... [00001.139] bootsvc: bootsvc.next = bin/devcoordinator [00001.139] bootsvc: Launched bin/devcoordinator [00001.150] devcoordinator: launch /boot/bin/svchost (svchost) OK [00001.157] devcoordinator: launch /boot/bin/fshost (fshost) OK [00001.172] devcoordinator: launch /boot/bin/pwrbtn-monitor (pwrbtn-monitor) OK [00001.179] devcoordinator: launch /boot/bin/miscsvc (miscsvc) OK [00001.198] devcoordinator: launch /boot/bin/netsvc (netsvc) OK [00001.214] devcoordinator: launch /boot/bin/virtual-console (virtual-console) OK [00001.294] devcoordinator: full system required, ignoring fallback drivers until /system is loaded [00001.300] devcoordinator: launch devhost 'devhost:sys': pid=2468 [00001.305] devcoordinator: launch devhost 'devhost:test': pid=2506 [00001.313] devcoordinator: launch devhost 'devhost:root': pid=2563 [00001.324] devcoordinator: launch devhost 'devhost:misc': pid=2644 [00001.404] acpi-bus: not using IOMMU [00001.406] GetHwProperties: No PM support [00001.406] IntelPT: No PT support [00001.406] devhost[sys/cpu-trace] bind driver '/boot/driver/cpu-trace.so' failed: -2 [00001.406] devcoordinator: rpc: bind-driver 'cpu-trace' status -2 [00001.408] [sysmem_init 28] async_get_default_dispatcher(): 0x7fc8afb64250 [00001.408] [sysmem_bind 40] sysmem_bind() [00001.410] acpi-pwrbtn: initialized [00001.421] vc: new input device /dev/class/input/000 [00001.456] devcoordinator: launch /boot/bin/sh (sh:console) OK [00001.481] acpi: published device pci(0x7fc7efb6f130), parent=sys(0x7fc92fb6a310), handle=0x7fc7afb6b8d0 [00001.481] acpi: failed to get PCI base bus number for device 'PCI0' (status 4294967295) [00001.482] acpi: published device rtc(0x7fc7efb65e90), parent=acpi(0x7fc92fb6a410), handle=0x7fc7afb6b450 [00001.482] acpi: published device i8042(0x7fc7efb65230), parent=acpi(0x7fc92fb6a410), handle=0x7fc7afb6b750 [00001.483] acpi: published device serial(0x7fc7efb64ed0), parent=acpi(0x7fc92fb6a410), handle=0x7fc7afb6bf10 [00001.483] acpi: published device serial(0x7fc7efb66f70), parent=acpi(0x7fc92fb6a410), handle=0x7fc7afb6cf90 [00001.521] devcoordinator: launch devhost 'devhost:pci#1:1234:1111': pid=3307 [00001.532] devcoordinator: launch devhost 'devhost:pci#2:1af4:1001': pid=3376 [00001.546] devcoordinator: launch devhost 'devhost:pci#3:8086:100e': pid=3460 [00001.559] devcoordinator: launch devhost 'devhost:pci#5:8086:2922': pid=3555 [00001.569] devhost: WARNING: driver '/boot/driver/pc-ps2.so' did not add device in bind() [00001.579] i8042 device 0xab 0x41 [00001.583] bochs_vbe: initialized display, 1024 x 768 (stride=1024 format=00020001) [00001.607] vc: new display device /dev/class/display-controller/000 [00001.614] GetSysmemConnection failed (continuing) - status: -2 [00001.621] vc: new input device /dev/class/input/001 [00001.647] vc: Successfully attached to display 1 [00001.660] ahci: using MSI interrupt [00001.681] fshost: Binding: /boot/driver/fvm.so [00001.682] DeviceControllerBind running: /boot/driver/fvm.so [00001.688] devcoordinator: rpc: bind-device 'block' [00001.689] DeviceControllerBind finished: /boot/driver/fvm.so ZX_OK [00001.705] fshost: BlockDevice::MountFilesystem(blobfs) [00001.714] devcoordinator: launch /boot/bin/blobfs (blobfs:/blob) OK [00001.752] i8042 device 0x00 [00001.756] 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.778] vc: new input device /dev/class/input/002 [00002.017] fshost: starting 'bin/pkgsvr' '5084d6162c8ca8ec07404614ec08c0d801835d5457b2d1d62135368ebf0a31c9'... [00002.065] devcoordinator: launch bin/pkgsvr (pkgfs) OK [00002.220] pkgsvr: system: will be served from 5084d6162c8ca8ec07404614ec08c0d801835d5457b2d1d62135368ebf0a31c9 [00002.220] pkgsvr: pkgfs serving index /data/pkgfs_index from blobfs /blob [00002.268] fshost: mounting minfs [00002.268] fshost: BlockDevice::MountFilesystem(minfs) [00002.280] devcoordinator: launch /boot/bin/minfs (minfs:/data) OK [00002.303] devcoordinator: adding system driver 'bt_hci_fake' '/system/driver/bt-hci-fake.so' [00002.311] minfs: filesystem in clean state. [00002.357] devcoordinator: launch /system/bin/appmgr (appmgr) OK [00002.365] devcoordinator: adding system driver 'bt_hog' '/system/driver/bt-hog.so' [00002.400] devcoordinator: adding system driver 'bt_hci_passthrough' '/system/driver/bt-hci-passthrough.so' [00002.465] devcoordinator: adding system driver 'bt_host' '/system/driver/bt-host.so' [00002.477] devcoordinator: driver 'bt_host' added [00002.477] devcoordinator: driver 'bt_hog' added [00002.477] devcoordinator: driver 'bt_hci_fake' added [00002.477] devcoordinator: driver 'bt_hci_passthrough' added [00002.477] devcoordinator: fallback driver 'usb_composite' is available [00002.477] devcoordinator: driver 'usb_composite' added [00002.477] devcoordinator: fallback driver 'intel_disp' is available [00002.477] devcoordinator: driver 'intel_disp' added [00002.884] netifc: ? /dev/class/ethernet/000 [00002.890] netifc: create 512 eth buffers [00002.891] macaddr: 52:54:00:63:5e:7b [00002.891] ip6addr: fe80::5054:4d:fe63:5e7b [00002.891] snmaddr: ff02::1:ff63:5e7b [00002.891] netsvc: using /dev/class/ethernet/000 [00002.891] netsvc: nodename='step-dime-yard-juicy' [00002.891] netsvc: start [00003.155] [00003.155568][1607557571][0][netstack] INFO: netstack.go(679): NIC lo added [sniff = false] [00003.157] [00003.157729][1607557571][0][netstack] WARNING: main.go(148): OnInterfacesChanged failed: ErrBadHandle: zx.Channel.Write [00004.813] [INFO:main.cc(35)] Trace Manager starting with config: /pkg/data/tracing.config [00004.869] [00004.869671][1607557571][0][netstack] WARNING: netstack.go(551): getNodeName: device settings error: key not set [00004.898] [00004.898978][1607557571][0][netstack] WARNING: netstack.go(551): getNodeName: device settings error: key not set [00004.975] [00004.975937][1607557571][0][netstack] INFO: netstack.go(679): NIC ethp0003 added [sniff = false] [00004.976] [00004.976151][1607557571][0][netstack] INFO: netstack.go(705): NIC ethp0003: link-local IPv6: fe80::5054:ff:fe63:5e7b [00004.992] [00004.992493][1607557571][0][netstack] INFO: netstack.go(466): NIC ethp0003: starting [00005.010] wlanstack [I]: Starting [00005.019] [00005.016822][1607557571][0][netstack] INFO: netstack.go(381): NIC ethp0003: DHCP acquired IP 192.168.3.9 for 24h0m0s [00005.020] [00005.020108][1607557571][0][netstack] INFO: netstack.go(382): NIC ethp0003: Adding DNS servers: [192.168.3.3] [00005.037] [00005.020133][1607557571][0][netstack] INFO: netstack.go(395): adding routes [{Destination:0.0.0.0 Mask:0.0.0.0 Gateway:192.168.3.2 NIC:2} {Destination::: Mask::: Gateway: NIC:2} {Destination:192.168.3.0 Mask:255.255.255.0 [00005.037] Gateway: NIC:2}] with metric=<not-set> dynamic=true [00005.295] [INFO:cobalt_main.cc(219)] Cobalt is starting with the following parameters: schedule_interval=3600 seconds, min_interval=10 seconds, initial_interval=60 seconds, max_bytes_per_observation_store=1048576, event_aggregator_bac [00005.295] kfill_days=2, start_event_aggregator_worker=1. [00005.397] [INFO:main.cc(20)] Exiting, perfmon device not supported [00005.577] [INFO:system_metrics_main.cc(23)] Cobalt SystemMetricsDaemon: System metrics daemon created. [00005.713] [INFO:display_watcher.cc(40)] Scenic: Acquired display controller /dev/class/display-controller/000.(000) [00005.713] GetSysmemConnection failed (continuing) - status: -2 [00005.722] [ERROR:garnet/lib/ui/gfx/gfx_system.cc(198)] No Vulkan on device, Graphics system exiting. [00005.723] [INFO:input_system.cc(203)] Scenic input system initialized. [00005.723] DdkClose [00005.753] [ERROR:garnet/bin/sysmgr/app.cc(185)] Singleton fuchsia-pkg://fuchsia.com/scenic#meta/scenic.cmx died [00005.782] [INFO:input_interpreter.cc(421)] Can't process HID report descriptor for 000; All parsing attempts failed. [00005.963] Starting mDNS on interface ethp0003 192.168.3.9 using port 5356 [00005.971] [WARNING:src/connectivity/network/mdns/service/mdns_interface_transceiver_v4.cc(68)] NET-2177 IP_TTL not supported (ENOPROTOOPT), continuing anyway. May cause spurious IP traffic [00005.984] Starting mDNS on interface ethp0003 ff02::1:ff63:5e7b using port 5356 [00005.990] [00005.990264][1607557571][0][netstack] INFO: socket_conv.go(523): unimplemented setsockopt: SOL_IPV6 name=19 optVal=00 [00005.990] [WARNING:src/connectivity/network/mdns/service/mdns_interface_transceiver_v6.cc(29)] NET-291 IPV6_MULTICAST_LOOP not supported (ENOPROTOOPT), continuing anyway [00005.991] [WARNING:src/connectivity/network/mdns/service/mdns_interface_transceiver_v6.cc(50)] NET-2180 IPV6_JOIN_GROUP returned ENODEV, mDNS will not communicate via IPV6 [00006.016] Starting mDNS on interface ethp0003 fe80::5054:ff:fe63:5e7b using port 5356 [00006.026] [00006.026569][1607557571][0][netstack] INFO: socket_conv.go(523): unimplemented setsockopt: SOL_IPV6 name=19 optVal=00 [00006.027] [INFO:display_watcher.cc(40)] Scenic: Acquired display controller /dev/class/display-controller/000.(000) [00006.040] [WARNING:src/connectivity/network/mdns/service/mdns_interface_transceiver_v6.cc(29)] NET-291 IPV6_MULTICAST_LOOP not supported (ENOPROTOOPT), continuing anyway [00006.040] GetSysmemConnection failed (continuing) - status: -2 [00006.040] [WARNING:src/connectivity/network/mdns/service/mdns_interface_transceiver_v6.cc(50)] NET-2180 IPV6_JOIN_GROUP retadding package web_engine_browsertests/0 2019/05/25 09:39:43 error removing source: Source not found 2019-05-25 02:39:45,045:INFO:root:Installing web_engine_browsertests version 0. 2019/05/25 09:39:47 Awaiting response... 2019/05/25 09:39:50 Awaiting response... 2019/05/25 09:39:53 Awaiting response... 2019/05/25 09:39:56 Awaiting response... 2019/05/25 09:39:59 Awaiting response... 2019/05/25 09:40:02 Awaiting response... 2019/05/25 09:40:05 Awaiting response... 2019/05/25 09:40:08 Awaiting response... 2019/05/25 09:40:11 Awaiting response... 2019/05/25 09:40:14 Awaiting response... 2019/05/25 09:40:17 Awaiting response... 2019/05/25 09:40:20 Awaiting response... 2019/05/25 09:40:23 Awaiting response... 2019-05-25 02:40:27,395:INFO:root:Running application. 2019-05-25 02:40:27,398:INFO:root:Running "/b/s/w/ir/third_party/fuchsia-sdk/sdk/tools/symbolize -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 -ids /b/s/w/ir/out/Release/gen/fuchsia/engine/web_engine_browsertests/ids.txt". urned ENODEV, mDNS will not communicate via IPV6 [00006.061] mDNS: Verifying uniqueness of host name step-dime-yard-juicy.local. [00006.072] [ERROR:garnet/lib/ui/gfx/gfx_system.cc(198)] No Vulkan on device, Graphics system exiting. [00006.072] [INFO:input_system.cc(203)] Scenic input system initialized. [00006.083] DdkClose [00006.085] [ERROR:garnet/bin/ui/root_presenter/app.cc(214)] Session died, destroying all presentations. [00006.102] [ERROR:garnet/bin/sysmgr/app.cc(185)] Singleton fuchsia-pkg://fuchsia.com/scenic#meta/scenic.cmx died [00006.940] mDNS: Using unique host name step-dime-yard-juicy.local. [00007.133] setsockopt IP_TOS 32: Protocol not available: [00007.327] [ERROR:garnet/bin/sshd-host/sshd-host.cc(194)] Failed to kill child job: -11 (ZX_ERR_BAD_HANDLE) [00050.791] pkgsvr: package activated web_engine_browsertests/0 (8bb30327e8b89276bee40637403adf78c8cedc6633c5a1f9209fd45d7896995a) 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. [00053.178] dlsvc: could not open 'libGLES_CM.so' [00053.182] dlsvc: could not open 'libGLES_CM.so' [1/36] IncognitoContextImplTest.NavigateFrame (2495 ms) [00055.179] dlsvc: could not open 'libGLES_CM.so' [00055.181] dlsvc: could not open 'libGLES_CM.so' [2/36] ContextImplTest.VerifyPersistentCookieStore (3101 ms) [3/36] FrameImplTest.NavigateFrame (1195 ms) [4/36] IncognitoContextImplTest.VerifyInMemoryCookieStore (1907 ms) [5/36] FrameImplTest.NavigateDataFrame (624 ms) [00057.073] dlsvc: msg write error: -24: ZX_ERR_PEER_CLOSED [6/36] FrameImplTest.FrameDeletedBeforeContext (700 ms) [7/36] FrameImplTest.ContextDeletedBeforeFrame (541 ms) [00057.793] dlsvc: could not open 'libGLES_CM.so' [00057.794] dlsvc: could not open 'libGLES_CM.so' [8/36] FrameImplTest.ContextDeletedBeforeFrameWithView (808 ms) [00058.045] dlsvc: could not open 'libGLES_CM.so' [00058.053] dlsvc: could not open 'libGLES_CM.so' [00059.454] dlsvc: could not open 'libGLES_CM.so' [00059.461] dlsvc: could not open 'libGLES_CM.so' [9/36] FrameImplTest.EnsureWebSqlDisabled (2471 ms) [00060.375] dlsvc: could not open 'libGLES_CM.so' [00060.383] dlsvc: could not open 'libGLES_CM.so' [10/36] FrameImplTest.GoBackAndForward (2728 ms) [11/36] FrameImplTest.NavigationEventDuringPendingLoad (2085 ms) [00062.313] dlsvc: could not open 'libGLES_CM.so' [00062.315] dlsvc: could not open 'libGLES_CM.so' [12/36] FrameImplTest.ReloadFrame (2499 ms) [00063.329] dlsvc: could not open 'libGLES_CM.so' [00063.337] dlsvc: could not open 'libGLES_CM.so' [13/36] FrameImplTest.NoNavigationObserverAttached (1911 ms) [00065.518] dlsvc: could not open 'libGLES_CM.so' [00065.532] dlsvc: could not open 'libGLES_CM.so' [14/36] FrameImplTest.GetVisibleEntry (3452 ms) [00066.979] dlsvc: could not open 'libGLES_CM.so' [00066.984] dlsvc: could not open 'libGLES_CM.so' [15/36] FrameImplTest.ExecuteJavaScriptOnLoad (2262 ms) [16/36] FrameImplTest.ExecuteJavaScriptUpdatedOnLoad (2827 ms) [17/36] FrameImplTest.ExecuteJavaScriptOnLoadOrdered (2376 ms) [00069.891] dlsvc: could not open 'libGLES_CM.so' [00069.905] dlsvc: could not open 'libGLES_CM.so' [00071.378] dlsvc: could not open 'libGLES_CM.so' [00071.379] dlsvc: could not open 'libGLES_CM.so' [18/36] FrameImplTest.ExecuteJavaScriptOnLoadRemoved (3534 ms) [19/36] FrameImplTest.ExecuteJavaScriptRemoveInvalidId (2856 ms) [00073.493] dlsvc: could not open 'libGLES_CM.so' [00073.494] dlsvc: could not open 'libGLES_CM.so' [00074.329] dlsvc: could not open 'libGLES_CM.so' [00074.341] dlsvc: could not open 'libGLES_CM.so' [20/36] FrameImplTest.ExecuteJavaScriptImmediate (2499 ms) [21/36] FrameImplTest.ExecuteJavaScriptOnLoadVmoDestroyed (3056 ms) [00076.356] dlsvc: could not open 'libGLES_CM.so' [00076.361] dlsvc: could not open 'libGLES_CM.so' [00077.631]2019-05-25 02:41:12,688:INFO:root:Process exited normally with status code 0. 2019-05-25 02:41:12,688:INFO:root:Terminating kernel log reader. 2019-05-25 02:41:12,892:INFO:root:Shutting down QEMU. dlsvc: could not open 'libGLES_CM.so' [22/36] FrameImplTest.ExecuteJavascriptOnLoadWrongOrigin (3206 ms) [00077.636] dlsvc: could not open 'libGLES_CM.so' [00079.382] dlsvc: could not open 'libGLES_CM.so' [00079.384] dlsvc: could not open 'libGLES_CM.so' [23/36] FrameImplTest.ExecuteJavaScriptOnLoadWildcardOrigin (3905 ms) [24/36] FrameImplTest.ExecuteMultipleJavaScriptsOnLoad (2857 ms) [00080.889] dlsvc: could not open 'libGLES_CM.so' [00080.890] dlsvc: could not open 'libGLES_CM.so' [00081.928] dlsvc: could not open 'libGLES_CM.so' [00081.929] dlsvc: could not open 'libGLES_CM.so' [25/36] FrameImplTest.ExecuteOnLoadEarlyAndLateRegistrations (3649 ms) [26/36] FrameImplTest.ExecuteJavaScriptBadEncoding (3005 ms) [00084.008] dlsvc: could not open 'libGLES_CM.so' [00084.012] dlsvc: could not open 'libGLES_CM.so' [00084.418] dlsvc: could not open 'libGLES_CM.so' [00084.423] dlsvc: could not open 'libGLES_CM.so' [27/36] FrameImplTest.DelayedNavigationEventAck (2610 ms) [28/36] FrameImplTest.NavigationObserverDisconnected (3006 ms) [29/36] FrameImplTest.Stop (1224 ms) [00088.088] dlsvc: could not open 'libGLES_CM.so' [00088.098] dlsvc: could not open 'libGLES_CM.so' [30/36] FrameImplTest.PostMessage (3152 ms) [00089.752] dlsvc: could not open 'libGLES_CM.so' [00089.759] dlsvc: could not open 'libGLES_CM.so' [31/36] FrameImplTest.PostMessagePassMessagePort (3067 ms) [00090.489] dlsvc: could not open 'libGLES_CM.so' [00090.494] dlsvc: could not open 'libGLES_CM.so' [00091.475] dlsvc: could not open 'libGLES_CM.so' [00091.477] dlsvc: could not open 'libGLES_CM.so' [32/36] FrameImplTest.PostMessageMessagePortDisconnected (2414 ms) [00093.026] dlsvc: could not open 'libGLES_CM.so' [00093.030] dlsvc: could not open 'libGLES_CM.so' [33/36] FrameImplTest.PostMessageUseContentProvidedPort (2890 ms) [00094.190] dlsvc: could not open 'libGLES_CM.so' [00094.204] dlsvc: could not open 'libGLES_CM.so' [34/36] FrameImplTest.PostMessageBadOriginDropped (3285 ms) [35/36] FrameImplTest.RecreateView (2491 ms) [00095.783] dlsvc: could not open 'libGLES_CM.so' [00095.788] dlsvc: could not open 'libGLES_CM.so' [36/36] RequestMonitoringFrameImplBrowserTest.ExtraHeaders (1469 ms) SUCCESS: all tests passed. 2019-05-25 02:41:56,949 - root: [DEBUG] Contents of task_output_dir: ['/b/swarming/w/ir/tmp/t/tmpDuGrGf/45072d7cc7a70010'] 2019-05-25 02:41:56,949 - root: [DEBUG] Found shard_json_files: ['/b/swarming/w/ir/tmp/t/tmpDuGrGf/45072d7cc7a70010/output.json'] 2019-05-25 02:41:56,949 - 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": 1558776638762028, "id": "8912497943191399472", "project": "chromium", "tags": ["build_address:luci.chromium.ci/Fuchsia x64/35872", "builder:Fuchsia x64", "buildset:commit/git/b0179d714f8129f2e95e447f35fc5c1031ffdb65", "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b0179d714f8129f2e95e447f35fc5c1031ffdb65", "gitiles_ref:refs/heads/master", "scheduler_invocation_id:9077928006292427632", "scheduler_job_id:chromium/Fuchsia x64", "user_agent:luci-scheduler"]}, "hostname": "cr-buildbucket.appspot.com"}, "buildername": "Fuchsia x64", "buildnumber": 35872, "got_angle_revision": "40757b28bdc8bd1ac7312dd975a33f96abe87429", "got_dawn_revision": "8aa036e9e498fb99756d507fc7a6b6f7ebce6767", "got_nacl_revision": "0ddc033406886a709b901e0c312872529f9705e8", "got_revision": "b0179d714f8129f2e95e447f35fc5c1031ffdb65", "got_revision_cp": "refs/heads/master@{#663392}", "got_swarming_client_revision": "779c4f0f8488c64587b75dbb001d18c3c0c4cda9", "got_v8_revision": "376299599eab0ec7932adb28c55209e8daf3b8d8", "got_v8_revision_cp": "refs/heads/7.6.274@{#1}", "got_webrtc_revision": "3a1b92772f661b6b52710fee27c5707bcfdda4a6", "got_webrtc_revision_cp": "refs/heads/master@{#28066}", "mastername": "chromium.linux", "path_config": "generic", "recipe": "chromium", "repository": "https://chromium.googlesource.com/chromium/src.git", "revision": "b0179d714f8129f2e95e447f35fc5c1031ffdb65"} --summary-json /b/swarming/w/ir/tmp/t/tmpfvppuc.json --task-output-dir /b/swarming/w/ir/tmp/t/tmpDuGrGf -o /b/swarming/w/ir/tmp/t/tmpvD7sex.json /b/swarming/w/ir/tmp/t/tmpDuGrGf/45072d7cc7a70010/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": 1558776638762028, "id": "8912497943191399472", "project": "chromium", "tags": ["build_address:luci.chromium.ci/Fuchsia x64/35872", "builder:Fuchsia x64", "buildset:commit/git/b0179d714f8129f2e95e447f35fc5c1031ffdb65", "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b0179d714f8129f2e95e447f35fc5c1031ffdb65", "gitiles_ref:refs/heads/master", "scheduler_invocation_id:9077928006292427632", "scheduler_job_id:chromium/Fuchsia x64", "user_agent:luci-scheduler"]}, "hostname": "cr-buildbucket.appspot.com"}, "buildername": "Fuchsia x64", "buildnumber": 35872, "got_angle_revision": "40757b28bdc8bd1ac7312dd975a33f96abe87429", "got_dawn_revision": "8aa036e9e498fb99756d507fc7a6b6f7ebce6767", "got_nacl_revision": "0ddc033406886a709b901e0c312872529f9705e8", "got_revision": "b0179d714f8129f2e95e447f35fc5c1031ffdb65", "got_revision_cp": "refs/heads/master@{#663392}", "got_swarming_client_revision": "779c4f0f8488c64587b75dbb001d18c3c0c4cda9", "got_v8_revision": "376299599eab0ec7932adb28c55209e8daf3b8d8", "got_v8_revision_cp": "refs/heads/7.6.274@{#1}", "got_webrtc_revision": "3a1b92772f661b6b52710fee27c5707bcfdda4a6", "got_webrtc_revision_cp": "refs/heads/master@{#28066}", "mastername": "chromium.linux", "path_config": "generic", "recipe": "chromium", "repository": "https://chromium.googlesource.com/chromium/src.git", "revision": "b0179d714f8129f2e95e447f35fc5c1031ffdb65"}', '--summary-json', '/b/swarming/w/ir/tmp/t/tmpfvppuc.json', '--task-output-dir', '/b/swarming/w/ir/tmp/t/tmpDuGrGf', '-o', '/b/swarming/w/ir/tmp/t/tmpvD7sex.json', '/b/swarming/w/ir/tmp/t/tmpDuGrGf/45072d7cc7a70010/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": 1558776638762028, "id": "8912497943191399472", "project": "chromium", "tags": ["build_address:luci.chromium.ci/Fuchsia x64/35872", "builder:Fuchsia x64", "buildset:commit/git/b0179d714f8129f2e95e447f35fc5c1031ffdb65", "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b0179d714f8129f2e95e447f35fc5c1031ffdb65", "gitiles_ref:refs/heads/master", "scheduler_invocation_id:9077928006292427632", "scheduler_job_id:chromium/Fuchsia x64", "user_agent:luci-scheduler"]}, "hostname": "cr-buildbucket.appspot.com"}, "buildername": "Fuchsia x64", "buildnumber": 35872, "got_angle_revision": "40757b28bdc8bd1ac7312dd975a33f96abe87429", "got_dawn_revision": "8aa036e9e498fb99756d507fc7a6b6f7ebce6767", "got_nacl_revision": "0ddc033406886a709b901e0c312872529f9705e8", "got_revision": "b0179d714f8129f2e95e447f35fc5c1031ffdb65", "got_revision_cp": "refs/heads/master@{#663392}", "got_swarming_client_revision": "779c4f0f8488c64587b75dbb001d18c3c0c4cda9", "got_v8_revision": "376299599eab0ec7932adb28c55209e8daf3b8d8", "got_v8_revision_cp": "refs/heads/7.6.274@{#1}", "got_webrtc_revision": "3a1b92772f661b6b52710fee27c5707bcfdda4a6", "got_webrtc_revision_cp": "refs/heads/master@{#28066}", "mastername": "chromium.linux", "path_config": "generic", "recipe": "chromium", "repository": "https://chromium.googlesource.com/chromium/src.git", "revision": "b0179d714f8129f2e95e447f35fc5c1031ffdb65"}', '--summary-json', '/b/swarming/w/ir/tmp/t/tmpfvppuc.json', '--task-output-dir', '/b/swarming/w/ir/tmp/t/tmpDuGrGf', '-o', '/b/swarming/w/ir/tmp/t/tmpvD7sex.json', '/b/swarming/w/ir/tmp/t/tmpDuGrGf/45072d7cc7a70010/output.json'] returned exit code 0