#538 Failure in prod of the multi-package packager workflow
Closed by asaleh. Opened by packagerbot.

A run of monitor-gating has just failed in prod for the multi-package workflow.

The suspects are 'datagrepper, datagrepper, resultsdb, datagrepper, datagrepper'.

Full log:

20:27:14 - Cloning as packagerbot the git repo: rpms/dummy-test-package-crested
20:27:18 - Cloning as packagerbot the git repo: rpms/dummy-test-package-crested                       [DONE]
20:27:18 - Switching to branch: master
20:27:19 - Switching to branch: master                                                                [DONE]
20:27:19 - Creating the side-tag
20:27:20 - Created side-tag f34-build-side-30615                                                      [DONE]
20:27:20 - Bumping release of: dummy-test-package-crested.spec
20:27:20 - Bumping release of: dummy-test-package-crested.spec                                        [DONE]
20:27:20 - Commiting changes
20:27:20 - Commiting changes                                                                          [DONE]
20:27:20 - Getting nevr
20:27:21 - Getting nevr                                                                               [DONE]
20:27:21 - Pushing changes
20:27:26 - Pushing changes                                                                            [DONE]
20:27:26 - Upcoming build : dummy-test-package-crested-0-1627
20:27:26 - Cloning as packagerbot the git repo: rpms/dummy-test-package-rubino
20:27:30 - Cloning as packagerbot the git repo: rpms/dummy-test-package-rubino                        [DONE]
20:27:30 - Switching to branch: master
20:27:30 - Switching to branch: master                                                                [DONE]
20:27:30 - Bumping release of: dummy-test-package-rubino.spec
20:27:31 - Bumping release of: dummy-test-package-rubino.spec                                         [DONE]
20:27:31 - Commiting changes
20:27:31 - Commiting changes                                                                          [DONE]
20:27:31 - Getting nevr
20:27:31 - Getting nevr                                                                               [DONE]
20:27:31 - Pushing changes
20:27:37 - Pushing changes                                                                            [DONE]
20:27:37 - Upcoming build : dummy-test-package-rubino-0-1627
20:27:37 - Chain-building the packages: ['dummy-test-package-crested', 'dummy-test-package-rubino']
20:44:42 - Chain-building the packages: ['dummy-test-package-crested', 'dummy-test-package-rubino']   [DONE]
20:44:42 - Creating a bodhi update
20:44:45 - Creating a bodhi update                                                                    [DONE]
20:44:45 - Retrieving update created from https://bodhi.fedoraproject.org/updates/?builds=dummy-test-package-crested-0-1627
20:44:45 - Retrieving update created from https://bodhi.fedoraproject.org/updates/?builds=dummy-test-package-crested-0-1627 [DONE]
20:44:45 - Update FEDORA-2020-20172b3b6a created : https://bodhi.fedoraproject.org/updates/FEDORA-2020-20172b3b6a [DONE]
20:44:45 - Retrieving koji tags
20:45:16 - Retrieving koji tags: ['f34-build-side-30615', 'f34-build-side-30615-testing-pending'] found while expected ['signing-pending', 'testing-pending'] [DONE]
20:45:16 - Checking datagrepper for bodhi to CI messages
20:45:16 - bodhi to CI results in datagrepper returned  - ran for: 0s                                 [DONE]
20:45:16 - Checking datagrepper for CI (running) messages
21:00:27 - CI (running) results not found in datagrepper - ran for: 910s                              [FAILED]
21:00:27 - Checking datagrepper for CI (complete) messages
21:00:27 - CI (complete) results not found in datagrepper - ran for: 0s                               [FAILED]
21:00:27 - Retrieving koji tags
21:00:27 - Retrieving koji tags: ['f34-build-side-30615', 'f34-build-side-30615-testing-pending'] found while expected ['testing-pending'] [DONE]
21:00:27 - CI results query from https://taskotron.fedoraproject.org/resultsdb_api/api/v2.0/results?testcases=fedora-ci.koji-build.tier0.functional&page=0 [DONE]
21:00:27 - Checking resultsdb(phx) for CI results
21:15:33 - CI results did not show in resultsdb(phx) for dummy-test-package-crested-0-1627 within 900 minutes since 2020-09-22 21:00:27.914095 - ran for: 905s [FAILED]
21:15:33 - Checking datagrepper for resultsdb messages
21:15:33 - resultsdb results not found in datagrepper - ran for: 0s                                   [FAILED]
21:15:33 - Checking datagrepper for greenwave messages
21:15:34 - greenwave results not found in datagrepper - ran for: 0s                                   [FAILED]
21:15:34 - Retrieving koji tags
21:15:34 - Retrieving koji tags: ['f34-build-side-30615', 'f34-build-side-30615-testing-pending'] found while expected ['testing-pending'] [DONE]
21:15:34 - Checking datagrepper for CI (running) messages
21:15:34 - CI (running) results in datagrepper returned running - ran for: 0s                         [DONE]
21:15:34 - Checking datagrepper for CI (complete) messages
21:15:34 - CI (complete) results in datagrepper returned error - ran for: 0s                          [DONE]
21:15:34 - Retrieving koji tags
21:15:35 - Retrieving koji tags: ['f34-build-side-30615', 'f34-build-side-30615-testing-pending'] found while expected ['testing-pending'] [DONE]
21:15:35 - CI results query from https://taskotron.fedoraproject.org/resultsdb_api/api/v2.0/results?testcases=fedora-ci.koji-build.tier0.functional&page=0 [DONE]
21:15:35 - Checking resultsdb(phx) for CI results
21:15:35 - CI results in resultsdb(phx) returned org.centos.prod.ci.koji-build.test.error - ran for: 0s [DONE]
21:15:35 - Checking datagrepper for resultsdb messages
21:15:35 - resultsdb results in datagrepper returned FAILED - ran for: 0s                             [DONE]
21:15:35 - Checking datagrepper for greenwave messages
21:15:35 - greenwave results in datagrepper returned False - ran for: 0s                              [DONE]
21:15:35 - Retrieving koji tags
21:15:35 - Retrieving koji tags: ['f34-build-side-30615', 'f34-build-side-30615-testing-pending'] found while expected ['testing-pending'] [DONE]
21:15:35 - Waiving test results for bodhi update
21:15:37 - Waiving test results for bodhi update                                                      [DONE]
21:15:37 - Checking datagrepper for waiverdb messages
21:15:37 - waiverdb results in datagrepper returned  - ran for: 0s                                    [DONE]
21:15:37 - Checking datagrepper for greenwave messages
21:15:37 - greenwave results in datagrepper returned True - ran for: 0s                               [DONE]
21:15:37 - Retrieving koji tags
21:18:10 - Retrieving koji tags: ['f34'] found while expected f34                                     [DONE]
Ran for 3055 seconds (50.92 minutes)

Intermittent testing timeouts but the escape hatch worked.

Metadata Update from @asaleh:
- Issue status updated to: Closed (was: Open)

Metadata