Bug 14201 - Bitbake server intermittent timeout
Summary: Bitbake server intermittent timeout
Status: RESOLVED WORKSFORME
Alias: None
Product: Functional (self) Testing
Classification: QA/Testing
Component: oe-selftest (show other bugs)
Version: 3.3
Hardware: x86 x86_64
: Medium+ normal
Target Milestone: 4.2 M2
Assignee: Unassigned
QA Contact:
URL:
Whiteboard: AB-INT AB-FREQUENT
: 14225 14278 14390 14662 (view as bug list)
Depends on:
Blocks:
 
Reported: 2021-01-26 21:22 UTC by Alexandre Belloni
Modified: 2023-01-12 15:52 UTC (History)
14 users (show)

See Also:
OS type for building Yocto: ---
Type of Regression: ---
Verified:
Documentation change: No (bug/feature does not impact docs)


Attachments

Note You need to log in before you can comment on or make changes to this bug.
Description Alexandre Belloni 2021-01-26 21:22:38 UTC
autobuilder failure:

2021-01-20 04:25:01,252 - oe-selftest - INFO - RESULTS - devtool.DevtoolAddTests.test_devtool_add_fetch: FAILED (1369.50s)
2021-01-20 04:25:01,252 - oe-selftest - INFO - RESULTS - devtool.DevtoolModifyTests.test_devtool_buildclean: FAILED (2264.95s)

Full log:
https://autobuilder.yoctoproject.org/typhoon/#/builders/79/builds/1739/steps/14/logs/stdio
Comment 1 Richard Purdie 2021-01-28 15:38:34 UTC
2021-01-20 04:24:52,032 - oe-selftest - INFO - ======================================================================
2021-01-20 04:24:52,032 - oe-selftest - INFO - FAIL: devtool.DevtoolAddTests.test_devtool_add_fetch (subunit.RemotedTestCase)
2021-01-20 04:24:52,032 - oe-selftest - INFO - ----------------------------------------------------------------------
2021-01-20 04:24:52,032 - oe-selftest - INFO - testtools.testresult.real._StringException: Traceback (most recent call last):
  File "/home/pokybuild/yocto-worker/oe-selftest-centos/build/meta/lib/oeqa/selftest/cases/devtool.py", line 434, in test_devtool_add_fetch
    recipefile = get_bb_var('FILE', testrecipe)
  File "/home/pokybuild/yocto-worker/oe-selftest-centos/build/meta/lib/oeqa/utils/commands.py", line 284, in get_bb_var
    return get_bb_vars([var], target, postconfig)[var]
  File "/home/pokybuild/yocto-worker/oe-selftest-centos/build/meta/lib/oeqa/utils/commands.py", line 246, in get_bb_vars
    bbenv = get_bb_env(target, postconfig=postconfig)
  File "/home/pokybuild/yocto-worker/oe-selftest-centos/build/meta/lib/oeqa/utils/commands.py", line 240, in get_bb_env
    return bitbake("-e %s" % target, postconfig=postconfig).output
  File "/home/pokybuild/yocto-worker/oe-selftest-centos/build/meta/lib/oeqa/utils/commands.py", line 232, in bitbake
    return runCmd(cmd, ignore_status, timeout, output_log=output_log, **options)
  File "/home/pokybuild/yocto-worker/oe-selftest-centos/build/meta/lib/oeqa/utils/commands.py", line 210, in runCmd
    raise AssertionError("Command '%s' returned non-zero exit status %d:\n%s" % (command, result.status, exc_output))
AssertionError: Command 'bitbake  -e python-markupsafe' returned non-zero exit status 1:
Timeout while waiting for a reply from the bitbake server (60s)
2021-01-20 04:24:52,032 - oe-selftest - INFO - ======================================================================
2021-01-20 04:24:52,032 - oe-selftest - INFO - FAIL: devtool.DevtoolModifyTests.test_devtool_buildclean (subunit.RemotedTestCase)
2021-01-20 04:24:52,032 - oe-selftest - INFO - ----------------------------------------------------------------------
2021-01-20 04:24:52,032 - oe-selftest - INFO - testtools.testresult.real._StringException: Traceback (most recent call last):
  File "/home/pokybuild/yocto-worker/oe-selftest-centos/build/meta/lib/oeqa/selftest/cases/devtool.py", line 630, in test_devtool_buildclean
    bitbake('mdadm m4 -c buildclean')
  File "/home/pokybuild/yocto-worker/oe-selftest-centos/build/meta/lib/oeqa/utils/commands.py", line 232, in bitbake
    return runCmd(cmd, ignore_status, timeout, output_log=output_log, **options)
  File "/home/pokybuild/yocto-worker/oe-selftest-centos/build/meta/lib/oeqa/utils/commands.py", line 210, in runCmd
    raise AssertionError("Command '%s' returned non-zero exit status %d:\n%s" % (command, result.status, exc_output))
AssertionError: Command 'bitbake  mdadm m4 -c buildclean' returned non-zero exit status 1:
Timeout while waiting for a reply from the bitbake server (60s)
Comment 2 Richard Purdie 2021-01-30 08:56:19 UTC
Happened again in:
https://autobuilder.yoctoproject.org/typhoon/#/builders/79/builds/1776
Comment 3 Randy MacLeod 2021-02-11 15:49:22 UTC
*** Bug 14225 has been marked as a duplicate of this bug. ***
Comment 4 Alexandre Belloni 2021-02-23 00:32:44 UTC
A new occurence here, right after setting up the environment:

https://autobuilder.yoctoproject.org/typhoon/#/builders/63/builds/3061/steps/16/logs/stdio


### Shell environment set up for builds. ###
You can now run 'bitbake <target>'
Common targets are:
    core-image-minimal
    core-image-full-cmdline
    core-image-sato
    core-image-weston
    meta-toolchain
    meta-ide-support
You can also run generated qemu images with a command like 'runqemu qemux86'
Other commonly useful commands are:
 - 'devtool' and 'recipetool' handle common recipe tasks
 - 'bitbake-layers' handles common layer tasks
 - 'oe-pkgdata-util' handles common target package tasks
Timeout while waiting for a reply from the bitbake server (60s)
Comment 6 Randy MacLeod 2021-02-26 16:33:42 UTC
In our internal builds when we are building poky images (but oddly enough, NEVER when we are building WR images!), we see:


[09:15:24-0800] bitbake core-image-sato
[09:16:26-0800] Timeout while waiting for a reply from the bitbake server (60s)

It's really infrequent but happens often enough that it annoys me. From the last 4 months this error has happend 15-38 times/month:

     19 10/2020
     15 11/2020
     22 12/2020
     38 1/2021
     24 2/2021
Of course we do 15K builds/month so it's never been a high priority.
At some point, we'll see if Sakib's periodic monitor script using flock can help us to understand what's going on there.
Comment 8 Alexandre Belloni 2021-03-03 12:46:52 UTC
https://autobuilder.yoctoproject.org/typhoon/#/builders/83/builds/1931/steps/21/logs/stdio

This one is alose pretty early:

NOTE: Starting bitbake server...
NOTE: Bitbake server didn't start within 5 seconds, waiting for 90
NOTE: No reply from server in 30s
NOTE: No reply from server in 30s
Timeout while waiting for a reply from the bitbake server (60s)
Comment 10 Köry Maincent 2021-03-09 16:14:49 UTC
Same in devtool tests.

https://autobuilder.yoctoproject.org/typhoon/#/builders/87/builds/1937/steps/14/logs/stdio

AssertionError: Command 'devtool reset cdrtools-native' returned non-zero exit status 1:
NOTE: Starting bitbake server...
NOTE: No reply from server in 30s
Timeout while waiting for a reply from the bitbake server (60s)
Comment 12 Randy MacLeod 2021-03-11 15:56:23 UTC
*** Bug 14278 has been marked as a duplicate of this bug. ***
Comment 13 Alejandro 2021-04-07 18:29:32 UTC
Happened again here during oe-selftest:

AssertionError: Command 'devtool add http://downloads.yoctoproject.org/mirror/sources/i2c-tools-3.1.2.tar.bz2' returned non-zero exit status 1:
NOTE: Starting bitbake server...
NOTE: Bitbake server didn't start within 5 seconds, waiting for 90
NOTE: No reply from server in 30s
NOTE: No reply from server in 30s
Timeout while waiting for a reply from the bitbake server (60s)

https://autobuilder.yoctoproject.org/typhoon/#/builders/79/builds/1984/steps/14/logs/stdio
Comment 14 Anuj Mittal 2021-04-26 00:43:19 UTC
During oe-selftest on hardknott:

https://autobuilder.yoctoproject.org/typhoon/#/builders/79/builds/2042
Comment 15 Alexandre Belloni 2021-05-01 22:41:58 UTC
https://autobuilder.yoctoproject.org/typhoon/#/builders/79/builds/2036

master-next on centos8-ty-1
Comment 16 Alexandre Belloni 2021-05-06 11:35:14 UTC
https://autobuilder.yoctoproject.org/typhoon/#/builders/60/builds/3358/steps/16/logs/stdio

master-next on fedora33-ty-1
Comment 17 Alexandre Belloni 2021-05-06 14:41:52 UTC
https://autobuilder.yoctoproject.org/typhoon/#/builders/86/builds/2052/steps/14/logs/stdio

master-next on fedora33-ty-1
Comment 18 Randy MacLeod 2021-05-06 15:53:14 UTC
Trevor is looking at the bitbake server timeout issue that we see in WR Linux so adding him to CC.
Comment 20 Chee Yang 2021-05-10 09:08:20 UTC
oe-selftest-ubuntu 
ubuntu1804-ty-3

https://autobuilder.yoctoproject.org/typhoon/#/builders/87/builds/2108/steps/14/logs/stdio

timeout while test for recipetool.RecipetoolTests.test_recipetool_create


NOTE: Tasks Summary: Attempted 83 tasks of which 81 didn't need to be rerun and all succeeded.
NOTE: No reply from server in 30s
INFO: Source extracted to /tmp/recipetoolqahflxdy4o/srctree
INFO: Recipe /tmp/recipetoolqahflxdy4o/logrotate_3.12.3.bb has been created; further editing may be required to make it fully functional
NOTE: No reply from server in 30s
Timeout while waiting for a reply from the bitbake server (60s)
Comment 21 Randy MacLeod 2021-05-13 14:47:37 UTC
*** Bug 14390 has been marked as a duplicate of this bug. ***
Comment 22 Jon Mason 2021-05-21 12:52:56 UTC
Reoccurance while testing GCC11 patches

https://autobuilder.yoctoproject.org/typhoon/#/builders/79/builds/2059/steps/14/logs/stdio

oe-selftest-centos 
centos8-ty-1
Comment 23 Alexandre Belloni 2021-06-16 00:30:33 UTC
https://autobuilder.yoctoproject.org/typhoon/#/builders/79/builds/2149/steps/14/logs/stdio

oe-selftest-centos
centos8-ty-1
Comment 24 Alexandre Belloni 2021-06-16 09:05:02 UTC
https://autobuilder.yoctoproject.org/typhoon/#/builders/87/builds/2182/steps/14/logs/stdio

oe-selftest-ubuntu
ubuntu1804-ty-3
Comment 25 Alexandre Belloni 2021-06-16 13:43:36 UTC
https://autobuilder.yoctoproject.org/typhoon/#/builders/79/builds/2188/steps/14/logs/stdio

oe-selftest-centos
centos8-ty-1
Comment 26 Randy MacLeod 2021-06-17 00:14:40 UTC
FYI: The poky build timeout in the WR Linux build that I mentioned earlier seems to have stopped when we updated the version of docker that we used. It's possible that it isn't the version but rather a system uptime but I'll try to remember to comment if the problem shows up again. It was happening every day or two and it's been 13 days now so it's most likely gone.
Comment 27 Alexandre Belloni 2021-06-17 22:40:47 UTC
https://autobuilder.yoctoproject.org/typhoon/#/builders/56/builds/1481/steps/14/logs/stdio

NOTE: Reconnecting to bitbake server...
NOTE: Retrying server connection (#1)...
NOTE: Reconnecting to bitbake server...
NOTE: Previous bitbake instance shutting down?, waiting to retry...
NOTE: Retrying server connection (#2)...
NOTE: Reconnecting to bitbake server...
NOTE: Retrying server connection (#1)...
NOTE: Reconnecting to bitbake server...

oe-selftest fedora31-ty-1
Comment 28 Alexandre Belloni 2021-06-30 20:43:32 UTC
https://autobuilder.yoctoproject.org/typhoon/#/builders/86/builds/2221

oe-selftest-fedora fedora33-ty-1
Comment 29 Alexandre Belloni 2021-06-30 22:13:23 UTC
https://autobuilder.yoctoproject.org/typhoon/#/builders/79/builds/2245/steps/14/logs/stdio

oe-selftest-centos centos8-ty-1
Comment 30 Alexandre Belloni 2021-06-30 22:25:04 UTC
https://autobuilder.yoctoproject.org/typhoon/#/builders/79/builds/2247/steps/14/logs/stdio

oe-selftest-centos centos8-ty-1
Comment 31 Alexandre Belloni 2021-06-30 22:27:21 UTC
https://autobuilder.yoctoproject.org/typhoon/#/builders/79/builds/2249/steps/14/logs/stdio

oe-selftest-centos centos8-ty-1
Comment 33 Alexandre Belloni 2021-07-04 14:02:22 UTC
https://autobuilder.yoctoproject.org/typhoon/#/builders/79/builds/2271/steps/15/logs/stdio

oe-selftest-centos centos7-ty-4
Comment 34 Alexandre Belloni 2021-07-15 14:47:26 UTC
https://autobuilder.yoctoproject.org/typhoon/#/builders/42/builds/3657/steps/14/logs/stdio

qemuarm64 tumbleweed-ty-3
Comment 35 Alexandre Belloni 2021-07-22 12:53:35 UTC
https://autobuilder.yoctoproject.org/typhoon/#/builders/119/builds/462/steps/10/logs/stdio

reproducible-ubuntu ubuntu1804-ty-3
Comment 36 Alexandre Belloni 2021-08-11 10:55:28 UTC
https://autobuilder.yoctoproject.org/typhoon/#/builders/74/builds/3762/steps/16/logs/stdio

qemumips64 fedora33-ty-1
Comment 37 Alexandre Belloni 2021-08-11 15:18:25 UTC
https://autobuilder.yoctoproject.org/typhoon/#/builders/87/builds/2385/steps/14/logs/stdio

oe-selftest-ubuntu ubuntu2004-ty-2
Comment 38 Alexandre Belloni 2021-08-11 20:24:55 UTC
https://autobuilder.yoctoproject.org/typhoon/#/builders/86/builds/2348/steps/14/logs/stdio

oe-selftest-fedora fedora33-ty-1
Comment 39 Alexandre Belloni 2021-08-13 13:13:09 UTC
https://autobuilder.yoctoproject.org/typhoon/#/builders/56/builds/1560/steps/14/logs/stdio

oe-selftest ubuntu1804-ty-3
Comment 40 Alexandre Belloni 2021-08-19 18:49:55 UTC
https://autobuilder.yoctoproject.org/typhoon/#/builders/56/builds/1565/steps/14/logs/stdio

oe-selftest centos8-ty-1
Comment 42 Chee Yang 2021-11-10 01:26:48 UTC
https://autobuilder.yoctoproject.org/typhoon/#/builders/87/builds/2828/steps/14/logs/stdio
ubuntu1804-ty-3
oe-selftest-ubuntu
Comment 43 Alexandre Belloni 2021-12-09 10:54:50 UTC
https://autobuilder.yoctoproject.org/typhoon/#builders/87/builds/2900/steps/14/logs/stdio
oe-selftest-ubuntu ubuntu2004-ty-2
Comment 44 Randy MacLeod 2021-12-16 15:44:50 UTC
*** Bug 14662 has been marked as a duplicate of this bug. ***
Comment 48 Alexandre Belloni 2022-01-08 00:37:11 UTC
https://autobuilder.yoctoproject.org/typhoon/#/builders/89/builds/4525/steps/6/logs/stdio
meta-mingw ubuntu1804-ty-3
Comment 51 Alexandre Belloni 2022-02-09 22:52:39 UTC
https://autobuilder.yoctoproject.org/typhoon/#/builders/87/builds/3139/steps/14/logs/stdio
ubuntu1804-ty-3 non-SSD
Comment 52 Jon Mason 2022-03-23 20:40:49 UTC
There appears to be another instance of this
https://autobuilder.yoctoproject.org/typhoon/#/builders/103/builds/3818
Comment 53 Alexandre Belloni 2022-03-24 15:09:50 UTC
Bug 14767 has been opened for that last instance
Comment 54 Alexandre Belloni 2022-03-31 13:41:07 UTC
https://autobuilder.yoctoproject.org/typhoon/#/builders/89/builds/4955/steps/6/logs/stdio

ubuntu1804-ty-3 164821914
updateConfig took ~90s
Comment 55 Randy MacLeod 2022-04-11 14:56:59 UTC
Some timeout issues have been fixed but let's wait a while to see if this one is gone.
Comment 56 Leonardo Sandoval 2022-04-20 23:35:45 UTC
https://autobuilder.yoctoproject.org/typhoon/#/builders/79/builds/3456/steps/15/logs/stdio

│ debian9-ty-2     │ Non-SSD │
Comment 57 Alexandre Belloni 2022-04-28 15:00:38 UTC
https://autobuilder.yoctoproject.org/typhoon/#/builders/87/builds/3491/steps/14/logs/stdio

oe-selftest-ubuntu on ubuntu1804-ty-3
Comment 58 Steve Sakoman 2022-05-25 18:01:09 UTC
kirkstone, meta-aws, centos7-ty-4:

NOTE: Starting bitbake server...
NOTE: Bitbake server didn't start within 5 seconds, waiting for 90
NOTE: No reply from server in 30s
Timeout while waiting for a reply from the bitbake server (60s)

https://autobuilder.yoctoproject.org/typhoon/#/builders/122/builds/1220
Comment 59 Alexandre Belloni 2022-06-23 00:02:08 UTC
https://autobuilder.yoctoproject.org/typhoon/#builders/79/builds/3716/steps/15/logs/stdio

oe-selftest-centos centos7-ty-4
Comment 60 Alexandre Belloni 2022-06-30 15:03:31 UTC
https://autobuilder.yoctoproject.org/typhoon/#/builders/37/builds/5429/steps/13/logs/stdio

genericx86-64 stream8-ty-1
Comment 61 Steve Sakoman 2022-07-13 18:21:50 UTC
dunfell

https://autobuilder.yoctoproject.org/typhoon/#/builders/79/builds/3817

oe-selftest-centos alma8-ty-1
Comment 62 Alexandre Belloni 2022-10-14 13:25:47 UTC
https://autobuilder.yoctoproject.org/typhoon/#/builders/44/builds/5889/steps/26/logs/stdio

multilib tumbleweed-ty-3
Comment 63 Alexandre Belloni 2022-12-22 13:47:48 UTC
https://autobuilder.yoctoproject.org/typhoon/#/builders/56/builds/2081/steps/15/logs/stdio

oe-selftest stream8-ty-1
Comment 66 Alexandre Belloni 2023-01-12 13:39:46 UTC
https://autobuilder.yoctoproject.org/typhoon/#/builders/87/builds/4648/steps/14/logs/stdio
oe-selftest-armhost ubuntu2004-arm-1
Comment 67 Alexandre Belloni 2023-01-12 13:49:26 UTC
https://autobuilder.yoctoproject.org/typhoon/#/builders/79/builds/4610/steps/15/logs/stdio
oe-selftest-centos alma8-ty-2
Comment 68 Alexandre Belloni 2023-01-12 15:52:28 UTC
Closing as bitbake changed dramatically, tracking new occurrences in a new bug.