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 = 1550153228, usec = 409216 } Thu Feb 14 06:07:08 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 [47865,2635494272:15:35:10.894747] Native Client module will be loaded at base address 0x00000abe00000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: gn_x86-64_with_irt_nnacl_newlib_static= {0.065270, 0.001370} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (860 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 (66330 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 [47871,2635494272:15:35:25.044224] Native Client module will be loaded at base address 0x0000154a00000000 TestNull: Measuring real time: 0.007 usec (7.37e-09 sec) per iteration: 7.37e-07 sec for 100 iterations 0.002 usec (2.303e-09 sec) per iteration: 2.303e-06 sec for 1000 iterations 0.002 usec (2.028e-09 sec) per iteration: 2.028e-05 sec for 10000 iterations 0.002 usec (1.98998e-09 sec) per iteration: 0.0981253 sec for 49309664 iterations 0.002 usec (2.0661e-09 sec) per iteration: 0.101879 sec for 49309664 iterations 0.002 usec (2.02598e-09 sec) per iteration: 0.0999005 sec for 49309664 iterations 0.002 usec (2.00932e-09 sec) per iteration: 0.0990787 sec for 49309664 iterations 0.002 usec (1.99144e-09 sec) per iteration: 0.0981972 sec for 49309664 iterations mean: 0.002017 usec stddev: 0.000028 usec relative stddev: 1.39% RESULT TestNull: gn_x86-64_with_irt_nnacl_newlib_static= {0.002017, 0.000028} us Measuring clock cycles: first runs (cycles): 219 24 24 24 21 24 21 24 21 24 ... slowest runs (cycles): ... 24 24 24 24 24 24 24 24 24 219 min: 18 cycles q1: 21 cycles median: 21 cycles q3: 24 cycles max: 219 cycles RESULT TestNull_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {21, 3} count Apparent clock speed: 10414 MHz TestNaClSyscall: Measuring real time: 0.162 usec (1.6161e-07 sec) per iteration: 1.6161e-05 sec for 100 iterations 0.158 usec (1.58471e-07 sec) per iteration: 0.0980579 sec for 618773 iterations 0.156 usec (1.55892e-07 sec) per iteration: 0.096462 sec for 618773 iterations 0.156 usec (1.55655e-07 sec) per iteration: 0.096315 sec for 618773 iterations 0.156 usec (1.55722e-07 sec) per iteration: 0.0963563 sec for 618773 iterations 0.161 usec (1.61165e-07 sec) per iteration: 0.0997246 sec for 618773 iterations mean: 0.157381 usec stddev: 0.002166 usec relative stddev: 1.38% RESULT TestNaClSyscall: gn_x86-64_with_irt_nnacl_newlib_static= {0.157381, 0.002166} us Measuring clock cycles: first runs (cycles): 1371 594 558 573 573 573 555 561 573 573 ... slowest runs (cycles): ... 576 576 576 576 576 576 576 594 1194 1371 min: 291 cycles q1: 567 cycles median: 573 cycles q3: 573 cycles max: 1371 cycles RESULT TestNaClSyscall_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {573, 6} count Apparent clock speed: 3641 MHz TestSetjmpLongjmp: Measuring real time: 0.017 usec (1.683e-08 sec) per iteration: 1.683e-06 sec for 100 iterations 0.012 usec (1.246e-08 sec) per iteration: 1.246e-05 sec for 1000 iterations 0.013 usec (1.25016e-08 sec) per iteration: 0.100334 sec for 8025682 iterations 0.012 usec (1.20578e-08 sec) per iteration: 0.0967718 sec for 8025682 iterations 0.012 usec (1.21114e-08 sec) per iteration: 0.0972023 sec for 8025682 iterations 0.012 usec (1.21113e-08 sec) per iteration: 0.0972014 sec for 8025682 iterations 0.012 usec (1.20696e-08 sec) per iteration: 0.0968668 sec for 8025682 iterations mean: 0.012170 usec stddev: 0.000167 usec relative stddev: 1.37% RESULT TestSetjmpLongjmp: gn_x86-64_with_irt_nnacl_newlib_static= {0.012170, 0.000167} us Measuring clock cycles: first runs (cycles): 459 24 36 33 33 24 24 24 21 33 ... slowest runs (cycles): ... 33 33 33 33 33 33 33 33 36 459 min: 21 cycles q1: 24 cycles median: 30 cycles q3: 33 cycles max: 459 cycles RESULT TestSetjmpLongjmp_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {30, 9} count Apparent clock speed: 2465 MHz TestClockGetTime: Measuring real time: 0.164 usec (1.6396e-07 sec) per iteration: 1.6396e-05 sec for 100 iterations 0.162 usec (1.62339e-07 sec) per iteration: 0.0990112 sec for 609904 iterations 0.157 usec (1.56854e-07 sec) per iteration: 0.0956658 sec for 609904 iterations 0.161 usec (1.60875e-07 sec) per iteration: 0.0981185 sec for 609904 iterations 0.164 usec (1.64366e-07 sec) per iteration: 0.100248 sec for 609904 iterations 0.168 usec (1.67939e-07 sec) per iteration: 0.102427 sec for 609904 iterations mean: 0.162475 usec stddev: 0.003677 usec relative stddev: 2.26% RESULT TestClockGetTime: gn_x86-64_with_irt_nnacl_newlib_static= {0.162475, 0.003677} us Measuring clock cycles: first runs (cycles): 1788 441 447 435 441 438 441 435 444 441 ... slowest runs (cycles): ... 450 450 450 450 450 453 453 456 456 1788 min: 435 cycles q1: 441 cycles median: 441 cycles q3: 444 cycles max: 1788 cycles RESULT TestClockGetTime_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {441, 3} count Apparent clock speed: 2714 MHz TestTlsVariable: Measuring real time: 0.011 usec (1.069e-08 sec) per iteration: 1.069e-06 sec for 100 iterations 0.006 usec (6.303e-09 sec) per iteration: 6.303e-06 sec for 1000 iterations 0.006 usec (6.0324e-09 sec) per iteration: 6.0324e-05 sec for 10000 iterations 0.006 usec (6.08418e-09 sec) per iteration: 0.100858 sec for 16577150 iterations 0.006 usec (5.98964e-09 sec) per iteration: 0.0992912 sec for 16577150 iterations 0.006 usec (6.37264e-09 sec) per iteration: 0.10564 sec for 16577150 iterations 0.006 usec (6.02345e-09 sec) per iteration: 0.0998517 sec for 16577150 iterations 0.006 usec (5.99312e-09 sec) per iteration: 0.0993489 sec for 16577150 iterations mean: 0.006093 usec stddev: 0.000144 usec relative stddev: 2.36% RESULT TestTlsVariable: gn_x86-64_with_irt_nnacl_newlib_static= {0.006093, 0.000144} us Measuring clock cycles: first runs (cycles): 309 78 18 21 24 21 21 21 21 30 ... slowest runs (cycles): ... 30 30 30 30 30 30 30 30 78 309 min: 18 cycles q1: 21 cycles median: 21 cycles q3: 30 cycles max: 309 cycles RESULT TestTlsVariable_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {21, 9} count Apparent clock speed: 3447 MHz TestMmapAnonymous: Measuring real time: 8.819 usec (8.81902e-06 sec) per iteration: 0.000881902 sec for 100 iterations 8.583 usec (8.58318e-06 sec) per iteration: 0.0973247 sec for 11339 iterations 8.579 usec (8.57921e-06 sec) per iteration: 0.0972797 sec for 11339 iterations 8.465 usec (8.46516e-06 sec) per iteration: 0.0959865 sec for 11339 iterations 8.585 usec (8.58532e-06 sec) per iteration: 0.097349 sec for 11339 iterations 8.816 usec (8.81603e-06 sec) per iteration: 0.099965 sec for 11339 iterations mean: 8.605781 usec stddev: 0.114554 usec relative stddev: 1.33% RESULT TestMmapAnonymous: gn_x86-64_with_irt_nnacl_newlib_static= {8.605781, 0.114554} us Measuring clock cycles: first runs (cycles): 35052 24369 23946 23592 23454 23322 23403 23400 23271 23247 ... slowest runs (cycles): ... 24567 28515 29370 33153 33720 35052 38724 39606 40071 43803 min: 23106 cycles q1: 23208 cycles median: 23349 cycles q3: 23646 cycles max: 43803 cycles RESULT TestMmapAnonymous_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {23349, 438} count Apparent clock speed: 2713 MHz TestAtomicIncrement: Measuring real time: 0.014 usec (1.39e-08 sec) per iteration: 1.39e-06 sec for 100 iterations 0.010 usec (9.947e-09 sec) per iteration: 9.947e-06 sec for 1000 iterations 0.010 usec (9.6927e-09 sec) per iteration: 9.6927e-05 sec for 10000 iterations 0.010 usec (9.83508e-09 sec) per iteration: 0.101469 sec for 10317042 iterations 0.010 usec (9.95081e-09 sec) per iteration: 0.102663 sec for 10317042 iterations 0.010 usec (9.62035e-09 sec) per iteration: 0.0992535 sec for 10317042 iterations 0.010 usec (9.67682e-09 sec) per iteration: 0.0998362 sec for 10317042 iterations 0.010 usec (9.75115e-09 sec) per iteration: 0.100603 sec for 10317042 iterations mean: 0.009767 usec stddev: 0.000117 usec relative stddev: 1.20% RESULT TestAtomicIncrement: gn_x86-64_with_irt_nnacl_newlib_static= {0.009767, 0.000117} us Measuring clock cycles: first runs (cycles): 315 51 51 48 42 51 51 51 51 51 ... slowest runs (cycles): ... 51 51 51 51 51 51 51 51 51 315 min: 42 cycles q1: 51 cycles median: 51 cycles q3: 51 cycles max: 315 cycles RESULT TestAtomicIncrement_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {51, 0} count Apparent clock speed: 5222 MHz TestUncontendedMutexLock: Measuring real time: 0.028 usec (2.83e-08 sec) per iteration: 2.83e-06 sec for 100 iterations 0.023 usec (2.3295e-08 sec) per iteration: 2.3295e-05 sec for 1000 iterations 0.023 usec (2.33103e-08 sec) per iteration: 0.100066 sec for 4292766 iterations 0.023 usec (2.3072e-08 sec) per iteration: 0.0990426 sec for 4292766 iterations 0.023 usec (2.30224e-08 sec) per iteration: 0.0988297 sec for 4292766 iterations 0.023 usec (2.31896e-08 sec) per iteration: 0.0995474 sec for 4292766 iterations 0.023 usec (2.32116e-08 sec) per iteration: 0.0996421 sec for 4292766 iterations mean: 0.023161 usec stddev: 0.000103 usec relative stddev: 0.44% RESULT TestUncontendedMutexLock: gn_x86-64_with_irt_nnacl_newlib_static= {0.023161, 0.000103} us Measuring clock cycles: first runs (cycles): 483 111 78 81 78 81 78 81 78 81 ... slowest runs (cycles): ... 90 90 90 90 90 90 90 90 111 483 min: 78 cycles q1: 78 cycles median: 87 cycles q3: 90 cycles max: 483 cycles RESULT TestUncontendedMutexLock_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {87, 12} count Apparent clock speed: 3756 MHz TestCondvarSignalNoOp: Measuring real time: 0.261 usec (2.6086e-07 sec) per iteration: 2.6086e-05 sec for 100 iterations 0.159 usec (1.586e-07 sec) per iteration: 0.060799 sec for 383347 iterations 0.159 usec (1.58903e-07 sec) per iteration: 0.0609151 sec for 383347 iterations 0.159 usec (1.59109e-07 sec) per iteration: 0.0609941 sec for 383347 iterations 0.159 usec (1.59101e-07 sec) per iteration: 0.0609909 sec for 383347 iterations 0.160 usec (1.59861e-07 sec) per iteration: 0.0612824 sec for 383347 iterations mean: 0.159115 usec stddev: 0.000416 usec relative stddev: 0.26% RESULT TestCondvarSignalNoOp: gn_x86-64_with_irt_nnacl_newlib_static= {0.159115, 0.000416} us Measuring clock cycles: first runs (cycles): 1338 438 432 435 438 432 432 432 432 432 ... slowest runs (cycles): ... 432 432 432 432 435 435 435 438 438 1338 min: 420 cycles q1: 432 cycles median: 432 cycles q3: 432 cycles max: 1338 cycles RESULT TestCondvarSignalNoOp_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {432, 0} count Apparent clock speed: 2715 MHz TestThreadCreateAndJoin: Measuring real time: 66.399 usec (6.63986e-05 sec) per iteration: 0.00663986 sec for 100 iterations 65.737 usec (6.57372e-05 sec) per iteration: 0.0990002 sec for 1506 iterations 64.282 usec (6.42816e-05 sec) per iteration: 0.0968082 sec for 1506 iterations 66.294 usec (6.62936e-05 sec) per iteration: 0.0998382 sec for 1506 iterations 66.409 usec (6.64092e-05 sec) per iteration: 0.100012 sec for 1506 iterations 64.397 usec (6.43975e-05 sec) per iteration: 0.0969826 sec for 1506 iterations mean: 65.423822 usec stddev: 0.914718 usec relative stddev: 1.40% RESULT TestThreadCreateAndJoin: gn_x86-64_with_irt_nnacl_newlib_static= {65.423822, 0.914718} us Measuring clock cycles: first runs (cycles): 177282 177837 244218 206106 197553 194514 180216 178149 180051 176877 ... slowest runs (cycles): ... 179361 179619 180051 180216 180786 194514 197553 206106 243114 244218 min: 170655 cycles q1: 173793 cycles median: 175602 cycles q3: 177399 cycles max: 244218 cycles RESULT TestThreadCreateAndJoin_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {175602, 3606} count Apparent clock speed: 2684 MHz TestThreadWakeup: Measuring real time: 31.454 usec (3.14544e-05 sec) per iteration: 0.00314544 sec for 100 iterations 30.808 usec (3.08083e-05 sec) per iteration: 0.0979397 sec for 3179 iterations 30.976 usec (3.09763e-05 sec) per iteration: 0.0984737 sec for 3179 iterations 30.760 usec (3.07604e-05 sec) per iteration: 0.0977873 sec for 3179 iterations 30.674 usec (3.06744e-05 sec) per iteration: 0.0975141 sec for 3179 iterations 31.197 usec (3.11974e-05 sec) per iteration: 0.0991766 sec for 3179 iterations mean: 30.883379 usec stddev: 0.185294 usec relative stddev: 0.60% RESULT TestThreadWakeup: gn_x86-64_with_irt_nnacl_newlib_static= {30.883379, 0.185294} us Measuring clock cycles: first runs (cycles): 82812 83877 81741 120522 130383 78975 80709 80628 103740 84126 ... slowest runs (cycles): ... 84126 84435 84438 86613 96174 103740 108144 120522 127182 130383 min: 78975 cycles q1: 81021 cycles median: 81840 cycles q3: 82797 cycles max: 130383 cycles RESULT TestThreadWakeup_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {81840, 1776} count Apparent clock speed: 2650 MHz TestCatchingFault: Measuring real time: 33.507 usec (3.35066e-05 sec) per iteration: 0.00335067 sec for 100 iterations 30.851 usec (3.0851e-05 sec) per iteration: 0.0920593 sec for 2984 iterations 31.190 usec (3.11899e-05 sec) per iteration: 0.0930707 sec for 2984 iterations 31.304 usec (3.13042e-05 sec) per iteration: 0.0934117 sec for 2984 iterations 31.286 usec (3.12858e-05 sec) per iteration: 0.0933567 sec for 2984 iterations 31.308 usec (3.13077e-05 sec) per iteration: 0.0934223 sec for 2984 iterations mean: 31.187721 usec stddev: 0.173779 usec relative stddev: 0.56% RESULT TestCatchingFault: gn_x86-64_with_irt_nnacl_newlib_static= {31.187721, 0.173779} us Measuring clock cycles: first runs (cycles): 106356 87252 86055 85728 131076 112395 93582 121884 141030 108033 ... slowest runs (cycles): ... 106356 106746 108033 109029 110328 112395 117993 121884 131076 141030 min: 81312 cycles q1: 82623 cycles median: 83277 cycles q3: 85158 cycles max: 141030 cycles RESULT TestCatchingFault_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {83277, 2535} count Apparent clock speed: 2670 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 68.283 usec (6.82826e-05 sec) per iteration: 0.00682826 sec for 100 iterations 65.396 usec (6.5396e-05 sec) per iteration: 0.0957397 sec for 1464 iterations 64.973 usec (6.49727e-05 sec) per iteration: 0.09512 sec for 1464 iterations 67.258 usec (6.7258e-05 sec) per iteration: 0.0984657 sec for 1464 iterations 64.747 usec (6.47466e-05 sec) per iteration: 0.094789 sec for 1464 iterations 64.807 usec (6.48065e-05 sec) per iteration: 0.0948767 sec for 1464 iterations mean: 65.435950 usec stddev: 0.938870 usec relative stddev: 1.43% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: gn_x86-64_with_irt_nnacl_newlib_static= {65.435950, 0.938870} us Measuring clock cycles: first runs (cycles): 176883 180423 205515 197793 215286 201015 213612 170640 182235 175032 ... slowest runs (cycles): ... 187125 187998 189063 193104 197793 198525 201015 205515 213612 215286 min: 162663 cycles q1: 172065 cycles median: 173418 cycles q3: 175962 cycles max: 215286 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {173418, 3897} count Apparent clock speed: 2650 MHz [ OK ] nacl_newlib.run_performance_test_irt (6580 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