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,dbg-host,nacl_irt_test platform=x86-64 --clang large_tests_irt force_sel_ldr=../out_64/sel_ldr force_irt=../out_64/irt_x64/irt_core.nexe perf_prefix=gn_ scons: Reading SConscript files ... ====================================================================== SCONS ARGS:['scons.py', '--verbose', '-k', '-j1', '--mode=nacl,dbg-host,nacl_irt_test', 'platform=x86-64', '--clang', 'large_tests_irt', 'force_sel_ldr=../out_64/sel_ldr', 'force_irt=../out_64/irt_x64/irt_core.nexe', 'perf_prefix=gn_'] ====================================================================== ====================================================================== 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 = 1547621684, usec = 967389 } Tue Jan 15 22:54:44 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 OBJ_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-64/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/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-x86-64/include -I/b/s/w/ir/kitchen-workdir -c -o UNKNOWN COMPILER NACL_BUILD_FAMILY: TRUSTED BUILD_TYPE: dbg-mac TARGET_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/dbg-mac-x86-64 OBJ_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/dbg-mac-x86-64/obj BUILD_TYPE_DESCRIPTION: MacOS dbg 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 -D_DEBUG -I/b/s/w/ir/kitchen-workdir -c -o UNKNOWN COMPILER NACL_BUILD_FAMILY: UNTRUSTED_IRT_TESTS BUILD_TYPE: nacl_irt_test TARGET_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt_test-x86-64 OBJ_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt_test-x86-64/obj BUILD_TYPE_DESCRIPTION: NaCl tests build with IRT 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 -Iscons-out/nacl-x86-64/include -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_gdb_invalid_memory_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_stack_frame_noopt_frame_test *** BROKEN run_stack_frame_noopt_noframe_test *** BROKEN run_stack_frame_opt_frame_test *** BROKEN run_stack_frame_opt_noframe_test *** BROKEN run_vla_test *** BROKEN run_nacl_sync_cond_test SKIPPING test text_overlaps_rodata SKIPPING test text_overlaps_data *** BROKEN run_gdb_invalid_memory_test_irt *** BROKEN run_nanosleep_test_irt *** BROKEN run_timefuncs_test_irt *** BROKEN run_raw_timefuncs_test_irt *** BROKEN run_main_thread_pthread_exit_test2_irt *** BROKEN run_overflowinline_test_irt *** BROKEN run_stack_frame_noopt_frame_test_irt *** BROKEN run_stack_frame_noopt_noframe_test_irt *** BROKEN run_stack_frame_opt_frame_test_irt *** BROKEN run_stack_frame_opt_noframe_test_irt *** BROKEN run_vla_test_irt There are 24 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 nacl_newlib.run_benchmark_test_irt --time_warning 10 --time_error 100 --perf_env_description gn_x86-64_with_irt_nnacl_newlib_static --subarch 64 --arch x86 --capture_output 0 --output_stamp /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt_test-x86-64/test_results/benchmark_test.out /b/s/w/ir/kitchen-workdir/out_64/sel_ldr -B /b/s/w/ir/kitchen-workdir/out_64/irt_x64/irt_core.nexe -- scons-out/nacl_irt_test-x86-64/obj/tests/benchmark/benchmark_test.nexe gn_x86-64_with_irt_nnacl_newlib_static [ RUN ] nacl_newlib.run_benchmark_test_irt /b/s/w/ir/kitchen-workdir/out_64/sel_ldr -B /b/s/w/ir/kitchen-workdir/out_64/irt_x64/irt_core.nexe -- scons-out/nacl_irt_test-x86-64/obj/tests/benchmark/benchmark_test.nexe gn_x86-64_with_irt_nnacl_newlib_static [40792,2906624896:09:49:31.679441] Native Client module will be loaded at base address 0x000035dc00000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: gn_x86-64_with_irt_nnacl_newlib_static= {0.076610, 0.000290} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (919 ms) /b/s/w/ir/cache/vpython/7c7ecd/bin/python tools/command_tester.py --name nacl_newlib.run_clock_cputime_test_irt --time_warning 60 --time_error 600 --perf_env_description gn_x86-64_with_irt_nnacl_newlib_static --subarch 64 --arch x86 --output_stamp /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt_test-x86-64/test_results/clock_cputime_test.out /b/s/w/ir/kitchen-workdir/out_64/sel_ldr -B /b/s/w/ir/kitchen-workdir/out_64/irt_x64/irt_core.nexe -- scons-out/nacl_irt_test-x86-64/obj/tests/clock/clock_test.nexe -c [ RUN ] nacl_newlib.run_clock_cputime_test_irt /b/s/w/ir/kitchen-workdir/out_64/sel_ldr -B /b/s/w/ir/kitchen-workdir/out_64/irt_x64/irt_core.nexe -- scons-out/nacl_irt_test-x86-64/obj/tests/clock/clock_test.nexe -c WARNING: should have taken less than 60.000000 secs [ OK ] nacl_newlib.run_clock_cputime_test_irt (66360 ms) doSizeCheck(["scons-out/nacl_irt_test-x86-64/obj/tests/hello_world/hello_world_size.out"], ["scons-out/nacl_irt_test-x86-64/obj/tests/hello_world/hello_world.nexe"]) RESULT hello_world_size.out: gn_x86-64_with_irt_nnacl_newlib_static= 529.947 KB RESULT hello_world_size.out: ZIPPED_gn_x86-64_with_irt_nnacl_newlib_static= 187.084 KB /b/s/w/ir/cache/vpython/7c7ecd/bin/python tools/command_tester.py --name nacl_newlib.run_performance_test_irt --time_warning 10 --time_error 100 --perf_env_description gn_x86-64_with_irt_nnacl_newlib_static --subarch 64 --arch x86 --capture_output 0 --output_stamp /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt_test-x86-64/test_results/performance_test.out /b/s/w/ir/kitchen-workdir/out_64/sel_ldr -e -B /b/s/w/ir/kitchen-workdir/out_64/irt_x64/irt_core.nexe -- scons-out/nacl_irt_test-x86-64/obj/tests/performance/performance_test.nexe gn_x86-64_with_irt_nnacl_newlib_static [ RUN ] nacl_newlib.run_performance_test_irt /b/s/w/ir/kitchen-workdir/out_64/sel_ldr -e -B /b/s/w/ir/kitchen-workdir/out_64/irt_x64/irt_core.nexe -- scons-out/nacl_irt_test-x86-64/obj/tests/performance/performance_test.nexe gn_x86-64_with_irt_nnacl_newlib_static [40797,2906624896:09:49:45.882313] Native Client module will be loaded at base address 0x00006a0500000000 TestNull: Measuring real time: 0.015 usec (1.472e-08 sec) per iteration: 1.472e-06 sec for 100 iterations 0.002 usec (2.486e-09 sec) per iteration: 2.486e-06 sec for 1000 iterations 0.002 usec (2.042e-09 sec) per iteration: 2.042e-05 sec for 10000 iterations 0.002 usec (2.09587e-09 sec) per iteration: 0.102638 sec for 48971596 iterations 0.002 usec (1.98743e-09 sec) per iteration: 0.0973274 sec for 48971596 iterations 0.002 usec (1.98405e-09 sec) per iteration: 0.0971619 sec for 48971596 iterations 0.002 usec (2.02145e-09 sec) per iteration: 0.0989935 sec for 48971596 iterations 0.002 usec (1.94454e-09 sec) per iteration: 0.0952271 sec for 48971596 iterations mean: 0.002007 usec stddev: 0.000051 usec relative stddev: 2.53% RESULT TestNull: gn_x86-64_with_irt_nnacl_newlib_static= {0.002007, 0.000051} us Measuring clock cycles: first runs (cycles): 258 30 27 30 30 21 21 21 21 21 ... slowest runs (cycles): ... 30 30 30 30 30 30 30 30 30 258 min: 21 cycles q1: 21 cycles median: 21 cycles q3: 30 cycles max: 258 cycles RESULT TestNull_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {21, 9} count Apparent clock speed: 10465 MHz TestNaClSyscall: Measuring real time: 0.215 usec (2.1504e-07 sec) per iteration: 2.1504e-05 sec for 100 iterations 0.209 usec (2.08957e-07 sec) per iteration: 0.0971711 sec for 465029 iterations 0.210 usec (2.09916e-07 sec) per iteration: 0.0976172 sec for 465029 iterations 0.224 usec (2.23522e-07 sec) per iteration: 0.103944 sec for 465029 iterations 0.218 usec (2.18303e-07 sec) per iteration: 0.101517 sec for 465029 iterations 0.208 usec (2.08085e-07 sec) per iteration: 0.0967655 sec for 465029 iterations mean: 0.213757 usec stddev: 0.006099 usec relative stddev: 2.85% RESULT TestNaClSyscall: gn_x86-64_with_irt_nnacl_newlib_static= {0.213757, 0.006099} us Measuring clock cycles: first runs (cycles): 1074 513 501 495 510 501 504 495 513 498 ... slowest runs (cycles): ... 513 513 513 513 513 513 513 558 564 1074 min: 492 cycles q1: 495 cycles median: 501 cycles q3: 507 cycles max: 1074 cycles RESULT TestNaClSyscall_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {501, 12} count Apparent clock speed: 2344 MHz TestSetjmpLongjmp: Measuring real time: 0.019 usec (1.936e-08 sec) per iteration: 1.936e-06 sec for 100 iterations 0.013 usec (1.2666e-08 sec) per iteration: 1.2666e-05 sec for 1000 iterations 0.012 usec (1.18483e-08 sec) per iteration: 0.0935438 sec for 7895152 iterations 0.012 usec (1.20486e-08 sec) per iteration: 0.0951254 sec for 7895152 iterations 0.013 usec (1.25507e-08 sec) per iteration: 0.0990899 sec for 7895152 iterations 0.012 usec (1.18524e-08 sec) per iteration: 0.0935767 sec for 7895152 iterations 0.012 usec (1.23385e-08 sec) per iteration: 0.0974142 sec for 7895152 iterations mean: 0.012128 usec stddev: 0.000277 usec relative stddev: 2.28% RESULT TestSetjmpLongjmp: gn_x86-64_with_irt_nnacl_newlib_static= {0.012128, 0.000277} us Measuring clock cycles: first runs (cycles): 399 21 24 36 24 39 24 24 24 21 ... slowest runs (cycles): ... 39 39 39 39 39 39 39 39 39 399 min: 21 cycles q1: 24 cycles median: 24 cycles q3: 36 cycles max: 399 cycles RESULT TestSetjmpLongjmp_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {24, 12} count Apparent clock speed: 1979 MHz TestClockGetTime: Measuring real time: 0.323 usec (3.23e-07 sec) per iteration: 3.23e-05 sec for 100 iterations 0.315 usec (3.15424e-07 sec) per iteration: 0.0976543 sec for 309597 iterations 0.329 usec (3.29005e-07 sec) per iteration: 0.101859 sec for 309597 iterations 0.322 usec (3.21668e-07 sec) per iteration: 0.0995875 sec for 309597 iterations 0.361 usec (3.60902e-07 sec) per iteration: 0.111734 sec for 309597 iterations 0.322 usec (3.22322e-07 sec) per iteration: 0.0997899 sec for 309597 iterations mean: 0.329864 usec stddev: 0.016104 usec relative stddev: 4.88% RESULT TestClockGetTime: gn_x86-64_with_irt_nnacl_newlib_static= {0.329864, 0.016104} us Measuring clock cycles: first runs (cycles): 2745 843 846 849 969 840 1008 927 987 846 ... slowest runs (cycles): ... 924 927 933 939 948 969 975 987 1008 2745 min: 831 cycles q1: 843 cycles median: 852 cycles q3: 861 cycles max: 2745 cycles RESULT TestClockGetTime_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {852, 18} count Apparent clock speed: 2583 MHz TestTlsVariable: Measuring real time: 0.012 usec (1.178e-08 sec) per iteration: 1.178e-06 sec for 100 iterations 0.006 usec (6.451e-09 sec) per iteration: 6.451e-06 sec for 1000 iterations 0.006 usec (6.0435e-09 sec) per iteration: 6.0435e-05 sec for 10000 iterations 0.006 usec (6.00373e-09 sec) per iteration: 0.0993419 sec for 16546703 iterations 0.006 usec (6.05905e-09 sec) per iteration: 0.100257 sec for 16546703 iterations 0.006 usec (5.92909e-09 sec) per iteration: 0.0981069 sec for 16546703 iterations 0.006 usec (5.98022e-09 sec) per iteration: 0.0989529 sec for 16546703 iterations 0.006 usec (5.96811e-09 sec) per iteration: 0.0987526 sec for 16546703 iterations mean: 0.005988 usec stddev: 0.000043 usec relative stddev: 0.72% RESULT TestTlsVariable: gn_x86-64_with_irt_nnacl_newlib_static= {0.005988, 0.000043} us Measuring clock cycles: first runs (cycles): 321 78 30 30 33 33 33 33 30 30 ... slowest runs (cycles): ... 33 33 33 33 33 33 33 33 78 321 min: 21 cycles q1: 24 cycles median: 30 cycles q3: 33 cycles max: 321 cycles RESULT TestTlsVariable_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {30, 9} count Apparent clock speed: 5010 MHz TestMmapAnonymous: Measuring real time: 10.189 usec (1.01893e-05 sec) per iteration: 0.00101893 sec for 100 iterations 9.971 usec (9.97135e-06 sec) per iteration: 0.0978588 sec for 9814 iterations 10.062 usec (1.00624e-05 sec) per iteration: 0.0987528 sec for 9814 iterations 10.149 usec (1.01486e-05 sec) per iteration: 0.099598 sec for 9814 iterations 10.023 usec (1.0023e-05 sec) per iteration: 0.0983656 sec for 9814 iterations 10.285 usec (1.02854e-05 sec) per iteration: 0.100941 sec for 9814 iterations mean: 10.098147 usec stddev: 0.110094 usec relative stddev: 1.09% RESULT TestMmapAnonymous: gn_x86-64_with_irt_nnacl_newlib_static= {10.098147, 0.110094} us Measuring clock cycles: first runs (cycles): 39012 27780 27678 27069 26958 26625 26556 27153 27036 26871 ... slowest runs (cycles): ... 27105 27153 27231 27291 27558 27678 27780 29514 39012 52989 min: 26331 cycles q1: 26535 cycles median: 26673 cycles q3: 26886 cycles max: 52989 cycles RESULT TestMmapAnonymous_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {26673, 351} count Apparent clock speed: 2641 MHz TestAtomicIncrement: Measuring real time: 0.017 usec (1.684e-08 sec) per iteration: 1.684e-06 sec for 100 iterations 0.010 usec (1.0094e-08 sec) per iteration: 1.0094e-05 sec for 1000 iterations 0.010 usec (9.74253e-09 sec) per iteration: 0.096518 sec for 9906875 iterations 0.010 usec (9.69187e-09 sec) per iteration: 0.0960162 sec for 9906875 iterations 0.010 usec (9.59631e-09 sec) per iteration: 0.0950694 sec for 9906875 iterations 0.010 usec (1.02302e-08 sec) per iteration: 0.10135 sec for 9906875 iterations 0.010 usec (9.87803e-09 sec) per iteration: 0.0978604 sec for 9906875 iterations mean: 0.009828 usec stddev: 0.000221 usec relative stddev: 2.25% RESULT TestAtomicIncrement: gn_x86-64_with_irt_nnacl_newlib_static= {0.009828, 0.000221} us Measuring clock cycles: first runs (cycles): 234 39 51 51 39 51 48 51 48 51 ... slowest runs (cycles): ... 51 51 51 51 51 51 51 51 51 234 min: 39 cycles q1: 48 cycles median: 48 cycles q3: 51 cycles max: 234 cycles RESULT TestAtomicIncrement_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {48, 3} count Apparent clock speed: 4884 MHz TestUncontendedMutexLock: Measuring real time: 0.031 usec (3.09e-08 sec) per iteration: 3.09e-06 sec for 100 iterations 0.023 usec (2.3457e-08 sec) per iteration: 2.3457e-05 sec for 1000 iterations 0.023 usec (2.31446e-08 sec) per iteration: 0.0986683 sec for 4263119 iterations 0.023 usec (2.30626e-08 sec) per iteration: 0.0983188 sec for 4263119 iterations 0.023 usec (2.2928e-08 sec) per iteration: 0.0977449 sec for 4263119 iterations 0.024 usec (2.37156e-08 sec) per iteration: 0.101103 sec for 4263119 iterations 0.024 usec (2.35452e-08 sec) per iteration: 0.100376 sec for 4263119 iterations mean: 0.023279 usec stddev: 0.000300 usec relative stddev: 1.29% RESULT TestUncontendedMutexLock: gn_x86-64_with_irt_nnacl_newlib_static= {0.023279, 0.000300} us Measuring clock cycles: first runs (cycles): 450 111 87 90 87 90 87 90 78 81 ... slowest runs (cycles): ... 90 90 90 90 90 90 90 90 111 450 min: 78 cycles q1: 81 cycles median: 87 cycles q3: 90 cycles max: 450 cycles RESULT TestUncontendedMutexLock_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {87, 9} count Apparent clock speed: 3737 MHz TestCondvarSignalNoOp: Measuring real time: 0.356 usec (3.5611e-07 sec) per iteration: 3.5611e-05 sec for 100 iterations 0.253 usec (2.52763e-07 sec) per iteration: 0.0709788 sec for 280812 iterations 0.245 usec (2.44524e-07 sec) per iteration: 0.0686652 sec for 280812 iterations 0.243 usec (2.43247e-07 sec) per iteration: 0.0683066 sec for 280812 iterations 0.250 usec (2.4981e-07 sec) per iteration: 0.0701497 sec for 280812 iterations 0.242 usec (2.41773e-07 sec) per iteration: 0.0678927 sec for 280812 iterations mean: 0.246423 usec stddev: 0.004171 usec relative stddev: 1.69% RESULT TestCondvarSignalNoOp: gn_x86-64_with_irt_nnacl_newlib_static= {0.246423, 0.004171} us Measuring clock cycles: first runs (cycles): 1692 681 657 657 669 732 651 663 648 666 ... slowest runs (cycles): ... 666 666 666 669 669 675 681 732 735 1692 min: 648 cycles q1: 654 cycles median: 657 cycles q3: 657 cycles max: 1692 cycles RESULT TestCondvarSignalNoOp_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {657, 3} count Apparent clock speed: 2666 MHz TestThreadCreateAndJoin: Measuring real time: 109.673 usec (0.000109673 sec) per iteration: 0.0109673 sec for 100 iterations 96.525 usec (9.6525e-05 sec) per iteration: 0.0879343 sec for 911 iterations 95.011 usec (9.5011e-05 sec) per iteration: 0.086555 sec for 911 iterations 102.269 usec (0.000102269 sec) per iteration: 0.0931673 sec for 911 iterations 95.457 usec (9.54573e-05 sec) per iteration: 0.0869616 sec for 911 iterations 93.150 usec (9.31498e-05 sec) per iteration: 0.0848595 sec for 911 iterations mean: 96.482474 usec stddev: 3.092290 usec relative stddev: 3.21% RESULT TestThreadCreateAndJoin: gn_x86-64_with_irt_nnacl_newlib_static= {96.482474, 3.092290} us Measuring clock cycles: first runs (cycles): 234939 237870 267789 267309 278784 259083 257721 249339 257412 249474 ... slowest runs (cycles): ... 266046 267024 267309 267789 269454 272538 275676 278784 330894 492750 min: 234939 cycles q1: 249720 cycles median: 253197 cycles q3: 258972 cycles max: 492750 cycles RESULT TestThreadCreateAndJoin_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {253197, 9252} count Apparent clock speed: 2624 MHz TestThreadWakeup: Measuring real time: 33.245 usec (3.32449e-05 sec) per iteration: 0.00332449 sec for 100 iterations 32.406 usec (3.24061e-05 sec) per iteration: 0.0974452 sec for 3007 iterations 31.739 usec (3.17389e-05 sec) per iteration: 0.0954388 sec for 3007 iterations 31.975 usec (3.19754e-05 sec) per iteration: 0.09615 sec for 3007 iterations 31.596 usec (3.15959e-05 sec) per iteration: 0.0950087 sec for 3007 iterations 31.802 usec (3.18021e-05 sec) per iteration: 0.0956288 sec for 3007 iterations mean: 31.903664 usec stddev: 0.279230 usec relative stddev: 0.88% RESULT TestThreadWakeup: gn_x86-64_with_irt_nnacl_newlib_static= {31.903664, 0.279230} us Measuring clock cycles: first runs (cycles): 90969 86103 85626 86688 95718 89367 85698 146802 94173 91821 ... slowest runs (cycles): ... 91422 91821 93021 94173 95718 97041 98244 101583 104142 146802 min: 83421 cycles q1: 84099 cycles median: 85086 cycles q3: 86304 cycles max: 146802 cycles RESULT TestThreadWakeup_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {85086, 2205} count Apparent clock speed: 2667 MHz TestCatchingFault: Measuring real time: 33.497 usec (3.3497e-05 sec) per iteration: 0.0033497 sec for 100 iterations 31.551 usec (3.15511e-05 sec) per iteration: 0.0941799 sec for 2985 iterations 31.580 usec (3.15802e-05 sec) per iteration: 0.0942669 sec for 2985 iterations 31.592 usec (3.15923e-05 sec) per iteration: 0.094303 sec for 2985 iterations 32.172 usec (3.21719e-05 sec) per iteration: 0.0960331 sec for 2985 iterations 31.718 usec (3.17181e-05 sec) per iteration: 0.0946786 sec for 2985 iterations mean: 31.722715 usec stddev: 0.231758 usec relative stddev: 0.73% RESULT TestCatchingFault: gn_x86-64_with_irt_nnacl_newlib_static= {31.722715, 0.231758} us Measuring clock cycles: first runs (cycles): 113553 125316 102768 121719 113568 125994 110571 111141 118323 94227 ... slowest runs (cycles): ... 110571 111141 113553 113568 118323 121719 125316 125994 132171 155868 min: 82413 cycles q1: 83910 cycles median: 85659 cycles q3: 101793 cycles max: 155868 cycles RESULT TestCatchingFault_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {85659, 17883} count Apparent clock speed: 2700 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 94.986 usec (9.49857e-05 sec) per iteration: 0.00949857 sec for 100 iterations 93.763 usec (9.37633e-05 sec) per iteration: 0.098639 sec for 1052 iterations 93.399 usec (9.33992e-05 sec) per iteration: 0.0982559 sec for 1052 iterations 93.669 usec (9.3669e-05 sec) per iteration: 0.0985398 sec for 1052 iterations 95.351 usec (9.53513e-05 sec) per iteration: 0.10031 sec for 1052 iterations 93.413 usec (9.34131e-05 sec) per iteration: 0.0982706 sec for 1052 iterations mean: 93.919181 usec stddev: 0.729975 usec relative stddev: 0.78% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: gn_x86-64_with_irt_nnacl_newlib_static= {93.919181, 0.729975} us Measuring clock cycles: first runs (cycles): 278610 260193 248886 273312 295296 266787 272082 255474 259401 264654 ... slowest runs (cycles): ... 266289 266787 270165 272082 273312 278610 286095 295296 318411 343077 min: 239133 cycles q1: 250893 cycles median: 253218 cycles q3: 257187 cycles max: 343077 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {253218, 6294} count Apparent clock speed: 2696 MHz [ OK ] nacl_newlib.run_performance_test_irt (6770 ms) scons: done building targets. ********************************************************************** COMMAND EXECUTION REPORT ********************************************************************** 3 command_tester.py 1 doSizeCheck ********************************************************************** ENVIRONMENT USAGE REPORT ********************************************************************** 4 nacl_irt_test-x86-64 Command return code: 0