[00:11:49.186] New invocation is queued and will start shortly
[00:11:50.800] Starting the invocation (attempt 1)
[00:11:50.826] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/4a47edd4d9f935198d8d9b8225ae6474fc2fb5e6"
[00:11:50.827] Popped gitiles commit info from properties and tags
[00:11:50.827] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:11:50.827] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:11:50.827] Buildbucket request:
{
"requestId": "8857450145935995824",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-bazel-bthost"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8857450145935995824",
"job": "pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "f683c042d968df0c5096e9ca87aa302a12776cd8"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@f683c042d968df0c5096e9ca87aa302a12776cd8",
"title": "f683c042d968df0c5096e9ca87aa302a12776cd8",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/f683c042d968df0c5096e9ca87aa302a12776cd8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "8501c065c45efd5901cd92e909f16d9f6bf6dc31"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@8501c065c45efd5901cd92e909f16d9f6bf6dc31",
"title": "8501c065c45efd5901cd92e909f16d9f6bf6dc31",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/8501c065c45efd5901cd92e909f16d9f6bf6dc31"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "2d24f8eacec38eb4fbdc6c311e0cedb7939b3fa4"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@2d24f8eacec38eb4fbdc6c311e0cedb7939b3fa4",
"title": "2d24f8eacec38eb4fbdc6c311e0cedb7939b3fa4",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/2d24f8eacec38eb4fbdc6c311e0cedb7939b3fa4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "32b1cd6f04f3ad5497954fb647e44aa27a759523"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@32b1cd6f04f3ad5497954fb647e44aa27a759523",
"title": "32b1cd6f04f3ad5497954fb647e44aa27a759523",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/32b1cd6f04f3ad5497954fb647e44aa27a759523"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "40514056e8850845046f57cdd2867427b2f2bb53"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@40514056e8850845046f57cdd2867427b2f2bb53",
"title": "40514056e8850845046f57cdd2867427b2f2bb53",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/40514056e8850845046f57cdd2867427b2f2bb53"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "4e7bc6c8679bfaa47885ec10d6a1535defdbfdd7"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@4e7bc6c8679bfaa47885ec10d6a1535defdbfdd7",
"title": "4e7bc6c8679bfaa47885ec10d6a1535defdbfdd7",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/4e7bc6c8679bfaa47885ec10d6a1535defdbfdd7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "4a47edd4d9f935198d8d9b8225ae6474fc2fb5e6"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@4a47edd4d9f935198d8d9b8225ae6474fc2fb5e6",
"title": "4a47edd4d9f935198d8d9b8225ae6474fc2fb5e6",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/4a47edd4d9f935198d8d9b8225ae6474fc2fb5e6"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "4a47edd4d9f935198d8d9b8225ae6474fc2fb5e6",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8857450145935995824"
},
{
"key": "scheduler_job_id",
"value": "pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[00:11:51.347] Scheduled build:
{
"id": "8692020083487352833",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-bazel-bthost"
},
"createdBy": "project:pigweed",
"createTime": "2026-01-22T00:11:50.898709205Z",
"updateTime": "2026-01-22T00:11:50.898709205Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "4a47edd4d9f935198d8d9b8225ae6474fc2fb5e6",
"ref": "refs/heads/main"
}
},
"canary": true
}
[00:11:51.347] Task URL: https://cr-buildbucket.appspot.com/build/8692020083487352833
[00:11:51.347] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:2:0) after 2m42s
[00:11:52.239] Received PubSub notification, asking Buildbucket for the build status
[00:11:52.256] Build status: SCHEDULED
[00:14:33.364] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:2:0)
[00:14:33.438] Build status: SCHEDULED
[00:14:33.438] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:4:0) after 9m51s
[00:24:24.494] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:4:0)
[00:24:24.512] Build status: SCHEDULED
[00:24:24.512] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:5:0) after 5m28s
[00:29:52.621] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:5:0)
[00:29:52.641] Build status: SCHEDULED
[00:29:52.641] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:6:0) after 9m2s
[00:38:54.800] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:6:0)
[00:38:55.401] Build status: SCHEDULED
[00:38:55.401] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:7:0) after 8m25s
[00:47:20.464] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:7:0)
[00:47:20.488] Build status: SCHEDULED
[00:47:20.488] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:8:0) after 7m59s
[00:55:19.500] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:8:0)
[00:55:19.522] Build status: SCHEDULED
[00:55:19.522] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:9:0) after 1m3s
[00:56:22.567] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:9:0)
[00:56:22.589] Build status: SCHEDULED
[00:56:22.589] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:10:0) after 2m53s
[00:59:15.604] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:10:0)
[00:59:15.737] Build status: SCHEDULED
[00:59:15.737] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:11:0) after 7m5s
[01:06:20.785] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:11:0)
[01:06:20.802] Build status: SCHEDULED
[01:06:20.802] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:12:0) after 6m13s
[01:12:33.867] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:12:0)
[01:12:33.883] Build status: SCHEDULED
[01:12:33.883] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:13:0) after 6m7s
[01:18:40.899] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:13:0)
[01:18:40.917] Build status: SCHEDULED
[01:18:40.917] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:14:0) after 9m38s
[01:28:18.959] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:14:0)
[01:28:18.975] Build status: SCHEDULED
[01:28:18.975] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:15:0) after 4m21s
[01:32:40.007] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:15:0)
[01:32:40.062] Build status: SCHEDULED
[01:32:40.062] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:16:0) after 8m58s
[01:41:38.076] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:16:0)
[01:41:38.099] Build status: SCHEDULED
[01:41:38.100] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:17:0) after 9m39s
[01:51:17.153] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:17:0)
[01:51:17.172] Build status: SCHEDULED
[01:51:17.172] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:18:0) after 4m12s
[01:55:29.190] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:18:0)
[01:55:29.213] Build status: SCHEDULED
[01:55:29.213] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:19:0) after 7m27s
[02:02:56.491] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:19:0)
[02:02:56.507] Build status: SCHEDULED
[02:02:56.507] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:20:0) after 3m18s
[02:06:14.521] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:20:0)
[02:06:14.545] Build status: SCHEDULED
[02:06:14.545] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:21:0) after 6m51s
[02:13:05.569] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:21:0)
[02:13:05.587] Build status: SCHEDULED
[02:13:05.587] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:22:0) after 8m31s
[02:21:36.606] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:22:0)
[02:21:36.651] Build status: SCHEDULED
[02:21:36.651] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:23:0) after 8m28s
[02:30:04.669] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:23:0)
[02:30:04.686] Build status: SCHEDULED
[02:30:04.686] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:24:0) after 8m0s
[02:38:04.741] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:24:0)
[02:38:04.768] Build status: SCHEDULED
[02:38:04.768] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:25:0) after 7m27s
[02:45:31.830] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:25:0)
[02:45:31.848] Build status: SCHEDULED
[02:45:31.848] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:26:0) after 8m6s
[02:53:37.986] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:26:0)
[02:53:37.999] Build status: SCHEDULED
[02:53:37.999] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:27:0) after 5m50s
[02:59:28.022] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:27:0)
[02:59:28.089] Build status: SCHEDULED
[02:59:28.089] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:28:0) after 2m45s
[03:02:13.081] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:28:0)
[03:02:13.097] Build status: SCHEDULED
[03:02:13.097] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:29:0) after 3m19s
[03:05:32.127] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:29:0)
[03:05:32.144] Build status: SCHEDULED
[03:05:32.145] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:30:0) after 7m40s
[03:13:12.169] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:30:0)
[03:13:12.279] Build status: SCHEDULED
[03:13:12.279] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:31:0) after 8m38s
[03:21:50.497] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:31:0)
[03:21:50.515] Build status: SCHEDULED
[03:21:50.515] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:32:0) after 7m41s
[03:29:31.536] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:32:0)
[03:29:31.552] Build status: SCHEDULED
[03:29:31.552] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:33:0) after 2m21s
[03:31:52.564] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:33:0)
[03:31:52.581] Build status: SCHEDULED
[03:31:52.581] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:34:0) after 4m38s
[03:36:30.733] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:34:0)
[03:36:30.758] Build status: SCHEDULED
[03:36:30.759] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:35:0) after 7m24s
[03:43:54.772] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:35:0)
[03:43:54.790] Build status: SCHEDULED
[03:43:54.790] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:36:0) after 7m22s
[03:51:16.853] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:36:0)
[03:51:16.878] Build status: SCHEDULED
[03:51:16.878] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:37:0) after 9m30s
[03:57:48.232] Received PubSub notification, asking Buildbucket for the build status
[03:57:48.246] Build status: STARTED
[04:00:46.900] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:37:0)
[04:00:46.956] Build status: STARTED
[04:00:46.956] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:39:0) after 9m18s
[04:10:04.970] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:39:0)
[04:10:04.985] Build status: STARTED
[04:10:04.985] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-bazel-bthost:8857450145935995824:40:0) after 4m49s
[04:10:20.744] Received PubSub notification, asking Buildbucket for the build status
[04:10:20.763] Build:
{
"id": "8692020083487352833",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-bazel-bthost"
},
"createdBy": "project:pigweed",
"createTime": "2026-01-22T00:11:50.898709205Z",
"startTime": "2026-01-22T03:57:47.961895019Z",
"endTime": "2026-01-22T04:10:20.294806803Z",
"updateTime": "2026-01-22T04:10:20.294806803Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "4a47edd4d9f935198d8d9b8225ae6474fc2fb5e6",
"ref": "refs/heads/main"
}
},
"canary": true
}
[04:10:20.763] Invocation finished in 3h58m31.583066158s with status SUCCEEDED