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)