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

Running command: /b/s/w/ir/cache/vpython/7c7ecd/bin/python scons.py --verbose -k -j1 --mode=nacl,dbg-host,nacl_irt_test platform=x86-64 --clang large_tests_irt force_sel_ldr=../out_64/sel_ldr force_irt=../out_64/irt_x64/irt_core.nexe perf_prefix=gn_ scons: Reading SConscript files ... ====================================================================== SCONS ARGS:['scons.py', '--verbose', '-k', '-j1', '--mode=nacl,dbg-host,nacl_irt_test', 'platform=x86-64', '--clang', 'large_tests_irt', 'force_sel_ldr=../out_64/sel_ldr', 'force_irt=../out_64/irt_x64/irt_core.nexe', 'perf_prefix=gn_'] ====================================================================== ====================================================================== Python Info: ====================================================================== darwin 2.7.14+chromium14 (6cc0aad35b-dirty:6cc0aad35b, Oct 3 2017, 17:23:56) [GCC 4.2.1 Compatible Apple LLVM 8.0.0 (clang-800.0.38)] ====================================================================== ENV: ====================================================================== PATH /b/s/w/ir/cache/vpython/7c7ecd/bin:/b/s/w/ir/cipd_bin_packages:/b/s/w/ir/cipd_bin_packages/bin:/b/s/cipd_cache/bin:/opt/local/bin:/opt/local/sbin:/usr/local/sbin:/usr/local/git/bin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/b/s/w/ir/kitchen-checkout/depot_tools ====================================================================== OS: ====================================================================== kern.ostype: Darwin kern.osrelease: 17.5.0 kern.osrevision: 199506 kern.version: Darwin Kernel Version 17.5.0: Mon Mar 5 22:24:32 PST 2018; root:xnu-4570.51.1~1/RELEASE_X86_64 kern.posix1version: 200112 kern.osversion: 17E199 kern.osproductversion: 10.13.4 ====================================================================== CPU: ====================================================================== hw.machine: x86_64 hw.model: VMware7,1 hw.ncpu: 8 hw.ncpu: 8 hw.activecpu: 8 hw.physicalcpu: 8 hw.physicalcpu_max: 8 hw.logicalcpu: 8 hw.logicalcpu_max: 8 hw.cputype: 7 hw.cpusubtype: 4 hw.cpu64bit_capable: 1 hw.cpufamily: 526772277 hw.cpufrequency: 2574000000 hw.cpufrequency_min: 2574000000 hw.cpufrequency_max: 2574000000 ====================================================================== RAM: ====================================================================== hw.memsize: 17179869184 ====================================================================== LOAD: ====================================================================== TBD ====================================================================== UPTIME: ====================================================================== kern.boottime: { sec = 1547675700, usec = 117403 } Wed Jan 16 13:55:00 2019 ====================================================================== 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/mac_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang ASPPCOM: /b/s/w/ir/kitchen-workdir/native_client/toolchain/mac_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-mac TARGET_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/dbg-mac-x86-64 OBJ_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/dbg-mac-x86-64/obj BUILD_TYPE_DESCRIPTION: MacOS 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 -isysroot /b/s/w/ir/cache/osx_sdk/XCode.app/Contents/Developer/Platforms/MacOSX.platform/Developer/SDKs/MacOSX10.13.sdk -mmacosx-version-min=10.6 -m64 -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/mac_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang ASPPCOM: /b/s/w/ir/kitchen-workdir/native_client/toolchain/mac_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/mac_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang ASPPCOM: /b/s/w/ir/kitchen-workdir/native_client/toolchain/mac_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/7c7ecd/bin/python tools/command_tester.py --name nacl_newlib.run_benchmark_test_irt --time_warning 10 --time_error 100 --perf_env_description gn_x86-64_with_irt_nnacl_newlib_static --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 /b/s/w/ir/kitchen-workdir/out_64/sel_ldr -B /b/s/w/ir/kitchen-workdir/out_64/irt_x64/irt_core.nexe -- scons-out/nacl_irt_test-x86-64/obj/tests/benchmark/benchmark_test.nexe gn_x86-64_with_irt_nnacl_newlib_static [ RUN ] nacl_newlib.run_benchmark_test_irt /b/s/w/ir/kitchen-workdir/out_64/sel_ldr -B /b/s/w/ir/kitchen-workdir/out_64/irt_x64/irt_core.nexe -- scons-out/nacl_irt_test-x86-64/obj/tests/benchmark/benchmark_test.nexe gn_x86-64_with_irt_nnacl_newlib_static [21625,2742653824:15:31:15.582122] Native Client module will be loaded at base address 0x000030bd00000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: gn_x86-64_with_irt_nnacl_newlib_static= {0.076420, 0.001240} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (900 ms) /b/s/w/ir/cache/vpython/7c7ecd/bin/python tools/command_tester.py --name nacl_newlib.run_clock_cputime_test_irt --time_warning 60 --time_error 600 --perf_env_description gn_x86-64_with_irt_nnacl_newlib_static --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 /b/s/w/ir/kitchen-workdir/out_64/sel_ldr -B /b/s/w/ir/kitchen-workdir/out_64/irt_x64/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 /b/s/w/ir/kitchen-workdir/out_64/sel_ldr -B /b/s/w/ir/kitchen-workdir/out_64/irt_x64/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 (66649 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: gn_x86-64_with_irt_nnacl_newlib_static= 527.127 KB RESULT hello_world_size.out: ZIPPED_gn_x86-64_with_irt_nnacl_newlib_static= 186.967 KB /b/s/w/ir/cache/vpython/7c7ecd/bin/python tools/command_tester.py --name nacl_newlib.run_performance_test_irt --time_warning 10 --time_error 100 --perf_env_description gn_x86-64_with_irt_nnacl_newlib_static --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 /b/s/w/ir/kitchen-workdir/out_64/sel_ldr -e -B /b/s/w/ir/kitchen-workdir/out_64/irt_x64/irt_core.nexe -- scons-out/nacl_irt_test-x86-64/obj/tests/performance/performance_test.nexe gn_x86-64_with_irt_nnacl_newlib_static [ RUN ] nacl_newlib.run_performance_test_irt /b/s/w/ir/kitchen-workdir/out_64/sel_ldr -e -B /b/s/w/ir/kitchen-workdir/out_64/irt_x64/irt_core.nexe -- scons-out/nacl_irt_test-x86-64/obj/tests/performance/performance_test.nexe gn_x86-64_with_irt_nnacl_newlib_static [21630,2742653824:15:31:29.864718] Native Client module will be loaded at base address 0x0000325e00000000 TestNull: Measuring real time: 0.015 usec (1.471e-08 sec) per iteration: 1.471e-06 sec for 100 iterations 0.002 usec (2.498e-09 sec) per iteration: 2.498e-06 sec for 1000 iterations 0.002 usec (2.045e-09 sec) per iteration: 2.045e-05 sec for 10000 iterations 0.002 usec (1.98519e-09 sec) per iteration: 0.0970755 sec for 48899755 iterations 0.002 usec (2.00933e-09 sec) per iteration: 0.098256 sec for 48899755 iterations 0.002 usec (2.00279e-09 sec) per iteration: 0.0979358 sec for 48899755 iterations 0.002 usec (1.99659e-09 sec) per iteration: 0.0976327 sec for 48899755 iterations 0.002 usec (1.98027e-09 sec) per iteration: 0.0968349 sec for 48899755 iterations mean: 0.001995 usec stddev: 0.000011 usec relative stddev: 0.54% RESULT TestNull: gn_x86-64_with_irt_nnacl_newlib_static= {0.001995, 0.000011} us Measuring clock cycles: first runs (cycles): 207 21 18 21 21 21 36 21 21 21 ... slowest runs (cycles): ... 36 36 36 36 36 36 36 36 36 207 min: 18 cycles q1: 21 cycles median: 21 cycles q3: 21 cycles max: 207 cycles RESULT TestNull_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {21, 0} count Apparent clock speed: 10527 MHz TestNaClSyscall: Measuring real time: 0.222 usec (2.2245e-07 sec) per iteration: 2.2245e-05 sec for 100 iterations 0.215 usec (2.15286e-07 sec) per iteration: 0.0967794 sec for 449539 iterations 0.223 usec (2.23093e-07 sec) per iteration: 0.100289 sec for 449539 iterations 0.214 usec (2.14039e-07 sec) per iteration: 0.0962189 sec for 449539 iterations 0.207 usec (2.06594e-07 sec) per iteration: 0.092872 sec for 449539 iterations 0.204 usec (2.03669e-07 sec) per iteration: 0.0915572 sec for 449539 iterations mean: 0.212536 usec stddev: 0.006859 usec relative stddev: 3.23% RESULT TestNaClSyscall: gn_x86-64_with_irt_nnacl_newlib_static= {0.212536, 0.006859} us Measuring clock cycles: first runs (cycles): 1107 501 501 489 486 495 495 495 495 504 ... slowest runs (cycles): ... 504 504 504 504 504 504 504 504 507 1107 min: 486 cycles q1: 492 cycles median: 495 cycles q3: 495 cycles max: 1107 cycles RESULT TestNaClSyscall_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {495, 3} count Apparent clock speed: 2329 MHz TestSetjmpLongjmp: Measuring real time: 0.019 usec (1.924e-08 sec) per iteration: 1.924e-06 sec for 100 iterations 0.013 usec (1.2619e-08 sec) per iteration: 1.2619e-05 sec for 1000 iterations 0.012 usec (1.20413e-08 sec) per iteration: 0.0954221 sec for 7924558 iterations 0.012 usec (1.20345e-08 sec) per iteration: 0.0953683 sec for 7924558 iterations 0.012 usec (1.19901e-08 sec) per iteration: 0.0950161 sec for 7924558 iterations 0.012 usec (1.1988e-08 sec) per iteration: 0.0949997 sec for 7924558 iterations 0.012 usec (1.20431e-08 sec) per iteration: 0.095436 sec for 7924558 iterations mean: 0.012019 usec stddev: 0.000025 usec relative stddev: 0.21% RESULT TestSetjmpLongjmp: gn_x86-64_with_irt_nnacl_newlib_static= {0.012019, 0.000025} us Measuring clock cycles: first runs (cycles): 561 57 24 24 33 33 33 21 24 24 ... slowest runs (cycles): ... 33 33 33 33 33 33 33 33 57 561 min: 21 cycles q1: 24 cycles median: 30 cycles q3: 33 cycles max: 561 cycles RESULT TestSetjmpLongjmp_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {30, 9} count Apparent clock speed: 2496 MHz TestClockGetTime: Measuring real time: 0.356 usec (3.5642e-07 sec) per iteration: 3.5642e-05 sec for 100 iterations 0.354 usec (3.53895e-07 sec) per iteration: 0.0992913 sec for 280567 iterations 0.352 usec (3.5205e-07 sec) per iteration: 0.0987737 sec for 280567 iterations 0.347 usec (3.46816e-07 sec) per iteration: 0.0973052 sec for 280567 iterations 0.351 usec (3.50825e-07 sec) per iteration: 0.09843 sec for 280567 iterations 0.351 usec (3.5138e-07 sec) per iteration: 0.0985858 sec for 280567 iterations mean: 0.350993 usec stddev: 0.002331 usec relative stddev: 0.66% RESULT TestClockGetTime: gn_x86-64_with_irt_nnacl_newlib_static= {0.350993, 0.002331} us Measuring clock cycles: first runs (cycles): 2802 939 942 942 948 939 927 927 933 933 ... slowest runs (cycles): ... 954 969 972 990 999 1002 1005 1026 1074 2802 min: 918 cycles q1: 933 cycles median: 939 cycles q3: 945 cycles max: 2802 cycles RESULT TestClockGetTime_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {939, 12} count Apparent clock speed: 2675 MHz TestTlsVariable: Measuring real time: 0.013 usec (1.314e-08 sec) per iteration: 1.314e-06 sec for 100 iterations 0.006 usec (6.468e-09 sec) per iteration: 6.468e-06 sec for 1000 iterations 0.006 usec (6.0468e-09 sec) per iteration: 6.0468e-05 sec for 10000 iterations 0.006 usec (6.19941e-09 sec) per iteration: 0.102524 sec for 16537672 iterations 0.006 usec (6.10446e-09 sec) per iteration: 0.100954 sec for 16537672 iterations 0.006 usec (6.0163e-09 sec) per iteration: 0.0994956 sec for 16537672 iterations 0.006 usec (6.09203e-09 sec) per iteration: 0.100748 sec for 16537672 iterations 0.006 usec (6.09244e-09 sec) per iteration: 0.100755 sec for 16537672 iterations mean: 0.006101 usec stddev: 0.000058 usec relative stddev: 0.96% RESULT TestTlsVariable: gn_x86-64_with_irt_nnacl_newlib_static= {0.006101, 0.000058} us Measuring clock cycles: first runs (cycles): 345 66 21 21 21 21 21 33 30 30 ... slowest runs (cycles): ... 30 30 30 30 30 30 33 33 66 345 min: 21 cycles q1: 21 cycles median: 21 cycles q3: 30 cycles max: 345 cycles RESULT TestTlsVariable_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {21, 9} count Apparent clock speed: 3442 MHz TestMmapAnonymous: Measuring real time: 10.146 usec (1.01458e-05 sec) per iteration: 0.00101458 sec for 100 iterations 9.913 usec (9.91317e-06 sec) per iteration: 0.0977042 sec for 9856 iterations 9.936 usec (9.93645e-06 sec) per iteration: 0.0979336 sec for 9856 iterations 9.864 usec (9.86449e-06 sec) per iteration: 0.0972244 sec for 9856 iterations 9.719 usec (9.71882e-06 sec) per iteration: 0.0957887 sec for 9856 iterations 10.541 usec (1.05408e-05 sec) per iteration: 0.10389 sec for 9856 iterations mean: 9.994750 usec stddev: 0.283319 usec relative stddev: 2.83% RESULT TestMmapAnonymous: gn_x86-64_with_irt_nnacl_newlib_static= {9.994750, 0.283319} us Measuring clock cycles: first runs (cycles): 38277 27276 27645 27129 27126 27015 27039 27153 27039 26880 ... slowest runs (cycles): ... 27150 27153 27225 27276 27309 27366 27645 29343 38277 47778 min: 26295 cycles q1: 26652 cycles median: 26850 cycles q3: 27030 cycles max: 47778 cycles RESULT TestMmapAnonymous_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {26850, 378} count Apparent clock speed: 2686 MHz TestAtomicIncrement: Measuring real time: 0.017 usec (1.701e-08 sec) per iteration: 1.701e-06 sec for 100 iterations 0.010 usec (1.0107e-08 sec) per iteration: 1.0107e-05 sec for 1000 iterations 0.010 usec (9.8425e-09 sec) per iteration: 0.0973829 sec for 9894132 iterations 0.010 usec (9.73853e-09 sec) per iteration: 0.0963543 sec for 9894132 iterations 0.010 usec (9.95923e-09 sec) per iteration: 0.0985379 sec for 9894132 iterations 0.010 usec (1.00816e-08 sec) per iteration: 0.0997485 sec for 9894132 iterations 0.010 usec (9.94504e-09 sec) per iteration: 0.0983975 sec for 9894132 iterations mean: 0.009913 usec stddev: 0.000116 usec relative stddev: 1.17% RESULT TestAtomicIncrement: gn_x86-64_with_irt_nnacl_newlib_static= {0.009913, 0.000116} us Measuring clock cycles: first runs (cycles): 282 42 48 45 45 48 45 48 42 48 ... slowest runs (cycles): ... 48 48 48 48 48 48 48 48 48 282 min: 42 cycles q1: 45 cycles median: 45 cycles q3: 48 cycles max: 282 cycles RESULT TestAtomicIncrement_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {45, 3} count Apparent clock speed: 4539 MHz TestUncontendedMutexLock: Measuring real time: 0.030 usec (3.027e-08 sec) per iteration: 3.027e-06 sec for 100 iterations 0.023 usec (2.3461e-08 sec) per iteration: 2.3461e-05 sec for 1000 iterations 0.023 usec (2.30202e-08 sec) per iteration: 0.0981213 sec for 4262392 iterations 0.023 usec (2.34593e-08 sec) per iteration: 0.0999926 sec for 4262392 iterations 0.024 usec (2.37767e-08 sec) per iteration: 0.101346 sec for 4262392 iterations 0.023 usec (2.33211e-08 sec) per iteration: 0.0994036 sec for 4262392 iterations 0.023 usec (2.32097e-08 sec) per iteration: 0.0989286 sec for 4262392 iterations mean: 0.023357 usec stddev: 0.000254 usec relative stddev: 1.09% RESULT TestUncontendedMutexLock: gn_x86-64_with_irt_nnacl_newlib_static= {0.023357, 0.000254} us Measuring clock cycles: first runs (cycles): 408 108 81 81 81 81 81 81 81 81 ... slowest runs (cycles): ... 90 90 90 90 90 90 90 90 108 408 min: 78 cycles q1: 81 cycles median: 90 cycles q3: 90 cycles max: 408 cycles RESULT TestUncontendedMutexLock_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {90, 9} count Apparent clock speed: 3853 MHz TestCondvarSignalNoOp: Measuring real time: 0.377 usec (3.7718e-07 sec) per iteration: 3.7718e-05 sec for 100 iterations 0.280 usec (2.80395e-07 sec) per iteration: 0.0743398 sec for 265125 iterations 0.290 usec (2.89833e-07 sec) per iteration: 0.0768419 sec for 265125 iterations 0.312 usec (3.12194e-07 sec) per iteration: 0.0827704 sec for 265125 iterations 0.290 usec (2.89819e-07 sec) per iteration: 0.0768383 sec for 265125 iterations 0.286 usec (2.86459e-07 sec) per iteration: 0.0759473 sec for 265125 iterations mean: 0.291740 usec stddev: 0.010792 usec relative stddev: 3.70% RESULT TestCondvarSignalNoOp: gn_x86-64_with_irt_nnacl_newlib_static= {0.291740, 0.010792} us Measuring clock cycles: first runs (cycles): 1896 810 762 759 759 750 765 756 756 756 ... slowest runs (cycles): ... 771 771 774 774 774 774 777 777 810 1896 min: 738 cycles q1: 756 cycles median: 756 cycles q3: 762 cycles max: 1896 cycles RESULT TestCondvarSignalNoOp_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {756, 6} count Apparent clock speed: 2591 MHz TestThreadCreateAndJoin: Measuring real time: 96.491 usec (9.64909e-05 sec) per iteration: 0.00964909 sec for 100 iterations 97.044 usec (9.70445e-05 sec) per iteration: 0.100538 sec for 1036 iterations 94.471 usec (9.4471e-05 sec) per iteration: 0.097872 sec for 1036 iterations 93.933 usec (9.39333e-05 sec) per iteration: 0.0973149 sec for 1036 iterations 95.803 usec (9.5803e-05 sec) per iteration: 0.0992519 sec for 1036 iterations 92.920 usec (9.29204e-05 sec) per iteration: 0.0962655 sec for 1036 iterations mean: 94.834440 usec stddev: 1.444320 usec relative stddev: 1.52% RESULT TestThreadCreateAndJoin: gn_x86-64_with_irt_nnacl_newlib_static= {94.834440, 1.444320} us Measuring clock cycles: first runs (cycles): 239295 243243 243639 272916 246810 265647 246417 238890 249495 246882 ... slowest runs (cycles): ... 257811 258267 258510 258546 259263 259536 262098 265647 266367 272916 min: 230793 cycles q1: 247068 cycles median: 249396 cycles q3: 253257 cycles max: 272916 cycles RESULT TestThreadCreateAndJoin_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {249396, 6189} count Apparent clock speed: 2630 MHz TestThreadWakeup: Measuring real time: 31.789 usec (3.17891e-05 sec) per iteration: 0.00317891 sec for 100 iterations 32.101 usec (3.21011e-05 sec) per iteration: 0.100958 sec for 3145 iterations 32.300 usec (3.23004e-05 sec) per iteration: 0.101585 sec for 3145 iterations 32.000 usec (3.20005e-05 sec) per iteration: 0.100642 sec for 3145 iterations 31.548 usec (3.15481e-05 sec) per iteration: 0.0992189 sec for 3145 iterations 32.215 usec (3.22147e-05 sec) per iteration: 0.101315 sec for 3145 iterations mean: 32.032976 usec stddev: 0.262801 usec relative stddev: 0.82% RESULT TestThreadWakeup: gn_x86-64_with_irt_nnacl_newlib_static= {32.032976, 0.262801} us Measuring clock cycles: first runs (cycles): 96441 86112 85881 86433 90495 123441 106971 94503 135846 113577 ... slowest runs (cycles): ... 101373 101844 102636 104277 104664 106971 113577 123441 135846 260205 min: 71868 cycles q1: 72861 cycles median: 73170 cycles q3: 85881 cycles max: 260205 cycles RESULT TestThreadWakeup_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {73170, 13020} count Apparent clock speed: 2284 MHz TestCatchingFault: Measuring real time: 34.383 usec (3.43831e-05 sec) per iteration: 0.00343831 sec for 100 iterations 31.186 usec (3.11859e-05 sec) per iteration: 0.0906885 sec for 2908 iterations 31.571 usec (3.1571e-05 sec) per iteration: 0.0918084 sec for 2908 iterations 34.426 usec (3.44263e-05 sec) per iteration: 0.100112 sec for 2908 iterations 33.067 usec (3.30668e-05 sec) per iteration: 0.0961581 sec for 2908 iterations 32.457 usec (3.24573e-05 sec) per iteration: 0.0943859 sec for 2908 iterations mean: 32.541430 usec stddev: 1.150219 usec relative stddev: 3.53% RESULT TestCatchingFault: gn_x86-64_with_irt_nnacl_newlib_static= {32.541430, 1.150219} us Measuring clock cycles: first runs (cycles): 105525 86415 85176 89850 85974 85008 123876 116823 136677 118890 ... slowest runs (cycles): ... 106971 116823 118890 123849 123876 126135 127569 133413 136677 139968 min: 82044 cycles q1: 82986 cycles median: 83547 cycles q3: 84660 cycles max: 139968 cycles RESULT TestCatchingFault_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {83547, 1674} count Apparent clock speed: 2567 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 96.045 usec (9.60447e-05 sec) per iteration: 0.00960447 sec for 100 iterations 95.366 usec (9.53665e-05 sec) per iteration: 0.0992765 sec for 1041 iterations 95.300 usec (9.53002e-05 sec) per iteration: 0.0992075 sec for 1041 iterations 100.052 usec (0.000100052 sec) per iteration: 0.104155 sec for 1041 iterations 93.405 usec (9.34054e-05 sec) per iteration: 0.0972351 sec for 1041 iterations 93.807 usec (9.38067e-05 sec) per iteration: 0.0976527 sec for 1041 iterations mean: 95.586216 usec stddev: 2.366398 usec relative stddev: 2.48% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: gn_x86-64_with_irt_nnacl_newlib_static= {95.586216, 2.366398} us Measuring clock cycles: first runs (cycles): 236853 252537 284814 271071 262482 290025 246981 254778 249699 257904 ... slowest runs (cycles): ... 263949 265560 267666 271071 277317 279918 283239 284814 290025 420828 min: 225720 cycles q1: 246135 cycles median: 250980 cycles q3: 254811 cycles max: 420828 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {250980, 8676} count Apparent clock speed: 2626 MHz [ OK ] nacl_newlib.run_performance_test_irt (6890 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