[14:24:03.740] New invocation is queued and will start shortly
[14:24:05.144] Starting the invocation (attempt 1)
[14:24:05.181] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/0f97cb4902063c32655c22a50028e8b9831652b3"
[14:24:05.181] Popped gitiles commit info from properties and tags
[14:24:05.181] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:24:05.182] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:24:05.182] Buildbucket request:
{
"requestId": "8964482141766421072",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8964482141766421072",
"job": "fuchsia/firebase-docs-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3d67826acd2b9d30e4535f0ee0565f609804603a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3d67826acd2b9d30e4535f0ee0565f609804603a",
"title": "3d67826acd2b9d30e4535f0ee0565f609804603a",
"url": "https://fuchsia.googlesource.com/integration/+/3d67826acd2b9d30e4535f0ee0565f609804603a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "cb9a09262ba9260f86b225c77a07457f41665444"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@cb9a09262ba9260f86b225c77a07457f41665444",
"title": "cb9a09262ba9260f86b225c77a07457f41665444",
"url": "https://fuchsia.googlesource.com/integration/+/cb9a09262ba9260f86b225c77a07457f41665444"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "2d01a61cc78ccd6b96e91b735abbe22b5a6c6dd9"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@2d01a61cc78ccd6b96e91b735abbe22b5a6c6dd9",
"title": "2d01a61cc78ccd6b96e91b735abbe22b5a6c6dd9",
"url": "https://fuchsia.googlesource.com/integration/+/2d01a61cc78ccd6b96e91b735abbe22b5a6c6dd9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9232986a473737ddfcfb1f047d4fe4ebfa92dd2f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9232986a473737ddfcfb1f047d4fe4ebfa92dd2f",
"title": "9232986a473737ddfcfb1f047d4fe4ebfa92dd2f",
"url": "https://fuchsia.googlesource.com/integration/+/9232986a473737ddfcfb1f047d4fe4ebfa92dd2f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "df9312ce6af0916b9b755e33fe6da9e9ffbdc946"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@df9312ce6af0916b9b755e33fe6da9e9ffbdc946",
"title": "df9312ce6af0916b9b755e33fe6da9e9ffbdc946",
"url": "https://fuchsia.googlesource.com/integration/+/df9312ce6af0916b9b755e33fe6da9e9ffbdc946"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3bab9489395b40e4d95abfbadb2672baf488aab0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3bab9489395b40e4d95abfbadb2672baf488aab0",
"title": "3bab9489395b40e4d95abfbadb2672baf488aab0",
"url": "https://fuchsia.googlesource.com/integration/+/3bab9489395b40e4d95abfbadb2672baf488aab0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4e4dd8d55007db0f843cbc4a5d0f24de6f223f80"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4e4dd8d55007db0f843cbc4a5d0f24de6f223f80",
"title": "4e4dd8d55007db0f843cbc4a5d0f24de6f223f80",
"url": "https://fuchsia.googlesource.com/integration/+/4e4dd8d55007db0f843cbc4a5d0f24de6f223f80"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0f97cb4902063c32655c22a50028e8b9831652b3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0f97cb4902063c32655c22a50028e8b9831652b3",
"title": "0f97cb4902063c32655c22a50028e8b9831652b3",
"url": "https://fuchsia.googlesource.com/integration/+/0f97cb4902063c32655c22a50028e8b9831652b3"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "0f97cb4902063c32655c22a50028e8b9831652b3",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8964482141766421072"
},
{
"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"
}
}
[14:24:05.569] Scheduled build:
{
"id": "8799052079494561505",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2022-10-28T14:24:05.273047515Z",
"updateTime": "2022-10-28T14:24:05.273047515Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "0f97cb4902063c32655c22a50028e8b9831652b3",
"ref": "refs/heads/main"
}
}
}
[14:24:05.569] Task URL: https://cr-buildbucket.appspot.com/build/8799052079494561505
[14:24:05.570] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:2:0) after 7m6s
[14:24:07.230] Received PubSub notification, asking Buildbucket for the build status
[14:24:07.252] Build status: STARTED
[14:31:11.796] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:2:0)
[14:31:11.832] Build status: STARTED
[14:31:11.832] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:4:0) after 2m50s
[14:34:02.440] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:4:0)
[14:34:02.482] Build status: STARTED
[14:34:02.482] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:5:0) after 9m45s
[14:43:47.503] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:5:0)
[14:43:47.619] Build status: STARTED
[14:43:47.619] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:6:0) after 6m3s
[14:49:50.714] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:6:0)
[14:49:50.742] Build status: STARTED
[14:49:50.742] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:7:0) after 8m15s
[14:58:05.818] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:7:0)
[14:58:05.854] Build status: STARTED
[14:58:05.854] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:8:0) after 2m17s
[15:00:23.058] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:8:0)
[15:00:23.089] Build status: STARTED
[15:00:23.089] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:9:0) after 8m53s
[15:09:16.185] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:9:0)
[15:09:16.210] Build status: STARTED
[15:09:16.210] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:10:0) after 2m8s
[15:11:24.264] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:10:0)
[15:11:24.289] Build status: STARTED
[15:11:24.289] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:11:0) after 5m37s
[15:17:02.365] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:11:0)
[15:17:02.395] Build status: STARTED
[15:17:02.395] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:12:0) after 5m50s
[15:22:52.842] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:12:0)
[15:22:52.864] Build status: STARTED
[15:22:52.865] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:13:0) after 4m16s
[15:27:09.016] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:13:0)
[15:27:09.050] Build status: STARTED
[15:27:09.050] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:14:0) after 6m19s
[15:33:28.030] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:14:0)
[15:33:28.056] Build status: STARTED
[15:33:28.056] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:15:0) after 1m27s
[15:34:55.077] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:15:0)
[15:34:55.102] Build status: STARTED
[15:34:55.102] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:16:0) after 4m27s
[15:39:22.574] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:16:0)
[15:39:22.610] Build status: STARTED
[15:39:22.610] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:17:0) after 3m46s
[15:43:08.790] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:17:0)
[15:43:08.822] Build status: STARTED
[15:43:08.822] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:18:0) after 7m4s
[15:50:12.861] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:18:0)
[15:50:12.915] Build status: STARTED
[15:50:12.915] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:19:0) after 8m35s
[15:58:48.387] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:19:0)
[15:58:48.413] Build status: STARTED
[15:58:48.413] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:20:0) after 6m49s
[16:05:37.477] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:20:0)
[16:05:37.505] Build status: STARTED
[16:05:37.505] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:21:0) after 7m52s
[16:13:29.541] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:21:0)
[16:13:29.567] Build status: STARTED
[16:13:29.567] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:22:0) after 5m21s
[16:18:50.601] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:22:0)
[16:18:50.727] Build status: STARTED
[16:18:50.727] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:23:0) after 6m29s
[16:25:19.805] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:23:0)
[16:25:19.837] Build status: STARTED
[16:25:19.837] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:24:0) after 6m8s
[16:31:27.914] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:24:0)
[16:31:27.939] Build status: STARTED
[16:31:27.939] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:25:0) after 6m12s
[16:37:39.962] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:25:0)
[16:37:40.007] Build status: STARTED
[16:37:40.007] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:26:0) after 6m53s
[16:44:33.032] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:26:0)
[16:44:33.161] Build status: STARTED
[16:44:33.161] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:27:0) after 1m6s
[16:45:39.183] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:27:0)
[16:45:39.223] Build status: STARTED
[16:45:39.223] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:28:0) after 2m28s
[16:48:07.232] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:28:0)
[16:48:07.259] Build status: STARTED
[16:48:07.259] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:29:0) after 9m17s
[16:57:24.282] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:29:0)
[16:57:24.307] Build status: STARTED
[16:57:24.307] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:30:0) after 8m4s
[17:05:28.432] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:30:0)
[17:05:28.527] Build status: STARTED
[17:05:28.527] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:31:0) after 1m2s
[17:06:30.602] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:31:0)
[17:06:30.635] Build status: STARTED
[17:06:30.635] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:32:0) after 9m58s
[17:16:28.735] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:32:0)
[17:16:28.779] Build status: STARTED
[17:16:28.779] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:33:0) after 6m8s
[17:22:37.024] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:33:0)
[17:22:37.091] Build status: STARTED
[17:22:37.091] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:34:0) after 3m43s
[17:26:20.116] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:34:0)
[17:26:20.149] Build status: STARTED
[17:26:20.149] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:35:0) after 3m20s
[17:29:40.249] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:35:0)
[17:29:40.273] Build status: STARTED
[17:29:40.273] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8964482141766421072:36:0) after 3m9s
[17:31:33.792] Received PubSub notification, asking Buildbucket for the build status
[17:31:33.835] Build:
{
"id": "8799052079494561505",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2022-10-28T14:24:05.273047515Z",
"startTime": "2022-10-28T14:24:06.658203Z",
"endTime": "2022-10-28T17:31:33.366820339Z",
"updateTime": "2022-10-28T17:31:33.366820339Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "0f97cb4902063c32655c22a50028e8b9831652b3",
"ref": "refs/heads/main"
}
}
}
[17:31:33.835] Invocation finished in 3h7m30.113206229s with status SUCCEEDED