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 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,opt-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 = 1547763288, usec = 570436 } Thu Jan 17 14:14:48 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: opt-mac TARGET_ROOT: /b/s/w/ir/kitchen-workdir/native_client/scons-out/opt-mac-x86-64 OBJ_ROOT: /b/s/w/ir/kitchen-workdir/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/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 -DNDEBUG -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 [52743,2716697472:17:41:00.281549] Native Client module will be loaded at base address 0x0000781e00000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: gn_x86-64_with_irt_nnacl_newlib_static= {0.070100, 0.012990} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (899 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 (66030 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 [52749,2716697472:17:41:14.363989] Native Client module will be loaded at base address 0x0000469c00000000 TestNull: Measuring real time: 0.007 usec (7.39e-09 sec) per iteration: 7.39e-07 sec for 100 iterations 0.002 usec (2.285e-09 sec) per iteration: 2.285e-06 sec for 1000 iterations 0.002 usec (2.0276e-09 sec) per iteration: 2.0276e-05 sec for 10000 iterations 0.002 usec (2.04468e-09 sec) per iteration: 0.100842 sec for 49319392 iterations 0.002 usec (1.93982e-09 sec) per iteration: 0.095671 sec for 49319392 iterations 0.002 usec (1.99852e-09 sec) per iteration: 0.0985659 sec for 49319392 iterations 0.002 usec (1.91319e-09 sec) per iteration: 0.0943571 sec for 49319392 iterations 0.002 usec (1.97825e-09 sec) per iteration: 0.0975663 sec for 49319392 iterations mean: 0.001975 usec stddev: 0.000046 usec relative stddev: 2.32% RESULT TestNull: gn_x86-64_with_irt_nnacl_newlib_static= {0.001975, 0.000046} us Measuring clock cycles: first runs (cycles): 270 18 21 21 36 21 21 21 21 21 ... slowest runs (cycles): ... 36 36 36 36 36 36 36 36 36 270 min: 18 cycles q1: 21 cycles median: 21 cycles q3: 21 cycles max: 270 cycles RESULT TestNull_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {21, 0} count Apparent clock speed: 10633 MHz TestNaClSyscall: Measuring real time: 0.298 usec (2.9787e-07 sec) per iteration: 2.9787e-05 sec for 100 iterations 0.150 usec (1.49816e-07 sec) per iteration: 0.0502955 sec for 335716 iterations 0.148 usec (1.48032e-07 sec) per iteration: 0.0496968 sec for 335716 iterations 0.147 usec (1.47011e-07 sec) per iteration: 0.0493539 sec for 335716 iterations 0.168 usec (1.68086e-07 sec) per iteration: 0.056429 sec for 335716 iterations 0.151 usec (1.51314e-07 sec) per iteration: 0.0507985 sec for 335716 iterations mean: 0.152852 usec stddev: 0.007759 usec relative stddev: 5.08% RESULT TestNaClSyscall: gn_x86-64_with_irt_nnacl_newlib_static= {0.152852, 0.007759} us Measuring clock cycles: first runs (cycles): 738 303 294 294 294 294 279 279 279 279 ... slowest runs (cycles): ... 294 294 294 294 294 294 294 294 303 738 min: 279 cycles q1: 279 cycles median: 294 cycles q3: 294 cycles max: 738 cycles RESULT TestNaClSyscall_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {294, 15} count Apparent clock speed: 1923 MHz TestSetjmpLongjmp: Measuring real time: 0.017 usec (1.668e-08 sec) per iteration: 1.668e-06 sec for 100 iterations 0.012 usec (1.2463e-08 sec) per iteration: 1.2463e-05 sec for 1000 iterations 0.012 usec (1.19149e-08 sec) per iteration: 0.0956022 sec for 8023750 iterations 0.011 usec (1.13548e-08 sec) per iteration: 0.0911078 sec for 8023750 iterations 0.011 usec (1.1388e-08 sec) per iteration: 0.0913743 sec for 8023750 iterations 0.012 usec (1.22624e-08 sec) per iteration: 0.0983905 sec for 8023750 iterations 0.012 usec (1.17118e-08 sec) per iteration: 0.0939726 sec for 8023750 iterations mean: 0.011726 usec stddev: 0.000339 usec relative stddev: 2.89% RESULT TestSetjmpLongjmp: gn_x86-64_with_irt_nnacl_newlib_static= {0.011726, 0.000339} us Measuring clock cycles: first runs (cycles): 426 54 27 27 27 27 30 27 30 27 ... slowest runs (cycles): ... 30 30 30 30 33 33 33 33 54 426 min: 21 cycles q1: 27 cycles median: 27 cycles q3: 30 cycles max: 426 cycles RESULT TestSetjmpLongjmp_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {27, 3} count Apparent clock speed: 2303 MHz TestClockGetTime: Measuring real time: 0.164 usec (1.6396e-07 sec) per iteration: 1.6396e-05 sec for 100 iterations 0.154 usec (1.54e-07 sec) per iteration: 0.0939252 sec for 609904 iterations 0.163 usec (1.62894e-07 sec) per iteration: 0.0993495 sec for 609904 iterations 0.165 usec (1.64725e-07 sec) per iteration: 0.100466 sec for 609904 iterations 0.173 usec (1.73303e-07 sec) per iteration: 0.105698 sec for 609904 iterations 0.161 usec (1.60613e-07 sec) per iteration: 0.0979583 sec for 609904 iterations mean: 0.163107 usec stddev: 0.006258 usec relative stddev: 3.84% RESULT TestClockGetTime: gn_x86-64_with_irt_nnacl_newlib_static= {0.163107, 0.006258} us Measuring clock cycles: first runs (cycles): 1548 393 387 387 387 390 387 387 387 387 ... slowest runs (cycles): ... 393 393 393 393 393 393 393 393 396 1548 min: 384 cycles q1: 387 cycles median: 387 cycles q3: 390 cycles max: 1548 cycles RESULT TestClockGetTime_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {387, 3} count Apparent clock speed: 2373 MHz TestTlsVariable: Measuring real time: 0.010 usec (9.64e-09 sec) per iteration: 9.64e-07 sec for 100 iterations 0.006 usec (6.085e-09 sec) per iteration: 6.085e-06 sec for 1000 iterations 0.007 usec (7.4797e-09 sec) per iteration: 7.4797e-05 sec for 10000 iterations 0.006 usec (5.82917e-09 sec) per iteration: 0.0779332 sec for 13369520 iterations 0.006 usec (5.80646e-09 sec) per iteration: 0.0776296 sec for 13369520 iterations 0.006 usec (5.71078e-09 sec) per iteration: 0.0763504 sec for 13369520 iterations 0.006 usec (5.79898e-09 sec) per iteration: 0.0775296 sec for 13369520 iterations 0.006 usec (6.02474e-09 sec) per iteration: 0.0805479 sec for 13369520 iterations mean: 0.005834 usec stddev: 0.000104 usec relative stddev: 1.77% RESULT TestTlsVariable: gn_x86-64_with_irt_nnacl_newlib_static= {0.005834, 0.000104} us Measuring clock cycles: first runs (cycles): 300 66 24 24 24 24 21 27 24 24 ... slowest runs (cycles): ... 24 24 24 24 24 27 27 27 66 300 min: 21 cycles q1: 24 cycles median: 24 cycles q3: 24 cycles max: 300 cycles RESULT TestTlsVariable_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {24, 0} count Apparent clock speed: 4114 MHz TestMmapAnonymous: Measuring real time: 9.121 usec (9.12097e-06 sec) per iteration: 0.000912097 sec for 100 iterations 9.248 usec (9.24849e-06 sec) per iteration: 0.101391 sec for 10963 iterations 9.040 usec (9.03994e-06 sec) per iteration: 0.0991049 sec for 10963 iterations 8.647 usec (8.64731e-06 sec) per iteration: 0.0948005 sec for 10963 iterations 8.788 usec (8.7882e-06 sec) per iteration: 0.0963451 sec for 10963 iterations 8.671 usec (8.67115e-06 sec) per iteration: 0.0950618 sec for 10963 iterations mean: 8.879019 usec stddev: 0.231323 usec relative stddev: 2.61% RESULT TestMmapAnonymous: gn_x86-64_with_irt_nnacl_newlib_static= {8.879019, 0.231323} us Measuring clock cycles: first runs (cycles): 33798 25191 24852 24312 24501 24525 24093 24303 24201 24057 ... slowest runs (cycles): ... 24399 24408 24423 24426 24435 24501 24525 24852 25191 33798 min: 24057 cycles q1: 24219 cycles median: 24240 cycles q3: 24285 cycles max: 33798 cycles RESULT TestMmapAnonymous_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {24240, 66} count Apparent clock speed: 2730 MHz TestAtomicIncrement: Measuring real time: 0.014 usec (1.373e-08 sec) per iteration: 1.373e-06 sec for 100 iterations 0.010 usec (9.934e-09 sec) per iteration: 9.934e-06 sec for 1000 iterations 0.010 usec (9.6923e-09 sec) per iteration: 9.6923e-05 sec for 10000 iterations 0.009 usec (9.48788e-09 sec) per iteration: 0.0978909 sec for 10317468 iterations 0.010 usec (9.86045e-09 sec) per iteration: 0.101735 sec for 10317468 iterations 0.010 usec (9.67929e-09 sec) per iteration: 0.0998658 sec for 10317468 iterations 0.009 usec (9.01349e-09 sec) per iteration: 0.0929964 sec for 10317468 iterations 0.010 usec (1.01434e-08 sec) per iteration: 0.104654 sec for 10317468 iterations mean: 0.009637 usec stddev: 0.000379 usec relative stddev: 3.94% RESULT TestAtomicIncrement: gn_x86-64_with_irt_nnacl_newlib_static= {0.009637, 0.000379} us Measuring clock cycles: first runs (cycles): 249 39 42 51 39 51 39 42 48 42 ... slowest runs (cycles): ... 51 51 51 51 51 51 51 51 51 249 min: 39 cycles q1: 39 cycles median: 42 cycles q3: 48 cycles max: 249 cycles RESULT TestAtomicIncrement_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {42, 9} count Apparent clock speed: 4358 MHz TestUncontendedMutexLock: Measuring real time: 0.028 usec (2.807e-08 sec) per iteration: 2.807e-06 sec for 100 iterations 0.023 usec (2.3299e-08 sec) per iteration: 2.3299e-05 sec for 1000 iterations 0.023 usec (2.29541e-08 sec) per iteration: 0.0985198 sec for 4292029 iterations 0.023 usec (2.30505e-08 sec) per iteration: 0.0989333 sec for 4292029 iterations 0.022 usec (2.20077e-08 sec) per iteration: 0.0944576 sec for 4292029 iterations 0.022 usec (2.16948e-08 sec) per iteration: 0.0931146 sec for 4292029 iterations 0.023 usec (2.29253e-08 sec) per iteration: 0.0983959 sec for 4292029 iterations mean: 0.022526 usec stddev: 0.000562 usec relative stddev: 2.49% RESULT TestUncontendedMutexLock: gn_x86-64_with_irt_nnacl_newlib_static= {0.022526, 0.000562} us Measuring clock cycles: first runs (cycles): 420 111 78 90 105 90 90 90 90 81 ... slowest runs (cycles): ... 90 90 90 90 90 90 90 105 111 420 min: 78 cycles q1: 81 cycles median: 90 cycles q3: 90 cycles max: 420 cycles RESULT TestUncontendedMutexLock_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {90, 9} count Apparent clock speed: 3995 MHz TestCondvarSignalNoOp: Measuring real time: 0.255 usec (2.551e-07 sec) per iteration: 2.551e-05 sec for 100 iterations 0.154 usec (1.53543e-07 sec) per iteration: 0.0601892 sec for 392003 iterations 0.151 usec (1.50924e-07 sec) per iteration: 0.0591627 sec for 392003 iterations 0.152 usec (1.52067e-07 sec) per iteration: 0.0596109 sec for 392003 iterations 0.159 usec (1.58514e-07 sec) per iteration: 0.062138 sec for 392003 iterations 0.151 usec (1.5078e-07 sec) per iteration: 0.0591062 sec for 392003 iterations mean: 0.153166 usec stddev: 0.002852 usec relative stddev: 1.86% RESULT TestCondvarSignalNoOp: gn_x86-64_with_irt_nnacl_newlib_static= {0.153166, 0.002852} us Measuring clock cycles: first runs (cycles): 1359 411 408 408 405 405 405 405 405 405 ... slowest runs (cycles): ... 408 408 408 408 411 411 411 411 411 1359 min: 399 cycles q1: 405 cycles median: 405 cycles q3: 405 cycles max: 1359 cycles RESULT TestCondvarSignalNoOp_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {405, 0} count Apparent clock speed: 2644 MHz TestThreadCreateAndJoin: Measuring real time: 69.918 usec (6.99183e-05 sec) per iteration: 0.00699183 sec for 100 iterations 64.360 usec (6.43603e-05 sec) per iteration: 0.0920353 sec for 1430 iterations 67.266 usec (6.72659e-05 sec) per iteration: 0.0961902 sec for 1430 iterations 63.944 usec (6.39438e-05 sec) per iteration: 0.0914396 sec for 1430 iterations 65.161 usec (6.51615e-05 sec) per iteration: 0.0931809 sec for 1430 iterations 64.254 usec (6.4254e-05 sec) per iteration: 0.0918832 sec for 1430 iterations mean: 64.997098 usec stddev: 1.203456 usec relative stddev: 1.85% RESULT TestThreadCreateAndJoin: gn_x86-64_with_irt_nnacl_newlib_static= {64.997098, 1.203456} us Measuring clock cycles: first runs (cycles): 163134 234681 198159 203085 201384 210147 185535 172728 173130 172026 ... slowest runs (cycles): ... 178395 180144 185535 198159 201384 203085 208635 210147 227934 234681 min: 161340 cycles q1: 169521 cycles median: 170319 cycles q3: 172209 cycles max: 234681 cycles RESULT TestThreadCreateAndJoin_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {170319, 2688} count Apparent clock speed: 2620 MHz TestThreadWakeup: Measuring real time: 30.869 usec (3.08689e-05 sec) per iteration: 0.00308689 sec for 100 iterations 30.542 usec (3.0542e-05 sec) per iteration: 0.0989256 sec for 3239 iterations 30.738 usec (3.07375e-05 sec) per iteration: 0.0995588 sec for 3239 iterations 31.320 usec (3.13195e-05 sec) per iteration: 0.101444 sec for 3239 iterations 30.198 usec (3.01984e-05 sec) per iteration: 0.0978125 sec for 3239 iterations 30.276 usec (3.02763e-05 sec) per iteration: 0.098065 sec for 3239 iterations mean: 30.614748 usec stddev: 0.401235 usec relative stddev: 1.31% RESULT TestThreadWakeup: gn_x86-64_with_irt_nnacl_newlib_static= {30.614748, 0.401235} us Measuring clock cycles: first runs (cycles): 90195 85839 86802 87885 91788 84057 75375 84321 93147 81987 ... slowest runs (cycles): ... 87477 87885 88140 89298 90195 91788 92091 93147 93381 174588 min: 75375 cycles q1: 81882 cycles median: 82593 cycles q3: 83766 cycles max: 174588 cycles RESULT TestThreadWakeup_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {82593, 1884} count Apparent clock speed: 2698 MHz TestCatchingFault: Measuring real time: 33.912 usec (3.39123e-05 sec) per iteration: 0.00339123 sec for 100 iterations 30.447 usec (3.04473e-05 sec) per iteration: 0.0897587 sec for 2948 iterations 30.515 usec (3.05152e-05 sec) per iteration: 0.0899589 sec for 2948 iterations 30.714 usec (3.07141e-05 sec) per iteration: 0.0905451 sec for 2948 iterations 30.511 usec (3.05109e-05 sec) per iteration: 0.089946 sec for 2948 iterations 30.864 usec (3.08642e-05 sec) per iteration: 0.0909876 sec for 2948 iterations mean: 30.610332 usec stddev: 0.155383 usec relative stddev: 0.51% RESULT TestCatchingFault: gn_x86-64_with_irt_nnacl_newlib_static= {30.610332, 0.155383} us Measuring clock cycles: first runs (cycles): 104805 86058 107715 121566 156966 88236 85212 92808 126297 88077 ... slowest runs (cycles): ... 88236 92808 94350 95475 104805 107715 121566 126297 136728 156966 min: 81102 cycles q1: 82098 cycles median: 82461 cycles q3: 83088 cycles max: 156966 cycles RESULT TestCatchingFault_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {82461, 990} count Apparent clock speed: 2694 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 68.544 usec (6.85444e-05 sec) per iteration: 0.00685444 sec for 100 iterations 68.667 usec (6.86666e-05 sec) per iteration: 0.100116 sec for 1458 iterations 65.165 usec (6.51653e-05 sec) per iteration: 0.0950109 sec for 1458 iterations 64.056 usec (6.40559e-05 sec) per iteration: 0.0933935 sec for 1458 iterations 64.816 usec (6.48161e-05 sec) per iteration: 0.0945018 sec for 1458 iterations 66.133 usec (6.6133e-05 sec) per iteration: 0.0964219 sec for 1458 iterations mean: 65.767348 usec stddev: 1.595975 usec relative stddev: 2.43% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: gn_x86-64_with_irt_nnacl_newlib_static= {65.767348, 1.595975} us Measuring clock cycles: first runs (cycles): 177309 178917 187890 202977 187101 187467 192000 192714 179856 179973 ... slowest runs (cycles): ... 191886 192000 192714 195102 202977 203541 207903 211659 222129 233589 min: 158448 cycles q1: 162981 cycles median: 172212 cycles q3: 177558 cycles max: 233589 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {172212, 14577} count Apparent clock speed: 2619 MHz [ OK ] nacl_newlib.run_performance_test_irt (6130 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