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 = 1550153149, usec = 291435 } Thu Feb 14 06:05:49 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 [67434,3086484352:12:50:51.666842] Native Client module will be loaded at base address 0x000063fb00000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: gn_x86-64_with_irt_nnacl_newlib_static= {0.090500, 0.003070} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (1170 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 (70370 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= 527.127 KB RESULT hello_world_size.out: ZIPPED_gn_x86-64_with_irt_nnacl_newlib_static= 186.967 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 [67440,3086484352:12:51:06.772420] Native Client module will be loaded at base address 0x00004f0500000000 TestNull: Measuring real time: 0.015 usec (1.503e-08 sec) per iteration: 1.503e-06 sec for 100 iterations 0.003 usec (2.549e-09 sec) per iteration: 2.549e-06 sec for 1000 iterations 0.002 usec (2.0455e-09 sec) per iteration: 2.0455e-05 sec for 10000 iterations 0.002 usec (2.05166e-09 sec) per iteration: 0.100301 sec for 48887802 iterations 0.002 usec (2.00614e-09 sec) per iteration: 0.0980758 sec for 48887802 iterations 0.002 usec (2.02258e-09 sec) per iteration: 0.0988795 sec for 48887802 iterations 0.002 usec (2.01554e-09 sec) per iteration: 0.0985353 sec for 48887802 iterations 0.002 usec (2.01261e-09 sec) per iteration: 0.0983921 sec for 48887802 iterations mean: 0.002022 usec stddev: 0.000016 usec relative stddev: 0.79% RESULT TestNull: gn_x86-64_with_irt_nnacl_newlib_static= {0.002022, 0.000016} us Measuring clock cycles: first runs (cycles): 213 21 21 30 30 27 30 27 21 18 ... slowest runs (cycles): ... 30 30 30 30 30 30 30 30 30 213 min: 18 cycles q1: 18 cycles median: 21 cycles q3: 27 cycles max: 213 cycles RESULT TestNull_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {21, 9} count Apparent clock speed: 10387 MHz TestNaClSyscall: Measuring real time: 0.227 usec (2.2674e-07 sec) per iteration: 2.2674e-05 sec for 100 iterations 0.256 usec (2.56424e-07 sec) per iteration: 0.113091 sec for 441033 iterations 0.218 usec (2.18223e-07 sec) per iteration: 0.0962436 sec for 441033 iterations 0.211 usec (2.1071e-07 sec) per iteration: 0.0929302 sec for 441033 iterations 0.213 usec (2.12556e-07 sec) per iteration: 0.0937444 sec for 441033 iterations 0.217 usec (2.16889e-07 sec) per iteration: 0.0956552 sec for 441033 iterations mean: 0.222961 usec stddev: 0.016955 usec relative stddev: 7.60% RESULT TestNaClSyscall: gn_x86-64_with_irt_nnacl_newlib_static= {0.222961, 0.016955} us Measuring clock cycles: first runs (cycles): 1026 513 504 501 513 492 501 495 510 495 ... slowest runs (cycles): ... 513 513 513 513 513 513 516 516 561 1026 min: 492 cycles q1: 498 cycles median: 504 cycles q3: 507 cycles max: 1026 cycles RESULT TestNaClSyscall_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {504, 9} count Apparent clock speed: 2260 MHz TestSetjmpLongjmp: Measuring real time: 0.018 usec (1.838e-08 sec) per iteration: 1.838e-06 sec for 100 iterations 0.013 usec (1.2631e-08 sec) per iteration: 1.2631e-05 sec for 1000 iterations 0.013 usec (1.34516e-08 sec) per iteration: 0.106496 sec for 7917029 iterations 0.013 usec (1.26995e-08 sec) per iteration: 0.100543 sec for 7917029 iterations 0.012 usec (1.22768e-08 sec) per iteration: 0.0971956 sec for 7917029 iterations 0.013 usec (1.33439e-08 sec) per iteration: 0.105644 sec for 7917029 iterations 0.012 usec (1.2173e-08 sec) per iteration: 0.0963739 sec for 7917029 iterations mean: 0.012789 usec stddev: 0.000529 usec relative stddev: 4.13% RESULT TestSetjmpLongjmp: gn_x86-64_with_irt_nnacl_newlib_static= {0.012789, 0.000529} us Measuring clock cycles: first runs (cycles): 513 57 24 24 24 33 33 33 24 24 ... slowest runs (cycles): ... 33 33 33 33 33 33 33 33 57 513 min: 24 cycles q1: 24 cycles median: 33 cycles q3: 33 cycles max: 513 cycles RESULT TestSetjmpLongjmp_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {33, 9} count Apparent clock speed: 2580 MHz TestClockGetTime: Measuring real time: 0.322 usec (3.2218e-07 sec) per iteration: 3.2218e-05 sec for 100 iterations 0.321 usec (3.21173e-07 sec) per iteration: 0.0996873 sec for 310385 iterations 0.322 usec (3.21648e-07 sec) per iteration: 0.0998348 sec for 310385 iterations 0.320 usec (3.20436e-07 sec) per iteration: 0.0994584 sec for 310385 iterations 0.322 usec (3.22121e-07 sec) per iteration: 0.0999816 sec for 310385 iterations 0.436 usec (4.36252e-07 sec) per iteration: 0.135406 sec for 310385 iterations mean: 0.344326 usec stddev: 0.045966 usec relative stddev: 13.35% RESULT TestClockGetTime: gn_x86-64_with_irt_nnacl_newlib_static= {0.344326, 0.045966} us Measuring clock cycles: first runs (cycles): 5451 1632 1398 1272 1398 1335 1281 1296 1629 1575 ... slowest runs (cycles): ... 1548 1575 1596 1602 1629 1632 1683 1707 1728 5451 min: 1182 cycles q1: 1254 cycles median: 1305 cycles q3: 1392 cycles max: 5451 cycles RESULT TestClockGetTime_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {1305, 138} count Apparent clock speed: 3790 MHz TestTlsVariable: Measuring real time: 0.023 usec (2.343e-08 sec) per iteration: 2.343e-06 sec for 100 iterations 0.011 usec (1.1119e-08 sec) per iteration: 1.1119e-05 sec for 1000 iterations 0.006 usec (6.0646e-09 sec) per iteration: 0.0545426 sec for 8993614 iterations 0.006 usec (6.05266e-09 sec) per iteration: 0.0544353 sec for 8993614 iterations 0.006 usec (6.04498e-09 sec) per iteration: 0.0543663 sec for 8993614 iterations 0.006 usec (6.07505e-09 sec) per iteration: 0.0546367 sec for 8993614 iterations 0.006 usec (6.02703e-09 sec) per iteration: 0.0542047 sec for 8993614 iterations mean: 0.006053 usec stddev: 0.000016 usec relative stddev: 0.27% RESULT TestTlsVariable: gn_x86-64_with_irt_nnacl_newlib_static= {0.006053, 0.000016} us Measuring clock cycles: first runs (cycles): 369 81 33 30 30 21 33 30 30 30 ... slowest runs (cycles): ... 33 33 33 33 33 33 33 33 81 369 min: 21 cycles q1: 21 cycles median: 30 cycles q3: 30 cycles max: 369 cycles RESULT TestTlsVariable_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {30, 9} count Apparent clock speed: 4956 MHz TestMmapAnonymous: Measuring real time: 14.067 usec (1.4067e-05 sec) per iteration: 0.0014067 sec for 100 iterations 10.967 usec (1.09669e-05 sec) per iteration: 0.0779525 sec for 7108 iterations 11.020 usec (1.10195e-05 sec) per iteration: 0.0783269 sec for 7108 iterations 11.002 usec (1.10021e-05 sec) per iteration: 0.0782029 sec for 7108 iterations 11.032 usec (1.10316e-05 sec) per iteration: 0.0784126 sec for 7108 iterations 11.041 usec (1.10415e-05 sec) per iteration: 0.0784829 sec for 7108 iterations mean: 11.012318 usec stddev: 0.026251 usec relative stddev: 0.24% RESULT TestMmapAnonymous: gn_x86-64_with_irt_nnacl_newlib_static= {11.012318, 0.026251} us Measuring clock cycles: first runs (cycles): 41004 30270 30204 29415 29628 29517 29172 29343 29091 28833 ... slowest runs (cycles): ... 29952 29970 29979 30024 30147 30186 30204 30270 30303 41004 min: 28833 cycles q1: 29373 cycles median: 29604 cycles q3: 29757 cycles max: 41004 cycles RESULT TestMmapAnonymous_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {29604, 384} count Apparent clock speed: 2688 MHz TestAtomicIncrement: Measuring real time: 0.016 usec (1.601e-08 sec) per iteration: 1.601e-06 sec for 100 iterations 0.010 usec (1.0083e-08 sec) per iteration: 1.0083e-05 sec for 1000 iterations 0.010 usec (9.7268e-09 sec) per iteration: 0.0964673 sec for 9917683 iterations 0.010 usec (9.71048e-09 sec) per iteration: 0.0963055 sec for 9917683 iterations 0.010 usec (9.72444e-09 sec) per iteration: 0.0964439 sec for 9917683 iterations 0.010 usec (9.72387e-09 sec) per iteration: 0.0964383 sec for 9917683 iterations 0.010 usec (9.71005e-09 sec) per iteration: 0.0963012 sec for 9917683 iterations mean: 0.009719 usec stddev: 0.000007 usec relative stddev: 0.08% RESULT TestAtomicIncrement: gn_x86-64_with_irt_nnacl_newlib_static= {0.009719, 0.000007} us Measuring clock cycles: first runs (cycles): 249 48 48 51 51 51 51 51 51 51 ... slowest runs (cycles): ... 51 51 51 51 51 51 51 51 51 249 min: 42 cycles q1: 51 cycles median: 51 cycles q3: 51 cycles max: 249 cycles RESULT TestAtomicIncrement_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {51, 0} count Apparent clock speed: 5247 MHz TestUncontendedMutexLock: Measuring real time: 0.030 usec (2.984e-08 sec) per iteration: 2.984e-06 sec for 100 iterations 0.023 usec (2.3448e-08 sec) per iteration: 2.3448e-05 sec for 1000 iterations 0.024 usec (2.3833e-08 sec) per iteration: 0.101642 sec for 4264756 iterations 0.024 usec (2.35674e-08 sec) per iteration: 0.100509 sec for 4264756 iterations 0.025 usec (2.51241e-08 sec) per iteration: 0.107148 sec for 4264756 iterations 0.023 usec (2.34761e-08 sec) per iteration: 0.10012 sec for 4264756 iterations 0.026 usec (2.64778e-08 sec) per iteration: 0.112921 sec for 4264756 iterations mean: 0.024496 usec stddev: 0.001154 usec relative stddev: 4.71% RESULT TestUncontendedMutexLock: gn_x86-64_with_irt_nnacl_newlib_static= {0.024496, 0.001154} us Measuring clock cycles: first runs (cycles): 378 108 81 81 81 81 90 90 90 90 ... slowest runs (cycles): ... 90 90 90 90 90 90 90 108 114 378 min: 78 cycles q1: 81 cycles median: 87 cycles q3: 90 cycles max: 378 cycles RESULT TestUncontendedMutexLock_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {87, 9} count Apparent clock speed: 3552 MHz TestCondvarSignalNoOp: Measuring real time: 0.411 usec (4.1105e-07 sec) per iteration: 4.1105e-05 sec for 100 iterations 0.256 usec (2.56208e-07 sec) per iteration: 0.0623301 sec for 243279 iterations 0.246 usec (2.45763e-07 sec) per iteration: 0.0597891 sec for 243279 iterations 0.246 usec (2.46128e-07 sec) per iteration: 0.0598779 sec for 243279 iterations 0.246 usec (2.46309e-07 sec) per iteration: 0.0599217 sec for 243279 iterations 0.246 usec (2.46106e-07 sec) per iteration: 0.0598724 sec for 243279 iterations mean: 0.248103 usec stddev: 0.004057 usec relative stddev: 1.64% RESULT TestCondvarSignalNoOp: gn_x86-64_with_irt_nnacl_newlib_static= {0.248103, 0.004057} us Measuring clock cycles: first runs (cycles): 1692 780 660 669 657 660 657 660 660 651 ... slowest runs (cycles): ... 672 675 675 675 678 723 723 729 780 1692 min: 651 cycles q1: 654 cycles median: 657 cycles q3: 666 cycles max: 1692 cycles RESULT TestCondvarSignalNoOp_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {657, 12} count Apparent clock speed: 2648 MHz TestThreadCreateAndJoin: Measuring real time: 108.260 usec (0.00010826 sec) per iteration: 0.010826 sec for 100 iterations 98.455 usec (9.84552e-05 sec) per iteration: 0.0908741 sec for 923 iterations 108.214 usec (0.000108214 sec) per iteration: 0.0998816 sec for 923 iterations 103.829 usec (0.000103829 sec) per iteration: 0.0958346 sec for 923 iterations 111.115 usec (0.000111115 sec) per iteration: 0.102559 sec for 923 iterations 99.362 usec (9.93617e-05 sec) per iteration: 0.0917108 sec for 923 iterations mean: 104.195007 usec stddev: 4.908675 usec relative stddev: 4.71% RESULT TestThreadCreateAndJoin: gn_x86-64_with_irt_nnacl_newlib_static= {104.195007, 4.908675} us Measuring clock cycles: first runs (cycles): 244761 309813 306180 248814 275151 399291 349308 366465 360153 347949 ... slowest runs (cycles): ... 347949 349308 360057 360153 366465 379662 399291 404385 441426 455538 min: 241635 cycles q1: 250482 cycles median: 295812 cycles q3: 329682 cycles max: 455538 cycles RESULT TestThreadCreateAndJoin_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {295812, 79200} count Apparent clock speed: 2839 MHz TestThreadWakeup: Measuring real time: 36.910 usec (3.69101e-05 sec) per iteration: 0.00369101 sec for 100 iterations 33.972 usec (3.39721e-05 sec) per iteration: 0.0920303 sec for 2709 iterations 34.104 usec (3.41037e-05 sec) per iteration: 0.0923871 sec for 2709 iterations 35.183 usec (3.51832e-05 sec) per iteration: 0.0953113 sec for 2709 iterations 33.846 usec (3.38465e-05 sec) per iteration: 0.0916901 sec for 2709 iterations 34.382 usec (3.43824e-05 sec) per iteration: 0.093142 sec for 2709 iterations mean: 34.297579 usec stddev: 0.477191 usec relative stddev: 1.39% RESULT TestThreadWakeup: gn_x86-64_with_irt_nnacl_newlib_static= {34.297579, 0.477191} us Measuring clock cycles: first runs (cycles): 110526 95748 93021 87435 126786 115554 99858 344937 118422 120594 ... slowest runs (cycles): ... 114339 114879 115554 118422 120594 126480 126786 132150 151407 344937 min: 82269 cycles q1: 92250 cycles median: 93021 cycles q3: 95748 cycles max: 344937 cycles RESULT TestThreadWakeup_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {93021, 3498} count Apparent clock speed: 2712 MHz TestCatchingFault: Measuring real time: 40.782 usec (4.07817e-05 sec) per iteration: 0.00407817 sec for 100 iterations 36.636 usec (3.66363e-05 sec) per iteration: 0.0898322 sec for 2452 iterations 35.928 usec (3.59278e-05 sec) per iteration: 0.0880951 sec for 2452 iterations 39.660 usec (3.96597e-05 sec) per iteration: 0.0972456 sec for 2452 iterations 36.199 usec (3.61991e-05 sec) per iteration: 0.0887603 sec for 2452 iterations 36.793 usec (3.67929e-05 sec) per iteration: 0.0902163 sec for 2452 iterations mean: 37.043187 usec stddev: 1.343939 usec relative stddev: 3.63% RESULT TestCatchingFault: gn_x86-64_with_irt_nnacl_newlib_static= {37.043187, 1.343939} us Measuring clock cycles: first runs (cycles): 116919 97512 96315 95592 95994 96642 147615 196479 173391 188517 ... slowest runs (cycles): ... 132972 147615 149622 156942 161406 171834 173391 188517 196479 292518 min: 92031 cycles q1: 93429 cycles median: 93732 cycles q3: 95094 cycles max: 292518 cycles RESULT TestCatchingFault_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {93732, 1665} count Apparent clock speed: 2530 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 106.158 usec (0.000106158 sec) per iteration: 0.0106158 sec for 100 iterations 99.302 usec (9.9302e-05 sec) per iteration: 0.0934432 sec for 941 iterations 97.775 usec (9.77747e-05 sec) per iteration: 0.092006 sec for 941 iterations 101.071 usec (0.000101071 sec) per iteration: 0.095108 sec for 941 iterations 104.842 usec (0.000104842 sec) per iteration: 0.0986561 sec for 941 iterations 103.673 usec (0.000103673 sec) per iteration: 0.0975566 sec for 941 iterations mean: 101.332607 usec stddev: 2.632250 usec relative stddev: 2.60% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: gn_x86-64_with_irt_nnacl_newlib_static= {101.332607, 2.632250} us Measuring clock cycles: first runs (cycles): 283317 289791 287376 335697 307602 277215 262830 270816 274545 274197 ... slowest runs (cycles): ... 274197 274545 277215 281685 283317 287376 289791 307602 335469 335697 min: 255033 cycles q1: 264315 cycles median: 266280 cycles q3: 268611 cycles max: 335697 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {266280, 4296} count Apparent clock speed: 2628 MHz [ OK ] nacl_newlib.run_performance_test_irt (6470 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