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,opt-host,nacl_irt_test platform=x86-64 --clang large_tests_irt scons: Reading SConscript files ... ====================================================================== SCONS ARGS:['scons.py', '--verbose', '-k', '-j1', '--mode=nacl,opt-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 = 1552669774, usec = 152375 } Fri Mar 15 10:09:34 2019 ====================================================================== 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-64 OBJ_ROOT: /b/s/w/ir/k/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/k/native_client/toolchain/mac_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang ASPPCOM: /b/s/w/ir/k/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/k/native_client/scons-out/nacl-x86-64/include -I/b/s/w/ir/k -c -o UNKNOWN COMPILER NACL_BUILD_FAMILY: TRUSTED BUILD_TYPE: opt-mac TARGET_ROOT: /b/s/w/ir/k/native_client/scons-out/opt-mac-x86-64 OBJ_ROOT: /b/s/w/ir/k/native_client/scons-out/opt-mac-x86-64/obj BUILD_TYPE_DESCRIPTION: MacOS opt build BUILD_OS: BUILD_ARCHITECTURE: x86 BUILD_SUBARCH: 64 TARGET_OS: TARGET_ARCHITECTURE: x86 TARGET_SUBARCH: 64 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 -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 -DNDEBUG -I/b/s/w/ir/k -c -o UNKNOWN COMPILER NACL_BUILD_FAMILY: UNTRUSTED_IRT_TESTS BUILD_TYPE: nacl_irt_test TARGET_ROOT: /b/s/w/ir/k/native_client/scons-out/nacl_irt_test-x86-64 OBJ_ROOT: /b/s/w/ir/k/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/k/native_client/toolchain/mac_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang ASPPCOM: /b/s/w/ir/k/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/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-64 OBJ_ROOT: /b/s/w/ir/k/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/k/native_client/toolchain/mac_x86/pnacl_newlib_raw/bin/x86_64-nacl-clang ASPPCOM: /b/s/w/ir/k/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/k/native_client/scons-out/nacl_irt-x86-64/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_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/k/native_client/scons-out/nacl_irt_test-x86-64/test_results/benchmark_test.out scons-out/opt-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/opt-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 [26411,3051619200:13:11:11.466467] Native Client module will be loaded at base address 0x00006aaf00000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_with_irt_nnacl_newlib_static= {0.066870, 0.000620} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (870 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/k/native_client/scons-out/nacl_irt_test-x86-64/test_results/clock_cputime_test.out scons-out/opt-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/opt-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 (66450 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= 526.596 KB RESULT hello_world_size.out: ZIPPED_x86-64_with_irt_nnacl_newlib_static= 187.013 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/k/native_client/scons-out/nacl_irt_test-x86-64/test_results/performance_test.out scons-out/opt-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/opt-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 [26416,3051619200:13:11:25.782374] Native Client module will be loaded at base address 0x000056c100000000 TestNull: Measuring real time: 0.008 usec (7.62e-09 sec) per iteration: 7.62e-07 sec for 100 iterations 0.002 usec (2.31e-09 sec) per iteration: 2.31e-06 sec for 1000 iterations 0.002 usec (2.0284e-09 sec) per iteration: 2.0284e-05 sec for 10000 iterations 0.002 usec (2.04718e-09 sec) per iteration: 0.100926 sec for 49299940 iterations 0.002 usec (2.01846e-09 sec) per iteration: 0.0995102 sec for 49299940 iterations 0.002 usec (2.01424e-09 sec) per iteration: 0.0993019 sec for 49299940 iterations 0.002 usec (2.01358e-09 sec) per iteration: 0.0992692 sec for 49299940 iterations 0.002 usec (2.01838e-09 sec) per iteration: 0.099506 sec for 49299940 iterations mean: 0.002022 usec stddev: 0.000013 usec relative stddev: 0.62% RESULT TestNull: x86-64_with_irt_nnacl_newlib_static= {0.002022, 0.000013} us Measuring clock cycles: first runs (cycles): 483 27 30 30 30 27 21 39 36 33 ... slowest runs (cycles): ... 33 33 33 33 33 33 36 39 216 483 min: 18 cycles q1: 21 cycles median: 24 cycles q3: 30 cycles max: 483 cycles RESULT TestNull_CycleCount: x86-64_with_irt_nnacl_newlib_static= {24, 9} count Apparent clock speed: 11867 MHz TestNaClSyscall: Measuring real time: 0.165 usec (1.6472e-07 sec) per iteration: 1.6472e-05 sec for 100 iterations 0.153 usec (1.52598e-07 sec) per iteration: 0.0926406 sec for 607090 iterations 0.153 usec (1.52867e-07 sec) per iteration: 0.092804 sec for 607090 iterations 0.153 usec (1.52776e-07 sec) per iteration: 0.0927488 sec for 607090 iterations 0.152 usec (1.52303e-07 sec) per iteration: 0.0924617 sec for 607090 iterations 0.152 usec (1.52041e-07 sec) per iteration: 0.0923028 sec for 607090 iterations mean: 0.152517 usec stddev: 0.000306 usec relative stddev: 0.20% RESULT TestNaClSyscall: x86-64_with_irt_nnacl_newlib_static= {0.152517, 0.000306} us Measuring clock cycles: first runs (cycles): 699 306 303 300 300 297 291 288 291 288 ... slowest runs (cycles): ... 300 300 300 300 300 300 300 303 306 699 min: 288 cycles q1: 288 cycles median: 291 cycles q3: 297 cycles max: 699 cycles RESULT TestNaClSyscall_CycleCount: x86-64_with_irt_nnacl_newlib_static= {291, 9} count Apparent clock speed: 1908 MHz TestSetjmpLongjmp: Measuring real time: 0.017 usec (1.73e-08 sec) per iteration: 1.73e-06 sec for 100 iterations 0.012 usec (1.2452e-08 sec) per iteration: 1.2452e-05 sec for 1000 iterations 0.012 usec (1.21144e-08 sec) per iteration: 0.097289 sec for 8030838 iterations 0.012 usec (1.22382e-08 sec) per iteration: 0.0982833 sec for 8030838 iterations 0.012 usec (1.20868e-08 sec) per iteration: 0.0970668 sec for 8030838 iterations 0.012 usec (1.24108e-08 sec) per iteration: 0.0996695 sec for 8030838 iterations 0.012 usec (1.20802e-08 sec) per iteration: 0.0970142 sec for 8030838 iterations mean: 0.012186 usec stddev: 0.000126 usec relative stddev: 1.03% RESULT TestSetjmpLongjmp: x86-64_with_irt_nnacl_newlib_static= {0.012186, 0.000126} us Measuring clock cycles: first runs (cycles): 456 57 33 36 24 24 21 33 33 33 ... slowest runs (cycles): ... 33 33 33 33 33 33 33 36 57 456 min: 21 cycles q1: 24 cycles median: 30 cycles q3: 33 cycles max: 456 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {30, 9} count Apparent clock speed: 2462 MHz TestClockGetTime: Measuring real time: 0.166 usec (1.6594e-07 sec) per iteration: 1.6594e-05 sec for 100 iterations 0.165 usec (1.65286e-07 sec) per iteration: 0.0996056 sec for 602627 iterations 0.165 usec (1.64537e-07 sec) per iteration: 0.0991541 sec for 602627 iterations 0.164 usec (1.64399e-07 sec) per iteration: 0.0990715 sec for 602627 iterations 0.164 usec (1.64361e-07 sec) per iteration: 0.0990482 sec for 602627 iterations 0.163 usec (1.6317e-07 sec) per iteration: 0.0983309 sec for 602627 iterations mean: 0.164351 usec stddev: 0.000679 usec relative stddev: 0.41% RESULT TestClockGetTime: x86-64_with_irt_nnacl_newlib_static= {0.164351, 0.000679} us Measuring clock cycles: first runs (cycles): 1713 441 447 450 441 435 453 438 441 453 ... slowest runs (cycles): ... 453 453 453 453 453 453 453 456 456 1713 min: 435 cycles q1: 441 cycles median: 444 cycles q3: 450 cycles max: 1713 cycles RESULT TestClockGetTime_CycleCount: x86-64_with_irt_nnacl_newlib_static= {444, 9} count Apparent clock speed: 2702 MHz TestTlsVariable: Measuring real time: 0.012 usec (1.246e-08 sec) per iteration: 1.246e-06 sec for 100 iterations 0.006 usec (6.317e-09 sec) per iteration: 6.317e-06 sec for 1000 iterations 0.006 usec (6.033e-09 sec) per iteration: 6.033e-05 sec for 10000 iterations 0.006 usec (6.0501e-09 sec) per iteration: 0.100283 sec for 16575501 iterations 0.006 usec (6.02589e-09 sec) per iteration: 0.0998822 sec for 16575501 iterations 0.006 usec (6.03904e-09 sec) per iteration: 0.1001 sec for 16575501 iterations 0.006 usec (6.06845e-09 sec) per iteration: 0.100588 sec for 16575501 iterations 0.006 usec (6.02162e-09 sec) per iteration: 0.0998114 sec for 16575501 iterations mean: 0.006041 usec stddev: 0.000017 usec relative stddev: 0.28% RESULT TestTlsVariable: x86-64_with_irt_nnacl_newlib_static= {0.006041, 0.000017} us Measuring clock cycles: first runs (cycles): 330 69 33 33 30 30 30 30 30 21 ... slowest runs (cycles): ... 30 30 30 30 30 30 33 33 69 330 min: 21 cycles q1: 21 cycles median: 21 cycles q3: 30 cycles max: 330 cycles RESULT TestTlsVariable_CycleCount: x86-64_with_irt_nnacl_newlib_static= {21, 9} count Apparent clock speed: 3476 MHz TestMmapAnonymous: Measuring real time: 9.307 usec (9.30678e-06 sec) per iteration: 0.000930678 sec for 100 iterations 9.154 usec (9.15417e-06 sec) per iteration: 0.0983523 sec for 10744 iterations 9.161 usec (9.16068e-06 sec) per iteration: 0.0984224 sec for 10744 iterations 9.073 usec (9.07254e-06 sec) per iteration: 0.0974753 sec for 10744 iterations 9.077 usec (9.07699e-06 sec) per iteration: 0.0975232 sec for 10744 iterations 9.250 usec (9.24963e-06 sec) per iteration: 0.0993781 sec for 10744 iterations mean: 9.142801 usec stddev: 0.065009 usec relative stddev: 0.71% RESULT TestMmapAnonymous: x86-64_with_irt_nnacl_newlib_static= {9.142801, 0.065009} us Measuring clock cycles: first runs (cycles): 34842 26034 25194 24777 24609 24810 24795 24858 24957 24783 ... slowest runs (cycles): ... 25143 25143 25149 25164 25182 25185 25194 25194 26034 34842 min: 24237 cycles q1: 24543 cycles median: 24690 cycles q3: 24870 cycles max: 34842 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_with_irt_nnacl_newlib_static= {24690, 327} count Apparent clock speed: 2700 MHz TestAtomicIncrement: Measuring real time: 0.013 usec (1.338e-08 sec) per iteration: 1.338e-06 sec for 100 iterations 0.010 usec (9.921e-09 sec) per iteration: 9.921e-06 sec for 1000 iterations 0.010 usec (9.692e-09 sec) per iteration: 9.692e-05 sec for 10000 iterations 0.010 usec (9.73122e-09 sec) per iteration: 0.100405 sec for 10317787 iterations 0.010 usec (9.77562e-09 sec) per iteration: 0.100863 sec for 10317787 iterations 0.010 usec (9.76173e-09 sec) per iteration: 0.100719 sec for 10317787 iterations 0.010 usec (9.68791e-09 sec) per iteration: 0.0999578 sec for 10317787 iterations 0.010 usec (9.70089e-09 sec) per iteration: 0.100092 sec for 10317787 iterations mean: 0.009731 usec stddev: 0.000034 usec relative stddev: 0.35% RESULT TestAtomicIncrement: x86-64_with_irt_nnacl_newlib_static= {0.009731, 0.000034} us Measuring clock cycles: first runs (cycles): 372 54 57 51 51 57 63 54 57 54 ... slowest runs (cycles): ... 60 60 63 63 72 78 84 84 84 372 min: 39 cycles q1: 51 cycles median: 54 cycles q3: 57 cycles max: 372 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_with_irt_nnacl_newlib_static= {54, 6} count Apparent clock speed: 5549 MHz TestUncontendedMutexLock: Measuring real time: 0.034 usec (3.43e-08 sec) per iteration: 3.43e-06 sec for 100 iterations 0.027 usec (2.6713e-08 sec) per iteration: 2.6713e-05 sec for 1000 iterations 0.023 usec (2.32e-08 sec) per iteration: 0.0868492 sec for 3743495 iterations 0.023 usec (2.31511e-08 sec) per iteration: 0.0866659 sec for 3743495 iterations 0.023 usec (2.32191e-08 sec) per iteration: 0.0869206 sec for 3743495 iterations 0.023 usec (2.319e-08 sec) per iteration: 0.0868117 sec for 3743495 iterations 0.023 usec (2.31741e-08 sec) per iteration: 0.086752 sec for 3743495 iterations mean: 0.023187 usec stddev: 0.000023 usec relative stddev: 0.10% RESULT TestUncontendedMutexLock: x86-64_with_irt_nnacl_newlib_static= {0.023187, 0.000023} us Measuring clock cycles: first runs (cycles): 390 108 87 90 87 90 87 90 78 81 ... slowest runs (cycles): ... 90 90 90 90 90 90 90 90 108 390 min: 78 cycles q1: 81 cycles median: 87 cycles q3: 90 cycles max: 390 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_with_irt_nnacl_newlib_static= {87, 9} count Apparent clock speed: 3752 MHz TestCondvarSignalNoOp: Measuring real time: 0.283 usec (2.8348e-07 sec) per iteration: 2.8348e-05 sec for 100 iterations 0.164 usec (1.63942e-07 sec) per iteration: 0.0578318 sec for 352758 iterations 0.161 usec (1.60991e-07 sec) per iteration: 0.056791 sec for 352758 iterations 0.159 usec (1.58933e-07 sec) per iteration: 0.0560649 sec for 352758 iterations 0.160 usec (1.60029e-07 sec) per iteration: 0.0564516 sec for 352758 iterations 0.166 usec (1.66314e-07 sec) per iteration: 0.0586687 sec for 352758 iterations mean: 0.162042 usec stddev: 0.002709 usec relative stddev: 1.67% RESULT TestCondvarSignalNoOp: x86-64_with_irt_nnacl_newlib_static= {0.162042, 0.002709} us Measuring clock cycles: first runs (cycles): 1245 438 432 435 435 429 432 432 432 432 ... slowest runs (cycles): ... 432 432 432 432 432 432 435 435 438 1245 min: 420 cycles q1: 432 cycles median: 432 cycles q3: 432 cycles max: 1245 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {432, 0} count Apparent clock speed: 2666 MHz TestThreadCreateAndJoin: Measuring real time: 68.588 usec (6.85882e-05 sec) per iteration: 0.00685882 sec for 100 iterations 65.681 usec (6.56812e-05 sec) per iteration: 0.0956975 sec for 1457 iterations 67.406 usec (6.74056e-05 sec) per iteration: 0.09821 sec for 1457 iterations 66.356 usec (6.63559e-05 sec) per iteration: 0.0966806 sec for 1457 iterations 66.535 usec (6.65348e-05 sec) per iteration: 0.0969412 sec for 1457 iterations 66.394 usec (6.63939e-05 sec) per iteration: 0.0967358 sec for 1457 iterations mean: 66.474281 usec stddev: 0.551452 usec relative stddev: 0.83% RESULT TestThreadCreateAndJoin: x86-64_with_irt_nnacl_newlib_static= {66.474281, 0.551452} us Measuring clock cycles: first runs (cycles): 173838 163167 180303 201798 256029 225897 191802 181923 180633 216501 ... slowest runs (cycles): ... 211932 212772 216501 220071 225624 225897 227994 243525 256029 436860 min: 158193 cycles q1: 173205 cycles median: 177207 cycles q3: 196338 cycles max: 436860 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_with_irt_nnacl_newlib_static= {177207, 23133} count Apparent clock speed: 2666 MHz TestThreadWakeup: Measuring real time: 31.541 usec (3.15406e-05 sec) per iteration: 0.00315406 sec for 100 iterations 30.554 usec (3.05535e-05 sec) per iteration: 0.0968547 sec for 3170 iterations 30.594 usec (3.05936e-05 sec) per iteration: 0.0969817 sec for 3170 iterations 31.221 usec (3.1221e-05 sec) per iteration: 0.0989706 sec for 3170 iterations 30.683 usec (3.06828e-05 sec) per iteration: 0.0972646 sec for 3170 iterations 31.086 usec (3.1086e-05 sec) per iteration: 0.0985427 sec for 3170 iterations mean: 30.827401 usec stddev: 0.272905 usec relative stddev: 0.89% RESULT TestThreadWakeup: x86-64_with_irt_nnacl_newlib_static= {30.827401, 0.272905} us Measuring clock cycles: first runs (cycles): 91164 84213 88782 116577 116589 95910 95778 105744 81072 80808 ... slowest runs (cycles): ... 97677 101874 102618 102783 104403 105744 105933 116577 116589 120573 min: 72801 cycles q1: 81495 cycles median: 82785 cycles q3: 84564 cycles max: 120573 cycles RESULT TestThreadWakeup_CycleCount: x86-64_with_irt_nnacl_newlib_static= {82785, 3069} count Apparent clock speed: 2685 MHz TestCatchingFault: Measuring real time: 33.380 usec (3.33802e-05 sec) per iteration: 0.00333802 sec for 100 iterations 31.064 usec (3.10636e-05 sec) per iteration: 0.0930354 sec for 2995 iterations 30.867 usec (3.08669e-05 sec) per iteration: 0.0924464 sec for 2995 iterations 30.925 usec (3.09253e-05 sec) per iteration: 0.0926212 sec for 2995 iterations 31.415 usec (3.14154e-05 sec) per iteration: 0.0940893 sec for 2995 iterations 30.783 usec (3.07825e-05 sec) per iteration: 0.0921937 sec for 2995 iterations mean: 31.010751 usec stddev: 0.222103 usec relative stddev: 0.72% RESULT TestCatchingFault: x86-64_with_irt_nnacl_newlib_static= {31.010751, 0.222103} us Measuring clock cycles: first runs (cycles): 101637 86178 84135 117048 145749 139242 106293 138819 121983 94623 ... slowest runs (cycles): ... 94917 98223 101637 106293 117048 118755 121983 138819 139242 145749 min: 80691 cycles q1: 81738 cycles median: 82521 cycles q3: 83889 cycles max: 145749 cycles RESULT TestCatchingFault_CycleCount: x86-64_with_irt_nnacl_newlib_static= {82521, 2151} count Apparent clock speed: 2661 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 65.335 usec (6.53346e-05 sec) per iteration: 0.00653346 sec for 100 iterations 66.000 usec (6.60001e-05 sec) per iteration: 0.10098 sec for 1530 iterations 65.081 usec (6.50809e-05 sec) per iteration: 0.0995738 sec for 1530 iterations 65.169 usec (6.51689e-05 sec) per iteration: 0.0997085 sec for 1530 iterations 64.837 usec (6.48372e-05 sec) per iteration: 0.0992009 sec for 1530 iterations 68.451 usec (6.84513e-05 sec) per iteration: 0.10473 sec for 1530 iterations mean: 65.907698 usec stddev: 1.330699 usec relative stddev: 2.02% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_with_irt_nnacl_newlib_static= {65.907698, 1.330699} us Measuring clock cycles: first runs (cycles): 170793 167250 176181 189018 202833 211251 170598 201180 197115 183366 ... slowest runs (cycles): ... 177990 177993 178260 178698 183366 189018 197115 201180 202833 211251 min: 167250 cycles q1: 171750 cycles median: 173016 cycles q3: 175086 cycles max: 211251 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_with_irt_nnacl_newlib_static= {173016, 3336} count Apparent clock speed: 2625 MHz [ OK ] nacl_newlib.run_performance_test_irt (6480 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