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 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'] ====================================================================== ====================================================================== 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 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 [34888,2906624896:09:43:39.509038] Native Client module will be loaded at base address 0x00000fbc00000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_with_irt_nnacl_newlib_static= {0.077090, 0.003350} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (929 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 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 (66410 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/7c7ecd/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 [34894,2906624896:09:43:53.818748] Native Client module will be loaded at base address 0x0000455000000000 TestNull: Measuring real time: 0.009 usec (9.13e-09 sec) per iteration: 9.13e-07 sec for 100 iterations 0.003 usec (2.52e-09 sec) per iteration: 2.52e-06 sec for 1000 iterations 0.002 usec (2.0413e-09 sec) per iteration: 2.0413e-05 sec for 10000 iterations 0.002 usec (2.06839e-09 sec) per iteration: 0.101327 sec for 48988389 iterations 0.002 usec (1.99981e-09 sec) per iteration: 0.0979675 sec for 48988389 iterations 0.002 usec (2.05426e-09 sec) per iteration: 0.100635 sec for 48988389 iterations 0.002 usec (1.99263e-09 sec) per iteration: 0.0976158 sec for 48988389 iterations 0.002 usec (1.9929e-09 sec) per iteration: 0.0976291 sec for 48988389 iterations mean: 0.002022 usec stddev: 0.000033 usec relative stddev: 1.62% RESULT TestNull: x86-64_with_irt_nnacl_newlib_static= {0.002022, 0.000033} us Measuring clock cycles: first runs (cycles): 228 30 30 21 21 18 21 18 21 27 ... slowest runs (cycles): ... 30 30 30 30 30 30 30 30 30 228 min: 18 cycles q1: 18 cycles median: 21 cycles q3: 27 cycles max: 228 cycles RESULT TestNull_CycleCount: x86-64_with_irt_nnacl_newlib_static= {21, 9} count Apparent clock speed: 10388 MHz TestNaClSyscall: Measuring real time: 0.229 usec (2.2941e-07 sec) per iteration: 2.2941e-05 sec for 100 iterations 0.223 usec (2.23345e-07 sec) per iteration: 0.0973559 sec for 435900 iterations 0.226 usec (2.26038e-07 sec) per iteration: 0.0985298 sec for 435900 iterations 0.225 usec (2.25237e-07 sec) per iteration: 0.0981809 sec for 435900 iterations 0.222 usec (2.22489e-07 sec) per iteration: 0.0969829 sec for 435900 iterations 0.223 usec (2.22512e-07 sec) per iteration: 0.096993 sec for 435900 iterations mean: 0.223924 usec stddev: 0.001455 usec relative stddev: 0.65% RESULT TestNaClSyscall: x86-64_with_irt_nnacl_newlib_static= {0.223924, 0.001455} us Measuring clock cycles: first runs (cycles): 1005 513 486 495 486 495 489 492 504 495 ... slowest runs (cycles): ... 504 504 504 504 504 504 507 507 513 1005 min: 474 cycles q1: 486 cycles median: 492 cycles q3: 495 cycles max: 1005 cycles RESULT TestNaClSyscall_CycleCount: x86-64_with_irt_nnacl_newlib_static= {492, 9} count Apparent clock speed: 2197 MHz TestSetjmpLongjmp: Measuring real time: 0.018 usec (1.754e-08 sec) per iteration: 1.754e-06 sec for 100 iterations 0.013 usec (1.2538e-08 sec) per iteration: 1.2538e-05 sec for 1000 iterations 0.012 usec (1.19092e-08 sec) per iteration: 0.0949848 sec for 7975753 iterations 0.012 usec (1.19613e-08 sec) per iteration: 0.0954005 sec for 7975753 iterations 0.012 usec (1.1995e-08 sec) per iteration: 0.0956689 sec for 7975753 iterations 0.012 usec (1.18711e-08 sec) per iteration: 0.0946808 sec for 7975753 iterations 0.012 usec (1.1985e-08 sec) per iteration: 0.0955895 sec for 7975753 iterations mean: 0.011944 usec stddev: 0.000047 usec relative stddev: 0.39% RESULT TestSetjmpLongjmp: x86-64_with_irt_nnacl_newlib_static= {0.011944, 0.000047} us Measuring clock cycles: first runs (cycles): 525 54 24 24 33 33 30 24 24 24 ... slowest runs (cycles): ... 33 33 33 33 33 33 33 33 54 525 min: 21 cycles q1: 24 cycles median: 30 cycles q3: 33 cycles max: 525 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {30, 9} count Apparent clock speed: 2512 MHz TestClockGetTime: Measuring real time: 0.306 usec (3.0619e-07 sec) per iteration: 3.0619e-05 sec for 100 iterations 0.299 usec (2.99242e-07 sec) per iteration: 0.0977306 sec for 326594 iterations 0.300 usec (2.99662e-07 sec) per iteration: 0.0978678 sec for 326594 iterations 0.303 usec (3.03389e-07 sec) per iteration: 0.0990849 sec for 326594 iterations 0.301 usec (3.00888e-07 sec) per iteration: 0.0982681 sec for 326594 iterations 0.303 usec (3.02789e-07 sec) per iteration: 0.098889 sec for 326594 iterations mean: 0.301194 usec stddev: 0.001650 usec relative stddev: 0.55% RESULT TestClockGetTime: x86-64_with_irt_nnacl_newlib_static= {0.301194, 0.001650} us Measuring clock cycles: first runs (cycles): 4938 1317 1308 1245 1200 1179 1281 1335 1332 1161 ... slowest runs (cycles): ... 1380 1383 1416 1422 1464 1470 1503 1524 1542 4938 min: 1077 cycles q1: 1170 cycles median: 1233 cycles q3: 1302 cycles max: 4938 cycles RESULT TestClockGetTime_CycleCount: x86-64_with_irt_nnacl_newlib_static= {1233, 132} count Apparent clock speed: 4094 MHz TestTlsVariable: Measuring real time: 0.019 usec (1.943e-08 sec) per iteration: 1.943e-06 sec for 100 iterations 0.010 usec (1.0368e-08 sec) per iteration: 1.0368e-05 sec for 1000 iterations 0.006 usec (6.07929e-09 sec) per iteration: 0.0586351 sec for 9645061 iterations 0.006 usec (6.33659e-09 sec) per iteration: 0.0611168 sec for 9645061 iterations 0.006 usec (6.23857e-09 sec) per iteration: 0.0601714 sec for 9645061 iterations 0.006 usec (5.73879e-09 sec) per iteration: 0.0553509 sec for 9645061 iterations 0.006 usec (5.72269e-09 sec) per iteration: 0.0551957 sec for 9645061 iterations mean: 0.006023 usec stddev: 0.000253 usec relative stddev: 4.19% RESULT TestTlsVariable: x86-64_with_irt_nnacl_newlib_static= {0.006023, 0.000253} us Measuring clock cycles: first runs (cycles): 321 81 21 21 21 21 21 21 30 30 ... slowest runs (cycles): ... 30 30 30 30 30 30 30 33 81 321 min: 21 cycles q1: 21 cycles median: 24 cycles q3: 30 cycles max: 321 cycles RESULT TestTlsVariable_CycleCount: x86-64_with_irt_nnacl_newlib_static= {24, 9} count Apparent clock speed: 3985 MHz TestMmapAnonymous: Measuring real time: 10.307 usec (1.03071e-05 sec) per iteration: 0.00103071 sec for 100 iterations 9.797 usec (9.79694e-06 sec) per iteration: 0.0950499 sec for 9702 iterations 9.844 usec (9.84362e-06 sec) per iteration: 0.0955028 sec for 9702 iterations 10.025 usec (1.00249e-05 sec) per iteration: 0.0972617 sec for 9702 iterations 10.024 usec (1.00241e-05 sec) per iteration: 0.0972539 sec for 9702 iterations 9.895 usec (9.8949e-06 sec) per iteration: 0.0960003 sec for 9702 iterations mean: 9.916895 usec stddev: 0.093173 usec relative stddev: 0.94% RESULT TestMmapAnonymous: x86-64_with_irt_nnacl_newlib_static= {9.916895, 0.093173} us Measuring clock cycles: first runs (cycles): 38463 28269 27441 27084 27165 26829 26790 26427 26802 26718 ... slowest runs (cycles): ... 26814 26829 26829 26949 27084 27165 27171 27441 28269 38463 min: 26253 cycles q1: 26352 cycles median: 26421 cycles q3: 26589 cycles max: 38463 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_with_irt_nnacl_newlib_static= {26421, 237} count Apparent clock speed: 2664 MHz TestAtomicIncrement: Measuring real time: 0.016 usec (1.568e-08 sec) per iteration: 1.568e-06 sec for 100 iterations 0.010 usec (1.0095e-08 sec) per iteration: 1.0095e-05 sec for 1000 iterations 0.010 usec (9.96742e-09 sec) per iteration: 0.0987362 sec for 9905894 iterations 0.011 usec (1.06314e-08 sec) per iteration: 0.105313 sec for 9905894 iterations 0.010 usec (9.53718e-09 sec) per iteration: 0.0944743 sec for 9905894 iterations 0.010 usec (9.69204e-09 sec) per iteration: 0.0960084 sec for 9905894 iterations 0.010 usec (9.61947e-09 sec) per iteration: 0.0952895 sec for 9905894 iterations mean: 0.009889 usec stddev: 0.000398 usec relative stddev: 4.03% RESULT TestAtomicIncrement: x86-64_with_irt_nnacl_newlib_static= {0.009889, 0.000398} us Measuring clock cycles: first runs (cycles): 303 51 48 48 51 48 42 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: 4854 MHz TestUncontendedMutexLock: Measuring real time: 0.031 usec (3.05e-08 sec) per iteration: 3.05e-06 sec for 100 iterations 0.023 usec (2.3473e-08 sec) per iteration: 2.3473e-05 sec for 1000 iterations 0.024 usec (2.37829e-08 sec) per iteration: 0.10132 sec for 4260213 iterations 0.023 usec (2.30194e-08 sec) per iteration: 0.0980677 sec for 4260213 iterations 0.023 usec (2.34911e-08 sec) per iteration: 0.100077 sec for 4260213 iterations 0.023 usec (2.30778e-08 sec) per iteration: 0.0983164 sec for 4260213 iterations 0.023 usec (2.26044e-08 sec) per iteration: 0.0962994 sec for 4260213 iterations mean: 0.023195 usec stddev: 0.000407 usec relative stddev: 1.75% RESULT TestUncontendedMutexLock: x86-64_with_irt_nnacl_newlib_static= {0.023195, 0.000407} us Measuring clock cycles: first runs (cycles): 465 111 81 81 81 81 90 90 90 90 ... slowest runs (cycles): ... 90 90 90 90 90 90 90 96 111 465 min: 78 cycles q1: 81 cycles median: 87 cycles q3: 90 cycles max: 465 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_with_irt_nnacl_newlib_static= {87, 9} count Apparent clock speed: 3751 MHz TestCondvarSignalNoOp: Measuring real time: 0.343 usec (3.4317e-07 sec) per iteration: 3.4317e-05 sec for 100 iterations 0.238 usec (2.38224e-07 sec) per iteration: 0.0694186 sec for 291400 iterations 0.243 usec (2.4251e-07 sec) per iteration: 0.0706673 sec for 291400 iterations 0.254 usec (2.53598e-07 sec) per iteration: 0.0738984 sec for 291400 iterations 0.239 usec (2.38942e-07 sec) per iteration: 0.0696277 sec for 291400 iterations 0.239 usec (2.38551e-07 sec) per iteration: 0.0695137 sec for 291400 iterations mean: 0.242365 usec stddev: 0.005824 usec relative stddev: 2.40% RESULT TestCondvarSignalNoOp: x86-64_with_irt_nnacl_newlib_static= {0.242365, 0.005824} us Measuring clock cycles: first runs (cycles): 1899 678 639 642 639 639 642 630 642 642 ... slowest runs (cycles): ... 776 776 776 780 780 780 784 1200 1899 41428 min: 630 cycles q1: 642 cycles median: 764 cycles q3: 772 cycles max: 41428 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {764, 130} count Apparent clock speed: 3152 MHz TestThreadCreateAndJoin: Measuring real time: 101.413 usec (0.000101413 sec) per iteration: 0.0101413 sec for 100 iterations 99.765 usec (9.97651e-05 sec) per iteration: 0.0983684 sec for 986 iterations 93.114 usec (9.31144e-05 sec) per iteration: 0.0918108 sec for 986 iterations 92.900 usec (9.29002e-05 sec) per iteration: 0.0915996 sec for 986 iterations 92.537 usec (9.25368e-05 sec) per iteration: 0.0912413 sec for 986 iterations 92.528 usec (9.25277e-05 sec) per iteration: 0.0912323 sec for 986 iterations mean: 94.168837 usec stddev: 2.807002 usec relative stddev: 2.98% RESULT TestThreadCreateAndJoin: x86-64_with_irt_nnacl_newlib_static= {94.168837, 2.807002} us Measuring clock cycles: first runs (cycles): 235485 234513 314568 254070 275592 254334 252033 248880 264828 251724 ... slowest runs (cycles): ... 261165 262665 262866 262977 264828 265434 275592 278418 281865 314568 min: 228750 cycles q1: 247044 cycles median: 250140 cycles q3: 254334 cycles max: 314568 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_with_irt_nnacl_newlib_static= {250140, 7290} count Apparent clock speed: 2656 MHz TestThreadWakeup: Measuring real time: 31.516 usec (3.1516e-05 sec) per iteration: 0.0031516 sec for 100 iterations 31.632 usec (3.16316e-05 sec) per iteration: 0.100336 sec for 3172 iterations 31.835 usec (3.18354e-05 sec) per iteration: 0.100982 sec for 3172 iterations 31.857 usec (3.18568e-05 sec) per iteration: 0.10105 sec for 3172 iterations 31.407 usec (3.1407e-05 sec) per iteration: 0.099623 sec for 3172 iterations 31.494 usec (3.14938e-05 sec) per iteration: 0.0998982 sec for 3172 iterations mean: 31.644910 usec stddev: 0.179322 usec relative stddev: 0.57% RESULT TestThreadWakeup: x86-64_with_irt_nnacl_newlib_static= {31.644910, 0.179322} us Measuring clock cycles: first runs (cycles): 98598 94245 98124 97002 127782 86199 82857 89142 84528 92190 ... slowest runs (cycles): ... 87711 88047 89142 92190 94245 97002 98124 98598 102555 127782 min: 72906 cycles q1: 73563 cycles median: 80499 cycles q3: 84975 cycles max: 127782 cycles RESULT TestThreadWakeup_CycleCount: x86-64_with_irt_nnacl_newlib_static= {80499, 11412} count Apparent clock speed: 2544 MHz TestCatchingFault: Measuring real time: 33.777 usec (3.37768e-05 sec) per iteration: 0.00337768 sec for 100 iterations 30.937 usec (3.09366e-05 sec) per iteration: 0.0915722 sec for 2960 iterations 30.893 usec (3.08928e-05 sec) per iteration: 0.0914427 sec for 2960 iterations 31.158 usec (3.1158e-05 sec) per iteration: 0.0922277 sec for 2960 iterations 30.834 usec (3.08343e-05 sec) per iteration: 0.0912694 sec for 2960 iterations 30.842 usec (3.08421e-05 sec) per iteration: 0.0912925 sec for 2960 iterations mean: 30.932740 usec stddev: 0.118554 usec relative stddev: 0.38% RESULT TestCatchingFault: x86-64_with_irt_nnacl_newlib_static= {30.932740, 0.118554} us Measuring clock cycles: first runs (cycles): 104874 89439 89454 84705 85743 107085 95082 89478 119388 108639 ... slowest runs (cycles): ... 95082 104874 105393 107085 108639 108987 119388 122100 133158 134160 min: 80739 cycles q1: 81993 cycles median: 82467 cycles q3: 83955 cycles max: 134160 cycles RESULT TestCatchingFault_CycleCount: x86-64_with_irt_nnacl_newlib_static= {82467, 1962} count Apparent clock speed: 2666 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 94.104 usec (9.41039e-05 sec) per iteration: 0.00941039 sec for 100 iterations 93.279 usec (9.32788e-05 sec) per iteration: 0.0990621 sec for 1062 iterations 92.482 usec (9.24818e-05 sec) per iteration: 0.0982157 sec for 1062 iterations 93.291 usec (9.32913e-05 sec) per iteration: 0.0990754 sec for 1062 iterations 92.946 usec (9.29458e-05 sec) per iteration: 0.0987084 sec for 1062 iterations 94.704 usec (9.47035e-05 sec) per iteration: 0.100575 sec for 1062 iterations mean: 93.340243 usec stddev: 0.742592 usec relative stddev: 0.80% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_with_irt_nnacl_newlib_static= {93.340243, 0.742592} us Measuring clock cycles: first runs (cycles): 309627 321132 268530 268317 247035 250338 245250 246402 254892 254196 ... slowest runs (cycles): ... 272721 275652 276537 278043 280932 287697 292893 293292 309627 321132 min: 231174 cycles q1: 246102 cycles median: 250338 cycles q3: 254910 cycles max: 321132 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_with_irt_nnacl_newlib_static= {250338, 8808} count Apparent clock speed: 2682 MHz [ OK ] nacl_newlib.run_performance_test_irt (6580 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