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-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: 12492444 kB MemAvailable: 29426104 kB ====================================================================== LOAD: ====================================================================== 1.09 1.54 1.03 1/438 4184 ====================================================================== UPTIME: ====================================================================== 21499.80 154799.60 ====================================================================== 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 [4192,4147910400:19:38:44.578599] 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.103920, 0.002490} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (1720 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 (70380 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 [4222,4147701504:19:38:59.399448] Native Client module will be loaded at base address 0x0000000000000000 TestNull: Measuring real time: 0.013 usec (1.297e-08 sec) per iteration: 1.297e-06 sec for 100 iterations 0.003 usec (2.744e-09 sec) per iteration: 2.744e-06 sec for 1000 iterations 0.002 usec (2.2768e-09 sec) per iteration: 2.2768e-05 sec for 10000 iterations 0.002 usec (2.28867e-09 sec) per iteration: 0.100521 sec for 43921293 iterations 0.002 usec (2.29202e-09 sec) per iteration: 0.100669 sec for 43921293 iterations 0.002 usec (2.27901e-09 sec) per iteration: 0.100097 sec for 43921293 iterations 0.002 usec (2.30242e-09 sec) per iteration: 0.101125 sec for 43921293 iterations 0.002 usec (2.27828e-09 sec) per iteration: 0.100065 sec for 43921293 iterations mean: 0.002288 usec stddev: 0.000009 usec relative stddev: 0.39% RESULT TestNull: x86-32_with_irt_nnacl_newlib_static= {0.002288, 0.000009} us Measuring clock cycles: first runs (cycles): 88 48 24 22 25 51 24 22 24 22 ... slowest runs (cycles): ... 25 26 26 26 27 27 27 48 51 88 min: 22 cycles q1: 22 cycles median: 24 cycles q3: 24 cycles max: 88 cycles RESULT TestNull_CycleCount: x86-32_with_irt_nnacl_newlib_static= {24, 2} count Apparent clock speed: 10489 MHz TestNaClSyscall: Measuring real time: 0.268 usec (2.6797e-07 sec) per iteration: 2.6797e-05 sec for 100 iterations 0.241 usec (2.40616e-07 sec) per iteration: 0.0897922 sec for 373176 iterations 0.240 usec (2.39673e-07 sec) per iteration: 0.0894402 sec for 373176 iterations 0.240 usec (2.4044e-07 sec) per iteration: 0.0897264 sec for 373176 iterations 0.240 usec (2.40167e-07 sec) per iteration: 0.0896246 sec for 373176 iterations 0.240 usec (2.40125e-07 sec) per iteration: 0.0896088 sec for 373176 iterations mean: 0.240204 usec stddev: 0.000321 usec relative stddev: 0.13% RESULT TestNaClSyscall: x86-32_with_irt_nnacl_newlib_static= {0.240204, 0.000321} us Measuring clock cycles: first runs (cycles): 622 541 545 542 544 541 543 545 545 541 ... slowest runs (cycles): ... 545 546 546 546 546 546 546 548 548 622 min: 539 cycles q1: 543 cycles median: 543 cycles q3: 544 cycles max: 622 cycles RESULT TestNaClSyscall_CycleCount: x86-32_with_irt_nnacl_newlib_static= {543, 1} count Apparent clock speed: 2261 MHz TestSetjmpLongjmp: Measuring real time: 0.019 usec (1.896e-08 sec) per iteration: 1.896e-06 sec for 100 iterations 0.013 usec (1.2574e-08 sec) per iteration: 1.2574e-05 sec for 1000 iterations 0.012 usec (1.23137e-08 sec) per iteration: 0.0979298 sec for 7952918 iterations 0.013 usec (1.25043e-08 sec) per iteration: 0.0994459 sec for 7952918 iterations 0.012 usec (1.22787e-08 sec) per iteration: 0.0976516 sec for 7952918 iterations 0.012 usec (1.22324e-08 sec) per iteration: 0.0972834 sec for 7952918 iterations 0.013 usec (1.26434e-08 sec) per iteration: 0.100552 sec for 7952918 iterations mean: 0.012395 usec stddev: 0.000155 usec relative stddev: 1.25% RESULT TestSetjmpLongjmp: x86-32_with_irt_nnacl_newlib_static= {0.012395, 0.000155} us Measuring clock cycles: first runs (cycles): 159 64 39 35 39 37 40 36 41 40 ... slowest runs (cycles): ... 42 42 42 42 42 42 42 44 64 159 min: 33 cycles q1: 37 cycles median: 38 cycles q3: 40 cycles max: 159 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-32_with_irt_nnacl_newlib_static= {38, 3} count Apparent clock speed: 3066 MHz TestClockGetTime: Measuring real time: 0.508 usec (5.0834e-07 sec) per iteration: 5.0834e-05 sec for 100 iterations 0.468 usec (4.67899e-07 sec) per iteration: 0.0920441 sec for 196718 iterations 0.466 usec (4.66492e-07 sec) per iteration: 0.0917673 sec for 196718 iterations 0.467 usec (4.67386e-07 sec) per iteration: 0.0919432 sec for 196718 iterations 0.475 usec (4.75281e-07 sec) per iteration: 0.0934963 sec for 196718 iterations 0.467 usec (4.67299e-07 sec) per iteration: 0.0919262 sec for 196718 iterations mean: 0.468871 usec stddev: 0.003236 usec relative stddev: 0.69% RESULT TestClockGetTime: x86-32_with_irt_nnacl_newlib_static= {0.468871, 0.003236} us Measuring clock cycles: first runs (cycles): 1335 1091 1029 1028 1031 1025 1030 1020 1019 1025 ... slowest runs (cycles): ... 1029 1030 1031 1034 1062 1065 1067 1091 1127 1335 min: 1014 cycles q1: 1020 cycles median: 1022 cycles q3: 1025 cycles max: 1335 cycles RESULT TestClockGetTime_CycleCount: x86-32_with_irt_nnacl_newlib_static= {1022, 5} count Apparent clock speed: 2180 MHz TestTlsVariable: Measuring real time: 0.008 usec (8.45e-09 sec) per iteration: 8.45e-07 sec for 100 iterations 0.004 usec (3.572e-09 sec) per iteration: 3.572e-06 sec for 1000 iterations 0.003 usec (3.0308e-09 sec) per iteration: 3.0308e-05 sec for 10000 iterations 0.003 usec (3.1136e-09 sec) per iteration: 0.102732 sec for 32994588 iterations 0.003 usec (3.08219e-09 sec) per iteration: 0.101696 sec for 32994588 iterations 0.003 usec (3.06533e-09 sec) per iteration: 0.101139 sec for 32994588 iterations 0.003 usec (3.0454e-09 sec) per iteration: 0.100482 sec for 32994588 iterations 0.003 usec (3.06278e-09 sec) per iteration: 0.101055 sec for 32994588 iterations mean: 0.003074 usec stddev: 0.000023 usec relative stddev: 0.75% RESULT TestTlsVariable: x86-32_with_irt_nnacl_newlib_static= {0.003074, 0.000023} us Measuring clock cycles: first runs (cycles): 110 48 29 26 29 27 28 28 29 29 ... slowest runs (cycles): ... 29 29 29 29 29 29 29 29 48 110 min: 24 cycles q1: 26 cycles median: 27 cycles q3: 28 cycles max: 110 cycles RESULT TestTlsVariable_CycleCount: x86-32_with_irt_nnacl_newlib_static= {27, 2} count Apparent clock speed: 8784 MHz TestMmapAnonymous: Measuring real time: 3.977 usec (3.97733e-06 sec) per iteration: 0.000397733 sec for 100 iterations 2.931 usec (2.93054e-06 sec) per iteration: 0.0736798 sec for 25142 iterations 2.924 usec (2.92429e-06 sec) per iteration: 0.0735225 sec for 25142 iterations 2.947 usec (2.94657e-06 sec) per iteration: 0.0740826 sec for 25142 iterations 2.907 usec (2.90701e-06 sec) per iteration: 0.073088 sec for 25142 iterations 2.941 usec (2.94144e-06 sec) per iteration: 0.0739537 sec for 25142 iterations mean: 2.929970 usec stddev: 0.013905 usec relative stddev: 0.47% RESULT TestMmapAnonymous: x86-32_with_irt_nnacl_newlib_static= {2.929970, 0.013905} us Measuring clock cycles: first runs (cycles): 7721 6307 6221 6120 6092 6177 6115 6126 6164 6149 ... slowest runs (cycles): ... 6265 6271 6307 6307 6327 6458 6478 7721 7946 8663 min: 6065 cycles q1: 6115 cycles median: 6136 cycles q3: 6173 cycles max: 8663 cycles RESULT TestMmapAnonymous_CycleCount: x86-32_with_irt_nnacl_newlib_static= {6136, 58} count Apparent clock speed: 2094 MHz TestAtomicIncrement: Measuring real time: 0.022 usec (2.222e-08 sec) per iteration: 2.222e-06 sec for 100 iterations 0.011 usec (1.1401e-08 sec) per iteration: 1.1401e-05 sec for 1000 iterations 0.009 usec (9.47715e-09 sec) per iteration: 0.0831256 sec for 8771160 iterations 0.010 usec (9.50896e-09 sec) per iteration: 0.0834046 sec for 8771160 iterations 0.009 usec (9.46309e-09 sec) per iteration: 0.0830022 sec for 8771160 iterations 0.009 usec (9.46514e-09 sec) per iteration: 0.0830202 sec for 8771160 iterations 0.009 usec (9.46646e-09 sec) per iteration: 0.0830319 sec for 8771160 iterations mean: 0.009476 usec stddev: 0.000017 usec relative stddev: 0.18% RESULT TestAtomicIncrement: x86-32_with_irt_nnacl_newlib_static= {0.009476, 0.000017} us Measuring clock cycles: first runs (cycles): 105 68 48 49 50 48 51 48 51 48 ... slowest runs (cycles): ... 51 51 51 51 51 51 51 53 68 105 min: 48 cycles q1: 48 cycles median: 50 cycles q3: 51 cycles max: 105 cycles RESULT TestAtomicIncrement_CycleCount: x86-32_with_irt_nnacl_newlib_static= {50, 3} count Apparent clock speed: 5276 MHz TestUncontendedMutexLock: Measuring real time: 0.029 usec (2.914e-08 sec) per iteration: 2.914e-06 sec for 100 iterations 0.022 usec (2.2414e-08 sec) per iteration: 2.2414e-05 sec for 1000 iterations 0.022 usec (2.23996e-08 sec) per iteration: 0.0999357 sec for 4461497 iterations 0.023 usec (2.31668e-08 sec) per iteration: 0.103359 sec for 4461497 iterations 0.022 usec (2.2469e-08 sec) per iteration: 0.100246 sec for 4461497 iterations 0.023 usec (2.30714e-08 sec) per iteration: 0.102933 sec for 4461497 iterations 0.022 usec (2.23499e-08 sec) per iteration: 0.0997139 sec for 4461497 iterations mean: 0.022691 usec stddev: 0.000353 usec relative stddev: 1.55% RESULT TestUncontendedMutexLock: x86-32_with_irt_nnacl_newlib_static= {0.022691, 0.000353} us Measuring clock cycles: first runs (cycles): 233 149 84 79 79 79 79 79 79 79 ... slowest runs (cycles): ... 82 82 82 82 82 82 84 84 149 233 min: 79 cycles q1: 79 cycles median: 79 cycles q3: 81 cycles max: 233 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-32_with_irt_nnacl_newlib_static= {79, 2} count Apparent clock speed: 3482 MHz TestCondvarSignalNoOp: Measuring real time: 0.442 usec (4.4174e-07 sec) per iteration: 4.4174e-05 sec for 100 iterations 0.416 usec (4.15996e-07 sec) per iteration: 0.0941719 sec for 226377 iterations 0.416 usec (4.15676e-07 sec) per iteration: 0.0940995 sec for 226377 iterations 0.417 usec (4.16801e-07 sec) per iteration: 0.0943542 sec for 226377 iterations 0.417 usec (4.17429e-07 sec) per iteration: 0.0944963 sec for 226377 iterations 0.418 usec (4.17654e-07 sec) per iteration: 0.0945474 sec for 226377 iterations mean: 0.416711 usec stddev: 0.000774 usec relative stddev: 0.19% RESULT TestCondvarSignalNoOp: x86-32_with_irt_nnacl_newlib_static= {0.416711, 0.000774} us Measuring clock cycles: first runs (cycles): 1163 979 924 898 900 904 899 905 902 904 ... slowest runs (cycles): ... 904 904 905 906 924 935 935 937 979 1163 min: 895 cycles q1: 898 cycles median: 900 cycles q3: 902 cycles max: 1163 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-32_with_irt_nnacl_newlib_static= {900, 4} count Apparent clock speed: 2160 MHz TestThreadCreateAndJoin: Measuring real time: 88.625 usec (8.86247e-05 sec) per iteration: 0.00886247 sec for 100 iterations 87.931 usec (8.79305e-05 sec) per iteration: 0.0991856 sec for 1128 iterations 87.429 usec (8.74292e-05 sec) per iteration: 0.0986202 sec for 1128 iterations 87.448 usec (8.74479e-05 sec) per iteration: 0.0986412 sec for 1128 iterations 86.919 usec (8.69186e-05 sec) per iteration: 0.0980442 sec for 1128 iterations 95.645 usec (9.56446e-05 sec) per iteration: 0.107887 sec for 1128 iterations mean: 89.074181 usec stddev: 3.300752 usec relative stddev: 3.71% RESULT TestThreadCreateAndJoin: x86-32_with_irt_nnacl_newlib_static= {89.074181, 3.300752} us Measuring clock cycles: first runs (cycles): 148775 206125 231318 216122 204113 154440 129010 305187 214225 212910 ... slowest runs (cycles): ... 286097 287736 288941 290940 305187 306795 321032 366535 424475 475151 min: 126176 cycles q1: 199383 cycles median: 211566 cycles q3: 232686 cycles max: 475151 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-32_with_irt_nnacl_newlib_static= {211566, 33303} count Apparent clock speed: 2375 MHz TestThreadWakeup: Measuring real time: 11.771 usec (1.17713e-05 sec) per iteration: 0.00117713 sec for 100 iterations 12.261 usec (1.22607e-05 sec) per iteration: 0.104155 sec for 8495 iterations 13.124 usec (1.3124e-05 sec) per iteration: 0.111489 sec for 8495 iterations 12.431 usec (1.2431e-05 sec) per iteration: 0.105601 sec for 8495 iterations 12.960 usec (1.29601e-05 sec) per iteration: 0.110096 sec for 8495 iterations 12.815 usec (1.28146e-05 sec) per iteration: 0.10886 sec for 8495 iterations mean: 12.718085 usec stddev: 0.323826 usec relative stddev: 2.55% RESULT TestThreadWakeup: x86-32_with_irt_nnacl_newlib_static= {12.718085, 0.323826} us Measuring clock cycles: first runs (cycles): 33779 29289 31684 30052 30506 29124 31130 30087 29958 29808 ... slowest runs (cycles): ... 32600 32909 32940 33167 33259 33779 41943 45326 62619 83125 min: 28970 cycles q1: 29727 cycles median: 30107 cycles q3: 30811 cycles max: 83125 cycles RESULT TestThreadWakeup_CycleCount: x86-32_with_irt_nnacl_newlib_static= {30107, 1084} count Apparent clock speed: 2367 MHz TestCatchingFault: Measuring real time: 3.705 usec (3.70516e-06 sec) per iteration: 0.000370516 sec for 100 iterations 2.289 usec (2.28867e-06 sec) per iteration: 0.0617688 sec for 26989 iterations 2.294 usec (2.29381e-06 sec) per iteration: 0.0619076 sec for 26989 iterations 2.294 usec (2.29428e-06 sec) per iteration: 0.0619203 sec for 26989 iterations 2.282 usec (2.28157e-06 sec) per iteration: 0.0615772 sec for 26989 iterations 2.460 usec (2.45963e-06 sec) per iteration: 0.0663829 sec for 26989 iterations mean: 2.323590 usec stddev: 0.068173 usec relative stddev: 2.93% RESULT TestCatchingFault: x86-32_with_irt_nnacl_newlib_static= {2.323590, 0.068173} us Measuring clock cycles: first runs (cycles): 6052 5025 5015 4985 5007 4958 4998 4985 4996 5005 ... slowest runs (cycles): ... 5104 5143 5152 5267 5339 5909 5911 6052 6072 14827 min: 4926 cycles q1: 4954 cycles median: 4963 cycles q3: 4991 cycles max: 14827 cycles RESULT TestCatchingFault_CycleCount: x86-32_with_irt_nnacl_newlib_static= {4963, 37} count Apparent clock speed: 2136 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 137.274 usec (0.000137274 sec) per iteration: 0.0137274 sec for 100 iterations 127.072 usec (0.000127072 sec) per iteration: 0.0925088 sec for 728 iterations 126.572 usec (0.000126572 sec) per iteration: 0.0921447 sec for 728 iterations 184.338 usec (0.000184338 sec) per iteration: 0.134198 sec for 728 iterations 184.412 usec (0.000184412 sec) per iteration: 0.134252 sec for 728 iterations 187.861 usec (0.000187861 sec) per iteration: 0.136763 sec for 728 iterations mean: 162.051095 usec stddev: 28.792683 usec relative stddev: 17.77% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-32_with_irt_nnacl_newlib_static= {162.051095, 28.792683} us Measuring clock cycles: first runs (cycles): 423327 553554 810931 715992 668640 467931 312048 477696 613442 443440 ... slowest runs (cycles): ... 672145 714325 715992 716861 723617 726965 728886 810931 853720 1180881 min: 111064 cycles q1: 215461 cycles median: 358406 cycles q3: 487527 cycles max: 1180881 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-32_with_irt_nnacl_newlib_static= {358406, 272066} count Apparent clock speed: 2212 MHz [ OK ] nacl_newlib.run_performance_test_irt (6120 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