[20:55:36.898] New invocation is queued and will start shortly
[20:55:39.865] Starting the invocation (attempt 1)
[20:55:39.886] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/8f7256acc3dce0d1b78a8ec035a6c306e995dcc8"
[20:55:39.886] Popped gitiles commit info from properties and tags
[20:55:39.886] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:55:39.886] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:55:39.886] Buildbucket request:
{
"requestId": "8833907278809914368",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "coverage"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8833907278809914368",
"job": "pigweed/pigweed.dev.ci-coverage",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "fca73403a7f2f0c94783b91da85b570048886ad0"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@fca73403a7f2f0c94783b91da85b570048886ad0",
"title": "fca73403a7f2f0c94783b91da85b570048886ad0",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/fca73403a7f2f0c94783b91da85b570048886ad0"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "147bc0f460e83167177a87053c1460d7be862675"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@147bc0f460e83167177a87053c1460d7be862675",
"title": "147bc0f460e83167177a87053c1460d7be862675",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/147bc0f460e83167177a87053c1460d7be862675"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "b54d39ca8bd7fcb07dd3a93191674388476254fd"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@b54d39ca8bd7fcb07dd3a93191674388476254fd",
"title": "b54d39ca8bd7fcb07dd3a93191674388476254fd",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/b54d39ca8bd7fcb07dd3a93191674388476254fd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "8f7256acc3dce0d1b78a8ec035a6c306e995dcc8"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@8f7256acc3dce0d1b78a8ec035a6c306e995dcc8",
"title": "8f7256acc3dce0d1b78a8ec035a6c306e995dcc8",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/8f7256acc3dce0d1b78a8ec035a6c306e995dcc8"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "8f7256acc3dce0d1b78a8ec035a6c306e995dcc8",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8833907278809914368"
},
{
"key": "scheduler_job_id",
"value": "pigweed/pigweed.dev.ci-coverage"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[20:55:40.781] Scheduled build:
{
"id": "8668477214922891505",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "coverage"
},
"createdBy": "project:pigweed",
"createTime": "2026-10-08T20:55:39.983653442Z",
"updateTime": "2026-10-08T20:55:39.983653442Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "8f7256acc3dce0d1b78a8ec035a6c306e995dcc8",
"ref": "refs/heads/main"
}
}
}
[20:55:40.781] Task URL: https://cr-buildbucket.appspot.com/build/8668477214922891505
[20:55:40.781] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:2:0) after 7m19s
[20:55:41.513] Received PubSub notification, asking Buildbucket for the build status
[20:55:41.588] Build status: SCHEDULED
[21:02:59.756] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:2:0)
[21:02:59.835] Build status: SCHEDULED
[21:02:59.835] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:4:0) after 7m19s
[21:10:19.159] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:4:0)
[21:10:19.183] Build status: SCHEDULED
[21:10:19.183] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:5:0) after 7m1s
[21:17:20.344] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:5:0)
[21:17:20.376] Build status: SCHEDULED
[21:17:20.376] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:6:0) after 3m23s
[21:20:43.626] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:6:0)
[21:20:43.648] Build status: SCHEDULED
[21:20:43.648] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:7:0) after 2m10s
[21:22:53.718] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:7:0)
[21:22:53.737] Build status: SCHEDULED
[21:22:53.738] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:8:0) after 6m24s
[21:29:17.751] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:8:0)
[21:29:17.774] Build status: SCHEDULED
[21:29:17.774] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:9:0) after 8m51s
[21:38:08.804] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:9:0)
[21:38:08.826] Build status: SCHEDULED
[21:38:08.826] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:10:0) after 2m47s
[21:40:55.843] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:10:0)
[21:40:55.865] Build status: SCHEDULED
[21:40:55.865] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:11:0) after 6m37s
[21:47:32.951] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:11:0)
[21:47:32.970] Build status: SCHEDULED
[21:47:32.970] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:12:0) after 3m4s
[21:50:37.197] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:12:0)
[21:50:37.220] Build status: SCHEDULED
[21:50:37.220] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:13:0) after 2m56s
[21:53:33.399] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:13:0)
[21:53:33.419] Build status: SCHEDULED
[21:53:33.419] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:14:0) after 1m53s
[21:55:26.879] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:14:0)
[21:55:26.901] Build status: SCHEDULED
[21:55:26.901] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:15:0) after 1m17s
[21:56:43.973] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:15:0)
[21:56:44.001] Build status: SCHEDULED
[21:56:44.001] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:16:0) after 3m11s
[21:59:55.017] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:16:0)
[21:59:55.045] Build status: SCHEDULED
[21:59:55.045] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:17:0) after 5m14s
[22:05:09.096] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:17:0)
[22:05:09.127] Build status: SCHEDULED
[22:05:09.127] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:18:0) after 1m14s
[22:06:23.142] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:18:0)
[22:06:23.162] Build status: SCHEDULED
[22:06:23.162] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:19:0) after 6m19s
[22:12:42.179] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:19:0)
[22:12:42.203] Build status: SCHEDULED
[22:12:42.203] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:20:0) after 1m30s
[22:14:12.340] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:20:0)
[22:14:12.360] Build status: SCHEDULED
[22:14:12.360] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:21:0) after 2m7s
[22:16:19.382] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:21:0)
[22:16:19.401] Build status: SCHEDULED
[22:16:19.401] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:22:0) after 2m53s
[22:19:12.576] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:22:0)
[22:19:12.598] Build status: SCHEDULED
[22:19:12.598] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:23:0) after 1m46s
[22:20:58.611] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:23:0)
[22:20:58.632] Build status: SCHEDULED
[22:20:58.632] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:24:0) after 5m53s
[22:26:51.691] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:24:0)
[22:26:51.715] Build status: SCHEDULED
[22:26:51.715] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:25:0) after 1m16s
[22:28:07.735] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:25:0)
[22:28:07.758] Build status: SCHEDULED
[22:28:07.758] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:26:0) after 6m27s
[22:34:34.772] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:26:0)
[22:34:34.849] Build status: SCHEDULED
[22:34:34.849] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:27:0) after 2m52s
[22:37:26.856] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:27:0)
[22:37:26.879] Build status: SCHEDULED
[22:37:26.879] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:28:0) after 3m32s
[22:40:58.931] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:28:0)
[22:40:58.959] Build status: SCHEDULED
[22:40:58.959] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:29:0) after 4m54s
[22:45:52.974] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:29:0)
[22:45:52.995] Build status: SCHEDULED
[22:45:52.995] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:30:0) after 6m16s
[22:52:09.056] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:30:0)
[22:52:09.077] Build status: SCHEDULED
[22:52:09.077] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:31:0) after 5m12s
[22:57:21.289] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:31:0)
[22:57:21.316] Build status: SCHEDULED
[22:57:21.316] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:32:0) after 7m47s
[23:05:08.388] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:32:0)
[23:05:08.406] Build status: SCHEDULED
[23:05:08.406] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:33:0) after 2m35s
[23:07:43.427] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:33:0)
[23:07:43.464] Build status: SCHEDULED
[23:07:43.465] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:34:0) after 6m26s
[23:14:09.513] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:34:0)
[23:14:09.547] Build status: SCHEDULED
[23:14:09.547] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:35:0) after 2m34s
[23:16:43.683] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:35:0)
[23:16:43.699] Build status: SCHEDULED
[23:16:43.699] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:36:0) after 7m3s
[23:23:46.866] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:36:0)
[23:23:46.884] Build status: SCHEDULED
[23:23:46.884] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:37:0) after 9m35s
[23:33:22.141] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:37:0)
[23:33:22.161] Build status: SCHEDULED
[23:33:22.161] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:38:0) after 4m33s
[23:37:55.309] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:38:0)
[23:37:55.333] Build status: SCHEDULED
[23:37:55.333] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:39:0) after 8m37s
[23:46:32.405] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:39:0)
[23:46:32.426] Build status: SCHEDULED
[23:46:32.427] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:40:0) after 2m22s
[23:48:54.446] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:40:0)
[23:48:54.506] Build status: SCHEDULED
[23:48:54.506] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:41:0) after 1m29s
[23:50:23.521] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:41:0)
[23:50:23.550] Build status: SCHEDULED
[23:50:23.550] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:42:0) after 5m37s
[23:56:00.595] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:42:0)
[23:56:00.633] Build status: SCHEDULED
[23:56:00.634] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:43:0) after 3m53s
[23:59:53.735] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:43:0)
[23:59:53.807] Build status: SCHEDULED
[23:59:53.807] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:44:0) after 1m22s
[00:01:15.855] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:44:0)
[00:01:15.879] Build status: SCHEDULED
[00:01:15.879] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:45:0) after 1m44s
[00:02:59.908] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:45:0)
[00:02:59.926] Build status: SCHEDULED
[00:02:59.926] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:46:0) after 2m24s
[00:05:24.443] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:46:0)
[00:05:24.462] Build status: SCHEDULED
[00:05:24.462] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:47:0) after 7m15s
[00:12:39.478] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:47:0)
[00:12:39.497] Build status: SCHEDULED
[00:12:39.497] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:48:0) after 3m54s
[00:12:42.440] Received PubSub notification, asking Buildbucket for the build status
[00:12:42.467] Build status: STARTED
[00:16:33.517] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:48:0)
[00:16:33.541] Build status: STARTED
[00:16:33.541] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:50:0) after 6m8s
[00:22:41.753] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:50:0)
[00:22:41.837] Build status: STARTED
[00:22:41.837] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8833907278809914368:51:0) after 3m58s
[00:23:03.157] Received PubSub notification, asking Buildbucket for the build status
[00:23:03.189] Build:
{
"id": "8668477214922891505",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "coverage"
},
"createdBy": "project:pigweed",
"createTime": "2026-10-08T20:55:39.983653442Z",
"startTime": "2026-10-09T00:12:42.196908811Z",
"endTime": "2026-10-09T00:23:02.879500193Z",
"updateTime": "2026-10-09T00:23:02.879500193Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "8f7256acc3dce0d1b78a8ec035a6c306e995dcc8",
"ref": "refs/heads/main"
}
}
}
[00:23:03.189] Invocation finished in 3h27m26.296614999s with status SUCCEEDED