[09:12:48.399] New invocation is queued and will start shortly
[09:12:49.651] Starting the invocation (attempt 1)
[09:12:49.677] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/875902579bdaf52beb7c5e371569dfc39fa05299"
[09:12:49.677] Popped gitiles commit info from properties and tags
[09:12:49.677] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[09:12:49.677] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[09:12:49.678] Buildbucket request:
{
"requestId": "8968940975630955888",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8968940975630955888",
"job": "fuchsia/firebase-docs-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "22cebee96abcbc6c48187407aaf93afdd057b8b7"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@22cebee96abcbc6c48187407aaf93afdd057b8b7",
"title": "22cebee96abcbc6c48187407aaf93afdd057b8b7",
"url": "https://fuchsia.googlesource.com/integration/+/22cebee96abcbc6c48187407aaf93afdd057b8b7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8b8d3dccf7fad9eb2625760b0d305a6a45803c90"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8b8d3dccf7fad9eb2625760b0d305a6a45803c90",
"title": "8b8d3dccf7fad9eb2625760b0d305a6a45803c90",
"url": "https://fuchsia.googlesource.com/integration/+/8b8d3dccf7fad9eb2625760b0d305a6a45803c90"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8b12d7eb24aaaaad740ec87ff7fa3755e0d526af"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8b12d7eb24aaaaad740ec87ff7fa3755e0d526af",
"title": "8b12d7eb24aaaaad740ec87ff7fa3755e0d526af",
"url": "https://fuchsia.googlesource.com/integration/+/8b12d7eb24aaaaad740ec87ff7fa3755e0d526af"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0a33788750315abf2ec5b4b3aa4706ebcf61f2d4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0a33788750315abf2ec5b4b3aa4706ebcf61f2d4",
"title": "0a33788750315abf2ec5b4b3aa4706ebcf61f2d4",
"url": "https://fuchsia.googlesource.com/integration/+/0a33788750315abf2ec5b4b3aa4706ebcf61f2d4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "f7220c92494710b9516aa3db2d5a0580d51c6ec8"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@f7220c92494710b9516aa3db2d5a0580d51c6ec8",
"title": "f7220c92494710b9516aa3db2d5a0580d51c6ec8",
"url": "https://fuchsia.googlesource.com/integration/+/f7220c92494710b9516aa3db2d5a0580d51c6ec8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "af8102921aa02d02ae8ffd8d423ce104ad27abc0"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@af8102921aa02d02ae8ffd8d423ce104ad27abc0",
"title": "af8102921aa02d02ae8ffd8d423ce104ad27abc0",
"url": "https://fuchsia.googlesource.com/integration/+/af8102921aa02d02ae8ffd8d423ce104ad27abc0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "c49480cb1ad539a387d1cee1d945c8fd94a8b608"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@c49480cb1ad539a387d1cee1d945c8fd94a8b608",
"title": "c49480cb1ad539a387d1cee1d945c8fd94a8b608",
"url": "https://fuchsia.googlesource.com/integration/+/c49480cb1ad539a387d1cee1d945c8fd94a8b608"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e2fec6a135e37d2837a92e8a2ab1e143daf07962"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e2fec6a135e37d2837a92e8a2ab1e143daf07962",
"title": "e2fec6a135e37d2837a92e8a2ab1e143daf07962",
"url": "https://fuchsia.googlesource.com/integration/+/e2fec6a135e37d2837a92e8a2ab1e143daf07962"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a4f0a4e7342ac28c1a6ded9907da57453d16b039"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a4f0a4e7342ac28c1a6ded9907da57453d16b039",
"title": "a4f0a4e7342ac28c1a6ded9907da57453d16b039",
"url": "https://fuchsia.googlesource.com/integration/+/a4f0a4e7342ac28c1a6ded9907da57453d16b039"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "875902579bdaf52beb7c5e371569dfc39fa05299"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@875902579bdaf52beb7c5e371569dfc39fa05299",
"title": "875902579bdaf52beb7c5e371569dfc39fa05299",
"url": "https://fuchsia.googlesource.com/integration/+/875902579bdaf52beb7c5e371569dfc39fa05299"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "875902579bdaf52beb7c5e371569dfc39fa05299",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8968940975630955888"
},
{
"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"
}
}
[09:12:50.102] Scheduled build:
{
"id": "8803510913575933425",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2022-09-09T09:12:49.728047537Z",
"updateTime": "2022-09-09T09:12:49.728047537Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "875902579bdaf52beb7c5e371569dfc39fa05299",
"ref": "refs/heads/main"
}
}
}
[09:12:50.102] Task URL: https://cr-buildbucket.appspot.com/build/8803510913575933425
[09:12:50.102] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:2:0) after 8m52s
[09:15:11.401] Received PubSub notification, asking Buildbucket for the build status
[09:15:11.420] Build status: STARTED
[09:21:42.443] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:2:0)
[09:21:42.473] Build status: STARTED
[09:21:42.473] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:4:0) after 6m24s
[09:28:06.589] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:4:0)
[09:28:06.611] Build status: STARTED
[09:28:06.611] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:5:0) after 4m28s
[09:32:34.628] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:5:0)
[09:32:34.671] Build status: STARTED
[09:32:34.671] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:6:0) after 7m10s
[09:39:44.888] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:6:0)
[09:39:44.912] Build status: STARTED
[09:39:44.912] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:7:0) after 1m42s
[09:41:26.931] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:7:0)
[09:41:26.957] Build status: STARTED
[09:41:26.957] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:8:0) after 3m26s
[09:44:53.050] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:8:0)
[09:44:53.079] Build status: STARTED
[09:44:53.079] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:9:0) after 2m4s
[09:46:57.181] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:9:0)
[09:46:57.215] Build status: STARTED
[09:46:57.215] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:10:0) after 6m23s
[09:53:20.360] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:10:0)
[09:53:20.387] Build status: STARTED
[09:53:20.387] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:11:0) after 6m18s
[09:59:38.438] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:11:0)
[09:59:38.466] Build status: STARTED
[09:59:38.466] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:12:0) after 3m12s
[10:02:50.643] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:12:0)
[10:02:50.670] Build status: STARTED
[10:02:50.670] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:13:0) after 5m9s
[10:07:59.882] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:13:0)
[10:07:59.962] Build status: STARTED
[10:07:59.963] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:14:0) after 4m14s
[10:12:14.263] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:14:0)
[10:12:14.297] Build status: STARTED
[10:12:14.297] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:15:0) after 7m56s
[10:20:10.590] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:15:0)
[10:20:10.615] Build status: STARTED
[10:20:10.615] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:16:0) after 1m31s
[10:21:41.727] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:16:0)
[10:21:41.766] Build status: STARTED
[10:21:41.766] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:17:0) after 4m9s
[10:25:50.863] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:17:0)
[10:25:50.890] Build status: STARTED
[10:25:50.890] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:18:0) after 3m45s
[10:29:35.889] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:18:0)
[10:29:35.917] Build status: STARTED
[10:29:35.917] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:19:0) after 2m47s
[10:32:23.168] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:19:0)
[10:32:23.221] Build status: STARTED
[10:32:23.221] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:20:0) after 5m39s
[10:38:02.238] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:20:0)
[10:38:02.262] Build status: STARTED
[10:38:02.262] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:21:0) after 4m13s
[10:42:15.283] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:21:0)
[10:42:15.303] Build status: STARTED
[10:42:15.303] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:22:0) after 4m16s
[10:46:31.323] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:22:0)
[10:46:31.354] Build status: STARTED
[10:46:31.354] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:23:0) after 9m3s
[10:55:34.624] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:23:0)
[10:55:34.650] Build status: STARTED
[10:55:34.650] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:24:0) after 6m48s
[11:02:22.822] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:24:0)
[11:02:22.856] Build status: STARTED
[11:02:22.857] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:25:0) after 6m49s
[11:09:11.980] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:25:0)
[11:09:12.005] Build status: STARTED
[11:09:12.005] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:26:0) after 5m21s
[11:14:33.029] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:26:0)
[11:14:33.051] Build status: STARTED
[11:14:33.051] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:27:0) after 9m22s
[11:23:55.068] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:27:0)
[11:23:55.183] Build status: STARTED
[11:23:55.183] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:28:0) after 1m3s
[11:24:58.157] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:28:0)
[11:24:58.184] Build status: STARTED
[11:24:58.184] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:29:0) after 9m0s
[11:33:58.203] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:29:0)
[11:33:58.230] Build status: STARTED
[11:33:58.230] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:30:0) after 1m51s
[11:35:49.453] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:30:0)
[11:35:49.476] Build status: STARTED
[11:35:49.476] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:31:0) after 5m35s
[11:41:24.687] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:31:0)
[11:41:24.712] Build status: STARTED
[11:41:24.712] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:32:0) after 8m55s
[11:50:19.991] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:32:0)
[11:50:20.035] Build status: STARTED
[11:50:20.035] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:33:0) after 3m15s
[11:53:35.278] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:33:0)
[11:53:35.307] Build status: STARTED
[11:53:35.307] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:34:0) after 4m25s
[11:58:00.297] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:34:0)
[11:58:00.323] Build status: STARTED
[11:58:00.323] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:35:0) after 3m46s
[12:01:46.405] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:35:0)
[12:01:46.430] Build status: STARTED
[12:01:46.430] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8968940975630955888:36:0) after 9m42s
[12:04:16.478] Received PubSub notification, asking Buildbucket for the build status
[12:04:16.522] Build:
{
"id": "8803510913575933425",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2022-09-09T09:12:49.728047537Z",
"startTime": "2022-09-09T09:15:10.273231Z",
"endTime": "2022-09-09T12:04:15.824196088Z",
"updateTime": "2022-09-09T12:04:15.824196088Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "875902579bdaf52beb7c5e371569dfc39fa05299",
"ref": "refs/heads/main"
}
}
}
[12:04:16.522] Invocation finished in 2h51m28.136917033s with status SUCCEEDED