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 = 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 x86-64_with_irt_nnacl_newlib_static --subarch 64 --arch x86 --capture_output 0 --output_stamp /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt_test-x86-64/test_results/benchmark_test.out scons-out/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 [41959,2635494272:15:29:22.616805] Native Client module will be loaded at base address 0x000042b800000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_with_irt_nnacl_newlib_static= {0.064980, 0.000700} 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 x86-64_with_irt_nnacl_newlib_static --subarch 64 --arch x86 --output_stamp /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt_test-x86-64/test_results/clock_cputime_test.out scons-out/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 (66229 ms) doSizeCheck(["scons-out/nacl_irt_test-x86-64/obj/tests/hello_world/hello_world_size.out"], ["scons-out/nacl_irt_test-x86-64/obj/tests/hello_world/hello_world.nexe"]) RESULT hello_world_size.out: x86-64_with_irt_nnacl_newlib_static= 527.127 KB RESULT hello_world_size.out: ZIPPED_x86-64_with_irt_nnacl_newlib_static= 186.967 KB /b/s/w/ir/cache/vpython/7c7ecd/bin/python tools/command_tester.py --name nacl_newlib.run_performance_test_irt --time_warning 10 --time_error 100 --perf_env_description x86-64_with_irt_nnacl_newlib_static --subarch 64 --arch x86 --capture_output 0 --output_stamp /b/s/w/ir/kitchen-workdir/native_client/scons-out/nacl_irt_test-x86-64/test_results/performance_test.out scons-out/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 [41965,2635494272:15:29:36.762728] Native Client module will be loaded at base address 0x000028e800000000 TestNull: Measuring real time: 0.008 usec (8.03e-09 sec) per iteration: 8.03e-07 sec for 100 iterations 0.002 usec (2.315e-09 sec) per iteration: 2.315e-06 sec for 1000 iterations 0.002 usec (2.0279e-09 sec) per iteration: 2.0279e-05 sec for 10000 iterations 0.002 usec (2.15166e-09 sec) per iteration: 0.106103 sec for 49312096 iterations 0.002 usec (1.99714e-09 sec) per iteration: 0.098483 sec for 49312096 iterations 0.002 usec (2.01102e-09 sec) per iteration: 0.0991675 sec for 49312096 iterations 0.002 usec (2.04697e-09 sec) per iteration: 0.10094 sec for 49312096 iterations 0.002 usec (2.22182e-09 sec) per iteration: 0.109562 sec for 49312096 iterations mean: 0.002086 usec stddev: 0.000087 usec relative stddev: 4.17% RESULT TestNull: x86-64_with_irt_nnacl_newlib_static= {0.002086, 0.000087} us Measuring clock cycles: first runs (cycles): 264 21 18 30 30 30 30 30 21 21 ... slowest runs (cycles): ... 30 30 30 30 30 30 30 30 30 264 min: 18 cycles q1: 21 cycles median: 30 cycles q3: 30 cycles max: 264 cycles RESULT TestNull_CycleCount: x86-64_with_irt_nnacl_newlib_static= {30, 9} count Apparent clock speed: 14384 MHz TestNaClSyscall: Measuring real time: 0.169 usec (1.685e-07 sec) per iteration: 1.685e-05 sec for 100 iterations 0.154 usec (1.54294e-07 sec) per iteration: 0.0915689 sec for 593471 iterations 0.158 usec (1.58203e-07 sec) per iteration: 0.0938891 sec for 593471 iterations 0.153 usec (1.53347e-07 sec) per iteration: 0.0910067 sec for 593471 iterations 0.154 usec (1.54117e-07 sec) per iteration: 0.0914642 sec for 593471 iterations 0.153 usec (1.53264e-07 sec) per iteration: 0.0909578 sec for 593471 iterations mean: 0.154645 usec stddev: 0.001825 usec relative stddev: 1.18% RESULT TestNaClSyscall: x86-64_with_irt_nnacl_newlib_static= {0.154645, 0.001825} us Measuring clock cycles: first runs (cycles): 681 312 297 300 300 297 288 288 288 291 ... slowest runs (cycles): ... 300 300 300 303 303 303 303 306 312 681 min: 288 cycles q1: 291 cycles median: 294 cycles q3: 300 cycles max: 681 cycles RESULT TestNaClSyscall_CycleCount: x86-64_with_irt_nnacl_newlib_static= {294, 9} count Apparent clock speed: 1901 MHz TestSetjmpLongjmp: Measuring real time: 0.016 usec (1.648e-08 sec) per iteration: 1.648e-06 sec for 100 iterations 0.012 usec (1.2449e-08 sec) per iteration: 1.2449e-05 sec for 1000 iterations 0.012 usec (1.21479e-08 sec) per iteration: 0.0975814 sec for 8032773 iterations 0.012 usec (1.22583e-08 sec) per iteration: 0.0984685 sec for 8032773 iterations 0.013 usec (1.2651e-08 sec) per iteration: 0.101622 sec for 8032773 iterations 0.012 usec (1.20806e-08 sec) per iteration: 0.0970405 sec for 8032773 iterations 0.012 usec (1.20507e-08 sec) per iteration: 0.0968007 sec for 8032773 iterations mean: 0.012238 usec stddev: 0.000219 usec relative stddev: 1.79% RESULT TestSetjmpLongjmp: x86-64_with_irt_nnacl_newlib_static= {0.012238, 0.000219} us Measuring clock cycles: first runs (cycles): 1035 144 72 72 72 81 81 72 81 72 ... slowest runs (cycles): ... 81 81 81 81 81 81 81 81 144 1035 min: 72 cycles q1: 72 cycles median: 81 cycles q3: 81 cycles max: 1035 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {81, 9} count Apparent clock speed: 6619 MHz TestClockGetTime: Measuring real time: 0.414 usec (4.1403e-07 sec) per iteration: 4.1403e-05 sec for 100 iterations 0.166 usec (1.65516e-07 sec) per iteration: 0.0399768 sec for 241528 iterations 0.163 usec (1.63441e-07 sec) per iteration: 0.0394756 sec for 241528 iterations 0.164 usec (1.63914e-07 sec) per iteration: 0.0395899 sec for 241528 iterations 0.164 usec (1.64208e-07 sec) per iteration: 0.0396607 sec for 241528 iterations 0.164 usec (1.64352e-07 sec) per iteration: 0.0396956 sec for 241528 iterations mean: 0.164286 usec stddev: 0.000689 usec relative stddev: 0.42% RESULT TestClockGetTime: x86-64_with_irt_nnacl_newlib_static= {0.164286, 0.000689} us Measuring clock cycles: first runs (cycles): 1821 429 435 429 429 426 426 426 432 426 ... slowest runs (cycles): ... 432 432 432 432 435 435 435 435 435 1821 min: 408 cycles q1: 426 cycles median: 426 cycles q3: 429 cycles max: 1821 cycles RESULT TestClockGetTime_CycleCount: x86-64_with_irt_nnacl_newlib_static= {426, 3} count Apparent clock speed: 2593 MHz TestTlsVariable: Measuring real time: 0.010 usec (9.58e-09 sec) per iteration: 9.58e-07 sec for 100 iterations 0.006 usec (6.302e-09 sec) per iteration: 6.302e-06 sec for 1000 iterations 0.006 usec (6.0319e-09 sec) per iteration: 6.0319e-05 sec for 10000 iterations 0.006 usec (6.06081e-09 sec) per iteration: 0.100479 sec for 16578524 iterations 0.006 usec (5.95769e-09 sec) per iteration: 0.0987697 sec for 16578524 iterations 0.006 usec (5.96293e-09 sec) per iteration: 0.0988565 sec for 16578524 iterations 0.006 usec (5.9816e-09 sec) per iteration: 0.0991661 sec for 16578524 iterations 0.006 usec (6.2727e-09 sec) per iteration: 0.103992 sec for 16578524 iterations mean: 0.006047 usec stddev: 0.000119 usec relative stddev: 1.96% RESULT TestTlsVariable: x86-64_with_irt_nnacl_newlib_static= {0.006047, 0.000119} us Measuring clock cycles: first runs (cycles): 342 66 21 21 21 21 21 30 30 30 ... slowest runs (cycles): ... 30 30 30 30 30 30 30 33 66 342 min: 21 cycles q1: 21 cycles median: 21 cycles q3: 30 cycles max: 342 cycles RESULT TestTlsVariable_CycleCount: x86-64_with_irt_nnacl_newlib_static= {21, 9} count Apparent clock speed: 3473 MHz TestMmapAnonymous: Measuring real time: 9.140 usec (9.14018e-06 sec) per iteration: 0.000914018 sec for 100 iterations 9.191 usec (9.19078e-06 sec) per iteration: 0.100547 sec for 10940 iterations 8.747 usec (8.74747e-06 sec) per iteration: 0.0956973 sec for 10940 iterations 8.745 usec (8.74531e-06 sec) per iteration: 0.0956737 sec for 10940 iterations 8.849 usec (8.84904e-06 sec) per iteration: 0.0968085 sec for 10940 iterations 9.041 usec (9.0411e-06 sec) per iteration: 0.0989096 sec for 10940 iterations mean: 8.914740 usec stddev: 0.175023 usec relative stddev: 1.96% RESULT TestMmapAnonymous: x86-64_with_irt_nnacl_newlib_static= {8.914740, 0.175023} us Measuring clock cycles: first runs (cycles): 33591 24489 24087 23958 24204 24171 23937 23991 23925 23979 ... slowest runs (cycles): ... 24177 24204 24231 24264 24294 24312 24489 24597 24693 33591 min: 23733 cycles q1: 23844 cycles median: 23910 cycles q3: 23994 cycles max: 33591 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_with_irt_nnacl_newlib_static= {23910, 150} count Apparent clock speed: 2682 MHz TestAtomicIncrement: Measuring real time: 0.015 usec (1.458e-08 sec) per iteration: 1.458e-06 sec for 100 iterations 0.010 usec (9.928e-09 sec) per iteration: 9.928e-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.6615e-09 sec) per iteration: 0.0996853 sec for 10317787 iterations 0.010 usec (9.7201e-09 sec) per iteration: 0.10029 sec for 10317787 iterations 0.010 usec (9.6479e-09 sec) per iteration: 0.099545 sec for 10317787 iterations 0.010 usec (9.62338e-09 sec) per iteration: 0.099292 sec for 10317787 iterations 0.010 usec (9.64973e-09 sec) per iteration: 0.0995639 sec for 10317787 iterations mean: 0.009661 usec stddev: 0.000032 usec relative stddev: 0.33% RESULT TestAtomicIncrement: x86-64_with_irt_nnacl_newlib_static= {0.009661, 0.000032} us Measuring clock cycles: first runs (cycles): 270 54 42 42 54 42 39 57 39 42 ... slowest runs (cycles): ... 57 57 57 57 57 57 57 57 57 270 min: 39 cycles q1: 39 cycles median: 42 cycles q3: 54 cycles max: 270 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_with_irt_nnacl_newlib_static= {42, 15} count Apparent clock speed: 4348 MHz TestUncontendedMutexLock: Measuring real time: 0.028 usec (2.85e-08 sec) per iteration: 2.85e-06 sec for 100 iterations 0.023 usec (2.3286e-08 sec) per iteration: 2.3286e-05 sec for 1000 iterations 0.023 usec (2.31197e-08 sec) per iteration: 0.0992857 sec for 4294425 iterations 0.023 usec (2.30371e-08 sec) per iteration: 0.0989311 sec for 4294425 iterations 0.023 usec (2.29796e-08 sec) per iteration: 0.0986842 sec for 4294425 iterations 0.023 usec (2.33653e-08 sec) per iteration: 0.100341 sec for 4294425 iterations 0.023 usec (2.29823e-08 sec) per iteration: 0.0986959 sec for 4294425 iterations mean: 0.023097 usec stddev: 0.000144 usec relative stddev: 0.62% RESULT TestUncontendedMutexLock: x86-64_with_irt_nnacl_newlib_static= {0.023097, 0.000144} us Measuring clock cycles: first runs (cycles): 462 108 90 87 90 87 90 87 90 78 ... slowest runs (cycles): ... 90 90 90 90 90 90 90 93 108 462 min: 78 cycles q1: 81 cycles median: 87 cycles q3: 90 cycles max: 462 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_with_irt_nnacl_newlib_static= {87, 9} count Apparent clock speed: 3767 MHz TestCondvarSignalNoOp: Measuring real time: 0.292 usec (2.921e-07 sec) per iteration: 2.921e-05 sec for 100 iterations 0.160 usec (1.59675e-07 sec) per iteration: 0.0546643 sec for 342348 iterations 0.157 usec (1.5679e-07 sec) per iteration: 0.0536769 sec for 342348 iterations 0.156 usec (1.55992e-07 sec) per iteration: 0.0534036 sec for 342348 iterations 0.157 usec (1.56872e-07 sec) per iteration: 0.0537048 sec for 342348 iterations 0.158 usec (1.57802e-07 sec) per iteration: 0.054023 sec for 342348 iterations mean: 0.157426 usec stddev: 0.001262 usec relative stddev: 0.80% RESULT TestCondvarSignalNoOp: x86-64_with_irt_nnacl_newlib_static= {0.157426, 0.001262} us Measuring clock cycles: first runs (cycles): 1146 423 420 420 420 420 420 423 405 420 ... slowest runs (cycles): ... 423 423 423 423 423 423 423 423 423 1146 min: 402 cycles q1: 420 cycles median: 420 cycles q3: 423 cycles max: 1146 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {420, 3} count Apparent clock speed: 2668 MHz TestThreadCreateAndJoin: Measuring real time: 73.770 usec (7.37702e-05 sec) per iteration: 0.00737702 sec for 100 iterations 67.029 usec (6.7029e-05 sec) per iteration: 0.0908242 sec for 1355 iterations 65.979 usec (6.59793e-05 sec) per iteration: 0.089402 sec for 1355 iterations 64.153 usec (6.41526e-05 sec) per iteration: 0.0869268 sec for 1355 iterations 64.832 usec (6.48319e-05 sec) per iteration: 0.0878472 sec for 1355 iterations 65.195 usec (6.51947e-05 sec) per iteration: 0.0883388 sec for 1355 iterations mean: 65.437498 usec stddev: 0.990257 usec relative stddev: 1.51% RESULT TestThreadCreateAndJoin: x86-64_with_irt_nnacl_newlib_static= {65.437498, 0.990257} us Measuring clock cycles: first runs (cycles): 167529 190398 197682 230055 191388 216900 181665 170547 168108 172539 ... slowest runs (cycles): ... 181665 190398 191007 191388 196599 197682 213252 216900 230055 234834 min: 167529 cycles q1: 171699 cycles median: 172710 cycles q3: 174732 cycles max: 234834 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_with_irt_nnacl_newlib_static= {172710, 3033} count Apparent clock speed: 2639 MHz TestThreadWakeup: Measuring real time: 31.368 usec (3.1368e-05 sec) per iteration: 0.0031368 sec for 100 iterations 30.892 usec (3.08916e-05 sec) per iteration: 0.0984516 sec for 3187 iterations 31.367 usec (3.13667e-05 sec) per iteration: 0.0999656 sec for 3187 iterations 30.813 usec (3.08132e-05 sec) per iteration: 0.0982017 sec for 3187 iterations 31.002 usec (3.10024e-05 sec) per iteration: 0.0988046 sec for 3187 iterations 31.024 usec (3.10235e-05 sec) per iteration: 0.0988719 sec for 3187 iterations mean: 31.019482 usec stddev: 0.189605 usec relative stddev: 0.61% RESULT TestThreadWakeup: x86-64_with_irt_nnacl_newlib_static= {31.019482, 0.189605} us Measuring clock cycles: first runs (cycles): 85740 89124 87936 88896 75399 85911 85260 103314 82824 83175 ... slowest runs (cycles): ... 87936 88896 89031 89124 89286 93399 93795 94950 101877 103314 min: 75399 cycles q1: 82347 cycles median: 83100 cycles q3: 83940 cycles max: 103314 cycles RESULT TestThreadWakeup_CycleCount: x86-64_with_irt_nnacl_newlib_static= {83100, 1593} count Apparent clock speed: 2679 MHz TestCatchingFault: Measuring real time: 33.148 usec (3.31483e-05 sec) per iteration: 0.00331483 sec for 100 iterations 31.738 usec (3.1738e-05 sec) per iteration: 0.095722 sec for 3016 iterations 31.315 usec (3.13151e-05 sec) per iteration: 0.0944462 sec for 3016 iterations 31.069 usec (3.10686e-05 sec) per iteration: 0.0937028 sec for 3016 iterations 31.616 usec (3.1616e-05 sec) per iteration: 0.0953539 sec for 3016 iterations 31.311 usec (3.1311e-05 sec) per iteration: 0.0944341 sec for 3016 iterations mean: 31.409742 usec stddev: 0.238936 usec relative stddev: 0.76% RESULT TestCatchingFault: x86-64_with_irt_nnacl_newlib_static= {31.409742, 0.238936} us Measuring clock cycles: first runs (cycles): 99960 85935 86847 104118 117090 111603 88914 98337 111300 125169 ... slowest runs (cycles): ... 99960 100752 102288 104118 106815 111300 111603 117090 123033 125169 min: 80925 cycles q1: 82308 cycles median: 82821 cycles q3: 83901 cycles max: 125169 cycles RESULT TestCatchingFault_CycleCount: x86-64_with_irt_nnacl_newlib_static= {82821, 1593} count Apparent clock speed: 2637 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 66.803 usec (6.6803e-05 sec) per iteration: 0.0066803 sec for 100 iterations 65.023 usec (6.50232e-05 sec) per iteration: 0.0972747 sec for 1496 iterations 68.127 usec (6.81272e-05 sec) per iteration: 0.101918 sec for 1496 iterations 64.179 usec (6.41792e-05 sec) per iteration: 0.0960121 sec for 1496 iterations 64.699 usec (6.46988e-05 sec) per iteration: 0.0967894 sec for 1496 iterations 65.256 usec (6.52563e-05 sec) per iteration: 0.0976234 sec for 1496 iterations mean: 65.456923 usec stddev: 1.383207 usec relative stddev: 2.11% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_with_irt_nnacl_newlib_static= {65.456923, 1.383207} us Measuring clock cycles: first runs (cycles): 179409 219744 234630 177627 172818 181563 173835 181338 177927 170583 ... slowest runs (cycles): ... 191982 195018 195654 197001 197646 200253 204576 207414 219744 234630 min: 165591 cycles q1: 173151 cycles median: 175239 cycles q3: 178212 cycles max: 234630 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_with_irt_nnacl_newlib_static= {175239, 5061} count Apparent clock speed: 2677 MHz [ OK ] nacl_newlib.run_performance_test_irt (6200 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