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

Running command: /b/s/w/ir/cache/vpython/7c7ecd/bin/python scons.py --verbose -k -j1 --mode=nacl,dbg-host,nacl_irt_test platform=x86-64 --clang large_tests_irt force_sel_ldr=../out_64/sel_ldr force_irt=../out_64/irt_x64/irt_core.nexe perf_prefix=gn_ scons: Reading SConscript files ... ====================================================================== SCONS ARGS:['scons.py', '--verbose', '-k', '-j1', '--mode=nacl,dbg-host,nacl_irt_test', 'platform=x86-64', '--clang', 'large_tests_irt', 'force_sel_ldr=../out_64/sel_ldr', 'force_irt=../out_64/irt_x64/irt_core.nexe', 'perf_prefix=gn_'] ====================================================================== ====================================================================== Python Info: ====================================================================== darwin 2.7.14+chromium14 (6cc0aad35b-dirty:6cc0aad35b, Oct 3 2017, 17:23:56) [GCC 4.2.1 Compatible Apple LLVM 8.0.0 (clang-800.0.38)] ====================================================================== ENV: ====================================================================== PATH /b/s/w/ir/cache/vpython/7c7ecd/bin:/b/s/w/ir/cipd_bin_packages:/b/s/w/ir/cipd_bin_packages/bin:/b/s/cipd_cache/bin:/opt/local/bin:/opt/local/sbin:/usr/local/sbin:/usr/local/git/bin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/b/s/w/ir/kitchen-checkout/depot_tools ====================================================================== OS: ====================================================================== kern.ostype: Darwin kern.osrelease: 17.7.0 kern.osrevision: 199506 kern.version: Darwin Kernel Version 17.7.0: Thu Jun 21 22:53:14 PDT 2018; root:xnu-4570.71.2~1/RELEASE_X86_64 kern.posix1version: 200112 kern.osversion: 17G65 kern.osproductversion: 10.13.6 ====================================================================== CPU: ====================================================================== hw.machine: x86_64 hw.model: Macmini7,1 hw.ncpu: 4 hw.ncpu: 4 hw.activecpu: 4 hw.physicalcpu: 2 hw.physicalcpu_max: 2 hw.logicalcpu: 4 hw.logicalcpu_max: 4 hw.cputype: 7 hw.cpusubtype: 8 hw.cpu64bit_capable: 1 hw.cpufamily: 280134364 hw.cpufrequency: 3000000000 hw.cpufrequency_min: 3000000000 hw.cpufrequency_max: 3000000000 hw.cputhreadtype: 1 ====================================================================== RAM: ====================================================================== hw.memsize: 17179869184 ====================================================================== LOAD: ====================================================================== TBD ====================================================================== UPTIME: ====================================================================== kern.boottime: { sec = 1552674630, usec = 596376 } Fri Mar 15 11:30:30 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: dbg-mac TARGET_ROOT: /b/s/w/ir/k/native_client/scons-out/dbg-mac-x86-64 OBJ_ROOT: /b/s/w/ir/k/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/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 -D_DEBUG -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 gn_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 /b/s/w/ir/k/out_64/sel_ldr -B /b/s/w/ir/k/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/k/out_64/sel_ldr -B /b/s/w/ir/k/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 [25340,2962969472:12:36:47.863658] Native Client module will be loaded at base address 0x0000000c00000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: gn_x86-64_with_irt_nnacl_newlib_static= {0.132810, 0.001030} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (1330 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/k/native_client/scons-out/nacl_irt_test-x86-64/test_results/clock_cputime_test.out /b/s/w/ir/k/out_64/sel_ldr -B /b/s/w/ir/k/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/k/out_64/sel_ldr -B /b/s/w/ir/k/out_64/irt_x64/irt_core.nexe -- scons-out/nacl_irt_test-x86-64/obj/tests/clock/clock_test.nexe -c [ OK ] nacl_newlib.run_clock_cputime_test_irt (59170 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= 526.596 KB RESULT hello_world_size.out: ZIPPED_gn_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 gn_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 /b/s/w/ir/k/out_64/sel_ldr -e -B /b/s/w/ir/k/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/k/out_64/sel_ldr -e -B /b/s/w/ir/k/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 [25351,2962969472:12:37:05.459276] Native Client module will be loaded at base address 0x00001aa900000000 TestNull: Measuring real time: 0.009 usec (8.68e-09 sec) per iteration: 8.68e-07 sec for 100 iterations 0.002 usec (1.823e-09 sec) per iteration: 1.823e-06 sec for 1000 iterations 0.002 usec (1.5128e-09 sec) per iteration: 1.5128e-05 sec for 10000 iterations 0.001 usec (1.46571e-09 sec) per iteration: 0.0968873 sec for 66102591 iterations 0.001 usec (1.45739e-09 sec) per iteration: 0.0963373 sec for 66102591 iterations 0.001 usec (1.47775e-09 sec) per iteration: 0.0976829 sec for 66102591 iterations 0.001 usec (1.45201e-09 sec) per iteration: 0.0959818 sec for 66102591 iterations 0.001 usec (1.45044e-09 sec) per iteration: 0.0958777 sec for 66102591 iterations mean: 0.001461 usec stddev: 0.000010 usec relative stddev: 0.69% RESULT TestNull: gn_x86-64_with_irt_nnacl_newlib_static= {0.001461, 0.000010} us Measuring clock cycles: first runs (cycles): 126 39 27 18 18 18 27 18 18 27 ... slowest runs (cycles): ... 27 27 27 27 27 27 27 27 39 126 min: 18 cycles q1: 18 cycles median: 18 cycles q3: 27 cycles max: 126 cycles RESULT TestNull_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {18, 9} count Apparent clock speed: 12323 MHz TestNaClSyscall: Measuring real time: 0.362 usec (3.6183e-07 sec) per iteration: 3.6183e-05 sec for 100 iterations 0.153 usec (1.52726e-07 sec) per iteration: 0.0422091 sec for 276372 iterations 0.157 usec (1.57412e-07 sec) per iteration: 0.0435043 sec for 276372 iterations 0.151 usec (1.50738e-07 sec) per iteration: 0.0416599 sec for 276372 iterations 0.152 usec (1.5168e-07 sec) per iteration: 0.0419202 sec for 276372 iterations 0.153 usec (1.52797e-07 sec) per iteration: 0.0422289 sec for 276372 iterations mean: 0.153071 usec stddev: 0.002298 usec relative stddev: 1.50% RESULT TestNaClSyscall: gn_x86-64_with_irt_nnacl_newlib_static= {0.153071, 0.002298} us Measuring clock cycles: first runs (cycles): 687 537 528 501 474 483 465 498 465 486 ... slowest runs (cycles): ... 498 501 507 528 537 540 570 579 582 687 min: 462 cycles q1: 474 cycles median: 480 cycles q3: 483 cycles max: 687 cycles RESULT TestNaClSyscall_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {480, 9} count Apparent clock speed: 3136 MHz TestSetjmpLongjmp: Measuring real time: 0.015 usec (1.528e-08 sec) per iteration: 1.528e-06 sec for 100 iterations 0.011 usec (1.0567e-08 sec) per iteration: 1.0567e-05 sec for 1000 iterations 0.010 usec (9.95421e-09 sec) per iteration: 0.0942009 sec for 9463423 iterations 0.010 usec (9.87043e-09 sec) per iteration: 0.0934081 sec for 9463423 iterations 0.010 usec (9.76908e-09 sec) per iteration: 0.0924489 sec for 9463423 iterations 0.010 usec (9.79165e-09 sec) per iteration: 0.0926625 sec for 9463423 iterations 0.010 usec (9.82383e-09 sec) per iteration: 0.0929671 sec for 9463423 iterations mean: 0.009842 usec stddev: 0.000066 usec relative stddev: 0.67% RESULT TestSetjmpLongjmp: gn_x86-64_with_irt_nnacl_newlib_static= {0.009842, 0.000066} us Measuring clock cycles: first runs (cycles): 237 48 18 21 21 21 21 21 21 21 ... slowest runs (cycles): ... 30 30 30 30 30 30 30 30 48 237 min: 18 cycles q1: 21 cycles median: 21 cycles q3: 30 cycles max: 237 cycles RESULT TestSetjmpLongjmp_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {21, 9} count Apparent clock speed: 2134 MHz TestClockGetTime: Measuring real time: 0.426 usec (4.2624e-07 sec) per iteration: 4.2624e-05 sec for 100 iterations 0.271 usec (2.70769e-07 sec) per iteration: 0.0635248 sec for 234609 iterations 0.268 usec (2.67867e-07 sec) per iteration: 0.0628441 sec for 234609 iterations 0.267 usec (2.66516e-07 sec) per iteration: 0.0625269 sec for 234609 iterations 0.265 usec (2.64865e-07 sec) per iteration: 0.0621398 sec for 234609 iterations 0.269 usec (2.6929e-07 sec) per iteration: 0.0631779 sec for 234609 iterations mean: 0.267861 usec stddev: 0.002063 usec relative stddev: 0.77% RESULT TestClockGetTime: gn_x86-64_with_irt_nnacl_newlib_static= {0.267861, 0.002063} us Measuring clock cycles: first runs (cycles): 1461 855 810 813 792 810 846 867 807 786 ... slowest runs (cycles): ... 855 855 861 867 867 870 870 879 933 1461 min: 783 cycles q1: 789 cycles median: 795 cycles q3: 804 cycles max: 1461 cycles RESULT TestClockGetTime_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {795, 15} count Apparent clock speed: 2968 MHz TestTlsVariable: Measuring real time: 0.010 usec (1.015e-08 sec) per iteration: 1.015e-06 sec for 100 iterations 0.005 usec (5.061e-09 sec) per iteration: 5.061e-06 sec for 1000 iterations 0.008 usec (7.5246e-09 sec) per iteration: 7.5246e-05 sec for 10000 iterations 0.005 usec (4.71764e-09 sec) per iteration: 0.0626962 sec for 13289742 iterations 0.005 usec (4.64168e-09 sec) per iteration: 0.0616867 sec for 13289742 iterations 0.005 usec (4.62439e-09 sec) per iteration: 0.061457 sec for 13289742 iterations 0.005 usec (4.64471e-09 sec) per iteration: 0.061727 sec for 13289742 iterations 0.005 usec (4.69722e-09 sec) per iteration: 0.0624248 sec for 13289742 iterations mean: 0.004665 usec stddev: 0.000036 usec relative stddev: 0.77% RESULT TestTlsVariable: gn_x86-64_with_irt_nnacl_newlib_static= {0.004665, 0.000036} us Measuring clock cycles: first runs (cycles): 264 60 63 33 18 30 18 18 18 27 ... slowest runs (cycles): ... 27 27 27 27 27 30 33 60 63 264 min: 18 cycles q1: 18 cycles median: 18 cycles q3: 27 cycles max: 264 cycles RESULT TestTlsVariable_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {18, 9} count Apparent clock speed: 3858 MHz TestMmapAnonymous: Measuring real time: 6.854 usec (6.85419e-06 sec) per iteration: 0.000685419 sec for 100 iterations 5.244 usec (5.24424e-06 sec) per iteration: 0.0765081 sec for 14589 iterations 5.236 usec (5.23557e-06 sec) per iteration: 0.0763818 sec for 14589 iterations 5.186 usec (5.18632e-06 sec) per iteration: 0.0756633 sec for 14589 iterations 5.225 usec (5.22471e-06 sec) per iteration: 0.0762233 sec for 14589 iterations 5.153 usec (5.15294e-06 sec) per iteration: 0.0751763 sec for 14589 iterations mean: 5.208756 usec stddev: 0.034208 usec relative stddev: 0.66% RESULT TestMmapAnonymous: gn_x86-64_with_irt_nnacl_newlib_static= {5.208756, 0.034208} us Measuring clock cycles: first runs (cycles): 18918 16020 15600 15708 15459 15792 15552 19566 26700 25419 ... slowest runs (cycles): ... 25917 26133 26202 26316 26355 26400 26700 26724 26976 41265 min: 15045 cycles q1: 15156 cycles median: 15246 cycles q3: 15903 cycles max: 41265 cycles RESULT TestMmapAnonymous_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {15246, 747} count Apparent clock speed: 2927 MHz TestAtomicIncrement: Measuring real time: 0.016 usec (1.551e-08 sec) per iteration: 1.551e-06 sec for 100 iterations 0.008 usec (7.96e-09 sec) per iteration: 7.96e-06 sec for 1000 iterations 0.009 usec (9.0575e-09 sec) per iteration: 9.0575e-05 sec for 10000 iterations 0.008 usec (7.59949e-09 sec) per iteration: 0.0839028 sec for 11040574 iterations 0.008 usec (7.62763e-09 sec) per iteration: 0.0842135 sec for 11040574 iterations 0.008 usec (7.58738e-09 sec) per iteration: 0.083769 sec for 11040574 iterations 0.008 usec (7.7133e-09 sec) per iteration: 0.0851593 sec for 11040574 iterations 0.008 usec (7.64549e-09 sec) per iteration: 0.0844106 sec for 11040574 iterations mean: 0.007635 usec stddev: 0.000044 usec relative stddev: 0.58% RESULT TestAtomicIncrement: gn_x86-64_with_irt_nnacl_newlib_static= {0.007635, 0.000044} us Measuring clock cycles: first runs (cycles): 129 51 48 57 39 48 48 48 48 48 ... slowest runs (cycles): ... 48 48 48 51 54 54 54 57 57 129 min: 39 cycles q1: 48 cycles median: 48 cycles q3: 48 cycles max: 129 cycles RESULT TestAtomicIncrement_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {48, 0} count Apparent clock speed: 6287 MHz TestUncontendedMutexLock: Measuring real time: 0.029 usec (2.859e-08 sec) per iteration: 2.859e-06 sec for 100 iterations 0.023 usec (2.3003e-08 sec) per iteration: 2.3003e-05 sec for 1000 iterations 0.019 usec (1.8902e-08 sec) per iteration: 0.0821717 sec for 4347259 iterations 0.019 usec (1.9069e-08 sec) per iteration: 0.0828979 sec for 4347259 iterations 0.019 usec (1.90316e-08 sec) per iteration: 0.0827353 sec for 4347259 iterations 0.019 usec (1.88415e-08 sec) per iteration: 0.0819087 sec for 4347259 iterations 0.019 usec (1.89307e-08 sec) per iteration: 0.0822968 sec for 4347259 iterations mean: 0.018955 usec stddev: 0.000084 usec relative stddev: 0.44% RESULT TestUncontendedMutexLock: gn_x86-64_with_irt_nnacl_newlib_static= {0.018955, 0.000084} us Measuring clock cycles: first runs (cycles): 285 105 78 75 87 75 78 84 78 75 ... slowest runs (cycles): ... 87 87 87 87 87 87 87 87 105 285 min: 75 cycles q1: 75 cycles median: 78 cycles q3: 84 cycles max: 285 cycles RESULT TestUncontendedMutexLock_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {78, 9} count Apparent clock speed: 4115 MHz TestCondvarSignalNoOp: Measuring real time: 0.602 usec (6.0224e-07 sec) per iteration: 6.0224e-05 sec for 100 iterations 0.211 usec (2.11024e-07 sec) per iteration: 0.0350396 sec for 166046 iterations 0.212 usec (2.11728e-07 sec) per iteration: 0.0351566 sec for 166046 iterations 0.210 usec (2.09927e-07 sec) per iteration: 0.0348575 sec for 166046 iterations 0.205 usec (2.04758e-07 sec) per iteration: 0.0339992 sec for 166046 iterations 0.205 usec (2.04533e-07 sec) per iteration: 0.0339619 sec for 166046 iterations mean: 0.208394 usec stddev: 0.003115 usec relative stddev: 1.49% RESULT TestCondvarSignalNoOp: gn_x86-64_with_irt_nnacl_newlib_static= {0.208394, 0.003115} us Measuring clock cycles: first runs (cycles): 1023 687 675 732 633 615 621 621 624 618 ... slowest runs (cycles): ... 636 675 675 687 690 708 717 720 732 1023 min: 612 cycles q1: 618 cycles median: 621 cycles q3: 627 cycles max: 1023 cycles RESULT TestCondvarSignalNoOp_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {621, 9} count Apparent clock speed: 2980 MHz TestThreadCreateAndJoin: Measuring real time: 71.290 usec (7.12896e-05 sec) per iteration: 0.00712896 sec for 100 iterations 47.047 usec (4.70468e-05 sec) per iteration: 0.0659596 sec for 1402 iterations 46.689 usec (4.66891e-05 sec) per iteration: 0.0654581 sec for 1402 iterations 45.959 usec (4.59587e-05 sec) per iteration: 0.0644341 sec for 1402 iterations 45.871 usec (4.58715e-05 sec) per iteration: 0.0643118 sec for 1402 iterations 45.821 usec (4.58206e-05 sec) per iteration: 0.0642404 sec for 1402 iterations mean: 46.277328 usec stddev: 0.497281 usec relative stddev: 1.07% RESULT TestThreadCreateAndJoin: gn_x86-64_with_irt_nnacl_newlib_static= {46.277328, 0.497281} us Measuring clock cycles: first runs (cycles): 147345 196575 198531 179391 205653 267153 155025 145116 140340 143643 ... slowest runs (cycles): ... 176448 179391 185628 186930 191931 196575 198531 205653 267153 298320 min: 128496 cycles q1: 131238 cycles median: 136845 cycles q3: 139317 cycles max: 298320 cycles RESULT TestThreadCreateAndJoin_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {136845, 8079} count Apparent clock speed: 2957 MHz TestThreadWakeup: Measuring real time: 11.675 usec (1.16749e-05 sec) per iteration: 0.00116749 sec for 100 iterations 9.647 usec (9.64734e-06 sec) per iteration: 0.0826294 sec for 8565 iterations 9.747 usec (9.74671e-06 sec) per iteration: 0.0834806 sec for 8565 iterations 9.476 usec (9.4758e-06 sec) per iteration: 0.0811603 sec for 8565 iterations 9.391 usec (9.39085e-06 sec) per iteration: 0.0804326 sec for 8565 iterations 9.611 usec (9.61107e-06 sec) per iteration: 0.0823188 sec for 8565 iterations mean: 9.574354 usec stddev: 0.126307 usec relative stddev: 1.32% RESULT TestThreadWakeup: gn_x86-64_with_irt_nnacl_newlib_static= {9.574354, 0.126307} us Measuring clock cycles: first runs (cycles): 58152 54141 34452 114366 123861 52965 51867 56889 35547 39996 ... slowest runs (cycles): ... 51867 52557 52965 54141 56889 58152 70629 85995 114366 123861 min: 27495 cycles q1: 27759 cycles median: 27873 cycles q3: 28176 cycles max: 123861 cycles RESULT TestThreadWakeup_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {27873, 417} count Apparent clock speed: 2911 MHz TestCatchingFault: Measuring real time: 15.588 usec (1.55881e-05 sec) per iteration: 0.00155881 sec for 100 iterations 14.167 usec (1.41669e-05 sec) per iteration: 0.0908804 sec for 6415 iterations 14.169 usec (1.41692e-05 sec) per iteration: 0.0908952 sec for 6415 iterations 14.148 usec (1.41481e-05 sec) per iteration: 0.0907601 sec for 6415 iterations 14.171 usec (1.4171e-05 sec) per iteration: 0.0909071 sec for 6415 iterations 14.177 usec (1.41769e-05 sec) per iteration: 0.0909446 sec for 6415 iterations mean: 14.166405 usec stddev: 0.009727 usec relative stddev: 0.07% RESULT TestCatchingFault: gn_x86-64_with_irt_nnacl_newlib_static= {14.166405, 0.009727} us Measuring clock cycles: first runs (cycles): 63495 60426 97725 84249 106977 49434 58278 54921 68391 44217 ... slowest runs (cycles): ... 48387 49434 54921 58278 60426 63495 68391 84249 97725 106977 min: 41106 cycles q1: 42033 cycles median: 42252 cycles q3: 42429 cycles max: 106977 cycles RESULT TestCatchingFault_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {42252, 396} count Apparent clock speed: 2983 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 47.741 usec (4.77406e-05 sec) per iteration: 0.00477406 sec for 100 iterations 45.767 usec (4.57671e-05 sec) per iteration: 0.0958363 sec for 2094 iterations 45.741 usec (4.57413e-05 sec) per iteration: 0.0957822 sec for 2094 iterations 46.110 usec (4.61099e-05 sec) per iteration: 0.0965542 sec for 2094 iterations 49.694 usec (4.96938e-05 sec) per iteration: 0.104059 sec for 2094 iterations 46.059 usec (4.60594e-05 sec) per iteration: 0.0964483 sec for 2094 iterations mean: 46.674287 usec stddev: 1.517079 usec relative stddev: 3.25% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: gn_x86-64_with_irt_nnacl_newlib_static= {46.674287, 1.517079} us Measuring clock cycles: first runs (cycles): 138981 183990 192183 206694 218253 232578 154590 150222 143358 152580 ... slowest runs (cycles): ... 183990 183990 192183 201435 206694 218253 222387 222552 232578 245463 min: 128592 cycles q1: 131337 cycles median: 137526 cycles q3: 145761 cycles max: 245463 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {137526, 14424} count Apparent clock speed: 2947 MHz [ OK ] nacl_newlib.run_performance_test_irt (5570 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