[17:12:07.269] New invocation is queued and will start shortly
[17:12:08.338] Starting the invocation (attempt 1)
[17:12:08.371] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/e7a52c1746f814ad6ace45bbb6e654f919be67b8"
[17:12:08.371] Popped gitiles commit info from properties and tags
[17:12:08.371] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:12:08.372] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:12:08.372] Buildbucket request:
{
"requestId": "8930860093873353536",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8930860093873353536",
"job": "fuchsia/firebase-docs-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "97452f9c0125ec8f2b6754e9e3c59814d747f8cd"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@97452f9c0125ec8f2b6754e9e3c59814d747f8cd",
"title": "97452f9c0125ec8f2b6754e9e3c59814d747f8cd",
"url": "https://fuchsia.googlesource.com/integration/+/97452f9c0125ec8f2b6754e9e3c59814d747f8cd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6a4b417e6e62716d1fe9fe0dd86abc2a48618a68"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6a4b417e6e62716d1fe9fe0dd86abc2a48618a68",
"title": "6a4b417e6e62716d1fe9fe0dd86abc2a48618a68",
"url": "https://fuchsia.googlesource.com/integration/+/6a4b417e6e62716d1fe9fe0dd86abc2a48618a68"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "edaa5cf8c008daf5c1fbac67031a1080195ffaef"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@edaa5cf8c008daf5c1fbac67031a1080195ffaef",
"title": "edaa5cf8c008daf5c1fbac67031a1080195ffaef",
"url": "https://fuchsia.googlesource.com/integration/+/edaa5cf8c008daf5c1fbac67031a1080195ffaef"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "14e87cd2f33c4ef949d3f8d46b069a3d779829e3"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@14e87cd2f33c4ef949d3f8d46b069a3d779829e3",
"title": "14e87cd2f33c4ef949d3f8d46b069a3d779829e3",
"url": "https://fuchsia.googlesource.com/integration/+/14e87cd2f33c4ef949d3f8d46b069a3d779829e3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "e7a52c1746f814ad6ace45bbb6e654f919be67b8"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@e7a52c1746f814ad6ace45bbb6e654f919be67b8",
"title": "e7a52c1746f814ad6ace45bbb6e654f919be67b8",
"url": "https://fuchsia.googlesource.com/integration/+/e7a52c1746f814ad6ace45bbb6e654f919be67b8"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "e7a52c1746f814ad6ace45bbb6e654f919be67b8",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8930860093873353536"
},
{
"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"
}
}
[17:12:08.834] Scheduled build:
{
"id": "8765430031992431409",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2023-11-03T17:12:08.440677755Z",
"updateTime": "2023-11-03T17:12:08.440677755Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "e7a52c1746f814ad6ace45bbb6e654f919be67b8",
"ref": "refs/heads/main"
}
}
}
[17:12:08.834] Task URL: https://cr-buildbucket.appspot.com/build/8765430031992431409
[17:12:08.834] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8930860093873353536:2:0) after 1m48s
[17:12:09.580] Received PubSub notification, asking Buildbucket for the build status
[17:12:09.605] Build status: SCHEDULED
[17:12:12.184] Received PubSub notification, asking Buildbucket for the build status
[17:12:12.236] Build status: STARTED
[17:13:56.950] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8930860093873353536:2:0)
[17:13:56.993] Build status: STARTED
[17:13:56.993] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8930860093873353536:5:0) after 2m53s
[17:16:50.243] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8930860093873353536:5:0)
[17:16:50.261] Build status: STARTED
[17:16:50.261] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8930860093873353536:6:0) after 8m42s
[17:25:32.277] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8930860093873353536:6:0)
[17:25:32.305] Build status: STARTED
[17:25:32.305] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8930860093873353536:7:0) after 7m58s
[17:33:30.284] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8930860093873353536:7:0)
[17:33:30.355] Build status: STARTED
[17:33:30.355] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8930860093873353536:8:0) after 1m16s
[17:34:46.400] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8930860093873353536:8:0)
[17:34:46.431] Build status: STARTED
[17:34:46.431] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8930860093873353536:9:0) after 6m32s
[17:41:18.402] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8930860093873353536:9:0)
[17:41:18.434] Build status: STARTED
[17:41:18.434] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8930860093873353536:10:0) after 8m27s
[17:49:45.463] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8930860093873353536:10:0)
[17:49:45.482] Build status: STARTED
[17:49:45.482] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8930860093873353536:11:0) after 7m36s
[17:57:21.499] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8930860093873353536:11:0)
[17:57:21.624] Build status: STARTED
[17:57:21.624] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8930860093873353536:12:0) after 6m10s
[18:03:31.648] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8930860093873353536:12:0)
[18:03:31.704] Build status: STARTED
[18:03:31.704] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8930860093873353536:13:0) after 9m48s
[18:13:19.763] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8930860093873353536:13:0)
[18:13:19.780] Build status: STARTED
[18:13:19.780] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8930860093873353536:14:0) after 9m50s
[18:23:09.800] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8930860093873353536:14:0)
[18:23:09.825] Build status: STARTED
[18:23:09.825] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8930860093873353536:15:0) after 8m7s
[18:31:16.854] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8930860093873353536:15:0)
[18:31:16.888] Build status: STARTED
[18:31:16.888] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8930860093873353536:16:0) after 3m10s
[18:34:26.878] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8930860093873353536:16:0)
[18:34:26.897] Build status: STARTED
[18:34:26.897] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8930860093873353536:17:0) after 2m41s
[18:37:07.914] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8930860093873353536:17:0)
[18:37:07.930] Build status: STARTED
[18:37:07.930] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8930860093873353536:18:0) after 4m41s
[18:41:48.944] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8930860093873353536:18:0)
[18:41:49.060] Build status: STARTED
[18:41:49.060] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8930860093873353536:19:0) after 1m25s
[18:43:14.082] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8930860093873353536:19:0)
[18:43:14.104] Build status: STARTED
[18:43:14.104] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8930860093873353536:20:0) after 9m4s
[18:52:18.157] Handling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8930860093873353536:20:0)
[18:52:18.177] Build status: STARTED
[18:52:18.177] Scheduling timer "check-buildbucket-build-status" (fuchsia/firebase-docs-e5a0ec7f:8930860093873353536:21:0) after 5m36s
[18:56:37.378] Received PubSub notification, asking Buildbucket for the build status
[18:56:37.409] Build:
{
"id": "8765430031992431409",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "firebase-docs"
},
"createdBy": "project:fuchsia",
"createTime": "2023-11-03T17:12:08.440677755Z",
"startTime": "2023-11-03T17:12:10.749740Z",
"endTime": "2023-11-03T18:56:36.273214Z",
"updateTime": "2023-11-03T18:56:36.273214Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "e7a52c1746f814ad6ace45bbb6e654f919be67b8",
"ref": "refs/heads/main"
}
}
}
[18:56:37.409] Invocation finished in 1h44m30.145897557s with status SUCCEEDED