mpz reuse test takes too much time

[Vincent Lefevre]

On 2016-09-28 15:11:28 +0200, Torbjörn Granlund wrote:
> Vincent Lefevre <vincent at vinc17.net> writes:
>   I just did "./configure" and "make" and "make check", and
>   noticed that the "reuse" test took around 110 seconds. All
>   the other tests are much faster.
>   
> It makes no sense that it takes 110 seconds.  Your machine must be in
> bad health.  Perhaps it has problems with CPU temp and is downclocked?

I had checked with /proc/cpuinfo, and ~ 2100 MHz was the reported
frequency.

But there may have been a temporary problem, as I now get:

./reuse  27.56s user 0.15s system 100% cpu 27.698 total

(and contrary to the previous test, where the machine was not loaded,
the machine is currently fully loaded, so that the 27.56s has been
obtained on a core shared with another process, i.e. it could be
around 15s if the machine were not loaded).

Here are the details of the timings for the mpz tests:

fraise:...p-6.1.1/tests/mpz> for i in *(*); eval time ./$i
./bit  0.13s user 0.02s system 93% cpu 0.159 total
./convert  2.14s user 0.02s system 99% cpu 2.162 total
./dive  1.30s user 0.02s system 99% cpu 1.331 total
./dive_ui  0.35s user 0.02s system 98% cpu 0.379 total
./io  0.06s user 0.08s system 93% cpu 0.150 total
./logic  0.16s user 0.03s system 94% cpu 0.200 total
./reuse  27.56s user 0.15s system 100% cpu 27.698 total
./t-addsub  0.08s user 0.02s system 80% cpu 0.120 total
./t-aorsmul  0.14s user 0.01s system 89% cpu 0.161 total
./t-bin  0.04s user 0.00s system 69% cpu 0.057 total
./t-cdiv_ui  0.01s user 0.02s system 66% cpu 0.042 total
./t-cmp  0.00s user 0.01s system 39% cpu 0.031 total
./t-cmp_d  0.01s user 0.00s system 47% cpu 0.025 total
./t-cmp_si  0.01s user 0.00s system 43% cpu 0.028 total
./t-cong  0.34s user 0.01s system 94% cpu 0.375 total
./t-cong_2exp  0.01s user 0.02s system 72% cpu 0.039 total
./t-div_2exp  0.00s user 0.02s system 70% cpu 0.028 total
./t-divis  0.58s user 0.01s system 96% cpu 0.610 total
./t-divis_2exp  0.00s user 0.01s system 46% cpu 0.026 total
./t-export  0.00s user 0.01s system 49% cpu 0.024 total
./t-fac_ui  0.54s user 0.04s system 97% cpu 0.586 total
./t-fdiv  0.32s user 0.01s system 95% cpu 0.339 total
./t-fdiv_ui  0.01s user 0.02s system 64% cpu 0.037 total
./t-fib_ui  0.03s user 0.01s system 75% cpu 0.053 total
./t-fits  0.00s user 0.01s system 46% cpu 0.026 total
./t-gcd  6.43s user 0.02s system 99% cpu 6.458 total
./t-gcd_ui  0.00s user 0.01s system 53% cpu 0.030 total
./t-get_d  0.00s user 0.01s system 47% cpu 0.034 total
./t-get_d_2exp  0.00s user 0.01s system 44% cpu 0.036 total
./t-get_si  0.00s user 0.02s system 77% cpu 0.026 total
./t-hamdist  0.02s user 0.02s system 83% cpu 0.053 total
./t-import  0.00s user 0.02s system 54% cpu 0.037 total
./t-inp_str  0.00s user 0.06s system 14% cpu 0.400 total
./t-invert  0.78s user 0.02s system 98% cpu 0.804 total
./t-io_raw  0.05s user 0.26s system 19% cpu 1.571 total
./t-jac  1.80s user 0.06s system 98% cpu 1.884 total
./t-lcm  0.02s user 0.06s system 84% cpu 0.095 total
./t-limbs  0.02s user 0.04s system 74% cpu 0.080 total
./t-lucnum_ui  0.04s user 0.02s system 79% cpu 0.081 total
./t-mfac_uiui  0.01s user 0.04s system 67% cpu 0.065 total
./t-mul  1.09s user 0.05s system 97% cpu 1.163 total
./t-mul_i  0.00s user 0.01s system 46% cpu 0.026 total
./t-nextprime  0.16s user 0.01s system 92% cpu 0.182 total
./t-oddeven  0.00s user 0.01s system 44% cpu 0.027 total
./t-perfpow  0.98s user 0.02s system 98% cpu 1.009 total
./t-perfsqr  0.18s user 0.02s system 95% cpu 0.209 total
./t-popcount  0.01s user 0.02s system 73% cpu 0.038 total
./t-pow  0.50s user 0.00s system 97% cpu 0.523 total
./t-powm  2.34s user 0.01s system 99% cpu 2.367 total
./t-powm_ui  3.69s user 0.08s system 99% cpu 3.774 total
./t-pprime_p  0.27s user 0.02s system 92% cpu 0.310 total
./t-primorial_ui  0.02s user 0.01s system 58% cpu 0.048 total
./t-remove  6.47s user 0.01s system 99% cpu 6.493 total
./t-root  1.14s user 0.02s system 98% cpu 1.172 total
./t-scan  0.00s user 0.06s system 66% cpu 0.102 total
./t-set_d  0.01s user 0.06s system 80% cpu 0.084 total
./t-set_f  0.01s user 0.05s system 76% cpu 0.084 total
./t-set_si  0.00s user 0.06s system 77% cpu 0.083 total
./t-set_str  0.01s user 0.06s system 75% cpu 0.085 total
./t-sizeinbase  0.03s user 0.04s system 78% cpu 0.082 total
./t-sqrtrem  0.85s user 0.06s system 97% cpu 0.927 total
./t-tdiv  2.45s user 0.07s system 99% cpu 2.531 total
./t-tdiv_ui  0.26s user 0.01s system 96% cpu 0.279 total

But note that since "reuse" is much slower than the other tests,
one doesn't benefit much from the parallelization of the tests.

-- 
Vincent Lefèvre <vincent at vinc17.net> - Web: <https://www.vinc17.net/>
100% accessible validated (X)HTML - Blog: <https://www.vinc17.net/blog/>
Work: CR INRIA - computer arithmetic / AriC project (LIP, ENS-Lyon)