[15:54:02.561] New invocation is queued and will start shortly
[15:54:03.840] Starting the invocation (attempt 1)
[15:54:03.879] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/c2f6cbfb8e6ce4d97c6943160ce2d9d2e19a8a64"
[15:54:03.879] Popped gitiles commit info from properties and tags
[15:54:03.879] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:54:03.880] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:54:03.880] Buildbucket request:
{
"requestId": "8966379016979631568",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8966379016979631568",
"job": "fuchsia/firebase-docs-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "12b99c79f20bddac10a3b47144f7534a19c983d2"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@12b99c79f20bddac10a3b47144f7534a19c983d2",
"title": "12b99c79f20bddac10a3b47144f7534a19c983d2",
"url": "https://fuchsia.googlesource.com/integration/+/12b99c79f20bddac10a3b47144f7534a19c983d2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "fdff7afb7404f7e2fa587250f5878dbc5f157f98"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@fdff7afb7404f7e2fa587250f5878dbc5f157f98",
"title": "fdff7afb7404f7e2fa587250f5878dbc5f157f98",
"url": "https://fuchsia.googlesource.com/integration/+/fdff7afb7404f7e2fa587250f5878dbc5f157f98"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "aad8c6dfa60dff8a95c6077bf1d557a9396ee015"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@aad8c6dfa60dff8a95c6077bf1d557a9396ee015",
"title": "aad8c6dfa60dff8a95c6077bf1d557a9396ee015",
"url": "https://fuchsia.googlesource.com/integration/+/aad8c6dfa60dff8a95c6077bf1d557a9396ee015"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ec1d1df510f79f7bda51805037708af62f20043f"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ec1d1df510f79f7bda51805037708af62f20043f",
"title": "ec1d1df510f79f7bda51805037708af62f20043f",
"url": "https://fuchsia.googlesource.com/integration/+/ec1d1df510f79f7bda51805037708af62f20043f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "73e8443501314f034b555959b9ff6bb516aec092"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@73e8443501314f034b555959b9ff6bb516aec092",
"title": "73e8443501314f034b555959b9ff6bb516aec092",
"url": "https://fuchsia.googlesource.com/integration/+/73e8443501314f034b555959b9ff6bb516aec092"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4d6b6a4dfc77be5fd3b5174766e3a12f15e17306"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4d6b6a4dfc77be5fd3b5174766e3a12f15e17306",
"title": "4d6b6a4dfc77be5fd3b5174766e3a12f15e17306",
"url": "https://fuchsia.googlesource.com/integration/+/4d6b6a4dfc77be5fd3b5174766e3a12f15e17306"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "29d902ff010f438c90687098f252312bf6bfddec"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@29d902ff010f438c90687098f252312bf6bfddec",
"title": "29d902ff010f438c90687098f252312bf6bfddec",
"url": "https://fuchsia.googlesource.com/integration/+/29d902ff010f438c90687098f252312bf6bfddec"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b51a0eecd669b39287ec39bbc1ec4e9bc6d231f1"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b51a0eecd669b39287ec39bbc1ec4e9bc6d231f1",
"title": "b51a0eecd669b39287ec39bbc1ec4e9bc6d231f1",
"url": "https://fuchsia.googlesource.com/integration/+/b51a0eecd669b39287ec39bbc1ec4e9bc6d231f1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a4ecd3003a22348797083ba98870d3064364be24"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a4ecd3003a22348797083ba98870d3064364be24",
"title": "a4ecd3003a22348797083ba98870d3064364be24",
"url": "https://fuchsia.googlesource.com/integration/+/a4ecd3003a22348797083ba98870d3064364be24"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c2f6cbfb8e6ce4d97c6943160ce2d9d2e19a8a64"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c2f6cbfb8e6ce4d97c6943160ce2d9d2e19a8a64",
"title": "c2f6cbfb8e6ce4d97c6943160ce2d9d2e19a8a64",
"url": "https://fuchsia.googlesource.com/integration/+/c2f6cbfb8e6ce4d97c6943160ce2d9d2e19a8a64"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "c2f6cbfb8e6ce4d97c6943160ce2d9d2e19a8a64",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8966379016979631568"
},
{
"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"
}
}
[15:54:04.319] Scheduled build:
{
"id": "8800948954848622961",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2022-10-07T15:54:03.966669934Z",
"updateTime": "2022-10-07T15:54:03.966669934Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "c2f6cbfb8e6ce4d97c6943160ce2d9d2e19a8a64",
"ref": "refs/heads/main"
}
}
}
[15:54:04.319] Task URL: https://cr-buildbucket.appspot.com/build/8800948954848622961
[15:54:04.319] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:2:0) after 3m12s
[15:54:50.576] Received PubSub notification, asking Buildbucket for the build status
[15:54:50.616] Build status: STARTED
[15:57:16.768] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:2:0)
[15:57:16.803] Build status: STARTED
[15:57:16.803] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:4:0) after 5m40s
[16:02:57.077] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:4:0)
[16:02:57.108] Build status: STARTED
[16:02:57.108] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:5:0) after 5m42s
[16:08:39.114] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:5:0)
[16:08:39.147] Build status: STARTED
[16:08:39.147] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:6:0) after 1m22s
[16:10:01.493] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:6:0)
[16:10:01.525] Build status: STARTED
[16:10:01.525] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:7:0) after 9m22s
[16:19:23.524] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:7:0)
[16:19:23.556] Build status: STARTED
[16:19:23.556] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:8:0) after 7m3s
[16:26:27.008] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:8:0)
[16:26:27.039] Build status: STARTED
[16:26:27.040] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:9:0) after 2m10s
[16:28:37.249] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:9:0)
[16:28:37.289] Build status: STARTED
[16:28:37.289] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:10:0) after 6m56s
[16:35:33.311] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:10:0)
[16:35:33.341] Build status: STARTED
[16:35:33.341] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:11:0) after 6m33s
[16:42:06.449] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:11:0)
[16:42:06.490] Build status: STARTED
[16:42:06.491] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:12:0) after 8m18s
[16:50:24.524] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:12:0)
[16:50:24.550] Build status: STARTED
[16:50:24.550] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:13:0) after 9m15s
[16:59:39.665] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:13:0)
[16:59:39.693] Build status: STARTED
[16:59:39.693] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:14:0) after 1m55s
[17:01:34.679] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:14:0)
[17:01:34.742] Build status: STARTED
[17:01:34.742] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:15:0) after 5m18s
[17:06:52.932] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:15:0)
[17:06:52.955] Build status: STARTED
[17:06:52.955] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:16:0) after 7m48s
[17:14:40.991] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:16:0)
[17:14:41.036] Build status: STARTED
[17:14:41.036] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:17:0) after 2m11s
[17:16:52.149] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:17:0)
[17:16:52.180] Build status: STARTED
[17:16:52.180] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:18:0) after 8m52s
[17:25:44.406] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:18:0)
[17:25:44.427] Build status: STARTED
[17:25:44.427] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:19:0) after 8m45s
[17:34:29.630] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:19:0)
[17:34:29.666] Build status: STARTED
[17:34:29.666] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:20:0) after 2m53s
[17:37:22.810] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:20:0)
[17:37:22.838] Build status: STARTED
[17:37:22.838] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:21:0) after 2m25s
[17:39:47.862] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:21:0)
[17:39:47.885] Build status: STARTED
[17:39:47.886] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:22:0) after 9m25s
[17:49:12.907] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:22:0)
[17:49:12.934] Build status: STARTED
[17:49:12.934] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:23:0) after 8m11s
[17:57:24.566] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:23:0)
[17:57:24.600] Build status: STARTED
[17:57:24.600] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:24:0) after 7m40s
[18:05:04.692] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:24:0)
[18:05:04.757] Build status: STARTED
[18:05:04.757] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:25:0) after 2m40s
[18:07:44.786] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:25:0)
[18:07:44.826] Build status: STARTED
[18:07:44.826] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:26:0) after 8m37s
[18:16:22.049] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:26:0)
[18:16:22.078] Build status: STARTED
[18:16:22.078] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:27:0) after 2m45s
[18:19:07.212] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:27:0)
[18:19:07.251] Build status: STARTED
[18:19:07.251] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:28:0) after 2m47s
[18:21:54.415] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:28:0)
[18:21:54.484] Build status: STARTED
[18:21:54.484] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:29:0) after 4m44s
[18:26:39.012] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:29:0)
[18:26:39.036] Build status: STARTED
[18:26:39.037] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:30:0) after 2m39s
[18:29:18.182] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:30:0)
[18:29:18.208] Build status: STARTED
[18:29:18.208] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:31:0) after 2m40s
[18:31:58.779] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:31:0)
[18:31:58.804] Build status: STARTED
[18:31:58.804] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:32:0) after 9m40s
[18:41:39.073] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:32:0)
[18:41:39.098] Build status: STARTED
[18:41:39.098] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:33:0) after 2m19s
[18:43:58.207] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:33:0)
[18:43:58.229] Build status: STARTED
[18:43:58.229] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:34:0) after 7m55s
[18:51:53.354] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:34:0)
[18:51:53.408] Build status: STARTED
[18:51:53.408] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:35:0) after 2m6s
[18:53:59.488] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:35:0)
[18:53:59.512] Build status: STARTED
[18:53:59.512] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8966379016979631568:36:0) after 9m7s
[18:55:40.895] Received PubSub notification, asking Buildbucket for the build status
[18:55:40.960] Build:
{
"id": "8800948954848622961",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2022-10-07T15:54:03.966669934Z",
"startTime": "2022-10-07T15:54:49.839509Z",
"endTime": "2022-10-07T18:55:40.248310809Z",
"updateTime": "2022-10-07T18:55:40.248310809Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "c2f6cbfb8e6ce4d97c6943160ce2d9d2e19a8a64",
"ref": "refs/heads/main"
}
}
}
[18:55:40.960] Invocation finished in 3h1m38.410836899s with status SUCCEEDED