[03:48:46.860] New invocation is queued and will start shortly
[03:48:48.513] Starting the invocation (attempt 1)
[03:48:48.554] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/8e58dcdf3924ad73b435cd899688ee9a3203c7b3"
[03:48:48.554] Popped gitiles commit info from properties and tags
[03:48:48.554] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:48:48.554] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:48:48.554] Buildbucket request:
{
"requestId": "8948667641083846688",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8948667641083846688",
"job": "fuchsia/firebase-docs-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6016227dd84cfc1b0a7b52581cf5ecb2f99a3730"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6016227dd84cfc1b0a7b52581cf5ecb2f99a3730",
"title": "6016227dd84cfc1b0a7b52581cf5ecb2f99a3730",
"url": "https://fuchsia.googlesource.com/integration/+/6016227dd84cfc1b0a7b52581cf5ecb2f99a3730"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "62ca550ff4d052a406bc7f3861d5a93b3bbdbbb8"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@62ca550ff4d052a406bc7f3861d5a93b3bbdbbb8",
"title": "62ca550ff4d052a406bc7f3861d5a93b3bbdbbb8",
"url": "https://fuchsia.googlesource.com/integration/+/62ca550ff4d052a406bc7f3861d5a93b3bbdbbb8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c4191c8017a6965d90f3ab49933c9e635b2b585f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c4191c8017a6965d90f3ab49933c9e635b2b585f",
"title": "c4191c8017a6965d90f3ab49933c9e635b2b585f",
"url": "https://fuchsia.googlesource.com/integration/+/c4191c8017a6965d90f3ab49933c9e635b2b585f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "294a1c6f8f55594e9a5d11ec39cf144f038ffb9b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@294a1c6f8f55594e9a5d11ec39cf144f038ffb9b",
"title": "294a1c6f8f55594e9a5d11ec39cf144f038ffb9b",
"url": "https://fuchsia.googlesource.com/integration/+/294a1c6f8f55594e9a5d11ec39cf144f038ffb9b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "efb4819a69290bbb477d3b0216101b078937e5e2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@efb4819a69290bbb477d3b0216101b078937e5e2",
"title": "efb4819a69290bbb477d3b0216101b078937e5e2",
"url": "https://fuchsia.googlesource.com/integration/+/efb4819a69290bbb477d3b0216101b078937e5e2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c5b00d69f2b40e6498d33a6d08e2d2f3cee7dca5"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c5b00d69f2b40e6498d33a6d08e2d2f3cee7dca5",
"title": "c5b00d69f2b40e6498d33a6d08e2d2f3cee7dca5",
"url": "https://fuchsia.googlesource.com/integration/+/c5b00d69f2b40e6498d33a6d08e2d2f3cee7dca5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "79708c67e47db2dc7f5746dc29bf04c5b16ea0d3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@79708c67e47db2dc7f5746dc29bf04c5b16ea0d3",
"title": "79708c67e47db2dc7f5746dc29bf04c5b16ea0d3",
"url": "https://fuchsia.googlesource.com/integration/+/79708c67e47db2dc7f5746dc29bf04c5b16ea0d3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "547a238a9a7f9b4cea89be01747315a7400d1523"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@547a238a9a7f9b4cea89be01747315a7400d1523",
"title": "547a238a9a7f9b4cea89be01747315a7400d1523",
"url": "https://fuchsia.googlesource.com/integration/+/547a238a9a7f9b4cea89be01747315a7400d1523"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "33ff0523146272a22ff40cec87469cc99b173e32"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@33ff0523146272a22ff40cec87469cc99b173e32",
"title": "33ff0523146272a22ff40cec87469cc99b173e32",
"url": "https://fuchsia.googlesource.com/integration/+/33ff0523146272a22ff40cec87469cc99b173e32"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "891707b66cfa2e720c3ba03d078ff4078917b8a4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@891707b66cfa2e720c3ba03d078ff4078917b8a4",
"title": "891707b66cfa2e720c3ba03d078ff4078917b8a4",
"url": "https://fuchsia.googlesource.com/integration/+/891707b66cfa2e720c3ba03d078ff4078917b8a4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7f1206b47bad8c7e89671201978c3acf5925f682"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7f1206b47bad8c7e89671201978c3acf5925f682",
"title": "7f1206b47bad8c7e89671201978c3acf5925f682",
"url": "https://fuchsia.googlesource.com/integration/+/7f1206b47bad8c7e89671201978c3acf5925f682"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8de0ded98d22dd49e5e3cb51b1a39e4272e7ccf4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8de0ded98d22dd49e5e3cb51b1a39e4272e7ccf4",
"title": "8de0ded98d22dd49e5e3cb51b1a39e4272e7ccf4",
"url": "https://fuchsia.googlesource.com/integration/+/8de0ded98d22dd49e5e3cb51b1a39e4272e7ccf4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ffec41237287b97e5fb836cc8af4c9c79f699139"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ffec41237287b97e5fb836cc8af4c9c79f699139",
"title": "ffec41237287b97e5fb836cc8af4c9c79f699139",
"url": "https://fuchsia.googlesource.com/integration/+/ffec41237287b97e5fb836cc8af4c9c79f699139"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8e58dcdf3924ad73b435cd899688ee9a3203c7b3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8e58dcdf3924ad73b435cd899688ee9a3203c7b3",
"title": "8e58dcdf3924ad73b435cd899688ee9a3203c7b3",
"url": "https://fuchsia.googlesource.com/integration/+/8e58dcdf3924ad73b435cd899688ee9a3203c7b3"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "8e58dcdf3924ad73b435cd899688ee9a3203c7b3",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8948667641083846688"
},
{
"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"
}
}
[03:48:48.851] Scheduled build:
{
"id": "8783237578587285169",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2023-04-21T03:48:48.615270664Z",
"updateTime": "2023-04-21T03:48:48.615270664Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "8e58dcdf3924ad73b435cd899688ee9a3203c7b3",
"ref": "refs/heads/main"
}
}
}
[03:48:48.851] Task URL: https://cr-buildbucket.appspot.com/build/8783237578587285169
[03:48:48.851] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:2:0) after 3m45s
[03:48:55.310] Received PubSub notification, asking Buildbucket for the build status
[03:48:55.340] Build status: STARTED
[03:52:33.866] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:2:0)
[03:52:33.901] Build status: STARTED
[03:52:33.901] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:4:0) after 5m47s
[03:58:20.993] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:4:0)
[03:58:21.011] Build status: STARTED
[03:58:21.012] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:5:0) after 2m9s
[04:00:30.036] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:5:0)
[04:00:30.063] Build status: STARTED
[04:00:30.063] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:6:0) after 8m7s
[04:08:37.096] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:6:0)
[04:08:37.163] Build status: STARTED
[04:08:37.163] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:7:0) after 1m47s
[04:10:24.282] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:7:0)
[04:10:24.318] Build status: STARTED
[04:10:24.318] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:8:0) after 7m49s
[04:18:13.562] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:8:0)
[04:18:13.659] Build status: STARTED
[04:18:13.659] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:9:0) after 2m55s
[04:21:08.814] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:9:0)
[04:21:08.844] Build status: STARTED
[04:21:08.844] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:10:0) after 1m45s
[04:22:53.826] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:10:0)
[04:22:53.849] Build status: STARTED
[04:22:53.849] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:11:0) after 7m38s
[04:30:32.039] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:11:0)
[04:30:32.061] Build status: STARTED
[04:30:32.061] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:12:0) after 4m39s
[04:35:11.317] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:12:0)
[04:35:11.350] Build status: STARTED
[04:35:11.350] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:13:0) after 7m57s
[04:43:08.375] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:13:0)
[04:43:08.397] Build status: STARTED
[04:43:08.397] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:14:0) after 2m7s
[04:45:15.421] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:14:0)
[04:45:15.449] Build status: STARTED
[04:45:15.449] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:15:0) after 4m53s
[04:50:08.598] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:15:0)
[04:50:08.631] Build status: STARTED
[04:50:08.631] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:16:0) after 7m48s
[04:57:56.732] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:16:0)
[04:57:56.759] Build status: STARTED
[04:57:56.759] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:17:0) after 2m42s
[05:00:38.882] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:17:0)
[05:00:38.933] Build status: STARTED
[05:00:38.933] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:18:0) after 8m7s
[05:08:45.971] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:18:0)
[05:08:46.000] Build status: STARTED
[05:08:46.000] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:19:0) after 2m6s
[05:10:52.283] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:19:0)
[05:10:52.303] Build status: STARTED
[05:10:52.303] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:20:0) after 8m6s
[05:18:58.447] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:20:0)
[05:18:58.466] Build status: STARTED
[05:18:58.466] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:21:0) after 1m28s
[05:20:26.664] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:21:0)
[05:20:26.687] Build status: STARTED
[05:20:26.687] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:22:0) after 2m41s
[05:23:07.796] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:22:0)
[05:23:07.825] Build status: STARTED
[05:23:07.825] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:23:0) after 8m29s
[05:31:36.846] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:23:0)
[05:31:36.868] Build status: STARTED
[05:31:36.868] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:24:0) after 1m22s
[05:32:58.887] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:24:0)
[05:32:58.910] Build status: STARTED
[05:32:58.910] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8948667641083846688:25:0) after 5m38s
[05:33:43.115] Received PubSub notification, asking Buildbucket for the build status
[05:33:43.138] Build:
{
"id": "8783237578587285169",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2023-04-21T03:48:48.615270664Z",
"startTime": "2023-04-21T03:48:54.025567Z",
"endTime": "2023-04-21T05:33:42.741582054Z",
"updateTime": "2023-04-21T05:33:42.741582054Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "8e58dcdf3924ad73b435cd899688ee9a3203c7b3",
"ref": "refs/heads/main"
}
}
}
[05:33:43.138] Invocation finished in 1h44m56.288292643s with status SUCCEEDED