Log File contents

(view as text)
./universalis/++wonderbuild/staged-install/usr/local/bin/universalis-unit-tests --log_level=test_suite --report_level=detailed
 in dir /mnt/main/cache/buildbot/psycle/slaves/factoid.retropaganda.info/universalis/build (timeout 1200 secs)
 watching logfiles {}
 argv: ./universalis/++wonderbuild/staged-install/usr/local/bin/universalis-unit-tests --log_level=test_suite --report_level=detailed
 environment:
  HOME=/var/lib/buildbot
  LOGNAME=buildbot
  MAIL=/var/lib/buildbot/Maildir
  PATH=/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
  PWD=/mnt/main/cache/buildbot/psycle/slaves/factoid.retropaganda.info/universalis/build
  SHELL=/bin/sh
  SHLVL=1
  USER=buildbot
  XDG_SESSION_COOKIE=0f2800b1829be68d4c45160046910d64-1307895801.441686-704449820
  _=/usr/bin/buildbot
 closing stdin
 using PTY: True
Running 12 test cases...
Entering test suite "Master Test Suite"
Entering test case "duration_test"
Leaving test case "duration_test"
Entering test case "time_point_test"
Leaving test case "time_point_test"
Entering test case "thread_hardware_concurrency_test"
hardware concurrency: 1
Leaving test case "thread_hardware_concurrency_test"; testing time: 10ms
Entering test case "measure_clocks_os_test"
clock:                     universalis::os::clocks::utc_since_epoch: absolute: 0, ratio: 1.00067
clock:                     universalis::os::clocks::utc_since_epoch: absolute: 1, ratio: 1.00036
clock:                              universalis::os::clocks::steady: absolute: 0, ratio: 1.00043
clock:                              universalis::os::clocks::steady: absolute: 1, ratio: 1.00037
clock:                     universalis::os::clocks::utc_since_epoch: min:  1.424e-06s, avg: 1.43279e-06s, max:   4.74e-06s
clock:                              universalis::os::clocks::steady: min:  1.425e-06s, avg: 3.83064e-06s, max:  0.0120699s
clock:                             universalis::os::clocks::process: min:  1.829e-06s, avg: 5.0612e-06s, max: 3.4082e-05s
clock:                              universalis::os::clocks::thread: min:  1.769e-06s, avg: 2.8654e-06s, max: 1.2723e-05s
clock:            universalis::os::clocks::hires_thread_or_fallback: min:  1.769e-06s, avg: 1.77898e-06s, max: 8.5729e-05s
Leaving test case "measure_clocks_os_test"; testing time: 870ms
Entering test case "measure_clocks_stdlib_test"
clock:                     universalis::os::clocks::utc_since_epoch: absolute: 0, ratio: 1.00046
clock:                     universalis::os::clocks::utc_since_epoch: absolute: 1, ratio: 1.00047
clock:                     universalis::os::clocks::utc_since_epoch: min:  1.425e-06s, avg: 1.43599e-06s, max:  2.295e-06s
clock:                              universalis::os::clocks::steady: absolute: 0, ratio: 1.00045
clock:                              universalis::os::clocks::steady: absolute: 1, ratio: 1.00047
clock:                              universalis::os::clocks::steady: min:   1.44e-06s, avg: 1.46673e-06s, max:   2.76e-06s
Leaving test case "measure_clocks_stdlib_test"
Entering test case "condition_variable_test"
Test case condition_variable_test did not check any assertions
Leaving test case "condition_variable_test"; testing time: 10ms
Entering test case "condition_variable_speed_test"
2 threads: 0: 1.0655e-05s
2 threads: 1: 1.06582e-05s
4 threads: 0: 1.86178e-05s
4 threads: 1: 1.86166e-05s
4 threads: 2: 1.85819e-05s
4 threads: 3: 1.86096e-05s
8 threads: 0: 3.97358e-05s
8 threads: 1: 3.97091e-05s
8 threads: 2: 3.95152e-05s
8 threads: 3: 3.95993e-05s
8 threads: 4: 3.97893e-05s
8 threads: 5: 3.97607e-05s
8 threads: 6: 3.97094e-05s
8 threads: 7: 3.96533e-05s
Test case condition_variable_speed_test did not check any assertions
Leaving test case "condition_variable_speed_test"; testing time: 1540ms
Entering test case "measure_clocks_os_detail_test"
clock:                universalis::os::clocks::detail::iso_std_time: min:          1s, avg:          1s, max:          1s
clock:               universalis::os::clocks::detail::iso_std_clock: min:       0.01s, avg:       0.01s, max:       0.01s
CLOCKS_PER_SEC: 1000000
clock:                         universalis::os::clocks::detail::omp: absolute: 0, ratio: 1.00042
clock:                         universalis::os::clocks::detail::omp: absolute: 1, ratio: 1.00037
clock:                         universalis::os::clocks::detail::omp: min:   9.22e-07s, avg: 1.11761e-06s, max: 0.00804262s
posix clocks
clock:          universalis::os::clocks::detail::posix::time_of_day: absolute: 0, ratio: 1.00046
clock:          universalis::os::clocks::detail::posix::time_of_day: absolute: 1, ratio: 1.00035
clock:          universalis::os::clocks::detail::posix::time_of_day: min:      1e-06s, avg: 1.06152e-06s, max:   0.000911s
clock:            universalis::os::clocks::detail::posix::real_time: absolute: 0, ratio: 1.00037
clock:            universalis::os::clocks::detail::posix::real_time: absolute: 1, ratio: 1.00036
clock:            universalis::os::clocks::detail::posix::real_time: min:   1.29e-06s, avg: 1.35255e-06s, max: 0.00100585s
clock:            universalis::os::clocks::detail::posix::monotonic: absolute: 0, ratio: 1.00039
clock:            universalis::os::clocks::detail::posix::monotonic: absolute: 1, ratio: 1.00038
clock:            universalis::os::clocks::detail::posix::monotonic: min:  1.327e-06s, avg: 1.36314e-06s, max: 0.00109034s
clock:     universalis::os::clocks::detail::posix::process_cpu_time: min:  1.701e-06s, avg: 1.72141e-06s, max: 0.000391824s
clock:      universalis::os::clocks::detail::posix::thread_cpu_time: min:  1.649e-06s, avg: 1.68081e-06s, max: 9.4328e-05s
posix clock_getres
clock: CLOCK_REALTIME, resolution: 1e-09s
clock: CLOCK_MONOTONIC, resolution: 1e-09s
clock: CLOCK_PROCESS_CPUTIME_ID, resolution: 1e-09s
clock: CLOCK_THREAD_CPUTIME_ID, resolution: 1e-09s
Leaving test case "measure_clocks_os_detail_test"; testing time: 22320ms
Entering test case "affinity_test"
process affinity mask active count: 1
thread affinity mask active count: 1
Test case affinity_test did not check any assertions
Leaving test case "affinity_test"
Entering test case "aligned_allocator_test"
Leaving test case "aligned_allocator_test"
Entering test case "atomic_compare_and_swap_test"
Test case atomic_compare_and_swap_test did not check any assertions
Leaving test case "atomic_compare_and_swap_test"; testing time: 10ms
Entering test case "atomic_compare_and_swap_speed_test"
2 threads: 0: 0.00798468s
2 threads: 1: 0.00702235s
4 threads: 0: 0.0160172s
4 threads: 1: 0.0140325s
4 threads: 2: 0.0159991s
4 threads: 3: 0.0149073s
8 threads: 0: 0.0320162s
8 threads: 1: 0.0309991s
8 threads: 2: 0.0300329s
8 threads: 3: 0.0290433s
8 threads: 4: 0.028054s
8 threads: 5: 0.0250853s
8 threads: 6: 0.026065s
8 threads: 7: 0.0270799s
Test case atomic_compare_and_swap_speed_test did not check any assertions
Leaving test case "atomic_compare_and_swap_speed_test"; testing time: 490ms
Leaving test suite "Master Test Suite"

Test suite "Master Test Suite" passed with:
  2100254 assertions out of 2100254 passed
  12 test cases out of 12 passed

  Test case "duration_test" passed with:
    34 assertions out of 34 passed

  Test case "time_point_test" passed with:
    3 assertions out of 3 passed

  Test case "thread_hardware_concurrency_test" passed with:
    1 assertion out of 1 passed

  Test case "measure_clocks_os_test" passed with:
    100004 assertions out of 100004 passed

  Test case "measure_clocks_stdlib_test" passed with:
    104 assertions out of 104 passed

  Test case "condition_variable_test" passed

  Test case "condition_variable_speed_test" passed

  Test case "measure_clocks_os_detail_test" passed with:
    2000008 assertions out of 2000008 passed

  Test case "affinity_test" passed

  Test case "aligned_allocator_test" passed with:
    100 assertions out of 100 passed

  Test case "atomic_compare_and_swap_test" passed

  Test case "atomic_compare_and_swap_speed_test" passed

program finished with exit code 0
elapsedTime=33.236542