[19:53:04.954] New invocation is queued and will start shortly
[19:53:06.183] Starting the invocation (attempt 1)
[19:53:06.205] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/experimental/+/1c434b00ac13caa1c8d4d49e7add079253185f60"
[19:53:06.206] Popped gitiles commit info from properties and tags
[19:53:06.206] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:53:06.207] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:53:06.207] Buildbucket request:
{
"requestId": "8841702552118439424",
"builder": {
"project": "pigweed",
"bucket": "experimental.dev.ci",
"builder": "experimental-envtest"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8841702552118439424",
"job": "pigweed/experimental.dev.ci-experimental-envtest",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/experimental",
"revision": "1c434b00ac13caa1c8d4d49e7add079253185f60"
},
"id": "https://pigweed.googlesource.com/pigweed/experimental/+/refs/heads/main@1c434b00ac13caa1c8d4d49e7add079253185f60",
"title": "1c434b00ac13caa1c8d4d49e7add079253185f60",
"url": "https://pigweed.googlesource.com/pigweed/experimental/+/1c434b00ac13caa1c8d4d49e7add079253185f60"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/experimental",
"id": "1c434b00ac13caa1c8d4d49e7add079253185f60",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8841702552118439424"
},
{
"key": "scheduler_job_id",
"value": "pigweed/experimental.dev.ci-experimental-envtest"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[19:53:06.617] Scheduled build:
{
"id": "8676272490073190833",
"builder": {
"project": "pigweed",
"bucket": "experimental.dev.ci",
"builder": "experimental-envtest"
},
"createdBy": "project:pigweed",
"createTime": "2026-07-14T19:53:06.283341822Z",
"updateTime": "2026-07-14T19:53:06.283341822Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/experimental",
"id": "1c434b00ac13caa1c8d4d49e7add079253185f60",
"ref": "refs/heads/main"
}
}
}
[19:53:06.617] Task URL: https://cr-buildbucket.appspot.com/build/8676272490073190833
[19:53:06.617] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:2:0) after 6m25s
[19:53:06.909] Received PubSub notification, asking Buildbucket for the build status
[19:53:06.983] Build status: SCHEDULED
[19:59:31.681] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:2:0)
[19:59:31.699] Build status: SCHEDULED
[19:59:31.699] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:4:0) after 8m41s
[20:08:12.720] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:4:0)
[20:08:12.768] Build status: SCHEDULED
[20:08:12.768] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:5:0) after 2m13s
[20:10:25.767] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:5:0)
[20:10:26.277] Build status: SCHEDULED
[20:10:26.277] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:6:0) after 8m1s
[20:18:27.299] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:6:0)
[20:18:27.324] Build status: SCHEDULED
[20:18:27.324] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:7:0) after 5m55s
[20:24:22.553] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:7:0)
[20:24:22.572] Build status: SCHEDULED
[20:24:22.572] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:8:0) after 3m48s
[20:28:10.628] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:8:0)
[20:28:10.645] Build status: SCHEDULED
[20:28:10.645] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:9:0) after 6m51s
[20:35:01.705] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:9:0)
[20:35:01.726] Build status: SCHEDULED
[20:35:01.726] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:10:0) after 5m44s
[20:40:45.778] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:10:0)
[20:40:45.798] Build status: SCHEDULED
[20:40:45.798] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:11:0) after 6m21s
[20:47:06.863] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:11:0)
[20:47:06.881] Build status: SCHEDULED
[20:47:06.881] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:12:0) after 5m9s
[20:52:15.885] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:12:0)
[20:52:15.903] Build status: SCHEDULED
[20:52:15.903] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:13:0) after 6m7s
[20:58:22.994] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:13:0)
[20:58:23.017] Build status: SCHEDULED
[20:58:23.017] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:14:0) after 2m53s
[21:01:16.119] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:14:0)
[21:01:16.147] Build status: SCHEDULED
[21:01:16.147] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:15:0) after 3m33s
[21:04:49.162] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:15:0)
[21:04:49.183] Build status: SCHEDULED
[21:04:49.184] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:16:0) after 3m9s
[21:07:58.246] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:16:0)
[21:07:58.264] Build status: SCHEDULED
[21:07:58.264] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:17:0) after 6m40s
[21:14:38.327] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:17:0)
[21:14:38.371] Build status: SCHEDULED
[21:14:38.371] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:18:0) after 7m1s
[21:21:39.386] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:18:0)
[21:21:39.410] Build status: SCHEDULED
[21:21:39.410] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:19:0) after 2m56s
[21:24:35.424] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:19:0)
[21:24:35.449] Build status: SCHEDULED
[21:24:35.449] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:20:0) after 7m23s
[21:31:58.501] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:20:0)
[21:31:58.520] Build status: SCHEDULED
[21:31:58.520] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:21:0) after 5m25s
[21:37:23.535] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:21:0)
[21:37:23.573] Build status: SCHEDULED
[21:37:23.573] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:22:0) after 3m53s
[21:41:16.590] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:22:0)
[21:41:16.655] Build status: SCHEDULED
[21:41:16.655] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:23:0) after 7m0s
[21:48:16.722] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:23:0)
[21:48:16.744] Build status: SCHEDULED
[21:48:16.745] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:24:0) after 1m17s
[21:49:33.758] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:24:0)
[21:49:33.776] Build status: SCHEDULED
[21:49:33.776] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:25:0) after 7m25s
[21:56:58.843] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:25:0)
[21:56:58.862] Build status: SCHEDULED
[21:56:58.862] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:26:0) after 2m0s
[21:58:59.070] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:26:0)
[21:58:59.089] Build status: SCHEDULED
[21:58:59.089] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:27:0) after 2m40s
[22:01:39.257] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:27:0)
[22:01:39.280] Build status: SCHEDULED
[22:01:39.280] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:28:0) after 6m41s
[22:08:20.346] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:28:0)
[22:08:20.372] Build status: SCHEDULED
[22:08:20.372] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:29:0) after 6m24s
[22:14:44.451] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:29:0)
[22:14:44.480] Build status: SCHEDULED
[22:14:44.480] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:30:0) after 3m5s
[22:17:49.495] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:30:0)
[22:17:49.550] Build status: SCHEDULED
[22:17:49.550] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:31:0) after 8m35s
[22:26:24.548] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:31:0)
[22:26:24.575] Build status: SCHEDULED
[22:26:24.575] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:32:0) after 7m11s
[22:33:35.620] Handling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:32:0)
[22:33:35.639] Build status: SCHEDULED
[22:33:35.639] Scheduling timer "check-buildbucket-build-status" (pigweed/experimental.dev.ci-experimental-envtest:8841702552118439424:33:0) after 9m58s
[22:37:18.556] Received PubSub notification, asking Buildbucket for the build status
[22:37:18.574] Build status: STARTED
[22:42:10.727] Received PubSub notification, asking Buildbucket for the build status
[22:42:10.743] Build:
{
"id": "8676272490073190833",
"builder": {
"project": "pigweed",
"bucket": "experimental.dev.ci",
"builder": "experimental-envtest"
},
"createdBy": "project:pigweed",
"createTime": "2026-07-14T19:53:06.283341822Z",
"startTime": "2026-07-14T22:37:18.337650927Z",
"endTime": "2026-07-14T22:42:10.460295167Z",
"updateTime": "2026-07-14T22:42:10.460295167Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/experimental",
"id": "1c434b00ac13caa1c8d4d49e7add079253185f60",
"ref": "refs/heads/main"
}
}
}
[22:42:10.743] Invocation finished in 2h49m5.7942794s with status SUCCEEDED