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/tmphMqRl1.json --task-output-dir /b/swarming/w/ir/tmp/t/tmpW6NDo8 --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/tmp1o1MpVmerge_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": 1552951822066166, "id": "8918605706183820624", "project": "chromium", "tags": ["build_address:luci.chromium.ci/Fuchsia x64/31423", "builder:Fuchsia x64", "buildset:commit/git/b51f4e151b1968c535fa52a7e25f99991504e3df", "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b51f4e151b1968c535fa52a7e25f99991504e3df", "gitiles_ref:refs/heads/master", "scheduler_invocation_id:9084035768280596672", "scheduler_job_id:chromium/Fuchsia x64", "user_agent:luci-scheduler"]}, "hostname": "cr-buildbucket.appspot.com"}, "buildername": "Fuchsia x64", "buildnumber": 31423, "got_angle_revision": "38e282570676f3e434482f8a02ea539b286fd1fa", "got_dawn_revision": "fef613365afe5234c1bae9fe33c3d30698d27078", "got_nacl_revision": "1ed9878b8eb68c1e0d0268754dafcf83e747fa8f", "got_revision": "b51f4e151b1968c535fa52a7e25f99991504e3df", "got_revision_cp": "refs/heads/master@{#641784}", "got_swarming_client_revision": "7a61cf37d6a0163f0ec02d495289a1d038e62457", "got_v8_revision": "36585c1b6b1fd69d093706a6c4ad9775506a7885", "got_v8_revision_cp": "refs/heads/7.5.62@{#1}", "got_webrtc_revision": "7dbc0eb2efa4708da5e3e44d2a15bfdee4ceb474", "got_webrtc_revision_cp": "refs/heads/master@{#27160}", "mastername": "chromium.linux", "path_config": "generic", "recipe": "chromium", "repository": "https://chromium.googlesource.com/chromium/src.git", "revision": "b51f4e151b1968c535fa52a7e25f99991504e3df"}' --summary-json-file /b/swarming/w/ir/tmp/t/tmpEQcgaa.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/tmpQXcR3q.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/tmphMqRl1.json', '--task-output-dir', '/b/swarming/w/ir/tmp/t/tmpW6NDo8', '--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/tmp1o1MpVmerge_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": 1552951822066166, "id": "8918605706183820624", "project": "chromium", "tags": ["build_address:luci.chromium.ci/Fuchsia x64/31423", "builder:Fuchsia x64", "buildset:commit/git/b51f4e151b1968c535fa52a7e25f99991504e3df", "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b51f4e151b1968c535fa52a7e25f99991504e3df", "gitiles_ref:refs/heads/master", "scheduler_invocation_id:9084035768280596672", "scheduler_job_id:chromium/Fuchsia x64", "user_agent:luci-scheduler"]}, "hostname": "cr-buildbucket.appspot.com"}, "buildername": "Fuchsia x64", "buildnumber": 31423, "got_angle_revision": "38e282570676f3e434482f8a02ea539b286fd1fa", "got_dawn_revision": "fef613365afe5234c1bae9fe33c3d30698d27078", "got_nacl_revision": "1ed9878b8eb68c1e0d0268754dafcf83e747fa8f", "got_revision": "b51f4e151b1968c535fa52a7e25f99991504e3df", "got_revision_cp": "refs/heads/master@{#641784}", "got_swarming_client_revision": "7a61cf37d6a0163f0ec02d495289a1d038e62457", "got_v8_revision": "36585c1b6b1fd69d093706a6c4ad9775506a7885", "got_v8_revision_cp": "refs/heads/7.5.62@{#1}", "got_webrtc_revision": "7dbc0eb2efa4708da5e3e44d2a15bfdee4ceb474", "got_webrtc_revision_cp": "refs/heads/master@{#27160}", "mastername": "chromium.linux", "path_config": "generic", "recipe": "chromium", "repository": "https://chromium.googlesource.com/chromium/src.git", "revision": "b51f4e151b1968c535fa52a7e25f99991504e3df"}', '--summary-json-file', '/b/swarming/w/ir/tmp/t/tmpEQcgaa.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/tmpQXcR3q.json'] env: {'CHROME_HEADLESS': '1'} env_prefixes: {'PATH': '/b/swarming/w/ir/cache/swarming_client'} env_suffixes: {} infra_step: False name_tokens: ('perfetto_unittests',) ok_ret: frozenset([0]) step_test_data: <lambda>(...) trigger_specs: () full environment: ADDRFAM: inet BOTO_CONFIG: /b/swarming/w/ir/tmp/gsutil_task/.boto BUILDBUCKET_EXPERIMENTAL: FALSE CHROME_HEADLESS: 1 CIPD_CACHE_DIR: /b/swarming/cipd_cache/cache CIPD_PROTOCOL: v2 DEVSHELL_CLIENT_PORT: 35779 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/8918605706183820624 LOGDOG_STREAM_PROJECT: chromium LOGDOG_STREAM_SERVER_PATH: unix:/b/swarming/w/ir/tmp/ld.sock LOGICAL: eth0 LUCI_CONTEXT: /b/swarming/w/itOwZwNp/luci_context.192870738 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: 43abf5d1daeba911 TEMP: /b/swarming/w/ir/tmp/t TEMPDIR: /b/swarming/w/ir/tmp/t TERM: linux TMP: /b/swarming/w/ir/tmp/t TMPDIR: /b/swarming/w/ir/tmp/t UPSTART_EVENTS: net-device-up UPSTART_INSTANCE: UPSTART_JOB: chromebuild-startup USER: chrome-bot USERNAME: chrome-bot VPYTHON_VIRTUALENV_ROOT: /b/swarming/w/ir/cache/vpython _: /b/swarming/w/ir/cipd_bin_packages/vpython Set PYTHONPATH: /b/swarming/w/ir/kitchen-checkout/build/scripts:/b/swarming/w/ir/kitchen-checkout/build/site_config:/b/swarming/w/ir/kitchen-checkout/build/third_party:/b/swarming/w/ir/kitchen-checkout/build/third_party/buildbot_8_4p1:/b/swarming/w/ir/kitchen-checkout/build/third_party/buildbot_slave_8_4:/b/swarming/w/ir/kitchen-checkout/build/third_party/coverage-3.7.1:/b/swarming/w/ir/kitchen-checkout/build/third_party/decorator_3_3_1:/b/swarming/w/ir/kitchen-checkout/build/third_party/google_api_python_client:/b/swarming/w/ir/kitchen-checkout/build/third_party/httplib2/python2:/b/swarming/w/ir/kitchen-checkout/build/third_party/infra_libs:/b/swarming/w/ir/kitchen-checkout/build/third_party/jinja2:/b/swarming/w/ir/kitchen-checkout/build/third_party/markupsafe:/b/swarming/w/ir/kitchen-checkout/build/third_party/oauth2client:/b/swarming/w/ir/kitchen-checkout/build/third_party/pyasn1:/b/swarming/w/ir/kitchen-checkout/build/third_party/pyasn1-modules:/b/swarming/w/ir/kitchen-checkout/build/third_party/python-rsa:/b/swarming/w/ir/kitchen-checkout/build/third_party/requests_2_10_0:/b/swarming/w/ir/kitchen-checkout/build/third_party/setuptools-0.6c11:/b/swarming/w/ir/kitchen-checkout/build/third_party/sqlalchemy_0_7_1:/b/swarming/w/ir/kitchen-checkout/build/third_party/sqlalchemy_migrate_0_7_1:/b/swarming/w/ir/kitchen-checkout/build/third_party/tempita_0_5:/b/swarming/w/ir/kitchen-checkout/build/third_party/twisted_10_2:/b/swarming/w/ir/kitchen-checkout/build/third_party/uritemplate:/b/swarming/w/ir/kitchen-checkout/build/third_party/site-packages 2019-03-18 16:36:49,814 - root: [DEBUG] Using task_output_dir: '/b/swarming/w/ir/tmp/t/tmpW6NDo8' 2019-03-18 16:36:49,815 - root: [WARNING] task_output_dir '/b/swarming/w/ir/tmp/t/tmpW6NDo8' already exists! 2019-03-18 16:36:49,815 - root: [WARNING] task_output_dir existing content: [] 2019-03-18 16:36:49,815 - 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/tmpQXcR3q.json -output-dir /b/swarming/w/ir/tmp/t/tmpW6NDo8 -task-summary-json /b/swarming/w/ir/tmp/t/tmpEQcgaa.json [D2019-03-18T16:36:49.823860-07:00 17665 0 auth.go:1265] Minting a new token {"key":"luci_ctx/3427b1d3f3a0b84928fece4b2c326372f9efc2b29fab702f6a79d650dca04797", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-03-18T16:36:49.823952-07:00 17665 0 luci_ctx.go:138] POST http://127.0.0.1:52950/rpc/LuciLocalAuthService.GetOAuthToken {"key":"luci_ctx/3427b1d3f3a0b84928fece4b2c326372f9efc2b29fab702f6a79d650dca04797", "scopes":"https://www.googleapis.com/auth/userinfo.email"} [D2019-03-18T16:36:49.825644-07:00 17665 0 auth.go:1222] Token expires in 47m52.174380112s {"key":"luci_ctx/3427b1d3f3a0b84928fece4b2c326372f9efc2b29fab702f6a79d650dca04797", "scopes":"https://www.googleapis.com/auth/userinfo.email"} 43abfb288ab50b10: exit 0 Additional test environment: CHROME_DEVEL_SANDBOX=/opt/chromium/chrome_sandbox CHROME_HEADLESS=1 LANG=en_US.UTF-8 Command: bin/run_perfetto_unittests --test-launcher-bot-mode --qemu-require-kvm --test-launcher-summary-output=/b/s/w/io9rODmd/output.json 2019-03-18 12:59:56,205:INFO:root:Connecting to Fuchsia using SSH. 2019-03-18 13:00:02,476:INFO:root:Connected! 2019-03-18 13:00:02,476:INFO:root:Attaching kernel logger. [00000.000] zbi: @ 0xffffff8000a6a000 (9859064 bytes) [00000.000] UART: FIFO depth 16 [00000.000] PMM: boot reserve add [0x100000, 0x2e4fff] [00000.000] PMM: boot reserve add [0xa6a000, 0x13d0fff] [00000.000] PMM: boot reserve marking WIRED [0x100000, 0x2e4fff] [00000.000] PMM: boot reserve marking WIRED [0xa6a000, 0x13d0fff] [00000.000] [00000.000] welcome to Zircon [00000.000] [00000.000] KASLR: .text section at 0xffffffff00100000 [00000.000] INIT: cpu 0, calling hook 0xffffffff0010f234 (global_prng_seed) at level 0x30000, flags 0x1 [00000.000] initializing vm pre-heap [00000.000] VM: marking boot alloc used range [0x13d1000, 0x13d1040) [00000.000] INIT: cpu 0, calling hook 0xffffffff0011c4d8 (elf_build_id) at level 0x4fffe, flags 0x1 [00000.000] INIT: cpu 0, calling hook 0xffffffff0011c664 (version) at level 0x4ffff, flags 0x1 [00000.000] version: [00000.000] arch: x86 [00000.000] platform: XXX [00000.000] target: XXX [00000.000] project: x64 [00000.000] buildid: git-f06654a350f387bb10b0cacb33f90aaba07a8f37-dirty [00000.000] ELF build ID: 2c7f842a946f17e3f248897279a6d31f7bb1f9a9 [00000.000] initializing heap [00000.000] INIT: cpu 0, calling hook 0xffffffff00101f78 (console) at level 0x50000, flags 0x1 [00000.000] INIT: cpu 0, calling hook 0xffffffff00171648 (x86_resource_init) at level 0x50000, flags 0x1 [00000.000] initializing vm [00000.000] VM: reserving kernel region [0xffffffff00100000, 0xffffffff00201000) flags 0x28 name 'kernel_code' [00000.000] VM: reserving kernel region [0xffffffff00201000, 0xffffffff0024f000) flags 0x8 name 'kernel_rodata' [00000.000] VM: reserving kernel region [0xffffffff0024f000, 0xffffffff0025f000) flags 0x18 name 'kernel_data' [00000.000] VM: reserving kernel region [0xffffffff0025f000, 0xffffffff002e5000) flags 0x18 name 'kernel_bss' [00000.000] INIT: cpu 0, calling hook 0xffffffff0017230c (display_memtype) at level 0x60001, flags 0x1 [00000.000] INIT: cpu 0, calling hook 0xffffffff0017534c (acpi_tables) at level 0x60001, flags 0x1 [00000.000] INIT: cpu 0, calling hook 0xffffffff0017046c (hpet) at level 0x60002, flags 0x1 [00000.000] INIT: cpu 0, calling hook 0xffffffff0017082c (apic) at level 0x60002, flags 0x1 [00000.000] x2APIC enabled [00000.000] INIT: cpu 0, calling hook 0xffffffff0017493c (timer) at level 0x60003, flags 0x1 [00000.000] pvclock: Clocksource is stable [00000.000] HPET frequency: 100000 ticks/ms [00000.000] pvclock: Fetching TSC frequency [00000.000] TSC frequency: 2199842 ticks/ms [00000.363] timer features: constant_tsc 1 invariant_tsc 1 tsc_deadline 1 [00000.363] Using TSC as wallclock [00000.363] initializing kernel [00000.363] initializing mp [00000.363] initializing timers [00000.363] INIT: cpu 0, calling hook 0xffffffff0010f488 (global_prng_thread_safe) at level 0x7ffff, flags 0x1 [00000.363] INIT: cpu 0, calling hook 0xffffffff0011b790 (debuglog) at level 0x7ffff, flags 0x1 [00000.363] INIT: cpu 0, calling hook 0xffffffff001e5d10 (thread_set_priority_experiment) at level 0x7ffff, flags 0x1 [00000.363] thread set priority experiment is : ENABLED [00000.363] creating bootstrap completion thread [00000.509] top of bootstrap2() [00000.509] INIT: cpu 0, calling hook 0xffffffff00127f70 (libobject) at level 0x80000, flags 0x1 [00000.509] OOM: started thread [00000.517] INIT: cpu 0, calling hook 0xffffffff0019d880 (dpc) at level 0x80000, flags 0x1 [00000.521] Processor Model Info: type 0 family 0x6 model 0xf stepping 0 [00000.521] display_family 0x6 display_model 0x4f [00000.521] Vendor: Intel [00000.521] Microarch: Broadwell [00000.521] F/M/S: 6/4f/0 [00000.521] Brand: Intel(R) Xeon(R) CPU @ 2.20GHz [00000.521] Features: fpu sse sse2 sse3 ssse3 sse4.1 sse4.2 mmx avx avx2 fxsr pcid xsave aesni clflush [00000.521] fsgsbase tsc_adj smep smap erms rdrand rdseed syscall nx rdtscp invar_tsc tsc_deadline [00000.521] x2apic hypervisor [00000.521] initializing platform [00000.521] INIT: cpu 0, calling hook 0xffffffff0018deb4 (x86_perfmon) at level 0x90000, flags 0x1 [00000.521] x86_perfmon_init_once:497: perfmon: unexpected programmable counter width 0 in cpuid.0AH [00000.521] UART: started IRQ driven RX [00000.521] UART: started IRQ driven TX [00000.521] cpu topology: [00000.521] 0: apic id 0x0 package 0 node 0 core 0 smt 0 BSP [00000.521] 1: apic id 0x1 package 1 node 0 core 0 smt 0 [00000.521] 2: apic id 0x2 package 2 node 0 core 0 smt 0 [00000.521] 3: apic id 0x3 package 3 node 0 core 0 smt 0 [00000.521] Found 4 cpus [00000.522] booting apic ids: 0x1 0x2 0x3 [00000.586] entering scheduler on cpu 3 [00000.586] entering scheduler on cpu 2 [00000.586] entering scheduler on cpu 1 [00000.595] smbios: manufacturer="QEMU" product="Standard PC (Q35 + ICH9, 2009)" [00000.596] initializing target [00000.596] INIT: cpu 0, calling hook 0xffffffff00173bec (x86_pcie_init) at level 0xa0000, flags 0x1 [00000.596] moving to last init level [00000.596] INIT: cpu 0, calling hook 0xffffffff00102a48 (kernel_shell) at level 0xc0000, flags 0x1 [00000.596] INIT: cpu 0, calling hook 0xffffffff001fc0a8 (ktrace) at level 0xc0000, flags 0x1 [00000.635] ktrace: buffer at 0xffffff9ff9b79000 (33554432 bytes) [00000.635] INIT: cpu 0, calling hook 0xffffffff001fdfe8 (userboot) at level 0xc0000, flags 0x1 [00000.635] userboot: ramdisk 0x967000 @ 0xffffff8000a6a000 [00000.648] userboot: userboot rodata 0 @ [0x3f7c7658f000,0x3f7c76592000) [00000.648] userboot: userboot code 0x3000 @ [0x3f7c76592000,0x3f7c7659d000) [00000.648] userboot: vdso/full rodata 0 @ [0x3f7c7659d000,0x3f7c765a4000) [00000.648] userboot: vdso/full code 0x7000 @ [0x3f7c765a4000,0x3f7c765a5000) [00000.648] userboot: entry point @ 0x3f7c76592c90 [00000.668] userboot: option "/b/s/w/ir/third_party/fuchsia-sdk/sdk/target/x64/qemu-kernel.bin=" [00000.668] userboot: option "devmgr.epoch=1552939195" [00000.668] userboot: option "zircon.nodename=f470db97-f70f-4bef-98b4-7922d49124fc" [00000.668] userboot: option "TERM=dumb" [00000.668] userboot: option "kernel.serial=legacy" [00000.668] userboot: option "multiboot.boot_loader_name=qemu" [00000.696] userboot: searching bootfs for 'bin/bootsvc' [00000.696] userboot: 'bin/bootsvc' has PT_INTERP "lib/ld.so.1" [00000.696] userboot: searching bootfs for 'lib/ld.so.1' [00000.696] userboot: userboot: loaded lib/ld.so.1 at 0x5fa48b404000, entry point 0x5fa48b4412f1 [00000.696] userboot: userboot: loaded vDSO at 0x62bcf18ea000, entry point 0x0 [00000.696] userboot: process bin/bootsvc started. [00000.696] userboot: waiting for loader-service requests... [00000.722] userboot: searching bootfs for 'lib/libasync-default.so' [00000.722] userboot: searching bootfs for 'lib/libfdio.so' [00000.722] userboot: searching bootfs for 'lib/libtrace-engine.so' [00000.722] userboot: searching bootfs for 'lib/liblaunchpad.so' [00000.736] {{{reset}}} [00000.736] {{{module:0:<application>:elf:64913060bc657fc459f4ab5301a5cb477104c184}}} [00000.736] {{{mmap:0x65de3aad2000:0x39000:load:0:rx:0}}} [00000.736] {{{mmap:0x65de3ab0b000:0x4000:load:0:rw:0x39000}}} [00000.736] dso: id=64913060bc657fc459f4ab5301a5cb477104c184 base=0x000065de3aad2000 name=<application> [00000.736] {{{module:0x2:<vDSO>:elf:5db6a550b7c22b4e5d9c55acc5bbc1d2583f0380}}} [00000.736] {{{mmap:0x62bcf18ea000:0x7000:load:0x2:r:0}}} [00000.736] {{{mmap:0x62bcf18f1000:0x1000:load:0x2:rx:0x7000}}} [00000.736] dso: id=5db6a550b7c22b4e5d9c55acc5bbc1d2583f0380 base=0x000062bcf18ea000 name=<vDSO> [00000.736] {{{module:0x3:libasync-default.so:elf:179378e0029e9d73bcaaf61330269def68f524f0}}} [00000.736] {{{mmap:0x6bf453bb7000:0x1000:load:0x3:rx:0}}} [00000.736] {{{mmap:0x6bf453bb8000:0x1000:load:0x3:rw:0x1000}}} [00000.736] dso: id=179378e0029e9d73bcaaf61330269def68f524f0 base=0x00006bf453bb7000 name=libasync-default.so [00000.736] {{{module:0x1:libc.so:elf:608526d5035c0cab2fdd1718baca2d9ed1f3441b}}} [00000.736] {{{mmap:0x5fa48b404000:0xcb000:load:0x1:rx:0}}} [00000.736] {{{mmap:0x5fa48b4d0000:0x6000:load:0x1:rw:0xcc000}}} [00000.736] dso: id=608526d5035c0cab2fdd1718baca2d9ed1f3441b base=0x00005fa48b404000 name=libc.so [00000.736] {{{module:0x4:libfdio.so:elf:b8b2c5016c76236a2d5415d1063cfcebb8fb5101}}} [00000.736] {{{mmap:0x278110d76000:0x32000:load:0x4:rx:0}}} [00000.736] {{{mmap:0x278110da9000:0x4000:load:0x4:rw:0x33000}}} [00000.736] dso: id=b8b2c5016c76236a2d5415d1063cfcebb8fb5101 base=0x0000278110d76000 name=libfdio.so [00000.736] {{{module:0x5:libtrace-engine.so:elf:0f28e2222418dde22d6dd647ddb13d0d42ff310a}}} [00000.736] {{{mmap:0x3949a8e5f000:0x9000:load:0x5:rx:0}}} [00000.736] {{{mmap:0x3949a8e68000:0x2000:load:0x5:rw:0x9000}}} [00000.736] dso: id=0f28e2222418dde22d6dd647ddb13d0d42ff310a base=0x00003949a8e5f000 name=libtrace-engine.so [00000.736] {{{module:0x6:liblaunchpad.so:elf:7bbb5d72e7b50ffd31348f1fff0031cd559eaa75}}} [00000.736] {{{mmap:0x46467843f000:0x7000:load:0x6:rx:0}}} [00000.736] {{{mmap:0x464678446000:0x2000:load:0x6:rw:0x7000}}} [00000.736] dso: id=7bbb5d72e7b50ffd31348f1fff0031cd559eaa75 base=0x000046467843f000 name=liblaunchpad.so [00000.851] bootsvc: Starting... [00000.851] bootsvc: Creating bootfs service... [00000.851] userboot: loader-service channel peer closed [00000.851] userboot: finished! [00000.862] bootsvc: Processing bootdata... [00000.862] bootsvc: Loading boot cmdline overrides... [00000.862] bootsvc: Loading kernel VMOs... [00000.862] bootsvc: Creating loader service... [00000.862] bootsvc: Launching next process... [00000.876] bootsvc: launched bin/devcoordinator [00000.878] devcoordinator: main() [00000.878] devcoordinator: coordinator_init() [00000.878] devcoordinator: init [00000.878] devcoordinator: svc init [00000.879] devcoordinator: launch /boot/bin/svchost (svchost) OK [00000.879] devcoordinator: vfs init [00000.883] devcoordinator: launch /boot/bin/fshost (fshost) OK [00000.884] devcoordinator: shell startup [00000.897] fshost: started. [00000.900] devcoordinator: launch /boot/bin/netsvc (netsvc) OK [00000.904] devcoordinator: launch /boot/bin/virtual-console (virtual-console) OK [00000.913] devcoordinator: launch /boot/bin/pwrbtn-monitor (pwrbtn-monitor) OK [00000.930] devcoordinator: no driver info in '/boot/driver/mtk-power.so' [00000.943] devcoordinator: full system required, ignoring fallback drivers until /system is loaded [00000.950] devcoordinator: launch devhost 'devhost:sys': pid=2083 [00000.956] devcoordinator: launch devhost 'devhost:test': pid=2129 [00000.957] devhost: trace provider registry begun [00000.964] devcoordinator: launch devhost 'devhost:root': pid=2213 [00000.965] devhost: trace provider registry begun [00000.973] devcoordinator: launch devhost 'devhost:misc': pid=2292 [00000.985] devhost: trace provider registry begun [00000.987] devhost: trace provider registry begun [00000.994] GetZbiMetadata metadata not found for type 524f426d, extra 0 [00001.008] devcoordinator: launch /boot/bin/sh (sh:console) OK [00001.021] acpi-bus: not using IOMMU [00001.024] GetHwProperties: No PM support [00001.024] IntelPT: No PT support [00001.024] devhost[sys/cpu-trace] bind driver '/boot/driver/cpu-trace.so' failed: -2 [00001.033] devcoordinator: rpc: bind-driver 'cpu-trace' status -2 [00001.034] [sysmem_init 28] async_get_default_dispatcher(): 0x79a39f6480a0 [00001.034] [sysmem_bind 40] sysmem_bind() [00001.043] acpi-pwrbtn: initialized [00001.046] vc: new input device /dev/class/input/000 [00001.113] acpi: published device pci(0x79223c96a180), parent=sys(0x79a39f6560c0), handle=0x79a39f653300 [00001.113] acpi: failed to get PCI base bus number for device 'PCI0' (status 4294967295) [00001.124] acpi: published device rtc(0x79223c96a200), parent=acpi(0x79a39f656300), handle=0x79a39f6537b0 [00001.125] acpi: published device i8042(0x79223c96a280), parent=acpi(0x79a39f656300), handle=0x79a39f653840 [00001.140] devcoordinator: launch devhost 'devhost:pci#1:1234:1111': pid=2781 [00001.145] devcoordinator: launch devhost 'devhost:pci#2:1af4:1001': pid=2838 [00001.156] devhost: trace provider registry begun [00001.164] devcoordinator: launch devhost 'devhost:pci#3:8086:100e': pid=2938 [00001.164] devhost: trace provider registry begun [00001.174] devcoordinator: launch devhost 'devhost:pci#5:8086:2922': pid=3029 [00001.183] devhost: trace provider registry begun [00001.186] devhost: WARNING: driver '/boot/driver/pc-ps2.so' did not add device in bind() [00001.188] i8042 device 0xab 0x41 [00001.196] vc: new input device /dev/class/input/001 [00001.206] bochs_vbe: initialized display, 1024 x 768 (stride=1024 format=00020001) [00001.212] devhost: trace provider registry begun [00001.221] vc: new display device /dev/class/display-controller/000/virtcon [00001.223] ahci: using MSI interrupt [00001.232] vc: Successfully attached to display 1 [00001.266] fshost: /dev/class/block/000: FVM? [00001.285] devcoordinator: launch /boot/bin/blobfs (blobfs:/blob) OK [00001.358] i8042 device 0x00 [00001.370] 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.378] vc: new input device /dev/class/input/002 [00001.442] fshost: starting 'bin/pkgsvr' 'e747bb3ffacb53962b1e6acd6db19019f3cabbefa89841326c816033f309ef8e'... [00001.461] devcoordinator: launch bin/pkgsvr (pkgfs) OK [00001.493] pkgsvr: system: will be served from e747bb3ffacb53962b1e6acd6db19019f3cabbefa89841326c816033f309ef8e [00001.493] pkgsvr: pkgfs serving index /data/pkgfs_index from blobfs /blob [00001.506] fshost: mounting minfs [00001.515] devcoordinator: launch /boot/bin/minfs (minfs:/data) OK [00001.558] devcoordinator: adding system driver 'wlan' '/system/driver/wlan.so' [00001.564] devcoordinator: launch /system/bin/appmgr (appmgr) OK [00001.571] devcoordinator: adding system driver 'bt_hog' '/system/driver/bt-hog.so' [00001.586] devcoordinator: adding system driver 'wlan' '/system/driver/wlanif.so' [00001.612] devcoordinator: adding system driver 'rtl88xx' '/system/driver/rtl88xx.so' [00001.627] devcoordinator: adding system driver 'usb_video' '/system/driver/usb_video.so' [00001.638] devcoordinator: adding system driver 'bt_hci_passthrough' '/system/driver/bt-hci-passthrough.so' [00001.663] devcoordinator: adding system driver 'bt_host' '/system/driver/bt-host.so' [00001.676] devcoordinator: adding system driver 'gpu' '/system/driver/libmsd_intel.so' [00001.692] devcoordinator: adding system driver 'wlantapctl' '/system/driver/wlantap.so' [00001.707] devcoordinator: adding system driver 'brcmfmac' '/system/driver/brcmfmac.so' [00001.717] devcoordinator: adding system driver 'qmi_fake' '/system/driver/qmi-fake.so' [00001.727] devcoordinator: adding system driver 'mt8167s_gpu' '/system/driver/libmsd_img_rgx_mtk.so' [00001.744] devcoordinator: adding system driver 'bt_hci_intel' '/system/driver/bt-hci-intel.so' [00001.762] devcoordinator: adding system driver 'virtual_audio' '/system/driver/virtual_audio_driver.so' [00001.776] devcoordinator: adding system driver 'wlan' '/system/driver/wlanphy.so' [00001.810] devcoordinator: adding system driver 'ralink' '/system/driver/ralink.so' [00001.827] [INFO:namespace_builder.cc(93)] config-data for sysmgr [00001.827] devcoordinator: adding system driver 'bt_hci_fake' '/system/driver/bt-hci-fake.so' [00001.835] devcoordinator: adding system driver 'qmi_usb' '/system/driver/qmi-transport.so' [00001.859] devcoordinator: adding system driver 'ath10k_pci' '/system/driver/ath10k.so' [00001.874] devcoordinator: adding system driver 'bt_hci_atheros' '/system/driver/bt-hci-atheros.so' [00001.882] devcoordinator: driver 'bt_hci_atheros' added [00001.882] devcoordinator: driver 'ath10k_pci' added [00001.882] devcoordinator: driver 'qmi_usb' added [00001.882] devcoordinator: driver 'bt_hci_fake' added [00001.882] devcoordinator: driver 'ralink' added [00001.882] devcoordinator: driver 'wlan' added [00001.882] devcoordinator: driver 'bt_hci_intel' added [00001.882] devcoordinator: driver 'mt8167s_gpu' added [00001.882] devcoordinator: driver 'qmi_fake' added [00001.882] devcoordinator: driver 'brcmfmac' added [00001.882] devcoordinator: driver 'gpu' added [00001.882] devcoordinator: driver 'bt_host' added [00001.882] devcoordinator: driver 'usb_video' added [00001.882] devcoordinator: driver 'rtl88xx' added [00001.882] devcoordinator: driver 'wlan' added [00001.882] devcoordinator: driver 'bt_hog' added [00001.882] devcoordinator: driver 'wlan' added [00001.8initialized repo /b/s/w/it07InIc/tmpCkop1w adding package perfetto_unittests/0 2019/03/18 20:00:02 error removing source: Source not found 2019-03-18 13:00:03,554:INFO:root:Installing perfetto_unittests version 0. 2019/03/18 20:00:04 Success perfetto_unittests/0: cd84a0949a51a0c6ff8dcb4092e78b0d1408101102ad55a0bfde9df7d9fdc6d0 2019-03-18 13:00:05,146:INFO:root:Running application. 2019-03-18 13:00:05,149:INFO:root:Running "/b/s/w/ir/third_party/fuchsia-sdk/sdk/tools/symbolize -ids /b/s/w/ir/out/Release/gen/third_party/perfetto/perfetto_unittests/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". 82] devcoordinator: driver 'bt_hci_passthrough' added [00001.882] devcoordinator: fallback driver 'usb_composite' is available [00001.882] devcoordinator: driver 'usb_composite' added [00001.882] devcoordinator: fallback driver 'intel_disp' is available [00001.882] devcoordinator: driver 'intel_disp' added [00002.490] [INFO:cobalt_main.cc(145)] Cobalt client schedule params: schedule_interval=3600 seconds, min_interval=10 seconds, initial_interval=60 seconds. [00002.763] [INFO:namespace_builder.cc(93)] config-data for wlancfg [00002.780] netifc: ? /dev/class/ethernet/000 [00002.787] netifc: create 512 eth buffers [00002.792] macaddr: 52:54:00:63:5e:7b [00002.792] ip6addr: fe80::5054:4d:fe63:5e7b [00002.792] snmaddr: ff02::1:ff63:5e7b [00002.792] netsvc: using /dev/class/ethernet/000 [00002.805] netsvc: nodename='f470db97-f70f-4bef-98b4-7922d49124fc' [00002.805] netsvc: start [00003.226] [INFO:namespace_builder.cc(93)] config-data for crashpad_agent [00003.391] [INFO:main.cc(35)] Trace Manager starting with config: /pkg/data/tracing.config [00003.460] wlanstack2 [I]: Starting [00003.587] [INFO:a11y_input_system.cc(9)] Scenic accessibility input system started. [00003.587] [INFO:display_watcher.cc(40)] Scenic: Acquired display controller /dev/class/display-controller/000.(000) [00003.790] [INFO:input_interpreter.cc(1006)] hid-parser succesful for 000 with usage page 1 and usage 128 [00003.928] ERROR: setupLoaderTermPhysDevs: Failed to detect any valid GPUs in the current config [00003.928] ERROR: setupLoaderTrampPhysDevs: Failed during dispatch call of 'vkEnumeratePhysicalDevices' to lower layers or loader to get count. [00003.928] [ERROR:garnet/lib/ui/gfx/gfx_system.cc(188)] No Vulkan on device, Graphics system exiting. [00003.928] [INFO:input_system.cc(245)] Scenic input system initialized. [00003.950] [ERROR:garnet/bin/ui/root_presenter/app.cc(214)] Scenic died, destroying all presentations. [00003.957] [ERROR:garnet/bin/ui/view_manager/view_registry.cc(80)] Exiting due to scene manager connection error. [00003.964] [ERROR:garnet/bin/sysmgr/app.cc(166)] Singleton fuchsia-pkg://fuchsia.com/view_manager#meta/view_manager.cmx died [00003.977] [ERROR:garnet/bin/sysmgr/app.cc(166)] Singleton fuchsia-pkg://fuchsia.com/scenic#meta/scenic.cmx died [00004.423] Starting mDNS on interface ethp0003 192.168.3.9 [00004.430] Starting mDNS on interface ethp0003 [80fe::5450:ff00:63fe:7b5e] [00004.431] [ERROR:garnet/bin/mdns/service/mdns_interface_transceiver_v6.cc(49)] Failed to set socket option IPV6_JOIN_GROUP, No such device [00004.432] mDNS: Verifying uniqueness of host name step-dime-yard-juicy.local. [00005.253] mDNS: Using unique host name step-dime-yard-juicy.local. [00007.636] pkgsvr: asking amber to fetch 3 needed blobs [00008.754] pkgsvr: package activated perfetto_unittests/0 (cd84a0949a51a0c6ff8dcb4092e78b0d1408101102ad55a0bfde9df7d9fdc6d0) IMPORTANT DEBUGGING NOTE: batches of tests are run inside their own process. For debugging a test inside a debugger, use the --gtest_filter=<your_test_name> flag along with --single-process-tests. Using sharding settings from environment. This is shard 0/1 Using 4 parallel jobs. [1/350] OptionalTest.AssignOverload (0 ms) [2/350] OptionalTest.OperatorStar (0 ms) [3/350] OptionalTest.OperatorStar_rvalue (0 ms) [4/350] OptionalTest.OperatorArrow (0 ms) [5/350] OptionalTest.Value_rvalue (1 ms) [6/350] OptionalTest.ValueOr (0 ms) [7/350] OptionalTest.Swap_bothNoValue (0 ms) [8/350] OptionalTest.Swap_inHasValue (0 ms) [9/350] OptionalTest.Swap_outHasValue (0 ms) [10/350] OptionalTest.Swap_bothValue (0 ms) [11/350] OptionalTest.ConvertingCopyConstructor (1 ms) [12/350] OptionalTest.ConvertingMoveConstructor (0 ms) [13/350] OptionalTest.ConstructorForwardArguments (0 ms) [14/350] OptionalTest.ConstructorForwardInitListAndArguments (0 ms) [15/350] OptionalTest.ForwardConstructor (0 ms) [16/350] OptionalTest.NulloptConstructor (0 ms) [17/350] OptionalTest.AssignValue (0 ms) [18/350] OptionalTest.AssignObject (0 ms) [19/350] OptionalTest.AssignObject_rvalue (0 ms) [20/350] OptionalTest.AssignNull (0 ms) [21/350] OptionalTest.Emplace (1 ms) [22/350] OptionalTest.Equals_TwoEmpty (0 ms) [23/350] OptionalTest.Equals_TwoEquals (0 ms) [24/350] OptionalTest.Equals_OneEmpty (0 ms) [25/350] OptionalTest.Equals_TwoDifferent (0 ms) [26/350] OptionalTest.Equals_DifferentType (0 ms) [27/350] OptionalTest.NotEquals_TwoEmpty (0 ms) [28/350] OptionalTest.NotEquals_TwoEquals (0 ms) [29/350] OptionalTest.NotEquals_OneEmpty (0 ms) [30/350] OptionalTest.NotEquals_TwoDifferent (0 ms) [31/350] CircularQueueTest.Int (5 ms) [32/350] CircularQueueTest.Sorting (11 ms) [33/350] CircularQueueTest.MoveOperators (0 ms) [34/350] CircularQueueTest.Iterators (0 ms) [35/350] CircularQueueTest.ObjectLifetime (0 ms) [36/350] OptionalTest.DefaultConstructor (0 ms) [37/350] OptionalTest.CopyConstructor (1 ms) [38/350] OptionalTest.ValueConstructor (0 ms) [39/350] OptionalTest.MoveConstructor (0 ms) [40/350] OptionalTest.MoveValueConstructor (1 ms) [41/350] OptionalTest.NotEquals_DifferentType (0 ms) [42/350] OptionalTest.Less_LeftEmpty (0 ms) [43/350] OptionalTest.Less_RightEmpty (0 ms) [44/350] OptionalTest.Less_BothEmpty (0 ms) [45/350] OptionalTest.Less_BothValues (0 ms) [46/350] OptionalTest.Less_DifferentType (0 ms) [47/350] OptionalTest.LessEq_LeftEmpty (0 ms) [48/350] OptionalTest.LessEq_RightEmpty (0 ms) [49/350] OptionalTest.LessEq_BothEmpty (0 ms) [50/350] OptionalTest.LessEq_BothValues (0 ms) [51/350] OptionalTest.GreaterEq_DifferentType (0 ms) [52/350] OptionalTest.OptNullEq (0 ms) [53/350] OptionalTest.NullOptEq (0 ms) [54/350] OptionalTest.OptNullNotEq (0 ms) [55/350] OptionalTest.NullOptNotEq (0 ms) [56/350] OptionalTest.OptNullLower (1 ms) [57/350] OptionalTest.NullOptLower (0 ms) [58/350] OptionalTest.OptNullLowerEq (0 ms) [59/350] OptionalTest.NullOptLowerEq (0 ms) [60/350] OptionalTest.OptNullGreater (0 ms) [61/350] OptionalTest.LessEq_DifferentType (0 ms) [62/350] OptionalTest.Greater_BothEmpty (0 ms) [63/350] OptionalTest.Greater_LeftEmpty (1 ms) [64/350] OptionalTest.Greater_RightEmpty (0 ms) [65/350] OptionalTest.Greater_BothValue (0 ms) [66/350] OptionalTest.Greater_DifferentType (0 ms) [67/350] OptionalTest.GreaterEq_BothEmpty (0 ms) [68/350] OptionalTest.GreaterEq_LeftEmpty (0 ms) [69/350] OptionalTest.GreaterEq_RightEmpty (0 ms) [70/350] OptionalTest.GreaterEq_BothValue (0 ms) [71/350] OptionalTest.NullOptGreater (0 ms) [72/350] OptionalTest.OptNullGreaterEq (0 ms) [73/350] OptionalTest.NullOptGreaterEq (1 ms) [74/350] OptionalTest.ValueEq_Empty (1 ms) [75/350] OptionalTest.ValueEq_NotEmpty (1 ms) [76/350] OptionalTest.ValueEq_DifferentType (0 ms) [77/350] OptionalTest.EqValue_Empty (0 ms) [78/350] OptionalTest.EqValue_NotEmpty (0 ms) [79/350] OptionalTest.EqValue_DifferentType (0 ms) [80/350] OptionalTest.ValueNotEq_Empty (0 ms) [81/350] OptionalTest.ValueNotEq_NotEmpty (0 ms) [82/350] OptionalTest.NotEqValue_Empty (0 ms) [83/350] OptionalTest.NotEqValue_NotEmpty (0 ms) [84/350] OptionalTest.NotEqValue_DifferentType (0 ms) [85/350] OptionalTest.ValueLess_Empty (0 ms) [86/350] OptionalTest.ValueLess_NotEmpty (0 ms) [87/350] OptionalTest.LessValue_Empty (0 ms) [88/350] OptionalTest.LessValue_NotEmpty (0 ms) [89/350] OptionalTest.LessValue_DifferentType (0 ms) [90/350] OptionalTest.ValueLessEq_Empty (0 ms) [91/350] OptionalTest.ValueLessEq_NotEmpty (0 ms) [92/350] OptionalTest.ValueLessEq_DifferentType (0 ms) [93/350] OptionalTest.LessEqValue_Empty (0 ms) [94/350] OptionalTest.LessEqValue_NotEmpty (0 ms) [95/350] OptionalTest.LessEqValue_DifferentType (0 ms) [96/350] OptionalTest.ValueGreater_Empty (0 ms) [97/350] OptionalTest.ValueGreater_NotEmpty (0 ms) [98/350] OptionalTest.ValueGreater_DifferentType (1 ms) [99/350] OptionalTest.GreaterValue_Empty (0 ms) [100/350] OptionalTest.GreaterValue_NotEmpty (0 ms) [101/350] OptionalTest.GreaterValue_DifferentType (0 ms) [102/350] OptionalTest.ValueGreaterEq_Empty (1 ms) [103/350] OptionalTest.ValueGreaterEq_NotEmpty (0 ms) [104/350] OptionalTest.ValueGreaterEq_DifferentType (0 ms) [105/350] OptionalTest.GreaterEqValue_Empty (0 ms) [106/350] OptionalTest.GreaterEqValue_NotEmpty (0 ms) [107/350] OptionalTest.GreaterEqValue_DifferentType (0 ms) [108/350] OptionalTest.NotEquals (0 ms) [109/350] OptionalTest.NotEqualsNull (0 ms) [110/350] OptionalTest.MakeOptional (0 ms) [111/350] OptionalTest.NonMemberSwap_bothNoValue (0 ms) [112/350] OptionalTest.NonMemberSwap_inHasValue (0 ms) [113/350] OptionalTest.NonMemberSwap_outHasValue (0 ms) [114/350] OptionalTest.NonMemberSwap_bothValue (0 ms) [115/350] OptionalTest.Hash_OptionalReflectsInternal (0 ms) [116/350] OptionalTest.Hash_NullOptEqualsNullOpt (0 ms) [117/350] OptionalTest.Hash_UseInSet (0 ms) [118/350] OptionalTest.HasValue (0 ms) [119/350] OptionalTest.Reset_int (0 ms) [120/350] OptionalTest.Reset_Object (0 ms) [121/350] ScopedDirTest.CloseOutOfScope (0 ms) [122/350] ScopedFileTest.CloseOutOfScope (2 ms) [123/350] ScopedFileTest.Reset (4 ms) [124/350] ScopedFileTest.Release (1 ms) [125/350] ScopedFileTest.MoveCtor (1 ms) [126/350] ScopedFileTest.MoveAssignment (4 ms) [127/350] ScopedFileTest.CloseFailureIsFatal (35 ms) [128/350] ScopedFstreamTest.CloseOutOfScope (1 ms) [129/350] StringSplitterTest.StdString (0 ms) [130/350] StringSplitterTest.CString (0 ms) [131/350] OptionalTest.Reset_NoOp (0 ms) [132/350] OptionalTest.AssignFromRValue (0 ms) [133/350] OptionalTest.DontCallDefaultCtor (0 ms) [134/350] OptionalTest.DontCallNewMemberFunction (0 ms) [135/350] OptionalTest.Noexcept (0 ms) [136/350] OPtionalTest.ValueNotEq_DifferentType (1 ms) [137/350] OPtionalTest.ValueLess_DifferentType (1 ms) [138/350] PagedMemoryTest.Basic (0 ms) [139/350] PagedMemoryTest.Uncommitted (44 ms) [140/350] PagedMemoryTest.GuardRegions (96 ms) [141/350] WeakPtrTest.AllCases (1 ms) [142/350] TaskRunnerTest/0.PostImmediateTask (0 ms) [143/350] TaskRunnerTest/0.PostDelayedTask (21 ms) [144/350] TaskRunnerTest/0.PostImmediateTaskFromTask (1 ms) [145/350] TaskRunnerTest/0.PostDelayedTaskFromTask (13 ms) [146/350] TaskRunnerTest/0.PostImmediateTaskFromOtherThread (21 ms) [147/350] TaskRunnerTest/0.PostDelayedTaskFromOtherThread (14 ms) [148/350] TaskRunnerTest/0.AddFileDescriptorWatch (0 ms) [149/350] TaskRunnerTest/0.RemoveFileDescriptorWatch (13 ms) [150/350] TaskRunnerTest/0.RemoveFileDescriptorWatchFromTask (13 ms) [151/350] TaskRunnerTest/0.AddFileDescriptorWatchFromAnotherWatch (1 ms) [152/350] TaskRunnerTest/0.RemoveFileDescriptorWatchFromAnotherWatch (10 ms) [153/350] TaskRunnerTest/0.ReplaceFileDescriptorWatchFromAnotherWatch (0 ms) [154/350] TaskRunnerTest/0.AddFileDescriptorWatchFromAnotherThread (1 ms) [155/350] TaskRunnerTest/0.FileDescriptorWatchWithMultipleEvents (0 ms) [156/350] TaskRunnerTest/0.FileDescriptorClosedEvent (0 ms) [157/350] TaskRunnerTest/0.PostManyDelayedTasks (22 ms) [158/350] TaskRunnerTest/0.RunAgain (0 ms) [159/350] TaskRunnerTest/0.FileDescriptorWatchesNotStarved (0 ms) [160/350] TaskRunnerTest/0.NoDuplicateFileDescriptorWatchCallbacks (1 ms) [161/350] TaskRunnerTest/0.ReplaceFileDescriptorWatchFromOtherThread (1 ms) [162/350] TaskRunnerTest/0.IsIdleForTesting (1 ms) [163/350] TaskRunnerTest/0.RunsTasksOnCurrentThread (1 ms) [164/350] TempFileTest.Create (2 ms) [165/350] TempFileTest.CreateUnlinked (1 ms) [166/350] TempFileTest.ReleaseUnlinked (10 ms) [167/350] TempFileTest.ReleaseLinked (4 ms) [168/350] TempFileTest.TempDir (3 ms) [169/350] ThreadCheckerTest.Basic (3 ms) [170/350] ThreadCheckerTest.Detach (3 ms) [171/350] ThreadCheckerTest.CopyConstructor (2 ms) [172/350] ThreadTaskRunnerTest.ConstructedRunning (2 ms) [173/350] ThreadTaskRunnerTest.RunsTasksOnOneDedicatedThread (1 ms) [174/350] ThreadTaskRunnerTest.MovableOwnership (13 ms) [175/350] ThreadTaskRunnerTest.EnqueuedTasksDestructedOnTaskThread (1 ms) [176/350] UtilsTest.ArraySize (0 ms) [177/350] UtilsTest.Align (0 ms) [178/350] MessageHandleTest.MoveHandleSharedMessageDoesntFinalize (1 ms) [179/350] MessageTest.ZeroLengthArraysAndStrings (1 ms) [180/350] MessageTest.BasicTypesNoNesting (0 ms) [181/350] MessageTest.NestedMessagesSimple (0 ms) [182/350] MessageTest.AppendScatteredBytes (0 ms) [183/350] MessageTest.BackfillSizeOnFinalization (0 ms) [184/350] MessageTest.StressTest (19 ms) [185/350] MessageTest.DestructInvalidMessageHandle (0 ms) [186/350] MessageTest.MessageHandle (0 ms) [187/350] MessageTest.MoveMessageHandle (0 ms) [188/350] ProtoDecoder.ReadString (0 ms) [189/350] ProtoDecoder.VeryLargeField (0 ms) [190/350] ProtoDecoder.FixedData (0 ms) [191/350] ProtoUtilsTest.FieldPreambleEncoding (0 ms) [192/350] ProtoUtilsTest.ZigZagEncoding (0 ms) [193/350] ProtoUtilsTest.VarIntEncoding (0 ms) [194/350] ProtoUtilsTest.VarIntEncodingNegative (0 ms) [195/350] ProtoUtilsTest.RedundantVarIntEncoding (0 ms) [196/350] ProtoUtilsTest.VarIntDecoding (1 ms) [197/350] ProtoUtilsTest.VarIntDecodingOutOfBounds (0 ms) [198/350] ScatteredStreamWriterTest.ScatteredWrites (0 ms) [199/350] ProtoZeroConformanceTest.SimpleFieldsNoNesting (1 ms) [200/350] ProtoZeroConformanceTest.NestedMessages (0 ms) [201/350] ProtoZeroTest.Simple (0 ms) [202/350] ProtoZeroTest.Reflection (0 ms) [203/350] IdAllocatorTest.IdAllocation (1 ms) [204/350] IdAllocatorTest.IdAllocation_U8 (0 ms) [205/350] NullTraceWriterTest.WriterIdIsZero (0 ms) [206/350] NullTraceWriterTest.Writing (2 ms) [207/350] NullTraceWriterTest.FlushCallbackIsCalled (0 ms) [208/350] PacketStreamValidatorTest.NullPacket (0 ms) [209/350] PacketStreamValidatorTest.SimplePacket (0 ms) [210/350] PacketStreamValidatorTest.ComplexPacket (1 ms) [211/350] PacketStreamValidatorTest.SimplePacketWithUid (14 ms) [212/350] PacketStreamValidatorTest.SimplePacketWithZeroUid (0 ms) [213/350] PacketStreamValidatorTest.SimplePacketWithNegativeOneUid (0 ms) [214/350] PacketStreamValidatorTest.ComplexPacketWithUid (1 ms) [215/350] PacketStreamValidatorTest.FragmentedPacket (0 ms) [216/350] PacketStreamValidatorTest.FragmentedPacketWithUid (1 ms) [217/350] PacketStreamValidatorTest.TruncatedPacket (0 ms) [218/350] PacketStreamValidatorTest.TrailingGarbage (1 ms) [219/350] PatchListTest.InsertAndRemove (0 ms) [220/350] PatchListTest.PointerStability (1 ms) [221/350] TraceBufferTest.ReadWrite_WrappingCases (0 ms) [222/350] TraceBufferTest.ReadWrite_PaddingAtEndUpdatesIndex (0 ms) [223/350] TraceBufferTest.ReadWrite_PaddingAtEndUpdatesIndexMisaligned (0 ms) [224/350] TraceBufferTest.ReadWrite_EmptyPacket (0 ms) [225/350] TraceBufferTest.Fragments_Simple (0 ms) [226/350] TraceBufferTest.Fragments_EdgeCases (1 ms) [227/350] TraceBufferTest.Fragments_OutOfOrderLastChunkIsMiddle (0 ms) [228/350] TraceBufferTest.Fragments_OutOfOrderLastChunkIsMiddleFragmentation (0 ms) [229/350] TraceBufferTest.Fragments_OutOfOrderLastChunkIsMaxFragmentation (0 ms) [230/350] TraceBufferTest.Fragments_OutOfOrderWithIdOverflowADCB (0 ms) [231/350] StringSplitterTest.SplitOnNUL (0 ms) [232/350] StringSplitterTest.NestedUsage (0 ms) [233/350] StringUtilsTest.StartsWith (0 ms) [234/350] StringUtilsTest.EndsWith (0 ms) [235/350] StringViewTest.BasicCases (0 ms) [236/350] StringViewTest.HashCollisions (610 ms) [237/350] StringWriterTest.BasicCases (0 ms) [238/350] StringWriterTest.WriteAllTypes (0 ms) [239/350] TimeTest.Conversions (0 ms) [240/350] TimeTest.GetTime (20 ms) [241/350] SlicedProtobufInputStreamTest.SingleSlice (0 ms) [242/350] SlicedProtobufInputStreamTest.SimpleSequence (0 ms) [243/350] SlicedProtobufInputStreamTest.SequenceWithSkipsAndBackups (0 ms) [244/350] TraceBufferTest.ReadWrite_EmptyBuffer (0 ms) [245/350] TraceBufferTest.ReadWrite_Simple (37 ms) [246/350] TraceBufferTest.ReadWrite_OneChunkPerWriter (50 ms) [247/350] TraceBufferTest.ReadWrite_FillTillEnd (2 ms) [248/350] TraceBufferTest.ReadWrite_Padding (2 ms) [249/350] TraceBufferTest.ReadWrite_MinimalPadding (2 ms) [250/350] TraceBufferTest.ReadWrite_RandomChunksNoWrapping (137 ms) [251/350] TraceBufferTest.Patching_ReadWaitsForPatchComplete (1 ms) [252/350] TraceBufferTest.Malicious_ZeroSizedChunk (0 ms) [253/350] TraceBufferTest.Malicious_ChunkTooBig (0 ms) [254/350] TraceBufferTest.Malicious_DeclareMorePacketsBeyondBoundaries (0 ms) [255/350] TraceBufferTest.Malicious_ZeroVarintHeader (0 ms) [256/350] TraceBufferTest.Malicious_OverflowingVarintHeader (1 ms) [257/350] TraceBufferTest.Malicious_VarintHeaderTooBig (0 ms) [258/350] TraceBufferTest.Malicious_JumboVarint (0 ms) [259/350] TraceBufferTest.Malicious_ZeroVarintHeaderInSequence (0 ms) [260/350] TraceBufferTest.Malicious_ZeroVarintHeaderAtEndOfChunk (0 ms) [261/350] TraceBufferTest.Fragments_OutOfOrderWithIdOverflowACBD (0 ms) [262/350] TraceBufferTest.Fragments_EmptyChunkBefore (0 ms) [263/350] TraceBufferTest.Fragments_EmptyChunkAfter (0 ms) [264/350] TraceBufferTest.Fragments_EmptyChunkInTheMiddle (1 ms) [265/350] TraceBufferTest.Fragments_LongPackets (1 ms) [266/350] TraceBufferTest.Fragments_LongPacketWithWrappingID (0 ms) [267/350] TraceBufferTest.Fragments_PreserveUID (0 ms) [268/350] TraceBufferTest.Patching_Simple (0 ms) [269/350] TraceBufferTest.Patching_SkipIfChunkDoesntExist (1 ms) [270/350] TraceBufferTest.Patching_AtBoundariesOfChunk (1 ms) [271/350] TraceBufferTest.Malicious_PatchOutOfBounds (0 ms) [272/350] TraceBufferTest.Malicious_OverrideWithShorterChunkSize (0 ms) [273/350] TraceBufferTest.Malicious_OverrideWithShorterChunkSizeAfterRead (0 ms) [274/350] TraceBufferTest.Malicious_OverrideWithDifferentOffsetAfterRead (0 ms) [275/350] TraceBufferTest.Iterator_OneStreamOrdered (0 ms) [276/350] TraceBufferTest.Iterator_OneStreamWrapping (0 ms) [277/350] TraceBufferTest.Iterator_ManyStreamsOrdered (0 ms) [278/350] TraceBufferTest.Iterator_ManyStreamsWrapping (1 ms) [279/350] TraceBufferTest.Override_ReCommitBeforeRead (0 ms) [280/350] TraceBufferTest.Override_ReCommitAfterPartialRead (4 ms) [281/350] TraceBufferTest.Override_ReCommitAfterFullRead (3 ms) [282/350] TraceBufferTest.Override_ReCommitInvalid (0 ms) [283/350] TraceBufferTest.Override_ReCommitReordered (0 ms) [284/350] TraceBufferTest.Override_ReCommitReorderedFragmenting (0 ms) [285/350] TraceBufferTest.Override_ReCommitSameBeforeRead (0 ms) [286/350] TraceBufferTest.Override_ReCommitSameAfterRead (0 ms) [287/350] TraceBufferTest.Override_ReCommitIncompleteAfterReadOutOfOrder (0 ms) [288/350] TraceBufferTest.Override_ReCommitIncompleteFragmenting (0 ms) [289/350] TraceBufferTest.DiscardPolicy (1 ms) [290/350] TraceBufferTest.MissingPacketsOnSequence (1 ms) [291/350] TracePacketTest.Simple (13 ms) [292/350] TracePacketTest.Sliced (0 ms) [293/350] TracePacketTest.Corrupted (0 ms) [294/350] TracePacketTest.GetProtoPreamble (0 ms) [295/350] TracePacketTest.MoveOperators (0 ms) [296/350] TracingServiceImplTest.RegisterAndUnregister (1 ms) [297/350] TracingServiceImplTest.EnableAndDisableTracing (14 ms) [298/350] TracingServiceImplTest.LockdownMode (13 ms) [299/350] TracingServiceImplTest.ProducerNameFilterChange (6 ms) [300/350] TracingServiceImplTest.DisconnectConsumerWhileTracing (1 ms) [301/350] TracingServiceImplTest.ScrapeBuffersOnDisable (20 ms) [302/350] TracingServiceImplTest.AbortIfTraceDurationIsTooLong (1 ms) [303/350] TracingServiceImplTest.GetTraceStats (1 ms) [304/350] PageSize/SharedMemoryABITest.NominalCases/0 (0 ms) [305/350] PageSize/SharedMemoryABITest.NominalCases/1 (1 ms) [306/350] PageSize/SharedMemoryABITest.NominalCases/2 (0 ms) [307/350] PageSize/SharedMemoryABITest.NominalCases/3 (1 ms) [308/350] PageSize/SharedMemoryABITest.NominalCases/4 (11 ms) [309/350] PageSize/SharedMemoryArbiterImplTest.GetAndReturnChunks/0 (0 ms) [310/350] PageSize/SharedMemoryArbiterImplTest.GetAndReturnChunks/1 (0 ms) [311/350] TracingServiceImplTest.ReconnectProducerWhileTracing (23 ms) [312/350] TracingServiceImplTest.ProducerIDWrapping (0 ms) [313/350] TracingServiceImplTest.WriteIntoFileAndStopOnMaxSize (71 ms) [314/350] TracingServiceImplTest.ProducerShmAndPageSizeOverriddenByTraceConfig (6 ms) [315/350] TracingServiceImplTest.ExplicitFlush (16 ms) [316/350] TracingServiceImplTest.ImplicitFlushOnTimedTraces (26 ms) [317/350] TracingServiceImplTest.BatchFlushes (43 ms) [318/350] TracingServiceImplTest.PeriodicFlush (25 ms) [319/350] TracingServiceImplTest.OnTracingDisabledWaitsForDataSourceStopAcks (7 ms) [320/350] TracingServiceImplTest.OnDataSourceAddedWhilePendingDisableAcks (2 ms) [321/350] PageSize/StartupTraceWriterTest.WriteMultipleChunksWhileUnboundAndBind/0 (4 ms) [322/350] PageSize/StartupTraceWriterTes2019-03-18 13:00:08,280:INFO:root:Process exited normally with status code 0. 2019-03-18 13:00:08,280:INFO:root:Terminating kernel log reader. 2019-03-18 13:00:10,503:INFO:root:Shutting down QEMU. t.WriteMultipleChunksWhileUnboundAndBind/1 (9 ms) [323/350] PageSize/StartupTraceWriterTest.BindingWhileWritingFails/0 (0 ms) [324/350] PageSize/StartupTraceWriterTest.BindingWhileWritingFails/1 (0 ms) [325/350] PageSize/StartupTraceWriterTest.CreateAndBindViaRegistry/0 (2 ms) [326/350] PageSize/StartupTraceWriterTest.CreateAndBindViaRegistry/1 (2 ms) [327/350] PageSize/TraceWriterImplTest.SingleWriter/0 (0 ms) [328/350] PageSize/TraceWriterImplTest.SingleWriter/1 (0 ms) [329/350] PageSize/TraceWriterImplTest.FragmentingPacket/0 (1 ms) [330/350] PageSize/TraceWriterImplTest.FragmentingPacket/1 (1 ms) [331/350] TracingServiceImplTest.OnTracingDisabledCalledAnywaysInCaseOfTimeout (21 ms) [332/350] TracingServiceImplTest.SessionId (25 ms) [333/350] TracingServiceImplTest.ResynchronizeTraceStreamUsingSyncMarker (219 ms) [334/350] TracingServiceImplTest.DeferredStart (4 ms) [335/350] TracingServiceImplTest.ProducerUIDsAndPacketSequenceIDs (12 ms) [336/350] TracingServiceImplTest.AllowedBuffers (1 ms) [337/350] TracingServiceImplTest.CommitToForbiddenBufferIsDiscarded (16 ms) [338/350] TracingServiceImplTest.RegisterAndUnregisterTraceWriter (17 ms) [339/350] TracingServiceImplTest.ScrapeBuffersOnFlush (108 ms) [340/350] TracingServiceImplTest.ScrapeBuffersOnProducerDisconnect (8 ms) [341/350] PageSize/SharedMemoryArbiterImplTest.WriterIDsAllocation/0 (203 ms) [342/350] PageSize/SharedMemoryArbiterImplTest.WriterIDsAllocation/1 (146 ms) [343/350] PageSize/StartupTraceWriterTest.CreateUnboundAndBind/0 (1 ms) [344/350] PageSize/StartupTraceWriterTest.CreateUnboundAndBind/1 (0 ms) [345/350] PageSize/StartupTraceWriterTest.CreateBound/0 (0 ms) [346/350] PageSize/StartupTraceWriterTest.CreateBound/1 (0 ms) [347/350] PageSize/StartupTraceWriterTest.WriteWhileUnboundAndDiscard/0 (0 ms) [348/350] PageSize/StartupTraceWriterTest.WriteWhileUnboundAndDiscard/1 (0 ms) [349/350] PageSize/StartupTraceWriterTest.WriteWhileUnboundAndBind/0 (0 ms) [350/350] PageSize/StartupTraceWriterTest.WriteWhileUnboundAndBind/1 (1 ms) SUCCESS: all tests passed. Tests took 2 seconds. 2019-03-18 16:36:50,162 - root: [DEBUG] Contents of task_output_dir: ['/b/swarming/w/ir/tmp/t/tmpW6NDo8/43abfb288ab50b10'] 2019-03-18 16:36:50,162 - root: [DEBUG] Found shard_json_files: ['/b/swarming/w/ir/tmp/t/tmpW6NDo8/43abfb288ab50b10/output.json'] 2019-03-18 16:36:50,163 - 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": 1552951822066166, "id": "8918605706183820624", "project": "chromium", "tags": ["build_address:luci.chromium.ci/Fuchsia x64/31423", "builder:Fuchsia x64", "buildset:commit/git/b51f4e151b1968c535fa52a7e25f99991504e3df", "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b51f4e151b1968c535fa52a7e25f99991504e3df", "gitiles_ref:refs/heads/master", "scheduler_invocation_id:9084035768280596672", "scheduler_job_id:chromium/Fuchsia x64", "user_agent:luci-scheduler"]}, "hostname": "cr-buildbucket.appspot.com"}, "buildername": "Fuchsia x64", "buildnumber": 31423, "got_angle_revision": "38e282570676f3e434482f8a02ea539b286fd1fa", "got_dawn_revision": "fef613365afe5234c1bae9fe33c3d30698d27078", "got_nacl_revision": "1ed9878b8eb68c1e0d0268754dafcf83e747fa8f", "got_revision": "b51f4e151b1968c535fa52a7e25f99991504e3df", "got_revision_cp": "refs/heads/master@{#641784}", "got_swarming_client_revision": "7a61cf37d6a0163f0ec02d495289a1d038e62457", "got_v8_revision": "36585c1b6b1fd69d093706a6c4ad9775506a7885", "got_v8_revision_cp": "refs/heads/7.5.62@{#1}", "got_webrtc_revision": "7dbc0eb2efa4708da5e3e44d2a15bfdee4ceb474", "got_webrtc_revision_cp": "refs/heads/master@{#27160}", "mastername": "chromium.linux", "path_config": "generic", "recipe": "chromium", "repository": "https://chromium.googlesource.com/chromium/src.git", "revision": "b51f4e151b1968c535fa52a7e25f99991504e3df"} --summary-json /b/swarming/w/ir/tmp/t/tmpEQcgaa.json --task-output-dir /b/swarming/w/ir/tmp/t/tmpW6NDo8 -o /b/swarming/w/ir/tmp/t/tmphMqRl1.json /b/swarming/w/ir/tmp/t/tmpW6NDo8/43abfb288ab50b10/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": 1552951822066166, "id": "8918605706183820624", "project": "chromium", "tags": ["build_address:luci.chromium.ci/Fuchsia x64/31423", "builder:Fuchsia x64", "buildset:commit/git/b51f4e151b1968c535fa52a7e25f99991504e3df", "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b51f4e151b1968c535fa52a7e25f99991504e3df", "gitiles_ref:refs/heads/master", "scheduler_invocation_id:9084035768280596672", "scheduler_job_id:chromium/Fuchsia x64", "user_agent:luci-scheduler"]}, "hostname": "cr-buildbucket.appspot.com"}, "buildername": "Fuchsia x64", "buildnumber": 31423, "got_angle_revision": "38e282570676f3e434482f8a02ea539b286fd1fa", "got_dawn_revision": "fef613365afe5234c1bae9fe33c3d30698d27078", "got_nacl_revision": "1ed9878b8eb68c1e0d0268754dafcf83e747fa8f", "got_revision": "b51f4e151b1968c535fa52a7e25f99991504e3df", "got_revision_cp": "refs/heads/master@{#641784}", "got_swarming_client_revision": "7a61cf37d6a0163f0ec02d495289a1d038e62457", "got_v8_revision": "36585c1b6b1fd69d093706a6c4ad9775506a7885", "got_v8_revision_cp": "refs/heads/7.5.62@{#1}", "got_webrtc_revision": "7dbc0eb2efa4708da5e3e44d2a15bfdee4ceb474", "got_webrtc_revision_cp": "refs/heads/master@{#27160}", "mastername": "chromium.linux", "path_config": "generic", "recipe": "chromium", "repository": "https://chromium.googlesource.com/chromium/src.git", "revision": "b51f4e151b1968c535fa52a7e25f99991504e3df"}', '--summary-json', '/b/swarming/w/ir/tmp/t/tmpEQcgaa.json', '--task-output-dir', '/b/swarming/w/ir/tmp/t/tmpW6NDo8', '-o', '/b/swarming/w/ir/tmp/t/tmphMqRl1.json', '/b/swarming/w/ir/tmp/t/tmpW6NDo8/43abfb288ab50b10/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": 1552951822066166, "id": "8918605706183820624", "project": "chromium", "tags": ["build_address:luci.chromium.ci/Fuchsia x64/31423", "builder:Fuchsia x64", "buildset:commit/git/b51f4e151b1968c535fa52a7e25f99991504e3df", "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b51f4e151b1968c535fa52a7e25f99991504e3df", "gitiles_ref:refs/heads/master", "scheduler_invocation_id:9084035768280596672", "scheduler_job_id:chromium/Fuchsia x64", "user_agent:luci-scheduler"]}, "hostname": "cr-buildbucket.appspot.com"}, "buildername": "Fuchsia x64", "buildnumber": 31423, "got_angle_revision": "38e282570676f3e434482f8a02ea539b286fd1fa", "got_dawn_revision": "fef613365afe5234c1bae9fe33c3d30698d27078", "got_nacl_revision": "1ed9878b8eb68c1e0d0268754dafcf83e747fa8f", "got_revision": "b51f4e151b1968c535fa52a7e25f99991504e3df", "got_revision_cp": "refs/heads/master@{#641784}", "got_swarming_client_revision": "7a61cf37d6a0163f0ec02d495289a1d038e62457", "got_v8_revision": "36585c1b6b1fd69d093706a6c4ad9775506a7885", "got_v8_revision_cp": "refs/heads/7.5.62@{#1}", "got_webrtc_revision": "7dbc0eb2efa4708da5e3e44d2a15bfdee4ceb474", "got_webrtc_revision_cp": "refs/heads/master@{#27160}", "mastername": "chromium.linux", "path_config": "generic", "recipe": "chromium", "repository": "https://chromium.googlesource.com/chromium/src.git", "revision": "b51f4e151b1968c535fa52a7e25f99991504e3df"}', '--summary-json', '/b/swarming/w/ir/tmp/t/tmpEQcgaa.json', '--task-output-dir', '/b/swarming/w/ir/tmp/t/tmpW6NDo8', '-o', '/b/swarming/w/ir/tmp/t/tmphMqRl1.json', '/b/swarming/w/ir/tmp/t/tmpW6NDo8/43abfb288ab50b10/output.json'] returned exit code 0