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 platform=x86-32 --clang breakpad_tools_dir=breakpad-out large_tests scons: Reading SConscript files ... ====================================================================== SCONS ARGS:['scons.py', '--verbose', '-k', '-j1', '--mode=nacl,dbg-host', 'platform=x86-32', '--clang', 'breakpad_tools_dir=breakpad-out', 'large_tests'] ====================================================================== ====================================================================== 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-ci-trusty-4-iiux 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: 22280120 kB MemAvailable: 29398936 kB ====================================================================== LOAD: ====================================================================== 1.64 1.79 0.93 2/418 8426 ====================================================================== UPTIME: ====================================================================== 499.33 2902.62 ====================================================================== 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 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 There are 12 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 /b/s/w/ir/k/native_client/tools/command_tester.py --name nacl_newlib.run_signal_handler_single_step_test --time_warning 2 --time_error 20 --perf_env_description x86-32_nnacl_newlib_static --subarch 32 --arch x86 --capture_output 0 --output_stamp /b/s/w/ir/k/native_client/scons-out/nacl-x86-32/test_results/signal_handler_single_step.out /b/s/w/ir/k/native_client/scons-out/nacl-x86-32/obj/tests/signal_handler_single_step/step_test_host /b/s/w/ir/k/native_client/scons-out/nacl-x86-32/obj/tests/signal_handler_single_step/step_test_guest.nexe x86-32_nnacl_newlib_static [ RUN ] nacl_newlib.run_signal_handler_single_step_test /b/s/w/ir/k/native_client/scons-out/nacl-x86-32/obj/tests/signal_handler_single_step/step_test_host /b/s/w/ir/k/native_client/scons-out/nacl-x86-32/obj/tests/signal_handler_single_step/step_test_guest.nexe x86-32_nnacl_newlib_static [8471,4148373248:05:05:44.065912] Native Client module will be loaded at base address 0x00000000121b0000 Switching to untrusted: since previous switch: 201 instructions, 509 instruction bytes, 23 jumps Switching to trusted: since previous switch: 12 instructions, 39 instruction bytes, 3 jumps Switching to untrusted: since previous switch: 796 instructions, 1989 instruction bytes, 85 jumps Switching to trusted: since previous switch: 10 instructions, 23 instruction bytes, 4 jumps Switching to untrusted: since previous switch: 796 instructions, 1989 instruction bytes, 85 jumps Switching to trusted: since previous switch: 10 instructions, 23 instruction bytes, 4 jumps Switching to untrusted: since previous switch: 796 instructions, 1989 instruction bytes, 85 jumps Switching to trusted: since previous switch: 10 instructions, 23 instruction bytes, 4 jumps Switching to untrusted: since previous switch: 796 instructions, 1989 instruction bytes, 85 jumps RESULT InstructionsPerSyscall: x86-32_nnacl_newlib_static= 796 count RESULT InstructionBytesPerSyscall: x86-32_nnacl_newlib_static= 1989 count RESULT JumpsPerSyscall: x86-32_nnacl_newlib_static= 85 count Switching to trusted: since previous switch: 10 instructions, 23 instruction bytes, 4 jumps [ OK ] nacl_newlib.run_signal_handler_single_step_test (20 ms) /b/s/w/ir/cache/vpython/b574a6/bin/python tools/command_tester.py --name nacl_newlib.run_benchmark_test --time_warning 10 --time_error 100 --perf_env_description x86-32_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-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 -- scons-out/nacl-x86-32/obj/tests/benchmark/benchmark_test.nexe x86-32_nnacl_newlib_static [ RUN ] nacl_newlib.run_benchmark_test 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 -- scons-out/nacl-x86-32/obj/tests/benchmark/benchmark_test.nexe x86-32_nnacl_newlib_static [8476,4147472128:05:05:44.430531] Native Client module will be loaded at base address 0x0000000000000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-32_nnacl_newlib_static= {0.088190, 0.000600} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test (1480 ms) /b/s/w/ir/cache/vpython/b574a6/bin/python tools/command_tester.py --name nacl_newlib.run_clock_cputime_test --time_warning 60 --time_error 600 --perf_env_description x86-32_nnacl_newlib_static --using_nacl_signal_handler True --subarch 32 --arch x86 --output_stamp /b/s/w/ir/k/native_client/scons-out/nacl-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 -- scons-out/nacl-x86-32/obj/tests/clock/clock_test.nexe -c [ RUN ] nacl_newlib.run_clock_cputime_test 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 -- scons-out/nacl-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 (70929 ms) doSizeCheck(["scons-out/nacl-x86-32/obj/tests/hello_world/hello_world_size.out"], ["scons-out/nacl-x86-32/obj/tests/hello_world/hello_world.nexe"]) RESULT hello_world_size.out: x86-32_nnacl_newlib_static= 508.969 KB RESULT hello_world_size.out: ZIPPED_x86-32_nnacl_newlib_static= 171.081 KB /b/s/w/ir/cache/vpython/b574a6/bin/python tools/command_tester.py --name nacl_newlib.run_performance_test --time_warning 10 --time_error 100 --perf_env_description x86-32_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-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 -- scons-out/nacl-x86-32/obj/tests/performance/performance_test.nexe x86-32_nnacl_newlib_static [ RUN ] nacl_newlib.run_performance_test 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 -- scons-out/nacl-x86-32/obj/tests/performance/performance_test.nexe x86-32_nnacl_newlib_static [8617,4147889920:05:05:59.327364] Native Client module will be loaded at base address 0x0000000000000000 TestNull: Measuring real time: 0.019 usec (1.893e-08 sec) per iteration: 1.893e-06 sec for 100 iterations 0.004 usec (3.823e-09 sec) per iteration: 3.823e-06 sec for 1000 iterations 0.003 usec (3.0736e-09 sec) per iteration: 3.0736e-05 sec for 10000 iterations 0.002 usec (2.279e-09 sec) per iteration: 0.0741475 sec for 32535137 iterations 0.002 usec (2.29965e-09 sec) per iteration: 0.0748195 sec for 32535137 iterations 0.002 usec (2.28119e-09 sec) per iteration: 0.0742189 sec for 32535137 iterations 0.002 usec (2.27496e-09 sec) per iteration: 0.0740162 sec for 32535137 iterations 0.002 usec (2.28555e-09 sec) per iteration: 0.0743606 sec for 32535137 iterations mean: 0.002284 usec stddev: 0.000009 usec relative stddev: 0.37% RESULT TestNull: x86-32_nnacl_newlib_static= {0.002284, 0.000009} us Measuring clock cycles: first runs (cycles): 108 27 24 24 22 25 22 26 22 24 ... slowest runs (cycles): ... 35 35 36 37 37 37 38 38 38 108 min: 19 cycles q1: 22 cycles median: 24 cycles q3: 28 cycles max: 108 cycles RESULT TestNull_CycleCount: x86-32_nnacl_newlib_static= {24, 6} count Apparent clock speed: 10508 MHz TestNaClSyscall: Measuring real time: 0.448 usec (4.4769e-07 sec) per iteration: 4.4769e-05 sec for 100 iterations 0.336 usec (3.35861e-07 sec) per iteration: 0.0750206 sec for 223368 iterations 0.341 usec (3.40613e-07 sec) per iteration: 0.0760821 sec for 223368 iterations 0.336 usec (3.36277e-07 sec) per iteration: 0.0751134 sec for 223368 iterations 0.334 usec (3.33792e-07 sec) per iteration: 0.0745585 sec for 223368 iterations 0.335 usec (3.34604e-07 sec) per iteration: 0.0747397 sec for 223368 iterations mean: 0.336229 usec stddev: 0.002364 usec relative stddev: 0.70% RESULT TestNaClSyscall: x86-32_nnacl_newlib_static= {0.336229, 0.002364} us Measuring clock cycles: first runs (cycles): 1010 849 817 811 823 792 806 789 783 788 ... slowest runs (cycles): ... 926 926 935 937 966 983 999 1010 1025 2103 min: 775 cycles q1: 792 cycles median: 811 cycles q3: 867 cycles max: 2103 cycles RESULT TestNaClSyscall_CycleCount: x86-32_nnacl_newlib_static= {811, 75} count Apparent clock speed: 2412 MHz TestSetjmpLongjmp: Measuring real time: 0.031 usec (3.145e-08 sec) per iteration: 3.145e-06 sec for 100 iterations 0.019 usec (1.93e-08 sec) per iteration: 1.93e-05 sec for 1000 iterations 0.012 usec (1.22178e-08 sec) per iteration: 0.0633048 sec for 5181347 iterations 0.013 usec (1.28246e-08 sec) per iteration: 0.0664486 sec for 5181347 iterations 0.012 usec (1.22527e-08 sec) per iteration: 0.0634855 sec for 5181347 iterations 0.012 usec (1.23409e-08 sec) per iteration: 0.0639427 sec for 5181347 iterations 0.013 usec (1.30735e-08 sec) per iteration: 0.0677382 sec for 5181347 iterations mean: 0.012542 usec stddev: 0.000344 usec relative stddev: 2.74% RESULT TestSetjmpLongjmp: x86-32_nnacl_newlib_static= {0.012542, 0.000344} us Measuring clock cycles: first runs (cycles): 218 88 64 40 48 48 37 42 44 33 ... slowest runs (cycles): ... 66 68 68 68 70 70 72 72 88 218 min: 33 cycles q1: 40 cycles median: 44 cycles q3: 52 cycles max: 218 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-32_nnacl_newlib_static= {44, 12} count Apparent clock speed: 3508 MHz TestClockGetTime: Measuring real time: 0.847 usec (8.4692e-07 sec) per iteration: 8.4692e-05 sec for 100 iterations 0.635 usec (6.34813e-07 sec) per iteration: 0.0749549 sec for 118074 iterations 0.636 usec (6.35803e-07 sec) per iteration: 0.0750718 sec for 118074 iterations 0.636 usec (6.36127e-07 sec) per iteration: 0.0751101 sec for 118074 iterations 0.635 usec (6.35191e-07 sec) per iteration: 0.0749995 sec for 118074 iterations 0.637 usec (6.36901e-07 sec) per iteration: 0.0752015 sec for 118074 iterations mean: 0.635767 usec stddev: 0.000729 usec relative stddev: 0.11% RESULT TestClockGetTime: x86-32_nnacl_newlib_static= {0.635767, 0.000729} us Measuring clock cycles: first runs (cycles): 2741 1821 1630 1701 1612 1692 1562 1614 1670 1570 ... slowest runs (cycles): ... 1831 1848 1854 1863 1868 1905 1947 1949 2015 2741 min: 1562 cycles q1: 1635 cycles median: 1692 cycles q3: 1744 cycles max: 2741 cycles RESULT TestClockGetTime_CycleCount: x86-32_nnacl_newlib_static= {1692, 109} count Apparent clock speed: 2661 MHz TestTlsVariable: Measuring real time: 0.016 usec (1.624e-08 sec) per iteration: 1.624e-06 sec for 100 iterations 0.006 usec (6.355e-09 sec) per iteration: 6.355e-06 sec for 1000 iterations 0.005 usec (5.299e-09 sec) per iteration: 5.299e-05 sec for 10000 iterations 0.003 usec (3.03626e-09 sec) per iteration: 0.0572987 sec for 18871485 iterations 0.003 usec (3.09543e-09 sec) per iteration: 0.0584154 sec for 18871485 iterations 0.003 usec (3.02785e-09 sec) per iteration: 0.0571401 sec for 18871485 iterations 0.003 usec (3.0437e-09 sec) per iteration: 0.0574391 sec for 18871485 iterations 0.003 usec (3.02551e-09 sec) per iteration: 0.0570959 sec for 18871485 iterations mean: 0.003046 usec stddev: 0.000026 usec relative stddev: 0.84% RESULT TestTlsVariable: x86-32_nnacl_newlib_static= {0.003046, 0.000026} us Measuring clock cycles: first runs (cycles): 123 28 27 26 26 27 27 26 26 27 ... slowest runs (cycles): ... 33 35 35 35 36 37 37 39 40 123 min: 22 cycles q1: 26 cycles median: 27 cycles q3: 29 cycles max: 123 cycles RESULT TestTlsVariable_CycleCount: x86-32_nnacl_newlib_static= {27, 3} count Apparent clock speed: 8865 MHz TestMmapAnonymous: Measuring real time: 4.074 usec (4.07381e-06 sec) per iteration: 0.000407381 sec for 100 iterations 3.543 usec (3.54299e-06 sec) per iteration: 0.0869698 sec for 24547 iterations 3.541 usec (3.54114e-06 sec) per iteration: 0.0869244 sec for 24547 iterations 3.551 usec (3.55128e-06 sec) per iteration: 0.0871734 sec for 24547 iterations 3.544 usec (3.54375e-06 sec) per iteration: 0.0869885 sec for 24547 iterations 3.554 usec (3.55428e-06 sec) per iteration: 0.087247 sec for 24547 iterations mean: 3.546691 usec stddev: 0.005136 usec relative stddev: 0.14% RESULT TestMmapAnonymous: x86-32_nnacl_newlib_static= {3.546691, 0.005136} us Measuring clock cycles: first runs (cycles): 11681 7786 7623 7582 7537 7611 7664 7625 7523 7647 ... slowest runs (cycles): ... 7733 7761 7776 7781 7786 7788 7818 7893 11681 17009 min: 7488 cycles q1: 7549 cycles median: 7603 cycles q3: 7647 cycles max: 17009 cycles RESULT TestMmapAnonymous_CycleCount: x86-32_nnacl_newlib_static= {7603, 98} count Apparent clock speed: 2144 MHz TestAtomicIncrement: Measuring real time: 0.023 usec (2.345e-08 sec) per iteration: 2.345e-06 sec for 100 iterations 0.010 usec (9.91e-09 sec) per iteration: 9.91e-06 sec for 1000 iterations 0.009 usec (9.3452e-09 sec) per iteration: 9.3452e-05 sec for 10000 iterations 0.009 usec (9.42977e-09 sec) per iteration: 0.100905 sec for 10700680 iterations 0.009 usec (9.43576e-09 sec) per iteration: 0.100969 sec for 10700680 iterations 0.009 usec (9.44138e-09 sec) per iteration: 0.101029 sec for 10700680 iterations 0.009 usec (9.42756e-09 sec) per iteration: 0.100881 sec for 10700680 iterations 0.009 usec (9.44198e-09 sec) per iteration: 0.101036 sec for 10700680 iterations mean: 0.009435 usec stddev: 0.000006 usec relative stddev: 0.06% RESULT TestAtomicIncrement: x86-32_nnacl_newlib_static= {0.009435, 0.000006} us Measuring clock cycles: first runs (cycles): 101 69 49 50 49 50 51 51 48 51 ... slowest runs (cycles): ... 51 51 51 51 51 51 51 51 69 101 min: 48 cycles q1: 49 cycles median: 50 cycles q3: 51 cycles max: 101 cycles RESULT TestAtomicIncrement_CycleCount: x86-32_nnacl_newlib_static= {50, 2} count Apparent clock speed: 5299 MHz TestUncontendedMutexLock: Measuring real time: 0.032 usec (3.15e-08 sec) per iteration: 3.15e-06 sec for 100 iterations 0.023 usec (2.3482e-08 sec) per iteration: 2.3482e-05 sec for 1000 iterations 0.022 usec (2.22215e-08 sec) per iteration: 0.0946321 sec for 4258581 iterations 0.023 usec (2.2877e-08 sec) per iteration: 0.0974236 sec for 4258581 iterations 0.022 usec (2.22718e-08 sec) per iteration: 0.0948464 sec for 4258581 iterations 0.022 usec (2.22937e-08 sec) per iteration: 0.0949397 sec for 4258581 iterations 0.022 usec (2.22129e-08 sec) per iteration: 0.0945954 sec for 4258581 iterations mean: 0.022375 usec stddev: 0.000253 usec relative stddev: 1.13% RESULT TestUncontendedMutexLock: x86-32_nnacl_newlib_static= {0.022375, 0.000253} us Measuring clock cycles: first runs (cycles): 251 118 81 82 82 82 81 81 81 81 ... slowest runs (cycles): ... 82 82 82 82 82 82 82 82 118 251 min: 79 cycles q1: 79 cycles median: 79 cycles q3: 81 cycles max: 251 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-32_nnacl_newlib_static= {79, 2} count Apparent clock speed: 3531 MHz TestCondvarSignalNoOp: Measuring real time: 0.556 usec (5.5635e-07 sec) per iteration: 5.5635e-05 sec for 100 iterations 0.523 usec (5.22642e-07 sec) per iteration: 0.0939407 sec for 179742 iterations 0.524 usec (5.23524e-07 sec) per iteration: 0.0940992 sec for 179742 iterations 0.524 usec (5.23576e-07 sec) per iteration: 0.0941086 sec for 179742 iterations 0.523 usec (5.23026e-07 sec) per iteration: 0.0940098 sec for 179742 iterations 0.525 usec (5.25169e-07 sec) per iteration: 0.094395 sec for 179742 iterations mean: 0.523587 usec stddev: 0.000862 usec relative stddev: 0.16% RESULT TestCondvarSignalNoOp: x86-32_nnacl_newlib_static= {0.523587, 0.000862} us Measuring clock cycles: first runs (cycles): 2217 1169 1207 1155 1148 1142 1231 1175 1142 1131 ... slowest runs (cycles): ... 1173 1173 1173 1175 1175 1179 1188 1207 1231 2217 min: 1130 cycles q1: 1138 cycles median: 1142 cycles q3: 1146 cycles max: 2217 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-32_nnacl_newlib_static= {1142, 8} count Apparent clock speed: 2181 MHz TestThreadCreateAndJoin: Measuring real time: 123.259 usec (0.000123259 sec) per iteration: 0.0123259 sec for 100 iterations 103.375 usec (0.000103375 sec) per iteration: 0.0838374 sec for 811 iterations 96.465 usec (9.64652e-05 sec) per iteration: 0.0782333 sec for 811 iterations 95.216 usec (9.52156e-05 sec) per iteration: 0.0772199 sec for 811 iterations 88.297 usec (8.8297e-05 sec) per iteration: 0.0716088 sec for 811 iterations 105.891 usec (0.000105891 sec) per iteration: 0.0858776 sec for 811 iterations mean: 97.848839 usec stddev: 6.249892 usec relative stddev: 6.39% RESULT TestThreadCreateAndJoin: x86-32_nnacl_newlib_static= {97.848839, 6.249892} us Measuring clock cycles: first runs (cycles): 133183 218449 190400 248716 218491 225108 206867 225712 192220 220668 ... slowest runs (cycles): ... 240917 242407 248113 248716 293633 296313 301584 377906 397630 486333 min: 126320 cycles q1: 184613 cycles median: 199743 cycles q3: 220668 cycles max: 486333 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-32_nnacl_newlib_static= {199743, 36055} count Apparent clock speed: 2041 MHz TestThreadWakeup: Measuring real time: 15.367 usec (1.53669e-05 sec) per iteration: 0.00153669 sec for 100 iterations 14.702 usec (1.4702e-05 sec) per iteration: 0.0956656 sec for 6507 iterations 15.156 usec (1.51563e-05 sec) per iteration: 0.0986221 sec for 6507 iterations 15.808 usec (1.58077e-05 sec) per iteration: 0.10286 sec for 6507 iterations 15.048 usec (1.50479e-05 sec) per iteration: 0.0979165 sec for 6507 iterations 15.234 usec (1.5234e-05 sec) per iteration: 0.0991273 sec for 6507 iterations mean: 15.189550 usec stddev: 0.358596 usec relative stddev: 2.36% RESULT TestThreadWakeup: x86-32_nnacl_newlib_static= {15.189550, 0.358596} us Measuring clock cycles: first runs (cycles): 52875 32573 32835 32872 33134 32267 36902 33237 32819 32502 ... slowest runs (cycles): ... 34143 34779 34904 36149 36902 38257 47665 49054 49594 52875 min: 25293 cycles q1: 28924 cycles median: 30564 cycles q3: 33160 cycles max: 52875 cycles RESULT TestThreadWakeup_CycleCount: x86-32_nnacl_newlib_static= {30564, 4236} count Apparent clock speed: 2012 MHz TestCatchingFault: Measuring real time: 4.461 usec (4.46082e-06 sec) per iteration: 0.000446082 sec for 100 iterations 2.508 usec (2.50831e-06 sec) per iteration: 0.0562289 sec for 22417 iterations 2.520 usec (2.52038e-06 sec) per iteration: 0.0564994 sec for 22417 iterations 2.485 usec (2.4853e-06 sec) per iteration: 0.0557129 sec for 22417 iterations 2.505 usec (2.50452e-06 sec) per iteration: 0.0561439 sec for 22417 iterations 2.511 usec (2.51123e-06 sec) per iteration: 0.0562942 sec for 22417 iterations mean: 2.505949 usec stddev: 0.011579 usec relative stddev: 0.46% RESULT TestCatchingFault: x86-32_nnacl_newlib_static= {2.505949, 0.011579} us Measuring clock cycles: first runs (cycles): 16718 5773 5458 5350 5425 5346 5398 5335 5398 5339 ... slowest runs (cycles): ... 6828 6837 6890 6953 6955 7040 7050 7075 12160 16718 min: 5308 cycles q1: 5349 cycles median: 5396 cycles q3: 5488 cycles max: 16718 cycles RESULT TestCatchingFault_CycleCount: x86-32_nnacl_newlib_static= {5396, 139} count Apparent clock speed: 2153 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 92.861 usec (9.28613e-05 sec) per iteration: 0.00928613 sec for 100 iterations 105.065 usec (0.000105065 sec) per iteration: 0.11305 sec for 1076 iterations 94.172 usec (9.41724e-05 sec) per iteration: 0.10133 sec for 1076 iterations 96.803 usec (9.68028e-05 sec) per iteration: 0.10416 sec for 1076 iterations 91.117 usec (9.11173e-05 sec) per iteration: 0.0980422 sec for 1076 iterations 101.312 usec (0.000101312 sec) per iteration: 0.109011 sec for 1076 iterations mean: 97.693778 usec stddev: 4.977231 usec relative stddev: 5.09% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-32_nnacl_newlib_static= {97.693778, 4.977231} us Measuring clock cycles: first runs (cycles): 140746 239950 193168 191705 352395 194795 171901 216778 296643 156247 ... slowest runs (cycles): ... 226773 239950 244804 245156 258837 276400 296643 297901 352395 460543 min: 140746 cycles q1: 154733 cycles median: 165760 cycles q3: 184756 cycles max: 460543 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-32_nnacl_newlib_static= {165760, 30023} count Apparent clock speed: 1697 MHz [ OK ] nacl_newlib.run_performance_test (5330 ms) /b/s/w/ir/cache/vpython/b574a6/bin/python /b/s/w/ir/k/native_client/tools/command_tester.py --name nacl_newlib.run_nacl_clock_cputime_test --time_warning 60 --time_error 600 --perf_env_description trusted_linux_x86-32 --subarch 32 --arch x86 --output_stamp /b/s/w/ir/k/native_client/scons-out/dbg-linux-x86-32/test_results/nacl_clock_cputime_test.out /b/s/w/ir/k/native_client/scons-out/dbg-linux-x86-32/obj/src/shared/platform/nacl_clock_test -c [ RUN ] nacl_newlib.run_nacl_clock_cputime_test /b/s/w/ir/k/native_client/scons-out/dbg-linux-x86-32/obj/src/shared/platform/nacl_clock_test -c [ OK ] nacl_newlib.run_nacl_clock_cputime_test (10620 ms) /b/s/w/ir/cache/vpython/b574a6/bin/python /b/s/w/ir/k/native_client/tools/command_tester.py --name nacl_newlib.run_nacl_interval_multi_reg_test --time_warning 60 --time_error 600 --perf_env_description trusted_linux_x86-32 --subarch 32 --arch x86 --output_stamp /b/s/w/ir/k/native_client/scons-out/dbg-linux-x86-32/test_results/nacl_interval_multi_reg_test.out /b/s/w/ir/k/native_client/scons-out/dbg-linux-x86-32/obj/src/trusted/interval_multiset/nacl_interval_test -k NaClIntervalListMultiset -k NaClIntervalRangeTree -c 1000000 [ RUN ] nacl_newlib.run_nacl_interval_multi_reg_test /b/s/w/ir/k/native_client/scons-out/dbg-linux-x86-32/obj/src/trusted/interval_multiset/nacl_interval_test -k NaClIntervalListMultiset -k NaClIntervalRangeTree -c 1000000 [ OK ] nacl_newlib.run_nacl_interval_multi_reg_test (1580 ms) /b/s/w/ir/cache/vpython/b574a6/bin/python /b/s/w/ir/k/native_client/tools/command_tester.py --name nacl_newlib.run_trusted_performance_test --time_warning 2 --time_error 20 --perf_env_description trusted_linux_x86-32 --subarch 32 --arch x86 --capture_output 0 --output_stamp /b/s/w/ir/k/native_client/scons-out/dbg-linux-x86-32/test_results/performance_test.out /b/s/w/ir/k/native_client/scons-out/dbg-linux-x86-32/obj/tests/performance/performance_test trusted_linux_x86-32 [ RUN ] nacl_newlib.run_trusted_performance_test /b/s/w/ir/k/native_client/scons-out/dbg-linux-x86-32/obj/tests/performance/performance_test trusted_linux_x86-32 TestNull: Measuring real time: 0.008 usec (8.07e-09 sec) per iteration: 8.07e-07 sec for 100 iterations 0.003 usec (3.148e-09 sec) per iteration: 3.148e-06 sec for 1000 iterations 0.003 usec (2.9072e-09 sec) per iteration: 2.9072e-05 sec for 10000 iterations 0.003 usec (2.94679e-09 sec) per iteration: 0.101362 sec for 34397358 iterations 0.003 usec (2.94576e-09 sec) per iteration: 0.101326 sec for 34397358 iterations 0.003 usec (2.93537e-09 sec) per iteration: 0.100969 sec for 34397358 iterations 0.003 usec (2.9343e-09 sec) per iteration: 0.100932 sec for 34397358 iterations 0.003 usec (2.9442e-09 sec) per iteration: 0.101273 sec for 34397358 iterations mean: 0.002941 usec stddev: 0.000005 usec relative stddev: 0.18% RESULT TestNull: trusted_linux_x86-32= {0.002941, 0.000005} us Measuring clock cycles: first runs (cycles): 97 26 29 29 27 29 29 27 27 29 ... slowest runs (cycles): ... 29 29 29 29 30 31 31 31 31 97 min: 20 cycles q1: 27 cycles median: 28 cycles q3: 29 cycles max: 97 cycles RESULT TestNull_CycleCount: trusted_linux_x86-32= {28, 2} count Apparent clock speed: 9520 MHz TestHostSyscall: Measuring real time: 0.123 usec (1.2263e-07 sec) per iteration: 1.2263e-05 sec for 100 iterations 0.106 usec (1.0561e-07 sec) per iteration: 0.0861207 sec for 815461 iterations 0.105 usec (1.05469e-07 sec) per iteration: 0.0860055 sec for 815461 iterations 0.106 usec (1.0553e-07 sec) per iteration: 0.0860556 sec for 815461 iterations 0.106 usec (1.05522e-07 sec) per iteration: 0.0860488 sec for 815461 iterations 0.106 usec (1.05578e-07 sec) per iteration: 0.0860946 sec for 815461 iterations mean: 0.105542 usec stddev: 0.000049 usec relative stddev: 0.05% RESULT TestHostSyscall: trusted_linux_x86-32= {0.105542, 0.000049} us Measuring clock cycles: first runs (cycles): 583 253 255 253 251 251 255 253 253 391 ... slowest runs (cycles): ... 256 256 257 257 258 258 258 391 583 999 min: 250 cycles q1: 251 cycles median: 253 cycles q3: 255 cycles max: 999 cycles RESULT TestHostSyscall_CycleCount: trusted_linux_x86-32= {253, 4} count Apparent clock speed: 2397 MHz TestSetjmpLongjmp: Measuring real time: 0.124 usec (1.237e-07 sec) per iteration: 1.237e-05 sec for 100 iterations 0.035 usec (3.52426e-08 sec) per iteration: 0.0284903 sec for 808407 iterations 0.035 usec (3.51602e-08 sec) per iteration: 0.0284238 sec for 808407 iterations 0.035 usec (3.49398e-08 sec) per iteration: 0.0282456 sec for 808407 iterations 0.035 usec (3.51182e-08 sec) per iteration: 0.0283898 sec for 808407 iterations 0.035 usec (3.49478e-08 sec) per iteration: 0.0282521 sec for 808407 iterations mean: 0.035082 usec stddev: 0.000120 usec relative stddev: 0.34% RESULT TestSetjmpLongjmp: trusted_linux_x86-32= {0.035082, 0.000120} us Measuring clock cycles: first runs (cycles): 214 886 102 108 99 101 101 102 145 101 ... slowest runs (cycles): ... 108 108 108 110 110 110 141 145 214 886 min: 99 cycles q1: 101 cycles median: 102 cycles q3: 105 cycles max: 886 cycles RESULT TestSetjmpLongjmp_CycleCount: trusted_linux_x86-32= {102, 4} count Apparent clock speed: 2907 MHz TestClockGetTime: Measuring real time: 0.198 usec (1.982e-07 sec) per iteration: 1.982e-05 sec for 100 iterations 0.198 usec (1.98353e-07 sec) per iteration: 0.100077 sec for 504540 iterations 0.198 usec (1.98237e-07 sec) per iteration: 0.100019 sec for 504540 iterations 0.198 usec (1.98088e-07 sec) per iteration: 0.0999432 sec for 504540 iterations 0.198 usec (1.98335e-07 sec) per iteration: 0.100068 sec for 504540 iterations 0.203 usec (2.03275e-07 sec) per iteration: 0.10256 sec for 504540 iterations mean: 0.199258 usec stddev: 0.002011 usec relative stddev: 1.01% RESULT TestClockGetTime: trusted_linux_x86-32= {0.199258, 0.002011} us Measuring clock cycles: first runs (cycles): 1399 467 462 456 456 467 456 464 457 587 ... slowest runs (cycles): ... 464 466 466 466 467 467 489 561 587 1399 min: 453 cycles q1: 456 cycles median: 462 cycles q3: 464 cycles max: 1399 cycles RESULT TestClockGetTime_CycleCount: trusted_linux_x86-32= {462, 8} count Apparent clock speed: 2319 MHz TestTlsVariable: Measuring real time: 0.019 usec (1.912e-08 sec) per iteration: 1.912e-06 sec for 100 iterations 0.019 usec (1.9272e-08 sec) per iteration: 1.9272e-05 sec for 1000 iterations 0.005 usec (4.50839e-09 sec) per iteration: 0.0233935 sec for 5188875 iterations 0.004 usec (4.38309e-09 sec) per iteration: 0.0227433 sec for 5188875 iterations 0.004 usec (4.38252e-09 sec) per iteration: 0.0227403 sec for 5188875 iterations 0.004 usec (4.40343e-09 sec) per iteration: 0.0228489 sec for 5188875 iterations 0.004 usec (4.38452e-09 sec) per iteration: 0.0227507 sec for 5188875 iterations mean: 0.004412 usec stddev: 0.000049 usec relative stddev: 1.10% RESULT TestTlsVariable: trusted_linux_x86-32= {0.004412, 0.000049} us Measuring clock cycles: first runs (cycles): 93 33 33 33 33 31 33 33 30 33 ... slowest runs (cycles): ... 33 33 33 33 33 33 35 93 154 158 min: 28 cycles q1: 30 cycles median: 31 cycles q3: 31 cycles max: 158 cycles RESULT TestTlsVariable_CycleCount: trusted_linux_x86-32= {31, 1} count Apparent clock speed: 7026 MHz TestMmapAnonymous: Measuring real time: 1.010 usec (1.0097e-06 sec) per iteration: 0.00010097 sec for 100 iterations 0.800 usec (8.00488e-07 sec) per iteration: 0.0792796 sec for 99039 iterations 0.800 usec (8.00028e-07 sec) per iteration: 0.0792339 sec for 99039 iterations 0.799 usec (7.99367e-07 sec) per iteration: 0.0791685 sec for 99039 iterations 0.799 usec (7.99246e-07 sec) per iteration: 0.0791565 sec for 99039 iterations 0.799 usec (7.98949e-07 sec) per iteration: 0.0791272 sec for 99039 iterations mean: 0.799616 usec stddev: 0.000561 usec relative stddev: 0.07% RESULT TestMmapAnonymous: trusted_linux_x86-32= {0.799616, 0.000561} us Measuring clock cycles: first runs (cycles): 2752 1791 1767 1773 1764 1751 1752 1747 1753 1911 ... slowest runs (cycles): ... 1791 1791 1800 1801 1828 1911 1923 2752 9452 16303 min: 1727 cycles q1: 1738 cycles median: 1747 cycles q3: 1756 cycles max: 16303 cycles RESULT TestMmapAnonymous_CycleCount: trusted_linux_x86-32= {1747, 18} count Apparent clock speed: 2185 MHz TestAtomicIncrement: Measuring real time: 0.013 usec (1.289e-08 sec) per iteration: 1.289e-06 sec for 100 iterations 0.009 usec (9.483e-09 sec) per iteration: 9.483e-06 sec for 1000 iterations 0.009 usec (9.2882e-09 sec) per iteration: 9.2882e-05 sec for 10000 iterations 0.009 usec (9.43859e-09 sec) per iteration: 0.101619 sec for 10766348 iterations 0.009 usec (9.42219e-09 sec) per iteration: 0.101443 sec for 10766348 iterations 0.009 usec (9.43903e-09 sec) per iteration: 0.101624 sec for 10766348 iterations 0.009 usec (9.44294e-09 sec) per iteration: 0.101666 sec for 10766348 iterations 0.009 usec (9.42239e-09 sec) per iteration: 0.101445 sec for 10766348 iterations mean: 0.009433 usec stddev: 0.000009 usec relative stddev: 0.09% RESULT TestAtomicIncrement: trusted_linux_x86-32= {0.009433, 0.000009} us Measuring clock cycles: first runs (cycles): 114 59 59 55 62 61 57 60 55 283 ... slowest runs (cycles): ... 64 64 64 64 66 71 114 277 283 293 min: 54 cycles q1: 57 cycles median: 59 cycles q3: 62 cycles max: 293 cycles RESULT TestAtomicIncrement_CycleCount: trusted_linux_x86-32= {59, 5} count Apparent clock speed: 6255 MHz TestUncontendedMutexLock: Measuring real time: 0.050 usec (4.966e-08 sec) per iteration: 4.966e-06 sec for 100 iterations 0.029 usec (2.9167e-08 sec) per iteration: 2.9167e-05 sec for 1000 iterations 0.030 usec (2.96772e-08 sec) per iteration: 0.101749 sec for 3428532 iterations 0.030 usec (2.96179e-08 sec) per iteration: 0.101546 sec for 3428532 iterations 0.030 usec (2.96304e-08 sec) per iteration: 0.101589 sec for 3428532 iterations 0.030 usec (2.96479e-08 sec) per iteration: 0.101649 sec for 3428532 iterations 0.030 usec (2.96424e-08 sec) per iteration: 0.10163 sec for 3428532 iterations mean: 0.029643 usec stddev: 0.000020 usec relative stddev: 0.07% RESULT TestUncontendedMutexLock: trusted_linux_x86-32= {0.029643, 0.000020} us Measuring clock cycles: first runs (cycles): 376 99 104 97 101 101 103 101 102 338 ... slowest runs (cycles): ... 106 106 107 108 108 108 108 323 338 376 min: 97 cycles q1: 103 cycles median: 105 cycles q3: 106 cycles max: 376 cycles RESULT TestUncontendedMutexLock_CycleCount: trusted_linux_x86-32= {105, 3} count Apparent clock speed: 3542 MHz TestCondvarSignalNoOp: Measuring real time: 0.039 usec (3.933e-08 sec) per iteration: 3.933e-06 sec for 100 iterations 0.024 usec (2.3548e-08 sec) per iteration: 2.3548e-05 sec for 1000 iterations 0.024 usec (2.37116e-08 sec) per iteration: 0.100695 sec for 4246645 iterations 0.024 usec (2.37188e-08 sec) per iteration: 0.100725 sec for 4246645 iterations 0.024 usec (2.3706e-08 sec) per iteration: 0.100671 sec for 4246645 iterations 0.024 usec (2.43715e-08 sec) per iteration: 0.103497 sec for 4246645 iterations 0.024 usec (2.37419e-08 sec) per iteration: 0.100823 sec for 4246645 iterations mean: 0.023850 usec stddev: 0.000261 usec relative stddev: 1.09% RESULT TestCondvarSignalNoOp: trusted_linux_x86-32= {0.023850, 0.000261} us Measuring clock cycles: first runs (cycles): 341 85 95 86 83 86 86 86 86 315 ... slowest runs (cycles): ... 88 88 88 90 90 91 95 315 341 1019 min: 83 cycles q1: 86 cycles median: 88 cycles q3: 88 cycles max: 1019 cycles RESULT TestCondvarSignalNoOp_CycleCount: trusted_linux_x86-32= {88, 2} count Apparent clock speed: 3690 MHz TestThreadCreateAndJoin: Measuring real time: 54.759 usec (5.47592e-05 sec) per iteration: 0.00547592 sec for 100 iterations 22.956 usec (2.29557e-05 sec) per iteration: 0.0419171 sec for 1826 iterations 22.850 usec (2.28497e-05 sec) per iteration: 0.0417236 sec for 1826 iterations 22.530 usec (2.25304e-05 sec) per iteration: 0.0411404 sec for 1826 iterations 23.377 usec (2.33772e-05 sec) per iteration: 0.0426868 sec for 1826 iterations 22.214 usec (2.22137e-05 sec) per iteration: 0.0405622 sec for 1826 iterations mean: 22.785343 usec stddev: 0.393776 usec relative stddev: 1.73% RESULT TestThreadCreateAndJoin: trusted_linux_x86-32= {22.785343, 0.393776} us Measuring clock cycles: first runs (cycles): 107516 75480 62913 64897 91422 180488 111102 385322 73465 42562 ... slowest runs (cycles): ... 73465 75480 91422 107516 111102 122594 172562 180488 187589 385322 min: 38950 cycles q1: 39975 cycles median: 40688 cycles q3: 42472 cycles max: 385322 cycles RESULT TestThreadCreateAndJoin_CycleCount: trusted_linux_x86-32= {40688, 2497} count Apparent clock speed: 1786 MHz TestThreadWakeup: Measuring real time: 11.300 usec (1.13002e-05 sec) per iteration: 0.00113002 sec for 100 iterations 14.537 usec (1.45366e-05 sec) per iteration: 0.128634 sec for 8849 iterations 15.465 usec (1.54651e-05 sec) per iteration: 0.136851 sec for 8849 iterations 16.030 usec (1.60297e-05 sec) per iteration: 0.141846 sec for 8849 iterations 15.429 usec (1.54286e-05 sec) per iteration: 0.136527 sec for 8849 iterations 18.303 usec (1.8303e-05 sec) per iteration: 0.161963 sec for 8849 iterations mean: 15.952566 usec stddev: 1.268671 usec relative stddev: 7.95% RESULT TestThreadWakeup: trusted_linux_x86-32= {15.952566, 1.268671} us Measuring clock cycles: first runs (cycles): 36830 35338 33230 32775 33288 33312 45716 34423 33288 34891 ... slowest runs (cycles): ... 38173 38255 38381 39098 39883 45716 50196 81421 89644 121428 min: 27707 cycles q1: 32751 cycles median: 33230 cycles q3: 34610 cycles max: 121428 cycles RESULT TestThreadWakeup_CycleCount: trusted_linux_x86-32= {33230, 1859} count Apparent clock speed: 2083 MHz WARNING: should have taken less than 2.000000 secs [ OK ] nacl_newlib.run_trusted_performance_test (4699 ms) scons: done building targets. ********************************************************************** COMMAND EXECUTION REPORT ********************************************************************** 7 command_tester.py 1 doSizeCheck ********************************************************************** ENVIRONMENT USAGE REPORT ********************************************************************** 3 dbg-linux-x86-32 5 nacl-x86-32 Command return code: 0