[01:57:51.729] New invocation is queued and will start shortly
[01:57:52.936] Starting the invocation (attempt 1)
[01:57:52.967] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/982d592c1c3f9ec707ed2c8a6c03aa3db5287f86"
[01:57:52.967] Popped gitiles commit info from properties and tags
[01:57:52.967] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:57:52.967] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:57:52.967] Buildbucket request:
{
"requestId": "8858802428831337056",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-san-msan"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8858802428831337056",
"job": "pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "21e681a53ac1a2d9ccc1b44c31c03f61e0a1924b"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@21e681a53ac1a2d9ccc1b44c31c03f61e0a1924b",
"title": "21e681a53ac1a2d9ccc1b44c31c03f61e0a1924b",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/21e681a53ac1a2d9ccc1b44c31c03f61e0a1924b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "cf568baf83c8a63ba0b641a6ef62a1710782a651"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@cf568baf83c8a63ba0b641a6ef62a1710782a651",
"title": "cf568baf83c8a63ba0b641a6ef62a1710782a651",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/cf568baf83c8a63ba0b641a6ef62a1710782a651"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "982d592c1c3f9ec707ed2c8a6c03aa3db5287f86"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@982d592c1c3f9ec707ed2c8a6c03aa3db5287f86",
"title": "982d592c1c3f9ec707ed2c8a6c03aa3db5287f86",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/982d592c1c3f9ec707ed2c8a6c03aa3db5287f86"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "982d592c1c3f9ec707ed2c8a6c03aa3db5287f86",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8858802428831337056"
},
{
"key": "scheduler_job_id",
"value": "pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:57:53.365] Scheduled build:
{
"id": "8693372366738278097",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-san-msan"
},
"createdBy": "project:pigweed",
"createTime": "2026-01-07T01:57:53.093115040Z",
"updateTime": "2026-01-07T01:57:53.093115040Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "982d592c1c3f9ec707ed2c8a6c03aa3db5287f86",
"ref": "refs/heads/main"
}
}
}
[01:57:53.365] Task URL: https://cr-buildbucket.appspot.com/build/8693372366738278097
[01:57:53.365] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:2:0) after 3m17s
[01:57:54.311] Received PubSub notification, asking Buildbucket for the build status
[01:57:54.327] Build status: SCHEDULED
[02:01:10.376] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:2:0)
[02:01:10.402] Build status: SCHEDULED
[02:01:10.402] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:4:0) after 7m49s
[02:08:59.417] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:4:0)
[02:08:59.450] Build status: SCHEDULED
[02:08:59.450] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:5:0) after 8m20s
[02:17:19.515] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:5:0)
[02:17:19.530] Build status: SCHEDULED
[02:17:19.530] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:6:0) after 1m22s
[02:18:41.542] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:6:0)
[02:18:41.628] Build status: SCHEDULED
[02:18:41.629] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:7:0) after 4m56s
[02:23:37.646] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:7:0)
[02:23:37.670] Build status: SCHEDULED
[02:23:37.670] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:8:0) after 6m20s
[02:29:57.710] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:8:0)
[02:29:57.736] Build status: SCHEDULED
[02:29:57.736] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:9:0) after 1m45s
[02:31:42.846] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:9:0)
[02:31:42.877] Build status: SCHEDULED
[02:31:42.877] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:10:0) after 1m8s
[02:32:50.891] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:10:0)
[02:32:50.914] Build status: SCHEDULED
[02:32:50.914] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:11:0) after 3m23s
[02:36:13.929] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:11:0)
[02:36:13.945] Build status: SCHEDULED
[02:36:13.945] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:12:0) after 5m42s
[02:41:55.966] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:12:0)
[02:41:56.004] Build status: SCHEDULED
[02:41:56.005] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:13:0) after 8m26s
[02:50:22.104] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:13:0)
[02:50:22.123] Build status: SCHEDULED
[02:50:22.123] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:14:0) after 2m3s
[02:52:25.139] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:14:0)
[02:52:25.164] Build status: SCHEDULED
[02:52:25.164] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:15:0) after 5m19s
[02:57:44.177] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:15:0)
[02:57:44.195] Build status: SCHEDULED
[02:57:44.195] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:16:0) after 2m57s
[03:00:41.241] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:16:0)
[03:00:41.310] Build status: SCHEDULED
[03:00:41.311] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:17:0) after 8m28s
[03:09:09.331] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:17:0)
[03:09:09.428] Build status: SCHEDULED
[03:09:09.428] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:18:0) after 6m32s
[03:15:41.527] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:18:0)
[03:15:41.563] Build status: SCHEDULED
[03:15:41.563] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:19:0) after 8m41s
[03:24:22.576] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:19:0)
[03:24:22.596] Build status: SCHEDULED
[03:24:22.596] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:20:0) after 3m11s
[03:27:33.663] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:20:0)
[03:27:33.678] Build status: SCHEDULED
[03:27:33.678] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:21:0) after 4m10s
[03:31:43.695] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:21:0)
[03:31:43.752] Build status: SCHEDULED
[03:31:43.752] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:22:0) after 4m38s
[03:36:21.781] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:22:0)
[03:36:21.799] Build status: SCHEDULED
[03:36:21.799] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:23:0) after 7m54s
[03:44:15.817] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:23:0)
[03:44:15.845] Build status: SCHEDULED
[03:44:15.846] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:24:0) after 4m28s
[03:48:44.054] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:24:0)
[03:48:44.075] Build status: SCHEDULED
[03:48:44.075] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:25:0) after 2m42s
[03:51:26.196] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:25:0)
[03:51:26.226] Build status: SCHEDULED
[03:51:26.226] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:26:0) after 7m39s
[03:59:05.203] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:26:0)
[03:59:05.230] Build status: SCHEDULED
[03:59:05.230] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:27:0) after 8m15s
[04:07:20.226] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:27:0)
[04:07:20.253] Build status: SCHEDULED
[04:07:20.253] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:28:0) after 9m37s
[04:16:57.552] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:28:0)
[04:16:57.568] Build status: SCHEDULED
[04:16:57.568] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:29:0) after 8m53s
[04:25:50.587] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:29:0)
[04:25:50.605] Build status: SCHEDULED
[04:25:50.605] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:30:0) after 6m59s
[04:32:49.754] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:30:0)
[04:32:49.772] Build status: SCHEDULED
[04:32:49.772] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:31:0) after 5m16s
[04:38:06.002] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:31:0)
[04:38:06.095] Build status: SCHEDULED
[04:38:06.095] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:32:0) after 3m20s
[04:41:26.212] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:32:0)
[04:41:26.225] Build status: SCHEDULED
[04:41:26.225] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:33:0) after 1m30s
[04:42:56.261] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:33:0)
[04:42:56.277] Build status: SCHEDULED
[04:42:56.277] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:34:0) after 7m55s
[04:50:51.243] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:34:0)
[04:50:51.257] Build status: SCHEDULED
[04:50:51.257] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:35:0) after 6m54s
[04:57:45.440] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:35:0)
[04:57:45.454] Build status: SCHEDULED
[04:57:45.454] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:36:0) after 1m29s
[04:59:14.477] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:36:0)
[04:59:14.513] Build status: SCHEDULED
[04:59:14.513] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:37:0) after 9m35s
[05:08:49.647] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:37:0)
[05:08:49.818] Build status: SCHEDULED
[05:08:49.818] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:38:0) after 2m47s
[05:11:37.019] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:38:0)
[05:11:37.038] Build status: SCHEDULED
[05:11:37.038] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:39:0) after 3m46s
[05:15:23.138] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:39:0)
[05:15:23.185] Build status: SCHEDULED
[05:15:23.185] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:40:0) after 3m51s
[05:19:14.199] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:40:0)
[05:19:14.215] Build status: SCHEDULED
[05:19:14.215] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:41:0) after 2m42s
[05:21:56.357] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:41:0)
[05:21:56.376] Build status: SCHEDULED
[05:21:56.376] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:42:0) after 7m11s
[05:29:07.782] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:42:0)
[05:29:07.795] Build status: SCHEDULED
[05:29:07.795] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:43:0) after 7m15s
[05:36:22.814] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:43:0)
[05:36:22.828] Build status: SCHEDULED
[05:36:22.828] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:44:0) after 9m25s
[05:45:48.000] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:44:0)
[05:45:48.017] Build status: SCHEDULED
[05:45:48.017] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:45:0) after 5m40s
[05:49:55.169] Received PubSub notification, asking Buildbucket for the build status
[05:49:55.183] Build status: STARTED
[05:51:28.071] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:45:0)
[05:51:28.093] Build status: STARTED
[05:51:28.093] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san-msan:8858802428831337056:47:0) after 4m14s
[05:54:06.436] Received PubSub notification, asking Buildbucket for the build status
[05:54:06.452] Build:
{
"id": "8693372366738278097",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-san-msan"
},
"createdBy": "project:pigweed",
"createTime": "2026-01-07T01:57:53.093115040Z",
"startTime": "2026-01-07T05:49:55.019715956Z",
"endTime": "2026-01-07T05:54:06.164414117Z",
"updateTime": "2026-01-07T05:54:06.164414117Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "982d592c1c3f9ec707ed2c8a6c03aa3db5287f86",
"ref": "refs/heads/main"
}
}
}
[05:54:06.452] Invocation finished in 3h56m14.737943881s with status SUCCEEDED