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-32 --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-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 gce-trusty-e833d7b0-us-east1-b-5n3b 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: 20418760 kB MemAvailable: 29473632 kB ====================================================================== LOAD: ====================================================================== 1.22 1.78 1.10 1/430 31862 ====================================================================== UPTIME: ====================================================================== 662.37 3929.18 ====================================================================== 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-32 OBJ_ROOT: /b/s/w/ir/kitchen-workdir/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/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 -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/kitchen-workdir/native_client/scons-out/nacl-x86-32/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-32 OBJ_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-linux-x86-32/obj BUILD_TYPE_DESCRIPTION: Linux opt build BUILD_OS: BUILD_ARCHITECTURE: x86 BUILD_SUBARCH: 32 TARGET_OS: TARGET_ARCHITECTURE: x86 TARGET_SUBARCH: 32 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 -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 -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-32 OBJ_ROOT: /b/s/w/ir/kitchen-workdir/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/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 -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/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-32 OBJ_ROOT: /b/s/w/ir/kitchen-workdir/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/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 -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/kitchen-workdir/native_client/scons-out/nacl_irt-x86-32/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_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/kitchen-workdir/native_client/scons-out/nacl_irt_test-x86-32/test_results/benchmark_test.out scons-out/opt-linux-x86-32/staging/nacl_helper_bootstrap scons-out/opt-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/opt-linux-x86-32/staging/nacl_helper_bootstrap scons-out/opt-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 [31870,4147627776:15:43:25.869254] 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.105420, 0.006780} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (1740 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/kitchen-workdir/native_client/scons-out/nacl_irt_test-x86-32/test_results/clock_cputime_test.out scons-out/opt-linux-x86-32/staging/nacl_helper_bootstrap scons-out/opt-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/opt-linux-x86-32/staging/nacl_helper_bootstrap scons-out/opt-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 (70270 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.997 KB RESULT hello_world_size.out: ZIPPED_x86-32_with_irt_nnacl_newlib_static= 170.128 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/kitchen-workdir/native_client/scons-out/nacl_irt_test-x86-32/test_results/performance_test.out scons-out/opt-linux-x86-32/staging/nacl_helper_bootstrap scons-out/opt-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/opt-linux-x86-32/staging/nacl_helper_bootstrap scons-out/opt-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 [31900,4147771136:15:43:40.291654] Native Client module will be loaded at base address 0x0000000000000000 TestNull: Measuring real time: 0.036 usec (3.558e-08 sec) per iteration: 3.558e-06 sec for 100 iterations 0.003 usec (2.757e-09 sec) per iteration: 2.757e-06 sec for 1000 iterations 0.002 usec (2.29e-09 sec) per iteration: 2.29e-05 sec for 10000 iterations 0.002 usec (2.27307e-09 sec) per iteration: 0.0992606 sec for 43668122 iterations 0.002 usec (2.27492e-09 sec) per iteration: 0.0993414 sec for 43668122 iterations 0.002 usec (2.26706e-09 sec) per iteration: 0.0989981 sec for 43668122 iterations 0.002 usec (2.26736e-09 sec) per iteration: 0.0990115 sec for 43668122 iterations 0.002 usec (2.27913e-09 sec) per iteration: 0.0995252 sec for 43668122 iterations mean: 0.002272 usec stddev: 0.000005 usec relative stddev: 0.20% RESULT TestNull: x86-32_with_irt_nnacl_newlib_static= {0.002272, 0.000005} us Measuring clock cycles: first runs (cycles): 80 49 24 24 24 24 22 24 24 24 ... slowest runs (cycles): ... 26 26 27 27 27 27 27 27 49 80 min: 21 cycles q1: 24 cycles median: 24 cycles q3: 25 cycles max: 80 cycles RESULT TestNull_CycleCount: x86-32_with_irt_nnacl_newlib_static= {24, 1} count Apparent clock speed: 10562 MHz TestNaClSyscall: Measuring real time: 0.245 usec (2.4457e-07 sec) per iteration: 2.4457e-05 sec for 100 iterations 0.239 usec (2.39377e-07 sec) per iteration: 0.0978765 sec for 408880 iterations 0.240 usec (2.396e-07 sec) per iteration: 0.0979678 sec for 408880 iterations 0.239 usec (2.3944e-07 sec) per iteration: 0.097902 sec for 408880 iterations 0.246 usec (2.46133e-07 sec) per iteration: 0.100639 sec for 408880 iterations 0.239 usec (2.39474e-07 sec) per iteration: 0.0979162 sec for 408880 iterations mean: 0.240805 usec stddev: 0.002665 usec relative stddev: 1.11% RESULT TestNaClSyscall: x86-32_with_irt_nnacl_newlib_static= {0.240805, 0.002665} us Measuring clock cycles: first runs (cycles): 667 545 530 531 530 530 528 531 528 533 ... slowest runs (cycles): ... 531 531 531 532 532 533 533 533 545 667 min: 525 cycles q1: 528 cycles median: 528 cycles q3: 530 cycles max: 667 cycles RESULT TestNaClSyscall_CycleCount: x86-32_with_irt_nnacl_newlib_static= {528, 2} count Apparent clock speed: 2193 MHz TestSetjmpLongjmp: Measuring real time: 0.020 usec (1.952e-08 sec) per iteration: 1.952e-06 sec for 100 iterations 0.013 usec (1.2552e-08 sec) per iteration: 1.2552e-05 sec for 1000 iterations 0.012 usec (1.22081e-08 sec) per iteration: 0.0972603 sec for 7966857 iterations 0.012 usec (1.21878e-08 sec) per iteration: 0.0970983 sec for 7966857 iterations 0.012 usec (1.22245e-08 sec) per iteration: 0.0973912 sec for 7966857 iterations 0.012 usec (1.21921e-08 sec) per iteration: 0.0971327 sec for 7966857 iterations 0.012 usec (1.22513e-08 sec) per iteration: 0.0976043 sec for 7966857 iterations mean: 0.012213 usec stddev: 0.000023 usec relative stddev: 0.19% RESULT TestSetjmpLongjmp: x86-32_with_irt_nnacl_newlib_static= {0.012213, 0.000023} us Measuring clock cycles: first runs (cycles): 117 91 39 35 40 39 37 36 39 37 ... slowest runs (cycles): ... 42 42 42 42 42 42 42 42 91 117 min: 33 cycles q1: 37 cycles median: 38 cycles q3: 40 cycles max: 117 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-32_with_irt_nnacl_newlib_static= {38, 3} count Apparent clock speed: 3111 MHz TestClockGetTime: Measuring real time: 0.465 usec (4.652e-07 sec) per iteration: 4.652e-05 sec for 100 iterations 0.465 usec (4.64876e-07 sec) per iteration: 0.0999303 sec for 214961 iterations 0.467 usec (4.66542e-07 sec) per iteration: 0.100288 sec for 214961 iterations 0.478 usec (4.78416e-07 sec) per iteration: 0.102841 sec for 214961 iterations 0.466 usec (4.65915e-07 sec) per iteration: 0.100154 sec for 214961 iterations 0.465 usec (4.653e-07 sec) per iteration: 0.100021 sec for 214961 iterations mean: 0.468210 usec stddev: 0.005134 usec relative stddev: 1.10% RESULT TestClockGetTime: x86-32_with_irt_nnacl_newlib_static= {0.468210, 0.005134} us Measuring clock cycles: first runs (cycles): 1406 1093 1028 1025 1064 1026 1020 1028 1029 1025 ... slowest runs (cycles): ... 1030 1030 1032 1040 1060 1063 1064 1093 1113 1406 min: 1016 cycles q1: 1021 cycles median: 1023 cycles q3: 1026 cycles max: 1406 cycles RESULT TestClockGetTime_CycleCount: x86-32_with_irt_nnacl_newlib_static= {1023, 5} count Apparent clock speed: 2185 MHz TestTlsVariable: Measuring real time: 0.012 usec (1.168e-08 sec) per iteration: 1.168e-06 sec for 100 iterations 0.005 usec (5.438e-09 sec) per iteration: 5.438e-06 sec for 1000 iterations 0.003 usec (3.0614e-09 sec) per iteration: 3.0614e-05 sec for 10000 iterations 0.003 usec (3.02923e-09 sec) per iteration: 0.0989491 sec for 32664793 iterations 0.003 usec (3.01997e-09 sec) per iteration: 0.0986466 sec for 32664793 iterations 0.003 usec (3.02564e-09 sec) per iteration: 0.0988319 sec for 32664793 iterations 0.003 usec (3.02559e-09 sec) per iteration: 0.0988304 sec for 32664793 iterations 0.003 usec (3.03028e-09 sec) per iteration: 0.0989835 sec for 32664793 iterations mean: 0.003026 usec stddev: 0.000004 usec relative stddev: 0.12% RESULT TestTlsVariable: x86-32_with_irt_nnacl_newlib_static= {0.003026, 0.000004} us Measuring clock cycles: first runs (cycles): 94 48 27 24 26 25 26 24 27 27 ... slowest runs (cycles): ... 27 27 27 27 27 27 27 27 48 94 min: 24 cycles q1: 26 cycles median: 26 cycles q3: 27 cycles max: 94 cycles RESULT TestTlsVariable_CycleCount: x86-32_with_irt_nnacl_newlib_static= {26, 1} count Apparent clock speed: 8592 MHz TestMmapAnonymous: Measuring real time: 4.688 usec (4.68793e-06 sec) per iteration: 0.000468793 sec for 100 iterations 2.858 usec (2.85794e-06 sec) per iteration: 0.0609628 sec for 21331 iterations 2.846 usec (2.84632e-06 sec) per iteration: 0.0607149 sec for 21331 iterations 2.851 usec (2.85124e-06 sec) per iteration: 0.0608197 sec for 21331 iterations 2.847 usec (2.84661e-06 sec) per iteration: 0.0607211 sec for 21331 iterations 2.887 usec (2.88653e-06 sec) per iteration: 0.0615725 sec for 21331 iterations mean: 2.857728 usec stddev: 0.015001 usec relative stddev: 0.52% RESULT TestMmapAnonymous: x86-32_with_irt_nnacl_newlib_static= {2.857728, 0.015001} us Measuring clock cycles: first runs (cycles): 8245 6276 6186 6223 6197 6131 6232 6155 6149 6168 ... slowest runs (cycles): ... 8429 8628 8643 8731 8960 9207 9268 9323 9400 12790 min: 6115 cycles q1: 6144 cycles median: 6175 cycles q3: 6276 cycles max: 12790 cycles RESULT TestMmapAnonymous_CycleCount: x86-32_with_irt_nnacl_newlib_static= {6175, 132} count Apparent clock speed: 2161 MHz TestAtomicIncrement: Measuring real time: 0.019 usec (1.895e-08 sec) per iteration: 1.895e-06 sec for 100 iterations 0.010 usec (9.772e-09 sec) per iteration: 9.772e-06 sec for 1000 iterations 0.011 usec (1.06959e-08 sec) per iteration: 0.000106959 sec for 10000 iterations 0.009 usec (9.45567e-09 sec) per iteration: 0.0884046 sec for 9349376 iterations 0.009 usec (9.42889e-09 sec) per iteration: 0.0881543 sec for 9349376 iterations 0.009 usec (9.41692e-09 sec) per iteration: 0.0880423 sec for 9349376 iterations 0.009 usec (9.44728e-09 sec) per iteration: 0.0883262 sec for 9349376 iterations 0.009 usec (9.4442e-09 sec) per iteration: 0.0882974 sec for 9349376 iterations mean: 0.009439 usec stddev: 0.000014 usec relative stddev: 0.15% RESULT TestAtomicIncrement: x86-32_with_irt_nnacl_newlib_static= {0.009439, 0.000014} us Measuring clock cycles: first runs (cycles): 93 69 51 50 49 50 49 50 49 50 ... slowest runs (cycles): ... 51 51 51 51 51 51 51 51 69 93 min: 48 cycles q1: 49 cycles median: 50 cycles q3: 51 cycles max: 93 cycles RESULT TestAtomicIncrement_CycleCount: x86-32_with_irt_nnacl_newlib_static= {50, 2} count Apparent clock speed: 5297 MHz TestUncontendedMutexLock: Measuring real time: 0.031 usec (3.077e-08 sec) per iteration: 3.077e-06 sec for 100 iterations 0.022 usec (2.2469e-08 sec) per iteration: 2.2469e-05 sec for 1000 iterations 0.022 usec (2.22427e-08 sec) per iteration: 0.0989926 sec for 4450576 iterations 0.022 usec (2.22675e-08 sec) per iteration: 0.0991032 sec for 4450576 iterations 0.022 usec (2.22906e-08 sec) per iteration: 0.0992059 sec for 4450576 iterations 0.022 usec (2.22777e-08 sec) per iteration: 0.0991486 sec for 4450576 iterations 0.022 usec (2.22531e-08 sec) per iteration: 0.0990393 sec for 4450576 iterations mean: 0.022266 usec stddev: 0.000017 usec relative stddev: 0.08% RESULT TestUncontendedMutexLock: x86-32_with_irt_nnacl_newlib_static= {0.022266, 0.000017} us Measuring clock cycles: first runs (cycles): 226 145 88 79 80 80 79 79 79 79 ... slowest runs (cycles): ... 82 82 82 82 82 82 88 88 145 226 min: 79 cycles q1: 79 cycles median: 79 cycles q3: 81 cycles max: 226 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-32_with_irt_nnacl_newlib_static= {79, 2} count Apparent clock speed: 3548 MHz TestCondvarSignalNoOp: Measuring real time: 0.440 usec (4.3979e-07 sec) per iteration: 4.3979e-05 sec for 100 iterations 0.411 usec (4.1146e-07 sec) per iteration: 0.0935583 sec for 227381 iterations 0.411 usec (4.11498e-07 sec) per iteration: 0.0935668 sec for 227381 iterations 0.412 usec (4.12348e-07 sec) per iteration: 0.0937602 sec for 227381 iterations 0.411 usec (4.11268e-07 sec) per iteration: 0.0935145 sec for 227381 iterations 0.411 usec (4.11047e-07 sec) per iteration: 0.0934643 sec for 227381 iterations mean: 0.411524 usec stddev: 0.000442 usec relative stddev: 0.11% RESULT TestCondvarSignalNoOp: x86-32_with_irt_nnacl_newlib_static= {0.411524, 0.000442} us Measuring clock cycles: first runs (cycles): 1164 979 940 899 900 897 898 897 896 897 ... slowest runs (cycles): ... 902 902 902 902 905 932 940 941 979 1164 min: 893 cycles q1: 897 cycles median: 899 cycles q3: 900 cycles max: 1164 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-32_with_irt_nnacl_newlib_static= {899, 3} count Apparent clock speed: 2185 MHz TestThreadCreateAndJoin: Measuring real time: 100.902 usec (0.000100902 sec) per iteration: 0.0100902 sec for 100 iterations 86.862 usec (8.6862e-05 sec) per iteration: 0.0860802 sec for 991 iterations 85.333 usec (8.53332e-05 sec) per iteration: 0.0845652 sec for 991 iterations 80.028 usec (8.00278e-05 sec) per iteration: 0.0793076 sec for 991 iterations 79.276 usec (7.92758e-05 sec) per iteration: 0.0785624 sec for 991 iterations 82.205 usec (8.22054e-05 sec) per iteration: 0.0814656 sec for 991 iterations mean: 82.740847 usec stddev: 2.944738 usec relative stddev: 3.56% RESULT TestThreadCreateAndJoin: x86-32_with_irt_nnacl_newlib_static= {82.740847, 2.944738} us Measuring clock cycles: first runs (cycles): 117023 176322 187591 357036 179982 176614 179478 163184 170747 190970 ... slowest runs (cycles): ... 193190 194753 197145 201104 208248 210916 225509 357036 567033 878106 min: 106240 cycles q1: 170747 cycles median: 175160 cycles q3: 180048 cycles max: 878106 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-32_with_irt_nnacl_newlib_static= {175160, 9301} count Apparent clock speed: 2117 MHz TestThreadWakeup: Measuring real time: 11.645 usec (1.16453e-05 sec) per iteration: 0.00116453 sec for 100 iterations 15.512 usec (1.55121e-05 sec) per iteration: 0.133203 sec for 8587 iterations 16.367 usec (1.63673e-05 sec) per iteration: 0.140546 sec for 8587 iterations 15.801 usec (1.58008e-05 sec) per iteration: 0.135681 sec for 8587 iterations 15.820 usec (1.58196e-05 sec) per iteration: 0.135843 sec for 8587 iterations 16.564 usec (1.65639e-05 sec) per iteration: 0.142234 sec for 8587 iterations mean: 16.012737 usec stddev: 0.390461 usec relative stddev: 2.44% RESULT TestThreadWakeup: x86-32_with_irt_nnacl_newlib_static= {16.012737, 0.390461} us Measuring clock cycles: first runs (cycles): 40197 37459 37811 37447 36990 38502 36822 41253 42987 41326 ... slowest runs (cycles): ... 42987 43111 43372 43549 44086 44845 45021 46524 47855 53587 min: 33176 cycles q1: 37176 cycles median: 37648 cycles q3: 39953 cycles max: 53587 cycles RESULT TestThreadWakeup_CycleCount: x86-32_with_irt_nnacl_newlib_static= {37648, 2777} count Apparent clock speed: 2351 MHz TestCatchingFault: Measuring real time: 3.503 usec (3.50325e-06 sec) per iteration: 0.000350325 sec for 100 iterations 2.320 usec (2.31985e-06 sec) per iteration: 0.0662178 sec for 28544 iterations 2.314 usec (2.31415e-06 sec) per iteration: 0.0660552 sec for 28544 iterations 2.327 usec (2.32733e-06 sec) per iteration: 0.0664312 sec for 28544 iterations 2.315 usec (2.3152e-06 sec) per iteration: 0.0660851 sec for 28544 iterations 2.319 usec (2.31931e-06 sec) per iteration: 0.0662025 sec for 28544 iterations mean: 2.319169 usec stddev: 0.004646 usec relative stddev: 0.20% RESULT TestCatchingFault: x86-32_with_irt_nnacl_newlib_static= {2.319169, 0.004646} us Measuring clock cycles: first runs (cycles): 5752 5117 4980 5002 4994 5014 5007 4987 4994 4998 ... slowest runs (cycles): ... 5018 5020 5031 5036 5117 5185 5284 5752 13976 16263 min: 4958 cycles q1: 4982 cycles median: 4990 cycles q3: 5002 cycles max: 16263 cycles RESULT TestCatchingFault_CycleCount: x86-32_with_irt_nnacl_newlib_static= {4990, 20} count Apparent clock speed: 2152 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 95.152 usec (9.51517e-05 sec) per iteration: 0.00951517 sec for 100 iterations 94.108 usec (9.41081e-05 sec) per iteration: 0.0988135 sec for 1050 iterations 83.707 usec (8.37067e-05 sec) per iteration: 0.087892 sec for 1050 iterations 80.598 usec (8.0598e-05 sec) per iteration: 0.0846279 sec for 1050 iterations 77.777 usec (7.7777e-05 sec) per iteration: 0.0816659 sec for 1050 iterations 148.197 usec (0.000148197 sec) per iteration: 0.155607 sec for 1050 iterations mean: 96.877455 usec stddev: 26.247785 usec relative stddev: 27.09% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-32_with_irt_nnacl_newlib_static= {96.877455, 26.247785} us Measuring clock cycles: first runs (cycles): 141538 287649 402383 512270 367936 192730 307566 291464 331544 290936 ... slowest runs (cycles): ... 545149 546746 564584 575504 639587 694153 701438 769212 854399 1006858 min: 120127 cycles q1: 219088 cycles median: 290936 cycles q3: 426082 cycles max: 1006858 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-32_with_irt_nnacl_newlib_static= {290936, 206994} count Apparent clock speed: 3003 MHz [ OK ] nacl_newlib.run_performance_test_irt (6150 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