#521 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'.

Full log:

04:41:04 - Cloning as packagerbot the git repo: rpms/dummy-test-package-crested
04:41:08 - Cloning as packagerbot the git repo: rpms/dummy-test-package-crested                       [DONE]
04:41:08 - Switching to branch: master
04:41:09 - Switching to branch: master                                                                [DONE]
04:41:09 - Creating the side-tag
04:41:09 - Created side-tag f34-build-side-30289                                                      [DONE]
04:41:09 - Bumping release of: dummy-test-package-crested.spec
04:41:10 - Bumping release of: dummy-test-package-crested.spec                                        [DONE]
04:41:10 - Commiting changes
04:41:10 - Commiting changes                                                                          [DONE]
04:41:10 - Getting nevr
04:41:10 - Getting nevr                                                                               [DONE]
04:41:10 - Pushing changes
04:41:17 - Pushing changes                                                                            [DONE]
04:41:17 - Upcoming build : dummy-test-package-crested-0-1559
04:41:17 - Cloning as packagerbot the git repo: rpms/dummy-test-package-rubino
04:41:20 - Cloning as packagerbot the git repo: rpms/dummy-test-package-rubino                        [DONE]
04:41:20 - Switching to branch: master
04:41:20 - Switching to branch: master                                                                [DONE]
04:41:20 - Bumping release of: dummy-test-package-rubino.spec
04:41:20 - Bumping release of: dummy-test-package-rubino.spec                                         [DONE]
04:41:20 - Commiting changes
04:41:20 - Commiting changes                                                                          [DONE]
04:41:20 - Getting nevr
04:41:21 - Getting nevr                                                                               [DONE]
04:41:21 - Pushing changes
04:41:28 - Pushing changes                                                                            [DONE]
04:41:28 - Upcoming build : dummy-test-package-rubino-0-1559
04:41:28 - Chain-building the packages: ['dummy-test-package-crested', 'dummy-test-package-rubino']
04:59:32 - Chain-building the packages: ['dummy-test-package-crested', 'dummy-test-package-rubino']   [DONE]
04:59:32 - Creating a bodhi update
04:59:36 - Creating a bodhi update                                                                    [DONE]
04:59:36 - Retrieving update created from https://bodhi.fedoraproject.org/updates/?builds=dummy-test-package-crested-0-1559
04:59:36 - Retrieving update created from https://bodhi.fedoraproject.org/updates/?builds=dummy-test-package-crested-0-1559 [DONE]
04:59:36 - Update FEDORA-2020-d6a9b77a2d created : https://bodhi.fedoraproject.org/updates/FEDORA-2020-d6a9b77a2d [DONE]
04:59:36 - Retrieving koji tags
04:59:37 - Retrieving koji tags: ['f34-build-side-30289', 'f34-build-side-30289-signing-pending'] found while expected ['signing-pending', 'testing-pending'] [DONE]
04:59:37 - Checking datagrepper for bodhi to CI messages
05:00:07 - bodhi to CI results in datagrepper returned  - ran for: 30s                                [DONE]
05:00:07 - Checking datagrepper for CI (running) messages
05:00:37 - CI (running) results in datagrepper returned running - ran for: 30s                        [DONE]
05:00:37 - Checking datagrepper for CI (complete) messages
05:15:18 - CI (complete) results not found in datagrepper - ran for: 880s                             [FAILED]
05:15:18 - Retrieving koji tags
05:15:19 - Retrieving koji tags: ['f34-build-side-30289', 'f34-build-side-30289-testing-pending'] found while expected ['testing-pending'] [DONE]
05:15:19 - CI results query from https://taskotron.fedoraproject.org/resultsdb_api/api/v2.0/results?testcases=fedora-ci.koji-build.tier0.functional&page=0 [DONE]
05:15:19 - Checking resultsdb(phx) for CI results
05:16:21 - CI results in resultsdb(phx) returned org.centos.prod.ci.koji-build.test.error - ran for: 62s [DONE]
05:16:21 - Checking datagrepper for resultsdb messages
05:16:21 - resultsdb results in datagrepper returned FAILED - ran for: 0s                             [DONE]
05:16:21 - Checking datagrepper for greenwave messages
05:16:21 - greenwave results in datagrepper returned False - ran for: 0s                              [DONE]
05:16:21 - Retrieving koji tags
05:16:22 - Retrieving koji tags: ['f34-build-side-30289', 'f34-build-side-30289-testing-pending'] found while expected ['testing-pending'] [DONE]
05:16:22 - Checking datagrepper for CI (running) messages
05:16:22 - CI (running) results in datagrepper returned running - ran for: 0s                         [DONE]
05:16:22 - Checking datagrepper for CI (complete) messages
05:16:22 - CI (complete) results not found in datagrepper - ran for: 0s                               [FAILED]
05:16:22 - Retrieving koji tags
05:16:22 - Retrieving koji tags: ['f34-build-side-30289', 'f34-build-side-30289-testing-pending'] found while expected ['testing-pending'] [DONE]
05:16:22 - CI results query from https://taskotron.fedoraproject.org/resultsdb_api/api/v2.0/results?testcases=fedora-ci.koji-build.tier0.functional&page=0 [DONE]
05:16:22 - Checking resultsdb(phx) for CI results
05:16:53 - CI results in resultsdb(phx) returned org.centos.prod.ci.koji-build.test.error - ran for: 31s [DONE]
05:16:53 - Checking datagrepper for resultsdb messages
05:16:54 - resultsdb results in datagrepper returned FAILED - ran for: 0s                             [DONE]
05:16:54 - Checking datagrepper for greenwave messages
05:16:54 - greenwave results in datagrepper returned False - ran for: 0s                              [DONE]
05:16:54 - Retrieving koji tags
05:16:54 - Retrieving koji tags: ['f34-build-side-30289', 'f34-build-side-30289-testing-pending'] found while expected ['testing-pending'] [DONE]
05:16:54 - Waiving test results for bodhi update
05:16:56 - Waiving test results for bodhi update                                                      [DONE]
05:16:56 - Checking datagrepper for waiverdb messages
05:16:56 - waiverdb results in datagrepper returned  - ran for: 0s                                    [DONE]
05:16:56 - Checking datagrepper for greenwave messages
05:16:56 - greenwave results in datagrepper returned True - ran for: 0s                               [DONE]
05:16:56 - Retrieving koji tags
05:19:58 - Retrieving koji tags: ['f34'] found while expected f34                                     [DONE]
Ran for 2333 seconds (38.88 minutes)

2020-530762b7-670f-4a3c-9ab1-d0990f6fa6cd
complete at 2020-09-17T05:16:03.010291Z

2020-712004d3-0206-483f-8eb8-6f4498126b6b
running at 2020-09-17T05:00:17.347Z

It looks like 16 mins is barely over the timeout.

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

Metadata