Back to build
Raw log | Switch to full mode | Login

Running command: /b/s/w/ir/cache/vpython/b574a6/bin/python scons.py --verbose -k -j1 --mode=nacl,dbg-host,nacl_irt_test platform=x86-64 --clang breakpad_tools_dir=breakpad-out large_tests_irt scons: Reading SConscript files ... ====================================================================== SCONS ARGS:['scons.py', '--verbose', '-k', '-j1', '--mode=nacl,dbg-host,nacl_irt_test', 'platform=x86-64', '--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-b-dqtb 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: 21875344 kB MemAvailable: 29484224 kB ====================================================================== LOAD: ====================================================================== 1.44 1.81 1.07 1/436 496 ====================================================================== UPTIME: ====================================================================== 601.73 3563.84 ====================================================================== 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-64 OBJ_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-64/obj BUILD_TYPE_DESCRIPTION: NaCl module build BUILD_OS: BUILD_ARCHITECTURE: x86 BUILD_SUBARCH: 64 TARGET_OS: TARGET_ARCHITECTURE: x86 TARGET_SUBARCH: 64 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 -m64 -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=64 -I/b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl-x86-64/include -I/b/s/w/ir/kitchen-workdir -c -o UNKNOWN COMPILER NACL_BUILD_FAMILY: TRUSTED BUILD_TYPE: dbg-linux TARGET_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/dbg-linux-x86-64 OBJ_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/dbg-linux-x86-64/obj BUILD_TYPE_DESCRIPTION: Linux dbg build BUILD_OS: BUILD_ARCHITECTURE: x86 BUILD_SUBARCH: 64 TARGET_OS: TARGET_ARCHITECTURE: x86 TARGET_SUBARCH: 64 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 -m64 -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/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-64 OBJ_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt_test-x86-64/obj BUILD_TYPE_DESCRIPTION: NaCl tests build with IRT BUILD_OS: BUILD_ARCHITECTURE: x86 BUILD_SUBARCH: 64 TARGET_OS: TARGET_ARCHITECTURE: x86 TARGET_SUBARCH: 64 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 -m64 -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=64 -Iscons-out/nacl-x86-64/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-64 OBJ_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt-x86-64/obj BUILD_TYPE_DESCRIPTION: NaCl IRT build BUILD_OS: BUILD_ARCHITECTURE: x86 BUILD_SUBARCH: 64 TARGET_OS: TARGET_ARCHITECTURE: x86 TARGET_SUBARCH: 64 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 -m64 -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=64 -I/b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt-x86-64/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_vla_test *** BROKEN run_nacl_sync_cond_test SKIPPING test text_overlaps_rodata SKIPPING test text_overlaps_data *** 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 *** BROKEN run_vla_test_irt There are 24 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-64_with_irt_nnacl_newlib_static --using_nacl_signal_handler True --subarch 64 --arch x86 --capture_output 0 --output_stamp /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt_test-x86-64/test_results/benchmark_test.out scons-out/dbg-linux-x86-64/staging/nacl_helper_bootstrap scons-out/dbg-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -B scons-out/nacl_irt-x86-64/staging/irt_core.nexe -- scons-out/nacl_irt_test-x86-64/obj/tests/benchmark/benchmark_test.nexe x86-64_with_irt_nnacl_newlib_static [ RUN ] nacl_newlib.run_benchmark_test_irt scons-out/dbg-linux-x86-64/staging/nacl_helper_bootstrap scons-out/dbg-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -B scons-out/nacl_irt-x86-64/staging/irt_core.nexe -- scons-out/nacl_irt_test-x86-64/obj/tests/benchmark/benchmark_test.nexe x86-64_with_irt_nnacl_newlib_static [504,692246400:13:50:01.758678] Native Client module will be loaded at base address 0x0000067a00000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_with_irt_nnacl_newlib_static= {0.107430, 0.001820} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (1690 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-64_with_irt_nnacl_newlib_static --using_nacl_signal_handler True --subarch 64 --arch x86 --output_stamp /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt_test-x86-64/test_results/clock_cputime_test.out scons-out/dbg-linux-x86-64/staging/nacl_helper_bootstrap scons-out/dbg-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -B scons-out/nacl_irt-x86-64/staging/irt_core.nexe -- scons-out/nacl_irt_test-x86-64/obj/tests/clock/clock_test.nexe -c [ RUN ] nacl_newlib.run_clock_cputime_test_irt scons-out/dbg-linux-x86-64/staging/nacl_helper_bootstrap scons-out/dbg-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -B scons-out/nacl_irt-x86-64/staging/irt_core.nexe -- scons-out/nacl_irt_test-x86-64/obj/tests/clock/clock_test.nexe -c WARNING: should have taken less than 60.000000 secs [ OK ] nacl_newlib.run_clock_cputime_test_irt (75120 ms) doSizeCheck(["scons-out/nacl_irt_test-x86-64/obj/tests/hello_world/hello_world_size.out"], ["scons-out/nacl_irt_test-x86-64/obj/tests/hello_world/hello_world.nexe"]) RESULT hello_world_size.out: x86-64_with_irt_nnacl_newlib_static= 527.182 KB RESULT hello_world_size.out: ZIPPED_x86-64_with_irt_nnacl_newlib_static= 186.968 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-64_with_irt_nnacl_newlib_static --using_nacl_signal_handler True --subarch 64 --arch x86 --capture_output 0 --output_stamp /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt_test-x86-64/test_results/performance_test.out scons-out/dbg-linux-x86-64/staging/nacl_helper_bootstrap scons-out/dbg-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -e -B scons-out/nacl_irt-x86-64/staging/irt_core.nexe -- scons-out/nacl_irt_test-x86-64/obj/tests/performance/performance_test.nexe x86-64_with_irt_nnacl_newlib_static [ RUN ] nacl_newlib.run_performance_test_irt scons-out/dbg-linux-x86-64/staging/nacl_helper_bootstrap scons-out/dbg-linux-x86-64/staging/sel_ldr --r_debug=0xXXXXXXXXXXXXXXXX --reserved_at_zero=0xXXXXXXXXXXXXXXXX -e -B scons-out/nacl_irt-x86-64/staging/irt_core.nexe -- scons-out/nacl_irt_test-x86-64/obj/tests/performance/performance_test.nexe x86-64_with_irt_nnacl_newlib_static [538,787175296:13:50:17.495805] Native Client module will be loaded at base address 0x00007fda00000000 TestNull: Measuring real time: 0.008 usec (7.64e-09 sec) per iteration: 7.64e-07 sec for 100 iterations 0.002 usec (2.228e-09 sec) per iteration: 2.228e-06 sec for 1000 iterations 0.002 usec (1.8903e-09 sec) per iteration: 1.8903e-05 sec for 10000 iterations 0.002 usec (1.90523e-09 sec) per iteration: 0.10079 sec for 52901655 iterations 0.002 usec (1.89374e-09 sec) per iteration: 0.100182 sec for 52901655 iterations 0.002 usec (1.88575e-09 sec) per iteration: 0.0997591 sec for 52901655 iterations 0.002 usec (1.89078e-09 sec) per iteration: 0.100025 sec for 52901655 iterations 0.002 usec (1.88924e-09 sec) per iteration: 0.099944 sec for 52901655 iterations mean: 0.001893 usec stddev: 0.000007 usec relative stddev: 0.35% RESULT TestNull: x86-64_with_irt_nnacl_newlib_static= {0.001893, 0.000007} us Measuring clock cycles: first runs (cycles): 99 24 24 24 26 24 24 22 27 24 ... slowest runs (cycles): ... 26 26 26 26 26 27 27 27 27 99 min: 20 cycles q1: 24 cycles median: 24 cycles q3: 25 cycles max: 99 cycles RESULT TestNull_CycleCount: x86-64_with_irt_nnacl_newlib_static= {24, 1} count Apparent clock speed: 12679 MHz TestNaClSyscall: Measuring real time: 0.228 usec (2.2799e-07 sec) per iteration: 2.2799e-05 sec for 100 iterations 0.210 usec (2.09858e-07 sec) per iteration: 0.092047 sec for 438615 iterations 0.209 usec (2.08819e-07 sec) per iteration: 0.091591 sec for 438615 iterations 0.211 usec (2.10985e-07 sec) per iteration: 0.0925412 sec for 438615 iterations 0.212 usec (2.11612e-07 sec) per iteration: 0.0928161 sec for 438615 iterations 0.212 usec (2.12346e-07 sec) per iteration: 0.0931383 sec for 438615 iterations mean: 0.210724 usec stddev: 0.001254 usec relative stddev: 0.60% RESULT TestNaClSyscall: x86-64_with_irt_nnacl_newlib_static= {0.210724, 0.001254} us Measuring clock cycles: first runs (cycles): 510 425 415 365 347 350 350 352 350 350 ... slowest runs (cycles): ... 374 379 379 380 383 388 391 415 425 510 min: 343 cycles q1: 347 cycles median: 350 cycles q3: 354 cycles max: 510 cycles RESULT TestNaClSyscall_CycleCount: x86-64_with_irt_nnacl_newlib_static= {350, 7} count Apparent clock speed: 1661 MHz TestSetjmpLongjmp: Measuring real time: 0.018 usec (1.774e-08 sec) per iteration: 1.774e-06 sec for 100 iterations 0.013 usec (1.2907e-08 sec) per iteration: 1.2907e-05 sec for 1000 iterations 0.013 usec (1.29759e-08 sec) per iteration: 0.100534 sec for 7747733 iterations 0.013 usec (1.32816e-08 sec) per iteration: 0.102902 sec for 7747733 iterations 0.013 usec (1.2942e-08 sec) per iteration: 0.100272 sec for 7747733 iterations 0.013 usec (1.29171e-08 sec) per iteration: 0.100078 sec for 7747733 iterations 0.013 usec (1.2855e-08 sec) per iteration: 0.0995974 sec for 7747733 iterations mean: 0.012994 usec stddev: 0.000149 usec relative stddev: 1.15% RESULT TestSetjmpLongjmp: x86-64_with_irt_nnacl_newlib_static= {0.012994, 0.000149} us Measuring clock cycles: first runs (cycles): 147 57 42 36 31 31 31 26 27 26 ... slowest runs (cycles): ... 31 31 31 31 31 31 36 42 57 147 min: 24 cycles q1: 26 cycles median: 28 cycles q3: 29 cycles max: 147 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {28, 3} count Apparent clock speed: 2155 MHz TestClockGetTime: Measuring real time: 0.281 usec (2.8051e-07 sec) per iteration: 2.8051e-05 sec for 100 iterations 0.280 usec (2.80313e-07 sec) per iteration: 0.0999297 sec for 356493 iterations 0.281 usec (2.80957e-07 sec) per iteration: 0.100159 sec for 356493 iterations 0.280 usec (2.80231e-07 sec) per iteration: 0.0999002 sec for 356493 iterations 0.280 usec (2.79769e-07 sec) per iteration: 0.0997358 sec for 356493 iterations 0.290 usec (2.90269e-07 sec) per iteration: 0.103479 sec for 356493 iterations mean: 0.282308 usec stddev: 0.003999 usec relative stddev: 1.42% RESULT TestClockGetTime: x86-64_with_irt_nnacl_newlib_static= {0.282308, 0.003999} us Measuring clock cycles: first runs (cycles): 1236 665 623 623 721 645 620 618 618 622 ... slowest runs (cycles): ... 656 663 665 675 691 695 700 702 721 1236 min: 605 cycles q1: 612 cycles median: 618 cycles q3: 625 cycles max: 1236 cycles RESULT TestClockGetTime_CycleCount: x86-64_with_irt_nnacl_newlib_static= {618, 13} count Apparent clock speed: 2189 MHz TestTlsVariable: Measuring real time: 0.010 usec (1.046e-08 sec) per iteration: 1.046e-06 sec for 100 iterations 0.006 usec (5.562e-09 sec) per iteration: 5.562e-06 sec for 1000 iterations 0.005 usec (5.2262e-09 sec) per iteration: 5.2262e-05 sec for 10000 iterations 0.005 usec (5.30583e-09 sec) per iteration: 0.101524 sec for 19134361 iterations 0.005 usec (5.30068e-09 sec) per iteration: 0.101425 sec for 19134361 iterations 0.005 usec (5.30153e-09 sec) per iteration: 0.101441 sec for 19134361 iterations 0.005 usec (5.31022e-09 sec) per iteration: 0.101608 sec for 19134361 iterations 0.005 usec (5.30118e-09 sec) per iteration: 0.101435 sec for 19134361 iterations mean: 0.005304 usec stddev: 0.000004 usec relative stddev: 0.07% RESULT TestTlsVariable: x86-64_with_irt_nnacl_newlib_static= {0.005304, 0.000004} us Measuring clock cycles: first runs (cycles): 141 75 61 35 31 33 30 28 26 31 ... slowest runs (cycles): ... 33 33 33 33 33 33 35 61 75 141 min: 26 cycles q1: 28 cycles median: 29 cycles q3: 31 cycles max: 141 cycles RESULT TestTlsVariable_CycleCount: x86-64_with_irt_nnacl_newlib_static= {29, 3} count Apparent clock speed: 5468 MHz TestMmapAnonymous: Measuring real time: 2.901 usec (2.90081e-06 sec) per iteration: 0.000290081 sec for 100 iterations 2.690 usec (2.68974e-06 sec) per iteration: 0.0927234 sec for 34473 iterations 2.682 usec (2.68151e-06 sec) per iteration: 0.0924398 sec for 34473 iterations 2.683 usec (2.68318e-06 sec) per iteration: 0.0924974 sec for 34473 iterations 2.679 usec (2.67883e-06 sec) per iteration: 0.0923474 sec for 34473 iterations 2.678 usec (2.67798e-06 sec) per iteration: 0.0923181 sec for 34473 iterations mean: 2.682250 usec stddev: 0.004181 usec relative stddev: 0.16% RESULT TestMmapAnonymous: x86-64_with_irt_nnacl_newlib_static= {2.682250, 0.004181} us Measuring clock cycles: first runs (cycles): 8199 5955 5842 5863 5759 5744 5779 5762 5675 5695 ... slowest runs (cycles): ... 8586 8603 8615 8669 8779 8799 8925 9017 9039 9107 min: 5631 cycles q1: 5746 cycles median: 5860 cycles q3: 7763 cycles max: 9107 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_with_irt_nnacl_newlib_static= {5860, 2017} count Apparent clock speed: 2185 MHz TestAtomicIncrement: Measuring real time: 0.020 usec (1.978e-08 sec) per iteration: 1.978e-06 sec for 100 iterations 0.011 usec (1.1406e-08 sec) per iteration: 1.1406e-05 sec for 1000 iterations 0.009 usec (9.47168e-09 sec) per iteration: 0.0830412 sec for 8767315 iterations 0.009 usec (9.43023e-09 sec) per iteration: 0.0826778 sec for 8767315 iterations 0.010 usec (9.78588e-09 sec) per iteration: 0.0857959 sec for 8767315 iterations 0.009 usec (9.44967e-09 sec) per iteration: 0.0828483 sec for 8767315 iterations 0.009 usec (9.42659e-09 sec) per iteration: 0.0826459 sec for 8767315 iterations mean: 0.009513 usec stddev: 0.000137 usec relative stddev: 1.45% RESULT TestAtomicIncrement: x86-64_with_irt_nnacl_newlib_static= {0.009513, 0.000137} us Measuring clock cycles: first runs (cycles): 202 53 51 53 51 51 55 50 50 53 ... slowest runs (cycles): ... 53 53 53 53 53 55 55 55 62 202 min: 50 cycles q1: 51 cycles median: 53 cycles q3: 53 cycles max: 202 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_with_irt_nnacl_newlib_static= {53, 2} count Apparent clock speed: 5571 MHz TestUncontendedMutexLock: Measuring real time: 0.030 usec (2.986e-08 sec) per iteration: 2.986e-06 sec for 100 iterations 0.025 usec (2.4852e-08 sec) per iteration: 2.4852e-05 sec for 1000 iterations 0.025 usec (2.4527e-08 sec) per iteration: 0.0986924 sec for 4023821 iterations 0.025 usec (2.45257e-08 sec) per iteration: 0.0986871 sec for 4023821 iterations 0.025 usec (2.45242e-08 sec) per iteration: 0.0986811 sec for 4023821 iterations 0.025 usec (2.451e-08 sec) per iteration: 0.0986237 sec for 4023821 iterations 0.025 usec (2.45158e-08 sec) per iteration: 0.098647 sec for 4023821 iterations mean: 0.024521 usec stddev: 0.000007 usec relative stddev: 0.03% RESULT TestUncontendedMutexLock: x86-64_with_irt_nnacl_newlib_static= {0.024521, 0.000007} us Measuring clock cycles: first runs (cycles): 284 110 83 84 84 83 84 84 83 84 ... slowest runs (cycles): ... 84 84 84 84 84 84 84 84 110 284 min: 81 cycles q1: 83 cycles median: 84 cycles q3: 84 cycles max: 284 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_with_irt_nnacl_newlib_static= {84, 1} count Apparent clock speed: 3426 MHz TestCondvarSignalNoOp: Measuring real time: 0.343 usec (3.4289e-07 sec) per iteration: 3.4289e-05 sec for 100 iterations 0.309 usec (3.08871e-07 sec) per iteration: 0.0900786 sec for 291638 iterations 0.309 usec (3.0912e-07 sec) per iteration: 0.0901512 sec for 291638 iterations 0.309 usec (3.08633e-07 sec) per iteration: 0.0900091 sec for 291638 iterations 0.309 usec (3.08998e-07 sec) per iteration: 0.0901157 sec for 291638 iterations 0.310 usec (3.10375e-07 sec) per iteration: 0.0905173 sec for 291638 iterations mean: 0.309200 usec stddev: 0.000610 usec relative stddev: 0.20% RESULT TestCondvarSignalNoOp: x86-64_with_irt_nnacl_newlib_static= {0.309200, 0.000610} us Measuring clock cycles: first runs (cycles): 1157 746 664 680 675 674 712 671 673 673 ... slowest runs (cycles): ... 698 711 711 712 715 730 736 746 762 1157 min: 662 cycles q1: 671 cycles median: 676 cycles q3: 680 cycles max: 1157 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {676, 9} count Apparent clock speed: 2186 MHz TestThreadCreateAndJoin: Measuring real time: 40.129 usec (4.01291e-05 sec) per iteration: 0.00401291 sec for 100 iterations 35.245 usec (3.52453e-05 sec) per iteration: 0.0877961 sec for 2491 iterations 33.970 usec (3.39698e-05 sec) per iteration: 0.0846187 sec for 2491 iterations 32.482 usec (3.24815e-05 sec) per iteration: 0.0809115 sec for 2491 iterations 32.029 usec (3.20286e-05 sec) per iteration: 0.0797833 sec for 2491 iterations 32.644 usec (3.2644e-05 sec) per iteration: 0.0813163 sec for 2491 iterations mean: 33.273862 usec stddev: 1.178646 usec relative stddev: 3.54% RESULT TestThreadCreateAndJoin: x86-64_with_irt_nnacl_newlib_static= {33.273862, 1.178646} us Measuring clock cycles: first runs (cycles): 82438 93362 63104 93475 240521 82440 78809 69811 100974 116973 ... slowest runs (cycles): ... 80745 82438 82440 93362 93475 100974 104819 116973 160647 240521 min: 56881 cycles q1: 58581 cycles median: 60278 cycles q3: 63029 cycles max: 240521 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_with_irt_nnacl_newlib_static= {60278, 4448} count Apparent clock speed: 1812 MHz TestThreadWakeup: Measuring real time: 15.746 usec (1.5746e-05 sec) per iteration: 0.0015746 sec for 100 iterations 18.052 usec (1.80521e-05 sec) per iteration: 0.114631 sec for 6350 iterations 19.013 usec (1.90129e-05 sec) per iteration: 0.120732 sec for 6350 iterations 19.845 usec (1.98449e-05 sec) per iteration: 0.126015 sec for 6350 iterations 19.618 usec (1.96182e-05 sec) per iteration: 0.124576 sec for 6350 iterations 19.591 usec (1.95909e-05 sec) per iteration: 0.124402 sec for 6350 iterations mean: 19.223798 usec stddev: 0.647085 usec relative stddev: 3.37% RESULT TestThreadWakeup: x86-64_with_irt_nnacl_newlib_static= {19.223798, 0.647085} us Measuring clock cycles: first runs (cycles): 96801 147025 46920 48314 49817 37632 37070 54283 43719 42553 ... slowest runs (cycles): ... 54283 55010 67363 73988 76130 90151 96801 147025 150324 253985 min: 34297 cycles q1: 42004 cycles median: 42714 cycles q3: 44984 cycles max: 253985 cycles RESULT TestThreadWakeup_CycleCount: x86-64_with_irt_nnacl_newlib_static= {42714, 2980} count Apparent clock speed: 2222 MHz TestCatchingFault: Measuring real time: 3.766 usec (3.7655e-06 sec) per iteration: 0.00037655 sec for 100 iterations 1.939 usec (1.93912e-06 sec) per iteration: 0.0514953 sec for 26556 iterations 1.930 usec (1.93011e-06 sec) per iteration: 0.0512559 sec for 26556 iterations 1.993 usec (1.99322e-06 sec) per iteration: 0.052932 sec for 26556 iterations 1.959 usec (1.95949e-06 sec) per iteration: 0.0520362 sec for 26556 iterations 1.932 usec (1.93213e-06 sec) per iteration: 0.0513097 sec for 26556 iterations mean: 1.950814 usec stddev: 0.023615 usec relative stddev: 1.21% RESULT TestCatchingFault: x86-64_with_irt_nnacl_newlib_static= {1.950814, 0.023615} us Measuring clock cycles: first runs (cycles): 5698 4331 4512 4606 4248 4180 4212 4226 4188 4110 ... slowest runs (cycles): ... 5180 5181 5213 5248 5253 5330 5442 5501 5698 5717 min: 4092 cycles q1: 4153 cycles median: 4202 cycles q3: 4551 cycles max: 5717 cycles RESULT TestCatchingFault_CycleCount: x86-64_with_irt_nnacl_newlib_static= {4202, 398} count Apparent clock speed: 2154 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 43.643 usec (4.36429e-05 sec) per iteration: 0.00436429 sec for 100 iterations 62.235 usec (6.2235e-05 sec) per iteration: 0.14258 sec for 2291 iterations 66.856 usec (6.68563e-05 sec) per iteration: 0.153168 sec for 2291 iterations 50.785 usec (5.07854e-05 sec) per iteration: 0.116349 sec for 2291 iterations 52.533 usec (5.25327e-05 sec) per iteration: 0.120352 sec for 2291 iterations 53.371 usec (5.33705e-05 sec) per iteration: 0.122272 sec for 2291 iterations mean: 57.155997 usec stddev: 6.263880 usec relative stddev: 10.96% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_with_irt_nnacl_newlib_static= {57.155997, 6.263880} us Measuring clock cycles: first runs (cycles): 106931 282508 63514 152654 487368 116360 271688 169395 209347 178652 ... slowest runs (cycles): ... 248399 252624 271688 282508 330939 336302 350237 439356 465539 487368 min: 63514 cycles q1: 80182 cycles median: 114068 cycles q3: 152654 cycles max: 487368 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_with_irt_nnacl_newlib_static= {114068, 72472} count Apparent clock speed: 1996 MHz [ OK ] nacl_newlib.run_performance_test_irt (6470 ms) scons: done building targets. ********************************************************************** COMMAND EXECUTION REPORT ********************************************************************** 3 command_tester.py 1 doSizeCheck ********************************************************************** ENVIRONMENT USAGE REPORT ********************************************************************** 4 nacl_irt_test-x86-64 Command return code: 0