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,dbg-host,nacl_irt_test platform=x86-64 large_tests_irt scons: Reading SConscript files ... ====================================================================== SCONS ARGS:['scons.py', '--verbose', '-k', '-j1', '--mode=nacl,dbg-host,nacl_irt_test', 'platform=x86-64', 'large_tests_irt'] ====================================================================== ====================================================================== 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 = 1537186175, usec = 570009 } Mon Sep 17 05:09:35 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 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 /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 -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/4f3597/bin/python tools/command_tester.py --name nacl_newlib.run_benchmark_test_irt --time_warning 10 --time_error 100 --perf_env_description 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 scons-out/dbg-mac-x86-64/staging/sel_ldr -B scons-out/nacl_irt-x86-64/staging/irt_core.nexe -- scons-out/nacl_irt_test-x86-64/obj/tests/benchmark/benchmark_test.nexe x86-64_with_irt_nnacl_newlib_static [ RUN ] nacl_newlib.run_benchmark_test_irt scons-out/dbg-mac-x86-64/staging/sel_ldr -B scons-out/nacl_irt-x86-64/staging/irt_core.nexe -- scons-out/nacl_irt_test-x86-64/obj/tests/benchmark/benchmark_test.nexe x86-64_with_irt_nnacl_newlib_static [43053,2586280832:14:43:58.791205] Native Client module will be loaded at base address 0x000011db00000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_with_irt_nnacl_newlib_static= {0.076280, 0.001380} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (919 ms) /b/s/w/ir/cache/vpython/4f3597/bin/python tools/command_tester.py --name nacl_newlib.run_clock_cputime_test_irt --time_warning 60 --time_error 600 --perf_env_description 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 scons-out/dbg-mac-x86-64/staging/sel_ldr -B scons-out/nacl_irt-x86-64/staging/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 scons-out/dbg-mac-x86-64/staging/sel_ldr -B scons-out/nacl_irt-x86-64/staging/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 (66920 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: x86-64_with_irt_nnacl_newlib_static= 529.947 KB RESULT hello_world_size.out: ZIPPED_x86-64_with_irt_nnacl_newlib_static= 187.084 KB /b/s/w/ir/cache/vpython/4f3597/bin/python tools/command_tester.py --name nacl_newlib.run_performance_test_irt --time_warning 10 --time_error 100 --perf_env_description 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 scons-out/dbg-mac-x86-64/staging/sel_ldr -e -B scons-out/nacl_irt-x86-64/staging/irt_core.nexe -- scons-out/nacl_irt_test-x86-64/obj/tests/performance/performance_test.nexe x86-64_with_irt_nnacl_newlib_static [ RUN ] nacl_newlib.run_performance_test_irt scons-out/dbg-mac-x86-64/staging/sel_ldr -e -B scons-out/nacl_irt-x86-64/staging/irt_core.nexe -- scons-out/nacl_irt_test-x86-64/obj/tests/performance/performance_test.nexe x86-64_with_irt_nnacl_newlib_static [43058,2586280832:14:44:13.167877] Native Client module will be loaded at base address 0x0000002f00000000 TestNull: Measuring real time: 0.014 usec (1.412e-08 sec) per iteration: 1.412e-06 sec for 100 iterations 0.002 usec (2.458e-09 sec) per iteration: 2.458e-06 sec for 1000 iterations 0.002 usec (2.0451e-09 sec) per iteration: 2.0451e-05 sec for 10000 iterations 0.002 usec (1.97455e-09 sec) per iteration: 0.0965504 sec for 48897364 iterations 0.002 usec (2.00384e-09 sec) per iteration: 0.0979823 sec for 48897364 iterations 0.002 usec (1.9853e-09 sec) per iteration: 0.0970758 sec for 48897364 iterations 0.002 usec (1.98684e-09 sec) per iteration: 0.0971515 sec for 48897364 iterations 0.002 usec (2.00986e-09 sec) per iteration: 0.098277 sec for 48897364 iterations mean: 0.001992 usec stddev: 0.000013 usec relative stddev: 0.65% RESULT TestNull: x86-64_with_irt_nnacl_newlib_static= {0.001992, 0.000013} us Measuring clock cycles: first runs (cycles): 228 21 18 21 21 30 30 30 30 30 ... slowest runs (cycles): ... 30 30 30 30 30 30 30 30 30 228 min: 18 cycles q1: 21 cycles median: 30 cycles q3: 30 cycles max: 228 cycles RESULT TestNull_CycleCount: x86-64_with_irt_nnacl_newlib_static= {30, 9} count Apparent clock speed: 15060 MHz TestNaClSyscall: Measuring real time: 0.232 usec (2.3203e-07 sec) per iteration: 2.3203e-05 sec for 100 iterations 0.223 usec (2.22967e-07 sec) per iteration: 0.0960939 sec for 430978 iterations 0.223 usec (2.23249e-07 sec) per iteration: 0.0962152 sec for 430978 iterations 0.223 usec (2.23157e-07 sec) per iteration: 0.0961757 sec for 430978 iterations 0.223 usec (2.22705e-07 sec) per iteration: 0.0959809 sec for 430978 iterations 0.223 usec (2.23052e-07 sec) per iteration: 0.0961307 sec for 430978 iterations mean: 0.223026 usec stddev: 0.000187 usec relative stddev: 0.08% RESULT TestNaClSyscall: x86-64_with_irt_nnacl_newlib_static= {0.223026, 0.000187} us Measuring clock cycles: first runs (cycles): 1191 507 492 489 489 486 492 486 486 489 ... slowest runs (cycles): ... 492 492 492 492 492 492 492 492 507 1191 min: 486 cycles q1: 486 cycles median: 489 cycles q3: 489 cycles max: 1191 cycles RESULT TestNaClSyscall_CycleCount: x86-64_with_irt_nnacl_newlib_static= {489, 3} count Apparent clock speed: 2193 MHz TestSetjmpLongjmp: Measuring real time: 0.019 usec (1.945e-08 sec) per iteration: 1.945e-06 sec for 100 iterations 0.013 usec (1.264e-08 sec) per iteration: 1.264e-05 sec for 1000 iterations 0.012 usec (1.19848e-08 sec) per iteration: 0.0948165 sec for 7911392 iterations 0.012 usec (1.2091e-08 sec) per iteration: 0.0956563 sec for 7911392 iterations 0.012 usec (1.20678e-08 sec) per iteration: 0.0954729 sec for 7911392 iterations 0.012 usec (1.22572e-08 sec) per iteration: 0.0969717 sec for 7911392 iterations 0.012 usec (1.20458e-08 sec) per iteration: 0.0952988 sec for 7911392 iterations mean: 0.012089 usec stddev: 0.000091 usec relative stddev: 0.75% RESULT TestSetjmpLongjmp: x86-64_with_irt_nnacl_newlib_static= {0.012089, 0.000091} us Measuring clock cycles: first runs (cycles): 522 33 21 24 24 33 33 33 33 24 ... slowest runs (cycles): ... 33 33 33 33 33 33 33 33 33 522 min: 21 cycles q1: 24 cycles median: 30 cycles q3: 33 cycles max: 522 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {30, 9} count Apparent clock speed: 2482 MHz TestClockGetTime: Measuring real time: 0.309 usec (3.0873e-07 sec) per iteration: 3.0873e-05 sec for 100 iterations 0.304 usec (3.03667e-07 sec) per iteration: 0.0983597 sec for 323907 iterations 0.301 usec (3.01019e-07 sec) per iteration: 0.0975022 sec for 323907 iterations 0.342 usec (3.42037e-07 sec) per iteration: 0.110788 sec for 323907 iterations 0.302 usec (3.01859e-07 sec) per iteration: 0.0977741 sec for 323907 iterations 0.302 usec (3.01959e-07 sec) per iteration: 0.0978068 sec for 323907 iterations mean: 0.310108 usec stddev: 0.015988 usec relative stddev: 5.16% RESULT TestClockGetTime: x86-64_with_irt_nnacl_newlib_static= {0.310108, 0.015988} us Measuring clock cycles: first runs (cycles): 3216 945 819 831 819 819 831 822 822 822 ... slowest runs (cycles): ... 834 834 834 834 837 885 909 933 945 3216 min: 816 cycles q1: 822 cycles median: 822 cycles q3: 831 cycles max: 3216 cycles RESULT TestClockGetTime_CycleCount: x86-64_with_irt_nnacl_newlib_static= {822, 9} count Apparent clock speed: 2651 MHz TestTlsVariable: Measuring real time: 0.012 usec (1.157e-08 sec) per iteration: 1.157e-06 sec for 100 iterations 0.006 usec (6.475e-09 sec) per iteration: 6.475e-06 sec for 1000 iterations 0.006 usec (6.046e-09 sec) per iteration: 6.046e-05 sec for 10000 iterations 0.006 usec (6.03394e-09 sec) per iteration: 0.0998005 sec for 16539861 iterations 0.006 usec (6.01312e-09 sec) per iteration: 0.0994561 sec for 16539861 iterations 0.006 usec (6.05998e-09 sec) per iteration: 0.100231 sec for 16539861 iterations 0.006 usec (6.0431e-09 sec) per iteration: 0.099952 sec for 16539861 iterations 0.006 usec (6.04784e-09 sec) per iteration: 0.10003 sec for 16539861 iterations mean: 0.006040 usec stddev: 0.000016 usec relative stddev: 0.26% RESULT TestTlsVariable: x86-64_with_irt_nnacl_newlib_static= {0.006040, 0.000016} us Measuring clock cycles: first runs (cycles): 345 78 30 30 30 21 21 21 21 21 ... slowest runs (cycles): ... 30 30 30 30 30 30 30 30 78 345 min: 21 cycles q1: 21 cycles median: 30 cycles q3: 30 cycles max: 345 cycles RESULT TestTlsVariable_CycleCount: x86-64_with_irt_nnacl_newlib_static= {30, 9} count Apparent clock speed: 4967 MHz TestMmapAnonymous: Measuring real time: 11.194 usec (1.11944e-05 sec) per iteration: 0.00111944 sec for 100 iterations 10.641 usec (1.06414e-05 sec) per iteration: 0.0950595 sec for 8933 iterations 10.741 usec (1.07409e-05 sec) per iteration: 0.0959485 sec for 8933 iterations 11.337 usec (1.1337e-05 sec) per iteration: 0.101273 sec for 8933 iterations 14.218 usec (1.42184e-05 sec) per iteration: 0.127013 sec for 8933 iterations 12.401 usec (1.24011e-05 sec) per iteration: 0.110779 sec for 8933 iterations mean: 11.867757 usec stddev: 1.331590 usec relative stddev: 11.22% RESULT TestMmapAnonymous: x86-64_with_irt_nnacl_newlib_static= {11.867757, 1.331590} us Measuring clock cycles: first runs (cycles): 41169 29721 29868 29241 29289 29076 28836 28839 28683 28986 ... slowest runs (cycles): ... 29151 29184 29241 29256 29289 29460 29625 29721 29868 41169 min: 28533 cycles q1: 28794 cycles median: 28878 cycles q3: 28983 cycles max: 41169 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_with_irt_nnacl_newlib_static= {28878, 189} count Apparent clock speed: 2433 MHz TestAtomicIncrement: Measuring real time: 0.017 usec (1.701e-08 sec) per iteration: 1.701e-06 sec for 100 iterations 0.010 usec (1.011e-08 sec) per iteration: 1.011e-05 sec for 1000 iterations 0.010 usec (9.67883e-09 sec) per iteration: 0.0957352 sec for 9891196 iterations 0.010 usec (9.58017e-09 sec) per iteration: 0.0947593 sec for 9891196 iterations 0.010 usec (9.69762e-09 sec) per iteration: 0.095921 sec for 9891196 iterations 0.010 usec (9.71859e-09 sec) per iteration: 0.0961285 sec for 9891196 iterations 0.010 usec (9.6864e-09 sec) per iteration: 0.0958101 sec for 9891196 iterations mean: 0.009672 usec stddev: 0.000048 usec relative stddev: 0.50% RESULT TestAtomicIncrement: x86-64_with_irt_nnacl_newlib_static= {0.009672, 0.000048} us Measuring clock cycles: first runs (cycles): 303 51 48 48 42 48 51 48 51 48 ... slowest runs (cycles): ... 51 51 51 51 51 51 51 51 51 303 min: 39 cycles q1: 48 cycles median: 48 cycles q3: 51 cycles max: 303 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_with_irt_nnacl_newlib_static= {48, 3} count Apparent clock speed: 4963 MHz TestUncontendedMutexLock: Measuring real time: 0.030 usec (3.006e-08 sec) per iteration: 3.006e-06 sec for 100 iterations 0.023 usec (2.3475e-08 sec) per iteration: 2.3475e-05 sec for 1000 iterations 0.023 usec (2.30261e-08 sec) per iteration: 0.0980878 sec for 4259850 iterations 0.024 usec (2.37493e-08 sec) per iteration: 0.101169 sec for 4259850 iterations 0.023 usec (2.33974e-08 sec) per iteration: 0.0996696 sec for 4259850 iterations 0.023 usec (2.34957e-08 sec) per iteration: 0.100088 sec for 4259850 iterations 0.023 usec (2.32689e-08 sec) per iteration: 0.0991222 sec for 4259850 iterations mean: 0.023387 usec stddev: 0.000240 usec relative stddev: 1.03% RESULT TestUncontendedMutexLock: x86-64_with_irt_nnacl_newlib_static= {0.023387, 0.000240} us Measuring clock cycles: first runs (cycles): 429 108 81 78 81 78 81 78 81 78 ... slowest runs (cycles): ... 90 90 90 90 90 90 90 90 108 429 min: 78 cycles q1: 81 cycles median: 87 cycles q3: 90 cycles max: 429 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_with_irt_nnacl_newlib_static= {87, 9} count Apparent clock speed: 3720 MHz TestCondvarSignalNoOp: Measuring real time: 0.385 usec (3.8458e-07 sec) per iteration: 3.8458e-05 sec for 100 iterations 0.245 usec (2.44861e-07 sec) per iteration: 0.0636694 sec for 260023 iterations 0.238 usec (2.37777e-07 sec) per iteration: 0.0618275 sec for 260023 iterations 0.241 usec (2.40652e-07 sec) per iteration: 0.0625749 sec for 260023 iterations 0.242 usec (2.41657e-07 sec) per iteration: 0.0628363 sec for 260023 iterations 0.294 usec (2.93625e-07 sec) per iteration: 0.0763492 sec for 260023 iterations mean: 0.251714 usec stddev: 0.021077 usec relative stddev: 8.37% RESULT TestCondvarSignalNoOp: x86-64_with_irt_nnacl_newlib_static= {0.251714, 0.021077} us Measuring clock cycles: first runs (cycles): 1944 651 648 651 714 642 651 651 654 651 ... slowest runs (cycles): ... 651 654 654 654 654 660 660 660 714 1944 min: 636 cycles q1: 648 cycles median: 651 cycles q3: 651 cycles max: 1944 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {651, 3} count Apparent clock speed: 2586 MHz TestThreadCreateAndJoin: Measuring real time: 96.766 usec (9.67657e-05 sec) per iteration: 0.00967657 sec for 100 iterations 96.930 usec (9.693e-05 sec) per iteration: 0.100129 sec for 1033 iterations 98.104 usec (9.81035e-05 sec) per iteration: 0.101341 sec for 1033 iterations 105.189 usec (0.000105189 sec) per iteration: 0.108661 sec for 1033 iterations 94.362 usec (9.43621e-05 sec) per iteration: 0.097476 sec for 1033 iterations 93.774 usec (9.37745e-05 sec) per iteration: 0.0968691 sec for 1033 iterations mean: 97.671918 usec stddev: 4.084003 usec relative stddev: 4.18% RESULT TestThreadCreateAndJoin: x86-64_with_irt_nnacl_newlib_static= {97.671918, 4.084003} us Measuring clock cycles: first runs (cycles): 254901 237855 248229 268926 249741 263787 260655 254937 255240 257229 ... slowest runs (cycles): ... 263010 263217 263787 264297 268569 268926 273144 275208 276144 333723 min: 237855 cycles q1: 251271 cycles median: 253422 cycles q3: 255990 cycles max: 333723 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_with_irt_nnacl_newlib_static= {253422, 4719} count Apparent clock speed: 2595 MHz TestThreadWakeup: Measuring real time: 36.475 usec (3.64752e-05 sec) per iteration: 0.00364752 sec for 100 iterations 32.780 usec (3.278e-05 sec) per iteration: 0.0898499 sec for 2741 iterations 33.004 usec (3.30036e-05 sec) per iteration: 0.090463 sec for 2741 iterations 33.452 usec (3.34523e-05 sec) per iteration: 0.0916927 sec for 2741 iterations 31.862 usec (3.1862e-05 sec) per iteration: 0.0873336 sec for 2741 iterations 32.472 usec (3.24723e-05 sec) per iteration: 0.0890066 sec for 2741 iterations mean: 32.714033 usec stddev: 0.532478 usec relative stddev: 1.63% RESULT TestThreadWakeup: x86-64_with_irt_nnacl_newlib_static= {32.714033, 0.532478} us Measuring clock cycles: first runs (cycles): 124884 113559 113703 113829 130740 131514 117942 108468 133806 119541 ... slowest runs (cycles): ... 113559 113703 113829 117942 119541 124884 130740 131514 133806 142143 min: 82104 cycles q1: 85569 cycles median: 86754 cycles q3: 88185 cycles max: 142143 cycles RESULT TestThreadWakeup_CycleCount: x86-64_with_irt_nnacl_newlib_static= {86754, 2616} count Apparent clock speed: 2652 MHz TestCatchingFault: Measuring real time: 35.184 usec (3.51838e-05 sec) per iteration: 0.00351838 sec for 100 iterations 33.023 usec (3.30232e-05 sec) per iteration: 0.0938519 sec for 2842 iterations 32.753 usec (3.27529e-05 sec) per iteration: 0.0930837 sec for 2842 iterations 32.807 usec (3.28066e-05 sec) per iteration: 0.0932362 sec for 2842 iterations 32.507 usec (3.25074e-05 sec) per iteration: 0.0923859 sec for 2842 iterations 32.646 usec (3.26461e-05 sec) per iteration: 0.0927803 sec for 2842 iterations mean: 32.747231 usec stddev: 0.171735 usec relative stddev: 0.52% RESULT TestCatchingFault: x86-64_with_irt_nnacl_newlib_static= {32.747231, 0.171735} us Measuring clock cycles: first runs (cycles): 107196 88023 86733 85767 86211 86454 99669 190974 154908 133548 ... slowest runs (cycles): ... 90381 94005 99669 104637 107196 133548 134940 154908 171519 190974 min: 85182 cycles q1: 86304 cycles median: 86820 cycles q3: 87330 cycles max: 190974 cycles RESULT TestCatchingFault_CycleCount: x86-64_with_irt_nnacl_newlib_static= {86820, 1026} count Apparent clock speed: 2651 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 95.354 usec (9.53538e-05 sec) per iteration: 0.00953538 sec for 100 iterations 105.369 usec (0.000105369 sec) per iteration: 0.110427 sec for 1048 iterations 99.132 usec (9.91321e-05 sec) per iteration: 0.10389 sec for 1048 iterations 96.284 usec (9.62844e-05 sec) per iteration: 0.100906 sec for 1048 iterations 94.372 usec (9.43717e-05 sec) per iteration: 0.0989015 sec for 1048 iterations 95.462 usec (9.5462e-05 sec) per iteration: 0.100044 sec for 1048 iterations mean: 98.123920 usec stddev: 3.951277 usec relative stddev: 4.03% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_with_irt_nnacl_newlib_static= {98.123920, 3.951277} us Measuring clock cycles: first runs (cycles): 235023 259980 283695 298836 315441 260649 256440 251532 248763 248112 ... slowest runs (cycles): ... 258687 259974 259980 260649 260775 261918 265044 283695 298836 315441 min: 235023 cycles q1: 247974 cycles median: 249168 cycles q3: 251673 cycles max: 315441 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_with_irt_nnacl_newlib_static= {249168, 3699} count Apparent clock speed: 2539 MHz [ OK ] nacl_newlib.run_performance_test_irt (6850 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