These are not actual doctest failures, these come from the patchbot being confused by these "Warning, slow doctest:" messages. And it seems that the doctest expected time completion is mis-computed for some reason, for, say, 0.19s for a doctest should be acceptable!
On Tue, Apr 23, 2019 at 11:55 AM Clemens Heuberger <clemens.heuber...@aau.at> wrote: > > I forgot to actually append the log. Here it is. > > Am 23.04.19 um 12:30 schrieb Clemens Heuberger: > > On one of my patchbots > > > > > > https://patchbot.sagemath.org/log/0/LinuxMint/18.2/x86_64/4.4.0-138-generic/rk02-math/2019-04-20%2005:53:22 > > > > I get non-transient errors (also when run separately) in > > > > sage -t --long src/sage/doctest/forker.py # 6 doctests failed > > sage -t --long src/sage/doctest/control.py # 4 doctests failed > > > > See the log below. > > > > It seems that this machine is now too slow to run doctests? On the other > > hand, > > `--warn-long 0.0` seems to be a bit excessive. > > > > This is a machine with 2 Intel(R) Xeon(R) CPU E5-2630 v3 @ 2.40GHz > > processors (8 > > cores each), so a total of 16 cores. These are no longer the fastest > > processors > > known, but it is somewhat embarrassing that I'd have to retire this > > patchbot. > > too many failed tests, not using stored timings > Running doctests with ID 2019-04-23-11-37-08-18f8a6be. > Git branch: patchbot/ticket_merged > Using --optional=ccache,dochtml,memlimit,mpir,python2,sage > Doctesting 2 files. > sage -t --long src/sage/doctest/forker.py > ********************************************************************** > File "src/sage/doctest/forker.py", line 1691, in > sage.doctest.forker.DocTestDispatcher.parallel_dispatch > Failed example: > DD.parallel_dispatch() > Expected: > sage -t .../databases/cremona.py > [... tests, ... s] > sage -t .../rings/big_oh.py > [... tests, ... s] > Got: > sage -t --warn-long 0.0 > /local/sage-patchbot/sage/src/sage/databases/cremona.py > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/databases/cremona.py", line 655, > in sage.databases.cremona.MiniCremonaDatabase.__iter__ > Warning, slow doctest: > skip = [next(it) for _ in range(100)] > Test ran for 0.05 s > [129 tests, 0.17 s] > sage -t --warn-long 0.0 > /local/sage-patchbot/sage/src/sage/rings/big_oh.py > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/rings/big_oh.py", line 45, in > sage.rings.big_oh.O > Warning, slow doctest: > R.<u,v> = QQ[[]] > Test ran for 0.02 s > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/rings/big_oh.py", line 53, in > sage.rings.big_oh.O > Warning, slow doctest: > 1/3 + O(7^6) > Test ran for 0.01 s > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/rings/big_oh.py", line 85, in > sage.rings.big_oh.O > Warning, slow doctest: > A.<n> = AsymptoticRing(growth_group='QQ^n * n^QQ * log(n)^QQ', > coefficient_ring=QQ); A > Test ran for 0.04 s > [24 tests, 0.11 s] > ********************************************************************** > File "src/sage/doctest/forker.py", line 1991, in > sage.doctest.forker.DocTestDispatcher.dispatch > Failed example: > DD.dispatch() > Expected: > sage -t .../sage/modules/free_module_homspace.py > [... tests, ... s] > sage -t .../sage/rings/big_oh.py > [... tests, ... s] > Got: > sage -t --warn-long 0.0 > /local/sage-patchbot/sage/src/sage/modules/free_module_homspace.py > ********************************************************************** > File > "/local/sage-patchbot/sage/src/sage/modules/free_module_homspace.py", > line 10, in sage.modules.free_module_homspace > Warning, slow doctest: > E = End(M) > Test ran for 0.01 s > [52 tests, 0.08 s] > sage -t --warn-long 0.0 > /local/sage-patchbot/sage/src/sage/rings/big_oh.py > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/rings/big_oh.py", line 45, in > sage.rings.big_oh.O > Warning, slow doctest: > R.<u,v> = QQ[[]] > Test ran for 0.01 s > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/rings/big_oh.py", line 85, in > sage.rings.big_oh.O > Warning, slow doctest: > A.<n> = AsymptoticRing(growth_group='QQ^n * n^QQ * log(n)^QQ', > coefficient_ring=QQ); A > Test ran for 0.03 s > [24 tests, 0.08 s] > ********************************************************************** > File "src/sage/doctest/forker.py", line 2052, in > sage.doctest.forker.DocTestWorker.__init__ > Failed example: > run_doctests(sage.rings.big_oh) # indirect doctest > Expected: > Running doctests with ID ... > Doctesting 1 file. > sage -t .../sage/rings/big_oh.py > [... tests, ... s] > ---------------------------------------------------------------------- > All tests passed! > ---------------------------------------------------------------------- > Total time for all tests: ... seconds > cpu time: ... seconds > cumulative wall time: ... seconds > Got: > Running doctests with ID 2019-04-23-11-37-17-906e2c77. > Git branch: patchbot/ticket_merged > Using --optional=sage > Doctesting 1 file. > sage -t --warn-long 0.0 > /local/sage-patchbot/sage/src/sage/rings/big_oh.py > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/rings/big_oh.py", line 45, in > sage.rings.big_oh.O > Warning, slow doctest: > R.<u,v> = QQ[[]] > Test ran for 0.02 s > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/rings/big_oh.py", line 85, in > sage.rings.big_oh.O > Warning, slow doctest: > A.<n> = AsymptoticRing(growth_group='QQ^n * n^QQ * log(n)^QQ', > coefficient_ring=QQ); A > Test ran for 0.03 s > [24 tests, 0.10 s] > ---------------------------------------------------------------------- > All tests passed! > ---------------------------------------------------------------------- > Total time for all tests: 0.1 seconds > cpu time: 0.1 seconds > cumulative wall time: 0.1 seconds > ********************************************************************** > File "src/sage/doctest/forker.py", line 2097, in > sage.doctest.forker.DocTestWorker.run > Failed example: > run_doctests(sage.symbolic.units) # indirect doctest > Expected: > Running doctests with ID ... > Doctesting 1 file. > sage -t .../sage/symbolic/units.py > [... tests, ... s] > ---------------------------------------------------------------------- > All tests passed! > ---------------------------------------------------------------------- > Total time for all tests: ... seconds > cpu time: ... seconds > cumulative wall time: ... seconds > Got: > Running doctests with ID 2019-04-23-11-37-17-8a07f670. > Git branch: patchbot/ticket_merged > Using --optional=sage > Doctesting 1 file. > sage -t --warn-long 0.0 > /local/sage-patchbot/sage/src/sage/symbolic/units.py > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/symbolic/units.py", line 11, in > sage.symbolic.units > Warning, slow doctest: > units.length.meter > Test ran for 0.02 s > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/symbolic/units.py", line 53, in > sage.symbolic.units > Warning, slow doctest: > s.convert(units.temperature.celsius) > Test ran for 0.01 s > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/symbolic/units.py", line 502, in > sage.symbolic.units.evalunitdict > Warning, slow doctest: > sage.symbolic.units.evalunitdict() > Test ran for 0.01 s > [89 tests, 0.11 s] > ---------------------------------------------------------------------- > All tests passed! > ---------------------------------------------------------------------- > Total time for all tests: 0.2 seconds > cpu time: 0.1 seconds > cumulative wall time: 0.1 seconds > ********************************************************************** > File "src/sage/doctest/forker.py", line 2374, in > sage.doctest.forker.DocTestTask > Failed example: > ntests, results = DTT(options=DD) > Expected nothing > Got: > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/doctest/sources.py", line 211, > in > sage.doctest.sources.DocTestSource._process_doc > Warning, slow doctest: > doctests, _ = FDS.create_doctests({}) > Test ran for 0.02 s > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/doctest/sources.py", line 213, > in > sage.doctest.sources.DocTestSource._process_doc > Warning, slow doctest: > for dt in doctests: > FDS.qualified_name = dt.name > FDS._process_doc(manual_doctests, dt.docstring, {}, dt.lineno-1) > Test ran for 0.02 s > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/doctest/sources.py", line 264, > in > sage.doctest.sources.DocTestSource._create_doctests > Warning, slow doctest: > doctests, extras = FDS._create_doctests({}) > Test ran for 0.06 s > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/doctest/sources.py", line 540, > in > sage.doctest.sources.FileDocTestSource.__iter__ > Warning, slow doctest: > with open(filename, 'w') as f: > _ = f.write(s) > Test ran for 0.03 s > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/doctest/sources.py", line 693, > in > sage.doctest.sources.FileDocTestSource.create_doctests > Warning, slow doctest: > doctests, extras = FDS.create_doctests(globals()) > Test ran for 0.09 s > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/doctest/sources.py", line 872, > in > sage.doctest.sources.SourceLanguage.parse_docstring > Warning, slow doctest: > doctests, _ = FDS.create_doctests({}) > Test ran for 0.02 s > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/doctest/sources.py", line 873, > in > sage.doctest.sources.SourceLanguage.parse_docstring > Warning, slow doctest: > for dt in doctests: > FDS.qualified_name = dt.name > dt.examples = dt.examples[:-1] # strip off the sig_on() test > assert(FDS.parse_docstring(dt.docstring,{},dt.lineno-1)[0] == dt) > Test ran for 0.02 s > ********************************************************************** > File "src/sage/doctest/forker.py", line 2452, in > sage.doctest.forker.DocTestTask.__call__ > Failed example: > ntests, runner = DTT(options=DD) > Expected nothing > Got: > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/doctest/parsing.py", line 538, > in > sage.doctest.parsing.OriginalSource > Warning, slow doctest: > doctests, extras = FDS.create_doctests(globals()) > Test ran for 0.11 s > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/doctest/parsing.py", line 565, > in > sage.doctest.parsing.OriginalSource.__init__ > Warning, slow doctest: > doctests, extras = FDS.create_doctests(globals()) > Test ran for 0.10 s > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/doctest/parsing.py", line 583, > in > sage.doctest.parsing.OriginalSource.__enter__ > Warning, slow doctest: > doctests, extras = FDS.create_doctests(globals()) > Test ran for 0.09 s > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/doctest/parsing.py", line 603, > in > sage.doctest.parsing.OriginalSource.__exit__ > Warning, slow doctest: > doctests, extras = FDS.create_doctests(globals()) > Test ran for 0.07 s > ********************************************************************** > 6 items had failures: > 1 of 17 in sage.doctest.forker.DocTestDispatcher.dispatch > 1 of 29 in sage.doctest.forker.DocTestDispatcher.parallel_dispatch > 1 of 14 in sage.doctest.forker.DocTestTask > 1 of 14 in sage.doctest.forker.DocTestTask.__call__ > 1 of 2 in sage.doctest.forker.DocTestWorker.__init__ > 1 of 2 in sage.doctest.forker.DocTestWorker.run > [449 tests, 6 failures, 13.52 s] > sage -t --long src/sage/doctest/control.py > ********************************************************************** > File "src/sage/doctest/control.py", line 896, in > sage.doctest.control.DocTestController.run_doctests > Failed example: > DC.run_doctests() > Expected: > Doctesting 1 file. > sage -t .../sage/rings/homset.py > [... tests, ... s] > ---------------------------------------------------------------------- > All tests passed! > ---------------------------------------------------------------------- > Total time for all tests: ... seconds > cpu time: ... seconds > cumulative wall time: ... seconds > Got: > Doctesting 1 file. > sage -t --warn-long 0.0 > /local/sage-patchbot/sage/src/sage/rings/homset.py > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/rings/homset.py", line 34, in > sage.rings.homset.is_RingHomset > Warning, slow doctest: > is_RH(Hom(FreeModule(ZZ,1), FreeModule(QQ,1))) > Test ran for 0.02 s > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/rings/homset.py", line 240, in > sage.rings.homset.RingHomset_quo_ring > Warning, slow doctest: > phi = S.hom([b,a]); phi > Test ran for 0.01 s > [48 tests, 0.10 s] > ---------------------------------------------------------------------- > All tests passed! > ---------------------------------------------------------------------- > Total time for all tests: 0.2 seconds > cpu time: 0.1 seconds > cumulative wall time: 0.1 seconds > ********************************************************************** > File "src/sage/doctest/control.py", line 970, in > sage.doctest.control.DocTestController.cleanup > Failed example: > DC.run() > Expected: > Running doctests with ID ... > Doctesting 1 file. > sage -t .../rings/infinity.py > [... tests, ... s] > ---------------------------------------------------------------------- > All tests passed! > ---------------------------------------------------------------------- > Total time for all tests: ... seconds > cpu time: ... seconds > cumulative wall time: ... seconds > 0 > Got: > Running doctests with ID 2019-04-23-11-38-17-7659638f. > Git branch: patchbot/ticket_merged > Using --optional=sage > Doctesting 1 file. > sage -t --warn-long 0.0 > /local/sage-patchbot/sage/src/sage/rings/infinity.py > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/rings/infinity.py", line 206, in > sage.rings.infinity > Warning, slow doctest: > m = Matrix([Infinity]) > Test ran for 0.01 s > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/rings/infinity.py", line 293, in > sage.rings.infinity.AnInfinity._maxima_init_ > Warning, slow doctest: > maxima(-oo) > Test ran for 0.58 s > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/rings/infinity.py", line 688, in > sage.rings.infinity.UnsignedInfinityRing_class._element_constructor_ > Warning, slow doctest: > K.<a> = QuadraticField(3) > Test ran for 0.05 s > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/rings/infinity.py", line 751, in > sage.rings.infinity.UnsignedInfinityRing_class._coerce_map_from_ > Warning, slow doctest: > UnsignedInfinityRing.has_coerce_map_from(QuadraticField(-163, 'a')) > Test ran for 0.01 s > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/rings/infinity.py", line 755, in > sage.rings.infinity.UnsignedInfinityRing_class._coerce_map_from_ > Warning, slow doctest: > UnsignedInfinityRing.has_coerce_map_from(SymmetricGroup(13)) > Test ran for 0.02 s > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/rings/infinity.py", line 1579, > in > sage.rings.infinity.MinusInfinity._gap_init_ > Warning, slow doctest: > gap(-Infinity) > Test ran for 0.28 s > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/rings/infinity.py", line 1581, > in > sage.rings.infinity.MinusInfinity._gap_init_ > Warning, slow doctest: > libgap(-Infinity) > Test ran for 0.24 s > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/rings/infinity.py", line 1709, > in > sage.rings.infinity.test_comparison > Warning, slow doctest: > for R in rings: > print('testing {}'.format(R)) > test_comparison(R) > Test ran for 0.07 s > [299 tests, 1.47 s] > ---------------------------------------------------------------------- > All tests passed! > ---------------------------------------------------------------------- > Total time for all tests: 1.7 seconds > cpu time: 0.7 seconds > cumulative wall time: 1.5 seconds > 0 > ********************************************************************** > File "src/sage/doctest/control.py", line 1160, in > sage.doctest.control.DocTestController.run > Failed example: > DC.run() > Expected: > Running doctests with ID ... > Doctesting 1 file. > sage -t .../sage/sets/non_negative_integers.py > [... tests, ... s] > ---------------------------------------------------------------------- > All tests passed! > ---------------------------------------------------------------------- > Total time for all tests: ... seconds > cpu time: ... seconds > cumulative wall time: ... seconds > 0 > Got: > Running doctests with ID 2019-04-23-11-38-19-4a509797. > Git branch: patchbot/ticket_merged > Using --optional=sage > Doctesting 1 file. > sage -t --warn-long 0.0 > /local/sage-patchbot/sage/src/sage/sets/non_negative_integers.py > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/sets/non_negative_integers.py", > line 31, in sage.sets.non_negative_integers.NonNegativeIntegers > Warning, slow doctest: > TestSuite(NN).run() > Test ran for 0.02 s > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/sets/non_negative_integers.py", > line 65, in sage.sets.non_negative_integers.NonNegativeIntegers > Warning, slow doctest: > TestSuite(NN).run() > Test ran for 0.01 s > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/sets/non_negative_integers.py", > line 80, in sage.sets.non_negative_integers.NonNegativeIntegers.__init__ > Warning, slow doctest: > TestSuite(NN).run() > Test ran for 0.01 s > [42 tests, 0.07 s] > ---------------------------------------------------------------------- > All tests passed! > ---------------------------------------------------------------------- > Total time for all tests: 0.1 seconds > cpu time: 0.1 seconds > cumulative wall time: 0.1 seconds > 0 > ********************************************************************** > File "src/sage/doctest/control.py", line 1253, in > sage.doctest.control.run_doctests > Failed example: > run_doctests(sage.rings.infinity) > Expected: > Running doctests with ID ... > Doctesting 1 file. > sage -t .../sage/rings/infinity.py > [... tests, ... s] > ---------------------------------------------------------------------- > All tests passed! > ---------------------------------------------------------------------- > Total time for all tests: ... seconds > cpu time: ... seconds > cumulative wall time: ... seconds > Got: > Running doctests with ID 2019-04-23-11-38-20-73910002. > Git branch: patchbot/ticket_merged > Using --optional=sage > Doctesting 1 file. > sage -t --warn-long 0.0 > /local/sage-patchbot/sage/src/sage/rings/infinity.py > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/rings/infinity.py", line 206, in > sage.rings.infinity > Warning, slow doctest: > m = Matrix([Infinity]) > Test ran for 0.01 s > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/rings/infinity.py", line 293, in > sage.rings.infinity.AnInfinity._maxima_init_ > Warning, slow doctest: > maxima(-oo) > Test ran for 0.54 s > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/rings/infinity.py", line 688, in > sage.rings.infinity.UnsignedInfinityRing_class._element_constructor_ > Warning, slow doctest: > K.<a> = QuadraticField(3) > Test ran for 0.04 s > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/rings/infinity.py", line 751, in > sage.rings.infinity.UnsignedInfinityRing_class._coerce_map_from_ > Warning, slow doctest: > UnsignedInfinityRing.has_coerce_map_from(QuadraticField(-163, 'a')) > Test ran for 0.01 s > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/rings/infinity.py", line 755, in > sage.rings.infinity.UnsignedInfinityRing_class._coerce_map_from_ > Warning, slow doctest: > UnsignedInfinityRing.has_coerce_map_from(SymmetricGroup(13)) > Test ran for 0.02 s > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/rings/infinity.py", line 1579, > in > sage.rings.infinity.MinusInfinity._gap_init_ > Warning, slow doctest: > gap(-Infinity) > Test ran for 0.27 s > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/rings/infinity.py", line 1581, > in > sage.rings.infinity.MinusInfinity._gap_init_ > Warning, slow doctest: > libgap(-Infinity) > Test ran for 0.21 s > ********************************************************************** > File "/local/sage-patchbot/sage/src/sage/rings/infinity.py", line 1709, > in > sage.rings.infinity.test_comparison > Warning, slow doctest: > for R in rings: > print('testing {}'.format(R)) > test_comparison(R) > Test ran for 0.19 s > [299 tests, 1.51 s] > ---------------------------------------------------------------------- > All tests passed! > ---------------------------------------------------------------------- > Total time for all tests: 1.7 seconds > cpu time: 0.7 seconds > cumulative wall time: 1.5 seconds > ********************************************************************** > 4 items had failures: > 1 of 12 in sage.doctest.control.DocTestController.cleanup > 1 of 13 in sage.doctest.control.DocTestController.run > 1 of 9 in sage.doctest.control.DocTestController.run_doctests > 1 of 2 in sage.doctest.control.run_doctests > [206 tests, 4 failures, 5.63 s] > ---------------------------------------------------------------------- > sage -t --long src/sage/doctest/forker.py # 6 doctests failed > sage -t --long src/sage/doctest/control.py # 4 doctests failed > ---------------------------------------------------------------------- > Total time for all tests: 73.5 seconds > cpu time: 5.6 seconds > cumulative wall time: 19.1 seconds > > -- > You received this message because you are subscribed to the Google Groups > "sage-release" group. > To unsubscribe from this group and stop receiving emails from it, send an > email to sage-release+unsubscr...@googlegroups.com. > To post to this group, send email to sage-release@googlegroups.com. > Visit this group at https://groups.google.com/group/sage-release. > For more options, visit https://groups.google.com/d/optout. -- You received this message because you are subscribed to the Google Groups "sage-release" group. To unsubscribe from this group and stop receiving emails from it, send an email to sage-release+unsubscr...@googlegroups.com. To post to this group, send email to sage-release@googlegroups.com. Visit this group at https://groups.google.com/group/sage-release. For more options, visit https://groups.google.com/d/optout.