Author vstinner
Recipients gvanrossum, neologix, python-dev, vstinner
Date 2014-01-31.10:55:59
SpamBayes Score -1.0
Marked as misclassified Yes
Message-id <1391165760.21.0.860247372116.issue20452@psf.upfronthosting.co.za>
In-reply-to
Content
More debug info on a new failure: test_timeout_rounding failed because once EPollSelector.select(timeout=0.0099) took dt=0.0086 seconds. dt-timeout=-0.0014 and dt+granularity > timeout is False (granularity=0.001, 1 millisecond).

* Buildbot: "x86 Gentoo Non-Debug 3.x"
* Linux kernel 2.6.35 (i686)
* clock_getres(CLOCK_MONOTONIC) = 1e-9 (1 nanosecond)
* os.sysconf('SC_CLK_TCK') = 100 (1/HZ => 10 ms ?)

http://buildbot.python.org/all/builders/x86%20Gentoo%20Non-Debug%203.x/builds/6121/steps/test/logs/stdio

Re-running test 'test_asyncio' in verbose mode

test_call_later (test.test_asyncio.test_events.EPollEventLoopTests) ...
WARNING: selector.select(timeout=0.09998385910876095295) took dt=0.09878822183236479759 sec (dt-timeout=-0.00119563727639615536)
WARNING: dt+0.00100000000000000002 > timeout? False
WARNING: selector.select(timeout=0.00106247211806476116) took dt=0.00085310707800090313 sec (dt-timeout=-0.00020936504006385803)
WARNING: dt+0.00100000000000000002 > timeout? True
ok

test_run_until_complete (test.test_asyncio.test_events.EPollEventLoopTests) ...
WARNING: selector.select(timeout=0.09997870912775397301) took dt=0.09859465109184384346 sec (dt-timeout=-0.00138405803591012955)
WARNING: dt+0.00100000000000000002 > timeout? False
WARNING: selector.select(timeout=0.00126257794909179211) took dt=0.00086452695541083813 sec (dt-timeout=-0.00039805099368095398)
WARNING: dt+0.00100000000000000002 > timeout? True
ok


test_signal_handling_args (test.test_asyncio.test_events.EPollEventLoopTests) ...
WARNING: selector.select(timeout=0.49998698616400361061) took dt=0.10008734907023608685 sec (dt-timeout=-0.39989963709376752377)
WARNING: dt+0.00100000000000000002 > timeout? False

WARNING: selector.select(timeout=0.39968195999972522259) took dt=0.39848947990685701370 sec (dt-timeout=-0.00119248009286820889)
WARNING: dt+0.00100000000000000002 > timeout? False

WARNING: selector.select(timeout=0.00107327196747064590) took dt=0.00087298289872705936 sec (dt-timeout=-0.00020028906874358654)
WARNING: dt+0.00100000000000000002 > timeout? True
ok

test_signal_handling_while_selecting (test.test_asyncio.test_events.EPollEventLoopTests) ... 
WARNING: selector.select(timeout=None) took dt=0.01009305310435593128 sec
ok

test_subprocess_close_after_finish (test.test_asyncio.test_events.EPollEventLoopTests) ... 
WARNING: selector.select(timeout=None) took dt=0.00544960913248360157 sec
ok

test_timeout_rounding (test.test_asyncio.test_events.EPollEventLoopTests) ...
WARNING: selector.select(timeout=0.00997558492235839367) took dt=0.00856216903775930405 sec (dt-timeout=-0.00141341588459908962)
WARNING: dt+0.00100000000000000002 > timeout? False

WARNING: selector.select(timeout=0.00129276583902537823) took dt=0.00085931317880749702 sec (dt-timeout=-0.00043345266021788120)
WARNING: dt+0.00100000000000000002 > timeout? True

WARNING: selector.select(timeout=0.00007506809197366238) took dt=0.00000555603764951229 sec (dt-timeout=-0.00006951205432415009)
WARNING: dt+0.00100000000000000002 > timeout? True
FAIL

test_signal_handling_args (test.test_asyncio.test_events.PollEventLoopTests) ... WARNING: selector.select(timeout=0.49998601293191313744) took dt=0.10008832695893943310 sec (dt-timeout=-0.39989768597297370434)
WARNING: dt+0.00100000000000000002 > timeout? False
ok

(...)

======================================================================
FAIL: test_timeout_rounding (test.test_asyncio.test_events.EPollEventLoopTests)
----------------------------------------------------------------------
Traceback (most recent call last):
  File "/var/lib/buildslave/3.x.murray-gentoo-wide/build/Lib/test/test_asyncio/test_events.py", line 1195, in test_timeout_rounding
    platform.platform()))
AssertionError: Lists differ: [1, 4, 6, 7] != [1, 3, 5, 6]

First differing element 1:
4
3

- [1, 4, 6, 7]
+ [1, 3, 5, 6] : (0.001, 0.001, namespace(adjustable=False, implementation='clock_gettime(CLOCK_MONOTONIC)', monotonic=True, resolution=1e-09), 100, 'Linux-2.6.35-vs2.3.0.36.32-gentoo-i686-Intel-R-_Core-TM-2_CPU_6600_@_2.40GHz-with-gentoo-2.1')
History
Date User Action Args
2014-01-31 10:56:00vstinnersetrecipients: + vstinner, gvanrossum, neologix, python-dev
2014-01-31 10:56:00vstinnersetmessageid: <1391165760.21.0.860247372116.issue20452@psf.upfronthosting.co.za>
2014-01-31 10:56:00vstinnerlinkissue20452 messages
2014-01-31 10:55:59vstinnercreate