[21:42:22.214] New invocation is queued and will start shortly
[21:42:23.352] Starting the invocation (attempt 1)
[21:42:23.379] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/ecd2f57ea8e4e1b04ceeede2c7399b8e1ba39001"
[21:42:23.379] Popped gitiles commit info from properties and tags
[21:42:23.379] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:42:23.379] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:42:23.379] Buildbucket request:
{
"requestId": "8935282342629505152",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8935282342629505152",
"job": "fuchsia/firebase-docs-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "eb45890ca2628f1cf117eee409d84e2326cbb3ba"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@eb45890ca2628f1cf117eee409d84e2326cbb3ba",
"title": "eb45890ca2628f1cf117eee409d84e2326cbb3ba",
"url": "https://fuchsia.googlesource.com/integration/+/eb45890ca2628f1cf117eee409d84e2326cbb3ba"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "feb855c6ca6829a3764c0ec1952d585efa4248cc"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@feb855c6ca6829a3764c0ec1952d585efa4248cc",
"title": "feb855c6ca6829a3764c0ec1952d585efa4248cc",
"url": "https://fuchsia.googlesource.com/integration/+/feb855c6ca6829a3764c0ec1952d585efa4248cc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3f6ca8ff660bfad7da1d7369c353c4f2965a316c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3f6ca8ff660bfad7da1d7369c353c4f2965a316c",
"title": "3f6ca8ff660bfad7da1d7369c353c4f2965a316c",
"url": "https://fuchsia.googlesource.com/integration/+/3f6ca8ff660bfad7da1d7369c353c4f2965a316c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3179d1146ecc4ae26de67662769a9ca88c24daa5"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3179d1146ecc4ae26de67662769a9ca88c24daa5",
"title": "3179d1146ecc4ae26de67662769a9ca88c24daa5",
"url": "https://fuchsia.googlesource.com/integration/+/3179d1146ecc4ae26de67662769a9ca88c24daa5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "002f3dd8d4a03a87022b452e48e0fae6aa4c417d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@002f3dd8d4a03a87022b452e48e0fae6aa4c417d",
"title": "002f3dd8d4a03a87022b452e48e0fae6aa4c417d",
"url": "https://fuchsia.googlesource.com/integration/+/002f3dd8d4a03a87022b452e48e0fae6aa4c417d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e3b7d2d961f1decc7d472f279bccb706774f6a44"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e3b7d2d961f1decc7d472f279bccb706774f6a44",
"title": "e3b7d2d961f1decc7d472f279bccb706774f6a44",
"url": "https://fuchsia.googlesource.com/integration/+/e3b7d2d961f1decc7d472f279bccb706774f6a44"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a8ee529696d60c1eb98ee08c504bc5564d80d840"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a8ee529696d60c1eb98ee08c504bc5564d80d840",
"title": "a8ee529696d60c1eb98ee08c504bc5564d80d840",
"url": "https://fuchsia.googlesource.com/integration/+/a8ee529696d60c1eb98ee08c504bc5564d80d840"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "670f45028edc9e1329fc7a3f75b0c97d204ea19c"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@670f45028edc9e1329fc7a3f75b0c97d204ea19c",
"title": "670f45028edc9e1329fc7a3f75b0c97d204ea19c",
"url": "https://fuchsia.googlesource.com/integration/+/670f45028edc9e1329fc7a3f75b0c97d204ea19c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "15625f37fad6b9997a06a1093931de7aee1589e5"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@15625f37fad6b9997a06a1093931de7aee1589e5",
"title": "15625f37fad6b9997a06a1093931de7aee1589e5",
"url": "https://fuchsia.googlesource.com/integration/+/15625f37fad6b9997a06a1093931de7aee1589e5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "8f4ad00b96850196423cecc8f4fca43c56c4a163"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@8f4ad00b96850196423cecc8f4fca43c56c4a163",
"title": "8f4ad00b96850196423cecc8f4fca43c56c4a163",
"url": "https://fuchsia.googlesource.com/integration/+/8f4ad00b96850196423cecc8f4fca43c56c4a163"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "a71a022be2b6429edbb5a422463db47451c9d2c1"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@a71a022be2b6429edbb5a422463db47451c9d2c1",
"title": "a71a022be2b6429edbb5a422463db47451c9d2c1",
"url": "https://fuchsia.googlesource.com/integration/+/a71a022be2b6429edbb5a422463db47451c9d2c1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "ecd2f57ea8e4e1b04ceeede2c7399b8e1ba39001"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@ecd2f57ea8e4e1b04ceeede2c7399b8e1ba39001",
"title": "ecd2f57ea8e4e1b04ceeede2c7399b8e1ba39001",
"url": "https://fuchsia.googlesource.com/integration/+/ecd2f57ea8e4e1b04ceeede2c7399b8e1ba39001"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "ecd2f57ea8e4e1b04ceeede2c7399b8e1ba39001",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8935282342629505152"
},
{
"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"
}
}
[21:42:23.842] Scheduled build:
{
"id": "8769852280691124161",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2023-09-15T21:42:23.436507461Z",
"updateTime": "2023-09-15T21:42:23.436507461Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "ecd2f57ea8e4e1b04ceeede2c7399b8e1ba39001",
"ref": "refs/heads/main"
}
}
}
[21:42:23.842] Task URL: https://cr-buildbucket.appspot.com/build/8769852280691124161
[21:42:23.842] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:2:0) after 9m36s
[21:42:24.747] Received PubSub notification, asking Buildbucket for the build status
[21:42:24.824] Build status: SCHEDULED
[21:43:00.453] Received PubSub notification, asking Buildbucket for the build status
[21:43:00.481] Build status: STARTED
[21:51:59.928] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:2:0)
[21:51:59.959] Build status: STARTED
[21:51:59.959] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:5:0) after 2m13s
[21:54:13.020] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:5:0)
[21:54:13.044] Build status: STARTED
[21:54:13.044] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:6:0) after 3m54s
[21:58:07.261] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:6:0)
[21:58:07.305] Build status: STARTED
[21:58:07.305] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:7:0) after 7m23s
[22:05:30.324] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:7:0)
[22:05:30.347] Build status: STARTED
[22:05:30.347] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:8:0) after 1m17s
[22:06:47.708] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:8:0)
[22:06:47.724] Build status: STARTED
[22:06:47.724] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:9:0) after 7m41s
[22:14:28.772] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:9:0)
[22:14:28.800] Build status: STARTED
[22:14:28.800] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:10:0) after 6m54s
[22:21:22.820] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:10:0)
[22:21:22.892] Build status: STARTED
[22:21:22.892] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:11:0) after 2m51s
[22:24:13.909] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:11:0)
[22:24:13.971] Build status: STARTED
[22:24:13.971] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:12:0) after 5m8s
[22:29:21.987] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:12:0)
[22:29:22.032] Build status: STARTED
[22:29:22.032] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:13:0) after 9m29s
[22:38:51.278] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:13:0)
[22:38:51.294] Build status: STARTED
[22:38:51.294] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:14:0) after 9m39s
[22:48:30.311] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:14:0)
[22:48:30.330] Build status: STARTED
[22:48:30.330] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:15:0) after 2m46s
[22:51:16.449] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:15:0)
[22:51:16.471] Build status: STARTED
[22:51:16.471] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:16:0) after 5m47s
[22:57:03.618] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:16:0)
[22:57:03.667] Build status: STARTED
[22:57:03.667] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:17:0) after 6m16s
[23:03:19.733] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:17:0)
[23:03:19.757] Build status: STARTED
[23:03:19.757] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:18:0) after 9m3s
[23:12:22.777] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:18:0)
[23:12:22.799] Build status: STARTED
[23:12:22.799] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:19:0) after 8m55s
[23:21:17.778] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:19:0)
[23:21:17.809] Build status: STARTED
[23:21:17.809] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:20:0) after 7m16s
[23:28:33.942] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:20:0)
[23:28:33.960] Build status: STARTED
[23:28:33.960] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:21:0) after 1m33s
[23:30:07.505] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:21:0)
[23:30:07.526] Build status: STARTED
[23:30:07.527] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:22:0) after 3m43s
[23:33:50.734] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:22:0)
[23:33:50.767] Build status: STARTED
[23:33:50.767] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:23:0) after 1m33s
[23:35:23.912] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:23:0)
[23:35:23.931] Build status: STARTED
[23:35:23.931] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8935282342629505152:24:0) after 6m49s
[23:40:58.070] Received PubSub notification, asking Buildbucket for the build status
[23:40:58.093] Build:
{
"id": "8769852280691124161",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2023-09-15T21:42:23.436507461Z",
"startTime": "2023-09-15T21:42:59.678042Z",
"endTime": "2023-09-15T23:40:57.478146262Z",
"updateTime": "2023-09-15T23:40:57.478146262Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "ecd2f57ea8e4e1b04ceeede2c7399b8e1ba39001",
"ref": "refs/heads/main"
}
}
}
[23:40:58.093] Invocation finished in 1h58m35.890177386s with status SUCCEEDED