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 = 1550153149, usec = 291435 } Thu Feb 14 06:05:49 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 [90840,3086484352:15:39:37.462066] Native Client module will be loaded at base address 0x0000057b00000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: gn_x86-64_with_irt_nnacl_newlib_static= {0.067560, 0.014860} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (889 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 (66760 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 [90845,3086484352:15:39:51.849850] Native Client module will be loaded at base address 0x000028c600000000 TestNull: Measuring real time: 0.007 usec (7.11e-09 sec) per iteration: 7.11e-07 sec for 100 iterations 0.002 usec (2.31e-09 sec) per iteration: 2.31e-06 sec for 1000 iterations 0.002 usec (2.0297e-09 sec) per iteration: 2.0297e-05 sec for 10000 iterations 0.002 usec (2.00675e-09 sec) per iteration: 0.0988695 sec for 49268364 iterations 0.002 usec (2.0076e-09 sec) per iteration: 0.0989112 sec for 49268364 iterations 0.002 usec (2.00676e-09 sec) per iteration: 0.0988699 sec for 49268364 iterations 0.002 usec (1.99467e-09 sec) per iteration: 0.0982742 sec for 49268364 iterations 0.002 usec (2.00715e-09 sec) per iteration: 0.098889 sec for 49268364 iterations mean: 0.002005 usec stddev: 0.000005 usec relative stddev: 0.25% RESULT TestNull: gn_x86-64_with_irt_nnacl_newlib_static= {0.002005, 0.000005} us Measuring clock cycles: first runs (cycles): 240 27 30 21 21 21 21 21 30 30 ... slowest runs (cycles): ... 30 30 30 30 30 30 30 30 30 240 min: 21 cycles q1: 21 cycles median: 27 cycles q3: 30 cycles max: 240 cycles RESULT TestNull_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {27, 9} count Apparent clock speed: 13469 MHz TestNaClSyscall: Measuring real time: 0.159 usec (1.5928e-07 sec) per iteration: 1.5928e-05 sec for 100 iterations 0.152 usec (1.52024e-07 sec) per iteration: 0.0954444 sec for 627825 iterations 0.153 usec (1.53431e-07 sec) per iteration: 0.0963277 sec for 627825 iterations 0.154 usec (1.53889e-07 sec) per iteration: 0.0966154 sec for 627825 iterations 0.154 usec (1.53587e-07 sec) per iteration: 0.0964255 sec for 627825 iterations 0.153 usec (1.53183e-07 sec) per iteration: 0.0961724 sec for 627825 iterations mean: 0.153223 usec stddev: 0.000642 usec relative stddev: 0.42% RESULT TestNaClSyscall: gn_x86-64_with_irt_nnacl_newlib_static= {0.153223, 0.000642} us Measuring clock cycles: first runs (cycles): 723 327 309 303 303 309 300 294 294 294 ... slowest runs (cycles): ... 303 303 303 303 303 309 309 312 327 723 min: 291 cycles q1: 294 cycles median: 300 cycles q3: 303 cycles max: 723 cycles RESULT TestNaClSyscall_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {300, 9} count Apparent clock speed: 1958 MHz TestSetjmpLongjmp: Measuring real time: 0.017 usec (1.67e-08 sec) per iteration: 1.67e-06 sec for 100 iterations 0.012 usec (1.2493e-08 sec) per iteration: 1.2493e-05 sec for 1000 iterations 0.012 usec (1.2122e-08 sec) per iteration: 0.0970304 sec for 8004482 iterations 0.013 usec (1.27202e-08 sec) per iteration: 0.101819 sec for 8004482 iterations 0.013 usec (1.31619e-08 sec) per iteration: 0.105355 sec for 8004482 iterations 0.015 usec (1.45543e-08 sec) per iteration: 0.116499 sec for 8004482 iterations 0.012 usec (1.20574e-08 sec) per iteration: 0.0965136 sec for 8004482 iterations mean: 0.012923 usec stddev: 0.000911 usec relative stddev: 7.05% RESULT TestSetjmpLongjmp: gn_x86-64_with_irt_nnacl_newlib_static= {0.012923, 0.000911} us Measuring clock cycles: first runs (cycles): 456 57 30 24 24 24 33 33 33 24 ... slowest runs (cycles): ... 33 33 33 33 33 33 33 33 57 456 min: 24 cycles q1: 24 cycles median: 33 cycles q3: 33 cycles max: 456 cycles RESULT TestSetjmpLongjmp_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {33, 9} count Apparent clock speed: 2554 MHz TestClockGetTime: Measuring real time: 0.169 usec (1.6929e-07 sec) per iteration: 1.6929e-05 sec for 100 iterations 0.165 usec (1.64796e-07 sec) per iteration: 0.0973456 sec for 590702 iterations 0.166 usec (1.6567e-07 sec) per iteration: 0.0978614 sec for 590702 iterations 0.165 usec (1.65102e-07 sec) per iteration: 0.0975261 sec for 590702 iterations 0.165 usec (1.65215e-07 sec) per iteration: 0.097593 sec for 590702 iterations 0.166 usec (1.65643e-07 sec) per iteration: 0.0978456 sec for 590702 iterations mean: 0.165285 usec stddev: 0.000333 usec relative stddev: 0.20% RESULT TestClockGetTime: gn_x86-64_with_irt_nnacl_newlib_static= {0.165285, 0.000333} us Measuring clock cycles: first runs (cycles): 1788 486 447 459 465 459 450 471 453 444 ... slowest runs (cycles): ... 468 468 468 468 468 468 471 471 486 1788 min: 444 cycles q1: 450 cycles median: 456 cycles q3: 462 cycles max: 1788 cycles RESULT TestClockGetTime_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {456, 12} count Apparent clock speed: 2759 MHz TestTlsVariable: Measuring real time: 0.010 usec (9.94e-09 sec) per iteration: 9.94e-07 sec for 100 iterations 0.006 usec (6.316e-09 sec) per iteration: 6.316e-06 sec for 1000 iterations 0.006 usec (6.0313e-09 sec) per iteration: 6.0313e-05 sec for 10000 iterations 0.006 usec (6.02095e-09 sec) per iteration: 0.0998284 sec for 16580173 iterations 0.006 usec (6.07787e-09 sec) per iteration: 0.100772 sec for 16580173 iterations 0.006 usec (6.09846e-09 sec) per iteration: 0.101114 sec for 16580173 iterations 0.006 usec (5.97782e-09 sec) per iteration: 0.0991134 sec for 16580173 iterations 0.007 usec (6.88044e-09 sec) per iteration: 0.114079 sec for 16580173 iterations mean: 0.006211 usec stddev: 0.000337 usec relative stddev: 5.43% RESULT TestTlsVariable: gn_x86-64_with_irt_nnacl_newlib_static= {0.006211, 0.000337} us Measuring clock cycles: first runs (cycles): 591 90 27 48 39 36 39 42 48 48 ... slowest runs (cycles): ... 48 51 51 51 51 54 54 72 90 591 min: 24 cycles q1: 33 cycles median: 36 cycles q3: 42 cycles max: 591 cycles RESULT TestTlsVariable_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {36, 9} count Apparent clock speed: 5796 MHz TestMmapAnonymous: Measuring real time: 16.097 usec (1.60971e-05 sec) per iteration: 0.00160971 sec for 100 iterations 10.388 usec (1.03876e-05 sec) per iteration: 0.0645279 sec for 6212 iterations 8.834 usec (8.83353e-06 sec) per iteration: 0.0548739 sec for 6212 iterations 8.761 usec (8.76101e-06 sec) per iteration: 0.0544234 sec for 6212 iterations 8.834 usec (8.83424e-06 sec) per iteration: 0.0548783 sec for 6212 iterations 8.781 usec (8.78074e-06 sec) per iteration: 0.054546 sec for 6212 iterations mean: 9.119426 usec stddev: 0.634754 usec relative stddev: 6.96% RESULT TestMmapAnonymous: gn_x86-64_with_irt_nnacl_newlib_static= {9.119426, 0.634754} us Measuring clock cycles: first runs (cycles): 34500 24033 23772 23940 23634 23511 23802 23925 23784 23814 ... slowest runs (cycles): ... 23940 23976 24030 24033 24060 24084 24102 24126 24174 34500 min: 23331 cycles q1: 23598 cycles median: 23724 cycles q3: 23808 cycles max: 34500 cycles RESULT TestMmapAnonymous_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {23724, 210} count Apparent clock speed: 2601 MHz TestAtomicIncrement: Measuring real time: 0.014 usec (1.448e-08 sec) per iteration: 1.448e-06 sec for 100 iterations 0.010 usec (9.943e-09 sec) per iteration: 9.943e-06 sec for 1000 iterations 0.010 usec (9.6942e-09 sec) per iteration: 9.6942e-05 sec for 10000 iterations 0.010 usec (9.67574e-09 sec) per iteration: 0.0998095 sec for 10315446 iterations 0.010 usec (9.6765e-09 sec) per iteration: 0.0998174 sec for 10315446 iterations 0.010 usec (9.72343e-09 sec) per iteration: 0.100301 sec for 10315446 iterations 0.010 usec (9.64901e-09 sec) per iteration: 0.0995339 sec for 10315446 iterations 0.010 usec (9.65342e-09 sec) per iteration: 0.0995794 sec for 10315446 iterations mean: 0.009676 usec stddev: 0.000026 usec relative stddev: 0.27% RESULT TestAtomicIncrement: gn_x86-64_with_irt_nnacl_newlib_static= {0.009676, 0.000026} us Measuring clock cycles: first runs (cycles): 318 51 48 48 51 39 51 48 48 51 ... slowest runs (cycles): ... 51 51 51 51 51 51 51 51 51 318 min: 39 cycles q1: 48 cycles median: 48 cycles q3: 51 cycles max: 318 cycles RESULT TestAtomicIncrement_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {48, 3} count Apparent clock speed: 4961 MHz TestUncontendedMutexLock: Measuring real time: 0.029 usec (2.864e-08 sec) per iteration: 2.864e-06 sec for 100 iterations 0.023 usec (2.331e-08 sec) per iteration: 2.331e-05 sec for 1000 iterations 0.023 usec (2.30501e-08 sec) per iteration: 0.0988852 sec for 4290004 iterations 0.023 usec (2.29585e-08 sec) per iteration: 0.0984921 sec for 4290004 iterations 0.025 usec (2.45302e-08 sec) per iteration: 0.105235 sec for 4290004 iterations 0.023 usec (2.29524e-08 sec) per iteration: 0.0984658 sec for 4290004 iterations 0.024 usec (2.37453e-08 sec) per iteration: 0.101867 sec for 4290004 iterations mean: 0.023447 usec stddev: 0.000617 usec relative stddev: 2.63% RESULT TestUncontendedMutexLock: gn_x86-64_with_irt_nnacl_newlib_static= {0.023447, 0.000617} us Measuring clock cycles: first runs (cycles): 441 108 90 90 90 90 81 81 81 81 ... slowest runs (cycles): ... 90 90 90 90 90 90 90 90 108 441 min: 81 cycles q1: 81 cycles median: 90 cycles q3: 90 cycles max: 441 cycles RESULT TestUncontendedMutexLock_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {90, 9} count Apparent clock speed: 3838 MHz TestCondvarSignalNoOp: Measuring real time: 0.309 usec (3.0866e-07 sec) per iteration: 3.0866e-05 sec for 100 iterations 0.163 usec (1.6282e-07 sec) per iteration: 0.0527506 sec for 323981 iterations 0.164 usec (1.64479e-07 sec) per iteration: 0.053288 sec for 323981 iterations 0.179 usec (1.79109e-07 sec) per iteration: 0.058028 sec for 323981 iterations 0.163 usec (1.63373e-07 sec) per iteration: 0.0529296 sec for 323981 iterations 0.163 usec (1.63367e-07 sec) per iteration: 0.0529277 sec for 323981 iterations mean: 0.166629 usec stddev: 0.006263 usec relative stddev: 3.76% RESULT TestCondvarSignalNoOp: gn_x86-64_with_irt_nnacl_newlib_static= {0.166629, 0.006263} us Measuring clock cycles: first runs (cycles): 1458 453 438 459 444 441 444 438 453 444 ... slowest runs (cycles): ... 453 453 453 456 459 459 462 462 462 1458 min: 435 cycles q1: 441 cycles median: 444 cycles q3: 450 cycles max: 1458 cycles RESULT TestCondvarSignalNoOp_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {444, 9} count Apparent clock speed: 2665 MHz TestThreadCreateAndJoin: Measuring real time: 81.099 usec (8.10994e-05 sec) per iteration: 0.00810994 sec for 100 iterations 69.926 usec (6.99265e-05 sec) per iteration: 0.0862193 sec for 1233 iterations 67.316 usec (6.73161e-05 sec) per iteration: 0.0830008 sec for 1233 iterations 65.775 usec (6.57751e-05 sec) per iteration: 0.0811007 sec for 1233 iterations 64.686 usec (6.46861e-05 sec) per iteration: 0.0797579 sec for 1233 iterations 65.744 usec (6.57443e-05 sec) per iteration: 0.0810628 sec for 1233 iterations mean: 66.689630 usec stddev: 1.822837 usec relative stddev: 2.73% RESULT TestThreadCreateAndJoin: gn_x86-64_with_irt_nnacl_newlib_static= {66.689630, 1.822837} us Measuring clock cycles: first runs (cycles): 172752 191571 198306 177453 203193 224583 217524 184893 175611 180351 ... slowest runs (cycles): ... 177453 177903 180351 184383 184893 191571 198306 203193 217524 224583 min: 167829 cycles q1: 173421 cycles median: 174159 cycles q3: 175380 cycles max: 224583 cycles RESULT TestThreadCreateAndJoin_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {174159, 1959} count Apparent clock speed: 2611 MHz TestThreadWakeup: Measuring real time: 31.663 usec (3.16632e-05 sec) per iteration: 0.00316632 sec for 100 iterations 30.969 usec (3.09686e-05 sec) per iteration: 0.0977988 sec for 3158 iterations 31.207 usec (3.12071e-05 sec) per iteration: 0.0985519 sec for 3158 iterations 29.743 usec (2.97431e-05 sec) per iteration: 0.0939287 sec for 3158 iterations 30.242 usec (3.02423e-05 sec) per iteration: 0.0955051 sec for 3158 iterations 31.271 usec (3.12711e-05 sec) per iteration: 0.0987541 sec for 3158 iterations mean: 30.686421 usec stddev: 0.596598 usec relative stddev: 1.94% RESULT TestThreadWakeup: gn_x86-64_with_irt_nnacl_newlib_static= {30.686421, 0.596598} us Measuring clock cycles: first runs (cycles): 88569 96717 101655 94905 82155 131550 97740 92430 94122 84546 ... slowest runs (cycles): ... 87453 88569 89898 92430 94122 94905 96717 97740 101655 131550 min: 79287 cycles q1: 83364 cycles median: 83967 cycles q3: 84960 cycles max: 131550 cycles RESULT TestThreadWakeup_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {83967, 1596} count Apparent clock speed: 2736 MHz TestCatchingFault: Measuring real time: 33.231 usec (3.32314e-05 sec) per iteration: 0.00332314 sec for 100 iterations 31.399 usec (3.13989e-05 sec) per iteration: 0.0944794 sec for 3009 iterations 31.879 usec (3.18792e-05 sec) per iteration: 0.0959245 sec for 3009 iterations 31.424 usec (3.14237e-05 sec) per iteration: 0.0945541 sec for 3009 iterations 31.784 usec (3.17841e-05 sec) per iteration: 0.0956383 sec for 3009 iterations 32.287 usec (3.22866e-05 sec) per iteration: 0.0971504 sec for 3009 iterations mean: 31.754512 usec stddev: 0.327220 usec relative stddev: 1.03% RESULT TestCatchingFault: gn_x86-64_with_irt_nnacl_newlib_static= {31.754512, 0.327220} us Measuring clock cycles: first runs (cycles): 104568 87633 86487 87531 87399 87582 100062 141693 150441 95892 ... slowest runs (cycles): ... 102228 102936 104568 107019 109644 112218 126534 133167 141693 150441 min: 83610 cycles q1: 84429 cycles median: 84903 cycles q3: 86487 cycles max: 150441 cycles RESULT TestCatchingFault_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {84903, 2058} count Apparent clock speed: 2674 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 66.751 usec (6.6751e-05 sec) per iteration: 0.0066751 sec for 100 iterations 69.533 usec (6.95326e-05 sec) per iteration: 0.10416 sec for 1498 iterations 67.604 usec (6.7604e-05 sec) per iteration: 0.101271 sec for 1498 iterations 65.708 usec (6.57083e-05 sec) per iteration: 0.098431 sec for 1498 iterations 71.254 usec (7.12543e-05 sec) per iteration: 0.106739 sec for 1498 iterations 65.764 usec (6.5764e-05 sec) per iteration: 0.0985145 sec for 1498 iterations mean: 67.972644 usec stddev: 2.160728 usec relative stddev: 3.18% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: gn_x86-64_with_irt_nnacl_newlib_static= {67.972644, 2.160728} us Measuring clock cycles: first runs (cycles): 172191 209577 187794 168552 229434 160764 227283 185799 179475 174075 ... slowest runs (cycles): ... 189855 192243 193278 198264 208446 209577 214359 227283 229434 258501 min: 160764 cycles q1: 167961 cycles median: 168870 cycles q3: 170793 cycles max: 258501 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {168870, 2832} count Apparent clock speed: 2484 MHz [ OK ] nacl_newlib.run_performance_test_irt (6310 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