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

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

The suspects are 'datagrepper, resultsdb, datagrepper'.

Full log:

14:22:23 - Cloning as packagerbot the git repo: rpms/dummy-test-package-gloster
14:22:29 - Cloning as packagerbot the git repo: rpms/dummy-test-package-gloster                       [DONE]
14:22:29 - Switching to branch: master
14:22:30 - Switching to branch: master                                                                [DONE]
14:22:30 - Bumping release of: dummy-test-package-gloster.spec
14:22:31 - Bumping release of: dummy-test-package-gloster.spec                                        [DONE]
14:22:31 - Commiting changes
14:22:31 - Commiting changes                                                                          [DONE]
14:22:31 - Getting nevr
14:22:32 - Getting nevr                                                                               [DONE]
14:22:32 - Upcoming build : dummy-test-package-gloster-0-1469.fc34                                    [DONE]
14:22:32 - Pushing changes
14:22:39 - Pushing changes                                                                            [DONE]
14:22:39 - The build should be found on: https://koji.fedoraproject.org/koji/search?match=glob&type=build&terms=dummy-test-package-gloster-0-1469.fc34 [DONE]
14:22:39 - The build should be found in: https://koji.fedoraproject.org/koji/search?match=glob&type=package&terms=dummy-test-package-gloster [DONE]
14:22:39 - Building the package
14:25:40 - Building the package                                                                       [DONE]
14:25:40 - Retrieving koji tags
14:25:41 - Retrieving koji tags: ['f34-updates-candidate', 'f34-updates-testing-pending'] found while expected ['updates-candidate', 'signing-pending'] [DONE]
14:25:41 - Retrieving update created from https://bodhi.fedoraproject.org/updates/?builds=dummy-test-package-gloster-0-1469.fc34
14:25:41 - Retrieving update created from https://bodhi.fedoraproject.org/updates/?builds=dummy-test-package-gloster-0-1469.fc34 [DONE]
14:25:41 - Update automatically created : https://bodhi.fedoraproject.org/updates/FEDORA-2020-32e846c093 [DONE]
14:25:41 - Retrieving koji tags
14:25:41 - Retrieving koji tags: ['f34-updates-candidate', 'f34-updates-testing-pending'] found while expected ['signing-pending', 'testing-pending'] [DONE]
14:25:41 - Checking datagrepper for bodhi to CI messages
14:25:41 - bodhi to CI results in datagrepper returned  - ran for: 0s                                 [DONE]
14:25:41 - Checking datagrepper for CI (running) messages
14:25:41 - CI (running) results in datagrepper returned running - ran for: 0s                         [DONE]
14:25:41 - Checking datagrepper for CI (complete) messages
14:56:02 - CI (complete) results not found in datagrepper - ran for: 1820s                            [FAILED]
14:56:02 - Retrieving koji tags
14:56:03 - Retrieving koji tags: ['f34-updates-candidate', 'f34-updates-testing-pending'] found while expected ['testing-pending'] [DONE]
14:56:03 - CI results query from https://taskotron.fedoraproject.org/resultsdb_api/api/v2.0/results?testcases=fedora-ci.koji-build.tier0.functional&page=0 [DONE]
14:56:03 - Checking resultsdb(phx) for CI results
15:11:09 - CI results did not show in resultsdb(phx) for dummy-test-package-gloster-0-1469.fc34 within 900 minutes since 2020-09-20 14:56:03.021347 - ran for: 906s [FAILED]
15:11:09 - Checking datagrepper for resultsdb messages
15:11:09 - resultsdb results in datagrepper returned PASSED - ran for: 0s                             [DONE]
15:11:09 - Checking datagrepper for greenwave messages
15:25:52 - greenwave results not found in datagrepper - ran for: 883s                                 [FAILED]
15:25:52 - Retrieving koji tags
15:25:54 - Retrieving koji tags: ['f34-updates-candidate', 'f34-updates-testing-pending'] found while expected ['testing-pending'] [DONE]
15:25:54 - Waiving test results for bodhi update
15:25:56 - Waiving test results for bodhi update                                                      [DONE]
15:25:56 - Checking datagrepper for waiverdb messages
15:25:56 - waiverdb results in datagrepper returned  - ran for: 0s                                    [DONE]
15:25:56 - Checking datagrepper for greenwave messages
15:26:26 - greenwave results in datagrepper returned True - ran for: 30s                              [DONE]
15:26:26 - Retrieving koji tags
15:28:29 - Retrieving koji tags: ['f34'] found while expected f34                                     [DONE]
Ran for 3965 seconds (66.08 minutes)

Found result on 2020-09-20T17:39:48.744375 : https://taskotron.fedoraproject.org/resultsdb_api/api/v2.0/results/39473228

Supposedly it only took 10 minutes in jenkins UI to run the job, but it took more than 25 minutes to get the message?

2020-09-20T17:39:48.382844Z is the sending of the error? 2020-09-20T14:24:51.954Z is the time it started running. This seems to be several hours later?

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

Metadata