Back to build
Raw log | Switch to full mode | Login

Running command: /b/s/w/ir/cache/vpython/b574a6/bin/python scons.py --verbose -k -j1 --mode=nacl,opt-host,nacl_irt_test platform=x86-64 --clang breakpad_tools_dir=breakpad-out large_tests_irt scons: Reading SConscript files ... ====================================================================== SCONS ARGS:['scons.py', '--verbose', '-k', '-j1', '--mode=nacl,opt-host,nacl_irt_test', 'platform=x86-64', '--clang', 'breakpad_tools_dir=breakpad-out', 'large_tests_irt'] ====================================================================== ====================================================================== Python Info: ====================================================================== linux2 2.7.14+chromium14 (df075345c7-dirty:df075345c7, Oct 3 2017, 17:29:00) [GCC 4.8.4] ====================================================================== ENV: ====================================================================== PATH /b/s/w/ir/cache/vpython/b574a6/bin:/b/s/w/ir/cipd_bin_packages:/b/s/w/ir/cipd_bin_packages/bin:/b/s/cipd_cache/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin:/b/s/w/ir/kitchen-checkout/depot_tools ====================================================================== OS: ====================================================================== Linux gce-trusty-e833d7b0-us-east1-b-x77q 4.4.0-78-generic #99~14.04.2-Ubuntu SMP Thu Apr 27 18:49:46 UTC 2017 x86_64 x86_64 x86_64 GNU/Linux ====================================================================== CPU: ====================================================================== model name : Intel(R) Xeon(R) CPU @ 2.20GHz stepping : 0 cpu MHz : 2200.000 model name : Intel(R) Xeon(R) CPU @ 2.20GHz stepping : 0 cpu MHz : 2200.000 model name : Intel(R) Xeon(R) CPU @ 2.20GHz stepping : 0 cpu MHz : 2200.000 model name : Intel(R) Xeon(R) CPU @ 2.20GHz stepping : 0 cpu MHz : 2200.000 model name : Intel(R) Xeon(R) CPU @ 2.20GHz stepping : 0 cpu MHz : 2200.000 model name : Intel(R) Xeon(R) CPU @ 2.20GHz stepping : 0 cpu MHz : 2200.000 model name : Intel(R) Xeon(R) CPU @ 2.20GHz stepping : 0 cpu MHz : 2200.000 model name : Intel(R) Xeon(R) CPU @ 2.20GHz stepping : 0 cpu MHz : 2200.000 ====================================================================== RAM: ====================================================================== MemTotal: 30882896 kB MemFree: 22442728 kB MemAvailable: 29495168 kB ====================================================================== LOAD: ====================================================================== 1.03 1.47 0.93 1/437 31534 ====================================================================== UPTIME: ====================================================================== 957.22 6411.86 ====================================================================== 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/linux_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang ASPPCOM: /b/s/w/ir/kitchen-workdir/native_client/toolchain/linux_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: opt-linux TARGET_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-linux-x86-64 OBJ_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-linux-x86-64/obj BUILD_TYPE_DESCRIPTION: Linux opt 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 -fPIE -m64 -Wall -pedantic -Wextra -Wno-long-long -Wswitch-enum -Wsign-compare -Wundef -fdiagnostics-show-option -fvisibility=hidden -fstack-protector -Werror -Wno-variadic-macros -Wno-covered-switch-default -Wno-c++11-extensions -O2 -D_FORTIFY_SOURCE=2 -D_POSIX_C_SOURCE=199506 -D_XOPEN_SOURCE=600 -D_GNU_SOURCE=1 -D_LARGEFILE64_SOURCE=1 -D__STDC_LIMIT_MACROS=1 -D__STDC_FORMAT_MACROS=1 -DNDEBUG -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/linux_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang ASPPCOM: /b/s/w/ir/kitchen-workdir/native_client/toolchain/linux_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/linux_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang ASPPCOM: /b/s/w/ir/kitchen-workdir/native_client/toolchain/linux_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/b574a6/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 --using_nacl_signal_handler True --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/opt-linux-x86-64/staging/nacl_helper_bootstrap scons-out/opt-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -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/opt-linux-x86-64/staging/nacl_helper_bootstrap scons-out/opt-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -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 [31542,2848962432:19:55:51.109794] Native Client module will be loaded at base address 0x000018b700000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_with_irt_nnacl_newlib_static= {0.105970, 0.004360} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (1620 ms) /b/s/w/ir/cache/vpython/b574a6/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 --using_nacl_signal_handler True --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/opt-linux-x86-64/staging/nacl_helper_bootstrap scons-out/opt-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -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/opt-linux-x86-64/staging/nacl_helper_bootstrap scons-out/opt-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -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 (75280 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= 527.182 KB RESULT hello_world_size.out: ZIPPED_x86-64_with_irt_nnacl_newlib_static= 186.968 KB /b/s/w/ir/cache/vpython/b574a6/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 --using_nacl_signal_handler True --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/opt-linux-x86-64/staging/nacl_helper_bootstrap scons-out/opt-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -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/opt-linux-x86-64/staging/nacl_helper_bootstrap scons-out/opt-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -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 [31572,1383159680:19:56:06.747261] Native Client module will be loaded at base address 0x0000799100000000 TestNull: Measuring real time: 0.008 usec (7.86e-09 sec) per iteration: 7.86e-07 sec for 100 iterations 0.003 usec (2.548e-09 sec) per iteration: 2.548e-06 sec for 1000 iterations 0.002 usec (2.2753e-09 sec) per iteration: 2.2753e-05 sec for 10000 iterations 0.002 usec (1.97265e-09 sec) per iteration: 0.0866985 sec for 43950248 iterations 0.002 usec (1.90143e-09 sec) per iteration: 0.0835684 sec for 43950248 iterations 0.002 usec (1.96409e-09 sec) per iteration: 0.0863222 sec for 43950248 iterations 0.002 usec (1.89791e-09 sec) per iteration: 0.0834135 sec for 43950248 iterations 0.002 usec (1.9e-09 sec) per iteration: 0.0835055 sec for 43950248 iterations mean: 0.001927 usec stddev: 0.000034 usec relative stddev: 1.75% RESULT TestNull: x86-64_with_irt_nnacl_newlib_static= {0.001927, 0.000034} us Measuring clock cycles: first runs (cycles): 108 24 22 33 33 37 22 29 22 29 ... slowest runs (cycles): ... 31 31 31 31 33 33 33 35 37 108 min: 20 cycles q1: 26 cycles median: 27 cycles q3: 29 cycles max: 108 cycles RESULT TestNull_CycleCount: x86-64_with_irt_nnacl_newlib_static= {27, 3} count Apparent clock speed: 14010 MHz TestNaClSyscall: Measuring real time: 0.108 usec (1.0821e-07 sec) per iteration: 1.0821e-05 sec for 100 iterations 0.137 usec (1.36512e-07 sec) per iteration: 0.126155 sec for 924129 iterations 0.139 usec (1.38832e-07 sec) per iteration: 0.128298 sec for 924129 iterations 0.140 usec (1.40302e-07 sec) per iteration: 0.129657 sec for 924129 iterations 0.138 usec (1.38216e-07 sec) per iteration: 0.12773 sec for 924129 iterations 0.139 usec (1.38954e-07 sec) per iteration: 0.128411 sec for 924129 iterations mean: 0.138563 usec stddev: 0.001231 usec relative stddev: 0.89% RESULT TestNaClSyscall: x86-64_with_irt_nnacl_newlib_static= {0.138563, 0.001231} us Measuring clock cycles: first runs (cycles): 365 225 189 191 246 244 198 196 190 192 ... slowest runs (cycles): ... 213 220 225 229 240 240 244 246 247 365 min: 181 cycles q1: 191 cycles median: 196 cycles q3: 202 cycles max: 365 cycles RESULT TestNaClSyscall_CycleCount: x86-64_with_irt_nnacl_newlib_static= {196, 11} count Apparent clock speed: 1415 MHz TestSetjmpLongjmp: Measuring real time: 0.018 usec (1.83e-08 sec) per iteration: 1.83e-06 sec for 100 iterations 0.014 usec (1.357e-08 sec) per iteration: 1.357e-05 sec for 1000 iterations 0.013 usec (1.28828e-08 sec) per iteration: 0.0949358 sec for 7369196 iterations 0.013 usec (1.29371e-08 sec) per iteration: 0.0953359 sec for 7369196 iterations 0.013 usec (1.29145e-08 sec) per iteration: 0.0951693 sec for 7369196 iterations 0.013 usec (1.28833e-08 sec) per iteration: 0.0949398 sec for 7369196 iterations 0.013 usec (1.29738e-08 sec) per iteration: 0.0956063 sec for 7369196 iterations mean: 0.012918 usec stddev: 0.000034 usec relative stddev: 0.27% RESULT TestSetjmpLongjmp: x86-64_with_irt_nnacl_newlib_static= {0.012918, 0.000034} us Measuring clock cycles: first runs (cycles): 216 73 37 35 35 39 47 44 37 35 ... slowest runs (cycles): ... 42 44 44 44 47 50 53 66 73 216 min: 29 cycles q1: 33 cycles median: 35 cycles q3: 38 cycles max: 216 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {35, 5} count Apparent clock speed: 2709 MHz TestClockGetTime: Measuring real time: 0.176 usec (1.7587e-07 sec) per iteration: 1.7587e-05 sec for 100 iterations 0.158 usec (1.57668e-07 sec) per iteration: 0.08965 sec for 568601 iterations 0.159 usec (1.58998e-07 sec) per iteration: 0.0904064 sec for 568601 iterations 0.159 usec (1.59361e-07 sec) per iteration: 0.0906131 sec for 568601 iterations 0.157 usec (1.57493e-07 sec) per iteration: 0.0895504 sec for 568601 iterations 0.159 usec (1.58841e-07 sec) per iteration: 0.0903171 sec for 568601 iterations mean: 0.158472 usec stddev: 0.000750 usec relative stddev: 0.47% RESULT TestClockGetTime: x86-64_with_irt_nnacl_newlib_static= {0.158472, 0.000750} us Measuring clock cycles: first runs (cycles): 759 383 414 407 379 382 365 374 363 377 ... slowest runs (cycles): ... 407 407 413 414 420 429 438 447 457 759 min: 356 cycles q1: 372 cycles median: 378 cycles q3: 387 cycles max: 759 cycles RESULT TestClockGetTime_CycleCount: x86-64_with_irt_nnacl_newlib_static= {378, 15} count Apparent clock speed: 2385 MHz TestTlsVariable: Measuring real time: 0.013 usec (1.286e-08 sec) per iteration: 1.286e-06 sec for 100 iterations 0.009 usec (8.825e-09 sec) per iteration: 8.825e-06 sec for 1000 iterations 0.008 usec (8.2714e-09 sec) per iteration: 8.2714e-05 sec for 10000 iterations 0.006 usec (5.7274e-09 sec) per iteration: 0.0692435 sec for 12089851 iterations 0.006 usec (5.96089e-09 sec) per iteration: 0.0720662 sec for 12089851 iterations 0.006 usec (5.70398e-09 sec) per iteration: 0.0689603 sec for 12089851 iterations 0.006 usec (6.05924e-09 sec) per iteration: 0.0732553 sec for 12089851 iterations 0.006 usec (5.69335e-09 sec) per iteration: 0.0688318 sec for 12089851 iterations mean: 0.005829 usec stddev: 0.000151 usec relative stddev: 2.60% RESULT TestTlsVariable: x86-64_with_irt_nnacl_newlib_static= {0.005829, 0.000151} us Measuring clock cycles: first runs (cycles): 165 73 62 64 33 31 31 28 31 28 ... slowest runs (cycles): ... 35 37 38 38 40 44 62 64 73 165 min: 26 cycles q1: 30 cycles median: 31 cycles q3: 33 cycles max: 165 cycles RESULT TestTlsVariable_CycleCount: x86-64_with_irt_nnacl_newlib_static= {31, 3} count Apparent clock speed: 5318 MHz TestMmapAnonymous: Measuring real time: 2.870 usec (2.86966e-06 sec) per iteration: 0.000286966 sec for 100 iterations 1.951 usec (1.95119e-06 sec) per iteration: 0.0679932 sec for 34847 iterations 1.934 usec (1.93423e-06 sec) per iteration: 0.0674022 sec for 34847 iterations 1.911 usec (1.91113e-06 sec) per iteration: 0.0665973 sec for 34847 iterations 1.906 usec (1.90622e-06 sec) per iteration: 0.0664262 sec for 34847 iterations 1.911 usec (1.91104e-06 sec) per iteration: 0.0665939 sec for 34847 iterations mean: 1.922764 usec stddev: 0.017239 usec relative stddev: 0.90% RESULT TestMmapAnonymous: x86-64_with_irt_nnacl_newlib_static= {1.922764, 0.017239} us Measuring clock cycles: first runs (cycles): 8940 5124 5277 4985 5372 5128 5451 5862 4968 5251 ... slowest runs (cycles): ... 6827 6859 6980 7200 7310 7497 7739 7818 7893 8940 min: 4085 cycles q1: 5267 cycles median: 5603 cycles q3: 6179 cycles max: 8940 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_with_irt_nnacl_newlib_static= {5603, 912} count Apparent clock speed: 2914 MHz TestAtomicIncrement: Measuring real time: 0.014 usec (1.417e-08 sec) per iteration: 1.417e-06 sec for 100 iterations 0.011 usec (1.0668e-08 sec) per iteration: 1.0668e-05 sec for 1000 iterations 0.010 usec (9.54672e-09 sec) per iteration: 0.0894893 sec for 9373828 iterations 0.009 usec (9.46456e-09 sec) per iteration: 0.0887191 sec for 9373828 iterations 0.009 usec (9.45262e-09 sec) per iteration: 0.0886072 sec for 9373828 iterations 0.009 usec (9.4395e-09 sec) per iteration: 0.0884843 sec for 9373828 iterations 0.009 usec (9.4654e-09 sec) per iteration: 0.0887271 sec for 9373828 iterations mean: 0.009474 usec stddev: 0.000038 usec relative stddev: 0.40% RESULT TestAtomicIncrement: x86-64_with_irt_nnacl_newlib_static= {0.009474, 0.000038} us Measuring clock cycles: first runs (cycles): 114 53 51 53 51 53 51 55 50 53 ... slowest runs (cycles): ... 55 55 55 55 55 55 55 55 55 114 min: 50 cycles q1: 52 cycles median: 53 cycles q3: 53 cycles max: 114 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_with_irt_nnacl_newlib_static= {53, 1} count Apparent clock speed: 5594 MHz TestUncontendedMutexLock: Measuring real time: 0.028 usec (2.787e-08 sec) per iteration: 2.787e-06 sec for 100 iterations 0.024 usec (2.4278e-08 sec) per iteration: 2.4278e-05 sec for 1000 iterations 0.025 usec (2.45082e-08 sec) per iteration: 0.100948 sec for 4118955 iterations 0.025 usec (2.45034e-08 sec) per iteration: 0.100928 sec for 4118955 iterations 0.024 usec (2.44606e-08 sec) per iteration: 0.100752 sec for 4118955 iterations 0.025 usec (2.45179e-08 sec) per iteration: 0.100988 sec for 4118955 iterations 0.025 usec (2.45134e-08 sec) per iteration: 0.10097 sec for 4118955 iterations mean: 0.024501 usec stddev: 0.000021 usec relative stddev: 0.08% RESULT TestUncontendedMutexLock: x86-64_with_irt_nnacl_newlib_static= {0.024501, 0.000021} us Measuring clock cycles: first runs (cycles): 196 84 84 82 86 84 82 84 84 84 ... slowest runs (cycles): ... 86 86 86 86 86 86 86 86 86 196 min: 81 cycles q1: 83 cycles median: 84 cycles q3: 84 cycles max: 196 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_with_irt_nnacl_newlib_static= {84, 1} count Apparent clock speed: 3428 MHz TestCondvarSignalNoOp: Measuring real time: 0.250 usec (2.5013e-07 sec) per iteration: 2.5013e-05 sec for 100 iterations 0.217 usec (2.17001e-07 sec) per iteration: 0.0867551 sec for 399792 iterations 0.216 usec (2.16392e-07 sec) per iteration: 0.0865119 sec for 399792 iterations 0.217 usec (2.16676e-07 sec) per iteration: 0.0866252 sec for 399792 iterations 0.216 usec (2.16008e-07 sec) per iteration: 0.0863583 sec for 399792 iterations 0.216 usec (2.16429e-07 sec) per iteration: 0.0865264 sec for 399792 iterations mean: 0.216501 usec stddev: 0.000329 usec relative stddev: 0.15% RESULT TestCondvarSignalNoOp: x86-64_with_irt_nnacl_newlib_static= {0.216501, 0.000329} us Measuring clock cycles: first runs (cycles): 623 488 482 484 482 479 477 480 480 479 ... slowest runs (cycles): ... 484 484 484 484 484 486 486 488 489 623 min: 477 cycles q1: 479 cycles median: 480 cycles q3: 482 cycles max: 623 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {480, 3} count Apparent clock speed: 2217 MHz TestThreadCreateAndJoin: Measuring real time: 73.274 usec (7.32736e-05 sec) per iteration: 0.00732736 sec for 100 iterations 40.966 usec (4.09664e-05 sec) per iteration: 0.0558782 sec for 1364 iterations 27.432 usec (2.74324e-05 sec) per iteration: 0.0374178 sec for 1364 iterations 30.732 usec (3.07316e-05 sec) per iteration: 0.0419178 sec for 1364 iterations 39.097 usec (3.90969e-05 sec) per iteration: 0.0533282 sec for 1364 iterations 31.612 usec (3.16123e-05 sec) per iteration: 0.0431192 sec for 1364 iterations mean: 33.967910 usec stddev: 5.177276 usec relative stddev: 15.24% RESULT TestThreadCreateAndJoin: x86-64_with_irt_nnacl_newlib_static= {33.967910, 5.177276} us Measuring clock cycles: first runs (cycles): 63945 71391 332135 61292 98999 80130 54026 170820 53887 74687 ... slowest runs (cycles): ... 119936 120223 149343 157810 157969 170820 300032 318652 332135 764096 min: 46661 cycles q1: 52121 cycles median: 61292 cycles q3: 78420 cycles max: 764096 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_with_irt_nnacl_newlib_static= {61292, 26299} count Apparent clock speed: 1804 MHz TestThreadWakeup: Measuring real time: 13.951 usec (1.39506e-05 sec) per iteration: 0.00139506 sec for 100 iterations 15.181 usec (1.51808e-05 sec) per iteration: 0.108816 sec for 7168 iterations 14.457 usec (1.44572e-05 sec) per iteration: 0.103629 sec for 7168 iterations 14.482 usec (1.44819e-05 sec) per iteration: 0.103807 sec for 7168 iterations 14.434 usec (1.44342e-05 sec) per iteration: 0.103464 sec for 7168 iterations 16.353 usec (1.6353e-05 sec) per iteration: 0.117218 sec for 7168 iterations mean: 14.981408 usec stddev: 0.740903 usec relative stddev: 4.95% RESULT TestThreadWakeup: x86-64_with_irt_nnacl_newlib_static= {14.981408, 0.740903} us Measuring clock cycles: first runs (cycles): 42070 39863 39712 41040 41163 40235 34421 40860 39390 42067 ... slowest runs (cycles): ... 41172 41221 41335 41505 41583 41669 42067 42070 43101 57035 min: 24575 cycles q1: 34025 cycles median: 37014 cycles q3: 38731 cycles max: 57035 cycles RESULT TestThreadWakeup_CycleCount: x86-64_with_irt_nnacl_newlib_static= {37014, 4706} count Apparent clock speed: 2471 MHz TestCatchingFault: Measuring real time: 2.936 usec (2.93634e-06 sec) per iteration: 0.000293634 sec for 100 iterations 1.715 usec (1.71474e-06 sec) per iteration: 0.0583971 sec for 34056 iterations 1.710 usec (1.70962e-06 sec) per iteration: 0.0582227 sec for 34056 iterations 1.713 usec (1.71311e-06 sec) per iteration: 0.0583415 sec for 34056 iterations 1.716 usec (1.71556e-06 sec) per iteration: 0.0584253 sec for 34056 iterations 1.711 usec (1.71103e-06 sec) per iteration: 0.0582708 sec for 34056 iterations mean: 1.712811 usec stddev: 0.002225 usec relative stddev: 0.13% RESULT TestCatchingFault: x86-64_with_irt_nnacl_newlib_static= {1.712811, 0.002225} us Measuring clock cycles: first runs (cycles): 4316 3790 3764 3713 3722 3709 3719 3731 3727 3720 ... slowest runs (cycles): ... 3744 3764 3790 3813 3821 3863 3869 4316 4426 220097 min: 3709 cycles q1: 3722 cycles median: 3729 cycles q3: 3735 cycles max: 220097 cycles RESULT TestCatchingFault_CycleCount: x86-64_with_irt_nnacl_newlib_static= {3729, 13} count Apparent clock speed: 2177 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 44.882 usec (4.48821e-05 sec) per iteration: 0.00448821 sec for 100 iterations 26.648 usec (2.66483e-05 sec) per iteration: 0.0593723 sec for 2228 iterations 27.138 usec (2.71377e-05 sec) per iteration: 0.0604629 sec for 2228 iterations 32.370 usec (3.23696e-05 sec) per iteration: 0.0721195 sec for 2228 iterations 25.713 usec (2.57133e-05 sec) per iteration: 0.0572892 sec for 2228 iterations 26.172 usec (2.6172e-05 sec) per iteration: 0.0583112 sec for 2228 iterations mean: 27.608181 usec stddev: 2.427653 usec relative stddev: 8.79% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_with_irt_nnacl_newlib_static= {27.608181, 2.427653} us Measuring clock cycles: first runs (cycles): 68641 136995 58256 107584 51947 55384 53975 47382 54044 48241 ... slowest runs (cycles): ... 94906 94941 95416 107584 114953 128144 136995 325773 335133 483009 min: 46806 cycles q1: 49371 cycles median: 51054 cycles q3: 59484 cycles max: 483009 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_with_irt_nnacl_newlib_static= {51054, 10113} count Apparent clock speed: 1849 MHz [ OK ] nacl_newlib.run_performance_test_irt (5590 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