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/cache/buildbot/psycle/slaves/anechoid.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
  LANG=en_US.UTF-8
  LOGNAME=buildbot
  MAIL=/var/mail/buildbot
  PATH=/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games
  PWD=/mnt/cache/buildbot/psycle/slaves/anechoid.retropaganda.info/universalis/build
  SHELL=/bin/sh
  SPEECHD_PORT=6681
  TERM=linux
  USER=buildbot
  XDG_SESSION_COOKIE=13125b62eb8bb1d18a43cc3e48a4dc82-1319821837.236492-1096079231
 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: 4
Leaving test case "thread_hardware_concurrency_test"
Entering test case "measure_clocks_os_test"
clock:                     universalis::os::clocks::utc_since_epoch: absolute: 0, ratio: 1.00031
clock:                     universalis::os::clocks::utc_since_epoch: absolute: 1, ratio: 1.00024
clock:                              universalis::os::clocks::steady: absolute: 0, ratio: 1.00026
clock:                              universalis::os::clocks::steady: absolute: 1, ratio: 1.00025
clock:                     universalis::os::clocks::utc_since_epoch: min:   2.96e-07s, avg: 3.05384e-07s, max: 4.6462e-05s
clock:                              universalis::os::clocks::steady: min:      3e-07s, avg: 3.17141e-07s, max: 4.3418e-05s
clock:                             universalis::os::clocks::process: min:   5.37e-07s, avg: 1.8495e-06s, max:  1.362e-05s
clock:                              universalis::os::clocks::thread: min:   5.16e-07s, avg: 1.2192e-06s, max:  7.536e-06s
clock:            universalis::os::clocks::hires_thread_or_fallback: min:    1.9e-08s, avg: 5.20704e-07s, max:   4.59e-05s
Leaving test case "measure_clocks_os_test"; testing time: 3170ms
Entering test case "measure_clocks_stdlib_test"
clock:                     universalis::os::clocks::utc_since_epoch: absolute: 0, ratio: 1.00026
clock:                     universalis::os::clocks::utc_since_epoch: absolute: 1, ratio: 1.00025
clock:                     universalis::os::clocks::utc_since_epoch: min:   3.77e-07s, avg: 3.8207e-07s, max:   6.44e-07s
clock:                              universalis::os::clocks::steady: absolute: 0, ratio: 1.00025
clock:                              universalis::os::clocks::steady: absolute: 1, ratio: 1.00026
clock:                              universalis::os::clocks::steady: min:   3.95e-07s, avg: 3.9851e-07s, max:   7.13e-07s
Leaving test case "measure_clocks_stdlib_test"
Entering test case "condition_variable_test"
Test case condition_variable_test did not run any assertions
Leaving test case "condition_variable_test"
Entering test case "condition_variable_speed_test"
2 threads: 0: 7.67878e-06s
2 threads: 1: 7.67874e-06s
4 threads: 0: 1.06878e-05s
4 threads: 1: 1.01951e-05s
4 threads: 2: 1.02165e-05s
4 threads: 3: 1.02653e-05s
8 threads: 0: 1.68304e-05s
8 threads: 1: 1.65232e-05s
8 threads: 2: 1.76158e-05s
8 threads: 3: 1.70408e-05s
8 threads: 4: 1.73814e-05s
8 threads: 5: 1.67947e-05s
8 threads: 6: 1.68018e-05s
8 threads: 7: 1.66856e-05s
Test case condition_variable_speed_test did not run any assertions
Leaving test case "condition_variable_speed_test"; testing time: 2420ms
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.00029
clock:                         universalis::os::clocks::detail::omp: absolute: 1, ratio: 1.00025
clock:                         universalis::os::clocks::detail::omp: min:   1.28e-07s, avg: 1.37902e-07s, max: 0.000100934s
posix clocks
clock:          universalis::os::clocks::detail::posix::time_of_day: absolute: 0, ratio: 1.00028
clock:          universalis::os::clocks::detail::posix::time_of_day: absolute: 1, ratio: 1.00025
clock:          universalis::os::clocks::detail::posix::time_of_day: min:      1e-06s, avg: 1.00211e-06s, max:    4.9e-05s
clock:            universalis::os::clocks::detail::posix::real_time: absolute: 0, ratio: 1.00026
clock:            universalis::os::clocks::detail::posix::real_time: absolute: 1, ratio: 1.00025
clock:            universalis::os::clocks::detail::posix::real_time: min:   2.57e-07s, avg: 2.66061e-07s, max: 4.5635e-05s
clock:            universalis::os::clocks::detail::posix::monotonic: absolute: 0, ratio: 1.00026
clock:            universalis::os::clocks::detail::posix::monotonic: absolute: 1, ratio: 1.00027
clock:            universalis::os::clocks::detail::posix::monotonic: min:   2.63e-07s, avg: 2.87007e-07s, max: 5.4631e-05s
clock:     universalis::os::clocks::detail::posix::process_cpu_time: min:      4e-09s, avg: 5.0467e-07s, max: 4.6035e-05s
clock:      universalis::os::clocks::detail::posix::thread_cpu_time: min:      1e-08s, avg: 4.78906e-07s, max:  4.639e-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: 7510ms
Entering test case "affinity_test"
process affinity mask active count: 4
thread affinity mask active count: 4
Test case affinity_test did not run 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 run any assertions
Leaving test case "atomic_compare_and_swap_test"
Entering test case "atomic_compare_and_swap_speed_test"
2 threads: 0: 5.53802e-07s
2 threads: 1: 5.5771e-07s
4 threads: 0: 1.02157e-06s
4 threads: 1: 8.32302e-07s
4 threads: 2: 1.03574e-06s
4 threads: 3: 8.36258e-07s
8 threads: 0: 0s
8 threads: 1: 0s
8 threads: 2: 0s
8 threads: 3: 0s
8 threads: 4: 9.45652e-07s
8 threads: 5: 9.51278e-07s
8 threads: 6: 9.5278e-07s
8 threads: 7: 9.48896e-07s
Test case atomic_compare_and_swap_speed_test did not run any assertions
Leaving test case "atomic_compare_and_swap_speed_test"; testing time: 30ms
Leaving test suite "Master Test Suite"

Test suite "Master Test Suite" passed with:
  3000254 assertions out of 3000254 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:
    1000004 assertions out of 1000004 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=16.563840