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 = 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 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 [20632,2531390336:15:07:26.049414] Native Client module will be loaded at base address 0x0000491500000000 Running suite of 1 benchmarks: Running Benchmark: Life (SIMD version)... RESULT BenchmarkLife: gn_x86-64_with_irt_nnacl_newlib_static= {0.067550, 0.002500} seconds --------------------------------------------------------------- Done running benchmark suite. [ OK ] nacl_newlib.run_benchmark_test_irt (880 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 (66470 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 [20638,2531390336:15:07:40.266799] Native Client module will be loaded at base address 0x000039bb00000000 TestNull: Measuring real time: 0.007 usec (7.16e-09 sec) per iteration: 7.16e-07 sec for 100 iterations 0.002 usec (2.264e-09 sec) per iteration: 2.264e-06 sec for 1000 iterations 0.002 usec (2.0255e-09 sec) per iteration: 2.0255e-05 sec for 10000 iterations 0.002 usec (2.00837e-09 sec) per iteration: 0.0991543 sec for 49370525 iterations 0.002 usec (2.00947e-09 sec) per iteration: 0.0992088 sec for 49370525 iterations 0.002 usec (2.01851e-09 sec) per iteration: 0.0996548 sec for 49370525 iterations 0.002 usec (1.99582e-09 sec) per iteration: 0.0985345 sec for 49370525 iterations 0.002 usec (2.00579e-09 sec) per iteration: 0.0990267 sec for 49370525 iterations mean: 0.002008 usec stddev: 0.000007 usec relative stddev: 0.36% RESULT TestNull: gn_x86-64_with_irt_nnacl_newlib_static= {0.002008, 0.000007} us Measuring clock cycles: first runs (cycles): 246 33 30 21 18 21 18 21 18 30 ... slowest runs (cycles): ... 30 30 30 30 30 30 30 30 33 246 min: 18 cycles q1: 18 cycles median: 21 cycles q3: 27 cycles max: 246 cycles RESULT TestNull_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {21, 9} count Apparent clock speed: 10460 MHz TestNaClSyscall: Measuring real time: 0.163 usec (1.6264e-07 sec) per iteration: 1.6264e-05 sec for 100 iterations 0.157 usec (1.57013e-07 sec) per iteration: 0.0965402 sec for 614854 iterations 0.157 usec (1.57138e-07 sec) per iteration: 0.0966169 sec for 614854 iterations 0.157 usec (1.57056e-07 sec) per iteration: 0.0965666 sec for 614854 iterations 0.157 usec (1.57016e-07 sec) per iteration: 0.0965418 sec for 614854 iterations 0.157 usec (1.57014e-07 sec) per iteration: 0.0965409 sec for 614854 iterations mean: 0.157048 usec stddev: 0.000048 usec relative stddev: 0.03% RESULT TestNaClSyscall: gn_x86-64_with_irt_nnacl_newlib_static= {0.157048, 0.000048} us Measuring clock cycles: first runs (cycles): 684 315 306 306 303 306 303 306 294 297 ... slowest runs (cycles): ... 306 306 306 306 306 306 306 309 315 684 min: 294 cycles q1: 297 cycles median: 303 cycles q3: 306 cycles max: 684 cycles RESULT TestNaClSyscall_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {303, 9} count Apparent clock speed: 1929 MHz TestSetjmpLongjmp: Measuring real time: 0.016 usec (1.632e-08 sec) per iteration: 1.632e-06 sec for 100 iterations 0.012 usec (1.2471e-08 sec) per iteration: 1.2471e-05 sec for 1000 iterations 0.012 usec (1.21082e-08 sec) per iteration: 0.0970906 sec for 8018603 iterations 0.012 usec (1.20798e-08 sec) per iteration: 0.0968627 sec for 8018603 iterations 0.012 usec (1.20679e-08 sec) per iteration: 0.0967674 sec for 8018603 iterations 0.012 usec (1.20591e-08 sec) per iteration: 0.0966969 sec for 8018603 iterations 0.012 usec (1.21827e-08 sec) per iteration: 0.0976881 sec for 8018603 iterations mean: 0.012100 usec stddev: 0.000045 usec relative stddev: 0.37% RESULT TestSetjmpLongjmp: gn_x86-64_with_irt_nnacl_newlib_static= {0.012100, 0.000045} us Measuring clock cycles: first runs (cycles): 525 78 30 33 24 24 24 33 33 33 ... slowest runs (cycles): ... 33 33 33 33 33 33 33 33 78 525 min: 24 cycles q1: 24 cycles median: 33 cycles q3: 33 cycles max: 525 cycles RESULT TestSetjmpLongjmp_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {33, 9} count Apparent clock speed: 2727 MHz TestClockGetTime: Measuring real time: 0.180 usec (1.7962e-07 sec) per iteration: 1.7962e-05 sec for 100 iterations 0.182 usec (1.81643e-07 sec) per iteration: 0.101126 sec for 556730 iterations 0.179 usec (1.78868e-07 sec) per iteration: 0.0995809 sec for 556730 iterations 0.182 usec (1.81556e-07 sec) per iteration: 0.101078 sec for 556730 iterations 0.182 usec (1.81856e-07 sec) per iteration: 0.101245 sec for 556730 iterations 0.185 usec (1.85218e-07 sec) per iteration: 0.103117 sec for 556730 iterations mean: 0.181828 usec stddev: 0.002018 usec relative stddev: 1.11% RESULT TestClockGetTime: gn_x86-64_with_irt_nnacl_newlib_static= {0.181828, 0.002018} us Measuring clock cycles: first runs (cycles): 1809 456 441 441 438 438 450 444 585 435 ... slowest runs (cycles): ... 450 450 450 450 450 450 453 456 585 1809 min: 435 cycles q1: 438 cycles median: 438 cycles q3: 441 cycles max: 1809 cycles RESULT TestClockGetTime_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {438, 3} count Apparent clock speed: 2409 MHz TestTlsVariable: Measuring real time: 0.009 usec (9.43e-09 sec) per iteration: 9.43e-07 sec for 100 iterations 0.006 usec (6.281e-09 sec) per iteration: 6.281e-06 sec for 1000 iterations 0.006 usec (6.0282e-09 sec) per iteration: 6.0282e-05 sec for 10000 iterations 0.006 usec (6.1743e-09 sec) per iteration: 0.102424 sec for 16588699 iterations 0.006 usec (6.10189e-09 sec) per iteration: 0.101222 sec for 16588699 iterations 0.006 usec (6.07204e-09 sec) per iteration: 0.100727 sec for 16588699 iterations 0.006 usec (6.11412e-09 sec) per iteration: 0.101425 sec for 16588699 iterations 0.006 usec (6.10953e-09 sec) per iteration: 0.101349 sec for 16588699 iterations mean: 0.006114 usec stddev: 0.000033 usec relative stddev: 0.55% RESULT TestTlsVariable: gn_x86-64_with_irt_nnacl_newlib_static= {0.006114, 0.000033} us Measuring clock cycles: first runs (cycles): 345 69 21 18 21 21 18 33 30 30 ... slowest runs (cycles): ... 30 30 30 30 30 30 30 33 69 345 min: 18 cycles q1: 21 cycles median: 24 cycles q3: 30 cycles max: 345 cycles RESULT TestTlsVariable_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {24, 9} count Apparent clock speed: 3925 MHz TestMmapAnonymous: Measuring real time: 8.809 usec (8.80893e-06 sec) per iteration: 0.000880893 sec for 100 iterations 8.665 usec (8.66523e-06 sec) per iteration: 0.0983676 sec for 11352 iterations 9.551 usec (9.55102e-06 sec) per iteration: 0.108423 sec for 11352 iterations 8.805 usec (8.80474e-06 sec) per iteration: 0.0999514 sec for 11352 iterations 8.476 usec (8.47619e-06 sec) per iteration: 0.0962218 sec for 11352 iterations 8.551 usec (8.5507e-06 sec) per iteration: 0.0970676 sec for 11352 iterations mean: 8.809576 usec stddev: 0.386979 usec relative stddev: 4.39% RESULT TestMmapAnonymous: gn_x86-64_with_irt_nnacl_newlib_static= {8.809576, 0.386979} us Measuring clock cycles: first runs (cycles): 34494 24114 23481 23691 23085 23310 23109 23136 23190 23148 ... slowest runs (cycles): ... 23334 23388 23394 23409 23481 23571 23691 24114 24339 34494 min: 22965 cycles q1: 23049 cycles median: 23103 cycles q3: 23187 cycles max: 34494 cycles RESULT TestMmapAnonymous_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {23103, 138} count Apparent clock speed: 2622 MHz TestAtomicIncrement: Measuring real time: 0.015 usec (1.452e-08 sec) per iteration: 1.452e-06 sec for 100 iterations 0.010 usec (9.907e-09 sec) per iteration: 9.907e-06 sec for 1000 iterations 0.010 usec (9.6905e-09 sec) per iteration: 9.6905e-05 sec for 10000 iterations 0.010 usec (9.74558e-09 sec) per iteration: 0.100568 sec for 10319384 iterations 0.010 usec (9.93402e-09 sec) per iteration: 0.102513 sec for 10319384 iterations 0.010 usec (9.74002e-09 sec) per iteration: 0.100511 sec for 10319384 iterations 0.010 usec (9.73067e-09 sec) per iteration: 0.100414 sec for 10319384 iterations 0.010 usec (9.6761e-09 sec) per iteration: 0.0998514 sec for 10319384 iterations mean: 0.009765 usec stddev: 0.000088 usec relative stddev: 0.90% RESULT TestAtomicIncrement: gn_x86-64_with_irt_nnacl_newlib_static= {0.009765, 0.000088} us Measuring clock cycles: first runs (cycles): 300 48 48 51 51 51 42 51 51 51 ... slowest runs (cycles): ... 51 51 51 51 51 51 51 51 51 300 min: 42 cycles q1: 51 cycles median: 51 cycles q3: 51 cycles max: 300 cycles RESULT TestAtomicIncrement_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {51, 0} count Apparent clock speed: 5223 MHz TestUncontendedMutexLock: Measuring real time: 0.028 usec (2.754e-08 sec) per iteration: 2.754e-06 sec for 100 iterations 0.023 usec (2.3279e-08 sec) per iteration: 2.3279e-05 sec for 1000 iterations 0.023 usec (2.30352e-08 sec) per iteration: 0.0989526 sec for 4295717 iterations 0.023 usec (2.31599e-08 sec) per iteration: 0.0994884 sec for 4295717 iterations 0.023 usec (2.30864e-08 sec) per iteration: 0.0991728 sec for 4295717 iterations 0.023 usec (2.30812e-08 sec) per iteration: 0.0991505 sec for 4295717 iterations 0.023 usec (2.31168e-08 sec) per iteration: 0.0993034 sec for 4295717 iterations mean: 0.023096 usec stddev: 0.000041 usec relative stddev: 0.18% RESULT TestUncontendedMutexLock: gn_x86-64_with_irt_nnacl_newlib_static= {0.023096, 0.000041} us Measuring clock cycles: first runs (cycles): 426 111 81 90 90 90 90 90 90 90 ... slowest runs (cycles): ... 90 90 90 90 90 90 90 111 114 426 min: 78 cycles q1: 81 cycles median: 90 cycles q3: 90 cycles max: 426 cycles RESULT TestUncontendedMutexLock_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {90, 9} count Apparent clock speed: 3897 MHz TestCondvarSignalNoOp: Measuring real time: 0.271 usec (2.7102e-07 sec) per iteration: 2.7102e-05 sec for 100 iterations 0.171 usec (1.70779e-07 sec) per iteration: 0.0630132 sec for 368976 iterations 0.171 usec (1.70802e-07 sec) per iteration: 0.063022 sec for 368976 iterations 0.171 usec (1.70907e-07 sec) per iteration: 0.0630606 sec for 368976 iterations 0.173 usec (1.72684e-07 sec) per iteration: 0.0637164 sec for 368976 iterations 0.170 usec (1.70293e-07 sec) per iteration: 0.0628342 sec for 368976 iterations mean: 0.171093 usec stddev: 0.000823 usec relative stddev: 0.48% RESULT TestCondvarSignalNoOp: gn_x86-64_with_irt_nnacl_newlib_static= {0.171093, 0.000823} us Measuring clock cycles: first runs (cycles): 1842 483 471 465 471 471 459 462 459 471 ... slowest runs (cycles): ... 474 477 483 486 489 531 534 537 555 1842 min: 453 cycles q1: 459 cycles median: 465 cycles q3: 471 cycles max: 1842 cycles RESULT TestCondvarSignalNoOp_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {465, 12} count Apparent clock speed: 2718 MHz TestThreadCreateAndJoin: Measuring real time: 69.364 usec (6.93636e-05 sec) per iteration: 0.00693636 sec for 100 iterations 66.839 usec (6.68392e-05 sec) per iteration: 0.0963153 sec for 1441 iterations 66.089 usec (6.60888e-05 sec) per iteration: 0.095234 sec for 1441 iterations 65.631 usec (6.5631e-05 sec) per iteration: 0.0945742 sec for 1441 iterations 66.053 usec (6.60528e-05 sec) per iteration: 0.0951821 sec for 1441 iterations 65.713 usec (6.57126e-05 sec) per iteration: 0.0946919 sec for 1441 iterations mean: 66.064879 usec stddev: 0.427229 usec relative stddev: 0.65% RESULT TestThreadCreateAndJoin: gn_x86-64_with_irt_nnacl_newlib_static= {66.064879, 0.427229} us Measuring clock cycles: first runs (cycles): 201900 228951 223431 196977 188595 172644 169212 162036 170448 185661 ... slowest runs (cycles): ... 192849 195915 196590 196977 199131 201900 203379 221472 223431 228951 min: 162036 cycles q1: 172479 cycles median: 173376 cycles q3: 175467 cycles max: 228951 cycles RESULT TestThreadCreateAndJoin_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {173376, 2988} count Apparent clock speed: 2624 MHz TestThreadWakeup: Measuring real time: 35.996 usec (3.59964e-05 sec) per iteration: 0.00359964 sec for 100 iterations 32.003 usec (3.20035e-05 sec) per iteration: 0.0889056 sec for 2778 iterations 31.644 usec (3.16444e-05 sec) per iteration: 0.0879081 sec for 2778 iterations 31.856 usec (3.18559e-05 sec) per iteration: 0.0884956 sec for 2778 iterations 31.694 usec (3.16941e-05 sec) per iteration: 0.0880463 sec for 2778 iterations 31.624 usec (3.16245e-05 sec) per iteration: 0.0878528 sec for 2778 iterations mean: 31.764462 usec stddev: 0.144525 usec relative stddev: 0.45% RESULT TestThreadWakeup: gn_x86-64_with_irt_nnacl_newlib_static= {31.764462, 0.144525} us Measuring clock cycles: first runs (cycles): 89637 86955 98079 61653 104100 98673 80676 88761 156075 93057 ... slowest runs (cycles): ... 98079 98673 101355 104100 104967 110697 110868 121119 139950 156075 min: 61653 cycles q1: 84942 cycles median: 86058 cycles q3: 88815 cycles max: 156075 cycles RESULT TestThreadWakeup_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {86058, 3873} count Apparent clock speed: 2709 MHz TestCatchingFault: Measuring real time: 32.769 usec (3.27686e-05 sec) per iteration: 0.00327686 sec for 100 iterations 30.849 usec (3.08489e-05 sec) per iteration: 0.0941201 sec for 3051 iterations 30.829 usec (3.08287e-05 sec) per iteration: 0.0940582 sec for 3051 iterations 30.633 usec (3.06334e-05 sec) per iteration: 0.0934625 sec for 3051 iterations 30.632 usec (3.06315e-05 sec) per iteration: 0.0934568 sec for 3051 iterations 30.926 usec (3.09256e-05 sec) per iteration: 0.094354 sec for 3051 iterations mean: 30.773627 usec stddev: 0.119709 usec relative stddev: 0.39% RESULT TestCatchingFault: gn_x86-64_with_irt_nnacl_newlib_static= {30.773627, 0.119709} us Measuring clock cycles: first runs (cycles): 99045 84033 95307 112977 100419 114351 115899 131919 104103 127968 ... slowest runs (cycles): ... 100419 104103 105504 106920 112977 114351 115899 120774 127968 131919 min: 79755 cycles q1: 80505 cycles median: 81231 cycles q3: 82251 cycles max: 131919 cycles RESULT TestCatchingFault_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {81231, 1746} count Apparent clock speed: 2640 MHz TestThreadCreateAndJoinAfterSettingFaultHandler: Measuring real time: 67.787 usec (6.77873e-05 sec) per iteration: 0.00677873 sec for 100 iterations 65.748 usec (6.57477e-05 sec) per iteration: 0.0969779 sec for 1475 iterations 67.797 usec (6.7797e-05 sec) per iteration: 0.100001 sec for 1475 iterations 66.983 usec (6.69826e-05 sec) per iteration: 0.0987994 sec for 1475 iterations 67.854 usec (6.78539e-05 sec) per iteration: 0.100085 sec for 1475 iterations 67.288 usec (6.72878e-05 sec) per iteration: 0.0992495 sec for 1475 iterations mean: 67.133827 usec stddev: 0.765009 usec relative stddev: 1.14% RESULT TestThreadCreateAndJoinAfterSettingFaultHandler: gn_x86-64_with_irt_nnacl_newlib_static= {67.133827, 0.765009} us Measuring clock cycles: first runs (cycles): 173580 184386 231069 227208 242868 192348 179949 176400 174561 177813 ... slowest runs (cycles): ... 202056 203073 206859 211263 214377 214899 221070 227208 231069 242868 min: 171048 cycles q1: 175335 cycles median: 176736 cycles q3: 180183 cycles max: 242868 cycles RESULT TestThreadCreateAndJoinAfterSettingFaultHandler_CycleCount: gn_x86-64_with_irt_nnacl_newlib_static= {176736, 4848} count Apparent clock speed: 2633 MHz [ OK ] nacl_newlib.run_performance_test_irt (6539 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