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 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', '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/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 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 [48960,2586280832:14:50:53.532621] Native Client module will be loaded at base address 0x0000359e00000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: gn_x86-64_with_irt_nnacl_newlib_static= {0.080110, 0.008950} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (960 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 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 (67279 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/4f3597/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 [48969,2586280832:14:51:07.933432] Native Client module will be loaded at base address 0x00007bac00000000 TestNull: Measuring real time: 0.010 usec (9.91e-09 sec) per iteration: 9.91e-07 sec for 100 iterations 0.002 usec (2.467e-09 sec) per iteration: 2.467e-06 sec for 1000 iterations 0.002 usec (2.0491e-09 sec) per iteration: 2.0491e-05 sec for 10000 iterations 0.002 usec (2.00173e-09 sec) per iteration: 0.0976884 sec for 48801913 iterations 0.002 usec (1.93685e-09 sec) per iteration: 0.0945221 sec for 48801913 iterations 0.002 usec (2.00152e-09 sec) per iteration: 0.0976781 sec for 48801913 iterations 0.002 usec (2.09824e-09 sec) per iteration: 0.102398 sec for 48801913 iterations 0.002 usec (1.99195e-09 sec) per iteration: 0.097211 sec for 48801913 iterations mean: 0.002006 usec stddev: 0.000052 usec relative stddev: 2.59% RESULT TestNull: gn_x86-64_with_irt_nnacl_newlib_static= {0.002006, 0.000052} us Measuring clock cycles: first runs (cycles): 270 18 21 21 21 30 30 30 30 30 ... slowest runs (cycles): ... 30 30 30 30 30 30 30 30 30 270 min: 18 cycles q1: 21 cycles median: 30 cycles q3: 30 cycles max: 270 cycles RESULT TestNull_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {30, 9} count Apparent clock speed: 14955 MHz TestNaClSyscall: Measuring real time: 0.228 usec (2.2768e-07 sec) per iteration: 2.2768e-05 sec for 100 iterations 0.218 usec (2.18245e-07 sec) per iteration: 0.0958556 sec for 439212 iterations 0.218 usec (2.17566e-07 sec) per iteration: 0.0955575 sec for 439212 iterations 0.224 usec (2.24175e-07 sec) per iteration: 0.0984603 sec for 439212 iterations 0.231 usec (2.31061e-07 sec) per iteration: 0.101485 sec for 439212 iterations 0.227 usec (2.27298e-07 sec) per iteration: 0.099832 sec for 439212 iterations mean: 0.223669 usec stddev: 0.005191 usec relative stddev: 2.32% RESULT TestNaClSyscall: gn_x86-64_with_irt_nnacl_newlib_static= {0.223669, 0.005191} us Measuring clock cycles: first runs (cycles): 1194 543 522 516 579 510 498 501 498 513 ... slowest runs (cycles): ... 516 516 522 543 546 549 558 579 585 1194 min: 495 cycles q1: 498 cycles median: 501 cycles q3: 507 cycles max: 1194 cycles RESULT TestNaClSyscall_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {501, 9} count Apparent clock speed: 2240 MHz TestSetjmpLongjmp: Measuring real time: 0.020 usec (1.971e-08 sec) per iteration: 1.971e-06 sec for 100 iterations 0.013 usec (1.266e-08 sec) per iteration: 1.266e-05 sec for 1000 iterations 0.012 usec (1.21262e-08 sec) per iteration: 0.0957833 sec for 7898894 iterations 0.013 usec (1.27264e-08 sec) per iteration: 0.100525 sec for 7898894 iterations 0.012 usec (1.20384e-08 sec) per iteration: 0.0950901 sec for 7898894 iterations 0.012 usec (1.20766e-08 sec) per iteration: 0.0953916 sec for 7898894 iterations 0.012 usec (1.19771e-08 sec) per iteration: 0.0946059 sec for 7898894 iterations mean: 0.012189 usec stddev: 0.000273 usec relative stddev: 2.24% RESULT TestSetjmpLongjmp: gn_x86-64_with_irt_nnacl_newlib_static= {0.012189, 0.000273} us Measuring clock cycles: first runs (cycles): 618 57 57 57 21 33 33 33 33 21 ... slowest runs (cycles): ... 33 33 33 33 33 33 57 57 57 618 min: 21 cycles q1: 24 cycles median: 30 cycles q3: 33 cycles max: 618 cycles RESULT TestSetjmpLongjmp_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {30, 9} count Apparent clock speed: 2461 MHz TestClockGetTime: Measuring real time: 0.322 usec (3.2174e-07 sec) per iteration: 3.2174e-05 sec for 100 iterations 0.320 usec (3.19766e-07 sec) per iteration: 0.0993861 sec for 310809 iterations 0.352 usec (3.51972e-07 sec) per iteration: 0.109396 sec for 310809 iterations 0.314 usec (3.14287e-07 sec) per iteration: 0.0976832 sec for 310809 iterations 0.318 usec (3.18436e-07 sec) per iteration: 0.0989726 sec for 310809 iterations 0.371 usec (3.70945e-07 sec) per iteration: 0.115293 sec for 310809 iterations mean: 0.335081 usec stddev: 0.022430 usec relative stddev: 6.69% RESULT TestClockGetTime: gn_x86-64_with_irt_nnacl_newlib_static= {0.335081, 0.022430} us Measuring clock cycles: first runs (cycles): 3156 855 924 840 840 840 852 846 849 846 ... slowest runs (cycles): ... 873 894 897 897 903 912 912 915 924 3156 min: 831 cycles q1: 843 cycles median: 849 cycles q3: 852 cycles max: 3156 cycles RESULT TestClockGetTime_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {849, 9} count Apparent clock speed: 2534 MHz TestTlsVariable: Measuring real time: 0.012 usec (1.176e-08 sec) per iteration: 1.176e-06 sec for 100 iterations 0.006 usec (6.481e-09 sec) per iteration: 6.481e-06 sec for 1000 iterations 0.006 usec (6.0521e-09 sec) per iteration: 6.0521e-05 sec for 10000 iterations 0.006 usec (5.98943e-09 sec) per iteration: 0.0989644 sec for 16523190 iterations 0.006 usec (5.97238e-09 sec) per iteration: 0.0986828 sec for 16523190 iterations 0.006 usec (6.03047e-09 sec) per iteration: 0.0996426 sec for 16523190 iterations 0.006 usec (6.00419e-09 sec) per iteration: 0.0992083 sec for 16523190 iterations 0.006 usec (5.91957e-09 sec) per iteration: 0.0978101 sec for 16523190 iterations mean: 0.005983 usec stddev: 0.000037 usec relative stddev: 0.62% RESULT TestTlsVariable: gn_x86-64_with_irt_nnacl_newlib_static= {0.005983, 0.000037} us Measuring clock cycles: first runs (cycles): 363 78 30 30 30 21 18 24 21 21 ... slowest runs (cycles): ... 30 30 30 30 30 30 30 33 78 363 min: 18 cycles q1: 21 cycles median: 21 cycles q3: 30 cycles max: 363 cycles RESULT TestTlsVariable_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {21, 9} count Apparent clock speed: 3510 MHz TestMmapAnonymous: Measuring real time: 10.650 usec (1.06499e-05 sec) per iteration: 0.00106499 sec for 100 iterations 10.122 usec (1.01221e-05 sec) per iteration: 0.095036 sec for 9389 iterations 10.130 usec (1.01296e-05 sec) per iteration: 0.0951065 sec for 9389 iterations 10.326 usec (1.03265e-05 sec) per iteration: 0.0969552 sec for 9389 iterations 10.489 usec (1.04887e-05 sec) per iteration: 0.098478 sec for 9389 iterations 10.674 usec (1.06735e-05 sec) per iteration: 0.100214 sec for 9389 iterations mean: 10.348059 usec stddev: 0.212129 usec relative stddev: 2.05% RESULT TestMmapAnonymous: gn_x86-64_with_irt_nnacl_newlib_static= {10.348059, 0.212129} us Measuring clock cycles: first runs (cycles): 38934 29220 28173 28419 28020 28704 28476 28059 28038 27993 ... slowest runs (cycles): ... 28644 28668 28683 28704 28710 29118 29220 37050 38934 42213 min: 27849 cycles q1: 28086 cycles median: 28191 cycles q3: 28404 cycles max: 42213 cycles RESULT TestMmapAnonymous_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {28191, 318} count Apparent clock speed: 2724 MHz TestAtomicIncrement: Measuring real time: 0.018 usec (1.788e-08 sec) per iteration: 1.788e-06 sec for 100 iterations 0.010 usec (1.0114e-08 sec) per iteration: 1.0114e-05 sec for 1000 iterations 0.010 usec (9.70231e-09 sec) per iteration: 0.0959295 sec for 9887284 iterations 0.010 usec (9.52265e-09 sec) per iteration: 0.0941531 sec for 9887284 iterations 0.010 usec (9.51182e-09 sec) per iteration: 0.0940461 sec for 9887284 iterations 0.010 usec (9.67356e-09 sec) per iteration: 0.0956453 sec for 9887284 iterations 0.010 usec (9.63605e-09 sec) per iteration: 0.0952744 sec for 9887284 iterations mean: 0.009609 usec stddev: 0.000078 usec relative stddev: 0.81% RESULT TestAtomicIncrement: gn_x86-64_with_irt_nnacl_newlib_static= {0.009609, 0.000078} us Measuring clock cycles: first runs (cycles): 309 51 48 48 51 39 51 48 51 48 ... slowest runs (cycles): ... 51 51 51 51 51 51 51 51 51 309 min: 39 cycles q1: 48 cycles median: 48 cycles q3: 51 cycles max: 309 cycles RESULT TestAtomicIncrement_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {48, 3} count Apparent clock speed: 4995 MHz TestUncontendedMutexLock: Measuring real time: 0.030 usec (3.042e-08 sec) per iteration: 3.042e-06 sec for 100 iterations 0.024 usec (2.3511e-08 sec) per iteration: 2.3511e-05 sec for 1000 iterations 0.023 usec (2.28722e-08 sec) per iteration: 0.097283 sec for 4253328 iterations 0.022 usec (2.24093e-08 sec) per iteration: 0.0953143 sec for 4253328 iterations 0.023 usec (2.2517e-08 sec) per iteration: 0.0957721 sec for 4253328 iterations 0.022 usec (2.24819e-08 sec) per iteration: 0.0956227 sec for 4253328 iterations 0.023 usec (2.29303e-08 sec) per iteration: 0.0975299 sec for 4253328 iterations mean: 0.022642 usec stddev: 0.000215 usec relative stddev: 0.95% RESULT TestUncontendedMutexLock: gn_x86-64_with_irt_nnacl_newlib_static= {0.022642, 0.000215} us Measuring clock cycles: first runs (cycles): 450 111 90 90 90 90 90 90 90 90 ... 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: 3842 MHz TestCondvarSignalNoOp: Measuring real time: 0.392 usec (3.9238e-07 sec) per iteration: 3.9238e-05 sec for 100 iterations 0.243 usec (2.43208e-07 sec) per iteration: 0.0619825 sec for 254854 iterations 0.241 usec (2.41366e-07 sec) per iteration: 0.061513 sec for 254854 iterations 0.240 usec (2.40044e-07 sec) per iteration: 0.0611763 sec for 254854 iterations 0.242 usec (2.42003e-07 sec) per iteration: 0.0616756 sec for 254854 iterations 0.241 usec (2.40883e-07 sec) per iteration: 0.06139 sec for 254854 iterations mean: 0.241501 usec stddev: 0.001067 usec relative stddev: 0.44% RESULT TestCondvarSignalNoOp: gn_x86-64_with_irt_nnacl_newlib_static= {0.241501, 0.001067} us Measuring clock cycles: first runs (cycles): 1929 747 654 660 648 657 645 657 645 660 ... slowest runs (cycles): ... 666 666 666 669 669 669 669 729 747 1929 min: 645 cycles q1: 651 cycles median: 654 cycles q3: 660 cycles max: 1929 cycles RESULT TestCondvarSignalNoOp_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {654, 9} count Apparent clock speed: 2708 MHz TestThreadCreateAndJoin: Measuring real time: 94.328 usec (9.43279e-05 sec) per iteration: 0.00943279 sec for 100 iterations 97.331 usec (9.73307e-05 sec) per iteration: 0.103171 sec for 1060 iterations 94.011 usec (9.40111e-05 sec) per iteration: 0.0996517 sec for 1060 iterations 102.040 usec (0.00010204 sec) per iteration: 0.108163 sec for 1060 iterations 95.151 usec (9.51515e-05 sec) per iteration: 0.100861 sec for 1060 iterations 95.325 usec (9.53245e-05 sec) per iteration: 0.101044 sec for 1060 iterations mean: 96.771592 usec stddev: 2.842869 usec relative stddev: 2.94% RESULT TestThreadCreateAndJoin: gn_x86-64_with_irt_nnacl_newlib_static= {96.771592, 2.842869} us Measuring clock cycles: first runs (cycles): 243603 258150 281814 307695 261738 257439 255537 253365 253017 250485 ... slowest runs (cycles): ... 328851 329016 329523 331050 332253 333888 335874 339531 342309 345672 min: 243603 cycles q1: 281814 cycles median: 315513 cycles q3: 321093 cycles max: 345672 cycles RESULT TestThreadCreateAndJoin_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {315513, 39279} count Apparent clock speed: 3260 MHz TestThreadWakeup: Measuring real time: 30.601 usec (3.0601e-05 sec) per iteration: 0.0030601 sec for 100 iterations 31.503 usec (3.15031e-05 sec) per iteration: 0.102921 sec for 3267 iterations 30.786 usec (3.07857e-05 sec) per iteration: 0.100577 sec for 3267 iterations 29.801 usec (2.98008e-05 sec) per iteration: 0.0973591 sec for 3267 iterations 31.794 usec (3.17939e-05 sec) per iteration: 0.103871 sec for 3267 iterations 32.258 usec (3.22578e-05 sec) per iteration: 0.105386 sec for 3267 iterations mean: 31.228250 usec stddev: 0.858959 usec relative stddev: 2.75% RESULT TestThreadWakeup: gn_x86-64_with_irt_nnacl_newlib_static= {31.228250, 0.858959} us Measuring clock cycles: first runs (cycles): 104781 103386 106542 97839 103017 85566 128844 91941 74406 96567 ... slowest runs (cycles): ... 91941 95238 96567 97839 103017 103386 104781 106542 118509 128844 min: 74406 cycles q1: 86544 cycles median: 87066 cycles q3: 88023 cycles max: 128844 cycles RESULT TestThreadWakeup_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {87066, 1479} count Apparent clock speed: 2788 MHz TestCatchingFault: Measuring real time: 34.406 usec (3.44064e-05 sec) per iteration: 0.00344064 sec for 100 iterations 33.595 usec (3.35953e-05 sec) per iteration: 0.0976278 sec for 2906 iterations 32.234 usec (3.22341e-05 sec) per iteration: 0.0936722 sec for 2906 iterations 31.919 usec (3.19188e-05 sec) per iteration: 0.092756 sec for 2906 iterations 32.067 usec (3.20672e-05 sec) per iteration: 0.0931872 sec for 2906 iterations 32.122 usec (3.21217e-05 sec) per iteration: 0.0933457 sec for 2906 iterations mean: 32.387399 usec stddev: 0.612395 usec relative stddev: 1.89% RESULT TestCatchingFault: gn_x86-64_with_irt_nnacl_newlib_static= {32.387399, 0.612395} us Measuring clock cycles: first runs (cycles): 108441 88305 98397 135078 114096 152922 183948 171321 131256 94173 ... slowest runs (cycles): ... 114096 115251 116289 116523 117333 131256 135078 152922 171321 183948 min: 85572 cycles q1: 86781 cycles median: 87855 cycles q3: 91227 cycles max: 183948 cycles RESULT TestCatchingFault_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {87855, 4446} count Apparent clock speed: 2713 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 101.131 usec (0.000101131 sec) per iteration: 0.0101131 sec for 100 iterations 100.454 usec (0.000100454 sec) per iteration: 0.0992481 sec for 988 iterations 96.857 usec (9.68572e-05 sec) per iteration: 0.095695 sec for 988 iterations 94.343 usec (9.43428e-05 sec) per iteration: 0.0932107 sec for 988 iterations 94.381 usec (9.43811e-05 sec) per iteration: 0.0932485 sec for 988 iterations 93.296 usec (9.32962e-05 sec) per iteration: 0.0921767 sec for 988 iterations mean: 95.866179 usec stddev: 2.575165 usec relative stddev: 2.69% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: gn_x86-64_with_irt_nnacl_newlib_static= {95.866179, 2.575165} us Measuring clock cycles: first runs (cycles): 267966 254862 270684 280317 282378 264081 252312 243759 332229 259338 ... slowest runs (cycles): ... 264081 265872 267966 268662 270684 272331 279867 280317 282378 332229 min: 237915 cycles q1: 247818 cycles median: 248787 cycles q3: 250530 cycles max: 332229 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {248787, 2712} count Apparent clock speed: 2595 MHz [ OK ] nacl_newlib.run_performance_test_irt (6830 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