[01:55:27.269] New invocation is queued and will start shortly
[01:55:28.381] Starting the invocation (attempt 1)
[01:55:28.402] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/7ba17036fa320d39270578c235250540f03a21e3"
[01:55:28.402] Popped gitiles commit info from properties and tags
[01:55:28.402] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:55:28.403] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:55:28.403] Buildbucket request:
{
"requestId": "8865053770988335744",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-san"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8865053770988335744",
"job": "pigweed/pigweed.dev.ci-pigweed-linux-gn-san",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "7d26c70c078481539cc6d23829d59e6b9d2a1bd1"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@7d26c70c078481539cc6d23829d59e6b9d2a1bd1",
"title": "7d26c70c078481539cc6d23829d59e6b9d2a1bd1",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/7d26c70c078481539cc6d23829d59e6b9d2a1bd1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "0deb8fc768bb57d62025ce0fbc5914785846b09c"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@0deb8fc768bb57d62025ce0fbc5914785846b09c",
"title": "0deb8fc768bb57d62025ce0fbc5914785846b09c",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/0deb8fc768bb57d62025ce0fbc5914785846b09c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "39729c6fed08fd003801d605c8645f0aed91dac2"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@39729c6fed08fd003801d605c8645f0aed91dac2",
"title": "39729c6fed08fd003801d605c8645f0aed91dac2",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/39729c6fed08fd003801d605c8645f0aed91dac2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "4db0933499499e3da0b8c8bb8d92abf7074ea6f8"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@4db0933499499e3da0b8c8bb8d92abf7074ea6f8",
"title": "4db0933499499e3da0b8c8bb8d92abf7074ea6f8",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/4db0933499499e3da0b8c8bb8d92abf7074ea6f8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "30d9b4a00975a375dcf00673611f3d8c52dc4b3a"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@30d9b4a00975a375dcf00673611f3d8c52dc4b3a",
"title": "30d9b4a00975a375dcf00673611f3d8c52dc4b3a",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/30d9b4a00975a375dcf00673611f3d8c52dc4b3a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "2e8738caec2fb618d79df76d855049e25ba003a2"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@2e8738caec2fb618d79df76d855049e25ba003a2",
"title": "2e8738caec2fb618d79df76d855049e25ba003a2",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/2e8738caec2fb618d79df76d855049e25ba003a2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "7ba17036fa320d39270578c235250540f03a21e3"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@7ba17036fa320d39270578c235250540f03a21e3",
"title": "7ba17036fa320d39270578c235250540f03a21e3",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/7ba17036fa320d39270578c235250540f03a21e3"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "7ba17036fa320d39270578c235250540f03a21e3",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8865053770988335744"
},
{
"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"
}
}
[01:55:28.832] Scheduled build:
{
"id": "8699623709046406881",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-san"
},
"createdBy": "project:pigweed",
"createTime": "2025-10-30T01:55:28.491797970Z",
"updateTime": "2025-10-30T01:55:28.491797970Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "7ba17036fa320d39270578c235250540f03a21e3",
"ref": "refs/heads/main"
}
}
}
[01:55:28.832] Task URL: https://cr-buildbucket.appspot.com/build/8699623709046406881
[01:55:28.832] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:2:0) after 3m59s
[01:55:29.240] Received PubSub notification, asking Buildbucket for the build status
[01:55:29.254] Build status: SCHEDULED
[01:59:27.871] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:2:0)
[01:59:27.886] Build status: SCHEDULED
[01:59:27.886] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:4:0) after 2m7s
[02:01:34.850] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:4:0)
[02:01:34.911] Build status: SCHEDULED
[02:01:34.911] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:5:0) after 5m11s
[02:06:45.973] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:5:0)
[02:06:46.187] Build status: SCHEDULED
[02:06:46.187] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:6:0) after 1m36s
[02:08:22.199] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:6:0)
[02:08:22.255] Build status: SCHEDULED
[02:08:22.255] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:7:0) after 7m59s
[02:16:21.285] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:7:0)
[02:16:21.306] Build status: SCHEDULED
[02:16:21.306] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:8:0) after 8m55s
[02:25:16.359] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:8:0)
[02:25:16.386] Build status: SCHEDULED
[02:25:16.386] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:9:0) after 8m19s
[02:33:35.463] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:9:0)
[02:33:35.487] Build status: SCHEDULED
[02:33:35.487] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:10:0) after 8m13s
[02:41:48.502] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:10:0)
[02:41:48.522] Build status: SCHEDULED
[02:41:48.522] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:11:0) after 1m17s
[02:43:05.617] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:11:0)
[02:43:05.633] Build status: SCHEDULED
[02:43:05.633] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:12:0) after 3m54s
[02:46:59.601] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:12:0)
[02:46:59.675] Build status: SCHEDULED
[02:46:59.675] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:13:0) after 5m11s
[02:52:10.791] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:13:0)
[02:52:10.808] Build status: SCHEDULED
[02:52:10.808] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:14:0) after 9m48s
[03:01:58.851] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:14:0)
[03:01:58.907] Build status: SCHEDULED
[03:01:58.907] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:15:0) after 9m11s
[03:11:10.068] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:15:0)
[03:11:10.086] Build status: SCHEDULED
[03:11:10.086] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:16:0) after 3m7s
[03:14:17.261] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:16:0)
[03:14:17.277] Build status: SCHEDULED
[03:14:17.277] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:17:0) after 8m50s
[03:23:07.338] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:17:0)
[03:23:07.355] Build status: SCHEDULED
[03:23:07.355] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:18:0) after 7m3s
[03:30:10.376] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:18:0)
[03:30:10.392] Build status: SCHEDULED
[03:30:10.392] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:19:0) after 6m6s
[03:36:16.409] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:19:0)
[03:36:16.670] Build status: SCHEDULED
[03:36:16.670] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:20:0) after 5m1s
[03:41:17.685] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:20:0)
[03:41:17.731] Build status: SCHEDULED
[03:41:17.731] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:21:0) after 2m17s
[03:43:34.797] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:21:0)
[03:43:34.811] Build status: SCHEDULED
[03:43:34.811] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:22:0) after 4m34s
[03:48:08.946] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:22:0)
[03:48:08.977] Build status: SCHEDULED
[03:48:08.977] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:23:0) after 3m3s
[03:51:12.077] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:23:0)
[03:51:12.105] Build status: SCHEDULED
[03:51:12.105] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:24:0) after 7m6s
[03:58:18.119] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:24:0)
[03:58:18.141] Build status: SCHEDULED
[03:58:18.141] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:25:0) after 6m53s
[04:05:11.157] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:25:0)
[04:05:11.214] Build status: SCHEDULED
[04:05:11.214] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:26:0) after 6m17s
[04:11:28.238] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:26:0)
[04:11:28.259] Build status: SCHEDULED
[04:11:28.259] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:27:0) after 5m42s
[04:17:10.348] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:27:0)
[04:17:10.364] Build status: SCHEDULED
[04:17:10.364] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:28:0) after 9m26s
[04:26:36.448] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:28:0)
[04:26:36.509] Build status: SCHEDULED
[04:26:36.509] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:29:0) after 9m51s
[04:36:27.590] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:29:0)
[04:36:27.604] Build status: SCHEDULED
[04:36:27.604] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:30:0) after 4m23s
[04:40:50.624] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:30:0)
[04:40:50.658] Build status: SCHEDULED
[04:40:50.658] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:31:0) after 7m55s
[04:48:45.675] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:31:0)
[04:48:45.692] Build status: SCHEDULED
[04:48:45.692] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:32:0) after 9m27s
[04:58:12.706] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:32:0)
[04:58:12.724] Build status: SCHEDULED
[04:58:12.724] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:33:0) after 2m32s
[05:00:44.739] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:33:0)
[05:00:45.064] Build status: SCHEDULED
[05:00:45.064] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:34:0) after 7m53s
[05:08:38.125] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:34:0)
[05:08:38.143] Build status: SCHEDULED
[05:08:38.143] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:35:0) after 1m15s
[05:09:53.262] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:35:0)
[05:09:53.307] Build status: SCHEDULED
[05:09:53.307] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:36:0) after 4m11s
[05:14:04.321] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:36:0)
[05:14:04.335] Build status: SCHEDULED
[05:14:04.335] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:37:0) after 9m15s
[05:21:56.687] Received PubSub notification, asking Buildbucket for the build status
[05:21:56.712] Build status: STARTED
[05:23:19.350] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:37:0)
[05:23:19.422] Build status: STARTED
[05:23:19.422] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:39:0) after 6m2s
[05:29:21.440] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:39:0)
[05:29:21.463] Build status: STARTED
[05:29:21.464] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:40:0) after 7m2s
[05:36:23.526] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:40:0)
[05:36:23.593] Build status: STARTED
[05:36:23.593] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8865053770988335744:41:0) after 5m2s
[05:40:41.929] Received PubSub notification, asking Buildbucket for the build status
[05:40:42.027] Build:
{
"id": "8699623709046406881",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-san"
},
"createdBy": "project:pigweed",
"createTime": "2025-10-30T01:55:28.491797970Z",
"startTime": "2025-10-30T05:21:56.548123991Z",
"endTime": "2025-10-30T05:40:41.664435040Z",
"updateTime": "2025-10-30T05:40:41.664435040Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "7ba17036fa320d39270578c235250540f03a21e3",
"ref": "refs/heads/main"
}
}
}
[05:40:42.027] Invocation finished in 3h45m14.771459836s with status SUCCEEDED