[16:31:43.049] New invocation is queued and will start shortly
[16:31:44.570] Starting the invocation (attempt 1)
[16:31:44.597] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/ebb06d62b6b6a185fc5782a956c43cb92e84033f"
[16:31:44.597] Popped gitiles commit info from properties and tags
[16:31:44.597] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[16:31:44.597] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[16:31:44.598] Buildbucket request:
{
"requestId": "8941100093062610704",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8941100093062610704",
"job": "fuchsia/firebase-docs-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a359391899bcd5229ccb054c5cea113fc71319c1"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a359391899bcd5229ccb054c5cea113fc71319c1",
"title": "a359391899bcd5229ccb054c5cea113fc71319c1",
"url": "https://fuchsia.googlesource.com/integration/+/a359391899bcd5229ccb054c5cea113fc71319c1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "55e876399570079705e674f0fdb06f2b9632b5e5"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@55e876399570079705e674f0fdb06f2b9632b5e5",
"title": "55e876399570079705e674f0fdb06f2b9632b5e5",
"url": "https://fuchsia.googlesource.com/integration/+/55e876399570079705e674f0fdb06f2b9632b5e5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7b0d195a0bbf537250f396f5cc57b9fcf30f8bbd"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7b0d195a0bbf537250f396f5cc57b9fcf30f8bbd",
"title": "7b0d195a0bbf537250f396f5cc57b9fcf30f8bbd",
"url": "https://fuchsia.googlesource.com/integration/+/7b0d195a0bbf537250f396f5cc57b9fcf30f8bbd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "41d26da960e3d29059ffb99f3b433b565d4575f1"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@41d26da960e3d29059ffb99f3b433b565d4575f1",
"title": "41d26da960e3d29059ffb99f3b433b565d4575f1",
"url": "https://fuchsia.googlesource.com/integration/+/41d26da960e3d29059ffb99f3b433b565d4575f1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d149a8df8c3c1237968417452e3d5a9d55a93f5c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d149a8df8c3c1237968417452e3d5a9d55a93f5c",
"title": "d149a8df8c3c1237968417452e3d5a9d55a93f5c",
"url": "https://fuchsia.googlesource.com/integration/+/d149a8df8c3c1237968417452e3d5a9d55a93f5c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "672c69ba1c20f9d9fcc54c61d61964f2e92a2818"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@672c69ba1c20f9d9fcc54c61d61964f2e92a2818",
"title": "672c69ba1c20f9d9fcc54c61d61964f2e92a2818",
"url": "https://fuchsia.googlesource.com/integration/+/672c69ba1c20f9d9fcc54c61d61964f2e92a2818"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8e210dc879a1693eb9126d3276646c3e7256b97f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8e210dc879a1693eb9126d3276646c3e7256b97f",
"title": "8e210dc879a1693eb9126d3276646c3e7256b97f",
"url": "https://fuchsia.googlesource.com/integration/+/8e210dc879a1693eb9126d3276646c3e7256b97f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f8a144379bbc8e80197062865f49556eaf0588ab"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f8a144379bbc8e80197062865f49556eaf0588ab",
"title": "f8a144379bbc8e80197062865f49556eaf0588ab",
"url": "https://fuchsia.googlesource.com/integration/+/f8a144379bbc8e80197062865f49556eaf0588ab"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ee34a32cac8b20379134f583d98c320dd4cf41e2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ee34a32cac8b20379134f583d98c320dd4cf41e2",
"title": "ee34a32cac8b20379134f583d98c320dd4cf41e2",
"url": "https://fuchsia.googlesource.com/integration/+/ee34a32cac8b20379134f583d98c320dd4cf41e2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3e7a6173d339ea1eda93d88a57a4d82d2a63f882"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3e7a6173d339ea1eda93d88a57a4d82d2a63f882",
"title": "3e7a6173d339ea1eda93d88a57a4d82d2a63f882",
"url": "https://fuchsia.googlesource.com/integration/+/3e7a6173d339ea1eda93d88a57a4d82d2a63f882"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b672c771f18d301ac8fdc1e51b565b42a969a3ca"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b672c771f18d301ac8fdc1e51b565b42a969a3ca",
"title": "b672c771f18d301ac8fdc1e51b565b42a969a3ca",
"url": "https://fuchsia.googlesource.com/integration/+/b672c771f18d301ac8fdc1e51b565b42a969a3ca"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ee2ee0d7bc348ffae53f9e313613487387f2d94e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ee2ee0d7bc348ffae53f9e313613487387f2d94e",
"title": "ee2ee0d7bc348ffae53f9e313613487387f2d94e",
"url": "https://fuchsia.googlesource.com/integration/+/ee2ee0d7bc348ffae53f9e313613487387f2d94e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "93920acc2c32148416af70cd4d1754dee1989351"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@93920acc2c32148416af70cd4d1754dee1989351",
"title": "93920acc2c32148416af70cd4d1754dee1989351",
"url": "https://fuchsia.googlesource.com/integration/+/93920acc2c32148416af70cd4d1754dee1989351"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ebb06d62b6b6a185fc5782a956c43cb92e84033f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ebb06d62b6b6a185fc5782a956c43cb92e84033f",
"title": "ebb06d62b6b6a185fc5782a956c43cb92e84033f",
"url": "https://fuchsia.googlesource.com/integration/+/ebb06d62b6b6a185fc5782a956c43cb92e84033f"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "ebb06d62b6b6a185fc5782a956c43cb92e84033f",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8941100093062610704"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/firebase-docs-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[16:31:44.971] Scheduled build:
{
"id": "8775670030672534785",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2023-07-13T16:31:44.699775969Z",
"updateTime": "2023-07-13T16:31:44.699775969Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "ebb06d62b6b6a185fc5782a956c43cb92e84033f",
"ref": "refs/heads/main"
}
},
"canary": true
}
[16:31:44.971] Task URL: https://cr-buildbucket.appspot.com/build/8775670030672534785
[16:31:44.972] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:2:0) after 8m13s
[16:34:12.439] Received PubSub notification, asking Buildbucket for the build status
[16:34:12.454] Build status: STARTED
[16:39:57.999] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:2:0)
[16:39:58.106] Build status: STARTED
[16:39:58.106] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:4:0) after 1m26s
[16:41:24.977] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:4:0)
[16:41:25.010] Build status: STARTED
[16:41:25.012] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:5:0) after 4m36s
[16:46:01.028] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:5:0)
[16:46:01.076] Build status: STARTED
[16:46:01.076] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:6:0) after 2m9s
[16:48:10.098] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:6:0)
[16:48:10.172] Build status: STARTED
[16:48:10.172] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:7:0) after 9m50s
[16:58:00.350] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:7:0)
[16:58:00.414] Build status: STARTED
[16:58:00.414] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:8:0) after 7m40s
[17:05:40.431] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:8:0)
[17:05:40.451] Build status: STARTED
[17:05:40.451] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:9:0) after 6m13s
[17:11:53.524] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:9:0)
[17:11:53.554] Build status: STARTED
[17:11:53.554] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:10:0) after 1m4s
[17:12:57.584] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:10:0)
[17:12:57.610] Build status: STARTED
[17:12:57.610] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:11:0) after 6m38s
[17:19:35.632] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:11:0)
[17:19:35.651] Build status: STARTED
[17:19:35.651] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:12:0) after 8m30s
[17:28:05.715] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:12:0)
[17:28:05.735] Build status: STARTED
[17:28:05.735] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:13:0) after 3m0s
[17:31:05.909] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:13:0)
[17:31:05.996] Build status: STARTED
[17:31:05.996] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:14:0) after 5m45s
[17:36:51.085] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:14:0)
[17:36:51.120] Build status: STARTED
[17:36:51.121] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:15:0) after 9m35s
[17:46:26.348] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:15:0)
[17:46:26.370] Build status: STARTED
[17:46:26.370] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:16:0) after 5m2s
[17:51:28.404] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:16:0)
[17:51:28.424] Build status: STARTED
[17:51:28.424] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:17:0) after 5m6s
[17:56:34.599] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:17:0)
[17:56:34.666] Build status: STARTED
[17:56:34.666] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:18:0) after 6m15s
[18:02:49.728] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:18:0)
[18:02:49.756] Build status: STARTED
[18:02:49.756] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:19:0) after 8m19s
[18:11:09.035] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:19:0)
[18:11:09.065] Build status: STARTED
[18:11:09.065] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:20:0) after 3m2s
[18:14:11.160] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:20:0)
[18:14:11.195] Build status: STARTED
[18:14:11.195] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:21:0) after 6m55s
[18:21:06.254] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:21:0)
[18:21:06.294] Build status: STARTED
[18:21:06.294] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:22:0) after 6m11s
[18:27:17.378] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:22:0)
[18:27:17.405] Build status: STARTED
[18:27:17.405] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:23:0) after 4m59s
[18:32:16.596] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:23:0)
[18:32:16.631] Build status: STARTED
[18:32:16.631] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:24:0) after 7m12s
[18:39:28.734] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:24:0)
[18:39:28.758] Build status: STARTED
[18:39:28.758] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:25:0) after 5m36s
[18:45:04.790] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:25:0)
[18:45:04.815] Build status: STARTED
[18:45:04.815] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:26:0) after 2m42s
[18:47:46.907] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:26:0)
[18:47:46.923] Build status: STARTED
[18:47:46.923] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:27:0) after 5m18s
[18:53:05.138] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:27:0)
[18:53:05.165] Build status: STARTED
[18:53:05.165] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:28:0) after 9m19s
[19:02:25.246] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:28:0)
[19:02:25.263] Build status: STARTED
[19:02:25.264] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:29:0) after 2m14s
[19:04:39.286] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:29:0)
[19:04:39.314] Build status: STARTED
[19:04:39.314] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8941100093062610704:30:0) after 3m7s
[19:07:29.198] Received PubSub notification, asking Buildbucket for the build status
[19:07:29.234] Build:
{
"id": "8775670030672534785",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2023-07-13T16:31:44.699775969Z",
"startTime": "2023-07-13T16:34:10.944250Z",
"endTime": "2023-07-13T19:07:28.524771564Z",
"updateTime": "2023-07-13T19:07:28.524771564Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "ebb06d62b6b6a185fc5782a956c43cb92e84033f",
"ref": "refs/heads/main"
}
},
"canary": true
}
[19:07:29.234] Invocation finished in 2h35m46.198566319s with status SUCCEEDED