[21:47:52.959] New invocation is queued and will start shortly
[21:47:54.061] Starting the invocation (attempt 1)
[21:47:54.089] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/8ca88966f31ba62d5097e7fc3b390be40545f0a9"
[21:47:54.089] Popped gitiles commit info from properties and tags
[21:47:54.089] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[21:47:54.089] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[21:47:54.090] Buildbucket request:
{
"requestId": "8856915619883856432",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-san"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8856915619883856432",
"job": "pigweed/pigweed.dev.ci-pigweed-linux-gn-san",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "71163020c080f08080aa2ad4bebc819ecdb239bc"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@71163020c080f08080aa2ad4bebc819ecdb239bc",
"title": "71163020c080f08080aa2ad4bebc819ecdb239bc",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/71163020c080f08080aa2ad4bebc819ecdb239bc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "f543f9f65f235b925114757d89ec349dca1f0b2a"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@f543f9f65f235b925114757d89ec349dca1f0b2a",
"title": "f543f9f65f235b925114757d89ec349dca1f0b2a",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/f543f9f65f235b925114757d89ec349dca1f0b2a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "1d5ee759f99bb3d0a218a8c077fb08b129284ab3"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@1d5ee759f99bb3d0a218a8c077fb08b129284ab3",
"title": "1d5ee759f99bb3d0a218a8c077fb08b129284ab3",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/1d5ee759f99bb3d0a218a8c077fb08b129284ab3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "8ca88966f31ba62d5097e7fc3b390be40545f0a9"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@8ca88966f31ba62d5097e7fc3b390be40545f0a9",
"title": "8ca88966f31ba62d5097e7fc3b390be40545f0a9",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/8ca88966f31ba62d5097e7fc3b390be40545f0a9"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "8ca88966f31ba62d5097e7fc3b390be40545f0a9",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8856915619883856432"
},
{
"key": "scheduler_job_id",
"value": "pigweed/pigweed.dev.ci-pigweed-linux-gn-san"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[21:47:54.440] Scheduled build:
{
"id": "8691485557988561201",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-san"
},
"createdBy": "project:pigweed",
"createTime": "2026-01-27T21:47:54.138035279Z",
"updateTime": "2026-01-27T21:47:54.138035279Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "8ca88966f31ba62d5097e7fc3b390be40545f0a9",
"ref": "refs/heads/main"
}
}
}
[21:47:54.441] Task URL: https://cr-buildbucket.appspot.com/build/8691485557988561201
[21:47:54.441] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:2:0) after 2m50s
[21:47:55.406] Received PubSub notification, asking Buildbucket for the build status
[21:47:55.430] Build status: SCHEDULED
[21:50:44.557] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:2:0)
[21:50:44.581] Build status: SCHEDULED
[21:50:44.581] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:4:0) after 1m51s
[21:52:35.649] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:4:0)
[21:52:35.672] Build status: SCHEDULED
[21:52:35.672] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:5:0) after 7m46s
[22:00:21.689] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:5:0)
[22:00:21.749] Build status: SCHEDULED
[22:00:21.749] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:6:0) after 9m55s
[22:10:16.768] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:6:0)
[22:10:16.795] Build status: SCHEDULED
[22:10:16.795] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:7:0) after 6m42s
[22:16:58.831] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:7:0)
[22:16:58.908] Build status: SCHEDULED
[22:16:58.908] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:8:0) after 9m23s
[22:26:21.902] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:8:0)
[22:26:21.921] Build status: SCHEDULED
[22:26:21.921] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:9:0) after 9m38s
[22:35:59.970] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:9:0)
[22:35:59.994] Build status: SCHEDULED
[22:35:59.994] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:10:0) after 1m13s
[22:37:13.029] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:10:0)
[22:37:13.046] Build status: SCHEDULED
[22:37:13.046] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:11:0) after 5m3s
[22:42:16.057] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:11:0)
[22:42:16.075] Build status: SCHEDULED
[22:42:16.075] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:12:0) after 8m8s
[22:50:24.117] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:12:0)
[22:50:24.145] Build status: SCHEDULED
[22:50:24.146] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:13:0) after 7m16s
[22:57:40.174] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:13:0)
[22:57:40.192] Build status: SCHEDULED
[22:57:40.193] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:14:0) after 6m32s
[23:04:12.208] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:14:0)
[23:04:12.227] Build status: SCHEDULED
[23:04:12.227] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:15:0) after 9m13s
[23:13:25.256] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:15:0)
[23:13:25.272] Build status: SCHEDULED
[23:13:25.272] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:16:0) after 7m42s
[23:21:07.386] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:16:0)
[23:21:07.616] Build status: SCHEDULED
[23:21:07.616] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:17:0) after 6m55s
[23:28:02.753] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:17:0)
[23:28:02.771] Build status: SCHEDULED
[23:28:02.771] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:18:0) after 4m21s
[23:32:23.823] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:18:0)
[23:32:23.839] Build status: SCHEDULED
[23:32:23.839] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:19:0) after 7m28s
[23:39:51.853] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:19:0)
[23:39:51.889] Build status: SCHEDULED
[23:39:51.889] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:20:0) after 6m41s
[23:46:32.951] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:20:0)
[23:46:33.328] Build status: SCHEDULED
[23:46:33.328] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:21:0) after 7m44s
[23:54:17.351] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:21:0)
[23:54:17.376] Build status: SCHEDULED
[23:54:17.376] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:22:0) after 4m53s
[23:59:10.400] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:22:0)
[23:59:10.422] Build status: SCHEDULED
[23:59:10.422] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:23:0) after 6m5s
[00:05:15.526] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:23:0)
[00:05:15.543] Build status: SCHEDULED
[00:05:15.543] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:24:0) after 9m23s
[00:14:38.580] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:24:0)
[00:14:38.600] Build status: SCHEDULED
[00:14:38.601] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:25:0) after 1m39s
[00:16:17.658] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:25:0)
[00:16:17.703] Build status: SCHEDULED
[00:16:17.703] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:26:0) after 2m1s
[00:18:18.770] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:26:0)
[00:18:18.801] Build status: SCHEDULED
[00:18:18.801] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:27:0) after 8m21s
[00:26:39.848] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:27:0)
[00:26:39.890] Build status: SCHEDULED
[00:26:39.891] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:28:0) after 7m28s
[00:34:07.907] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:28:0)
[00:34:07.924] Build status: SCHEDULED
[00:34:07.924] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:29:0) after 5m5s
[00:39:12.940] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:29:0)
[00:39:12.962] Build status: SCHEDULED
[00:39:12.962] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:30:0) after 9m29s
[00:48:42.023] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:30:0)
[00:48:42.100] Build status: SCHEDULED
[00:48:42.100] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:31:0) after 3m14s
[00:51:56.250] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:31:0)
[00:51:56.266] Build status: SCHEDULED
[00:51:56.266] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:32:0) after 8m50s
[01:00:46.311] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:32:0)
[01:00:46.343] Build status: SCHEDULED
[01:00:46.343] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:33:0) after 3m47s
[01:04:33.551] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:33:0)
[01:04:33.567] Build status: SCHEDULED
[01:04:33.567] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:34:0) after 8m57s
[01:13:30.588] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:34:0)
[01:13:30.602] Build status: SCHEDULED
[01:13:30.602] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:35:0) after 8m32s
[01:22:02.650] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:35:0)
[01:22:02.670] Build status: SCHEDULED
[01:22:02.670] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:36:0) after 3m45s
[01:23:25.704] Received PubSub notification, asking Buildbucket for the build status
[01:23:25.717] Build status: STARTED
[01:25:47.684] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:36:0)
[01:25:47.764] Build status: STARTED
[01:25:47.764] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:38:0) after 2m14s
[01:28:01.779] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:38:0)
[01:28:01.798] Build status: STARTED
[01:28:01.798] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:39:0) after 7m54s
[01:35:55.864] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:39:0)
[01:35:55.878] Build status: STARTED
[01:35:55.878] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:40:0) after 4m32s
[01:40:27.856] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:40:0)
[01:40:27.882] Build status: STARTED
[01:40:27.882] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8856915619883856432:41:0) after 8m37s
[01:45:52.132] Received PubSub notification, asking Buildbucket for the build status
[01:45:52.154] Build:
{
"id": "8691485557988561201",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-san"
},
"createdBy": "project:pigweed",
"createTime": "2026-01-27T21:47:54.138035279Z",
"startTime": "2026-01-28T01:23:25.216675852Z",
"endTime": "2026-01-28T01:45:33.800856118Z",
"updateTime": "2026-01-28T01:45:33.800856118Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "8ca88966f31ba62d5097e7fc3b390be40545f0a9",
"ref": "refs/heads/main"
}
}
}
[01:45:52.154] Invocation finished in 3h57m59.206388771s with status SUCCEEDED