[08:50:56.175] New invocation is queued and will start shortly
[08:50:57.253] Starting the invocation (attempt 1)
[08:50:57.285] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/ae7cc1bb6e7a513d0a1372f548930dced323f174"
[08:50:57.285] Popped gitiles commit info from properties and tags
[08:50:57.285] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[08:50:57.285] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[08:50:57.286] Buildbucket request:
{
"requestId": "8960426236755757872",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8960426236755757872",
"job": "fuchsia/firebase-docs-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3c35f2620a7ec1d95cee6270bef27019b978e0e6"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3c35f2620a7ec1d95cee6270bef27019b978e0e6",
"title": "3c35f2620a7ec1d95cee6270bef27019b978e0e6",
"url": "https://fuchsia.googlesource.com/integration/+/3c35f2620a7ec1d95cee6270bef27019b978e0e6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3b1efe9672ed61ced0844bbabe237705f6c8a972"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3b1efe9672ed61ced0844bbabe237705f6c8a972",
"title": "3b1efe9672ed61ced0844bbabe237705f6c8a972",
"url": "https://fuchsia.googlesource.com/integration/+/3b1efe9672ed61ced0844bbabe237705f6c8a972"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0e5655546efbdaf05922a4038393d67d43b74399"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0e5655546efbdaf05922a4038393d67d43b74399",
"title": "0e5655546efbdaf05922a4038393d67d43b74399",
"url": "https://fuchsia.googlesource.com/integration/+/0e5655546efbdaf05922a4038393d67d43b74399"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ab2025e31acb96d9117d10f06084cc9ab5870b5e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ab2025e31acb96d9117d10f06084cc9ab5870b5e",
"title": "ab2025e31acb96d9117d10f06084cc9ab5870b5e",
"url": "https://fuchsia.googlesource.com/integration/+/ab2025e31acb96d9117d10f06084cc9ab5870b5e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6ba4600c0cd89d9ca8209cb9e469cea569f72dc5"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6ba4600c0cd89d9ca8209cb9e469cea569f72dc5",
"title": "6ba4600c0cd89d9ca8209cb9e469cea569f72dc5",
"url": "https://fuchsia.googlesource.com/integration/+/6ba4600c0cd89d9ca8209cb9e469cea569f72dc5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "fe813d3cdb9d7e52e0ab886a46921cd2d1cd91ca"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@fe813d3cdb9d7e52e0ab886a46921cd2d1cd91ca",
"title": "fe813d3cdb9d7e52e0ab886a46921cd2d1cd91ca",
"url": "https://fuchsia.googlesource.com/integration/+/fe813d3cdb9d7e52e0ab886a46921cd2d1cd91ca"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9b99235b3e645e2e3e7b3db45dd8f088e974fed2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9b99235b3e645e2e3e7b3db45dd8f088e974fed2",
"title": "9b99235b3e645e2e3e7b3db45dd8f088e974fed2",
"url": "https://fuchsia.googlesource.com/integration/+/9b99235b3e645e2e3e7b3db45dd8f088e974fed2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f74dff8d9d4408c9d05538975717c2cc6e465c15"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f74dff8d9d4408c9d05538975717c2cc6e465c15",
"title": "f74dff8d9d4408c9d05538975717c2cc6e465c15",
"url": "https://fuchsia.googlesource.com/integration/+/f74dff8d9d4408c9d05538975717c2cc6e465c15"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "d67ec0a5c993ac5e648f7bb800a00c031b2217f5"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@d67ec0a5c993ac5e648f7bb800a00c031b2217f5",
"title": "d67ec0a5c993ac5e648f7bb800a00c031b2217f5",
"url": "https://fuchsia.googlesource.com/integration/+/d67ec0a5c993ac5e648f7bb800a00c031b2217f5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ae7cc1bb6e7a513d0a1372f548930dced323f174"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ae7cc1bb6e7a513d0a1372f548930dced323f174",
"title": "ae7cc1bb6e7a513d0a1372f548930dced323f174",
"url": "https://fuchsia.googlesource.com/integration/+/ae7cc1bb6e7a513d0a1372f548930dced323f174"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "ae7cc1bb6e7a513d0a1372f548930dced323f174",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8960426236755757872"
},
{
"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"
}
}
[08:50:57.687] Scheduled build:
{
"id": "8794996174862782081",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2022-12-12T08:50:57.350310189Z",
"updateTime": "2022-12-12T08:50:57.350310189Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "ae7cc1bb6e7a513d0a1372f548930dced323f174",
"ref": "refs/heads/main"
}
},
"canary": true
}
[08:50:57.687] Task URL: https://cr-buildbucket.appspot.com/build/8794996174862782081
[08:50:57.687] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:2:0) after 7m49s
[08:51:48.739] Received PubSub notification, asking Buildbucket for the build status
[08:51:48.783] Build status: STARTED
[08:58:46.719] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:2:0)
[08:58:46.751] Build status: STARTED
[08:58:46.751] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:4:0) after 8m16s
[09:07:02.788] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:4:0)
[09:07:02.817] Build status: STARTED
[09:07:02.817] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:5:0) after 1m30s
[09:08:32.960] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:5:0)
[09:08:32.988] Build status: STARTED
[09:08:32.988] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:6:0) after 2m17s
[09:10:50.246] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:6:0)
[09:10:50.283] Build status: STARTED
[09:10:50.283] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:7:0) after 9m58s
[09:20:48.302] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:7:0)
[09:20:48.329] Build status: STARTED
[09:20:48.329] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:8:0) after 7m12s
[09:28:00.461] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:8:0)
[09:28:00.492] Build status: STARTED
[09:28:00.492] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:9:0) after 2m34s
[09:30:34.794] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:9:0)
[09:30:34.817] Build status: STARTED
[09:30:34.817] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:10:0) after 7m13s
[09:37:47.914] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:10:0)
[09:37:47.941] Build status: STARTED
[09:37:47.941] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:11:0) after 2m11s
[09:39:58.960] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:11:0)
[09:39:58.985] Build status: STARTED
[09:39:58.985] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:12:0) after 7m23s
[09:47:22.035] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:12:0)
[09:47:22.064] Build status: STARTED
[09:47:22.064] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:13:0) after 5m44s
[09:53:06.098] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:13:0)
[09:53:06.140] Build status: STARTED
[09:53:06.140] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:14:0) after 6m27s
[09:59:33.173] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:14:0)
[09:59:33.196] Build status: STARTED
[09:59:33.196] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:15:0) after 1m6s
[10:00:39.214] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:15:0)
[10:00:39.242] Build status: STARTED
[10:00:39.242] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:16:0) after 1m23s
[10:02:02.274] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:16:0)
[10:02:02.334] Build status: STARTED
[10:02:02.334] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:17:0) after 7m31s
[10:09:33.758] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:17:0)
[10:09:33.788] Build status: STARTED
[10:09:33.788] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:18:0) after 2m39s
[10:12:12.810] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:18:0)
[10:12:12.837] Build status: STARTED
[10:12:12.837] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:19:0) after 3m15s
[10:15:28.399] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:19:0)
[10:15:28.425] Build status: STARTED
[10:15:28.425] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:20:0) after 5m53s
[10:21:21.908] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:20:0)
[10:21:21.942] Build status: STARTED
[10:21:21.942] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:21:0) after 4m36s
[10:25:58.165] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:21:0)
[10:25:58.197] Build status: STARTED
[10:25:58.197] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:22:0) after 5m11s
[10:31:09.795] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:22:0)
[10:31:09.822] Build status: STARTED
[10:31:09.822] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:23:0) after 9m56s
[10:41:06.121] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:23:0)
[10:41:06.163] Build status: STARTED
[10:41:06.163] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:24:0) after 2m26s
[10:43:32.182] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:24:0)
[10:43:32.211] Build status: STARTED
[10:43:32.211] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:25:0) after 4m54s
[10:48:26.777] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:25:0)
[10:48:26.801] Build status: STARTED
[10:48:26.801] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:26:0) after 9m53s
[10:58:20.087] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:26:0)
[10:58:20.114] Build status: STARTED
[10:58:20.114] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:27:0) after 9m53s
[11:08:13.376] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:27:0)
[11:08:13.399] Build status: STARTED
[11:08:13.399] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:28:0) after 2m57s
[11:11:10.373] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:28:0)
[11:11:10.401] Build status: STARTED
[11:11:10.401] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:29:0) after 3m22s
[11:14:32.520] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:29:0)
[11:14:32.551] Build status: STARTED
[11:14:32.551] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:30:0) after 2m28s
[11:17:00.544] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:30:0)
[11:17:00.567] Build status: STARTED
[11:17:00.567] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:31:0) after 8m22s
[11:25:22.588] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:31:0)
[11:25:22.629] Build status: STARTED
[11:25:22.629] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:32:0) after 7m36s
[11:32:58.819] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:32:0)
[11:32:58.890] Build status: STARTED
[11:32:58.890] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:33:0) after 8m55s
[11:41:54.059] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:33:0)
[11:41:54.093] Build status: STARTED
[11:41:54.093] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:34:0) after 6m19s
[11:48:13.500] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:34:0)
[11:48:13.593] Build status: STARTED
[11:48:13.593] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:35:0) after 1m57s
[11:50:10.635] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:35:0)
[11:50:10.716] Build status: STARTED
[11:50:10.716] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:36:0) after 6m56s
[11:57:06.886] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:36:0)
[11:57:06.925] Build status: STARTED
[11:57:06.926] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:37:0) after 8m11s
[12:05:18.539] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:37:0)
[12:05:18.583] Build status: STARTED
[12:05:18.583] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:38:0) after 6m14s
[12:11:32.713] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:38:0)
[12:11:32.745] Build status: STARTED
[12:11:32.745] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:39:0) after 3m48s
[12:15:20.886] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:39:0)
[12:15:20.917] Build status: STARTED
[12:15:20.917] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:40:0) after 2m17s
[12:17:38.328] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:40:0)
[12:17:38.624] Build status: STARTED
[12:17:38.624] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8960426236755757872:41:0) after 2m39s
[12:18:51.727] Received PubSub notification, asking Buildbucket for the build status
[12:18:51.793] Build:
{
"id": "8794996174862782081",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2022-12-12T08:50:57.350310189Z",
"startTime": "2022-12-12T08:51:44.226579Z",
"endTime": "2022-12-12T12:18:51.172724042Z",
"updateTime": "2022-12-12T12:18:51.172724042Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "ae7cc1bb6e7a513d0a1372f548930dced323f174",
"ref": "refs/heads/main"
}
},
"canary": true
}
[12:18:51.793] Invocation finished in 3h27m55.631957483s with status SUCCEEDED