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

Running command: /b/s/w/ir/cache/vpython/4f3597/bin/python scons.py --verbose -k -j1 --mode=nacl,opt-host platform=x86-64 bitcode=1 skip_trusted_tests=1 use_sz=1 large_tests scons: Reading SConscript files ... ====================================================================== SCONS ARGS:['scons.py', '--verbose', '-k', '-j1', '--mode=nacl,opt-host', 'platform=x86-64', 'bitcode=1', 'skip_trusted_tests=1', 'use_sz=1', 'large_tests'] ====================================================================== ====================================================================== Python Info: ====================================================================== darwin 2.7.14+chromium14 (6cc0aad35b-dirty:6cc0aad35b, Oct 3 2017, 17:23:56) [GCC 4.2.1 Compatible Apple LLVM 8.0.0 (clang-800.0.38)] ====================================================================== ENV: ====================================================================== PATH /b/s/w/ir/cache/vpython/4f3597/bin:/b/s/w/ir/cipd_bin_packages:/b/s/w/ir/cipd_bin_packages/bin:/b/s/cipd_cache/bin:/opt/local/bin:/opt/local/sbin:/usr/local/sbin:/usr/local/git/bin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/b/s/w/ir/kitchen-checkout/depot_tools ====================================================================== OS: ====================================================================== kern.ostype: Darwin kern.osrelease: 17.5.0 kern.osrevision: 199506 kern.version: Darwin Kernel Version 17.5.0: Mon Mar 5 22:24:32 PST 2018; root:xnu-4570.51.1~1/RELEASE_X86_64 kern.posix1version: 200112 kern.osversion: 17E199 kern.osproductversion: 10.13.4 ====================================================================== CPU: ====================================================================== hw.machine: x86_64 hw.model: VMware7,1 hw.ncpu: 8 hw.ncpu: 8 hw.activecpu: 8 hw.physicalcpu: 8 hw.physicalcpu_max: 8 hw.logicalcpu: 8 hw.logicalcpu_max: 8 hw.cputype: 7 hw.cpusubtype: 4 hw.cpu64bit_capable: 1 hw.cpufamily: 526772277 hw.cpufrequency: 2574000000 hw.cpufrequency_min: 2574000000 hw.cpufrequency_max: 2574000000 ====================================================================== RAM: ====================================================================== hw.memsize: 17179869184 ====================================================================== LOAD: ====================================================================== TBD ====================================================================== UPTIME: ====================================================================== kern.boottime: { sec = 1534411521, usec = 775376 } Thu Aug 16 02:25:21 2018 ====================================================================== The following environments have been configured ====================================================================== NACL_BUILD_FAMILY: UNTRUSTED BUILD_TYPE: nacl TARGET_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-64-pnacl-subzero-pexe-clang OBJ_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-64-pnacl-subzero-pexe-clang/obj BUILD_TYPE_DESCRIPTION: NaCl module build BUILD_OS: BUILD_ARCHITECTURE: x86 BUILD_SUBARCH: 64 TARGET_OS: TARGET_ARCHITECTURE: x86 TARGET_SUBARCH: 64 CC: /b/s/w/ir/kitchen-workdir/native_client/toolchain/mac_x86/pnacl_newlib_raw/bin/pnacl-clang -std=gnu99 ASPPCOM: /b/s/w/ir/kitchen-workdir/native_client/toolchain/mac_x86/pnacl_newlib_raw/bin/pnacl-clang -std=gnu99 -D__STDC_LIMIT_MACROS=1 -D__STDC_FORMAT_MACROS=1 -D_GNU_SOURCE=1 -D_POSIX_C_SOURCE=199506 -D_XOPEN_SOURCE=600 -DDYNAMIC_ANNOTATIONS_ENABLED=1 -DDYNAMIC_ANNOTATIONS_PREFIX=NACL_ -DNACL_BUILD_ARCH=x86 -DNACL_BUILD_SUBARCH=64 -I/b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-64-pnacl-subzero-pexe-clang/include -I/b/s/w/ir/kitchen-workdir -c -o UNKNOWN COMPILER NACL_BUILD_FAMILY: TRUSTED BUILD_TYPE: opt-mac TARGET_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-mac-x86-64 OBJ_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-mac-x86-64/obj BUILD_TYPE_DESCRIPTION: MacOS opt build BUILD_OS: BUILD_ARCHITECTURE: x86 BUILD_SUBARCH: 64 TARGET_OS: TARGET_ARCHITECTURE: x86 TARGET_SUBARCH: 64 CC: /b/s/w/ir/kitchen-workdir/third_party/llvm-build/Release+Asserts/bin/clang ASPPCOM: /b/s/w/ir/kitchen-workdir/third_party/llvm-build/Release+Asserts/bin/clang -isysroot /Applications/Xcode9.3.app/Contents/Developer/Platforms/MacOSX.platform/Developer/SDKs/MacOSX10.13.sdk -mmacosx-version-min=10.6 -m64 -D__STDC_LIMIT_MACROS=1 -D__STDC_FORMAT_MACROS=1 -DNDEBUG -I/b/s/w/ir/kitchen-workdir -c -o UNKNOWN COMPILER NACL_BUILD_FAMILY: UNTRUSTED_IRT BUILD_TYPE: nacl_irt TARGET_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt-x86-64 OBJ_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt-x86-64/obj BUILD_TYPE_DESCRIPTION: NaCl IRT build BUILD_OS: BUILD_ARCHITECTURE: x86 BUILD_SUBARCH: 64 TARGET_OS: TARGET_ARCHITECTURE: x86 TARGET_SUBARCH: 64 CC: /b/s/w/ir/kitchen-workdir/native_client/toolchain/mac_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang ASPPCOM: /b/s/w/ir/kitchen-workdir/native_client/toolchain/mac_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang -m64 -D__STDC_LIMIT_MACROS=1 -D__STDC_FORMAT_MACROS=1 -D_GNU_SOURCE=1 -D_POSIX_C_SOURCE=199506 -D_XOPEN_SOURCE=600 -DDYNAMIC_ANNOTATIONS_ENABLED=1 -DDYNAMIC_ANNOTATIONS_PREFIX=NACL_ -DNACL_BUILD_ARCH=x86 -DNACL_BUILD_SUBARCH=64 -I/b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt-x86-64/include -I/b/s/w/ir/kitchen-workdir -I/b/s/w/ir/kitchen-workdir/native_client/src/untrusted/pthread -c -o UNKNOWN COMPILER *** BROKEN run_ncval_annotate_test *** BROKEN run_nanosleep_test *** BROKEN run_timefuncs_test *** BROKEN run_raw_timefuncs_test *** BROKEN run_main_thread_pthread_exit_test2 *** BROKEN run_overflowinline_test *** BROKEN run_initfini_static_test *** SKIPPING opt-mac-64 : run_package_version_tests *** SKIPPING opt-mac-64 : run_pynacl_tests *** SKIPPING opt-mac-64 : run_gio_test *** SKIPPING opt-mac-64 : run_gprintf_test *** SKIPPING opt-mac-64 : run_sigpipe_test *** SKIPPING opt-mac-64 : run_platform_tests *** SKIPPING opt-mac-64 : run_nacl_semaphore_test *** SKIPPING opt-mac-64 : run_nacl_host_dir_test *** SKIPPING opt-mac-64 : run_nacl_clock_test *** SKIPPING opt-mac-64 : run_nacl_clock_cputime_test *** SKIPPING opt-mac-64 : run_nacl_sync_lock_lock_test *** SKIPPING opt-mac-64 : run_nacl_sync_lock_trylock_test *** SKIPPING opt-mac-64 : run_nacl_sync_trylock_lock_test *** SKIPPING opt-mac-64 : run_nacl_sync_trylock_trylock_test *** SKIPPING opt-mac-64 : run_nacl_host_desc_mmap_test *** SKIPPING opt-mac-64 : run_nacl_host_desc_pread_pwrite_test *** SKIPPING opt-mac-64 : run_nacl_host_desc_big_file_test *** SKIPPING opt-mac-64 : run_cpu_x86_test *** SKIPPING opt-mac-64 : run_gdb_rsp_tests *** SKIPPING opt-mac-64 : run_transport_ipc_test *** SKIPPING opt-mac-64 : run_nacl_desc_quota_test *** SKIPPING opt-mac-64 : run_metadata_test *** SKIPPING opt-mac-64 : run_nacl_desc_io_alloc_ctor_test *** SKIPPING opt-mac-64 : run_nacl_fi_test *** SKIPPING opt-mac-64 : run_nacl_interval_list_test *** SKIPPING opt-mac-64 : run_nacl_interval_tree_test *** SKIPPING opt-mac-64 : run_nacl_interval_list_random_test *** SKIPPING opt-mac-64 : run_nacl_interval_tree_random_test *** SKIPPING opt-mac-64 : run_nacl_interval_multi_test *** SKIPPING opt-mac-64 : run_nacl_interval_multi_reg_test *** SKIPPING opt-mac-64 : run_nacl_base_test *** SKIPPING opt-mac-64 : run_nacl_min_max_test *** SKIPPING opt-mac-64 : run_nacl_min_max_cpp_test *** SKIPPING opt-mac-64 : run_nacl_perf_counter_test *** SKIPPING opt-mac-64 : run_platform_qual_test *** SKIPPING opt-mac-64 : run_cpuwhitelist_test *** SKIPPING opt-mac-64 : run_service_runtime_tests *** SKIPPING opt-mac-64 : run_format_string_test *** SKIPPING opt-mac-64 : run_trusted_mmap_test *** BROKEN run_nacl_sync_cond_test *** SKIPPING opt-mac-64 : run_env_cleanser_test *** SKIPPING opt-mac-64 : run_nacl_resource_test *** SKIPPING opt-mac-64 : run_check_test *** SKIPPING opt-mac-64 : run_check_test_death *** SKIPPING opt-mac-64 : run_dcheck_test_death *** SKIPPING opt-mac-64 : run_check_test_always_death *** SKIPPING opt-mac-64 : run_check_test_always_death_abort *** SKIPPING opt-mac-64 : run_dcheck_test_never_death *** SKIPPING opt-mac-64 : run_dcheck_test_always_death *** SKIPPING opt-mac-64 : run_sel_ldr_exe_not_found_test *** SKIPPING opt-mac-64 : run_fuzz_nullptr_test *** SKIPPING opt-mac-64 : run_unaligned_data_test *** SKIPPING opt-mac-64 : run_unaligned_data_irt_test *** SKIPPING opt-mac-64 : run_hello_x32_test *** SKIPPING opt-mac-64 : run_service_runtime_hello_world_test *** SKIPPING opt-mac-64 : run_old_abi_death_test *** SKIPPING opt-mac-64 : run_integer_overflow_while_madvising_death_test *** SKIPPING opt-mac-64 : run_negative_hole_death_test *** SKIPPING opt-mac-64 : run_rodata_data_overlap_death_test *** SKIPPING opt-mac-64 : run_data_not_last_death_test SKIPPING test text_overlaps_rodata SKIPPING test text_overlaps_data *** SKIPPING opt-mac-64 : run_text_too_big_death_test *** SKIPPING opt-mac-64 : run_sel_ldr_thread_death_test *** SKIPPING opt-mac-64 : run_nacl_error_gio_test *** SKIPPING opt-mac-64 : run_nacl_error_log_test *** SKIPPING opt-mac-64 : run_dyn_array_test *** SKIPPING opt-mac-64 : run_validation_cache_test *** SKIPPING opt-mac-64 : run_validator_debug_stub_test *** SKIPPING opt-mac-64 : run_disable_nontemporals_test *** SKIPPING opt-mac-64 : run_validation_rewrite_test *** SKIPPING opt-mac-64 : run_gen_dfa_test *** SKIPPING opt-mac-64 : run_trie_test *** SKIPPING opt-mac-64 : run_proof_tools_test *** SKIPPING opt-mac-64 : run_validator_py_test *** SKIPPING opt-mac-64 : run_rdfa_targeted_tests_32 *** SKIPPING opt-mac-64 : run_dis_section_test_32 *** SKIPPING opt-mac-64 : run_spec_val_test_32 *** SKIPPING opt-mac-64 : run_rdfa_targeted_tests_64 *** SKIPPING opt-mac-64 : run_dis_section_test_64 *** SKIPPING opt-mac-64 : run_spec_val_test_64 *** SKIPPING opt-mac-64 : run_nacl_file_lock_all_test *** SKIPPING opt-mac-64 : run_nacl_file_lock_any_test *** SKIPPING opt-mac-64 : run_nacl_file_lock_t3f2_test *** SKIPPING opt-mac-64 : run_nacl_file_lock_t3f3_test *** SKIPPING opt-mac-64 : run_nacl_file_lock_all_real_test *** SKIPPING opt-mac-64 : run_nacl_file_lock_any_real_test *** SKIPPING opt-mac-64 : run_nacl_file_lock_t3f2_real_test *** SKIPPING opt-mac-64 : run_nacl_file_lock_t3f3_real_test *** SKIPPING opt-mac-64 : run_trusted_performance_test *** SKIPPING opt-mac-64 : run_python_version_test *** SKIPPING opt-mac-64 : run_tool_tests *** SKIPPING opt-mac-64 : run_nacl_close_test *** SKIPPING opt-mac-64 : run_double_nacl_close_test *** SKIPPING opt-mac-64 : run_nacl_create_memory_object_test *** SKIPPING opt-mac-64 : run_nacl_thread_create_joinable_test *** SKIPPING opt-mac-64 : run_atomic_ops_test *** SKIPPING opt-mac-64 : run_bits_test *** SKIPPING opt-mac-64 : run_kernel_version_test *** SKIPPING opt-mac-64 : run_nacl_memory_test *** SKIPPING opt-mac-64 : run_toolchain_python_tests *** SKIPPING nacl_irt-64 : run_irt_thread_test There are 8 broken tests. ====================================================================== B U I L D - O U T P U T: ====================================================================== scons: done reading SConscript files. scons: Building targets ... /b/s/w/ir/cache/vpython/4f3597/bin/python tools/command_tester.py --name pnacl_newlib.run_benchmark_test --time_warning 10 --time_error 100 --perf_env_description x86-64_pnacl_sz_newlib_static --subarch 64 --arch x86 --time_error 1000 --capture_output 0 --output_stamp /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-64-pnacl-subzero-pexe-clang/test_results/benchmark_test.out scons-out/opt-mac-x86-64/staging/sel_ldr -- scons-out/nacl-x86-64-pnacl-subzero-pexe-clang/obj/tests/benchmark/benchmark_test.nexe x86-64_pnacl_sz_newlib_static [ RUN ] pnacl_newlib.run_benchmark_test scons-out/opt-mac-x86-64/staging/sel_ldr -- scons-out/nacl-x86-64-pnacl-subzero-pexe-clang/obj/tests/benchmark/benchmark_test.nexe x86-64_pnacl_sz_newlib_static [37563,2437280640:11:27:14.922176] Native Client module will be loaded at base address 0x000014f300000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_pnacl_sz_newlib_static= {2.309560, 0.003210} seconds --------------------------------------------------------------- Done running benchmark suite. WARNING: should have taken less than 10.000000 secs [ OK ] pnacl_newlib.run_benchmark_test (54500 ms) /b/s/w/ir/cache/vpython/4f3597/bin/python tools/command_tester.py --name pnacl_newlib.run_clock_cputime_test --time_warning 60 --time_error 600 --perf_env_description x86-64_pnacl_sz_newlib_static --subarch 64 --arch x86 --output_stamp /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-64-pnacl-subzero-pexe-clang/test_results/clock_cputime_test.out scons-out/opt-mac-x86-64/staging/sel_ldr -- scons-out/nacl-x86-64-pnacl-subzero-pexe-clang/obj/tests/clock/clock_test.nexe -c [ RUN ] pnacl_newlib.run_clock_cputime_test scons-out/opt-mac-x86-64/staging/sel_ldr -- scons-out/nacl-x86-64-pnacl-subzero-pexe-clang/obj/tests/clock/clock_test.nexe -c WARNING: should have taken less than 60.000000 secs [ OK ] pnacl_newlib.run_clock_cputime_test (66090 ms) doSizeCheck(["scons-out/nacl-x86-64-pnacl-subzero-pexe-clang/obj/tests/hello_world/hello_world_size.out"], ["scons-out/nacl-x86-64-pnacl-subzero-pexe-clang/obj/tests/hello_world/hello_world.nonfinal.pexe"]) RESULT hello_world_size.out: x86-64_pnacl_sz_newlib_static= 354.035 KB RESULT hello_world_size.out: ZIPPED_x86-64_pnacl_sz_newlib_static= 232.617 KB /b/s/w/ir/cache/vpython/4f3597/bin/python tools/command_tester.py --name pnacl_newlib.run_performance_test --time_warning 10 --time_error 100 --perf_env_description x86-64_pnacl_sz_newlib_static --subarch 64 --arch x86 --capture_output 0 --output_stamp /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-64-pnacl-subzero-pexe-clang/test_results/performance_test.out scons-out/opt-mac-x86-64/staging/sel_ldr -e -- scons-out/nacl-x86-64-pnacl-subzero-pexe-clang/obj/tests/performance/performance_test.nexe x86-64_pnacl_sz_newlib_static [ RUN ] pnacl_newlib.run_performance_test scons-out/opt-mac-x86-64/staging/sel_ldr -e -- scons-out/nacl-x86-64-pnacl-subzero-pexe-clang/obj/tests/performance/performance_test.nexe x86-64_pnacl_sz_newlib_static [37569,2437280640:11:27:35.784386] Native Client module will be loaded at base address 0x0000728200000000 TestNull: Measuring real time: 0.008 usec (7.59e-09 sec) per iteration: 7.59e-07 sec for 100 iterations 0.002 usec (1.642e-09 sec) per iteration: 1.642e-06 sec for 1000 iterations 0.001 usec (1.4141e-09 sec) per iteration: 1.4141e-05 sec for 10000 iterations 0.002 usec (1.67975e-09 sec) per iteration: 0.118786 sec for 70716356 iterations 0.002 usec (1.5816e-09 sec) per iteration: 0.111845 sec for 70716356 iterations 0.002 usec (1.56094e-09 sec) per iteration: 0.110384 sec for 70716356 iterations 0.002 usec (1.61693e-09 sec) per iteration: 0.114344 sec for 70716356 iterations 0.002 usec (1.71425e-09 sec) per iteration: 0.121225 sec for 70716356 iterations mean: 0.001631 usec stddev: 0.000058 usec relative stddev: 3.56% RESULT TestNull: x86-64_pnacl_sz_newlib_static= {0.001631, 0.000058} us TestNaClSyscall: Measuring real time: 0.109 usec (1.0914e-07 sec) per iteration: 1.0914e-05 sec for 100 iterations 0.102 usec (1.015e-07 sec) per iteration: 0.093 sec for 916254 iterations 0.111 usec (1.10687e-07 sec) per iteration: 0.101418 sec for 916254 iterations 0.102 usec (1.0175e-07 sec) per iteration: 0.0932284 sec for 916254 iterations 0.102 usec (1.0166e-07 sec) per iteration: 0.0931465 sec for 916254 iterations 0.106 usec (1.0609e-07 sec) per iteration: 0.0972054 sec for 916254 iterations mean: 0.104337 usec stddev: 0.003614 usec relative stddev: 3.46% RESULT TestNaClSyscall: x86-64_pnacl_sz_newlib_static= {0.104337, 0.003614} us TestSetjmpLongjmp: Measuring real time: 0.015 usec (1.505e-08 sec) per iteration: 1.505e-06 sec for 100 iterations 0.011 usec (1.1133e-08 sec) per iteration: 1.1133e-05 sec for 1000 iterations 0.012 usec (1.15137e-08 sec) per iteration: 0.10342 sec for 8982304 iterations 0.011 usec (1.14739e-08 sec) per iteration: 0.103062 sec for 8982304 iterations 0.011 usec (1.14573e-08 sec) per iteration: 0.102913 sec for 8982304 iterations 0.011 usec (1.14178e-08 sec) per iteration: 0.102558 sec for 8982304 iterations 0.011 usec (1.13762e-08 sec) per iteration: 0.102184 sec for 8982304 iterations mean: 0.011448 usec stddev: 0.000047 usec relative stddev: 0.41% RESULT TestSetjmpLongjmp: x86-64_pnacl_sz_newlib_static= {0.011448, 0.000047} us TestClockGetTime: Measuring real time: 0.152 usec (1.5214e-07 sec) per iteration: 1.5214e-05 sec for 100 iterations 0.155 usec (1.55204e-07 sec) per iteration: 0.102014 sec for 657289 iterations 0.157 usec (1.56942e-07 sec) per iteration: 0.103156 sec for 657289 iterations 0.155 usec (1.54771e-07 sec) per iteration: 0.101729 sec for 657289 iterations 0.155 usec (1.54684e-07 sec) per iteration: 0.101672 sec for 657289 iterations 0.160 usec (1.59606e-07 sec) per iteration: 0.104907 sec for 657289 iterations mean: 0.156242 usec stddev: 0.001869 usec relative stddev: 1.20% RESULT TestClockGetTime: x86-64_pnacl_sz_newlib_static= {0.156242, 0.001869} us TestTlsVariable: Measuring real time: 0.017 usec (1.738e-08 sec) per iteration: 1.738e-06 sec for 100 iterations 0.012 usec (1.2486e-08 sec) per iteration: 1.2486e-05 sec for 1000 iterations 0.006 usec (6.01433e-09 sec) per iteration: 0.0481686 sec for 8008970 iterations 0.006 usec (6.33961e-09 sec) per iteration: 0.0507737 sec for 8008970 iterations 0.006 usec (5.98282e-09 sec) per iteration: 0.0479162 sec for 8008970 iterations 0.006 usec (6.01874e-09 sec) per iteration: 0.0482039 sec for 8008970 iterations 0.006 usec (6.05573e-09 sec) per iteration: 0.0485002 sec for 8008970 iterations mean: 0.006082 usec stddev: 0.000131 usec relative stddev: 2.15% RESULT TestTlsVariable: x86-64_pnacl_sz_newlib_static= {0.006082, 0.000131} us TestMmapAnonymous: Measuring real time: 8.032 usec (8.03232e-06 sec) per iteration: 0.000803232 sec for 100 iterations 7.560 usec (7.55978e-06 sec) per iteration: 0.0941117 sec for 12449 iterations 7.676 usec (7.67632e-06 sec) per iteration: 0.0955625 sec for 12449 iterations 7.610 usec (7.61024e-06 sec) per iteration: 0.0947398 sec for 12449 iterations 7.865 usec (7.86483e-06 sec) per iteration: 0.0979093 sec for 12449 iterations 7.589 usec (7.58891e-06 sec) per iteration: 0.0944743 sec for 12449 iterations mean: 7.660016 usec stddev: 0.109360 usec relative stddev: 1.43% RESULT TestMmapAnonymous: x86-64_pnacl_sz_newlib_static= {7.660016, 0.109360} us TestAtomicIncrement: Measuring real time: 0.013 usec (1.315e-08 sec) per iteration: 1.315e-06 sec for 100 iterations 0.010 usec (9.882e-09 sec) per iteration: 9.882e-06 sec for 1000 iterations 0.010 usec (9.6865e-09 sec) per iteration: 9.6865e-05 sec for 10000 iterations 0.010 usec (9.55488e-09 sec) per iteration: 0.0986412 sec for 10323646 iterations 0.010 usec (9.61159e-09 sec) per iteration: 0.0992266 sec for 10323646 iterations 0.009 usec (9.41601e-09 sec) per iteration: 0.0972076 sec for 10323646 iterations 0.009 usec (9.13906e-09 sec) per iteration: 0.0943484 sec for 10323646 iterations 0.010 usec (9.77926e-09 sec) per iteration: 0.100958 sec for 10323646 iterations mean: 0.009500 usec stddev: 0.000215 usec relative stddev: 2.26% RESULT TestAtomicIncrement: x86-64_pnacl_sz_newlib_static= {0.009500, 0.000215} us TestUncontendedMutexLock: Measuring real time: 0.028 usec (2.767e-08 sec) per iteration: 2.767e-06 sec for 100 iterations 0.022 usec (2.1598e-08 sec) per iteration: 2.1598e-05 sec for 1000 iterations 0.021 usec (2.10095e-08 sec) per iteration: 0.0972751 sec for 4630058 iterations 0.021 usec (2.08927e-08 sec) per iteration: 0.0967344 sec for 4630058 iterations 0.021 usec (2.1145e-08 sec) per iteration: 0.0979027 sec for 4630058 iterations 0.021 usec (2.06973e-08 sec) per iteration: 0.0958299 sec for 4630058 iterations 0.022 usec (2.20092e-08 sec) per iteration: 0.101904 sec for 4630058 iterations mean: 0.021151 usec stddev: 0.000454 usec relative stddev: 2.14% RESULT TestUncontendedMutexLock: x86-64_pnacl_sz_newlib_static= {0.021151, 0.000454} us TestCondvarSignalNoOp: Measuring real time: 0.387 usec (3.8718e-07 sec) per iteration: 3.8718e-05 sec for 100 iterations 0.157 usec (1.56784e-07 sec) per iteration: 0.0404938 sec for 258277 iterations 0.154 usec (1.53615e-07 sec) per iteration: 0.0396752 sec for 258277 iterations 0.156 usec (1.56143e-07 sec) per iteration: 0.0403283 sec for 258277 iterations 0.148 usec (1.48262e-07 sec) per iteration: 0.0382926 sec for 258277 iterations 0.161 usec (1.61011e-07 sec) per iteration: 0.0415856 sec for 258277 iterations mean: 0.155163 usec stddev: 0.004191 usec relative stddev: 2.70% RESULT TestCondvarSignalNoOp: x86-64_pnacl_sz_newlib_static= {0.155163, 0.004191} us TestThreadCreateAndJoin: Measuring real time: 69.626 usec (6.96256e-05 sec) per iteration: 0.00696256 sec for 100 iterations 61.483 usec (6.14827e-05 sec) per iteration: 0.0882891 sec for 1436 iterations 60.009 usec (6.00088e-05 sec) per iteration: 0.0861726 sec for 1436 iterations 59.324 usec (5.93242e-05 sec) per iteration: 0.0851895 sec for 1436 iterations 59.639 usec (5.96387e-05 sec) per iteration: 0.0856411 sec for 1436 iterations 59.569 usec (5.95692e-05 sec) per iteration: 0.0855413 sec for 1436 iterations mean: 60.004693 usec stddev: 0.770881 usec relative stddev: 1.28% RESULT TestThreadCreateAndJoin: x86-64_pnacl_sz_newlib_static= {60.004693, 0.770881} us TestThreadWakeup: Measuring real time: 30.043 usec (3.00434e-05 sec) per iteration: 0.00300434 sec for 100 iterations 29.130 usec (2.913e-05 sec) per iteration: 0.0969446 sec for 3328 iterations 29.335 usec (2.93348e-05 sec) per iteration: 0.0976263 sec for 3328 iterations 29.252 usec (2.92522e-05 sec) per iteration: 0.0973513 sec for 3328 iterations 28.895 usec (2.88955e-05 sec) per iteration: 0.0961642 sec for 3328 iterations 29.482 usec (2.9482e-05 sec) per iteration: 0.098116 sec for 3328 iterations mean: 29.218896 usec stddev: 0.198121 usec relative stddev: 0.68% RESULT TestThreadWakeup: x86-64_pnacl_sz_newlib_static= {29.218896, 0.198121} us TestCatchingFault: Measuring real time: 31.945 usec (3.19454e-05 sec) per iteration: 0.00319454 sec for 100 iterations 29.581 usec (2.95806e-05 sec) per iteration: 0.0925873 sec for 3130 iterations 29.907 usec (2.9907e-05 sec) per iteration: 0.093609 sec for 3130 iterations 30.352 usec (3.03521e-05 sec) per iteration: 0.0950021 sec for 3130 iterations 30.045 usec (3.00445e-05 sec) per iteration: 0.0940394 sec for 3130 iterations 29.943 usec (2.99428e-05 sec) per iteration: 0.093721 sec for 3130 iterations mean: 29.965421 usec stddev: 0.248102 usec relative stddev: 0.83% RESULT TestCatchingFault: x86-64_pnacl_sz_newlib_static= {29.965421, 0.248102} us TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 61.876 usec (6.18764e-05 sec) per iteration: 0.00618764 sec for 100 iterations 60.082 usec (6.00821e-05 sec) per iteration: 0.0970926 sec for 1616 iterations 60.323 usec (6.0323e-05 sec) per iteration: 0.0974819 sec for 1616 iterations 60.692 usec (6.0692e-05 sec) per iteration: 0.0980783 sec for 1616 iterations 60.147 usec (6.01466e-05 sec) per iteration: 0.0971969 sec for 1616 iterations 60.346 usec (6.0346e-05 sec) per iteration: 0.0975191 sec for 1616 iterations mean: 60.317925 usec stddev: 0.212480 usec relative stddev: 0.35% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_pnacl_sz_newlib_static= {60.317925, 0.212480} us [ OK ] pnacl_newlib.run_performance_test (6170 ms) scons: done building targets. ********************************************************************** COMMAND EXECUTION REPORT ********************************************************************** 3 command_tester.py 1 doSizeCheck ********************************************************************** ENVIRONMENT USAGE REPORT ********************************************************************** 4 nacl-x86-64-pnacl-subzero-pexe-clang Command return code: 0