This issue tracker has been migrated to GitHub, and is currently read-only.
For more information, see the GitHub FAQs in the Python's Developer Guide.

classification
Title: test_logging fails sometimes
Type: behavior Stage: test needed
Components: Versions: Python 3.3
process
Status: closed Resolution: fixed
Dependencies: Superseder:
Assigned To: vinay.sajip Nosy List: eric.araujo, nadeem.vawda, python-dev, r.david.murray, vinay.sajip, vstinner
Priority: normal Keywords:

Created on 2011-05-22 23:16 by vstinner, last changed 2022-04-11 14:57 by admin. This issue is now closed.

Messages (16)
msg136576 - (view) Author: STINNER Victor (vstinner) * (Python committer) Date: 2011-05-22 23:16
test_logging does sometimes fail.

A recent example on Windows XP:
http://www.python.org/dev/buildbot/all/builders/x86%20XP-4%203.x/builds/4645/steps/test/logs/stdio

test test_logging failed -- Traceback (most recent call last):
  File "D:\cygwin\home\db3l\buildarea\3.x.bolen-windows\build\lib\test\test_logging.py", line 3599, in test_rollover
    self.assertTrue(found, msg=msg)
AssertionError: False is not true : No rotated files found, went back 300 seconds
msg136577 - (view) Author: Roundup Robot (python-dev) (Python triager) Date: 2011-05-22 23:17
New changeset 54ffc01b6cc2 by Victor Stinner in branch 'default':
Issue #12151: test_logging writes debug messages to stderr, not stdout
http://hg.python.org/cpython/rev/54ffc01b6cc2
msg136597 - (view) Author: Éric Araujo (eric.araujo) * (Python committer) Date: 2011-05-23 09:30
Victor, you just did what a recent discussion on python-dev strongly recommended not to do: you used ambiguous present tense in your commit message.  “test_logging writes debug messages to stderr, not stdout” does not say whether it is the buggy behavior (unambiguous example for that: “test_logging used to write debug messages to stderr, not stdout”) or the new, fixed behavior (“test_logging now writes debug messages to stderr, not stdout”).  You should use unambiguous phrasing like “make X do Y”, “fix Y with Z”, “don’t W when Z”.
msg136630 - (view) Author: R. David Murray (r.david.murray) * (Python committer) Date: 2011-05-23 13:18
Here's another (unrelated?) failure:

http://www.python.org/dev/buildbot/all/builders/x86%20Gentoo%203.x/builds/173/steps/test/logs/stdio

[ 43/355] test_logging
----------------------------------------
Traceback (most recent call last):
  File "/var/lib/buildslave/3.x.murray-gentoo/build/Lib/socketserver.py", line 582, in process_request_thread
    self.finish_request(request, client_address)
  File "/var/lib/buildslave/3.x.murray-gentoo/build/Lib/socketserver.py", line 323, in finish_request
    self.RequestHandlerClass(request, client_address, self)
  File "/var/lib/buildslave/3.x.murray-gentoo/build/Lib/socketserver.py", line 641, in __init__
    self.finish()
  File "/var/lib/buildslave/3.x.murray-gentoo/build/Lib/socketserver.py", line 713, in finish
    self.socket.sendto(self.wfile.getvalue(), self.client_address)
socket.error: [Errno 9] Bad file descriptor
test test_logging failed -- multiple errors occurred; run in verbose mode for details
msg136693 - (view) Author: Roundup Robot (python-dev) (Python triager) Date: 2011-05-23 20:38
New changeset 7980838e2f55 by Vinay Sajip in branch 'default':
Issue #12151: Added diagnostics to help diagnose intermittent socket errors.
http://hg.python.org/cpython/rev/7980838e2f55
msg136694 - (view) Author: Roundup Robot (python-dev) (Python triager) Date: 2011-05-23 20:49
New changeset 9ea03afc81f4 by Vinay Sajip in branch 'default':
Issue #12151: Correction to diagnostic code.
http://hg.python.org/cpython/rev/9ea03afc81f4
msg136699 - (view) Author: Roundup Robot (python-dev) (Python triager) Date: 2011-05-23 22:00
New changeset 1b1471d50b9e by Vinay Sajip in branch 'default':
Issue #12151: Test now ignores datagram socket errors after server is closed.
http://hg.python.org/cpython/rev/1b1471d50b9e
msg137250 - (view) Author: Vinay Sajip (vinay.sajip) * (Python committer) Date: 2011-05-30 09:42
The last change appears to have fixed the problem; AFAIK there have been no test_logging failures on the buildbots for several days.
msg137252 - (view) Author: STINNER Victor (vstinner) * (Python committer) Date: 2011-05-30 10:00
> The last change appears to have fixed the problem; 
> AFAIK there have been no test_logging failures on the buildbots 
> for several days.

Great job, thanks!
msg154499 - (view) Author: Nadeem Vawda (nadeem.vawda) * (Python committer) Date: 2012-02-27 18:52
A similar failure to msg136576 has cropped up on the Windows 7 bot:

http://www.python.org/dev/buildbot/all/builders/x86%20Windows7%203.x/builds/4469/steps/test/logs/stdio
msg154508 - (view) Author: Vinay Sajip (vinay.sajip) * (Python committer) Date: 2012-02-27 20:25
> A similar failure to msg136576 has cropped up on the Windows 7 bot:

> 
> http://www.python.org/dev/buildbot/all/builders/x86%20Windows7%203.x/builds/4469/steps/test/logs/stdio

Right. Not making excuses, but this one is rather hard to find, as it's pretty hard to reproduce. That's why I haven't made any progress :-(
msg154511 - (view) Author: Nadeem Vawda (nadeem.vawda) * (Python committer) Date: 2012-02-27 21:09
> Not making excuses, but this one is rather hard to find, as it's pretty hard to reproduce. That's why I haven't made any progress :-(

Yeah, not having a reproducible test case is quite a pain.

(I suppose this should be reopened if the failure is still happening...)
msg154562 - (view) Author: Roundup Robot (python-dev) (Python triager) Date: 2012-02-28 19:03
New changeset ce0b9b1328d4 by Vinay Sajip in branch 'default':
Added additional diagnostics to help with #12151.
http://hg.python.org/cpython/rev/ce0b9b1328d4
msg154936 - (view) Author: Roundup Robot (python-dev) (Python triager) Date: 2012-03-05 09:44
New changeset 0457fb8bf39c by Vinay Sajip in branch 'default':
Added more diagnostics for diagnosing #12151.
http://hg.python.org/cpython/rev/0457fb8bf39c
msg154945 - (view) Author: Roundup Robot (python-dev) (Python triager) Date: 2012-03-05 12:48
New changeset a495c41c1e13 by Vinay Sajip in branch 'default':
Issue #12151: Added more info to diagnostics.
http://hg.python.org/cpython/rev/a495c41c1e13
msg155264 - (view) Author: Vinay Sajip (vinay.sajip) * (Python committer) Date: 2012-03-09 20:13
After an increase to a delay in the test(1.01 -> 1.1 seconds) the problem seems to have disappeared. So closing for now, since this error appears not to have occurred over a period of a few days.
History
Date User Action Args
2022-04-11 14:57:17adminsetgithub: 56360
2012-03-09 20:13:36vinay.sajipsetstatus: open -> closed
resolution: fixed
messages: + msg155264
2012-03-05 12:48:54python-devsetmessages: + msg154945
2012-03-05 09:44:33python-devsetmessages: + msg154936
2012-02-28 19:03:07python-devsetmessages: + msg154562
2012-02-27 21:09:41nadeem.vawdasetstatus: closed -> open
type: behavior
messages: + msg154511

resolution: fixed -> (no value)
stage: test needed
2012-02-27 20:25:54vinay.sajipsetmessages: + msg154508
2012-02-27 18:52:41nadeem.vawdasetnosy: + nadeem.vawda
messages: + msg154499
2011-05-30 10:00:50vstinnersetmessages: + msg137252
2011-05-30 09:42:06vinay.sajipsetstatus: open -> closed
resolution: fixed
messages: + msg137250
2011-05-24 06:11:48vinay.sajipsetassignee: vinay.sajip
2011-05-23 22:00:49python-devsetmessages: + msg136699
2011-05-23 20:49:17python-devsetmessages: + msg136694
2011-05-23 20:38:05python-devsetmessages: + msg136693
2011-05-23 13:18:11r.david.murraysetnosy: + r.david.murray
messages: + msg136630
2011-05-23 09:30:47eric.araujosetnosy: + eric.araujo
messages: + msg136597
2011-05-22 23:17:21python-devsetnosy: + python-dev
messages: + msg136577
2011-05-22 23:16:03vstinnercreate