[07:09:35.077] New invocation is queued and will start shortly
[07:09:36.137] Starting the invocation (attempt 1)
[07:09:36.163] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/1bcab24cf25e8260480323b627489e7185bdf6e3"
[07:09:36.163] Popped gitiles commit info from properties and tags
[07:09:36.163] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:09:36.163] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:09:36.163] Buildbucket request:
{
"requestId": "8832781487581735552",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "envtest-linux"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8832781487581735552",
"job": "pigweed/pigweed.dev.ci-envtest-linux",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "1bcab24cf25e8260480323b627489e7185bdf6e3"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@1bcab24cf25e8260480323b627489e7185bdf6e3",
"title": "1bcab24cf25e8260480323b627489e7185bdf6e3",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/1bcab24cf25e8260480323b627489e7185bdf6e3"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "1bcab24cf25e8260480323b627489e7185bdf6e3",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8832781487581735552"
},
{
"key": "scheduler_job_id",
"value": "pigweed/pigweed.dev.ci-envtest-linux"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[07:09:36.605] Scheduled build:
{
"id": "8667351425716880049",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "envtest-linux"
},
"createdBy": "project:pigweed",
"createTime": "2026-10-21T07:09:36.234929625Z",
"updateTime": "2026-10-21T07:09:36.234929625Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "1bcab24cf25e8260480323b627489e7185bdf6e3",
"ref": "refs/heads/main"
}
}
}
[07:09:36.605] Task URL: https://cr-buildbucket.appspot.com/build/8667351425716880049
[07:09:36.605] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:2:0) after 1m1s
[07:09:37.617] Received PubSub notification, asking Buildbucket for the build status
[07:09:37.640] Build status: SCHEDULED
[07:10:37.619] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:2:0)
[07:10:37.640] Build status: SCHEDULED
[07:10:37.640] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:4:0) after 6m20s
[07:16:57.809] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:4:0)
[07:16:57.845] Build status: SCHEDULED
[07:16:57.845] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:5:0) after 1m0s
[07:17:57.863] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:5:0)
[07:17:57.902] Build status: SCHEDULED
[07:17:57.902] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:6:0) after 2m57s
[07:20:54.944] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:6:0)
[07:20:54.969] Build status: SCHEDULED
[07:20:54.969] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:7:0) after 7m25s
[07:28:20.007] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:7:0)
[07:28:20.032] Build status: SCHEDULED
[07:28:20.032] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:8:0) after 1m9s
[07:29:29.072] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:8:0)
[07:29:29.124] Build status: SCHEDULED
[07:29:29.125] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:9:0) after 8m11s
[07:37:40.186] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:9:0)
[07:37:40.253] Build status: SCHEDULED
[07:37:40.253] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:10:0) after 9m54s
[07:47:34.271] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:10:0)
[07:47:34.299] Build status: SCHEDULED
[07:47:34.299] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:11:0) after 2m2s
[07:49:36.316] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:11:0)
[07:49:36.338] Build status: SCHEDULED
[07:49:36.338] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:12:0) after 6m8s
[07:55:44.323] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:12:0)
[07:55:44.343] Build status: SCHEDULED
[07:55:44.343] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:13:0) after 5m21s
[08:01:05.397] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:13:0)
[08:01:05.425] Build status: SCHEDULED
[08:01:05.425] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:14:0) after 3m56s
[08:05:01.496] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:14:0)
[08:05:01.515] Build status: SCHEDULED
[08:05:01.515] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:15:0) after 1m9s
[08:06:10.580] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:15:0)
[08:06:10.648] Build status: SCHEDULED
[08:06:10.648] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:16:0) after 9m22s
[08:15:32.712] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:16:0)
[08:15:32.737] Build status: SCHEDULED
[08:15:32.737] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:17:0) after 6m44s
[08:22:16.816] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:17:0)
[08:22:17.002] Build status: SCHEDULED
[08:22:17.002] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:18:0) after 6m43s
[08:29:00.025] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:18:0)
[08:29:00.118] Build status: SCHEDULED
[08:29:00.118] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:19:0) after 1m46s
[08:30:46.224] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:19:0)
[08:30:46.247] Build status: SCHEDULED
[08:30:46.247] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:20:0) after 1m18s
[08:32:04.270] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:20:0)
[08:32:04.302] Build status: SCHEDULED
[08:32:04.302] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:21:0) after 6m8s
[08:38:12.421] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:21:0)
[08:38:12.442] Build status: SCHEDULED
[08:38:12.442] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:22:0) after 7m31s
[08:45:43.472] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:22:0)
[08:45:43.510] Build status: SCHEDULED
[08:45:43.510] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:23:0) after 3m37s
[08:49:20.553] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:23:0)
[08:49:20.582] Build status: SCHEDULED
[08:49:20.582] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:24:0) after 2m43s
[08:52:03.755] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:24:0)
[08:52:03.776] Build status: SCHEDULED
[08:52:03.776] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:25:0) after 5m5s
[08:57:08.796] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:25:0)
[08:57:08.813] Build status: SCHEDULED
[08:57:08.813] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:26:0) after 4m2s
[09:01:10.828] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:26:0)
[09:01:10.862] Build status: SCHEDULED
[09:01:10.862] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:27:0) after 7m19s
[09:08:29.857] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:27:0)
[09:08:29.920] Build status: SCHEDULED
[09:08:29.920] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:28:0) after 1m43s
[09:10:12.970] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:28:0)
[09:10:12.990] Build status: SCHEDULED
[09:10:12.990] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:29:0) after 3m1s
[09:13:14.091] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:29:0)
[09:13:14.138] Build status: SCHEDULED
[09:13:14.138] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:30:0) after 8m23s
[09:21:37.155] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:30:0)
[09:21:37.214] Build status: SCHEDULED
[09:21:37.214] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:31:0) after 9m37s
[09:31:14.304] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:31:0)
[09:31:14.331] Build status: SCHEDULED
[09:31:14.331] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:32:0) after 1m6s
[09:32:20.527] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:32:0)
[09:32:20.552] Build status: SCHEDULED
[09:32:20.552] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:33:0) after 6m21s
[09:38:41.545] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:33:0)
[09:38:41.566] Build status: SCHEDULED
[09:38:41.566] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:34:0) after 2m4s
[09:40:45.670] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:34:0)
[09:40:45.725] Build status: SCHEDULED
[09:40:45.725] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:35:0) after 4m27s
[09:45:12.767] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:35:0)
[09:45:12.846] Build status: SCHEDULED
[09:45:12.846] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:36:0) after 4m58s
[09:50:10.996] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:36:0)
[09:50:11.022] Build status: SCHEDULED
[09:50:11.022] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:37:0) after 4m27s
[09:54:38.076] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:37:0)
[09:54:38.109] Build status: SCHEDULED
[09:54:38.109] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:38:0) after 3m23s
[09:58:01.146] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:38:0)
[09:58:01.194] Build status: SCHEDULED
[09:58:01.194] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:39:0) after 1m24s
[09:59:25.417] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:39:0)
[09:59:25.452] Build status: SCHEDULED
[09:59:25.452] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:40:0) after 6m17s
[10:05:42.469] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:40:0)
[10:05:42.493] Build status: SCHEDULED
[10:05:42.493] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:41:0) after 3m55s
[10:09:37.584] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:41:0)
[10:09:37.679] Build status: SCHEDULED
[10:09:37.679] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:42:0) after 1m0s
[10:10:37.821] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:42:0)
[10:10:37.847] Build status: SCHEDULED
[10:10:37.847] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:43:0) after 1m50s
[10:12:27.882] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:43:0)
[10:12:27.929] Build status: SCHEDULED
[10:12:27.929] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:44:0) after 7m7s
[10:19:35.191] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:44:0)
[10:19:35.214] Build status: SCHEDULED
[10:19:35.214] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:45:0) after 8m8s
[10:27:43.255] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:45:0)
[10:27:43.295] Build status: SCHEDULED
[10:27:43.295] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:46:0) after 9m53s
[10:37:36.364] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:46:0)
[10:37:36.385] Build status: SCHEDULED
[10:37:36.385] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:47:0) after 7m54s
[10:43:22.321] Received PubSub notification, asking Buildbucket for the build status
[10:43:22.400] Build status: STARTED
[10:45:30.467] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:47:0)
[10:45:30.494] Build status: STARTED
[10:45:30.494] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-linux:8832781487581735552:49:0) after 7m22s
[10:49:21.741] Received PubSub notification, asking Buildbucket for the build status
[10:49:21.768] Build:
{
"id": "8667351425716880049",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "envtest-linux"
},
"createdBy": "project:pigweed",
"createTime": "2026-10-21T07:09:36.234929625Z",
"startTime": "2026-10-21T10:43:22.100799224Z",
"endTime": "2026-10-21T10:49:21.325224414Z",
"updateTime": "2026-10-21T10:49:21.325224414Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "1bcab24cf25e8260480323b627489e7185bdf6e3",
"ref": "refs/heads/main"
}
}
}
[10:49:21.768] Invocation finished in 3h39m46.695810563s with status SUCCEEDED