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

Running command: /b/s/w/ir/cache/vpython/7c7ecd/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/7c7ecd/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 = 1550174907, usec = 305576 } Thu Feb 14 12:08:27 2019 ====================================================================== 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 /b/s/w/ir/cache/osx_sdk/XCode.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/7c7ecd/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 [23831,2579817344:15:32:54.536995] Native Client module will be loaded at base address 0x000061bc00000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_pnacl_sz_newlib_static= {2.320820, 0.003620} seconds --------------------------------------------------------------- Done running benchmark suite. WARNING: should have taken less than 10.000000 secs [ OK ] pnacl_newlib.run_benchmark_test (54640 ms) /b/s/w/ir/cache/vpython/7c7ecd/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 (67639 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= 353.605 KB RESULT hello_world_size.out: ZIPPED_x86-64_pnacl_sz_newlib_static= 232.463 KB /b/s/w/ir/cache/vpython/7c7ecd/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 [23837,2579817344:15:33:15.828594] Native Client module will be loaded at base address 0x00006a6d00000000 TestNull: Measuring real time: 0.008 usec (7.78e-09 sec) per iteration: 7.78e-07 sec for 100 iterations 0.002 usec (2.274e-09 sec) per iteration: 2.274e-06 sec for 1000 iterations 0.002 usec (2.0253e-09 sec) per iteration: 2.0253e-05 sec for 10000 iterations 0.002 usec (1.83074e-09 sec) per iteration: 0.0903936 sec for 49375401 iterations 0.002 usec (1.7468e-09 sec) per iteration: 0.0862488 sec for 49375401 iterations 0.002 usec (1.70583e-09 sec) per iteration: 0.0842259 sec for 49375401 iterations 0.002 usec (1.65181e-09 sec) per iteration: 0.0815589 sec for 49375401 iterations 0.002 usec (1.6581e-09 sec) per iteration: 0.0818694 sec for 49375401 iterations mean: 0.001719 usec stddev: 0.000066 usec relative stddev: 3.83% RESULT TestNull: x86-64_pnacl_sz_newlib_static= {0.001719, 0.000066} us TestNaClSyscall: Measuring real time: 0.107 usec (1.0691e-07 sec) per iteration: 1.0691e-05 sec for 100 iterations 0.103 usec (1.0307e-07 sec) per iteration: 0.0964083 sec for 935366 iterations 0.103 usec (1.02816e-07 sec) per iteration: 0.0961705 sec for 935366 iterations 0.104 usec (1.03589e-07 sec) per iteration: 0.0968937 sec for 935366 iterations 0.103 usec (1.03127e-07 sec) per iteration: 0.0964613 sec for 935366 iterations 0.103 usec (1.02558e-07 sec) per iteration: 0.0959295 sec for 935366 iterations mean: 0.103032 usec stddev: 0.000344 usec relative stddev: 0.33% RESULT TestNaClSyscall: x86-64_pnacl_sz_newlib_static= {0.103032, 0.000344} us TestSetjmpLongjmp: Measuring real time: 0.016 usec (1.619e-08 sec) per iteration: 1.619e-06 sec for 100 iterations 0.012 usec (1.1871e-08 sec) per iteration: 1.1871e-05 sec for 1000 iterations 0.012 usec (1.16446e-08 sec) per iteration: 0.0980931 sec for 8423890 iterations 0.012 usec (1.16392e-08 sec) per iteration: 0.0980473 sec for 8423890 iterations 0.012 usec (1.15738e-08 sec) per iteration: 0.0974963 sec for 8423890 iterations 0.012 usec (1.16591e-08 sec) per iteration: 0.0982147 sec for 8423890 iterations 0.012 usec (1.16603e-08 sec) per iteration: 0.0982252 sec for 8423890 iterations mean: 0.011635 usec stddev: 0.000032 usec relative stddev: 0.27% RESULT TestSetjmpLongjmp: x86-64_pnacl_sz_newlib_static= {0.011635, 0.000032} us TestClockGetTime: Measuring real time: 0.162 usec (1.624e-07 sec) per iteration: 1.624e-05 sec for 100 iterations 0.157 usec (1.57054e-07 sec) per iteration: 0.0967079 sec for 615763 iterations 0.158 usec (1.5843e-07 sec) per iteration: 0.0975551 sec for 615763 iterations 0.162 usec (1.62217e-07 sec) per iteration: 0.0998872 sec for 615763 iterations 0.159 usec (1.59292e-07 sec) per iteration: 0.098086 sec for 615763 iterations 0.157 usec (1.57409e-07 sec) per iteration: 0.0969264 sec for 615763 iterations mean: 0.158880 usec stddev: 0.001844 usec relative stddev: 1.16% RESULT TestClockGetTime: x86-64_pnacl_sz_newlib_static= {0.158880, 0.001844} us TestTlsVariable: Measuring real time: 0.014 usec (1.376e-08 sec) per iteration: 1.376e-06 sec for 100 iterations 0.009 usec (8.893e-09 sec) per iteration: 8.893e-06 sec for 1000 iterations 0.009 usec (8.593e-09 sec) per iteration: 8.593e-05 sec for 10000 iterations 0.006 usec (6.33885e-09 sec) per iteration: 0.0737676 sec for 11637379 iterations 0.006 usec (6.02841e-09 sec) per iteration: 0.0701549 sec for 11637379 iterations 0.006 usec (6.36622e-09 sec) per iteration: 0.0740862 sec for 11637379 iterations 0.006 usec (6.04083e-09 sec) per iteration: 0.0702995 sec for 11637379 iterations 0.006 usec (6.07695e-09 sec) per iteration: 0.0707198 sec for 11637379 iterations mean: 0.006170 usec stddev: 0.000150 usec relative stddev: 2.43% RESULT TestTlsVariable: x86-64_pnacl_sz_newlib_static= {0.006170, 0.000150} us TestMmapAnonymous: Measuring real time: 8.411 usec (8.41138e-06 sec) per iteration: 0.000841138 sec for 100 iterations 8.107 usec (8.10708e-06 sec) per iteration: 0.096377 sec for 11888 iterations 8.191 usec (8.19097e-06 sec) per iteration: 0.0973742 sec for 11888 iterations 8.187 usec (8.18703e-06 sec) per iteration: 0.0973275 sec for 11888 iterations 8.205 usec (8.20466e-06 sec) per iteration: 0.097537 sec for 11888 iterations 8.253 usec (8.25256e-06 sec) per iteration: 0.0981064 sec for 11888 iterations mean: 8.188461 usec stddev: 0.046909 usec relative stddev: 0.57% RESULT TestMmapAnonymous: x86-64_pnacl_sz_newlib_static= {8.188461, 0.046909} us TestAtomicIncrement: Measuring real time: 0.014 usec (1.358e-08 sec) per iteration: 1.358e-06 sec for 100 iterations 0.010 usec (9.9e-09 sec) per iteration: 9.9e-06 sec for 1000 iterations 0.010 usec (9.6874e-09 sec) per iteration: 9.6874e-05 sec for 10000 iterations 0.010 usec (9.69408e-09 sec) per iteration: 0.100069 sec for 10322687 iterations 0.010 usec (9.54174e-09 sec) per iteration: 0.0984964 sec for 10322687 iterations 0.010 usec (9.69154e-09 sec) per iteration: 0.100043 sec for 10322687 iterations 0.010 usec (9.69904e-09 sec) per iteration: 0.10012 sec for 10322687 iterations 0.010 usec (9.69793e-09 sec) per iteration: 0.100109 sec for 10322687 iterations mean: 0.009665 usec stddev: 0.000062 usec relative stddev: 0.64% RESULT TestAtomicIncrement: x86-64_pnacl_sz_newlib_static= {0.009665, 0.000062} us TestUncontendedMutexLock: Measuring real time: 0.028 usec (2.759e-08 sec) per iteration: 2.759e-06 sec for 100 iterations 0.022 usec (2.1621e-08 sec) per iteration: 2.1621e-05 sec for 1000 iterations 0.021 usec (2.12207e-08 sec) per iteration: 0.0981484 sec for 4625132 iterations 0.021 usec (2.12269e-08 sec) per iteration: 0.0981772 sec for 4625132 iterations 0.022 usec (2.20286e-08 sec) per iteration: 0.101885 sec for 4625132 iterations 0.021 usec (2.13158e-08 sec) per iteration: 0.0985885 sec for 4625132 iterations 0.021 usec (2.13647e-08 sec) per iteration: 0.0988146 sec for 4625132 iterations mean: 0.021431 usec stddev: 0.000304 usec relative stddev: 1.42% RESULT TestUncontendedMutexLock: x86-64_pnacl_sz_newlib_static= {0.021431, 0.000304} us TestCondvarSignalNoOp: Measuring real time: 0.246 usec (2.4645e-07 sec) per iteration: 2.4645e-05 sec for 100 iterations 0.152 usec (1.52011e-07 sec) per iteration: 0.06168 sec for 405761 iterations 0.151 usec (1.5102e-07 sec) per iteration: 0.0612781 sec for 405761 iterations 0.151 usec (1.51468e-07 sec) per iteration: 0.0614599 sec for 405761 iterations 0.151 usec (1.51256e-07 sec) per iteration: 0.0613737 sec for 405761 iterations 0.151 usec (1.51392e-07 sec) per iteration: 0.0614288 sec for 405761 iterations mean: 0.151429 usec stddev: 0.000328 usec relative stddev: 0.22% RESULT TestCondvarSignalNoOp: x86-64_pnacl_sz_newlib_static= {0.151429, 0.000328} us TestThreadCreateAndJoin: Measuring real time: 92.303 usec (9.23027e-05 sec) per iteration: 0.00923027 sec for 100 iterations 64.159 usec (6.41588e-05 sec) per iteration: 0.069484 sec for 1083 iterations 65.397 usec (6.53967e-05 sec) per iteration: 0.0708246 sec for 1083 iterations 63.558 usec (6.35579e-05 sec) per iteration: 0.0688332 sec for 1083 iterations 68.048 usec (6.80483e-05 sec) per iteration: 0.0736964 sec for 1083 iterations 64.931 usec (6.49315e-05 sec) per iteration: 0.0703208 sec for 1083 iterations mean: 65.218647 usec stddev: 1.549366 usec relative stddev: 2.38% RESULT TestThreadCreateAndJoin: x86-64_pnacl_sz_newlib_static= {65.218647, 1.549366} us TestThreadWakeup: Measuring real time: 31.260 usec (3.12597e-05 sec) per iteration: 0.00312597 sec for 100 iterations 30.664 usec (3.06635e-05 sec) per iteration: 0.0980926 sec for 3199 iterations 30.709 usec (3.07093e-05 sec) per iteration: 0.0982391 sec for 3199 iterations 30.964 usec (3.09638e-05 sec) per iteration: 0.0990533 sec for 3199 iterations 29.986 usec (2.99855e-05 sec) per iteration: 0.0959236 sec for 3199 iterations 30.984 usec (3.09839e-05 sec) per iteration: 0.0991175 sec for 3199 iterations mean: 30.661209 usec stddev: 0.361826 usec relative stddev: 1.18% RESULT TestThreadWakeup: x86-64_pnacl_sz_newlib_static= {30.661209, 0.361826} us TestCatchingFault: Measuring real time: 33.832 usec (3.38318e-05 sec) per iteration: 0.00338318 sec for 100 iterations 29.652 usec (2.96524e-05 sec) per iteration: 0.0876227 sec for 2955 iterations 29.717 usec (2.97167e-05 sec) per iteration: 0.0878129 sec for 2955 iterations 29.646 usec (2.96462e-05 sec) per iteration: 0.0876047 sec for 2955 iterations 29.388 usec (2.93875e-05 sec) per iteration: 0.0868402 sec for 2955 iterations 29.876 usec (2.98761e-05 sec) per iteration: 0.0882839 sec for 2955 iterations mean: 29.655794 usec stddev: 0.157680 usec relative stddev: 0.53% RESULT TestCatchingFault: x86-64_pnacl_sz_newlib_static= {29.655794, 0.157680} us TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 64.273 usec (6.42732e-05 sec) per iteration: 0.00642732 sec for 100 iterations 63.365 usec (6.33645e-05 sec) per iteration: 0.0985319 sec for 1555 iterations 62.901 usec (6.29011e-05 sec) per iteration: 0.0978112 sec for 1555 iterations 62.929 usec (6.29286e-05 sec) per iteration: 0.097854 sec for 1555 iterations 62.686 usec (6.26861e-05 sec) per iteration: 0.0974768 sec for 1555 iterations 63.239 usec (6.32387e-05 sec) per iteration: 0.0983362 sec for 1555 iterations mean: 63.023805 usec stddev: 0.245143 usec relative stddev: 0.39% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_pnacl_sz_newlib_static= {63.023805, 0.245143} us [ OK ] pnacl_newlib.run_performance_test (6090 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