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 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'] ====================================================================== ====================================================================== 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 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 scons-out/dbg-mac-x86-64/staging/sel_ldr -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-mac-x86-64/staging/sel_ldr -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 [15721,2742653824:15:25:12.120936] Native Client module will be loaded at base address 0x00004c6700000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_with_irt_nnacl_newlib_static= {0.077300, 0.004570} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (910 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 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 scons-out/dbg-mac-x86-64/staging/sel_ldr -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-mac-x86-64/staging/sel_ldr -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 (66589 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.127 KB RESULT hello_world_size.out: ZIPPED_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 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 scons-out/dbg-mac-x86-64/staging/sel_ldr -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-mac-x86-64/staging/sel_ldr -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 [15726,2742653824:15:25:26.406560] Native Client module will be loaded at base address 0x0000530c00000000 TestNull: Measuring real time: 0.009 usec (9.35e-09 sec) per iteration: 9.35e-07 sec for 100 iterations 0.002 usec (2.486e-09 sec) per iteration: 2.486e-06 sec for 1000 iterations 0.002 usec (2.0527e-09 sec) per iteration: 2.0527e-05 sec for 10000 iterations 0.002 usec (2.01304e-09 sec) per iteration: 0.0980678 sec for 48716324 iterations 0.002 usec (2.00643e-09 sec) per iteration: 0.0977461 sec for 48716324 iterations 0.002 usec (1.99894e-09 sec) per iteration: 0.097381 sec for 48716324 iterations 0.002 usec (1.98914e-09 sec) per iteration: 0.0969038 sec for 48716324 iterations 0.002 usec (2.00862e-09 sec) per iteration: 0.0978524 sec for 48716324 iterations mean: 0.002003 usec stddev: 0.000008 usec relative stddev: 0.42% RESULT TestNull: x86-64_with_irt_nnacl_newlib_static= {0.002003, 0.000008} us Measuring clock cycles: first runs (cycles): 228 30 27 30 30 21 21 21 21 21 ... slowest runs (cycles): ... 30 30 30 30 30 30 30 30 30 228 min: 21 cycles q1: 21 cycles median: 21 cycles q3: 30 cycles max: 228 cycles RESULT TestNull_CycleCount: x86-64_with_irt_nnacl_newlib_static= {21, 9} count Apparent clock speed: 10483 MHz TestNaClSyscall: Measuring real time: 0.232 usec (2.3244e-07 sec) per iteration: 2.3244e-05 sec for 100 iterations 0.224 usec (2.24384e-07 sec) per iteration: 0.0965342 sec for 430218 iterations 0.225 usec (2.24925e-07 sec) per iteration: 0.0967666 sec for 430218 iterations 0.218 usec (2.17744e-07 sec) per iteration: 0.0936774 sec for 430218 iterations 0.226 usec (2.25642e-07 sec) per iteration: 0.0970751 sec for 430218 iterations 0.226 usec (2.26256e-07 sec) per iteration: 0.0973392 sec for 430218 iterations mean: 0.223790 usec stddev: 0.003089 usec relative stddev: 1.38% RESULT TestNaClSyscall: x86-64_with_irt_nnacl_newlib_static= {0.223790, 0.003089} us Measuring clock cycles: first runs (cycles): 1113 504 501 489 504 495 492 492 501 486 ... slowest runs (cycles): ... 501 501 501 504 504 504 504 504 504 1113 min: 483 cycles q1: 486 cycles median: 495 cycles q3: 495 cycles max: 1113 cycles RESULT TestNaClSyscall_CycleCount: x86-64_with_irt_nnacl_newlib_static= {495, 9} count Apparent clock speed: 2212 MHz TestSetjmpLongjmp: Measuring real time: 0.018 usec (1.809e-08 sec) per iteration: 1.809e-06 sec for 100 iterations 0.013 usec (1.2539e-08 sec) per iteration: 1.2539e-05 sec for 1000 iterations 0.012 usec (1.21432e-08 sec) per iteration: 0.0968433 sec for 7975117 iterations 0.012 usec (1.20701e-08 sec) per iteration: 0.0962601 sec for 7975117 iterations 0.012 usec (1.2059e-08 sec) per iteration: 0.0961722 sec for 7975117 iterations 0.012 usec (1.20895e-08 sec) per iteration: 0.0964155 sec for 7975117 iterations 0.012 usec (1.20699e-08 sec) per iteration: 0.0962592 sec for 7975117 iterations mean: 0.012086 usec stddev: 0.000030 usec relative stddev: 0.25% RESULT TestSetjmpLongjmp: x86-64_with_irt_nnacl_newlib_static= {0.012086, 0.000030} us Measuring clock cycles: first runs (cycles): 516 60 57 51 24 24 33 33 33 21 ... slowest runs (cycles): ... 33 33 33 33 33 33 51 57 60 516 min: 21 cycles q1: 24 cycles median: 30 cycles q3: 33 cycles max: 516 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {30, 9} count Apparent clock speed: 2482 MHz TestClockGetTime: Measuring real time: 0.314 usec (3.141e-07 sec) per iteration: 3.141e-05 sec for 100 iterations 0.339 usec (3.38871e-07 sec) per iteration: 0.107886 sec for 318369 iterations 0.309 usec (3.09424e-07 sec) per iteration: 0.0985112 sec for 318369 iterations 0.323 usec (3.22547e-07 sec) per iteration: 0.102689 sec for 318369 iterations 0.310 usec (3.10427e-07 sec) per iteration: 0.0988303 sec for 318369 iterations 0.306 usec (3.05694e-07 sec) per iteration: 0.0973236 sec for 318369 iterations mean: 0.317393 usec stddev: 0.012139 usec relative stddev: 3.82% RESULT TestClockGetTime: x86-64_with_irt_nnacl_newlib_static= {0.317393, 0.012139} us Measuring clock cycles: first runs (cycles): 2763 846 816 816 828 912 954 828 813 822 ... slowest runs (cycles): ... 828 828 828 828 831 834 846 912 954 2763 min: 810 cycles q1: 816 cycles median: 816 cycles q3: 819 cycles max: 2763 cycles RESULT TestClockGetTime_CycleCount: x86-64_with_irt_nnacl_newlib_static= {816, 3} count Apparent clock speed: 2571 MHz TestTlsVariable: Measuring real time: 0.011 usec (1.097e-08 sec) per iteration: 1.097e-06 sec for 100 iterations 0.006 usec (6.441e-09 sec) per iteration: 6.441e-06 sec for 1000 iterations 0.006 usec (6.0438e-09 sec) per iteration: 6.0438e-05 sec for 10000 iterations 0.006 usec (6.04583e-09 sec) per iteration: 0.100034 sec for 16545881 iterations 0.006 usec (6.02892e-09 sec) per iteration: 0.0997537 sec for 16545881 iterations 0.006 usec (5.99848e-09 sec) per iteration: 0.0992501 sec for 16545881 iterations 0.006 usec (5.99236e-09 sec) per iteration: 0.0991489 sec for 16545881 iterations 0.006 usec (6.11098e-09 sec) per iteration: 0.101111 sec for 16545881 iterations mean: 0.006035 usec stddev: 0.000043 usec relative stddev: 0.71% RESULT TestTlsVariable: x86-64_with_irt_nnacl_newlib_static= {0.006035, 0.000043} us Measuring clock cycles: first runs (cycles): 306 66 21 21 21 30 30 30 30 30 ... slowest runs (cycles): ... 30 30 30 30 30 30 30 30 66 306 min: 21 cycles q1: 21 cycles median: 30 cycles q3: 30 cycles max: 306 cycles RESULT TestTlsVariable_CycleCount: x86-64_with_irt_nnacl_newlib_static= {30, 9} count Apparent clock speed: 4971 MHz TestMmapAnonymous: Measuring real time: 10.033 usec (1.00328e-05 sec) per iteration: 0.00100328 sec for 100 iterations 9.866 usec (9.86644e-06 sec) per iteration: 0.0983388 sec for 9967 iterations 9.908 usec (9.90821e-06 sec) per iteration: 0.0987551 sec for 9967 iterations 9.720 usec (9.71962e-06 sec) per iteration: 0.0968755 sec for 9967 iterations 10.901 usec (1.09012e-05 sec) per iteration: 0.108652 sec for 9967 iterations 13.283 usec (1.32828e-05 sec) per iteration: 0.132389 sec for 9967 iterations mean: 10.735643 usec stddev: 1.340729 usec relative stddev: 12.49% RESULT TestMmapAnonymous: x86-64_with_irt_nnacl_newlib_static= {10.735643, 1.340729} us Measuring clock cycles: first runs (cycles): 72003 55692 54993 55476 54105 40626 42243 46449 49449 51657 ... slowest runs (cycles): ... 55089 55476 55692 55761 56046 56490 56688 57339 72003 136029 min: 26064 cycles q1: 42243 cycles median: 49137 cycles q3: 51420 cycles max: 136029 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_with_irt_nnacl_newlib_static= {49137, 9177} count Apparent clock speed: 4577 MHz TestAtomicIncrement: Measuring real time: 0.017 usec (1.67e-08 sec) per iteration: 1.67e-06 sec for 100 iterations 0.010 usec (1.0082e-08 sec) per iteration: 1.0082e-05 sec for 1000 iterations 0.010 usec (9.64229e-09 sec) per iteration: 0.0956386 sec for 9918666 iterations 0.010 usec (9.60178e-09 sec) per iteration: 0.0952368 sec for 9918666 iterations 0.010 usec (9.62175e-09 sec) per iteration: 0.0954349 sec for 9918666 iterations 0.010 usec (9.84351e-09 sec) per iteration: 0.0976345 sec for 9918666 iterations 0.010 usec (9.71052e-09 sec) per iteration: 0.0963154 sec for 9918666 iterations mean: 0.009684 usec stddev: 0.000088 usec relative stddev: 0.91% RESULT TestAtomicIncrement: x86-64_with_irt_nnacl_newlib_static= {0.009684, 0.000088} us Measuring clock cycles: first runs (cycles): 234 51 48 48 51 48 51 48 51 39 ... slowest runs (cycles): ... 51 51 51 51 51 51 51 51 51 234 min: 39 cycles q1: 48 cycles median: 48 cycles q3: 51 cycles max: 234 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_with_irt_nnacl_newlib_static= {48, 3} count Apparent clock speed: 4957 MHz TestUncontendedMutexLock: Measuring real time: 0.030 usec (3.033e-08 sec) per iteration: 3.033e-06 sec for 100 iterations 0.023 usec (2.3469e-08 sec) per iteration: 2.3469e-05 sec for 1000 iterations 0.023 usec (2.30862e-08 sec) per iteration: 0.0983691 sec for 4260939 iterations 0.023 usec (2.30453e-08 sec) per iteration: 0.0981948 sec for 4260939 iterations 0.023 usec (2.30452e-08 sec) per iteration: 0.0981943 sec for 4260939 iterations 0.023 usec (2.30442e-08 sec) per iteration: 0.0981899 sec for 4260939 iterations 0.023 usec (2.29692e-08 sec) per iteration: 0.0978703 sec for 4260939 iterations mean: 0.023038 usec stddev: 0.000038 usec relative stddev: 0.16% RESULT TestUncontendedMutexLock: x86-64_with_irt_nnacl_newlib_static= {0.023038, 0.000038} us Measuring clock cycles: first runs (cycles): 387 111 90 90 90 90 90 90 90 81 ... slowest runs (cycles): ... 90 90 90 90 90 90 90 90 111 387 min: 81 cycles q1: 81 cycles median: 90 cycles q3: 90 cycles max: 387 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_with_irt_nnacl_newlib_static= {90, 9} count Apparent clock speed: 3907 MHz TestCondvarSignalNoOp: Measuring real time: 0.384 usec (3.8378e-07 sec) per iteration: 3.8378e-05 sec for 100 iterations 0.237 usec (2.36624e-07 sec) per iteration: 0.061656 sec for 260565 iterations 0.239 usec (2.39416e-07 sec) per iteration: 0.0623835 sec for 260565 iterations 0.239 usec (2.39192e-07 sec) per iteration: 0.0623251 sec for 260565 iterations 0.238 usec (2.38452e-07 sec) per iteration: 0.0621324 sec for 260565 iterations 0.236 usec (2.36151e-07 sec) per iteration: 0.0615327 sec for 260565 iterations mean: 0.237967 usec stddev: 0.001337 usec relative stddev: 0.56% RESULT TestCondvarSignalNoOp: x86-64_with_irt_nnacl_newlib_static= {0.237967, 0.001337} us Measuring clock cycles: first runs (cycles): 1512 630 630 627 627 627 624 630 624 627 ... slowest runs (cycles): ... 630 630 630 630 633 633 633 633 633 1512 min: 606 cycles q1: 615 cycles median: 624 cycles q3: 627 cycles max: 1512 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {624, 12} count Apparent clock speed: 2622 MHz TestThreadCreateAndJoin: Measuring real time: 96.787 usec (9.67867e-05 sec) per iteration: 0.00967867 sec for 100 iterations 92.789 usec (9.2789e-05 sec) per iteration: 0.0958511 sec for 1033 iterations 92.605 usec (9.26045e-05 sec) per iteration: 0.0956605 sec for 1033 iterations 94.315 usec (9.43148e-05 sec) per iteration: 0.0974272 sec for 1033 iterations 92.551 usec (9.25506e-05 sec) per iteration: 0.0956048 sec for 1033 iterations 92.412 usec (9.24123e-05 sec) per iteration: 0.0954619 sec for 1033 iterations mean: 92.934262 usec stddev: 0.700777 usec relative stddev: 0.75% RESULT TestThreadCreateAndJoin: x86-64_with_irt_nnacl_newlib_static= {92.934262, 0.700777} us Measuring clock cycles: first runs (cycles): 266169 252888 254751 322221 263034 270141 270885 234624 275493 262260 ... slowest runs (cycles): ... 270141 270885 273072 274038 274932 275493 281634 284433 289311 322221 min: 234624 cycles q1: 248886 cycles median: 254751 cycles q3: 263601 cycles max: 322221 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_with_irt_nnacl_newlib_static= {254751, 14715} count Apparent clock speed: 2741 MHz TestThreadWakeup: Measuring real time: 32.909 usec (3.29095e-05 sec) per iteration: 0.00329095 sec for 100 iterations 32.257 usec (3.22568e-05 sec) per iteration: 0.0979961 sec for 3038 iterations 32.237 usec (3.22375e-05 sec) per iteration: 0.0979375 sec for 3038 iterations 33.063 usec (3.30633e-05 sec) per iteration: 0.100446 sec for 3038 iterations 32.241 usec (3.22415e-05 sec) per iteration: 0.0979495 sec for 3038 iterations 32.013 usec (3.20127e-05 sec) per iteration: 0.0972547 sec for 3038 iterations mean: 32.362357 usec stddev: 0.361923 usec relative stddev: 1.12% RESULT TestThreadWakeup: x86-64_with_irt_nnacl_newlib_static= {32.362357, 0.361923} us Measuring clock cycles: first runs (cycles): 107247 76398 77106 105516 86460 90030 103527 102765 74856 126072 ... slowest runs (cycles): ... 91419 91452 94818 102765 103527 105516 107247 107349 107769 126072 min: 74856 cycles q1: 87018 cycles median: 87747 cycles q3: 88614 cycles max: 126072 cycles RESULT TestThreadWakeup_CycleCount: x86-64_with_irt_nnacl_newlib_static= {87747, 1596} count Apparent clock speed: 2711 MHz TestCatchingFault: Measuring real time: 33.210 usec (3.32099e-05 sec) per iteration: 0.00332099 sec for 100 iterations 30.917 usec (3.0917e-05 sec) per iteration: 0.093091 sec for 3011 iterations 30.838 usec (3.08377e-05 sec) per iteration: 0.0928524 sec for 3011 iterations 30.807 usec (3.08073e-05 sec) per iteration: 0.0927608 sec for 3011 iterations 30.636 usec (3.06359e-05 sec) per iteration: 0.0922446 sec for 3011 iterations 31.634 usec (3.16342e-05 sec) per iteration: 0.0952506 sec for 3011 iterations mean: 30.966416 usec stddev: 0.346269 usec relative stddev: 1.12% RESULT TestCatchingFault: x86-64_with_irt_nnacl_newlib_static= {30.966416, 0.346269} us Measuring clock cycles: first runs (cycles): 102030 85029 82548 83484 115356 155298 133707 129099 131115 98940 ... slowest runs (cycles): ... 102600 103068 103098 110874 111423 115356 129099 131115 133707 155298 min: 80817 cycles q1: 81999 cycles median: 82653 cycles q3: 83484 cycles max: 155298 cycles RESULT TestCatchingFault_CycleCount: x86-64_with_irt_nnacl_newlib_static= {82653, 1485} count Apparent clock speed: 2669 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 113.590 usec (0.00011359 sec) per iteration: 0.011359 sec for 100 iterations 92.197 usec (9.21969e-05 sec) per iteration: 0.0811333 sec for 880 iterations 106.384 usec (0.000106384 sec) per iteration: 0.093618 sec for 880 iterations 101.737 usec (0.000101737 sec) per iteration: 0.0895284 sec for 880 iterations 99.077 usec (9.9077e-05 sec) per iteration: 0.0871878 sec for 880 iterations 101.699 usec (0.000101699 sec) per iteration: 0.089495 sec for 880 iterations mean: 100.218742 usec stddev: 4.651024 usec relative stddev: 4.64% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_with_irt_nnacl_newlib_static= {100.218742, 4.651024} us Measuring clock cycles: first runs (cycles): 281613 275595 264672 253248 259272 280278 321960 306831 304851 308433 ... slowest runs (cycles): ... 321960 322155 325401 326163 326319 327603 327642 328833 331413 332589 min: 246288 cycles q1: 302178 cycles median: 307212 cycles q3: 312384 cycles max: 332589 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_with_irt_nnacl_newlib_static= {307212, 10206} count Apparent clock speed: 3065 MHz [ OK ] nacl_newlib.run_performance_test_irt (6710 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