GeezerVison is showing github.com — [3.11] gh-106883 Fix deadlock in threaded application by diegorusso · Pull Request #117332 · python/cpython
Conversation
diegorusso
commented
Mar 28, 2024
•
edited by bedevere-app
Bot
Loading
Uh oh!
There was an error while loading. Please reload this page.
When using threaded applications, there is a high risk of a deadlock in the intepreter.
It's a lock ordering deadlock with HEAD_LOCK(&_PyRuntime); and the GIL.
It has been suggested to disable GC during the
_PyThread_CurrentFrames() and _PyThread_CurrentExceptions() calls.
Sorry, something went wrong.
Uh oh!
There was an error while loading. Please reload this page.
When using threaded applications, there is a high risk of a deadlock in
the intepreter.
It's a lock ordering deadlock with HEAD_LOCK(&_PyRuntime); and the GIL.
It has been suggested to disable GC during the
_PyThread_CurrentFrames() and _PyThread_CurrentExceptions() calls.
|
This will break users that have disabled the gc, we need to do the dance only if it has not been disabled
|
diegorusso reacted with thumbs up emoji
Sorry, something went wrong.
Uh oh!
There was an error while loading. Please reload this page.
|
Also there are return paths for errors where this leaves the gc disabled
|
diegorusso reacted with thumbs up emoji
Sorry, something went wrong.
Uh oh!
There was an error while loading. Please reload this page.
diegorusso
commented
Mar 28, 2024
•
edited
Loading
Uh oh!
There was an error while loading. Please reload this page.
|
@pablogsal thanks for your comments! Of course this was more an "attempt" to check if the approach was in the right direction and to check if the C API I used are the correct ones. Next week I will produce a more complete patch.
|
Sorry, something went wrong.
Uh oh!
There was an error while loading. Please reload this page.
There was a problem hiding this comment.
Choose a reason for hiding this comment
The reason will be displayed to describe this comment to others. Learn more.
Yeah, I think this is the right general approach. I left some inline comments to expand on what @pablogsal wrote.
Sorry, something went wrong.
Uh oh!
There was an error while loading. Please reload this page.
diegorusso reacted with thumbs up emoji
Uh oh!
There was an error while loading. Please reload this page.
Uh oh!
There was an error while loading. Please reload this page.
Uh oh!
There was an error while loading. Please reload this page.
Uh oh!
There was an error while loading. Please reload this page.
When using threaded applications, there is a high risk of a deadlock in
the intepreter.
It's a lock ordering deadlock with HEAD_LOCK(&_PyRuntime); and the GIL.
By disabling the GC during the _PyThread_CurrentFrames() and
_PyThread_CurrentExceptions() calls fixes the issue.
|
@pablogsal @colesbury I've updated the PR addressing your comments and adding a test to verify the patch. Please let me know what you think.
|
Sorry, something went wrong.
Uh oh!
There was an error while loading. Please reload this page.
|
It is currently failing on the Address Sanitiser build, I'm investigating what the problem is.
|
Sorry, something went wrong.
Uh oh!
There was an error while loading. Please reload this page.
|
@pablogsal @colesbury
I have pushed a new test for it. It is slower compare to the existent tests: it takes about 10 seconds on my machine (Parallels on MBP) when it succeeds. If it fails (it shouldn't) then it's the timeout of 40 seconds.
When used in the build with the address sanitizer enabled, it is skipped because it will be way slower.
My questions to you are: do we really need the test? if yes, can we mark it as slow?
Thoughts?
|
Sorry, something went wrong.
Uh oh!
There was an error while loading. Please reload this page.
pablogsal
commented
Apr 9, 2024
•
edited
Loading
Uh oh!
There was an error while loading. Please reload this page.
|
We have a thing to consider which is that 3.11.9 was the last bug fix release of 3.11 and that is already released. I could make an extra bug fix release to include a fix for this PR but technically this is too late. I will check with the other release managers to see what they think
|
diegorusso, ambv, and gediminasel reacted with thumbs up emoji
Sorry, something went wrong.
Uh oh!
There was an error while loading. Please reload this page.
|
Sure, no problem. Whatever the outcome is, it has been entertaining debugging it :)
|
Sorry, something went wrong.
Uh oh!
There was an error while loading. Please reload this page.
|
I just want to give a gentle ping on this PR. What will be its fate? Merge or close? :) Joking apart, it would be nice to have a closure.
|
gediminasel and faulaire reacted with thumbs up emoji
Sorry, something went wrong.
Uh oh!
There was an error while loading. Please reload this page.
pablogsal
commented
May 28, 2024
•
edited
Loading
Uh oh!
There was an error while loading. Please reload this page.
|
Unfortunately unless you want to argue that this is a security fix, we cannot backport bug fixes anymore to 3.11.
If this issue is something that's affecting a considerable number of users I am willing to reconsider, though. But in any case the release will be folded with the next security release of 3.11.
|
Sorry, something went wrong.
Uh oh!
There was an error while loading. Please reload this page.
|
Hello, I don't think I can argue that this is a security fix (it isn't :) ) but also I left a comment on dask/distributed#8616 issue as they bumped into the same problem. I requested them to comment directly here: if they have found a way to workaround the issue, we can just close the PR otherwise we need to understand what the blockers are and what's the magnitude of the issue.
|
Sorry, something went wrong.
Uh oh!
There was an error while loading. Please reload this page.
|
Let's check what the other RMs think just to get some consensus on this.
CC @ambv @Yhg1s @hugovk
|
Sorry, something went wrong.
Uh oh!
There was an error while loading. Please reload this page.
tvalentyn
commented
Feb 12, 2025
•
edited
Loading
Uh oh!
There was an error while loading. Please reload this page.
If this issue is something that's affecting a considerable number of users I am willing to reconsider, though. But in any case the release will be folded with the next security release of 3.11.
I work on a python library, and in the last ~2 months at least 3 of our customers have reached out for help debugging a deadlock situation. after much of very involved debugging, we attributed the issue to: #106883 .
|
Sorry, something went wrong.
Uh oh!
There was an error while loading. Please reload this page.
Sorry, something went wrong.
Uh oh!
There was an error while loading. Please reload this page.
|
3.11 is yours, Pablo, so it's really up to you. I recall working around this issue at Google, and while I think fixing it is worth while, as RM I'm not sure fixing it in 3.11, at this point is worth the risk...
That said, the risk is very small, and it is the case that we're pushing for more use of threads (and testing with threads) with free-threaded Python on the horizon, and it would definitely be nice if threads were less flaky in 3.11... But that's thinking more with my free-threaded contributor hat than my RM hat :P
|
Sorry, something went wrong.
Uh oh!
There was an error while loading. Please reload this page.
|
I just wanted to double check with you and other RMs. But I am convinced to backport this
|
Sorry, something went wrong.
Uh oh!
There was an error while loading. Please reload this page.
|
merged, this will go in the next security release
|
diegorusso reacted with hooray emoji
Sorry, something went wrong.
Uh oh!
There was an error while loading. Please reload this page.
⚠️⚠️⚠️ Buildbot failure ⚠️⚠️⚠️
Hi! The buildbot PPC64LE Fedora Stable 3.11 (tier-2) has failed when building commit 6b37486.
What do you need to do:
- Don't panic.
- Check the buildbot page in the devguide if you don't know what the buildbots are or how they work.
- Go to the page of the buildbot that failed (https://buildbot.python.org/#/builders/933/builds/1172) and take a look at the build logs.
- Check if the failure is related to this commit (6b37486) or if it is a false positive.
- If the failure is related to this commit, please, reflect that on the issue and make a new Pull Request with a fix.
You can take a look at the buildbot page here:
https://buildbot.python.org/#/builders/933/builds/1172
Failed tests:
Failed subtests:
- test_current_frames_exceptions_deadlock - test.test_sys.SysModuleTest.test_current_frames_exceptions_deadlock
Summary of the results of the build (if available):
==
Click to see traceback logs
Traceback (most recent call last):
File "/home/buildbot/buildarea/3.11.cstratak-fedora-stable-ppc64le/build/Lib/test/support/threading_helper.py", line 66, in decorator
return func(*args)
^^^^^^^^^^^
File "/home/buildbot/buildarea/3.11.cstratak-fedora-stable-ppc64le/build/Lib/test/test_sys.py", line 530, in test_current_frames_exceptions_deadlock
support.wait_process(pid, exitcode=0, timeout=TIMEOUT)
File "/home/buildbot/buildarea/3.11.cstratak-fedora-stable-ppc64le/build/Lib/test/support/__init__.py", line 1963, in wait_process
raise AssertionError(f"process {pid} is still running "
AssertionError: process 2699152 is still running after 40.3 seconds
Traceback (most recent call last):
File "/home/buildbot/buildarea/3.11.cstratak-fedora-stable-ppc64le/build/Lib/test/support/threading_helper.py", line 66, in decorator
return func(*args)
^^^^^^^^^^^
File "/home/buildbot/buildarea/3.11.cstratak-fedora-stable-ppc64le/build/Lib/test/test_sys.py", line 530, in test_current_frames_exceptions_deadlock
support.wait_process(pid, exitcode=0, timeout=TIMEOUT)
File "/home/buildbot/buildarea/3.11.cstratak-fedora-stable-ppc64le/build/Lib/test/support/__init__.py", line 1963, in wait_process
raise AssertionError(f"process {pid} is still running "
AssertionError: process 2676978 is still running after 40.3 seconds
|
Sorry, something went wrong.
Uh oh!
There was an error while loading. Please reload this page.
⚠️⚠️⚠️ Buildbot failure ⚠️⚠️⚠️
Hi! The buildbot PPC64LE Fedora Stable Clang 3.11 (tier-3) has failed when building commit 6b37486.
What do you need to do:
- Don't panic.
- Check the buildbot page in the devguide if you don't know what the buildbots are or how they work.
- Go to the page of the buildbot that failed (https://buildbot.python.org/#/builders/967/builds/1171) and take a look at the build logs.
- Check if the failure is related to this commit (6b37486) or if it is a false positive.
- If the failure is related to this commit, please, reflect that on the issue and make a new Pull Request with a fix.
You can take a look at the buildbot page here:
https://buildbot.python.org/#/builders/967/builds/1171
Failed tests:
Failed subtests:
- test_current_frames_exceptions_deadlock - test.test_sys.SysModuleTest.test_current_frames_exceptions_deadlock
Summary of the results of the build (if available):
==
Click to see traceback logs
Traceback (most recent call last):
File "/home/buildbot/buildarea/3.11.cstratak-fedora-stable-ppc64le.clang/build/Lib/test/support/threading_helper.py", line 66, in decorator
return func(*args)
^^^^^^^^^^^
File "/home/buildbot/buildarea/3.11.cstratak-fedora-stable-ppc64le.clang/build/Lib/test/test_sys.py", line 530, in test_current_frames_exceptions_deadlock
support.wait_process(pid, exitcode=0, timeout=TIMEOUT)
File "/home/buildbot/buildarea/3.11.cstratak-fedora-stable-ppc64le.clang/build/Lib/test/support/__init__.py", line 1963, in wait_process
raise AssertionError(f"process {pid} is still running "
AssertionError: process 2710960 is still running after 46.9 seconds
Traceback (most recent call last):
File "/home/buildbot/buildarea/3.11.cstratak-fedora-stable-ppc64le.clang/build/Lib/test/support/threading_helper.py", line 66, in decorator
return func(*args)
^^^^^^^^^^^
File "/home/buildbot/buildarea/3.11.cstratak-fedora-stable-ppc64le.clang/build/Lib/test/test_sys.py", line 530, in test_current_frames_exceptions_deadlock
support.wait_process(pid, exitcode=0, timeout=TIMEOUT)
File "/home/buildbot/buildarea/3.11.cstratak-fedora-stable-ppc64le.clang/build/Lib/test/support/__init__.py", line 1963, in wait_process
raise AssertionError(f"process {pid} is still running "
AssertionError: process 2732847 is still running after 40.3 seconds
|
Sorry, something went wrong.
Uh oh!
There was an error while loading. Please reload this page.
|
Ugh, this is not good. @diegorusso do you mind checking? Otherwise we may need to revert :(
|
diegorusso reacted with thumbs up emoji
Sorry, something went wrong.
Uh oh!
There was an error while loading. Please reload this page.
⚠️⚠️⚠️ Buildbot failure ⚠️⚠️⚠️
Hi! The buildbot PPC64LE Fedora Stable Refleaks 3.11 (tier-2) has failed when building commit 6b37486.
What do you need to do:
- Don't panic.
- Check the buildbot page in the devguide if you don't know what the buildbots are or how they work.
- Go to the page of the buildbot that failed (https://buildbot.python.org/#/builders/966/builds/843) and take a look at the build logs.
- Check if the failure is related to this commit (6b37486) or if it is a false positive.
- If the failure is related to this commit, please, reflect that on the issue and make a new Pull Request with a fix.
You can take a look at the buildbot page here:
https://buildbot.python.org/#/builders/966/builds/843
Failed tests:
Failed subtests:
- test_current_frames_exceptions_deadlock - test.test_sys.SysModuleTest.test_current_frames_exceptions_deadlock
Summary of the results of the build (if available):
==
Click to see traceback logs
Traceback (most recent call last):
File "/home/buildbot/buildarea/3.11.cstratak-fedora-stable-ppc64le.refleak/build/Lib/test/support/threading_helper.py", line 66, in decorator
return func(*args)
^^^^^^^^^^^
File "/home/buildbot/buildarea/3.11.cstratak-fedora-stable-ppc64le.refleak/build/Lib/test/test_sys.py", line 530, in test_current_frames_exceptions_deadlock
support.wait_process(pid, exitcode=0, timeout=TIMEOUT)
File "/home/buildbot/buildarea/3.11.cstratak-fedora-stable-ppc64le.refleak/build/Lib/test/support/__init__.py", line 1963, in wait_process
raise AssertionError(f"process {pid} is still running "
AssertionError: process 3111838 is still running after 40.3 seconds
Traceback (most recent call last):
File "/home/buildbot/buildarea/3.11.cstratak-fedora-stable-ppc64le.refleak/build/Lib/test/support/threading_helper.py", line 66, in decorator
return func(*args)
^^^^^^^^^^^
File "/home/buildbot/buildarea/3.11.cstratak-fedora-stable-ppc64le.refleak/build/Lib/test/test_sys.py", line 530, in test_current_frames_exceptions_deadlock
support.wait_process(pid, exitcode=0, timeout=TIMEOUT)
File "/home/buildbot/buildarea/3.11.cstratak-fedora-stable-ppc64le.refleak/build/Lib/test/support/__init__.py", line 1963, in wait_process
raise AssertionError(f"process {pid} is still running "
AssertionError: process 3114961 is still running after 40.3 seconds
|
Sorry, something went wrong.
Uh oh!
There was an error while loading. Please reload this page.
⚠️⚠️⚠️ Buildbot failure ⚠️⚠️⚠️
Hi! The buildbot PPC64LE RHEL8 3.11 (tier-2) has failed when building commit 6b37486.
What do you need to do:
- Don't panic.
- Check the buildbot page in the devguide if you don't know what the buildbots are or how they work.
- Go to the page of the buildbot that failed (https://buildbot.python.org/#/builders/997/builds/1182) and take a look at the build logs.
- Check if the failure is related to this commit (6b37486) or if it is a false positive.
- If the failure is related to this commit, please, reflect that on the issue and make a new Pull Request with a fix.
You can take a look at the buildbot page here:
https://buildbot.python.org/#/builders/997/builds/1182
Failed tests:
Failed subtests:
- test_current_frames_exceptions_deadlock - test.test_sys.SysModuleTest.test_current_frames_exceptions_deadlock
Summary of the results of the build (if available):
==
Click to see traceback logs
Traceback (most recent call last):
File "/home/buildbot/buildarea/3.11.cstratak-RHEL8-ppc64le/build/Lib/test/support/threading_helper.py", line 66, in decorator
return func(*args)
^^^^^^^^^^^
File "/home/buildbot/buildarea/3.11.cstratak-RHEL8-ppc64le/build/Lib/test/test_sys.py", line 530, in test_current_frames_exceptions_deadlock
support.wait_process(pid, exitcode=0, timeout=TIMEOUT)
File "/home/buildbot/buildarea/3.11.cstratak-RHEL8-ppc64le/build/Lib/test/support/__init__.py", line 1963, in wait_process
raise AssertionError(f"process {pid} is still running "
AssertionError: process 3527836 is still running after 40.3 seconds
Traceback (most recent call last):
File "/home/buildbot/buildarea/3.11.cstratak-RHEL8-ppc64le/build/Lib/test/support/threading_helper.py", line 66, in decorator
return func(*args)
^^^^^^^^^^^
File "/home/buildbot/buildarea/3.11.cstratak-RHEL8-ppc64le/build/Lib/test/test_sys.py", line 530, in test_current_frames_exceptions_deadlock
support.wait_process(pid, exitcode=0, timeout=TIMEOUT)
File "/home/buildbot/buildarea/3.11.cstratak-RHEL8-ppc64le/build/Lib/test/support/__init__.py", line 1963, in wait_process
raise AssertionError(f"process {pid} is still running "
AssertionError: process 3511003 is still running after 40.5 seconds
|
Sorry, something went wrong.
Uh oh!
There was an error while loading. Please reload this page.
⚠️⚠️⚠️ Buildbot failure ⚠️⚠️⚠️
Hi! The buildbot PPC64LE RHEL8 Refleaks 3.11 (tier-2) has failed when building commit 6b37486.
What do you need to do:
- Don't panic.
- Check the buildbot page in the devguide if you don't know what the buildbots are or how they work.
- Go to the page of the buildbot that failed (https://buildbot.python.org/#/builders/978/builds/854) and take a look at the build logs.
- Check if the failure is related to this commit (6b37486) or if it is a false positive.
- If the failure is related to this commit, please, reflect that on the issue and make a new Pull Request with a fix.
You can take a look at the buildbot page here:
https://buildbot.python.org/#/builders/978/builds/854
Failed tests:
Failed subtests:
- test_current_frames_exceptions_deadlock - test.test_sys.SysModuleTest.test_current_frames_exceptions_deadlock
Test leaking resources:
- test_functools: memory blocks
Summary of the results of the build (if available):
==
Click to see traceback logs
Traceback (most recent call last):
File "/home/buildbot/buildarea/3.11.cstratak-RHEL8-ppc64le.refleak/build/Lib/test/support/threading_helper.py", line 66, in decorator
return func(*args)
^^^^^^^^^^^
File "/home/buildbot/buildarea/3.11.cstratak-RHEL8-ppc64le.refleak/build/Lib/test/test_sys.py", line 530, in test_current_frames_exceptions_deadlock
support.wait_process(pid, exitcode=0, timeout=TIMEOUT)
File "/home/buildbot/buildarea/3.11.cstratak-RHEL8-ppc64le.refleak/build/Lib/test/support/__init__.py", line 1963, in wait_process
raise AssertionError(f"process {pid} is still running "
AssertionError: process 3696628 is still running after 40.5 seconds
Traceback (most recent call last):
File "/home/buildbot/buildarea/3.11.cstratak-RHEL8-ppc64le.refleak/build/Lib/test/support/threading_helper.py", line 66, in decorator
return func(*args)
^^^^^^^^^^^
File "/home/buildbot/buildarea/3.11.cstratak-RHEL8-ppc64le.refleak/build/Lib/test/test_sys.py", line 530, in test_current_frames_exceptions_deadlock
support.wait_process(pid, exitcode=0, timeout=TIMEOUT)
File "/home/buildbot/buildarea/3.11.cstratak-RHEL8-ppc64le.refleak/build/Lib/test/support/__init__.py", line 1963, in wait_process
raise AssertionError(f"process {pid} is still running "
AssertionError: process 3726771 is still running after 40.3 seconds
|
Sorry, something went wrong.
Uh oh!
There was an error while loading. Please reload this page.
Ugh, this is not good. @diegorusso do you mind checking? Otherwise we may need to revert :(
I'm looking into it.
|
Sorry, something went wrong.
Uh oh!
There was an error while loading. Please reload this page.
|
Technically by our policy we will need to revert if this is not fixed in 24h but given that we don't have a release soon, let's wait until Friday EOD max
|
Sorry, something went wrong.
Uh oh!
There was an error while loading. Please reload this page.
|
I plan to make a new PR today.
|
Sorry, something went wrong.
Uh oh!
There was an error while loading. Please reload this page.
diegorusso
commented
Mar 13, 2025
•
edited
Loading
Uh oh!
There was an error while loading. Please reload this page.
|
Upon an initial investigation, the culprit is the low timeout set in the test: increasing the timeout makes the test passing.
If we look at the builds related to this commit https://buildbot.python.org/#/changes/40264 we notice that all the failing ones related to this test have been built with --with-pydebug.
If we look at the file gcmodule.c (https://github.com/python/cpython/blob/6b37486184590d19c6f24e620545ec8f8f65e4c7/Modules/gcmodule.c) we see that in the file there are different code paths depending if the debug is enabled or not.
This has an impact on the execution time: with the debug enabled it takes more time execute the test case and it cannot finish by the timeout set of 40 seconds. The fact that happens on PPC64LE is because very likely these machines don't have a high clock speed and it might take some time to execute the test.
We can observe a similar failure on SPARCv9 Oracle Solaris as well https://buildbot.python.org/#/builders/988/builds/1187
On GitHub tests are passing because we build CPython without debug.
As a reference on my AArch64 Linux VM the execution time difference of test_sys between a build with and without debug is:
- without debug: ~1.5 seconds
- with debug: ~7 seconds
Another data point. On a software emulated environment:
- without debug: ~17.3 seconds
- with debug: ~68 seconds
There is about a 4x difference between the build with debug and without.
I suggest to increase the timeout to a level that it is enough for all the platforms, even the older ones.
|
Sorry, something went wrong.
Uh oh!
There was an error while loading. Please reload this page.
Sorry, something went wrong.
Uh oh!
There was an error while loading. Please reload this page.
Uh oh!
There was an error while loading. Please reload this page.
Assignees
No one assigned
Milestone
No milestone
Development
Successfully merging this pull request may close these issues.
Uh oh!
There was an error while loading. Please reload this page.
Add this suggestion to a batch that can be applied as a single commit.This suggestion is invalid because no changes were made to the code.Suggestions cannot be applied while the pull request is closed.Suggestions cannot be applied while viewing a subset of changes.Only one suggestion per line can be applied in a batch.Add this suggestion to a batch that can be applied as a single commit.Applying suggestions on deleted lines is not supported.You must change the existing code in this line in order to create a valid suggestion.Outdated suggestions cannot be applied.This suggestion has been applied or marked resolved.Suggestions cannot be applied from pending reviews.Suggestions cannot be applied on multi-line comments.Suggestions cannot be applied while the pull request is queued to merge.Suggestion cannot be applied right now. Please check back later.
|