[19:04:53.254] New invocation is queued and will start shortly
[19:04:54.492] Starting the invocation (attempt 1)
[19:04:54.520] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/783059f700f1d6d2d7d4d1d8b47165fdf97a01b8"
[19:04:54.520] Popped gitiles commit info from properties and tags
[19:04:54.520] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:04:54.520] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:04:54.521] Buildbucket request:
{
"requestId": "8865804377348884336",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-bazel-default"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8865804377348884336",
"job": "pigweed/pigweed.dev.ci-pigweed-linux-bazel-default",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "d62d9a094fa33e152364e6149f96679952cb3d3d"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@d62d9a094fa33e152364e6149f96679952cb3d3d",
"title": "d62d9a094fa33e152364e6149f96679952cb3d3d",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/d62d9a094fa33e152364e6149f96679952cb3d3d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "8de330fb81dbf1599e73dbecb147febea22923b2"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@8de330fb81dbf1599e73dbecb147febea22923b2",
"title": "8de330fb81dbf1599e73dbecb147febea22923b2",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/8de330fb81dbf1599e73dbecb147febea22923b2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "43b338db9571a07e3d458daa307f80891b228b5f"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@43b338db9571a07e3d458daa307f80891b228b5f",
"title": "43b338db9571a07e3d458daa307f80891b228b5f",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/43b338db9571a07e3d458daa307f80891b228b5f"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "8e1d5fd307ccef9c91f28e99c29c3ca884f543f9"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@8e1d5fd307ccef9c91f28e99c29c3ca884f543f9",
"title": "8e1d5fd307ccef9c91f28e99c29c3ca884f543f9",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/8e1d5fd307ccef9c91f28e99c29c3ca884f543f9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "783059f700f1d6d2d7d4d1d8b47165fdf97a01b8"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@783059f700f1d6d2d7d4d1d8b47165fdf97a01b8",
"title": "783059f700f1d6d2d7d4d1d8b47165fdf97a01b8",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/783059f700f1d6d2d7d4d1d8b47165fdf97a01b8"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "783059f700f1d6d2d7d4d1d8b47165fdf97a01b8",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8865804377348884336"
},
{
"key": "scheduler_job_id",
"value": "pigweed/pigweed.dev.ci-pigweed-linux-bazel-default"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[19:04:54.874] Scheduled build:
{
"id": "8700374315301663745",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-bazel-default"
},
"createdBy": "project:pigweed",
"createTime": "2025-10-21T19:04:54.584575982Z",
"updateTime": "2025-10-21T19:04:54.584575982Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "783059f700f1d6d2d7d4d1d8b47165fdf97a01b8",
"ref": "refs/heads/main"
}
}
}
[19:04:54.874] Task URL: https://cr-buildbucket.appspot.com/build/8700374315301663745
[19:04:54.874] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:2:0) after 4m18s
[19:05:01.465] Received PubSub notification, asking Buildbucket for the build status
[19:05:01.488] Build status: SCHEDULED
[19:09:12.929] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:2:0)
[19:09:12.946] Build status: SCHEDULED
[19:09:12.946] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:4:0) after 3m12s
[19:12:25.016] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:4:0)
[19:12:25.048] Build status: SCHEDULED
[19:12:25.048] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:5:0) after 4m14s
[19:16:39.064] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:5:0)
[19:16:39.123] Build status: SCHEDULED
[19:16:39.123] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:6:0) after 5m6s
[19:21:45.222] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:6:0)
[19:21:45.239] Build status: SCHEDULED
[19:21:45.239] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:7:0) after 8m45s
[19:30:30.393] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:7:0)
[19:30:30.419] Build status: SCHEDULED
[19:30:30.419] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:8:0) after 9m31s
[19:40:01.434] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:8:0)
[19:40:01.451] Build status: SCHEDULED
[19:40:01.451] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:9:0) after 5m56s
[19:45:57.514] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:9:0)
[19:45:57.550] Build status: SCHEDULED
[19:45:57.550] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:10:0) after 8m16s
[19:54:13.564] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:10:0)
[19:54:13.586] Build status: SCHEDULED
[19:54:13.586] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:11:0) after 8m31s
[20:02:44.689] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:11:0)
[20:02:44.711] Build status: SCHEDULED
[20:02:44.711] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:12:0) after 2m21s
[20:05:05.733] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:12:0)
[20:05:05.808] Build status: SCHEDULED
[20:05:05.808] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:13:0) after 9m20s
[20:14:25.951] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:13:0)
[20:14:25.983] Build status: SCHEDULED
[20:14:25.983] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:14:0) after 4m48s
[20:19:14.063] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:14:0)
[20:19:14.079] Build status: SCHEDULED
[20:19:14.079] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:15:0) after 7m9s
[20:26:23.142] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:15:0)
[20:26:23.162] Build status: SCHEDULED
[20:26:23.162] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:16:0) after 6m15s
[20:32:38.186] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:16:0)
[20:32:38.256] Build status: SCHEDULED
[20:32:38.256] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:17:0) after 7m29s
[20:40:07.272] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:17:0)
[20:40:07.303] Build status: SCHEDULED
[20:40:07.303] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:18:0) after 8m6s
[20:48:13.348] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:18:0)
[20:48:13.364] Build status: SCHEDULED
[20:48:13.364] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:19:0) after 2m41s
[20:50:54.437] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:19:0)
[20:50:54.450] Build status: SCHEDULED
[20:50:54.450] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:20:0) after 5m40s
[20:56:34.466] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:20:0)
[20:56:34.489] Build status: SCHEDULED
[20:56:34.489] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:21:0) after 6m36s
[21:03:10.533] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:21:0)
[21:03:10.548] Build status: SCHEDULED
[21:03:10.548] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:22:0) after 6m2s
[21:09:12.563] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:22:0)
[21:09:12.583] Build status: SCHEDULED
[21:09:12.583] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:23:0) after 1m20s
[21:10:32.628] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:23:0)
[21:10:32.761] Build status: SCHEDULED
[21:10:32.761] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:24:0) after 5m46s
[21:16:18.822] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:24:0)
[21:16:18.841] Build status: SCHEDULED
[21:16:18.841] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:25:0) after 6m29s
[21:22:47.855] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:25:0)
[21:22:47.873] Build status: SCHEDULED
[21:22:47.873] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:26:0) after 6m14s
[21:29:01.938] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:26:0)
[21:29:02.118] Build status: SCHEDULED
[21:29:02.119] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:27:0) after 2m3s
[21:31:05.104] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:27:0)
[21:31:05.118] Build status: SCHEDULED
[21:31:05.118] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:28:0) after 1m52s
[21:32:57.241] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:28:0)
[21:32:57.288] Build status: SCHEDULED
[21:32:57.288] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:29:0) after 4m9s
[21:37:06.412] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:29:0)
[21:37:06.456] Build status: SCHEDULED
[21:37:06.456] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:30:0) after 5m17s
[21:42:23.522] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:30:0)
[21:42:23.536] Build status: SCHEDULED
[21:42:23.536] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:31:0) after 9m5s
[21:51:28.607] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:31:0)
[21:51:28.623] Build status: SCHEDULED
[21:51:28.623] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:32:0) after 5m29s
[21:56:57.615] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:32:0)
[21:56:57.665] Build status: SCHEDULED
[21:56:57.665] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:33:0) after 1m56s
[21:58:53.680] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:33:0)
[21:58:53.696] Build status: SCHEDULED
[21:58:53.696] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:34:0) after 1m33s
[22:00:26.801] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:34:0)
[22:00:26.839] Build status: SCHEDULED
[22:00:26.839] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:35:0) after 4m5s
[22:04:31.875] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:35:0)
[22:04:31.894] Build status: SCHEDULED
[22:04:31.894] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:36:0) after 1m14s
[22:05:45.909] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:36:0)
[22:05:45.926] Build status: SCHEDULED
[22:05:45.926] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:37:0) after 3m25s
[22:09:11.000] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:37:0)
[22:09:11.076] Build status: SCHEDULED
[22:09:11.076] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:38:0) after 4m10s
[22:13:21.147] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:38:0)
[22:13:21.162] Build status: SCHEDULED
[22:13:21.162] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:39:0) after 2m49s
[22:16:10.205] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:39:0)
[22:16:10.227] Build status: SCHEDULED
[22:16:10.227] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:40:0) after 6m1s
[22:22:11.293] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:40:0)
[22:22:11.318] Build status: SCHEDULED
[22:22:11.318] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:41:0) after 1m49s
[22:24:00.296] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:41:0)
[22:24:00.841] Build status: SCHEDULED
[22:24:00.841] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:42:0) after 5m48s
[22:24:35.165] Received PubSub notification, asking Buildbucket for the build status
[22:24:35.202] Build status: STARTED
[22:29:48.923] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:42:0)
[22:29:48.943] Build status: STARTED
[22:29:48.943] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:44:0) after 1m18s
[22:31:07.032] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:44:0)
[22:31:07.061] Build status: STARTED
[22:31:07.061] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-default:8865804377348884336:45:0) after 7m55s
[22:33:17.526] Received PubSub notification, asking Buildbucket for the build status
[22:33:17.543] Build:
{
"id": "8700374315301663745",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-bazel-default"
},
"createdBy": "project:pigweed",
"createTime": "2025-10-21T19:04:54.584575982Z",
"startTime": "2025-10-21T22:24:33.676151397Z",
"endTime": "2025-10-21T22:32:38.521359889Z",
"updateTime": "2025-10-21T22:32:38.521359889Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "783059f700f1d6d2d7d4d1d8b47165fdf97a01b8",
"ref": "refs/heads/main"
}
}
}
[22:33:17.543] Invocation finished in 3h28m24.294998689s with status SUCCEEDED