[18:27:24.068] New invocation is queued and will start shortly
[18:27:25.131] Starting the invocation (attempt 1)
[18:27:25.160] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/eabd688f9c9fa7f1eec0100c68d60d45afd9bb1a"
[18:27:25.160] Popped gitiles commit info from properties and tags
[18:27:25.160] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:27:25.161] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:27:25.161] Buildbucket request:
{
"requestId": "8900414776955556304",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8900414776955556304",
"job": "fuchsia/firebase-docs-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3c96a1f6ae73003ee05497df06461b7f4e2da755"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3c96a1f6ae73003ee05497df06461b7f4e2da755",
"title": "3c96a1f6ae73003ee05497df06461b7f4e2da755",
"url": "https://fuchsia.googlesource.com/integration/+/3c96a1f6ae73003ee05497df06461b7f4e2da755"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "20ff0dd4dbe22d0a873e3874f5a1abdbbfb058a2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@20ff0dd4dbe22d0a873e3874f5a1abdbbfb058a2",
"title": "20ff0dd4dbe22d0a873e3874f5a1abdbbfb058a2",
"url": "https://fuchsia.googlesource.com/integration/+/20ff0dd4dbe22d0a873e3874f5a1abdbbfb058a2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f94f0a6fc1e39300315de40da780f7941e28ddb3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f94f0a6fc1e39300315de40da780f7941e28ddb3",
"title": "f94f0a6fc1e39300315de40da780f7941e28ddb3",
"url": "https://fuchsia.googlesource.com/integration/+/f94f0a6fc1e39300315de40da780f7941e28ddb3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1c7cfdbf286199358c1f3b01925f29eeca947faf"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1c7cfdbf286199358c1f3b01925f29eeca947faf",
"title": "1c7cfdbf286199358c1f3b01925f29eeca947faf",
"url": "https://fuchsia.googlesource.com/integration/+/1c7cfdbf286199358c1f3b01925f29eeca947faf"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8f8bf9b49c1f8d3d117889f6e3efced07b0e3587"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8f8bf9b49c1f8d3d117889f6e3efced07b0e3587",
"title": "8f8bf9b49c1f8d3d117889f6e3efced07b0e3587",
"url": "https://fuchsia.googlesource.com/integration/+/8f8bf9b49c1f8d3d117889f6e3efced07b0e3587"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "071ae2ced3cc478e2f2ca85a38a960459e6306ed"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@071ae2ced3cc478e2f2ca85a38a960459e6306ed",
"title": "071ae2ced3cc478e2f2ca85a38a960459e6306ed",
"url": "https://fuchsia.googlesource.com/integration/+/071ae2ced3cc478e2f2ca85a38a960459e6306ed"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ff79ebbe2d93ab57d48c8572c6160ad137df9e3e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ff79ebbe2d93ab57d48c8572c6160ad137df9e3e",
"title": "ff79ebbe2d93ab57d48c8572c6160ad137df9e3e",
"url": "https://fuchsia.googlesource.com/integration/+/ff79ebbe2d93ab57d48c8572c6160ad137df9e3e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "17def703d798f2b7cefacb8fd76931cb4aa1a2b9"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@17def703d798f2b7cefacb8fd76931cb4aa1a2b9",
"title": "17def703d798f2b7cefacb8fd76931cb4aa1a2b9",
"url": "https://fuchsia.googlesource.com/integration/+/17def703d798f2b7cefacb8fd76931cb4aa1a2b9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f6b7ef8be84430963b17107768ce9f5381497200"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f6b7ef8be84430963b17107768ce9f5381497200",
"title": "f6b7ef8be84430963b17107768ce9f5381497200",
"url": "https://fuchsia.googlesource.com/integration/+/f6b7ef8be84430963b17107768ce9f5381497200"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c1221f5dd7f458342da3b851de7d75f4e85a3c4e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c1221f5dd7f458342da3b851de7d75f4e85a3c4e",
"title": "c1221f5dd7f458342da3b851de7d75f4e85a3c4e",
"url": "https://fuchsia.googlesource.com/integration/+/c1221f5dd7f458342da3b851de7d75f4e85a3c4e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9f5e66a90bb22fa1f42c1ce8967b12ca804107c1"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9f5e66a90bb22fa1f42c1ce8967b12ca804107c1",
"title": "9f5e66a90bb22fa1f42c1ce8967b12ca804107c1",
"url": "https://fuchsia.googlesource.com/integration/+/9f5e66a90bb22fa1f42c1ce8967b12ca804107c1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f4694a9393f688ace5463a6da22413d59427a5c0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f4694a9393f688ace5463a6da22413d59427a5c0",
"title": "f4694a9393f688ace5463a6da22413d59427a5c0",
"url": "https://fuchsia.googlesource.com/integration/+/f4694a9393f688ace5463a6da22413d59427a5c0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e9c62cb98d0b198f8e2cf2d083f8cc5c9e12ca4c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e9c62cb98d0b198f8e2cf2d083f8cc5c9e12ca4c",
"title": "e9c62cb98d0b198f8e2cf2d083f8cc5c9e12ca4c",
"url": "https://fuchsia.googlesource.com/integration/+/e9c62cb98d0b198f8e2cf2d083f8cc5c9e12ca4c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7699071f31ab2424df9ddae1f69e0bbda395a734"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7699071f31ab2424df9ddae1f69e0bbda395a734",
"title": "7699071f31ab2424df9ddae1f69e0bbda395a734",
"url": "https://fuchsia.googlesource.com/integration/+/7699071f31ab2424df9ddae1f69e0bbda395a734"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "eabd688f9c9fa7f1eec0100c68d60d45afd9bb1a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@eabd688f9c9fa7f1eec0100c68d60d45afd9bb1a",
"title": "eabd688f9c9fa7f1eec0100c68d60d45afd9bb1a",
"url": "https://fuchsia.googlesource.com/integration/+/eabd688f9c9fa7f1eec0100c68d60d45afd9bb1a"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "eabd688f9c9fa7f1eec0100c68d60d45afd9bb1a",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8900414776955556304"
},
{
"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"
}
}
[18:27:25.839] Scheduled build:
{
"id": "8734984715094804849",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2024-10-04T18:27:25.220106180Z",
"updateTime": "2024-10-04T18:27:25.220106180Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "eabd688f9c9fa7f1eec0100c68d60d45afd9bb1a",
"ref": "refs/heads/main"
}
}
}
[18:27:25.839] Task URL: https://cr-buildbucket.appspot.com/build/8734984715094804849
[18:27:25.839] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:2:0) after 1m14s
[18:27:26.652] Received PubSub notification, asking Buildbucket for the build status
[18:27:26.717] Build status: SCHEDULED
[18:27:37.831] Received PubSub notification, asking Buildbucket for the build status
[18:27:37.848] Build status: STARTED
[18:28:39.925] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:2:0)
[18:28:39.944] Build status: STARTED
[18:28:39.944] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:5:0) after 1m50s
[18:30:29.962] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:5:0)
[18:30:30.099] Build status: STARTED
[18:30:30.099] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:6:0) after 9m4s
[18:39:34.113] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:6:0)
[18:39:34.137] Build status: STARTED
[18:39:34.137] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:7:0) after 3m47s
[18:43:21.194] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:7:0)
[18:43:21.211] Build status: STARTED
[18:43:21.211] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:8:0) after 1m39s
[18:45:00.253] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:8:0)
[18:45:00.275] Build status: STARTED
[18:45:00.275] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:9:0) after 3m40s
[18:48:40.296] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:9:0)
[18:48:40.316] Build status: STARTED
[18:48:40.316] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:10:0) after 9m20s
[18:58:00.337] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:10:0)
[18:58:00.361] Build status: STARTED
[18:58:00.361] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:11:0) after 1m39s
[18:59:39.384] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:11:0)
[18:59:39.404] Build status: STARTED
[18:59:39.404] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:12:0) after 1m27s
[19:01:06.434] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:12:0)
[19:01:06.572] Build status: STARTED
[19:01:06.572] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:13:0) after 2m8s
[19:03:14.544] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:13:0)
[19:03:14.565] Build status: STARTED
[19:03:14.565] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:14:0) after 6m40s
[19:09:54.681] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:14:0)
[19:09:54.696] Build status: STARTED
[19:09:54.696] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:15:0) after 8m34s
[19:18:28.723] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:15:0)
[19:18:28.740] Build status: STARTED
[19:18:28.740] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:16:0) after 3m7s
[19:21:35.750] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:16:0)
[19:21:35.769] Build status: STARTED
[19:21:35.769] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:17:0) after 1m31s
[19:23:06.792] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:17:0)
[19:23:06.812] Build status: STARTED
[19:23:06.812] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:18:0) after 9m27s
[19:32:33.866] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:18:0)
[19:32:33.889] Build status: STARTED
[19:32:33.889] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:19:0) after 5m55s
[19:38:28.864] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:19:0)
[19:38:28.887] Build status: STARTED
[19:38:28.887] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:20:0) after 2m44s
[19:41:12.904] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:20:0)
[19:41:12.921] Build status: STARTED
[19:41:12.921] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:21:0) after 9m17s
[19:50:29.939] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:21:0)
[19:50:29.958] Build status: STARTED
[19:50:29.958] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:22:0) after 7m35s
[19:58:04.977] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:22:0)
[19:58:04.993] Build status: STARTED
[19:58:04.993] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:23:0) after 9m1s
[20:07:06.022] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:23:0)
[20:07:06.040] Build status: STARTED
[20:07:06.040] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:24:0) after 5m48s
[20:12:54.143] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:24:0)
[20:12:54.163] Build status: STARTED
[20:12:54.163] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:25:0) after 1m46s
[20:14:40.420] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:25:0)
[20:14:40.466] Build status: STARTED
[20:14:40.466] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:26:0) after 4m57s
[20:19:37.487] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:26:0)
[20:19:37.506] Build status: STARTED
[20:19:37.506] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:27:0) after 4m1s
[20:23:38.542] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:27:0)
[20:23:38.603] Build status: STARTED
[20:23:38.603] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:28:0) after 5m20s
[20:28:58.626] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:28:0)
[20:28:58.672] Build status: STARTED
[20:28:58.672] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:29:0) after 8m32s
[20:37:30.740] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:29:0)
[20:37:30.804] Build status: STARTED
[20:37:30.804] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:30:0) after 4m44s
[20:42:14.855] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:30:0)
[20:42:14.873] Build status: STARTED
[20:42:14.873] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:31:0) after 6m2s
[20:48:16.893] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:31:0)
[20:48:16.911] Build status: STARTED
[20:48:16.911] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:32:0) after 7m35s
[20:55:51.934] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:32:0)
[20:55:51.953] Build status: STARTED
[20:55:51.953] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:33:0) after 2m6s
[20:57:57.970] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:33:0)
[20:57:57.989] Build status: STARTED
[20:57:57.989] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:34:0) after 1m11s
[20:59:09.006] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:34:0)
[20:59:09.039] Build status: STARTED
[20:59:09.039] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:35:0) after 5m53s
[21:05:02.059] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:35:0)
[21:05:02.081] Build status: STARTED
[21:05:02.081] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8900414776955556304:36:0) after 3m19s
[21:07:21.754] Received PubSub notification, asking Buildbucket for the build status
[21:07:21.770] Build:
{
"id": "8734984715094804849",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2024-10-04T18:27:25.220106180Z",
"startTime": "2024-10-04T18:27:37.660079191Z",
"endTime": "2024-10-04T21:07:12.407887451Z",
"updateTime": "2024-10-04T21:07:12.407887451Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "eabd688f9c9fa7f1eec0100c68d60d45afd9bb1a",
"ref": "refs/heads/main"
}
}
}
[21:07:21.770] Invocation finished in 2h39m57.707683211s with status SUCCEEDED