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 = 1552943920, usec = 500963 } Mon Mar 18 14:18:40 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: opt-mac TARGET_ROOT: /b/s/w/ir/k/native_client/scons-out/opt-mac-x86-64 OBJ_ROOT: /b/s/w/ir/k/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/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 -DNDEBUG -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 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 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 [14735,2531390336:15:01:38.589380] Native Client module will be loaded at base address 0x00000d5c00000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: x86-64_with_irt_nnacl_newlib_static= {0.080740, 0.003130} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (1150 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/k/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 (70580 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= 526.596 KB RESULT hello_world_size.out: ZIPPED_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 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 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 [14740,2531390336:15:01:53.760461] Native Client module will be loaded at base address 0x0000365200000000 TestNull: Measuring real time: 0.008 usec (7.87e-09 sec) per iteration: 7.87e-07 sec for 100 iterations 0.002 usec (2.323e-09 sec) per iteration: 2.323e-06 sec for 1000 iterations 0.002 usec (2.0305e-09 sec) per iteration: 2.0305e-05 sec for 10000 iterations 0.002 usec (2.03463e-09 sec) per iteration: 0.100204 sec for 49248953 iterations 0.002 usec (2.02e-09 sec) per iteration: 0.0994828 sec for 49248953 iterations 0.002 usec (2.01442e-09 sec) per iteration: 0.099208 sec for 49248953 iterations 0.002 usec (2.02446e-09 sec) per iteration: 0.0997024 sec for 49248953 iterations 0.002 usec (2.02832e-09 sec) per iteration: 0.0998928 sec for 49248953 iterations mean: 0.002024 usec stddev: 0.000007 usec relative stddev: 0.34% RESULT TestNull: x86-64_with_irt_nnacl_newlib_static= {0.002024, 0.000007} us Measuring clock cycles: first runs (cycles): 237 21 21 30 30 27 30 18 21 18 ... slowest runs (cycles): ... 30 30 30 30 30 30 30 30 30 237 min: 18 cycles q1: 18 cycles median: 21 cycles q3: 27 cycles max: 237 cycles RESULT TestNull_CycleCount: x86-64_with_irt_nnacl_newlib_static= {21, 9} count Apparent clock speed: 10374 MHz TestNaClSyscall: Measuring real time: 0.163 usec (1.6319e-07 sec) per iteration: 1.6319e-05 sec for 100 iterations 0.152 usec (1.52358e-07 sec) per iteration: 0.093362 sec for 612782 iterations 0.152 usec (1.52352e-07 sec) per iteration: 0.0933589 sec for 612782 iterations 0.152 usec (1.51872e-07 sec) per iteration: 0.0930643 sec for 612782 iterations 0.148 usec (1.47766e-07 sec) per iteration: 0.0905481 sec for 612782 iterations 0.141 usec (1.40869e-07 sec) per iteration: 0.0863217 sec for 612782 iterations mean: 0.149043 usec stddev: 0.004436 usec relative stddev: 2.98% RESULT TestNaClSyscall: x86-64_with_irt_nnacl_newlib_static= {0.149043, 0.004436} us Measuring clock cycles: first runs (cycles): 969 468 390 414 396 357 387 390 399 432 ... slowest runs (cycles): ... 438 438 441 441 444 450 450 456 468 969 min: 321 cycles q1: 387 cycles median: 399 cycles q3: 420 cycles max: 969 cycles RESULT TestNaClSyscall_CycleCount: x86-64_with_irt_nnacl_newlib_static= {399, 33} count Apparent clock speed: 2677 MHz TestSetjmpLongjmp: Measuring real time: 0.022 usec (2.179e-08 sec) per iteration: 2.179e-06 sec for 100 iterations 0.015 usec (1.4616e-08 sec) per iteration: 1.4616e-05 sec for 1000 iterations 0.015 usec (1.46585e-08 sec) per iteration: 0.100291 sec for 6841817 iterations 0.015 usec (1.46742e-08 sec) per iteration: 0.100398 sec for 6841817 iterations 0.015 usec (1.46188e-08 sec) per iteration: 0.100019 sec for 6841817 iterations 0.012 usec (1.22118e-08 sec) per iteration: 0.083551 sec for 6841817 iterations 0.012 usec (1.20732e-08 sec) per iteration: 0.0826027 sec for 6841817 iterations mean: 0.013647 usec stddev: 0.001230 usec relative stddev: 9.01% RESULT TestSetjmpLongjmp: x86-64_with_irt_nnacl_newlib_static= {0.013647, 0.001230} us Measuring clock cycles: first runs (cycles): 432 57 33 33 36 21 24 21 33 33 ... slowest runs (cycles): ... 33 33 33 33 33 33 33 36 57 432 min: 21 cycles q1: 24 cycles median: 30 cycles q3: 33 cycles max: 432 cycles RESULT TestSetjmpLongjmp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {30, 9} count Apparent clock speed: 2198 MHz TestClockGetTime: Measuring real time: 0.217 usec (2.1665e-07 sec) per iteration: 2.1665e-05 sec for 100 iterations 0.212 usec (2.12284e-07 sec) per iteration: 0.0979846 sec for 461573 iterations 0.207 usec (2.0693e-07 sec) per iteration: 0.0955132 sec for 461573 iterations 0.206 usec (2.06212e-07 sec) per iteration: 0.0951818 sec for 461573 iterations 0.210 usec (2.10184e-07 sec) per iteration: 0.0970152 sec for 461573 iterations 0.207 usec (2.07225e-07 sec) per iteration: 0.0956494 sec for 461573 iterations mean: 0.208567 usec stddev: 0.002300 usec relative stddev: 1.10% RESULT TestClockGetTime: x86-64_with_irt_nnacl_newlib_static= {0.208567, 0.002300} us Measuring clock cycles: first runs (cycles): 1686 564 558 552 552 564 567 552 558 552 ... slowest runs (cycles): ... 567 567 567 570 570 570 570 570 1047 1686 min: 552 cycles q1: 552 cycles median: 558 cycles q3: 561 cycles max: 1686 cycles RESULT TestClockGetTime_CycleCount: x86-64_with_irt_nnacl_newlib_static= {558, 9} count Apparent clock speed: 2675 MHz TestTlsVariable: Measuring real time: 0.019 usec (1.856e-08 sec) per iteration: 1.856e-06 sec for 100 iterations 0.014 usec (1.3907e-08 sec) per iteration: 1.3907e-05 sec for 1000 iterations 0.006 usec (6.10417e-09 sec) per iteration: 0.0438928 sec for 7190623 iterations 0.007 usec (6.52918e-09 sec) per iteration: 0.0469489 sec for 7190623 iterations 0.006 usec (6.08865e-09 sec) per iteration: 0.0437812 sec for 7190623 iterations 0.006 usec (6.04957e-09 sec) per iteration: 0.0435002 sec for 7190623 iterations 0.006 usec (6.10978e-09 sec) per iteration: 0.0439331 sec for 7190623 iterations mean: 0.006176 usec stddev: 0.000178 usec relative stddev: 2.88% RESULT TestTlsVariable: x86-64_with_irt_nnacl_newlib_static= {0.006176, 0.000178} us Measuring clock cycles: first runs (cycles): 321 63 30 30 33 30 21 21 21 21 ... slowest runs (cycles): ... 30 30 30 30 30 33 33 33 63 321 min: 21 cycles q1: 21 cycles median: 24 cycles q3: 30 cycles max: 321 cycles RESULT TestTlsVariable_CycleCount: x86-64_with_irt_nnacl_newlib_static= {24, 9} count Apparent clock speed: 3886 MHz TestMmapAnonymous: Measuring real time: 12.066 usec (1.20664e-05 sec) per iteration: 0.00120664 sec for 100 iterations 8.805 usec (8.80501e-06 sec) per iteration: 0.0729671 sec for 8287 iterations 8.917 usec (8.91678e-06 sec) per iteration: 0.0738934 sec for 8287 iterations 8.966 usec (8.96563e-06 sec) per iteration: 0.0742982 sec for 8287 iterations 8.798 usec (8.79807e-06 sec) per iteration: 0.0729096 sec for 8287 iterations 9.034 usec (9.03439e-06 sec) per iteration: 0.074868 sec for 8287 iterations mean: 8.903976 usec stddev: 0.091632 usec relative stddev: 1.03% RESULT TestMmapAnonymous: x86-64_with_irt_nnacl_newlib_static= {8.903976, 0.091632} us Measuring clock cycles: first runs (cycles): 52101 30240 28461 28647 29046 28542 28068 27921 27849 27789 ... slowest runs (cycles): ... 48030 48336 48633 48636 48747 49200 49206 49554 50181 52101 min: 27531 cycles q1: 40509 cycles median: 43116 cycles q3: 44961 cycles max: 52101 cycles RESULT TestMmapAnonymous_CycleCount: x86-64_with_irt_nnacl_newlib_static= {43116, 4452} count Apparent clock speed: 4842 MHz TestAtomicIncrement: Measuring real time: 0.017 usec (1.707e-08 sec) per iteration: 1.707e-06 sec for 100 iterations 0.011 usec (1.095e-08 sec) per iteration: 1.095e-05 sec for 1000 iterations 0.010 usec (1.0293e-08 sec) per iteration: 0.0939998 sec for 9132420 iterations 0.010 usec (1.01609e-08 sec) per iteration: 0.0927937 sec for 9132420 iterations 0.010 usec (9.69359e-09 sec) per iteration: 0.0885259 sec for 9132420 iterations 0.010 usec (9.69218e-09 sec) per iteration: 0.088513 sec for 9132420 iterations 0.010 usec (9.6915e-09 sec) per iteration: 0.0885068 sec for 9132420 iterations mean: 0.009906 usec stddev: 0.000265 usec relative stddev: 2.68% RESULT TestAtomicIncrement: x86-64_with_irt_nnacl_newlib_static= {0.009906, 0.000265} us Measuring clock cycles: first runs (cycles): 225 48 51 51 48 51 48 51 39 51 ... slowest runs (cycles): ... 51 51 51 51 51 51 51 51 51 225 min: 39 cycles q1: 48 cycles median: 48 cycles q3: 51 cycles max: 225 cycles RESULT TestAtomicIncrement_CycleCount: x86-64_with_irt_nnacl_newlib_static= {48, 3} count Apparent clock speed: 4845 MHz TestUncontendedMutexLock: Measuring real time: 0.028 usec (2.783e-08 sec) per iteration: 2.783e-06 sec for 100 iterations 0.023 usec (2.3298e-08 sec) per iteration: 2.3298e-05 sec for 1000 iterations 0.023 usec (2.31389e-08 sec) per iteration: 0.0993171 sec for 4292213 iterations 0.023 usec (2.30669e-08 sec) per iteration: 0.0990082 sec for 4292213 iterations 0.024 usec (2.42319e-08 sec) per iteration: 0.104009 sec for 4292213 iterations 0.024 usec (2.44148e-08 sec) per iteration: 0.104794 sec for 4292213 iterations 0.023 usec (2.3454e-08 sec) per iteration: 0.100669 sec for 4292213 iterations mean: 0.023661 usec stddev: 0.000559 usec relative stddev: 2.36% RESULT TestUncontendedMutexLock: x86-64_with_irt_nnacl_newlib_static= {0.023661, 0.000559} us Measuring clock cycles: first runs (cycles): 414 108 87 90 87 90 87 90 87 90 ... slowest runs (cycles): ... 90 90 90 90 90 90 90 90 108 414 min: 78 cycles q1: 81 cycles median: 87 cycles q3: 90 cycles max: 414 cycles RESULT TestUncontendedMutexLock_CycleCount: x86-64_with_irt_nnacl_newlib_static= {87, 9} count Apparent clock speed: 3677 MHz TestCondvarSignalNoOp: Measuring real time: 0.308 usec (3.0837e-07 sec) per iteration: 3.0837e-05 sec for 100 iterations 0.159 usec (1.59125e-07 sec) per iteration: 0.0516019 sec for 324285 iterations 0.168 usec (1.67986e-07 sec) per iteration: 0.0544754 sec for 324285 iterations 0.160 usec (1.60284e-07 sec) per iteration: 0.0519778 sec for 324285 iterations 0.160 usec (1.60081e-07 sec) per iteration: 0.0519119 sec for 324285 iterations 0.160 usec (1.60231e-07 sec) per iteration: 0.0519606 sec for 324285 iterations mean: 0.161542 usec stddev: 0.003250 usec relative stddev: 2.01% RESULT TestCondvarSignalNoOp: x86-64_with_irt_nnacl_newlib_static= {0.161542, 0.003250} us Measuring clock cycles: first runs (cycles): 1152 432 432 432 444 432 444 432 432 435 ... slowest runs (cycles): ... 435 435 435 435 435 444 444 444 444 1152 min: 432 cycles q1: 432 cycles median: 432 cycles q3: 435 cycles max: 1152 cycles RESULT TestCondvarSignalNoOp_CycleCount: x86-64_with_irt_nnacl_newlib_static= {432, 3} count Apparent clock speed: 2674 MHz TestThreadCreateAndJoin: Measuring real time: 69.683 usec (6.96834e-05 sec) per iteration: 0.00696833 sec for 100 iterations 72.508 usec (7.25076e-05 sec) per iteration: 0.104048 sec for 1435 iterations 67.288 usec (6.72884e-05 sec) per iteration: 0.0965589 sec for 1435 iterations 71.652 usec (7.16523e-05 sec) per iteration: 0.102821 sec for 1435 iterations 67.751 usec (6.7751e-05 sec) per iteration: 0.0972226 sec for 1435 iterations 73.615 usec (7.36148e-05 sec) per iteration: 0.105637 sec for 1435 iterations mean: 70.562802 usec stddev: 2.565599 usec relative stddev: 3.64% RESULT TestThreadCreateAndJoin: x86-64_with_irt_nnacl_newlib_static= {70.562802, 2.565599} us Measuring clock cycles: first runs (cycles): 177300 194010 238353 239943 218562 226413 426936 225126 300768 323568 ... slowest runs (cycles): ... 285909 286944 288570 300768 307209 316662 323568 349917 352917 426936 min: 177300 cycles q1: 235863 cycles median: 239943 cycles q3: 270021 cycles max: 426936 cycles RESULT TestThreadCreateAndJoin_CycleCount: x86-64_with_irt_nnacl_newlib_static= {239943, 34158} count Apparent clock speed: 3400 MHz TestThreadWakeup: Measuring real time: 34.117 usec (3.41168e-05 sec) per iteration: 0.00341167 sec for 100 iterations 30.634 usec (3.06336e-05 sec) per iteration: 0.089787 sec for 2931 iterations 32.309 usec (3.2309e-05 sec) per iteration: 0.0946977 sec for 2931 iterations 32.885 usec (3.28854e-05 sec) per iteration: 0.0963872 sec for 2931 iterations 31.258 usec (3.12582e-05 sec) per iteration: 0.0916179 sec for 2931 iterations 30.556 usec (3.05561e-05 sec) per iteration: 0.08956 sec for 2931 iterations mean: 31.528469 usec stddev: 0.924107 usec relative stddev: 2.93% RESULT TestThreadWakeup: x86-64_with_irt_nnacl_newlib_static= {31.528469, 0.924107} us Measuring clock cycles: first runs (cycles): 86511 72516 71544 71424 70893 72390 104100 107253 97974 99225 ... slowest runs (cycles): ... 97974 98010 98259 99225 104100 107253 110511 119313 121551 125256 min: 70893 cycles q1: 85908 cycles median: 86826 cycles q3: 87861 cycles max: 125256 cycles RESULT TestThreadWakeup_CycleCount: x86-64_with_irt_nnacl_newlib_static= {86826, 1953} count Apparent clock speed: 2754 MHz TestCatchingFault: Measuring real time: 35.968 usec (3.5968e-05 sec) per iteration: 0.0035968 sec for 100 iterations 34.137 usec (3.41371e-05 sec) per iteration: 0.0949012 sec for 2780 iterations 34.804 usec (3.48045e-05 sec) per iteration: 0.0967564 sec for 2780 iterations 31.758 usec (3.17584e-05 sec) per iteration: 0.0882884 sec for 2780 iterations 34.395 usec (3.43953e-05 sec) per iteration: 0.095619 sec for 2780 iterations 33.669 usec (3.36692e-05 sec) per iteration: 0.0936005 sec for 2780 iterations mean: 33.752914 usec stddev: 1.063112 usec relative stddev: 3.15% RESULT TestCatchingFault: x86-64_with_irt_nnacl_newlib_static= {33.752914, 1.063112} us Measuring clock cycles: first runs (cycles): 173286 147369 106977 143829 128265 140823 119088 185034 132555 135594 ... slowest runs (cycles): ... 128265 132555 135594 140823 143829 147369 148899 154320 173286 185034 min: 79623 cycles q1: 81486 cycles median: 82278 cycles q3: 83748 cycles max: 185034 cycles RESULT TestCatchingFault_CycleCount: x86-64_with_irt_nnacl_newlib_static= {82278, 2262} count Apparent clock speed: 2438 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 70.034 usec (7.00338e-05 sec) per iteration: 0.00700338 sec for 100 iterations 67.533 usec (6.75327e-05 sec) per iteration: 0.0963692 sec for 1427 iterations 72.540 usec (7.25396e-05 sec) per iteration: 0.103514 sec for 1427 iterations 85.132 usec (8.51316e-05 sec) per iteration: 0.121483 sec for 1427 iterations 84.836 usec (8.48355e-05 sec) per iteration: 0.12106 sec for 1427 iterations 91.513 usec (9.15129e-05 sec) per iteration: 0.130589 sec for 1427 iterations mean: 80.310487 usec stddev: 8.864217 usec relative stddev: 11.04% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: x86-64_with_irt_nnacl_newlib_static= {80.310487, 8.864217} us Measuring clock cycles: first runs (cycles): 237744 256410 206826 201054 324366 255570 233586 201486 224754 195759 ... slowest runs (cycles): ... 266322 266919 269202 269457 271308 275073 282399 315849 324366 436830 min: 176586 cycles q1: 228618 cycles median: 235707 cycles q3: 245904 cycles max: 436830 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: x86-64_with_irt_nnacl_newlib_static= {235707, 17286} count Apparent clock speed: 2935 MHz [ OK ] nacl_newlib.run_performance_test_irt (6120 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