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 = 1552669774, usec = 152375 } Fri Mar 15 10:09:34 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 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 [32299,3051619200:13:16:52.658248] Native Client module will be loaded at base address 0x00001ca400000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: gn_x86-64_with_irt_nnacl_newlib_static= {0.068230, 0.002960} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (890 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 WARNING: should have taken less than 60.000000 secs [ OK ] nacl_newlib.run_clock_cputime_test_irt (66290 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 [32308,3051619200:13:17:07.004780] Native Client module will be loaded at base address 0x000026f600000000 TestNull: Measuring real time: 0.017 usec (1.709e-08 sec) per iteration: 1.709e-06 sec for 100 iterations 0.005 usec (4.585e-09 sec) per iteration: 4.585e-06 sec for 1000 iterations 0.004 usec (4.0565e-09 sec) per iteration: 4.0565e-05 sec for 10000 iterations 0.002 usec (2.16498e-09 sec) per iteration: 0.0533707 sec for 24651793 iterations 0.002 usec (1.99962e-09 sec) per iteration: 0.0492942 sec for 24651793 iterations 0.002 usec (2.01897e-09 sec) per iteration: 0.0497712 sec for 24651793 iterations 0.002 usec (2.01565e-09 sec) per iteration: 0.0496893 sec for 24651793 iterations 0.002 usec (2.07479e-09 sec) per iteration: 0.0511472 sec for 24651793 iterations mean: 0.002055 usec stddev: 0.000061 usec relative stddev: 2.95% RESULT TestNull: gn_x86-64_with_irt_nnacl_newlib_static= {0.002055, 0.000061} us Measuring clock cycles: first runs (cycles): 204 18 21 18 30 30 30 30 30 21 ... slowest runs (cycles): ... 30 30 30 30 30 30 30 30 30 204 min: 18 cycles q1: 21 cycles median: 30 cycles q3: 30 cycles max: 204 cycles RESULT TestNull_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {30, 9} count Apparent clock speed: 14600 MHz TestNaClSyscall: Measuring real time: 0.157 usec (1.5698e-07 sec) per iteration: 1.5698e-05 sec for 100 iterations 0.151 usec (1.51473e-07 sec) per iteration: 0.0964917 sec for 637023 iterations 0.154 usec (1.53745e-07 sec) per iteration: 0.0979389 sec for 637023 iterations 0.156 usec (1.56114e-07 sec) per iteration: 0.0994479 sec for 637023 iterations 0.152 usec (1.52483e-07 sec) per iteration: 0.0971349 sec for 637023 iterations 0.155 usec (1.54683e-07 sec) per iteration: 0.0985363 sec for 637023 iterations mean: 0.153699 usec stddev: 0.001627 usec relative stddev: 1.06% RESULT TestNaClSyscall: gn_x86-64_with_irt_nnacl_newlib_static= {0.153699, 0.001627} us Measuring clock cycles: first runs (cycles): 678 306 294 294 294 294 294 294 294 294 ... slowest runs (cycles): ... 303 303 303 303 303 306 306 309 309 678 min: 291 cycles q1: 294 cycles median: 294 cycles q3: 303 cycles max: 678 cycles RESULT TestNaClSyscall_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {294, 9} count Apparent clock speed: 1913 MHz TestSetjmpLongjmp: Measuring real time: 0.016 usec (1.634e-08 sec) per iteration: 1.634e-06 sec for 100 iterations 0.012 usec (1.2432e-08 sec) per iteration: 1.2432e-05 sec for 1000 iterations 0.012 usec (1.20692e-08 sec) per iteration: 0.097082 sec for 8043758 iterations 0.012 usec (1.2014e-08 sec) per iteration: 0.0966379 sec for 8043758 iterations 0.012 usec (1.19969e-08 sec) per iteration: 0.0965001 sec for 8043758 iterations 0.012 usec (1.20787e-08 sec) per iteration: 0.0971584 sec for 8043758 iterations 0.012 usec (1.19613e-08 sec) per iteration: 0.0962134 sec for 8043758 iterations mean: 0.012024 usec stddev: 0.000044 usec relative stddev: 0.37% RESULT TestSetjmpLongjmp: gn_x86-64_with_irt_nnacl_newlib_static= {0.012024, 0.000044} us Measuring clock cycles: first runs (cycles): 414 33 24 24 24 33 33 30 33 24 ... slowest runs (cycles): ... 33 33 33 33 33 33 33 33 33 414 min: 21 cycles q1: 24 cycles median: 30 cycles q3: 33 cycles max: 414 cycles RESULT TestSetjmpLongjmp_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {30, 9} count Apparent clock speed: 2495 MHz TestClockGetTime: Measuring real time: 0.162 usec (1.6247e-07 sec) per iteration: 1.6247e-05 sec for 100 iterations 0.164 usec (1.6358e-07 sec) per iteration: 0.100683 sec for 615498 iterations 0.162 usec (1.6199e-07 sec) per iteration: 0.0997045 sec for 615498 iterations 0.162 usec (1.62259e-07 sec) per iteration: 0.0998703 sec for 615498 iterations 0.162 usec (1.61937e-07 sec) per iteration: 0.099672 sec for 615498 iterations 0.163 usec (1.63135e-07 sec) per iteration: 0.100409 sec for 615498 iterations mean: 0.162580 usec stddev: 0.000659 usec relative stddev: 0.41% RESULT TestClockGetTime: gn_x86-64_with_irt_nnacl_newlib_static= {0.162580, 0.000659} us Measuring clock cycles: first runs (cycles): 1722 405 519 399 408 399 393 396 396 393 ... slowest runs (cycles): ... 417 417 417 417 420 429 447 519 1722 37632 min: 390 cycles q1: 399 cycles median: 402 cycles q3: 408 cycles max: 37632 cycles RESULT TestClockGetTime_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {402, 9} count Apparent clock speed: 2473 MHz TestTlsVariable: Measuring real time: 0.009 usec (9.41e-09 sec) per iteration: 9.41e-07 sec for 100 iterations 0.006 usec (6.291e-09 sec) per iteration: 6.291e-06 sec for 1000 iterations 0.006 usec (6.029e-09 sec) per iteration: 6.029e-05 sec for 10000 iterations 0.006 usec (6.03481e-09 sec) per iteration: 0.100096 sec for 16586498 iterations 0.006 usec (6.07907e-09 sec) per iteration: 0.10083 sec for 16586498 iterations 0.006 usec (5.97062e-09 sec) per iteration: 0.0990317 sec for 16586498 iterations 0.006 usec (6.12253e-09 sec) per iteration: 0.101551 sec for 16586498 iterations 0.006 usec (6.29445e-09 sec) per iteration: 0.104403 sec for 16586498 iterations mean: 0.006100 usec stddev: 0.000109 usec relative stddev: 1.79% RESULT TestTlsVariable: gn_x86-64_with_irt_nnacl_newlib_static= {0.006100, 0.000109} us Measuring clock cycles: first runs (cycles): 336 72 33 30 21 21 18 24 21 21 ... slowest runs (cycles): ... 30 30 30 30 30 30 30 33 72 336 min: 18 cycles q1: 21 cycles median: 21 cycles q3: 30 cycles max: 336 cycles RESULT TestTlsVariable_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {21, 9} count Apparent clock speed: 3442 MHz TestMmapAnonymous: Measuring real time: 10.177 usec (1.0177e-05 sec) per iteration: 0.0010177 sec for 100 iterations 9.972 usec (9.97202e-06 sec) per iteration: 0.0979851 sec for 9826 iterations 10.128 usec (1.01282e-05 sec) per iteration: 0.0995194 sec for 9826 iterations 9.890 usec (9.89007e-06 sec) per iteration: 0.0971798 sec for 9826 iterations 10.105 usec (1.01048e-05 sec) per iteration: 0.0992893 sec for 9826 iterations 9.947 usec (9.94717e-06 sec) per iteration: 0.0977409 sec for 9826 iterations mean: 10.008438 usec stddev: 0.092417 usec relative stddev: 0.92% RESULT TestMmapAnonymous: gn_x86-64_with_irt_nnacl_newlib_static= {10.008438, 0.092417} us Measuring clock cycles: first runs (cycles): 38064 27702 27291 27072 27081 26904 26949 27093 27039 27006 ... slowest runs (cycles): ... 27114 27120 27120 27129 27138 27159 27258 27291 27702 38064 min: 26727 cycles q1: 26871 cycles median: 26916 cycles q3: 27048 cycles max: 38064 cycles RESULT TestMmapAnonymous_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {26916, 177} count Apparent clock speed: 2689 MHz TestAtomicIncrement: Measuring real time: 0.015 usec (1.472e-08 sec) per iteration: 1.472e-06 sec for 100 iterations 0.010 usec (9.913e-09 sec) per iteration: 9.913e-06 sec for 1000 iterations 0.010 usec (9.6921e-09 sec) per iteration: 9.6921e-05 sec for 10000 iterations 0.010 usec (9.65567e-09 sec) per iteration: 0.0996242 sec for 10317681 iterations 0.010 usec (1.00095e-08 sec) per iteration: 0.103275 sec for 10317681 iterations 0.010 usec (9.73264e-09 sec) per iteration: 0.100418 sec for 10317681 iterations 0.010 usec (9.88006e-09 sec) per iteration: 0.101939 sec for 10317681 iterations 0.010 usec (9.6681e-09 sec) per iteration: 0.0997524 sec for 10317681 iterations mean: 0.009789 usec stddev: 0.000136 usec relative stddev: 1.39% RESULT TestAtomicIncrement: gn_x86-64_with_irt_nnacl_newlib_static= {0.009789, 0.000136} us Measuring clock cycles: first runs (cycles): 210 51 39 48 51 39 51 48 51 48 ... slowest runs (cycles): ... 51 51 51 51 51 51 51 51 51 210 min: 39 cycles q1: 48 cycles median: 48 cycles q3: 51 cycles max: 210 cycles RESULT TestAtomicIncrement_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {48, 3} count Apparent clock speed: 4903 MHz TestUncontendedMutexLock: Measuring real time: 0.027 usec (2.75e-08 sec) per iteration: 2.75e-06 sec for 100 iterations 0.023 usec (2.3267e-08 sec) per iteration: 2.3267e-05 sec for 1000 iterations 0.023 usec (2.30304e-08 sec) per iteration: 0.0989832 sec for 4297932 iterations 0.023 usec (2.32429e-08 sec) per iteration: 0.0998966 sec for 4297932 iterations 0.023 usec (2.28536e-08 sec) per iteration: 0.0982233 sec for 4297932 iterations 0.023 usec (2.30734e-08 sec) per iteration: 0.0991677 sec for 4297932 iterations 0.023 usec (2.26969e-08 sec) per iteration: 0.0975499 sec for 4297932 iterations mean: 0.022979 usec stddev: 0.000188 usec relative stddev: 0.82% RESULT TestUncontendedMutexLock: gn_x86-64_with_irt_nnacl_newlib_static= {0.022979, 0.000188} us Measuring clock cycles: first runs (cycles): 417 111 78 81 78 81 78 90 87 90 ... slowest runs (cycles): ... 90 90 90 90 90 90 90 90 111 417 min: 78 cycles q1: 81 cycles median: 87 cycles q3: 90 cycles max: 417 cycles RESULT TestUncontendedMutexLock_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {87, 9} count Apparent clock speed: 3786 MHz TestCondvarSignalNoOp: Measuring real time: 0.272 usec (2.7181e-07 sec) per iteration: 2.7181e-05 sec for 100 iterations 0.160 usec (1.60382e-07 sec) per iteration: 0.0590051 sec for 367904 iterations 0.160 usec (1.59661e-07 sec) per iteration: 0.0587398 sec for 367904 iterations 0.160 usec (1.59901e-07 sec) per iteration: 0.0588283 sec for 367904 iterations 0.158 usec (1.58043e-07 sec) per iteration: 0.0581448 sec for 367904 iterations 0.155 usec (1.55024e-07 sec) per iteration: 0.0570341 sec for 367904 iterations mean: 0.158602 usec stddev: 0.001954 usec relative stddev: 1.23% RESULT TestCondvarSignalNoOp: gn_x86-64_with_irt_nnacl_newlib_static= {0.158602, 0.001954} us Measuring clock cycles: first runs (cycles): 1398 420 420 423 420 405 423 420 420 420 ... slowest runs (cycles): ... 423 423 423 423 423 423 423 423 423 1398 min: 405 cycles q1: 420 cycles median: 420 cycles q3: 420 cycles max: 1398 cycles RESULT TestCondvarSignalNoOp_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {420, 0} count Apparent clock speed: 2648 MHz TestThreadCreateAndJoin: Measuring real time: 76.749 usec (7.67491e-05 sec) per iteration: 0.00767491 sec for 100 iterations 76.823 usec (7.68235e-05 sec) per iteration: 0.100024 sec for 1302 iterations 75.245 usec (7.5245e-05 sec) per iteration: 0.0979689 sec for 1302 iterations 75.380 usec (7.53797e-05 sec) per iteration: 0.0981444 sec for 1302 iterations 74.691 usec (7.46912e-05 sec) per iteration: 0.0972479 sec for 1302 iterations 76.529 usec (7.65291e-05 sec) per iteration: 0.0996409 sec for 1302 iterations mean: 75.733687 usec stddev: 0.808871 usec relative stddev: 1.07% RESULT TestThreadCreateAndJoin: gn_x86-64_with_irt_nnacl_newlib_static= {75.733687, 0.808871} us Measuring clock cycles: first runs (cycles): 210177 222060 269340 227634 364308 226833 205959 200229 201774 196434 ... slowest runs (cycles): ... 227634 231813 231987 234201 238569 246231 261408 269340 272019 364308 min: 196434 cycles q1: 199998 cycles median: 201936 cycles q3: 204630 cycles max: 364308 cycles RESULT TestThreadCreateAndJoin_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {201936, 4632} count Apparent clock speed: 2666 MHz TestThreadWakeup: Measuring real time: 36.420 usec (3.64197e-05 sec) per iteration: 0.00364197 sec for 100 iterations 35.817 usec (3.58174e-05 sec) per iteration: 0.0983187 sec for 2745 iterations 35.391 usec (3.53907e-05 sec) per iteration: 0.0971475 sec for 2745 iterations 35.030 usec (3.50302e-05 sec) per iteration: 0.0961579 sec for 2745 iterations 35.264 usec (3.52643e-05 sec) per iteration: 0.0968005 sec for 2745 iterations 35.004 usec (3.50042e-05 sec) per iteration: 0.0960864 sec for 2745 iterations mean: 35.301351 usec stddev: 0.295795 usec relative stddev: 0.84% RESULT TestThreadWakeup: gn_x86-64_with_irt_nnacl_newlib_static= {35.301351, 0.295795} us Measuring clock cycles: first runs (cycles): 109833 98403 106947 97287 111939 103209 102096 199326 92787 88692 ... slowest runs (cycles): ... 103209 104331 105573 106947 108678 109794 109833 111939 117015 199326 min: 87477 cycles q1: 94275 cycles median: 95379 cycles q3: 96453 cycles max: 199326 cycles RESULT TestThreadWakeup_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {95379, 2178} count Apparent clock speed: 2702 MHz TestCatchingFault: Measuring real time: 39.267 usec (3.92669e-05 sec) per iteration: 0.00392669 sec for 100 iterations 36.331 usec (3.63312e-05 sec) per iteration: 0.0924992 sec for 2546 iterations 36.377 usec (3.63771e-05 sec) per iteration: 0.092616 sec for 2546 iterations 36.437 usec (3.64373e-05 sec) per iteration: 0.0927695 sec for 2546 iterations 36.337 usec (3.63373e-05 sec) per iteration: 0.0925149 sec for 2546 iterations 36.373 usec (3.6373e-05 sec) per iteration: 0.0926057 sec for 2546 iterations mean: 36.371182 usec stddev: 0.037844 usec relative stddev: 0.10% RESULT TestCatchingFault: gn_x86-64_with_irt_nnacl_newlib_static= {36.371182, 0.037844} us Measuring clock cycles: first runs (cycles): 114201 98088 111378 144405 128997 132864 137394 136554 170832 122202 ... slowest runs (cycles): ... 136554 137394 143169 144405 146913 153654 170832 179310 197349 258684 min: 94353 cycles q1: 95805 cycles median: 96261 cycles q3: 98259 cycles max: 258684 cycles RESULT TestCatchingFault_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {96261, 2454} count Apparent clock speed: 2647 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 78.276 usec (7.8276e-05 sec) per iteration: 0.0078276 sec for 100 iterations 75.071 usec (7.50706e-05 sec) per iteration: 0.0958652 sec for 1277 iterations 74.412 usec (7.4412e-05 sec) per iteration: 0.0950241 sec for 1277 iterations 74.350 usec (7.43504e-05 sec) per iteration: 0.0949455 sec for 1277 iterations 75.104 usec (7.51044e-05 sec) per iteration: 0.0959083 sec for 1277 iterations 76.948 usec (7.69482e-05 sec) per iteration: 0.0982629 sec for 1277 iterations mean: 75.177141 usec stddev: 0.940450 usec relative stddev: 1.25% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: gn_x86-64_with_irt_nnacl_newlib_static= {75.177141, 0.940450} us Measuring clock cycles: first runs (cycles): 216270 231411 213351 217524 222471 237978 205119 202128 196749 197325 ... slowest runs (cycles): ... 216270 217524 222471 231411 236532 237642 237978 241176 242733 243810 min: 193095 cycles q1: 197214 cycles median: 199719 cycles q3: 201849 cycles max: 243810 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {199719, 4635} count Apparent clock speed: 2657 MHz [ OK ] nacl_newlib.run_performance_test_irt (6340 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