[18:08:10.260] New invocation is queued and will start shortly
[18:08:11.315] Starting the invocation (attempt 1)
[18:08:11.341] Popped buildset tag "buildset:commit/gitiles/fuchsia.googlesource.com/integration/+/b913fbed66c4c1ec745c545232dcf782ba06f50d"
[18:08:11.341] Popped gitiles commit info from properties and tags
[18:08:11.341] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:08:11.342] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:08:11.342] Buildbucket request:
{
"requestId": "8862637051819976096",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "fuchsia-coverage"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8862637051819976096",
"job": "fuchsia/global.ci-fuchsia-coverage-e5a0ec7f",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "13ceea2c8642a126b1dd56a5a524c85b3ca827b4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@13ceea2c8642a126b1dd56a5a524c85b3ca827b4",
"title": "13ceea2c8642a126b1dd56a5a524c85b3ca827b4",
"url": "https://fuchsia.googlesource.com/integration/+/13ceea2c8642a126b1dd56a5a524c85b3ca827b4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "4137b5eb0643798700c1a6869e6d93ca93fa4ab4"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@4137b5eb0643798700c1a6869e6d93ca93fa4ab4",
"title": "4137b5eb0643798700c1a6869e6d93ca93fa4ab4",
"url": "https://fuchsia.googlesource.com/integration/+/4137b5eb0643798700c1a6869e6d93ca93fa4ab4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "6509555e963037f72a076777a1e9b80af6d9a257"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@6509555e963037f72a076777a1e9b80af6d9a257",
"title": "6509555e963037f72a076777a1e9b80af6d9a257",
"url": "https://fuchsia.googlesource.com/integration/+/6509555e963037f72a076777a1e9b80af6d9a257"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "034bb42a3c011bff336b0a61e79fbeffe5dfd3c9"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@034bb42a3c011bff336b0a61e79fbeffe5dfd3c9",
"title": "034bb42a3c011bff336b0a61e79fbeffe5dfd3c9",
"url": "https://fuchsia.googlesource.com/integration/+/034bb42a3c011bff336b0a61e79fbeffe5dfd3c9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "40eba89a6f9a7b67d10c413e56ffee5df52fe1be"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@40eba89a6f9a7b67d10c413e56ffee5df52fe1be",
"title": "40eba89a6f9a7b67d10c413e56ffee5df52fe1be",
"url": "https://fuchsia.googlesource.com/integration/+/40eba89a6f9a7b67d10c413e56ffee5df52fe1be"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://fuchsia.googlesource.com/integration",
"revision": "b913fbed66c4c1ec745c545232dcf782ba06f50d"
},
"id": "https://fuchsia.googlesource.com/integration/+/refs/heads/main@b913fbed66c4c1ec745c545232dcf782ba06f50d",
"title": "b913fbed66c4c1ec745c545232dcf782ba06f50d",
"url": "https://fuchsia.googlesource.com/integration/+/b913fbed66c4c1ec745c545232dcf782ba06f50d"
}
]
}
},
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "b913fbed66c4c1ec745c545232dcf782ba06f50d",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8862637051819976096"
},
{
"key": "scheduler_job_id",
"value": "fuchsia/global.ci-fuchsia-coverage-e5a0ec7f"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:08:11.881] Scheduled build:
{
"id": "8697206989909963665",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "fuchsia-coverage"
},
"createdBy": "project:fuchsia",
"createTime": "2025-11-25T18:08:11.461312725Z",
"updateTime": "2025-11-25T18:08:11.461312725Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "b913fbed66c4c1ec745c545232dcf782ba06f50d",
"ref": "refs/heads/main"
}
}
}
[18:08:11.882] Task URL: https://cr-buildbucket.appspot.com/build/8697206989909963665
[18:08:11.882] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:2:0) after 1m38s
[18:08:12.780] Received PubSub notification, asking Buildbucket for the build status
[18:08:12.863] Build status: SCHEDULED
[18:08:17.449] Received PubSub notification, asking Buildbucket for the build status
[18:08:17.493] Build status: STARTED
[18:09:49.951] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:2:0)
[18:09:49.971] Build status: STARTED
[18:09:49.971] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:5:0) after 3m59s
[18:13:48.993] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:5:0)
[18:13:49.044] Build status: STARTED
[18:13:49.044] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:6:0) after 3m14s
[18:17:03.074] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:6:0)
[18:17:03.099] Build status: STARTED
[18:17:03.099] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:7:0) after 6m48s
[18:23:51.186] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:7:0)
[18:23:51.213] Build status: STARTED
[18:23:51.213] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:8:0) after 4m57s
[18:28:48.262] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:8:0)
[18:28:48.283] Build status: STARTED
[18:28:48.283] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:9:0) after 1m35s
[18:30:23.298] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:9:0)
[18:30:23.330] Build status: STARTED
[18:30:23.330] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:10:0) after 4m31s
[18:34:54.439] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:10:0)
[18:34:54.467] Build status: STARTED
[18:34:54.468] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:11:0) after 9m17s
[18:44:11.554] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:11:0)
[18:44:11.586] Build status: STARTED
[18:44:11.586] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:12:0) after 4m24s
[18:48:35.643] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:12:0)
[18:48:35.658] Build status: STARTED
[18:48:35.658] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:13:0) after 7m46s
[18:56:21.717] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:13:0)
[18:56:21.733] Build status: STARTED
[18:56:21.733] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:14:0) after 3m10s
[18:59:31.767] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:14:0)
[18:59:31.782] Build status: STARTED
[18:59:31.782] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:15:0) after 9m16s
[19:08:47.800] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:15:0)
[19:08:47.818] Build status: STARTED
[19:08:47.818] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:16:0) after 3m44s
[19:12:31.847] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:16:0)
[19:12:31.861] Build status: STARTED
[19:12:31.861] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:17:0) after 1m14s
[19:13:45.901] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:17:0)
[19:13:45.919] Build status: STARTED
[19:13:45.919] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:18:0) after 3m45s
[19:17:31.009] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:18:0)
[19:17:31.022] Build status: STARTED
[19:17:31.022] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:19:0) after 8m20s
[19:25:51.038] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:19:0)
[19:25:51.055] Build status: STARTED
[19:25:51.055] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:20:0) after 3m11s
[19:29:02.075] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:20:0)
[19:29:02.090] Build status: STARTED
[19:29:02.090] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:21:0) after 7m1s
[19:36:03.117] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:21:0)
[19:36:03.136] Build status: STARTED
[19:36:03.136] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:22:0) after 5m53s
[19:41:56.153] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:22:0)
[19:41:56.169] Build status: STARTED
[19:41:56.169] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:23:0) after 8m25s
[19:50:21.185] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:23:0)
[19:50:21.201] Build status: STARTED
[19:50:21.202] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:24:0) after 5m29s
[19:55:50.274] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:24:0)
[19:55:50.291] Build status: STARTED
[19:55:50.291] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:25:0) after 3m4s
[19:58:54.307] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:25:0)
[19:58:54.323] Build status: STARTED
[19:58:54.323] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:26:0) after 4m9s
[20:03:03.505] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:26:0)
[20:03:03.523] Build status: STARTED
[20:03:03.523] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:27:0) after 4m18s
[20:07:21.586] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:27:0)
[20:07:21.603] Build status: STARTED
[20:07:21.603] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:28:0) after 3m5s
[20:10:26.666] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:28:0)
[20:10:26.717] Build status: STARTED
[20:10:26.717] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:29:0) after 7m6s
[20:17:32.782] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:29:0)
[20:17:32.814] Build status: STARTED
[20:17:32.814] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:30:0) after 6m40s
[20:24:12.926] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:30:0)
[20:24:12.939] Build status: STARTED
[20:24:12.939] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:31:0) after 8m0s
[20:32:12.925] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:31:0)
[20:32:12.962] Build status: STARTED
[20:32:12.962] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:32:0) after 8m23s
[20:40:35.985] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:32:0)
[20:40:36.008] Build status: STARTED
[20:40:36.009] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:33:0) after 6m26s
[20:47:02.009] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:33:0)
[20:47:02.022] Build status: STARTED
[20:47:02.023] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:34:0) after 2m55s
[20:49:57.230] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:34:0)
[20:49:57.258] Build status: STARTED
[20:49:57.258] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:35:0) after 9m15s
[20:59:12.277] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:35:0)
[20:59:12.295] Build status: STARTED
[20:59:12.295] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:36:0) after 3m18s
[21:02:30.320] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:36:0)
[21:02:30.391] Build status: STARTED
[21:02:30.391] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:37:0) after 6m47s
[21:09:17.517] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:37:0)
[21:09:17.533] Build status: STARTED
[21:09:17.533] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:38:0) after 7m32s
[21:16:49.605] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:38:0)
[21:16:49.783] Build status: STARTED
[21:16:49.783] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:39:0) after 9m11s
[21:26:01.030] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:39:0)
[21:26:01.151] Build status: STARTED
[21:26:01.151] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:40:0) after 1m49s
[21:27:50.267] Handling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:40:0)
[21:27:50.283] Build status: STARTED
[21:27:50.283] Scheduling timer "check-buildbucket-build-status" (fuchsia/global.ci-fuchsia-coverage-e5a0ec7f:8862637051819976096:41:0) after 9m58s
[21:36:41.353] Received PubSub notification, asking Buildbucket for the build status
[21:36:41.371] Build:
{
"id": "8697206989909963665",
"builder": {
"project": "fuchsia",
"bucket": "global.ci",
"builder": "fuchsia-coverage"
},
"createdBy": "project:fuchsia",
"createTime": "2025-11-25T18:08:11.461312725Z",
"startTime": "2025-11-25T18:08:16.982513312Z",
"endTime": "2025-11-25T21:36:41.078071096Z",
"updateTime": "2025-11-25T21:36:41.078071096Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "fuchsia.googlesource.com",
"project": "integration",
"id": "b913fbed66c4c1ec745c545232dcf782ba06f50d",
"ref": "refs/heads/main"
}
}
}
[21:36:41.371] Invocation finished in 3h28m31.11567661s with status SUCCEEDED