Repository navigation
test_gdb failed with 15 min timeout on PPC64LE RHEL8 3.x (LTO + pydebug) #109972
Copy link
Copy link
Closed
Labels
testsTests in the Lib/test dirTests in the Lib/test dir
Description
PPC64LE RHEL8 3.x:
0:19:19 load avg: 1.37 [463/463/1] test_gdb process crashed (Exit code 1)
Timeout (0:15:00)!
Thread 0x00007fffae664d70 (most recent call first):
File "/home/buildbot/buildarea/3.x.cstratak-RHEL8-ppc64le/build/Lib/selectors.py", line 398 in select
File "/home/buildbot/buildarea/3.x.cstratak-RHEL8-ppc64le/build/Lib/subprocess.py", line 2108 in _communicate
File "/home/buildbot/buildarea/3.x.cstratak-RHEL8-ppc64le/build/Lib/subprocess.py", line 1209 in communicate
File "/home/buildbot/buildarea/3.x.cstratak-RHEL8-ppc64le/build/Lib/test/test_gdb.py", line 107 in run_gdb
File "/home/buildbot/buildarea/3.x.cstratak-RHEL8-ppc64le/build/Lib/test/test_gdb.py", line 224 in get_stack_trace
File "/home/buildbot/buildarea/3.x.cstratak-RHEL8-ppc64le/build/Lib/test/test_gdb.py", line 949 in test_pycfunction
(...)
(...)
0:19:19 load avg: 1.37 Re-running 1 failed tests in verbose mode in subprocesses
0:19:19 load avg: 1.37 Run 1 test in parallel using 1 worker process (timeout: 15 min, worker timeout: 20 min)
0:19:49 load avg: 1.22 running (1): test_gdb (30.0 sec)
0:20:19 load avg: 1.19 running (1): test_gdb (1 min)
0:20:49 load avg: 1.11 running (1): test_gdb (1 min 30 sec)
(...)
0:33:50 load avg: 1.09 running (1): test_gdb (14 min 30 sec)
0:34:20 load avg: 1.14 running (1): test_gdb (15 min)
0:34:20 load avg: 1.14 [1/1/1] test_gdb process crashed (Exit code 1)
Re-running test_gdb in verbose mode
test_NULL_ob_type (test.test_gdb.PrettyPrintTests.test_NULL_ob_type)
Ensure that a PyObject* with NULL ob_type is handled gracefully ... ok
(...)
test_pycfunction (test.test_gdb.PyBtTests.test_pycfunction)
Verify that "py-bt" displays invocations of PyCFunction instances ... Timeout (0:15:00)!
Thread 0x00007fffa14b4d70 (most recent call first):
File "/home/buildbot/buildarea/3.x.cstratak-RHEL8-ppc64le/build/Lib/selectors.py", line 398 in select
File "/home/buildbot/buildarea/3.x.cstratak-RHEL8-ppc64le/build/Lib/subprocess.py", line 2108 in _communicate
File "/home/buildbot/buildarea/3.x.cstratak-RHEL8-ppc64le/build/Lib/subprocess.py", line 1209 in communicate
File "/home/buildbot/buildarea/3.x.cstratak-RHEL8-ppc64le/build/Lib/test/test_gdb.py", line 107 in run_gdb
File "/home/buildbot/buildarea/3.x.cstratak-RHEL8-ppc64le/build/Lib/test/test_gdb.py", line 224 in get_stack_trace
File "/home/buildbot/buildarea/3.x.cstratak-RHEL8-ppc64le/build/Lib/test/test_gdb.py", line 949 in test_pycfunction
(...)
build: https://buildbot.python.org/all/#/builders/559/builds/3951
Linked PRs
- gh-109972: Split test_gdb into a package of 3 tests #109977
- gh-109972: Enhance test_gdb #110026
- [3.12] gh-109972: Split test_gdb.py into test_gdb package (#109977) #110339
- [3.11] gh-109972: Split test_gdb.py into test_gdb package (#109977) (… #110343
- [3.12] gh-109972: Enhance test_gdb (#110026) #110351
- [3.11] [3.12] gh-109972: Enhance test_gdb (GH-110026) (GH-110351) #110354
Reactions are currently unavailable
Metadata
Metadata
Assignees
Labels
testsTests in the Lib/test dirTests in the Lib/test dir
Activity
[-]test_gdb failed with 15 min timeout on AMD64 RHEL8 Refleaks 3.x[/-][+]test_gdb failed with 15 min timeout on PPC64LE RHEL8 3.x[/+]vstinner commentedon Sep 27, 2023
Ah, it's a LTO build.
configure + make:
test.pythoninfo:
vstinner commentedon Sep 27, 2023
On this buildbot, if Python is built with
./configure --with-pydebugandgcc -Og, test_gdb completes in 4 min 30 sec and looks reliable:[-]test_gdb failed with 15 min timeout on PPC64LE RHEL8 3.x[/-][+]test_gdb failed with 15 min timeout on PPC64LE RHEL8 3.x (LTO + pydebug)[/+]vstinner commentedon Sep 27, 2023
When Python is built with
./configure --with-pydebug --with-lto && make -j10, well, test_gdb is ... way slower: 19 min 45 sec.vstinner commentedon Sep 27, 2023
Python built with
./configure --with-pydebug CFLAGS="-O0"andgcc -O0:vstinner commentedon Sep 27, 2023
The test runs 130 gdb commands in total. Average:
gcc -O0: 1.9 sec / commandgcc -Og: 2.1 sec / commandgcc -Og -flto: 9.1 sec / commandvstinner commentedon Sep 27, 2023
Number of gdb commands per test case:
pythongh-109972: Split test_gdb into a package of 3 tests
vstinner commentedon Sep 27, 2023
I wrote PR #109977 to split test_gdb into a package of 3 tests so these 3 tests can be run in parallel.
pythongh-109972: Split test_gdb into a package of 3 tests
pythongh-109972: Split test_gdb into a package of 3 tests
pythongh-109972: Split test_gdb into a package of 3 tests
vstinner commentedon Sep 28, 2023
Well, gdb takes 3.9 seconds just to load symbols from the Python ELF binary. Time measured with (before, I removed manually
python-gdb.py):8 remaining items
pythongh-109972: Enhance test_gdb
gh-109972: Enhance test_gdb (#110026)
pythongh-109972: Split test_gdb.py into test_gdb package (python#109977)
pythongh-109972: Enhance test_gdb (python#110026)
pythongh-109972: Split test_gdb.py into test_gdb package (python#109977)
[3.12] gh-109972: Split test_gdb.py into test_gdb package (#109977) (#…
[3.12] pythongh-109972: Split test_gdb.py into test_gdb package (pyth…
[3.11] gh-109972: Split test_gdb.py into test_gdb package (#109977) (… (
pythongh-109972: Enhance test_gdb (python#110026)
[3.12] gh-109972: Enhance test_gdb (#110026) (#110351)
[3.12] pythongh-109972: Enhance test_gdb (pythonGH-110026) (pythonGH-…
[3.11] [3.12] gh-109972: Enhance test_gdb (GH-110026) (GH-110351) (#1…
pythongh-109972: Split test_gdb.py into test_gdb package (python#109977)
pythongh-109972: Enhance test_gdb (python#110026)