{
"43d3bb424cea5e10": {
"output": "TAP version 13\n1..2\n\nrunning 0 tests\n\ntest result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out\n\nok 1 //garnet/lib/rust/cm_json:cm_json\n\nrunning 115 tests\ntest compile::tests::test_compile_facets ... ok\ntest compile::tests::test_compile_compact ... ok\ntest compile::tests::test_compile_program ... ok\ntest format::tests::test_format_invalid_json_fails ... ok\ntest format::tests::test_format_json ... ok\ntest merge::tests::test_merge_invalid_json_fails ... ok\ntest compile::tests::test_compile_empty ... ok\ntest opts::tests::test_parse_extra_schema_arg_msg_with_sep ... ok\ntest opts::tests::test_parse_extra_schema_arg_schema_and_msg ... ok\ntest opts::tests::test_parse_extra_schema_arg_schema_only ... ok\ntest compile::tests::test_compile_expose ... ok\ntest compile::tests::test_invalid_json ... ok\ntest merge::tests::test_merge_json ... ok\ntest compile::tests::test_compile_children ... ok\ntest validate::tests::test_cm_children ... ok\ntest validate::tests::test_cm_children_bad_name ... ok\ntest validate::tests::test_cm_children_missing_props ... ok\ntest validate::tests::test_cm_exposes ... ok\ntest compile::tests::test_compile_use ... ok\ntest compile::tests::test_compile_all_sections ... ok\ntest validate::tests::test_cm_empty_json ... ok\ntest validate::tests::test_cm_exposes_missing_props ... ok\ntest compile::tests::test_compile_offer ... ok\ntest validate::tests::test_cm_exposes_source_bad_child_name ... ok\ntest validate::tests::test_cm_exposes_bad_type ... ok\ntest validate::tests::test_cm_exposes_source_bad_relation ... ok\ntest validate::tests::test_cm_exposes_source_extraneous_child ... ok\ntest validate::tests::test_cm_exposes_source_missing_child ... ok\ntest validate::tests::test_cm_facets ... ok\ntest validate::tests::test_cm_facets_wrong_type ... ok\ntest validate::tests::test_cm_exposes_source_missing_props ... ok\ntest validate::tests::test_cm_name ... ok\ntest validate::tests::test_cm_name_invalid ... ok\ntest validate::tests::test_cm_offers_bad_type ... ok\ntest validate::tests::test_cm_offers_missing_props ... ok\ntest validate::tests::test_cm_name_too_long ... ok\ntest validate::tests::test_cm_offers_source_bad_child_name ... ok\ntest validate::tests::test_cm_offers_source_extraneous_child ... ok\ntest validate::tests::test_cm_offers ... ok\ntest validate::tests::test_cm_offers_all_valid_chars ... ok\ntest validate::tests::test_cm_offers_source_bad_relation ... ok\ntest validate::tests::test_cm_offers_source_missing_child ... ok\ntest validate::tests::test_cm_offers_source_missing_props ... ok\ntest validate::tests::test_cm_offers_target_missing_props ... ok\ntest validate::tests::test_cm_offers_target_bad_child_name ... ok\ntest validate::tests::test_cm_path ... ok\ntest validate::tests::test_cm_path_invalid_empty ... ok\ntest validate::tests::test_cm_path_invalid_trailing ... ok\ntest validate::tests::test_cm_path_invalid_relative ... ok\ntest validate::tests::test_cm_path_invalid_root ... ok\ntest validate::tests::test_cm_path_too_long ... ok\ntest validate::tests::test_cm_program ... ok\ntest validate::tests::test_cm_uses_bad_type ... ok\ntest validate::tests::test_cm_uri_too_long ... ok\ntest validate::tests::test_cm_uses_missing_props ... ok\ntest validate::tests::test_cm_uses ... ok\ntest validate::tests::test_cm_uri_invalid ... ok\ntest validate::tests::test_cm_uri ... ok\ntest validate::tests::test_cml_child_name ... ok\ntest validate::tests::test_cml_child_name_too_long ... ok\ntest validate::tests::test_cml_children_bad_startup ... ok\ntest validate::tests::test_cml_child_name_invalid ... ok\ntest validate::tests::test_cml_children ... ok\ntest validate::tests::test_cml_children_missing_props ... ok\ntest validate::tests::test_cml_empty_json ... ok\ntest validate::tests::test_cml_expose ... ok\ntest validate::tests::test_cml_children_duplicate_names ... ok\ntest validate::tests::test_cml_expose_missing_from ... ok\ntest validate::tests::test_cml_expose_missing_props ... ok\ntest validate::tests::test_cml_expose_all_valid_chars ... ok\ntest validate::tests::test_cml_facets ... ok\ntest validate::tests::test_cml_expose_bad_from ... ok\ntest validate::tests::test_cml_facets_wrong_type ... ok\ntest validate::tests::test_cml_expose_duplicate_target_paths ... ok\ntest validate::tests::test_cml_offer_bad_from ... ok\ntest validate::tests::test_cml_offer_all_valid_chars ... ok\ntest validate::tests::test_cml_json5 ... ok\ntest validate::tests::test_cml_offer_missing_from ... ok\ntest validate::tests::test_cml_offer_empty_targets ... ok\ntest validate::tests::test_cml_offer_target_bad_to ... ok\ntest validate::tests::test_cml_offer_missing_props ... ok\ntest validate::tests::test_cml_offer_duplicate_target_paths ... ok\ntest validate::tests::test_cml_offer_target_missing_to ... ok\ntest validate::tests::test_cml_path ... ok\ntest validate::tests::test_cml_path_invalid_root ... ok\ntest validate::tests::test_cml_offer_target_missing_props ... ok\ntest validate::tests::test_cml_path_invalid_relative ... ok\ntest validate::tests::test_cml_offer ... ok\ntest validate::tests::test_cml_path_invalid_trailing ... ok\ntest validate::tests::test_cml_path_invalid_empty ... ok\ntest validate::tests::test_cml_program ... ok\ntest validate::tests::test_cml_program_no_binary ... ok\ntest validate::tests::test_cml_uri ... ok\ntest validate::tests::test_cmx_bad_program ... ok\ntest validate::tests::test_cml_relative_id_too_long ... ok\ntest validate::tests::test_cml_use ... ok\ntest validate::tests::test_cml_uri_invalid ... ok\ntest validate::tests::test_cmx_facets ... ok\ntest validate::tests::test_cmx_program ... ok\ntest validate::tests::test_cmx_err_empty_json ... ok\ntest validate::tests::test_json5_parse_number ... ok\ntest validate::tests::test_cmx_program_no_binary ... ok\ntest validate::tests::test_validate_extra_schemas_block_dev ... ok\ntest validate::tests::test_validate_extra_error ... ok\ntest validate::tests::test_cmx_sandbox ... ok\ntest validate::tests::test_cml_use_missing_props ... ok\ntest validate::tests::test_validate_invalid_json_fails ... ok\ntest validate::tests::test_validate_extra_schemas_empty_json ... ok\ntest validate::tests::test_validate_extra_schemas_feature_not_present ... ok\ntest validate::tests::test_validate_extra_schemas_empty_features ... ok\ntest validate::tests::test_validate_multiple_extra_schemas_invalid ... ok\ntest validate::tests::test_validate_extra_schemas_feature_present ... ok\ntest validate::tests::test_validate_multiple_extra_schemas_valid ... ok\ntest validate::tests::test_cml_path_too_long ... ok\ntest validate::tests::test_cml_uri_too_long ... ok\n\ntest result: ok. 115 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out\n\nok 2 //garnet/bin/cmc:cmc\n",
"outputs": [
"out.tar"
],
"results": {
"bot_dimensions": [
{
"key": "cores",
"value": [
"8"
]
},
{
"key": "cpu",
"value": [
"x64",
"x86",
"x86-64-Haswell_GCE",
"x86-64-avx2"
]
},
{
"key": "gce",
"value": [
"1"
]
},
{
"key": "gpu",
"value": [
"none"
]
},
{
"key": "id",
"value": [
"fuchsia-debian-1338871f-us-central1-b-73f0"
]
},
{
"key": "image",
"value": [
"fuchsia-debian-9-drawfork-v20190319-3f58170"
]
},
{
"key": "inside_docker",
"value": [
"0"
]
},
{
"key": "kvm",
"value": [
"1"
]
},
{
"key": "locale",
"value": [
"en_US.UTF-8"
]
},
{
"key": "machine_type",
"value": [
"n1-standard-8"
]
},
{
"key": "os",
"value": [
"Debian",
"Debian-9.5",
"Linux"
]
},
{
"key": "pool",
"value": [
"fuchsia.tests"
]
},
{
"key": "python",
"value": [
"2.7.13"
]
},
{
"key": "server_version",
"value": [
"4216-cf4a3cd"
]
},
{
"key": "zone",
"value": [
"us",
"us-central",
"us-central1",
"us-central1-b"
]
}
],
"bot_id": "fuchsia-debian-1338871f-us-central1-b-73f0",
"bot_version": "c7f0c187ee2160053ce935aa86119b526086da1e165dea02547e2f5a35dc9e3f",
"cipd_pins": {
"client_package": {
"package_name": "infra/tools/cipd/linux-amd64",
"version": "nFftZl2kFquaI2EUmPGwzbc5sIbCocl9L55UeASqlLsC"
},
"packages": [
{
"package_name": "fuchsia/tools/testrunner/linux-amd64",
"path": ".",
"version": "-1S6SRUIviVgClyJlP_Xg013kXyfYKAfmLZqruIfRFkC"
}
]
},
"completed_ts": "2019-03-26T16:51:22.017566",
"costs_usd": [
0.0009107676856845326
],
"created_ts": "2019-03-26T16:51:13.597900",
"duration": 4.261002063751221,
"modified_ts": "2019-03-26T16:51:22.017566",
"name": "Linux",
"outputs_ref": {
"isolated": "1ae7eee18e4a0ce45d4aebe8ea4f70ae19d1764a",
"isolatedserver": "
https://isolateserver.appspot.com",
"namespace": "default-gzip"
},
"run_id": "43d3bb424cea5e11",
"server_versions": [
"4216-cf4a3cd"
],
"started_ts": "2019-03-26T16:51:14.012084",
"state": "COMPLETED",
"tags": [
"cpu:x64",
"os:Linux",
"pool:fuchsia.tests",
"priority:200",
"service_account:none",
"swarming.pool.template:none",
"swarming.pool.version:b6c541c43c2d995a2d695b6e44ad9c4f540ac415",
"user:None"
],
"task_id": "43d3bb424cea5e10",
"try_number": "1"
}
},
"43d3bb426a717b10": {
"output": "2019/03/26 16:51:34 QEMU invocation:\n[/b/s/w/ir/qemu/bin/qemu-system-x86_64 -machine q35 -device isa-debug-exit,iobase=0xf4,iosize=0x04 -cpu host -enable-kvm -m 4096 -smp 4 -nographic -serial stdio -monitor none -kernel /b/s/w/ir/multiboot-created_by_recipe.bin -initrd /b/s/w/ir/fuchsia-QEMU.zbi -drive id=maindisk,file=/b/s/w/ir/obj/build/images/fvm.blk,format=raw,if=none -device virtio-blk-pci,drive=maindisk -drive id=testdisk,file=/b/s/w/ir/output.fs,format=raw,if=none -device virtio-blk-pci,drive=testdisk,addr=06.0 -append zircon.autorun.system=/boot/bin/sh+/boot/infra/runcmds kernel.halt-on-panic=true devmgr.suspend-timeout-debug=true TERM=dumb kernel.serial=legacy]\n[00000.000] 00000.00000> zbi: @ 0xffffff8000eeb000 (14581760 bytes)\r\n[00000.000] 00000.00000> UART: FIFO depth 16\r\n[00000.000] 00000.00000> PMM: boot reserve add [0x100000, 0x2f7fff]\r\n[00000.000] 00000.00000> PMM: boot reserve add [0xeeb000, 0x1cd2fff]\r\n[00000.000] 00000.00000> PMM: boot reserve marking WIRED [0x100000, 0x2f7fff]\r\n[00000.000] 00000.00000> PMM: boot reserve marking WIRED [0xeeb000, 0x1cd2fff]\r\n[00000.000] 00000.00000> \r\n[00000.000] 00000.00000> welcome to Zircon\r\n[00000.000] 00000.00000> \r\n[00000.000] 00000.00000> KASLR: .text section at 0xffffffff00100000\r\n[00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff0010f204 (global_prng_seed) at level 0x30000, flags 0x1\r\n[00000.000] 00000.00000> initializing vm pre-heap\r\n[00000.000] 00000.00000> VM: marking boot alloc used range [0x1cd3000, 0x1cd3080)\r\n[00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff0011c4a8 (elf_build_id) at level 0x4fffe, flags 0x1\r\n[00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff0011c634 (version) at level 0x4ffff, flags 0x1\r\n[00000.000] 00000.00000> version:\r\n[00000.000] 00000.00000> \tarch: x86\r\n[00000.000] 00000.00000> \tplatform: XXX\r\n[00000.000] 00000.00000> \ttarget: XXX\r\n[00000.000] 00000.00000> \tproject: x64\r\n[00000.000] 00000.00000> \tbuildid: git-2ea98fb15d39b1fcf1e2c228918cea7b05c4938c-dirty\r\n[00000.000] 00000.00000> \tELF build ID: e37aa3e63b39d4525d87d4563bf29467a74fcdbf\r\n[00000.000] 00000.00000> initializing heap\r\n[00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00101f78 (console) at level 0x50000, flags 0x1\r\n[00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00175974 (x86_resource_init) at level 0x50000, flags 0x1\r\n[00000.000] 00000.00000> initializing vm\r\n[00000.000] 00000.00000> VM: reserving kernel region [0xffffffff00100000, 0xffffffff00205000) flags 0x28 name 'kernel_code'\r\n[00000.000] 00000.00000> VM: reserving kernel region [0xffffffff00205000, 0xffffffff00254000) flags 0x8 name 'kernel_rodata'\r\n[00000.000] 00000.00000> VM: reserving kernel region [0xffffffff00254000, 0xffffffff0026e000) flags 0x18 name 'kernel_data'\r\n[00000.000] 00000.00000> VM: reserving kernel region [0xffffffff0026e000, 0xffffffff002f8000) flags 0x18 name 'kernel_bss'\r\n[00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00176638 (display_memtype) at level 0x60001, flags 0x1\r\n[00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001796a0 (acpi_tables) at level 0x60001, flags 0x1\r\n[00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00174798 (hpet) at level 0x60002, flags 0x1\r\n[00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00174b58 (apic) at level 0x60002, flags 0x1\r\n[00000.000] 00000.00000> x2APIC enabled\r\n[00000.000] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00178c90 (timer) at level 0x60003, flags 0x1\r\n[00000.000] 00000.00000> pvclock: Clocksource is stable\r\n[00000.000] 00000.00000> HPET frequency: 100000 ticks/ms\r\n[00000.000] 00000.00000> pvclock: Fetching TSC frequency\r\n[00000.000] 00000.00000> TSC frequency: 2299808 ticks/ms\r\n[00000.515] 00000.00000> timer features: constant_tsc 1 invariant_tsc 1 tsc_deadline 1\r\n[00000.515] 00000.00000> Using TSC as wallclock\r\n[00000.515] 00000.00000> initializing kernel\r\n[00000.515] 00000.00000> initializing mp\r\n[00000.515] 00000.00000> initializing timers\r\n[00000.515] 00000.00000> INIT: cpu 0, calling hook 0xffffffff0010f458 (global_prng_thread_safe) at level 0x7ffff, flags 0x1\r\n[00000.515] 00000.00000> INIT: cpu 0, calling hook 0xffffffff0011b760 (debuglog) at level 0x7ffff, flags 0x1\r\n[00000.515] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001ea134 (thread_set_priority_experiment) at level 0x7ffff, flags 0x1\r\n[00000.515] 00000.00000> thread set priority experiment is : ENABLED\r\n[00000.515] 00000.00000> creating bootstrap completion thread\r\n[00000.680] 00000.00000> top of bootstrap2()\r\n[00000.680] 00000.00000> INIT: cpu 0, calling hook 0xffffffff0012936c (libobject) at level 0x80000, flags 0x1\r\n[00000.680] 00000.00000> OOM: started thread\r\n[00000.689] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001a1b80 (dpc) at level 0x80000, flags 0x1\r\n[00000.694] 00000.00000> Processor Model Info: type 0 family 0x6 model 0xf stepping 0\r\n[00000.694] 00000.00000> \tdisplay_family 0x6 display_model 0x3f\r\n[00000.694] 00000.00000> Vendor: Intel\r\n[00000.694] 00000.00000> Microarch: Haswell\r\n[00000.694] 00000.00000> F/M/S: 6/3f/0\r\n[00000.694] 00000.00000> Brand: Intel(R) Xeon(R) CPU @ 2.30GHz\r\n[00000.694] 00000.00000> Features: fpu sse sse2 sse3 ssse3 sse4.1 sse4.2 mmx avx avx2 fxsr pcid xsave aesni clflush \r\n[00000.694] 00000.00000> fsgsbase tsc_adj smep erms rdrand syscall nx rdtscp tsc_deadline x2apic hypervisor \r\n[00000.694] 00000.00000> initializing platform\r\n[00000.694] 00000.00000> INIT: cpu 0, calling hook 0xffffffff001921b8 (x86_perfmon) at level 0x90000, flags 0x1\r\n[00000.694] 00000.00000> x86_perfmon_init_once:497: perfmon: unexpected programmable counter width 0 in cpuid.0AH\r\n[00000.694] 00000.00000> UART: started IRQ driven RX\r\n[00000.694] 00000.00000> UART: started IRQ driven TX\r\n[00000.695] 00000.00000> cpu topology:\r\n[00000.695] 00000.00000> \t0: apic id 0x0 package 0 node 0 core 0 smt 0 BSP\r\n[00000.695] 00000.00000> \t1: apic id 0x1 package 1 node 0 core 0 smt 0\r\n[00000.695] 00000.00000> \t2: apic id 0x2 package 2 node 0 core 0 smt 0\r\n[00000.695] 00000.00000> \t3: apic id 0x3 package 3 node 0 core 0 smt 0\r\n[00000.695] 00000.00000> Found 4 cpus\r\n[00000.695] 00000.00000> booting apic ids: 0x1 0x2 0x3 \r\n[00000.764] 00000.00000> entering scheduler on cpu 2\r\n[00000.764] 00000.00000> entering scheduler on cpu 1\r\n[00000.764] 00000.00000> entering scheduler on cpu 3\r\n[00000.775] 00000.00000> smbios: manufacturer=\"QEMU\" product=\"Standard PC (Q35 + ICH9, 2009)\"\r\n[00000.775] 00000.00000> initializing target\r\n[00000.775] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00177f40 (x86_pcie_init) at level 0xa0000, flags 0x1\r\n[00000.775] 00000.00000> moving to last init level\r\n[00000.775] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00102a48 (kernel_shell) at level 0xc0000, flags 0x1\r\n[00000.775] 00000.00000> INIT: cpu 0, calling hook 0xffffffff00200330 (ktrace) at level 0xc0000, flags 0x1\r\n[00000.823] 00000.00000> ktrace: buffer at 0xffffff9a329e2000 (33554432 bytes)\r\n[00000.823] 00000.00000> INIT: cpu 0, calling hook 0xffffffff0020240c (userboot) at level 0xc0000, flags 0x1\r\n[00000.823] 00000.00000> userboot: ramdisk 0xde8000 @ 0xffffff8000eeb000\r\n[00000.839] 00000.00000> userboot: userboot rodata 0 @ [0x5b1a97ad2000,0x5b1a97ad5000)\r\n[00000.839] 00000.00000> userboot: userboot code 0x3000 @ [0x5b1a97ad5000,0x5b1a97ae0000)\r\n[00000.839] 00000.00000> userboot: vdso/full rodata 0 @ [0x5b1a97ae0000,0x5b1a97ae7000)\r\n[00000.839] 00000.00000> userboot: vdso/full code 0x7000 @ [0x5b1a97ae7000,0x5b1a97ae8000)\r\n[00000.839] 00000.00000> userboot: entry point @ 0x5b1a97ad5c90\r\n[00000.839] 01034.01041> userboot: option \"kernel.serial=legacy\"\r\n[00000.839] 01034.01041> userboot: option \"netsvc.interface=/dev/sys/pci/00:1f.6/e1000/ethernet\"\r\n[00000.839] 01034.01041> userboot: option \"/b/s/w/ir/multiboot-created_by_recipe.bin=\"\r\n[00000.839] 01034.01041> userboot: option \"zircon.autorun.system=/boot/bin/sh+/boot/infra/runcmds\"\r\n[00000.839] 01034.01041> userboot: option \"kernel.halt-on-panic=true\"\r\n[00000.839] 01034.01041> userboot: option \"devmgr.suspend-timeout-debug=true\"\r\n[00000.839] 01034.01041> userboot: option \"TERM=dumb\"\r\n[00000.839] 01034.01041> userboot: option \"kernel.serial=legacy\"\r\n[00000.839] 01034.01041> userboot: option \"multiboot.boot_loader_name=qemu\"\r\n[00000.882] 01034.01041> userboot: searching bootfs for 'bin/bootsvc'\r\n[00000.882] 01034.01041> userboot: 'bin/bootsvc' has PT_INTERP \"lib/ld.so.1\"\r\n[00000.882] 01034.01041> userboot: searching bootfs for 'lib/ld.so.1'\r\n[00000.882] 01034.01041> userboot: userboot: loaded lib/ld.so.1 at 0x6d6ed8079000, entry point 0x6d6ed80b62f1\r\n[00000.882] 01034.01041> userboot: userboot: loaded vDSO at 0x4eddda723000, entry point 0x0\r\n[00000.882] 01034.01041> userboot: process bin/bootsvc started.\r\n[00000.882] 01034.01041> userboot: waiting for loader-service requests...\r\n[00000.932] 01034.01041> userboot: searching bootfs for 'lib/libasync-default.so'\r\n[00000.932] 01034.01041> userboot: searching bootfs for 'lib/libfdio.so'\r\n[00000.933] 01034.01041> userboot: searching bootfs for 'lib/libtrace-engine.so'\r\n[00000.933] 01034.01041> userboot: searching bootfs for 'lib/liblaunchpad.so'\r\n[00000.950] 01048.01051> {{{reset}}}\r\n[00000.950] 01048.01051> {{{module:0:<application>:elf:e417f56aca80eacfd6b0c3075f8dd5d0578e394f}}}\r\n[00000.950] 01048.01051> {{{mmap:0x2cc3c8142000:0x39000:load:0:rx:0}}}\r\n[00000.950] 01048.01051> {{{mmap:0x2cc3c817c000:0x4000:load:0:rw:0x3a000}}}\r\n[00000.950] 01048.01051> dso: id=e417f56aca80eacfd6b0c3075f8dd5d0578e394f base=0x00002cc3c8142000 name=<application>\r\n[00000.950] 01048.01051> {{{module:0x2:<vDSO>:elf:2cf766e7d76eeaa9b6bf218554516232fd691c16}}}\r\n[00000.950] 01048.01051> {{{mmap:0x4eddda723000:0x7000:load:0x2:r:0}}}\r\n[00000.950] 01048.01051> {{{mmap:0x4eddda72a000:0x1000:load:0x2:rx:0x7000}}}\r\n[00000.950] 01048.01051> dso: id=2cf766e7d76eeaa9b6bf218554516232fd691c16 base=0x00004eddda723000 name=<vDSO>\r\n[00000.950] 01048.01051> {{{module:0x3:libasync-default.so:elf:e4b1ee4bc32fd7c559763e81d285997a5c9310cf}}}\r\n[00000.950] 01048.01051> {{{mmap:0x7190e48f6000:0x1000:load:0x3:rx:0}}}\r\n[00000.950] 01048.01051> {{{mmap:0x7190e48f7000:0x1000:load:0x3:rw:0x1000}}}\r\n[00000.950] 01048.01051> dso: id=e4b1ee4bc32fd7c559763e81d285997a5c9310cf base=0x00007190e48f6000 name=libasync-default.so\r\n[00000.950] 01048.01051> {{{module:0x4:libfdio.so:elf:1a41e839db940d441ccd43199fd0daf1184e0ca2}}}\r\n[00000.950] 01048.01051> {{{mmap:0x567e01055000:0x32000:load:0x4:rx:0}}}\r\n[00000.950] 01048.01051> {{{mmap:0x567e01088000:0x4000:load:0x4:rw:0x33000}}}\r\n[00000.950] 01048.01051> dso: id=1a41e839db940d441ccd43199fd0daf1184e0ca2 base=0x0000567e01055000 name=libfdio.so\r\n[00000.950] 01048.01051> {{{module:0x5:libtrace-engine.so:elf:c5691bed1dd95d64da5f2541424cc2cafc3cebf0}}}\r\n[00000.950] 01048.01051> {{{mmap:0x31aae1be0000:0x9000:load:0x5:rx:0}}}\r\n[00000.950] 01048.01051> {{{mmap:0x31aae1be9000:0x2000:load:0x5:rw:0x9000}}}\r\n[00000.950] 01048.01051> dso: id=c5691bed1dd95d64da5f2541424cc2cafc3cebf0 base=0x000031aae1be0000 name=libtrace-engine.so\r\n[00000.950] 01048.01051> {{{module:0x6:liblaunchpad.so:elf:8d28ee7cfc81d75744c78ff0bcf70eef63a686fd}}}\r\n[00000.950] 01048.01051> {{{mmap:0x6fdc6bcee000:0x7000:load:0x6:rx:0}}}\r\n[00000.950] 01048.01051> {{{mmap:0x6fdc6bcf5000:0x2000:load:0x6:rw:0x7000}}}\r\n[00000.950] 01048.01051> dso: id=8d28ee7cfc81d75744c78ff0bcf70eef63a686fd base=0x00006fdc6bcee000 name=liblaunchpad.so\r\n[00000.950] 01048.01051> {{{module:0x1:libc.so:elf:b8bb4fd92a46ce3b287644c9a8273a4bcf224779}}}\r\n[00000.950] 01048.01051> {{{mmap:0x6d6ed8079000:0xcb000:load:0x1:rx:0}}}\r\n[00000.950] 01048.01051> {{{mmap:0x6d6ed8145000:0x6000:load:0x1:rw:0xcc000}}}\r\n[00000.950] 01048.01051> dso: id=b8bb4fd92a46ce3b287644c9a8273a4bcf224779 base=0x00006d6ed8079000 name=libc.so\r\n[00000.951] 01048.01051> bootsvc: Starting...\r\n[00000.951] 01048.01051> bootsvc: Creating bootfs service...\r\n[00000.951] 01034.01041> userboot: loader-service channel peer closed\r\n[00000.951] 01034.01041> userboot: finished!\r\n[00001.101] 01048.01051> bootsvc: Retrieving boot image...\r\n[00001.102] 01048.01051> bootsvc: Loading boot arguments...\r\n[00001.102] 01048.01051> bootsvc:: Creating svcfs service...\r\n[00001.102] 01048.01051> bootsvc: Loading kernel VMOs...\r\n[00001.102] 01048.01051> bootsvc: Creating loader service...\r\n[00001.102] 01048.01051> bootsvc: Launching next process...\r\n[00001.122] 01048.01096> bootsvc: launched bin/devcoordinator\r\n[00001.125] 01104.01117> devcoordinator: init\r\n[00001.126] 01104.01117> devcoordinator: launch /boot/bin/svchost (svchost) OK\r\n[00001.131] 01104.01117> devcoordinator: launch /boot/bin/fshost (fshost) OK\r\n[00001.138] 01266.01275> fshost: started.\r\n[00001.151] 01104.01297> devcoordinator: launch /boot/bin/netsvc (netsvc) OK\r\n[00001.168] 01104.01284> devcoordinator: launch /boot/bin/pwrbtn-monitor (pwrbtn-monitor) OK\r\n[00001.191] 01488.01508> netsvc: looking for interface /dev/sys/pci/00:1f.6/e1000/ethernet\r\n[00001.191] 01104.01297> devcoordinator: launch /boot/bin/virtual-console (virtual-console) OK\r\n[00001.226] 01104.01117> devcoordinator: full system required, ignoring fallback drivers until /system is loaded\r\n[00001.228] 01104.01117> devcoordinator: launch devhost 'devhost:sys': pid=2114\r\n[00001.232] 01104.01117> devcoordinator: launch devhost 'devhost:test': pid=2157\r\n[00001.241] 01104.01117> devcoordinator: launch devhost 'devhost:root': pid=2213\r\n[00001.244] 02114.02128> devhost: trace provider registry begun\r\n[00001.256] 02157.02182> devhost: trace provider registry begun\r\n[00001.257] 01104.01117> devcoordinator: launch devhost 'devhost:misc': pid=2286\r\n[00001.273] 02213.02240> devhost: trace provider registry begun\r\n[00001.283] 02286.02328> devhost: trace provider registry begun\r\n[00001.291] 01104.01292> devcoordinator: launch /boot/bin/sh (sh:console) OK\r\n$ [00001.301] 02114.02128> acpi-bus: not using IOMMU\r\n[00001.305] 02114.02128> GetHwProperties: No PM support\r\n[00001.305] 02114.02128> IntelPT: No PT support\r\n[00001.305] 02114.02128> devhost[sys/cpu-trace] bind driver '/boot/driver/cpu-trace.so' failed: -2\r\n[00001.305] 01104.01117> devcoordinator: rpc: bind-driver 'cpu-trace' status -2\r\n[00001.321] 02114.02128> [sysmem_init 28] async_get_default_dispatcher(): 0x44e7073bc0a0\r\n[00001.321] 02114.02128> [sysmem_bind 40] sysmem_bind()\r\n[00001.330] 02114.02701> acpi-pwrbtn: initialized\r\n[00001.334] 01592.01636> vc: new input device /dev/class/input/000\r\n[00001.421] 02114.02701> acpi: published device pci(0x4496d2653180), parent=sys(0x44e7073cb0e0), handle=0x44e7073c8300\r\n[00001.423] 02114.02701> acpi: failed to get PCI base bus number for device 'PCI0' (status 4294967295)\r\n[00001.425] 02114.02701> acpi: published device rtc(0x4496d2653200), parent=acpi(0x44e7073cb380), handle=0x44e7073c87b0\r\n[00001.427] 02114.02701> acpi: published device i8042(0x4496d2653280), parent=acpi(0x44e7073cb380), handle=0x44e7073c8840\r\n[00001.451] 01104.01117> devcoordinator: launch devhost 'devhost:pci#1:1234:1111': pid=2797\r\n[00001.465] 01104.01117> devcoordinator: launch devhost 'devhost:pci#2:8086:100e': pid=2857\r\n[00001.476] 02797.02811> devhost: trace provider registry begun\r\n[00001.480] 01104.01117> devcoordinator: launch devhost 'devhost:pci#3:1af4:1001': pid=2919\r\n[00001.488] 02857.02885> devhost: trace provider registry begun\r\n[00001.491] 02919.02961> devhost: trace provider registry begun\r\n[00001.491] 01104.01117> devcoordinator: launch devhost 'devhost:pci#4:1af4:1001': pid=3045\r\n[00001.495] 03045.03084> devhost: trace provider registry begun\r\n[00001.506] 01104.01117> devcoordinator: launch devhost 'devhost:pci#6:8086:2922': pid=3174\r\n[00001.516] 02114.02128> devhost: WARNING: driver '/boot/driver/pc-ps2.so' did not add device in bind()\r\n[00001.516] 03174.03188> devhost: trace provider registry begun\r\n[00001.523] 03174.03188> ahci: using MSI interrupt\r\n[00001.534] 02797.02811> bochs_vbe: initialized display, 1024 x 768 (stride=1024 format=00020001)\r\n[00001.538] 01592.01636> vc: new display device /dev/class/display-controller/000/virtcon\r\n[00001.540] 01592.01636> vc: Successfully attached to display 1\r\n[00001.552] 02114.03243> i8042 device 0xab 0x41\r\n[00001.577] 01592.01636> vc: new input device /dev/class/input/001\r\n[00001.588] 01266.01275> fshost: /dev/class/block/001: FVM?\r\n[00001.611] 01266.01275> devcoordinator: launch /boot/bin/blobfs (blobfs:/blob) OK\r\n[00001.700] 02114.03243> i8042 device 0x00\r\n[00001.708] 02114.02128> hid: boot mouse hack for \"hid-device-002\": report count (0->1), inp sz (24->24), out sz (0->0), feat sz (0->0)\r\n[00001.735] 01592.01636> vc: new input device /dev/class/input/002\r\n[00001.802] 01266.01275> fshost: starting 'bin/pkgsvr' 'f3ee4a449b9e0f507c992a46d1ed3dade82c15bb49efb54f5f04395c201e0d51'...\r\n[00001.819] 01266.01275> devcoordinator: launch bin/pkgsvr (pkgfs) OK\r\n[00001.853] 03760.03964> pkgsvr: system: will be served from f3ee4a449b9e0f507c992a46d1ed3dade82c15bb49efb54f5f04395c201e0d51\r\n[00001.853] 03760.03964> pkgsvr: pkgfs serving index /data/pkgfs_index from blobfs /blob\r\n[00001.872] 01266.01275> fshost: mounting minfs\r\n[00001.897] 01266.01275> devcoordinator: launch /boot/bin/minfs (minfs:/data) OK\r\n[00001.982] 01104.04008> devcoordinator: adding system driver 'ralink' '/system/driver/ralink.so'\r\n[00001.991] 01104.01652> devcoordinator: launch /system/bin/appmgr (appmgr) OK\r\n[00001.991] 01104.01652> autorun: starting '/boot/bin/sh' '/boot/infra/runcmds'...\r\n[00002.018] 01104.04008> devcoordinator: adding system driver 'ath10k_pci' '/system/driver/ath10k.so'\r\n[00002.060] 01104.04008> devcoordinator: adding system driver 'bt_host' '/system/driver/bt-host.so'\r\n[00002.085] 01104.01652> devcoordinator: launch /boot/bin/sh (autorun:system) OK\r\n[00002.095] 01104.04008> devcoordinator: adding system driver 'qmi_fake' '/system/driver/qmi-fake.so'\r\n[00002.140] 01104.04008> devcoordinator: adding system driver 'wlan' '/system/driver/wlanphy.so'\r\n[00002.215] 01104.04008> devcoordinator: adding system driver 'wlan' '/system/driver/wlan.so'\r\n[00002.239] 01104.04008> devcoordinator: adding system driver 'qmi_usb' '/system/driver/qmi-transport.so'\r\n[00002.263] 01104.04008> devcoordinator: adding system driver 'usb_video' '/system/driver/usb_video.so'\r\n[00002.283] 01104.04008> devcoordinator: adding system driver 'bt_hog' '/system/driver/bt-hog.so'\r\n[00002.302] 01104.04008> devcoordinator: adding system driver 'brcmfmac' '/system/driver/brcmfmac.so'\r\n[00002.322] 01104.04008> devcoordinator: adding system driver 'virtual_audio' '/system/driver/virtual_audio_driver.so'\r\n[00002.376] 01104.04008> devcoordinator: adding system driver 'bt_hci_atheros' '/system/driver/bt-hci-atheros.so'\r\n[00002.395] 01104.04008> devcoordinator: adding system driver 'wlantapctl' '/system/driver/wlantap.so'\r\n[00002.424] 01104.04008> devcoordinator: adding system driver 'bt_hci_fake' '/system/driver/bt-hci-fake.so'\r\n[00002.463] 01104.04008> devcoordinator: adding system driver 'bt_hci_intel' '/system/driver/bt-hci-intel.so'\r\n[00002.507] 01104.04008> devcoordinator: adding system driver 'rtl88xx' '/system/driver/rtl88xx.so'\r\n[00002.516] 04195.04210> [INFO:namespace_builder.cc(93)] config-data for sysmgr\r\n[00002.527] 01104.04008> devcoordinator: adding system driver 'bt_hci_passthrough' '/system/driver/bt-hci-passthrough.so'\r\n[00002.557] 01104.04008> devcoordinator: adding system driver 'gpu' '/system/driver/libmsd_intel.so'\r\n[00002.578] 01104.04008> devcoordinator: adding system driver 'wlan' '/system/driver/wlanif.so'\r\n[00002.588] 01104.01117> devcoordinator: driver 'wlan' added\r\n[00002.588] 01104.01117> devcoordinator: driver 'gpu' added\r\n[00002.588] 01104.01117> devcoordinator: driver 'rtl88xx' added\r\n[00002.588] 01104.01117> devcoordinator: driver 'bt_hci_intel' added\r\n[00002.588] 01104.01117> devcoordinator: driver 'bt_hci_fake' added\r\n[00002.588] 01104.01117> devcoordinator: driver 'bt_hci_atheros' added\r\n[00002.588] 01104.01117> devcoordinator: driver 'brcmfmac' added\r\n[00002.588] 01104.01117> devcoordinator: driver 'bt_hog' added\r\n[00002.588] 01104.01117> devcoordinator: driver 'usb_video' added\r\n[00002.588] 01104.01117> devcoordinator: driver 'qmi_usb' added\r\n[00002.588] 01104.01117> devcoordinator: driver 'wlan' added\r\n[00002.588] 01104.01117> devcoordinator: driver 'wlan' added\r\n[00002.588] 01104.01117> devcoordinator: driver 'qmi_fake' added\r\n[00002.588] 01104.01117> devcoordinator: driver 'bt_host' added\r\n[00002.588] 01104.01117> devcoordinator: driver 'ath10k_pci' added\r\n[00002.588] 01104.01117> devcoordinator: driver 'ralink' added\r\n[00002.588] 01104.01117> devcoordinator: driver 'bt_hci_passthrough' added\r\n[00002.588] 01104.01117> devcoordinator: fallback driver 'usb_composite' is available\r\n[00002.588] 01104.01117> devcoordinator: driver 'usb_composite' added\r\n[00002.588] 01104.01117> devcoordinator: fallback driver 'intel_disp' is available\r\n[00002.588] 01104.01117> devcoordinator: driver 'intel_disp' added\r\n[00003.167] 01488.01508> netifc: ? /dev/class/ethernet/000\r\n[00004.826] 06770.06788> [INFO:cobalt_main.cc(145)] Cobalt client schedule params: schedule_interval=3600 seconds, min_interval=10 seconds, initial_interval=60 seconds.\r\n[00005.140] 04195.04210> [INFO:namespace_builder.cc(93)] config-data for wlancfg\r\n[00005.896] 04195.04210> [INFO:namespace_builder.cc(93)] config-data for crashpad_agent\r\n[00005.942] 10250.10267> \r\n[00005.942] 10250.10267> ------------------------------------------------\r\n[00005.942] 10250.10267> RUNNING TEST: /pkgfs/packages/ril-qmi-tests/0/test/ril-qmi-tests\r\n[00005.942] 10250.10267> \r\n[00006.054] 10250.10267> \r\n[00006.054] 10250.10267> running 2 tests\r\n[00006.066] 10250.10267> test client::tests::no_client ... ok\r\n[00006.074] 10250.10267> test client::tests::request_id ... ok\r\n[00006.078] 10250.10267> \r\n[00006.080] 10250.10267> test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out\r\n[00006.080] 10250.10267> \r\n[00006.089] 10250.10267> PASSED: /pkgfs/packages/ril-qmi-tests/0/test/ril-qmi-tests passed\r\n[00006.149] 10250.10267> \r\n[00006.149] 10250.10267> SUMMARY: Ran 1 tests: 0 failed (0.212 sec)\r\n[00006.307] 10760.10802> [INFO:main.cc(35)] Trace Manager starting with config: /pkg/data/tracing.config\r\n[00006.460] 11541.11554> wlanstack2 [I]: Starting\r\n[00006.647] 10069.10086> minfs: Unmounted\r\n",
"outputs": [
"output.fs"
],
"results": {
"bot_dimensions": [
{
"key": "cores",
"value": [
"8"
]
},
{
"key": "cpu",
"value": [
"x64",
"x86",
"x86-64-Haswell_GCE",
"x86-64-avx2"
]
},
{
"key": "gce",
"value": [
"1"
]
},
{
"key": "gpu",
"value": [
"none"
]
},
{
"key": "id",
"value": [
"fuchsia-debian-1338871f-us-central1-b-bzqh"
]
},
{
"key": "image",
"value": [
"fuchsia-debian-9-drawfork-v20190319-3f58170"
]
},
{
"key": "inside_docker",
"value": [
"0"
]
},
{
"key": "kvm",
"value": [
"1"
]
},
{
"key": "locale",
"value": [
"en_US.UTF-8"
]
},
{
"key": "machine_type",
"value": [
"n1-standard-8"
]
},
{
"key": "os",
"value": [
"Debian",
"Debian-9.5",
"Linux"
]
},
{
"key": "pool",
"value": [
"fuchsia.tests"
]
},
{
"key": "python",
"value": [
"2.7.13"
]
},
{
"key": "server_version",
"value": [
"4216-cf4a3cd"
]
},
{
"key": "zone",
"value": [
"us",
"us-central",
"us-central1",
"us-central1-b"
]
}
],
"bot_id": "fuchsia-debian-1338871f-us-central1-b-bzqh",
"bot_version": "c7f0c187ee2160053ce935aa86119b526086da1e165dea02547e2f5a35dc9e3f",
"cipd_pins": {
"client_package": {
"package_name": "infra/tools/cipd/linux-amd64",
"version": "nFftZl2kFquaI2EUmPGwzbc5sIbCocl9L55UeASqlLsC"
},
"packages": [
{
"package_name": "fuchsia/tools/botanist/linux-amd64",
"path": ".",
"version": "pFNwtOmR12Ls7-MhTO1gKe1M4zp1gSatglN4bt8HS8wC"
},
{
"package_name": "fuchsia/qemu/linux-amd64",
"path": "qemu",
"version": "9cc486c5b18a0be515c39a280ca9a309c54cf994"
}
]
},
"completed_ts": "2019-03-26T16:51:50.898303",
"costs_usd": [
0.004029347937940791
],
"created_ts": "2019-03-26T16:51:13.609299",
"duration": 14.104798078536987,
"modified_ts": "2019-03-26T16:51:50.898303",
"name": "QEMU",
"outputs_ref": {
"isolated": "ad8abc49485830d2eb2ccfd1eb83db7b7692deba",
"isolatedserver": "
https://isolateserver.appspot.com",
"namespace": "default-gzip"
},
"run_id": "43d3bb426a717b11",
"server_versions": [
"4216-cf4a3cd"
],
"started_ts": "2019-03-26T16:51:16.961062",
"state": "COMPLETED",
"tags": [
"cpu:x64",
"kvm:1",
"os:Debian",
"pool:fuchsia.tests",
"priority:200",
"service_account:none",
"swarming.pool.template:none",
"swarming.pool.version:b6c541c43c2d995a2d695b6e44ad9c4f540ac415",
"user:None"
],
"task_id": "43d3bb426a717b10",
"try_number": "1"
}
},
"43d3bb426d676d10": {
"output": "",
"outputs": null,
"results": {
"abandoned_ts": "2019-03-26T17:02:11.913608",
"completed_ts": "2019-03-26T17:02:11.913608",
"created_ts": "2019-03-26T16:51:13.601664",
"modified_ts": "2019-03-26T17:02:11.913608",
"name": "Intel NUC Kit NUC7i5DNHE",
"server_versions": [
"4216-cf4a3cd"
],
"state": "EXPIRED",
"tags": [
"device_type:Intel NUC Kit NUC7i5DNHE",
"pool:fuchsia.tests",
"priority:200",
"service_account:none",
"swarming.pool.template:none",
"swarming.pool.version:b6c541c43c2d995a2d695b6e44ad9c4f540ac415",
"user:None"
],
"task_id": "43d3bb426d676d10"
}
}
}