[20:25:26.356] New invocation is queued and will start shortly
[20:25:27.574] Starting the invocation (attempt 1)
[20:25:27.604] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/0ecd3e84b7e7113d0c0a4a4c8aa6fa0e752f3edd"
[20:25:27.604] Popped gitiles commit info from properties and tags
[20:25:27.604] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:25:27.604] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:25:27.604] Buildbucket request:
{
"requestId": "8956849260706640688",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8956849260706640688",
"job": "fuchsia/firebase-docs-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8f2512f62e13213d249719a5c088bf65bff4d145"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8f2512f62e13213d249719a5c088bf65bff4d145",
"title": "8f2512f62e13213d249719a5c088bf65bff4d145",
"url": "https://fuchsia.googlesource.com/integration/+/8f2512f62e13213d249719a5c088bf65bff4d145"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b246e0d6fa87c223647810e01123460653b3912b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b246e0d6fa87c223647810e01123460653b3912b",
"title": "b246e0d6fa87c223647810e01123460653b3912b",
"url": "https://fuchsia.googlesource.com/integration/+/b246e0d6fa87c223647810e01123460653b3912b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "fcc00d99388dffb778f49fbfd3fe84d2d64d5a85"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@fcc00d99388dffb778f49fbfd3fe84d2d64d5a85",
"title": "fcc00d99388dffb778f49fbfd3fe84d2d64d5a85",
"url": "https://fuchsia.googlesource.com/integration/+/fcc00d99388dffb778f49fbfd3fe84d2d64d5a85"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "19d67a918fcccd9a27a80983e9453af8f4f25e94"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@19d67a918fcccd9a27a80983e9453af8f4f25e94",
"title": "19d67a918fcccd9a27a80983e9453af8f4f25e94",
"url": "https://fuchsia.googlesource.com/integration/+/19d67a918fcccd9a27a80983e9453af8f4f25e94"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0eddcbb461d36b3661313372bd664be11fe0927e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0eddcbb461d36b3661313372bd664be11fe0927e",
"title": "0eddcbb461d36b3661313372bd664be11fe0927e",
"url": "https://fuchsia.googlesource.com/integration/+/0eddcbb461d36b3661313372bd664be11fe0927e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9e58e20aeecc13438d82c59ecbe8eed153042d29"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9e58e20aeecc13438d82c59ecbe8eed153042d29",
"title": "9e58e20aeecc13438d82c59ecbe8eed153042d29",
"url": "https://fuchsia.googlesource.com/integration/+/9e58e20aeecc13438d82c59ecbe8eed153042d29"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5f6e539f039b330e63d373a233dee70699e9c0a4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5f6e539f039b330e63d373a233dee70699e9c0a4",
"title": "5f6e539f039b330e63d373a233dee70699e9c0a4",
"url": "https://fuchsia.googlesource.com/integration/+/5f6e539f039b330e63d373a233dee70699e9c0a4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0ecd3e84b7e7113d0c0a4a4c8aa6fa0e752f3edd"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0ecd3e84b7e7113d0c0a4a4c8aa6fa0e752f3edd",
"title": "0ecd3e84b7e7113d0c0a4a4c8aa6fa0e752f3edd",
"url": "https://fuchsia.googlesource.com/integration/+/0ecd3e84b7e7113d0c0a4a4c8aa6fa0e752f3edd"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "0ecd3e84b7e7113d0c0a4a4c8aa6fa0e752f3edd",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8956849260706640688"
},
{
"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"
}
}
[20:25:27.928] Scheduled build:
{
"id": "8791419198655472417",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2023-01-20T20:25:27.689742558Z",
"updateTime": "2023-01-20T20:25:27.689742558Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "0ecd3e84b7e7113d0c0a4a4c8aa6fa0e752f3edd",
"ref": "refs/heads/main"
}
}
}
[20:25:27.928] Task URL: https://cr-buildbucket.appspot.com/build/8791419198655472417
[20:25:27.928] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:2:0) after 5m25s
[20:28:25.087] Received PubSub notification, asking Buildbucket for the build status
[20:28:25.112] Build status: STARTED
[20:30:53.264] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:2:0)
[20:30:53.283] Build status: STARTED
[20:30:53.283] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:4:0) after 2m46s
[20:33:39.424] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:4:0)
[20:33:39.444] Build status: STARTED
[20:33:39.444] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:5:0) after 9m0s
[20:42:39.462] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:5:0)
[20:42:39.482] Build status: STARTED
[20:42:39.482] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:6:0) after 7m57s
[20:50:36.750] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:6:0)
[20:50:36.780] Build status: STARTED
[20:50:36.780] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:7:0) after 6m57s
[20:57:34.111] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:7:0)
[20:57:34.168] Build status: STARTED
[20:57:34.168] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:8:0) after 5m2s
[21:02:36.190] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:8:0)
[21:02:36.224] Build status: STARTED
[21:02:36.224] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:9:0) after 7m15s
[21:09:51.364] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:9:0)
[21:09:51.396] Build status: STARTED
[21:09:51.396] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:10:0) after 3m15s
[21:13:06.577] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:10:0)
[21:13:06.608] Build status: STARTED
[21:13:06.608] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:11:0) after 7m52s
[21:20:58.632] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:11:0)
[21:20:58.688] Build status: STARTED
[21:20:58.688] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:12:0) after 8m6s
[21:29:04.832] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:12:0)
[21:29:04.864] Build status: STARTED
[21:29:04.864] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:13:0) after 5m3s
[21:34:07.884] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:13:0)
[21:34:07.906] Build status: STARTED
[21:34:07.906] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:14:0) after 3m33s
[21:37:40.986] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:14:0)
[21:37:41.012] Build status: STARTED
[21:37:41.012] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:15:0) after 9m25s
[21:47:06.029] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:15:0)
[21:47:06.111] Build status: STARTED
[21:47:06.111] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:16:0) after 9m37s
[21:56:43.225] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:16:0)
[21:56:43.248] Build status: STARTED
[21:56:43.248] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:17:0) after 9m57s
[22:06:40.468] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:17:0)
[22:06:40.496] Build status: STARTED
[22:06:40.496] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:18:0) after 2m7s
[22:08:48.157] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:18:0)
[22:08:48.215] Build status: STARTED
[22:08:48.215] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:19:0) after 1m17s
[22:10:05.373] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:19:0)
[22:10:05.400] Build status: STARTED
[22:10:05.400] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:20:0) after 4m28s
[22:14:33.420] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:20:0)
[22:14:33.445] Build status: STARTED
[22:14:33.445] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:21:0) after 4m12s
[22:18:45.506] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:21:0)
[22:18:45.532] Build status: STARTED
[22:18:45.532] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:22:0) after 2m22s
[22:21:07.520] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:22:0)
[22:21:07.543] Build status: STARTED
[22:21:07.544] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:23:0) after 4m0s
[22:25:07.563] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:23:0)
[22:25:07.584] Build status: STARTED
[22:25:07.584] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:24:0) after 5m5s
[22:30:12.616] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:24:0)
[22:30:12.639] Build status: STARTED
[22:30:12.639] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:25:0) after 9m31s
[22:39:43.659] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:25:0)
[22:39:43.680] Build status: STARTED
[22:39:43.680] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:26:0) after 4m59s
[22:44:42.897] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:26:0)
[22:44:42.921] Build status: STARTED
[22:44:42.921] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:27:0) after 2m1s
[22:46:44.265] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:27:0)
[22:46:44.418] Build status: STARTED
[22:46:44.418] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:28:0) after 5m48s
[22:52:32.608] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:28:0)
[22:52:32.672] Build status: STARTED
[22:52:32.672] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:29:0) after 5m37s
[22:58:09.703] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:29:0)
[22:58:09.722] Build status: STARTED
[22:58:09.722] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:30:0) after 7m19s
[23:05:28.952] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:30:0)
[23:05:28.986] Build status: STARTED
[23:05:28.987] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:31:0) after 8m14s
[23:13:43.105] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:31:0)
[23:13:43.133] Build status: STARTED
[23:13:43.133] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:32:0) after 8m36s
[23:22:19.223] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:32:0)
[23:22:19.247] Build status: STARTED
[23:22:19.247] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:33:0) after 2m1s
[23:24:20.679] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:33:0)
[23:24:20.705] Build status: STARTED
[23:24:20.705] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:34:0) after 5m39s
[23:29:59.851] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:34:0)
[23:29:59.878] Build status: STARTED
[23:29:59.878] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:35:0) after 4m31s
[23:34:30.908] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:35:0)
[23:34:30.932] Build status: STARTED
[23:34:30.932] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956849260706640688:36:0) after 8m59s
[23:35:14.808] Received PubSub notification, asking Buildbucket for the build status
[23:35:14.867] Build:
{
"id": "8791419198655472417",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2023-01-20T20:25:27.689742558Z",
"startTime": "2023-01-20T20:28:24.235406Z",
"endTime": "2023-01-20T23:35:14.584164490Z",
"updateTime": "2023-01-20T23:35:14.584164490Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "0ecd3e84b7e7113d0c0a4a4c8aa6fa0e752f3edd",
"ref": "refs/heads/main"
}
}
}
[23:35:14.867] Invocation finished in 3h9m48.518365825s with status SUCCEEDED