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-west1-c-wpkx 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: 19104048 kB MemAvailable: 29478796 kB ====================================================================== LOAD: ====================================================================== 1.13 1.79 1.50 1/448 3680 ====================================================================== UPTIME: ====================================================================== 26503.33 207900.51 ====================================================================== 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 [3704,4147746560:10:59:10.139726] 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.106680, 0.002940} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (1830 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 (69470 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= 512.889 KB RESULT hello_world_size.out: ZIPPED_x86-32_with_irt_nnacl_newlib_static= 170.219 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 [3734,4147287808:10:59:24.834397] Native Client module will be loaded at base address 0x0000000000000000 TestNull: Measuring real time: 0.013 usec (1.327e-08 sec) per iteration: 1.327e-06 sec for 100 iterations 0.004 usec (3.539e-09 sec) per iteration: 3.539e-06 sec for 1000 iterations 0.003 usec (3.1065e-09 sec) per iteration: 3.1065e-05 sec for 10000 iterations 0.002 usec (2.26524e-09 sec) per iteration: 0.0729194 sec for 32190568 iterations 0.002 usec (2.26774e-09 sec) per iteration: 0.0729998 sec for 32190568 iterations 0.002 usec (2.26013e-09 sec) per iteration: 0.072755 sec for 32190568 iterations 0.002 usec (2.26255e-09 sec) per iteration: 0.0728328 sec for 32190568 iterations 0.002 usec (2.25967e-09 sec) per iteration: 0.0727401 sec for 32190568 iterations mean: 0.002263 usec stddev: 0.000003 usec relative stddev: 0.14% RESULT TestNull: x86-32_with_irt_nnacl_newlib_static= {0.002263, 0.000003} us Measuring clock cycles: first runs (cycles): 103 46 25 25 24 55 24 22 24 22 ... slowest runs (cycles): ... 36 37 37 38 40 41 42 46 55 103 min: 20 cycles q1: 24 cycles median: 25 cycles q3: 27 cycles max: 103 cycles RESULT TestNull_CycleCount: x86-32_with_irt_nnacl_newlib_static= {25, 3} count Apparent clock speed: 11047 MHz TestNaClSyscall: Measuring real time: 0.304 usec (3.0399e-07 sec) per iteration: 3.0399e-05 sec for 100 iterations 0.239 usec (2.38793e-07 sec) per iteration: 0.0785528 sec for 328958 iterations 0.239 usec (2.38694e-07 sec) per iteration: 0.0785204 sec for 328958 iterations 0.241 usec (2.413e-07 sec) per iteration: 0.0793776 sec for 328958 iterations 0.239 usec (2.39148e-07 sec) per iteration: 0.0786696 sec for 328958 iterations 0.239 usec (2.38823e-07 sec) per iteration: 0.0785627 sec for 328958 iterations mean: 0.239352 usec stddev: 0.000986 usec relative stddev: 0.41% RESULT TestNaClSyscall: x86-32_with_irt_nnacl_newlib_static= {0.239352, 0.000986} us Measuring clock cycles: first runs (cycles): 704 601 660 662 582 557 559 627 629 616 ... slowest runs (cycles): ... 660 660 660 662 669 682 704 706 798 1837 min: 546 cycles q1: 570 cycles median: 585 cycles q3: 634 cycles max: 1837 cycles RESULT TestNaClSyscall_CycleCount: x86-32_with_irt_nnacl_newlib_static= {585, 64} count Apparent clock speed: 2444 MHz TestSetjmpLongjmp: Measuring real time: 0.029 usec (2.878e-08 sec) per iteration: 2.878e-06 sec for 100 iterations 0.019 usec (1.921e-08 sec) per iteration: 1.921e-05 sec for 1000 iterations 0.012 usec (1.2169e-08 sec) per iteration: 0.0633471 sec for 5205622 iterations 0.012 usec (1.21668e-08 sec) per iteration: 0.0633356 sec for 5205622 iterations 0.012 usec (1.21708e-08 sec) per iteration: 0.0633565 sec for 5205622 iterations 0.012 usec (1.21636e-08 sec) per iteration: 0.0633189 sec for 5205622 iterations 0.012 usec (1.21599e-08 sec) per iteration: 0.0632997 sec for 5205622 iterations mean: 0.012166 usec stddev: 0.000004 usec relative stddev: 0.03% RESULT TestSetjmpLongjmp: x86-32_with_irt_nnacl_newlib_static= {0.012166, 0.000004} us Measuring clock cycles: first runs (cycles): 190 41 33 53 42 39 37 42 38 44 ... slowest runs (cycles): ... 57 58 60 60 61 61 62 63 64 190 min: 33 cycles q1: 39 cycles median: 42 cycles q3: 51 cycles max: 190 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-32_with_irt_nnacl_newlib_static= {42, 12} count Apparent clock speed: 3452 MHz TestClockGetTime: Measuring real time: 0.690 usec (6.9039e-07 sec) per iteration: 6.9039e-05 sec for 100 iterations 0.473 usec (4.73218e-07 sec) per iteration: 0.0685433 sec for 144845 iterations 0.468 usec (4.67675e-07 sec) per iteration: 0.0677404 sec for 144845 iterations 0.468 usec (4.68115e-07 sec) per iteration: 0.0678041 sec for 144845 iterations 0.467 usec (4.67464e-07 sec) per iteration: 0.0677098 sec for 144845 iterations 0.468 usec (4.67658e-07 sec) per iteration: 0.0677379 sec for 144845 iterations mean: 0.468826 usec stddev: 0.002206 usec relative stddev: 0.47% RESULT TestClockGetTime: x86-32_with_irt_nnacl_newlib_static= {0.468826, 0.002206} us Measuring clock cycles: first runs (cycles): 1538 1154 1315 1127 1127 1117 1113 1139 1133 1084 ... slowest runs (cycles): ... 1315 1320 1324 1326 1353 1355 1406 1412 1412 1538 min: 1084 cycles q1: 1133 cycles median: 1175 cycles q3: 1228 cycles max: 1538 cycles RESULT TestClockGetTime_CycleCount: x86-32_with_irt_nnacl_newlib_static= {1175, 95} count Apparent clock speed: 2506 MHz TestTlsVariable: Measuring real time: 0.013 usec (1.35e-08 sec) per iteration: 1.35e-06 sec for 100 iterations 0.006 usec (5.957e-09 sec) per iteration: 5.957e-06 sec for 1000 iterations 0.005 usec (5.3789e-09 sec) per iteration: 5.3789e-05 sec for 10000 iterations 0.003 usec (3.00969e-09 sec) per iteration: 0.0559536 sec for 18591161 iterations 0.003 usec (3.0082e-09 sec) per iteration: 0.0559259 sec for 18591161 iterations 0.003 usec (3.01093e-09 sec) per iteration: 0.0559766 sec for 18591161 iterations 0.003 usec (3.01542e-09 sec) per iteration: 0.0560602 sec for 18591161 iterations 0.003 usec (3.01007e-09 sec) per iteration: 0.0559607 sec for 18591161 iterations mean: 0.003011 usec stddev: 0.000002 usec relative stddev: 0.08% RESULT TestTlsVariable: x86-32_with_irt_nnacl_newlib_static= {0.003011, 0.000002} us Measuring clock cycles: first runs (cycles): 119 29 26 26 26 29 26 27 26 26 ... slowest runs (cycles): ... 44 44 44 44 44 46 46 48 48 119 min: 24 cycles q1: 27 cycles median: 29 cycles q3: 40 cycles max: 119 cycles RESULT TestTlsVariable_CycleCount: x86-32_with_irt_nnacl_newlib_static= {29, 13} count Apparent clock speed: 9632 MHz TestMmapAnonymous: Measuring real time: 3.201 usec (3.20098e-06 sec) per iteration: 0.000320098 sec for 100 iterations 2.896 usec (2.89562e-06 sec) per iteration: 0.0904592 sec for 31240 iterations 2.861 usec (2.86086e-06 sec) per iteration: 0.0893731 sec for 31240 iterations 2.920 usec (2.91996e-06 sec) per iteration: 0.0912195 sec for 31240 iterations 2.936 usec (2.93643e-06 sec) per iteration: 0.0917342 sec for 31240 iterations 2.889 usec (2.88869e-06 sec) per iteration: 0.0902427 sec for 31240 iterations mean: 2.900312 usec stddev: 0.026095 usec relative stddev: 0.90% RESULT TestMmapAnonymous: x86-32_with_irt_nnacl_newlib_static= {2.900312, 0.026095} us Measuring clock cycles: first runs (cycles): 11065 8259 7809 7708 7594 8540 7789 8775 8385 7976 ... slowest runs (cycles): ... 9011 9195 9204 9252 9402 9457 10377 10621 10669 11065 min: 6094 cycles q1: 6164 cycles median: 7789 cycles q3: 8478 cycles max: 11065 cycles RESULT TestMmapAnonymous_CycleCount: x86-32_with_irt_nnacl_newlib_static= {7789, 2314} count Apparent clock speed: 2686 MHz TestAtomicIncrement: Measuring real time: 0.022 usec (2.219e-08 sec) per iteration: 2.219e-06 sec for 100 iterations 0.011 usec (1.1098e-08 sec) per iteration: 1.1098e-05 sec for 1000 iterations 0.009 usec (9.40005e-09 sec) per iteration: 0.0847004 sec for 9010632 iterations 0.009 usec (9.42342e-09 sec) per iteration: 0.0849109 sec for 9010632 iterations 0.009 usec (9.40833e-09 sec) per iteration: 0.084775 sec for 9010632 iterations 0.009 usec (9.39975e-09 sec) per iteration: 0.0846977 sec for 9010632 iterations 0.009 usec (9.40077e-09 sec) per iteration: 0.0847069 sec for 9010632 iterations mean: 0.009406 usec stddev: 0.000009 usec relative stddev: 0.10% RESULT TestAtomicIncrement: x86-32_with_irt_nnacl_newlib_static= {0.009406, 0.000009} us Measuring clock cycles: first runs (cycles): 117 50 51 50 49 50 51 53 51 59 ... slowest runs (cycles): ... 55 57 57 57 57 57 57 59 62 117 min: 48 cycles q1: 50 cycles median: 51 cycles q3: 51 cycles max: 117 cycles RESULT TestAtomicIncrement_CycleCount: x86-32_with_irt_nnacl_newlib_static= {51, 1} count Apparent clock speed: 5422 MHz TestUncontendedMutexLock: Measuring real time: 0.036 usec (3.562e-08 sec) per iteration: 3.562e-06 sec for 100 iterations 0.026 usec (2.6216e-08 sec) per iteration: 2.6216e-05 sec for 1000 iterations 0.022 usec (2.2188e-08 sec) per iteration: 0.0846353 sec for 3814464 iterations 0.022 usec (2.22149e-08 sec) per iteration: 0.0847379 sec for 3814464 iterations 0.022 usec (2.22091e-08 sec) per iteration: 0.0847158 sec for 3814464 iterations 0.022 usec (2.219e-08 sec) per iteration: 0.084643 sec for 3814464 iterations 0.022 usec (2.21898e-08 sec) per iteration: 0.0846423 sec for 3814464 iterations mean: 0.022198 usec stddev: 0.000011 usec relative stddev: 0.05% RESULT TestUncontendedMutexLock: x86-32_with_irt_nnacl_newlib_static= {0.022198, 0.000011} us Measuring clock cycles: first runs (cycles): 264 119 112 85 106 95 81 84 90 82 ... slowest runs (cycles): ... 91 92 92 95 99 99 106 112 119 264 min: 79 cycles q1: 83 cycles median: 84 cycles q3: 86 cycles max: 264 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-32_with_irt_nnacl_newlib_static= {84, 3} count Apparent clock speed: 3784 MHz TestCondvarSignalNoOp: Measuring real time: 0.637 usec (6.3717e-07 sec) per iteration: 6.3717e-05 sec for 100 iterations 0.414 usec (4.13567e-07 sec) per iteration: 0.0649064 sec for 156943 iterations 0.420 usec (4.20292e-07 sec) per iteration: 0.065962 sec for 156943 iterations 0.412 usec (4.12409e-07 sec) per iteration: 0.0647248 sec for 156943 iterations 0.412 usec (4.12426e-07 sec) per iteration: 0.0647274 sec for 156943 iterations 0.412 usec (4.11769e-07 sec) per iteration: 0.0646243 sec for 156943 iterations mean: 0.414093 usec stddev: 0.003154 usec relative stddev: 0.76% RESULT TestCondvarSignalNoOp: x86-32_with_irt_nnacl_newlib_static= {0.414093, 0.003154} us Measuring clock cycles: first runs (cycles): 1320 1014 1021 988 942 954 970 981 973 990 ... slowest runs (cycles): ... 1096 1106 1111 1120 1122 1157 1162 1162 1188 1320 min: 942 cycles q1: 977 cycles median: 1003 cycles q3: 1045 cycles max: 1320 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-32_with_irt_nnacl_newlib_static= {1003, 68} count Apparent clock speed: 2422 MHz TestThreadCreateAndJoin: Measuring real time: 90.614 usec (9.06145e-05 sec) per iteration: 0.00906145 sec for 100 iterations 85.338 usec (8.53382e-05 sec) per iteration: 0.0941281 sec for 1103 iterations 79.752 usec (7.97517e-05 sec) per iteration: 0.0879661 sec for 1103 iterations 79.153 usec (7.91527e-05 sec) per iteration: 0.0873054 sec for 1103 iterations 79.579 usec (7.95793e-05 sec) per iteration: 0.0877759 sec for 1103 iterations 85.042 usec (8.5042e-05 sec) per iteration: 0.0938013 sec for 1103 iterations mean: 81.772759 usec stddev: 2.798614 usec relative stddev: 3.42% RESULT TestThreadCreateAndJoin: x86-32_with_irt_nnacl_newlib_static= {81.772759, 2.798614} us Measuring clock cycles: first runs (cycles): 157425 235591 216684 220127 204734 400219 145861 230391 200014 179778 ... slowest runs (cycles): ... 220127 225085 230391 235591 237248 237470 241806 269097 296284 400219 min: 121421 cycles q1: 181500 cycles median: 185458 cycles q3: 198648 cycles max: 400219 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-32_with_irt_nnacl_newlib_static= {185458, 17148} count Apparent clock speed: 2268 MHz TestThreadWakeup: Measuring real time: 13.725 usec (1.37248e-05 sec) per iteration: 0.00137248 sec for 100 iterations 14.889 usec (1.48892e-05 sec) per iteration: 0.108483 sec for 7286 iterations 15.351 usec (1.53507e-05 sec) per iteration: 0.111845 sec for 7286 iterations 16.322 usec (1.63218e-05 sec) per iteration: 0.118921 sec for 7286 iterations 15.465 usec (1.54647e-05 sec) per iteration: 0.112676 sec for 7286 iterations 16.073 usec (1.60731e-05 sec) per iteration: 0.117109 sec for 7286 iterations mean: 15.619925 usec stddev: 0.515470 usec relative stddev: 3.30% RESULT TestThreadWakeup: x86-32_with_irt_nnacl_newlib_static= {15.619925, 0.515470} us Measuring clock cycles: first runs (cycles): 79134 43908 42369 44192 75263 41116 55420 58449 35351 37854 ... slowest runs (cycles): ... 43908 44192 44571 51236 51786 55420 58449 63806 75263 79134 min: 29486 cycles q1: 34842 cycles median: 35338 cycles q3: 36376 cycles max: 79134 cycles RESULT TestThreadWakeup_CycleCount: x86-32_with_irt_nnacl_newlib_static= {35338, 1534} count Apparent clock speed: 2262 MHz TestCatchingFault: Measuring real time: 3.610 usec (3.60953e-06 sec) per iteration: 0.000360953 sec for 100 iterations 2.270 usec (2.27031e-06 sec) per iteration: 0.0628968 sec for 27704 iterations 2.265 usec (2.26542e-06 sec) per iteration: 0.0627611 sec for 27704 iterations 2.266 usec (2.2658e-06 sec) per iteration: 0.0627718 sec for 27704 iterations 2.266 usec (2.26632e-06 sec) per iteration: 0.062786 sec for 27704 iterations 2.272 usec (2.27188e-06 sec) per iteration: 0.0629402 sec for 27704 iterations mean: 2.267946 usec stddev: 0.002636 usec relative stddev: 0.12% RESULT TestCatchingFault: x86-32_with_irt_nnacl_newlib_static= {2.267946, 0.002636} us Measuring clock cycles: first runs (cycles): 8052 5579 5953 6015 5640 5546 5801 5436 5535 6427 ... slowest runs (cycles): ... 6109 6148 6326 6427 6579 6665 6773 6806 6840 8052 min: 4897 cycles q1: 4928 cycles median: 5525 cycles q3: 5909 cycles max: 8052 cycles RESULT TestCatchingFault_CycleCount: x86-32_with_irt_nnacl_newlib_static= {5525, 981} count Apparent clock speed: 2436 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 91.674 usec (9.16736e-05 sec) per iteration: 0.00916736 sec for 100 iterations 81.334 usec (8.13335e-05 sec) per iteration: 0.0886535 sec for 1090 iterations 84.355 usec (8.43548e-05 sec) per iteration: 0.0919467 sec for 1090 iterations 81.753 usec (8.17529e-05 sec) per iteration: 0.0891107 sec for 1090 iterations 80.885 usec (8.08847e-05 sec) per iteration: 0.0881644 sec for 1090 iterations 82.881 usec (8.28807e-05 sec) per iteration: 0.0903399 sec for 1090 iterations mean: 82.241331 usec stddev: 1.247283 usec relative stddev: 1.52% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-32_with_irt_nnacl_newlib_static= {82.241331, 1.247283} us Measuring clock cycles: first runs (cycles): 141895 235268 186643 188250 128553 180669 184434 170438 119995 146807 ... slowest runs (cycles): ... 184622 186119 186643 188250 191261 204251 204763 235268 241977 400428 min: 110391 cycles q1: 126677 cycles median: 141925 cycles q3: 170204 cycles max: 400428 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-32_with_irt_nnacl_newlib_static= {141925, 43527} count Apparent clock speed: 1726 MHz [ OK ] nacl_newlib.run_performance_test_irt (5120 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