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,dbg-host,nacl_irt_test platform=x86-32 --clang breakpad_tools_dir=breakpad-out large_tests_irt scons: Reading SConscript files ... ====================================================================== SCONS ARGS:['scons.py', '--verbose', '-k', '-j1', '--mode=nacl,dbg-host,nacl_irt_test', 'platform=x86-32', '--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 luci-flex-try-trusty-5-rm2p 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: 17094840 kB MemAvailable: 29470696 kB ====================================================================== LOAD: ====================================================================== 1.20 1.79 1.68 1/412 13836 ====================================================================== UPTIME: ====================================================================== 2971.81 20363.47 ====================================================================== The following environments have been configured ====================================================================== NACL_BUILD_FAMILY: UNTRUSTED BUILD_TYPE: nacl TARGET_ROOT: /b/s/w/ir/k/native_client/scons-out/nacl-x86-32 OBJ_ROOT: /b/s/w/ir/k/native_client/scons-out/nacl-x86-32/obj BUILD_TYPE_DESCRIPTION: NaCl module build BUILD_OS: BUILD_ARCHITECTURE: x86 BUILD_SUBARCH: 32 TARGET_OS: TARGET_ARCHITECTURE: x86 TARGET_SUBARCH: 32 CC: /b/s/w/ir/k/native_client/toolchain/linux_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang ASPPCOM: /b/s/w/ir/k/native_client/toolchain/linux_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang -m32 -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=32 -I/b/s/w/ir/k/native_client/scons-out/nacl-x86-32/include -I/b/s/w/ir/k -c -o UNKNOWN COMPILER NACL_BUILD_FAMILY: TRUSTED BUILD_TYPE: dbg-linux TARGET_ROOT: /b/s/w/ir/k/native_client/scons-out/dbg-linux-x86-32 OBJ_ROOT: /b/s/w/ir/k/native_client/scons-out/dbg-linux-x86-32/obj BUILD_TYPE_DESCRIPTION: Linux dbg build BUILD_OS: BUILD_ARCHITECTURE: x86 BUILD_SUBARCH: 32 TARGET_OS: TARGET_ARCHITECTURE: x86 TARGET_SUBARCH: 32 CC: /b/s/w/ir/k/third_party/llvm-build/Release+Asserts/bin/clang ASPPCOM: /b/s/w/ir/k/third_party/llvm-build/Release+Asserts/bin/clang -fPIE -m32 -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 -O0 -g -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 -D_DEBUG -I/b/s/w/ir/k -c -o UNKNOWN COMPILER NACL_BUILD_FAMILY: UNTRUSTED_IRT_TESTS BUILD_TYPE: nacl_irt_test TARGET_ROOT: /b/s/w/ir/k/native_client/scons-out/nacl_irt_test-x86-32 OBJ_ROOT: /b/s/w/ir/k/native_client/scons-out/nacl_irt_test-x86-32/obj BUILD_TYPE_DESCRIPTION: NaCl tests build with IRT BUILD_OS: BUILD_ARCHITECTURE: x86 BUILD_SUBARCH: 32 TARGET_OS: TARGET_ARCHITECTURE: x86 TARGET_SUBARCH: 32 CC: /b/s/w/ir/k/native_client/toolchain/linux_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang ASPPCOM: /b/s/w/ir/k/native_client/toolchain/linux_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang -m32 -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=32 -Iscons-out/nacl-x86-32/include -I/b/s/w/ir/k -c -o UNKNOWN COMPILER NACL_BUILD_FAMILY: UNTRUSTED_IRT BUILD_TYPE: nacl_irt TARGET_ROOT: /b/s/w/ir/k/native_client/scons-out/nacl_irt-x86-32 OBJ_ROOT: /b/s/w/ir/k/native_client/scons-out/nacl_irt-x86-32/obj BUILD_TYPE_DESCRIPTION: NaCl IRT build BUILD_OS: BUILD_ARCHITECTURE: x86 BUILD_SUBARCH: 32 TARGET_OS: TARGET_ARCHITECTURE: x86 TARGET_SUBARCH: 32 CC: /b/s/w/ir/k/native_client/toolchain/linux_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang ASPPCOM: /b/s/w/ir/k/native_client/toolchain/linux_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang -m32 -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=32 -I/b/s/w/ir/k/native_client/scons-out/nacl_irt-x86-32/include -I/b/s/w/ir/k -I/b/s/w/ir/k/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_nacl_sync_cond_test *** 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 There are 22 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-32_with_irt_nnacl_newlib_static --using_nacl_signal_handler True --subarch 32 --arch x86 --capture_output 0 --output_stamp /b/s/w/ir/k/native_client/scons-out/nacl_irt_test-x86-32/test_results/benchmark_test.out scons-out/dbg-linux-x86-32/staging/nacl_helper_bootstrap scons-out/dbg-linux-x86-32/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -B scons-out/nacl_irt-x86-32/staging/irt_core.nexe -- scons-out/nacl_irt_test-x86-32/obj/tests/benchmark/benchmark_test.nexe x86-32_with_irt_nnacl_newlib_static [ RUN ] nacl_newlib.run_benchmark_test_irt scons-out/dbg-linux-x86-32/staging/nacl_helper_bootstrap scons-out/dbg-linux-x86-32/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -B scons-out/nacl_irt-x86-32/staging/irt_core.nexe -- scons-out/nacl_irt_test-x86-32/obj/tests/benchmark/benchmark_test.nexe x86-32_with_irt_nnacl_newlib_static [13846,4147816192:12:32:43.074394] Native Client module will be loaded at base address 0x0000000000000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-32_with_irt_nnacl_newlib_static= {0.107170, 0.010900} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (1850 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-32_with_irt_nnacl_newlib_static --using_nacl_signal_handler True --subarch 32 --arch x86 --output_stamp /b/s/w/ir/k/native_client/scons-out/nacl_irt_test-x86-32/test_results/clock_cputime_test.out scons-out/dbg-linux-x86-32/staging/nacl_helper_bootstrap scons-out/dbg-linux-x86-32/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -B scons-out/nacl_irt-x86-32/staging/irt_core.nexe -- scons-out/nacl_irt_test-x86-32/obj/tests/clock/clock_test.nexe -c [ RUN ] nacl_newlib.run_clock_cputime_test_irt scons-out/dbg-linux-x86-32/staging/nacl_helper_bootstrap scons-out/dbg-linux-x86-32/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -B scons-out/nacl_irt-x86-32/staging/irt_core.nexe -- scons-out/nacl_irt_test-x86-32/obj/tests/clock/clock_test.nexe -c WARNING: should have taken less than 60.000000 secs [ OK ] nacl_newlib.run_clock_cputime_test_irt (69480 ms) doSizeCheck(["scons-out/nacl_irt_test-x86-32/obj/tests/hello_world/hello_world_size.out"], ["scons-out/nacl_irt_test-x86-32/obj/tests/hello_world/hello_world.nexe"]) RESULT hello_world_size.out: x86-32_with_irt_nnacl_newlib_static= 509.474 KB RESULT hello_world_size.out: ZIPPED_x86-32_with_irt_nnacl_newlib_static= 170.133 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-32_with_irt_nnacl_newlib_static --using_nacl_signal_handler True --subarch 32 --arch x86 --capture_output 0 --output_stamp /b/s/w/ir/k/native_client/scons-out/nacl_irt_test-x86-32/test_results/performance_test.out scons-out/dbg-linux-x86-32/staging/nacl_helper_bootstrap scons-out/dbg-linux-x86-32/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -e -B scons-out/nacl_irt-x86-32/staging/irt_core.nexe -- scons-out/nacl_irt_test-x86-32/obj/tests/performance/performance_test.nexe x86-32_with_irt_nnacl_newlib_static [ RUN ] nacl_newlib.run_performance_test_irt scons-out/dbg-linux-x86-32/staging/nacl_helper_bootstrap scons-out/dbg-linux-x86-32/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -e -B scons-out/nacl_irt-x86-32/staging/irt_core.nexe -- scons-out/nacl_irt_test-x86-32/obj/tests/performance/performance_test.nexe x86-32_with_irt_nnacl_newlib_static [13874,4147115776:12:32:57.688146] Native Client module will be loaded at base address 0x0000000000000000 TestNull: Measuring real time: 0.014 usec (1.402e-08 sec) per iteration: 1.402e-06 sec for 100 iterations 0.003 usec (2.91e-09 sec) per iteration: 2.91e-06 sec for 1000 iterations 0.002 usec (2.2951e-09 sec) per iteration: 2.2951e-05 sec for 10000 iterations 0.002 usec (2.27271e-09 sec) per iteration: 0.0990244 sec for 43571086 iterations 0.002 usec (2.26979e-09 sec) per iteration: 0.0988972 sec for 43571086 iterations 0.002 usec (2.26785e-09 sec) per iteration: 0.0988125 sec for 43571086 iterations 0.002 usec (2.2666e-09 sec) per iteration: 0.0987583 sec for 43571086 iterations 0.002 usec (2.25936e-09 sec) per iteration: 0.0984429 sec for 43571086 iterations mean: 0.002267 usec stddev: 0.000004 usec relative stddev: 0.20% RESULT TestNull: x86-32_with_irt_nnacl_newlib_static= {0.002267, 0.000004} us Measuring clock cycles: first runs (cycles): 93 44 24 25 25 22 24 26 24 24 ... slowest runs (cycles): ... 25 25 25 26 26 26 27 27 44 93 min: 22 cycles q1: 24 cycles median: 24 cycles q3: 24 cycles max: 93 cycles RESULT TestNull_CycleCount: x86-32_with_irt_nnacl_newlib_static= {24, 0} count Apparent clock speed: 10585 MHz TestNaClSyscall: Measuring real time: 0.338 usec (3.3777e-07 sec) per iteration: 3.3777e-05 sec for 100 iterations 0.332 usec (3.32048e-07 sec) per iteration: 0.0983058 sec for 296059 iterations 0.332 usec (3.31758e-07 sec) per iteration: 0.0982198 sec for 296059 iterations 0.338 usec (3.38064e-07 sec) per iteration: 0.100087 sec for 296059 iterations 0.332 usec (3.32183e-07 sec) per iteration: 0.0983458 sec for 296059 iterations 0.332 usec (3.31821e-07 sec) per iteration: 0.0982386 sec for 296059 iterations mean: 0.333175 usec stddev: 0.002449 usec relative stddev: 0.74% RESULT TestNaClSyscall: x86-32_with_irt_nnacl_newlib_static= {0.333175, 0.002449} us Measuring clock cycles: first runs (cycles): 869 761 748 723 741 761 737 733 728 723 ... slowest runs (cycles): ... 737 737 737 741 748 755 761 761 761 869 min: 721 cycles q1: 724 cycles median: 728 cycles q3: 732 cycles max: 869 cycles RESULT TestNaClSyscall_CycleCount: x86-32_with_irt_nnacl_newlib_static= {728, 8} count Apparent clock speed: 2185 MHz TestSetjmpLongjmp: Measuring real time: 0.021 usec (2.113e-08 sec) per iteration: 2.113e-06 sec for 100 iterations 0.017 usec (1.6819e-08 sec) per iteration: 1.6819e-05 sec for 1000 iterations 0.012 usec (1.21809e-08 sec) per iteration: 0.0724232 sec for 5945656 iterations 0.012 usec (1.22026e-08 sec) per iteration: 0.0725525 sec for 5945656 iterations 0.012 usec (1.21867e-08 sec) per iteration: 0.072458 sec for 5945656 iterations 0.012 usec (1.21772e-08 sec) per iteration: 0.0724013 sec for 5945656 iterations 0.012 usec (1.21563e-08 sec) per iteration: 0.0722772 sec for 5945656 iterations mean: 0.012181 usec stddev: 0.000015 usec relative stddev: 0.12% RESULT TestSetjmpLongjmp: x86-32_with_irt_nnacl_newlib_static= {0.012181, 0.000015} us Measuring clock cycles: first runs (cycles): 108 63 42 35 39 40 36 37 37 38 ... slowest runs (cycles): ... 42 42 42 42 44 44 44 44 63 108 min: 32 cycles q1: 36 cycles median: 38 cycles q3: 40 cycles max: 108 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-32_with_irt_nnacl_newlib_static= {38, 4} count Apparent clock speed: 3120 MHz TestClockGetTime: Measuring real time: 0.701 usec (7.0051e-07 sec) per iteration: 7.0051e-05 sec for 100 iterations 0.633 usec (6.32915e-07 sec) per iteration: 0.0903505 sec for 142753 iterations 0.633 usec (6.32646e-07 sec) per iteration: 0.0903121 sec for 142753 iterations 0.654 usec (6.53848e-07 sec) per iteration: 0.0933388 sec for 142753 iterations 0.635 usec (6.34836e-07 sec) per iteration: 0.0906248 sec for 142753 iterations 0.635 usec (6.35133e-07 sec) per iteration: 0.0906672 sec for 142753 iterations mean: 0.637876 usec stddev: 0.008048 usec relative stddev: 1.26% RESULT TestClockGetTime: x86-32_with_irt_nnacl_newlib_static= {0.637876, 0.008048} us Measuring clock cycles: first runs (cycles): 2572 1660 1380 1476 1418 1426 1467 1386 1434 1382 ... slowest runs (cycles): ... 1432 1434 1436 1467 1476 1479 1481 1524 1660 2572 min: 1373 cycles q1: 1380 cycles median: 1383 cycles q3: 1393 cycles max: 2572 cycles RESULT TestClockGetTime_CycleCount: x86-32_with_irt_nnacl_newlib_static= {1383, 13} count Apparent clock speed: 2168 MHz TestTlsVariable: Measuring real time: 0.015 usec (1.467e-08 sec) per iteration: 1.467e-06 sec for 100 iterations 0.004 usec (3.979e-09 sec) per iteration: 3.979e-06 sec for 1000 iterations 0.003 usec (3.3551e-09 sec) per iteration: 3.3551e-05 sec for 10000 iterations 0.003 usec (3.03761e-09 sec) per iteration: 0.0905372 sec for 29805370 iterations 0.003 usec (3.02297e-09 sec) per iteration: 0.0901006 sec for 29805370 iterations 0.003 usec (3.01299e-09 sec) per iteration: 0.0898032 sec for 29805370 iterations 0.003 usec (3.01104e-09 sec) per iteration: 0.0897453 sec for 29805370 iterations 0.003 usec (3.03136e-09 sec) per iteration: 0.0903509 sec for 29805370 iterations mean: 0.003023 usec stddev: 0.000010 usec relative stddev: 0.34% RESULT TestTlsVariable: x86-32_with_irt_nnacl_newlib_static= {0.003023, 0.000010} us Measuring clock cycles: first runs (cycles): 101 48 26 25 26 24 27 24 26 26 ... slowest runs (cycles): ... 28 28 28 29 29 29 29 29 48 101 min: 24 cycles q1: 26 cycles median: 26 cycles q3: 27 cycles max: 101 cycles RESULT TestTlsVariable_CycleCount: x86-32_with_irt_nnacl_newlib_static= {26, 1} count Apparent clock speed: 8600 MHz TestMmapAnonymous: Measuring real time: 4.652 usec (4.65174e-06 sec) per iteration: 0.000465174 sec for 100 iterations 3.794 usec (3.79398e-06 sec) per iteration: 0.0815591 sec for 21497 iterations 3.782 usec (3.78154e-06 sec) per iteration: 0.0812918 sec for 21497 iterations 3.859 usec (3.85916e-06 sec) per iteration: 0.0829604 sec for 21497 iterations 3.830 usec (3.82985e-06 sec) per iteration: 0.0823302 sec for 21497 iterations 3.850 usec (3.85043e-06 sec) per iteration: 0.0827728 sec for 21497 iterations mean: 3.822992 usec stddev: 0.030556 usec relative stddev: 0.80% RESULT TestMmapAnonymous: x86-32_with_irt_nnacl_newlib_static= {3.822992, 0.030556} us Measuring clock cycles: first runs (cycles): 10311 8364 8113 8277 8276 8166 8084 8032 8086 8146 ... slowest runs (cycles): ... 12468 12543 12605 12627 12665 13087 13180 14437 22590 24435 min: 8032 cycles q1: 8150 cycles median: 10311 cycles q3: 11456 cycles max: 24435 cycles RESULT TestMmapAnonymous_CycleCount: x86-32_with_irt_nnacl_newlib_static= {10311, 3306} count Apparent clock speed: 2697 MHz TestAtomicIncrement: Measuring real time: 0.020 usec (2.042e-08 sec) per iteration: 2.042e-06 sec for 100 iterations 0.010 usec (9.977e-09 sec) per iteration: 9.977e-06 sec for 1000 iterations 0.010 usec (9.7266e-09 sec) per iteration: 9.7266e-05 sec for 10000 iterations 0.010 usec (9.656e-09 sec) per iteration: 0.0992742 sec for 10281084 iterations 0.009 usec (9.46569e-09 sec) per iteration: 0.0973176 sec for 10281084 iterations 0.009 usec (9.44197e-09 sec) per iteration: 0.0970737 sec for 10281084 iterations 0.009 usec (9.41811e-09 sec) per iteration: 0.0968283 sec for 10281084 iterations 0.009 usec (9.43941e-09 sec) per iteration: 0.0970474 sec for 10281084 iterations mean: 0.009484 usec stddev: 0.000087 usec relative stddev: 0.92% RESULT TestAtomicIncrement: x86-32_with_irt_nnacl_newlib_static= {0.009484, 0.000087} us Measuring clock cycles: first runs (cycles): 99 68 50 51 50 51 48 51 51 50 ... slowest runs (cycles): ... 51 51 51 51 51 51 51 51 68 99 min: 48 cycles q1: 49 cycles median: 50 cycles q3: 51 cycles max: 99 cycles RESULT TestAtomicIncrement_CycleCount: x86-32_with_irt_nnacl_newlib_static= {50, 2} count Apparent clock speed: 5272 MHz TestUncontendedMutexLock: Measuring real time: 0.031 usec (3.105e-08 sec) per iteration: 3.105e-06 sec for 100 iterations 0.023 usec (2.2613e-08 sec) per iteration: 2.2613e-05 sec for 1000 iterations 0.022 usec (2.22549e-08 sec) per iteration: 0.0984163 sec for 4422234 iterations 0.022 usec (2.22766e-08 sec) per iteration: 0.0985122 sec for 4422234 iterations 0.022 usec (2.22176e-08 sec) per iteration: 0.0982516 sec for 4422234 iterations 0.022 usec (2.22194e-08 sec) per iteration: 0.0982596 sec for 4422234 iterations 0.022 usec (2.22592e-08 sec) per iteration: 0.0984352 sec for 4422234 iterations mean: 0.022246 usec stddev: 0.000023 usec relative stddev: 0.10% RESULT TestUncontendedMutexLock: x86-32_with_irt_nnacl_newlib_static= {0.022246, 0.000023} us Measuring clock cycles: first runs (cycles): 240 146 85 79 82 88 79 80 80 79 ... slowest runs (cycles): ... 82 82 82 82 82 83 85 88 146 240 min: 79 cycles q1: 79 cycles median: 79 cycles q3: 81 cycles max: 240 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-32_with_irt_nnacl_newlib_static= {79, 2} count Apparent clock speed: 3551 MHz TestCondvarSignalNoOp: Measuring real time: 0.569 usec (5.6861e-07 sec) per iteration: 5.6861e-05 sec for 100 iterations 0.526 usec (5.26141e-07 sec) per iteration: 0.0925308 sec for 175867 iterations 0.526 usec (5.26389e-07 sec) per iteration: 0.0925744 sec for 175867 iterations 0.526 usec (5.2641e-07 sec) per iteration: 0.0925781 sec for 175867 iterations 0.526 usec (5.25504e-07 sec) per iteration: 0.0924189 sec for 175867 iterations 0.525 usec (5.25175e-07 sec) per iteration: 0.0923609 sec for 175867 iterations mean: 0.525924 usec stddev: 0.000497 usec relative stddev: 0.09% RESULT TestCondvarSignalNoOp: x86-32_with_irt_nnacl_newlib_static= {0.525924, 0.000497} us Measuring clock cycles: first runs (cycles): 1353 1206 1148 1181 1179 1140 1183 1155 1150 1153 ... slowest runs (cycles): ... 1183 1183 1184 1184 1206 1221 1226 1353 1428 8898 min: 1137 cycles q1: 1146 cycles median: 1149 cycles q3: 1155 cycles max: 8898 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-32_with_irt_nnacl_newlib_static= {1149, 9} count Apparent clock speed: 2185 MHz TestThreadCreateAndJoin: Measuring real time: 96.756 usec (9.67559e-05 sec) per iteration: 0.00967559 sec for 100 iterations 76.472 usec (7.64719e-05 sec) per iteration: 0.0789955 sec for 1033 iterations 70.030 usec (7.00302e-05 sec) per iteration: 0.0723412 sec for 1033 iterations 76.712 usec (7.67116e-05 sec) per iteration: 0.0792431 sec for 1033 iterations 78.419 usec (7.84188e-05 sec) per iteration: 0.0810066 sec for 1033 iterations 75.356 usec (7.53565e-05 sec) per iteration: 0.0778432 sec for 1033 iterations mean: 75.397779 usec stddev: 2.857247 usec relative stddev: 3.79% RESULT TestThreadCreateAndJoin: x86-32_with_irt_nnacl_newlib_static= {75.397779, 2.857247} us Measuring clock cycles: first runs (cycles): 115528 176434 178507 188881 121701 173614 127794 316226 330695 175282 ... slowest runs (cycles): ... 212116 212870 224325 230599 233064 235666 238610 264236 316226 330695 min: 115528 cycles q1: 177466 cycles median: 185605 cycles q3: 195188 cycles max: 330695 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-32_with_irt_nnacl_newlib_static= {185605, 17722} count Apparent clock speed: 2462 MHz TestThreadWakeup: Measuring real time: 14.324 usec (1.43236e-05 sec) per iteration: 0.00143236 sec for 100 iterations 13.061 usec (1.30612e-05 sec) per iteration: 0.0911803 sec for 6981 iterations 12.711 usec (1.27113e-05 sec) per iteration: 0.0887373 sec for 6981 iterations 13.521 usec (1.35209e-05 sec) per iteration: 0.0943892 sec for 6981 iterations 11.338 usec (1.13384e-05 sec) per iteration: 0.0791537 sec for 6981 iterations 14.499 usec (1.44993e-05 sec) per iteration: 0.10122 sec for 6981 iterations mean: 13.026223 usec stddev: 1.035733 usec relative stddev: 7.95% RESULT TestThreadWakeup: x86-32_with_irt_nnacl_newlib_static= {13.026223, 1.035733} us Measuring clock cycles: first runs (cycles): 36642 35688 34388 34099 33640 33619 33831 34236 33697 34748 ... slowest runs (cycles): ... 39671 40000 40578 43266 43675 43704 47952 48343 51096 182904 min: 31458 cycles q1: 33906 cycles median: 34421 cycles q3: 36046 cycles max: 182904 cycles RESULT TestThreadWakeup_CycleCount: x86-32_with_irt_nnacl_newlib_static= {34421, 2140} count Apparent clock speed: 2642 MHz TestCatchingFault: Measuring real time: 4.143 usec (4.14331e-06 sec) per iteration: 0.000414331 sec for 100 iterations 2.517 usec (2.51674e-06 sec) per iteration: 0.0607415 sec for 24135 iterations 2.519 usec (2.51854e-06 sec) per iteration: 0.0607849 sec for 24135 iterations 2.512 usec (2.51212e-06 sec) per iteration: 0.06063 sec for 24135 iterations 2.512 usec (2.51222e-06 sec) per iteration: 0.0606325 sec for 24135 iterations 2.515 usec (2.51491e-06 sec) per iteration: 0.0606975 sec for 24135 iterations mean: 2.514907 usec stddev: 0.002511 usec relative stddev: 0.10% RESULT TestCatchingFault: x86-32_with_irt_nnacl_newlib_static= {2.514907, 0.002511} us Measuring clock cycles: first runs (cycles): 6295 5594 5543 5436 5389 5488 5385 5432 5388 5469 ... slowest runs (cycles): ... 5486 5488 5490 5508 5543 5594 5808 6285 6295 6833 min: 5343 cycles q1: 5378 cycles median: 5418 cycles q3: 5438 cycles max: 6833 cycles RESULT TestCatchingFault_CycleCount: x86-32_with_irt_nnacl_newlib_static= {5418, 60} count Apparent clock speed: 2154 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 88.721 usec (8.87208e-05 sec) per iteration: 0.00887208 sec for 100 iterations 79.993 usec (7.99933e-05 sec) per iteration: 0.0901524 sec for 1127 iterations 75.004 usec (7.50041e-05 sec) per iteration: 0.0845296 sec for 1127 iterations 79.334 usec (7.93342e-05 sec) per iteration: 0.0894096 sec for 1127 iterations 129.212 usec (0.000129212 sec) per iteration: 0.145622 sec for 1127 iterations 112.339 usec (0.000112339 sec) per iteration: 0.126606 sec for 1127 iterations mean: 95.176546 usec stddev: 21.639818 usec relative stddev: 22.74% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-32_with_irt_nnacl_newlib_static= {95.176546, 21.639818} us Measuring clock cycles: first runs (cycles): 120729 202575 620866 488232 270674 178117 355031 219676 141937 226693 ... slowest runs (cycles): ... 305150 316253 316412 355031 372624 379938 425221 488232 601169 620866 min: 120729 cycles q1: 162534 cycles median: 190346 cycles q3: 226436 cycles max: 620866 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-32_with_irt_nnacl_newlib_static= {190346, 63902} count Apparent clock speed: 2000 MHz [ OK ] nacl_newlib.run_performance_test_irt (5890 ms) scons: done building targets. ********************************************************************** COMMAND EXECUTION REPORT ********************************************************************** 3 command_tester.py 1 doSizeCheck ********************************************************************** ENVIRONMENT USAGE REPORT ********************************************************************** 4 nacl_irt_test-x86-32 Command return code: 0