[15:31:36.464] New invocation is queued and will start shortly
[15:31:37.648] Starting the invocation (attempt 1)
[15:31:37.681] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/9676d71e7eaed5d5f10238113da2a115c81f469d"
[15:31:37.681] Popped gitiles commit info from properties and tags
[15:31:37.681] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:31:37.682] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:31:37.682] Buildbucket request:
{
"requestId": "8956958343958911952",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8956958343958911952",
"job": "fuchsia/firebase-docs-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "1a0e3d342c0d928b08e911401eaeea5fc3c64291"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@1a0e3d342c0d928b08e911401eaeea5fc3c64291",
"title": "1a0e3d342c0d928b08e911401eaeea5fc3c64291",
"url": "https://fuchsia.googlesource.com/integration/+/1a0e3d342c0d928b08e911401eaeea5fc3c64291"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "429844100fc1821b1b15e5503e9724d237c0da1e"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@429844100fc1821b1b15e5503e9724d237c0da1e",
"title": "429844100fc1821b1b15e5503e9724d237c0da1e",
"url": "https://fuchsia.googlesource.com/integration/+/429844100fc1821b1b15e5503e9724d237c0da1e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "5eb1ce251fe1ee47c01a5805552756c620738ca1"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@5eb1ce251fe1ee47c01a5805552756c620738ca1",
"title": "5eb1ce251fe1ee47c01a5805552756c620738ca1",
"url": "https://fuchsia.googlesource.com/integration/+/5eb1ce251fe1ee47c01a5805552756c620738ca1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e5a8201ea7d0377f45883758f9bb4eda00f2bd8b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e5a8201ea7d0377f45883758f9bb4eda00f2bd8b",
"title": "e5a8201ea7d0377f45883758f9bb4eda00f2bd8b",
"url": "https://fuchsia.googlesource.com/integration/+/e5a8201ea7d0377f45883758f9bb4eda00f2bd8b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "52419c2242b5266cc30d61631151d3104983ff8b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@52419c2242b5266cc30d61631151d3104983ff8b",
"title": "52419c2242b5266cc30d61631151d3104983ff8b",
"url": "https://fuchsia.googlesource.com/integration/+/52419c2242b5266cc30d61631151d3104983ff8b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "7ff03c2eb6d33cb86c036cad4128d6ca5bcda4dc"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@7ff03c2eb6d33cb86c036cad4128d6ca5bcda4dc",
"title": "7ff03c2eb6d33cb86c036cad4128d6ca5bcda4dc",
"url": "https://fuchsia.googlesource.com/integration/+/7ff03c2eb6d33cb86c036cad4128d6ca5bcda4dc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "95917490d7d8b90227422ae4153ff10acd1f3c18"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@95917490d7d8b90227422ae4153ff10acd1f3c18",
"title": "95917490d7d8b90227422ae4153ff10acd1f3c18",
"url": "https://fuchsia.googlesource.com/integration/+/95917490d7d8b90227422ae4153ff10acd1f3c18"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "fec61a08bb39f5f0b1c79219d7888098004fd09b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@fec61a08bb39f5f0b1c79219d7888098004fd09b",
"title": "fec61a08bb39f5f0b1c79219d7888098004fd09b",
"url": "https://fuchsia.googlesource.com/integration/+/fec61a08bb39f5f0b1c79219d7888098004fd09b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "211c1e72bb370ce6237dc3bd2b6993d38760720b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@211c1e72bb370ce6237dc3bd2b6993d38760720b",
"title": "211c1e72bb370ce6237dc3bd2b6993d38760720b",
"url": "https://fuchsia.googlesource.com/integration/+/211c1e72bb370ce6237dc3bd2b6993d38760720b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "321b0768e631b9169463fcb64f04456128e6deee"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@321b0768e631b9169463fcb64f04456128e6deee",
"title": "321b0768e631b9169463fcb64f04456128e6deee",
"url": "https://fuchsia.googlesource.com/integration/+/321b0768e631b9169463fcb64f04456128e6deee"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4c93543b405c46c854bd38022e5c3d0016092a31"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4c93543b405c46c854bd38022e5c3d0016092a31",
"title": "4c93543b405c46c854bd38022e5c3d0016092a31",
"url": "https://fuchsia.googlesource.com/integration/+/4c93543b405c46c854bd38022e5c3d0016092a31"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "37170ff86786d43ab06237c5cb78967df2123774"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@37170ff86786d43ab06237c5cb78967df2123774",
"title": "37170ff86786d43ab06237c5cb78967df2123774",
"url": "https://fuchsia.googlesource.com/integration/+/37170ff86786d43ab06237c5cb78967df2123774"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9c0d6d8abcd5aae1dc3f1c56003235aeb147f65b"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9c0d6d8abcd5aae1dc3f1c56003235aeb147f65b",
"title": "9c0d6d8abcd5aae1dc3f1c56003235aeb147f65b",
"url": "https://fuchsia.googlesource.com/integration/+/9c0d6d8abcd5aae1dc3f1c56003235aeb147f65b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "0c30c2c455d04fe35ef2b6b6e78b50d1f92c2b0a"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@0c30c2c455d04fe35ef2b6b6e78b50d1f92c2b0a",
"title": "0c30c2c455d04fe35ef2b6b6e78b50d1f92c2b0a",
"url": "https://fuchsia.googlesource.com/integration/+/0c30c2c455d04fe35ef2b6b6e78b50d1f92c2b0a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "3d202a6e3d1036dc6eadcfbcd2c4abed9a119a33"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@3d202a6e3d1036dc6eadcfbcd2c4abed9a119a33",
"title": "3d202a6e3d1036dc6eadcfbcd2c4abed9a119a33",
"url": "https://fuchsia.googlesource.com/integration/+/3d202a6e3d1036dc6eadcfbcd2c4abed9a119a33"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "9676d71e7eaed5d5f10238113da2a115c81f469d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@9676d71e7eaed5d5f10238113da2a115c81f469d",
"title": "9676d71e7eaed5d5f10238113da2a115c81f469d",
"url": "https://fuchsia.googlesource.com/integration/+/9676d71e7eaed5d5f10238113da2a115c81f469d"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "9676d71e7eaed5d5f10238113da2a115c81f469d",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8956958343958911952"
},
{
"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:31:38.079] Scheduled build:
{
"id": "8791528281939650001",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2023-01-19T15:31:37.762607582Z",
"updateTime": "2023-01-19T15:31:37.762607582Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "9676d71e7eaed5d5f10238113da2a115c81f469d",
"ref": "refs/heads/main"
}
}
}
[15:31:38.079] Task URL: https://cr-buildbucket.appspot.com/build/8791528281939650001
[15:31:38.079] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:2:0) after 3m52s
[15:32:16.635] Received PubSub notification, asking Buildbucket for the build status
[15:32:16.663] Build status: STARTED
[15:35:30.102] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:2:0)
[15:35:30.134] Build status: STARTED
[15:35:30.134] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:4:0) after 6m49s
[15:42:19.793] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:4:0)
[15:42:19.822] Build status: STARTED
[15:42:19.822] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:5:0) after 6m18s
[15:48:38.134] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:5:0)
[15:48:38.169] Build status: STARTED
[15:48:38.169] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:6:0) after 7m56s
[15:56:34.238] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:6:0)
[15:56:34.261] Build status: STARTED
[15:56:34.261] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:7:0) after 2m26s
[15:59:00.324] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:7:0)
[15:59:00.343] Build status: STARTED
[15:59:00.343] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:8:0) after 5m56s
[16:04:56.363] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:8:0)
[16:04:56.402] Build status: STARTED
[16:04:56.402] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:9:0) after 9m50s
[16:14:46.426] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:9:0)
[16:14:46.487] Build status: STARTED
[16:14:46.487] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:10:0) after 4m57s
[16:19:43.944] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:10:0)
[16:19:43.964] Build status: STARTED
[16:19:43.964] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:11:0) after 4m13s
[16:23:57.117] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:11:0)
[16:23:57.138] Build status: STARTED
[16:23:57.138] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:12:0) after 9m41s
[16:33:38.158] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:12:0)
[16:33:38.184] Build status: STARTED
[16:33:38.184] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:13:0) after 3m39s
[16:37:17.253] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:13:0)
[16:37:17.276] Build status: STARTED
[16:37:17.276] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:14:0) after 6m10s
[16:43:27.295] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:14:0)
[16:43:27.316] Build status: STARTED
[16:43:27.316] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:15:0) after 5m30s
[16:48:57.483] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:15:0)
[16:48:57.506] Build status: STARTED
[16:48:57.506] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:16:0) after 1m5s
[16:50:02.555] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:16:0)
[16:50:02.579] Build status: STARTED
[16:50:02.579] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:17:0) after 8m55s
[16:58:58.270] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:17:0)
[16:58:58.296] Build status: STARTED
[16:58:58.296] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:18:0) after 4m50s
[17:03:48.373] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:18:0)
[17:03:48.403] Build status: STARTED
[17:03:48.403] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:19:0) after 7m48s
[17:11:37.118] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:19:0)
[17:11:37.146] Build status: STARTED
[17:11:37.146] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:20:0) after 2m37s
[17:14:14.667] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:20:0)
[17:14:14.688] Build status: STARTED
[17:14:14.688] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:21:0) after 7m24s
[17:21:38.712] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:21:0)
[17:21:38.731] Build status: STARTED
[17:21:38.731] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:22:0) after 7m14s
[17:28:52.794] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:22:0)
[17:28:52.816] Build status: STARTED
[17:28:52.816] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:23:0) after 1m11s
[17:30:03.897] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:23:0)
[17:30:03.920] Build status: STARTED
[17:30:03.920] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:24:0) after 8m9s
[17:38:12.938] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:24:0)
[17:38:12.978] Build status: STARTED
[17:38:12.978] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:25:0) after 9m40s
[17:47:53.198] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:25:0)
[17:47:53.232] Build status: STARTED
[17:47:53.232] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:26:0) after 3m18s
[17:51:11.258] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:26:0)
[17:51:11.278] Build status: STARTED
[17:51:11.278] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:27:0) after 7m28s
[17:58:39.477] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:27:0)
[17:58:39.505] Build status: STARTED
[17:58:39.505] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:28:0) after 8m39s
[18:07:18.689] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:28:0)
[18:07:18.720] Build status: STARTED
[18:07:18.720] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:29:0) after 6m23s
[18:13:41.772] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:29:0)
[18:13:41.806] Build status: STARTED
[18:13:41.806] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:30:0) after 8m49s
[18:22:30.826] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:30:0)
[18:22:30.846] Build status: STARTED
[18:22:30.846] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:31:0) after 9m50s
[18:32:20.881] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:31:0)
[18:32:20.904] Build status: STARTED
[18:32:20.904] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:32:0) after 3m37s
[18:35:57.949] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:32:0)
[18:35:57.978] Build status: STARTED
[18:35:57.978] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8956958343958911952:33:0) after 5m4s
[18:37:10.775] Received PubSub notification, asking Buildbucket for the build status
[18:37:10.798] Build:
{
"id": "8791528281939650001",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2023-01-19T15:31:37.762607582Z",
"startTime": "2023-01-19T15:32:15.419705Z",
"endTime": "2023-01-19T18:37:10.457397628Z",
"updateTime": "2023-01-19T18:37:10.457397628Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "9676d71e7eaed5d5f10238113da2a115c81f469d",
"ref": "refs/heads/main"
}
}
}
[18:37:10.798] Invocation finished in 3h5m34.344838415s with status SUCCEEDED