[01:06:34.782] New invocation is queued and will start shortly
[01:06:35.849] Starting the invocation (attempt 1)
[01:06:35.872] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/4cefbefa935422f8a394c243682b54c034c9d9b8"
[01:06:35.872] Popped gitiles commit info from properties and tags
[01:06:35.872] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:06:35.872] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:06:35.872] Buildbucket request:
{
"requestId": "8872214006270386080",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-vscode"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8872214006270386080",
"job": "pigweed/pigweed.dev.ci-pigweed-linux-vscode",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "652d07ce5457ca1c6be4d889037a961effbf8e67"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@652d07ce5457ca1c6be4d889037a961effbf8e67",
"title": "652d07ce5457ca1c6be4d889037a961effbf8e67",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/652d07ce5457ca1c6be4d889037a961effbf8e67"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "913b3b28a206cf785d4885f91a19397f1ee4d0f3"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@913b3b28a206cf785d4885f91a19397f1ee4d0f3",
"title": "913b3b28a206cf785d4885f91a19397f1ee4d0f3",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/913b3b28a206cf785d4885f91a19397f1ee4d0f3"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "93ebb0136a8706c7af5d183ced7ba605238bd8fd"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@93ebb0136a8706c7af5d183ced7ba605238bd8fd",
"title": "93ebb0136a8706c7af5d183ced7ba605238bd8fd",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/93ebb0136a8706c7af5d183ced7ba605238bd8fd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "4cefbefa935422f8a394c243682b54c034c9d9b8"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@4cefbefa935422f8a394c243682b54c034c9d9b8",
"title": "4cefbefa935422f8a394c243682b54c034c9d9b8",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/4cefbefa935422f8a394c243682b54c034c9d9b8"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "4cefbefa935422f8a394c243682b54c034c9d9b8",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8872214006270386080"
},
{
"key": "scheduler_job_id",
"value": "pigweed/pigweed.dev.ci-pigweed-linux-vscode"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[01:06:36.352] Scheduled build:
{
"id": "8706783944375047345",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-vscode"
},
"createdBy": "project:pigweed",
"createTime": "2025-08-12T01:06:35.959966253Z",
"updateTime": "2025-08-12T01:06:35.959966253Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "4cefbefa935422f8a394c243682b54c034c9d9b8",
"ref": "refs/heads/main"
}
}
}
[01:06:36.352] Task URL: https://cr-buildbucket.appspot.com/build/8706783944375047345
[01:06:36.352] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:2:0) after 3m2s
[01:06:37.130] Received PubSub notification, asking Buildbucket for the build status
[01:06:37.152] Build status: SCHEDULED
[01:09:38.343] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:2:0)
[01:09:38.360] Build status: SCHEDULED
[01:09:38.360] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:4:0) after 3m0s
[01:12:38.430] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:4:0)
[01:12:38.477] Build status: SCHEDULED
[01:12:38.477] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:5:0) after 7m30s
[01:20:08.647] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:5:0)
[01:20:08.682] Build status: SCHEDULED
[01:20:08.682] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:6:0) after 1m54s
[01:22:02.746] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:6:0)
[01:22:02.770] Build status: SCHEDULED
[01:22:02.770] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:7:0) after 2m24s
[01:24:26.790] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:7:0)
[01:24:26.814] Build status: SCHEDULED
[01:24:26.814] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:8:0) after 5m21s
[01:29:47.889] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:8:0)
[01:29:47.922] Build status: SCHEDULED
[01:29:47.922] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:9:0) after 8m41s
[01:38:28.947] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:9:0)
[01:38:29.006] Build status: SCHEDULED
[01:38:29.006] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:10:0) after 2m45s
[01:41:14.070] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:10:0)
[01:41:14.127] Build status: SCHEDULED
[01:41:14.127] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:11:0) after 8m38s
[01:49:52.176] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:11:0)
[01:49:52.233] Build status: SCHEDULED
[01:49:52.233] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:12:0) after 3m12s
[01:53:04.248] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:12:0)
[01:53:04.408] Build status: SCHEDULED
[01:53:04.409] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:13:0) after 9m59s
[02:03:03.478] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:13:0)
[02:03:03.501] Build status: SCHEDULED
[02:03:03.501] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:14:0) after 9m20s
[02:12:23.553] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:14:0)
[02:12:23.568] Build status: SCHEDULED
[02:12:23.568] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:15:0) after 9m37s
[02:22:00.586] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:15:0)
[02:22:00.628] Build status: SCHEDULED
[02:22:00.628] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:16:0) after 3m33s
[02:25:33.644] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:16:0)
[02:25:33.661] Build status: SCHEDULED
[02:25:33.661] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:17:0) after 2m32s
[02:28:05.674] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:17:0)
[02:28:05.709] Build status: SCHEDULED
[02:28:05.709] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:18:0) after 8m32s
[02:36:37.728] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:18:0)
[02:36:37.745] Build status: SCHEDULED
[02:36:37.745] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:19:0) after 8m50s
[02:45:27.871] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:19:0)
[02:45:27.891] Build status: SCHEDULED
[02:45:27.891] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:20:0) after 5m44s
[02:51:13.341] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:20:0)
[02:51:13.402] Build status: SCHEDULED
[02:51:13.402] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:21:0) after 3m31s
[02:54:44.414] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:21:0)
[02:54:44.441] Build status: SCHEDULED
[02:54:44.441] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:22:0) after 2m47s
[02:57:31.572] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:22:0)
[02:57:31.593] Build status: SCHEDULED
[02:57:31.593] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:23:0) after 3m3s
[03:00:34.659] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:23:0)
[03:00:34.677] Build status: SCHEDULED
[03:00:34.677] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:24:0) after 3m6s
[03:03:40.689] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:24:0)
[03:03:40.723] Build status: SCHEDULED
[03:03:40.723] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:25:0) after 2m25s
[03:06:05.738] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:25:0)
[03:06:05.756] Build status: SCHEDULED
[03:06:05.756] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:26:0) after 2m25s
[03:08:30.770] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:26:0)
[03:08:30.805] Build status: SCHEDULED
[03:08:30.805] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:27:0) after 6m3s
[03:14:33.921] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:27:0)
[03:14:33.937] Build status: SCHEDULED
[03:14:33.937] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:28:0) after 4m44s
[03:19:17.980] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:28:0)
[03:19:18.047] Build status: SCHEDULED
[03:19:18.047] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:29:0) after 1m55s
[03:21:13.405] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:29:0)
[03:21:13.425] Build status: SCHEDULED
[03:21:13.425] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:30:0) after 1m37s
[03:22:51.106] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:30:0)
[03:22:51.126] Build status: SCHEDULED
[03:22:51.126] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:31:0) after 7m36s
[03:30:27.141] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:31:0)
[03:30:27.162] Build status: SCHEDULED
[03:30:27.162] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:32:0) after 5m11s
[03:35:38.177] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:32:0)
[03:35:38.200] Build status: SCHEDULED
[03:35:38.200] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:33:0) after 3m50s
[03:39:28.279] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:33:0)
[03:39:28.306] Build status: SCHEDULED
[03:39:28.306] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:34:0) after 5m1s
[03:44:29.352] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:34:0)
[03:44:29.413] Build status: SCHEDULED
[03:44:29.413] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:35:0) after 4m32s
[03:49:01.426] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:35:0)
[03:49:01.448] Build status: SCHEDULED
[03:49:01.448] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:36:0) after 7m57s
[03:56:58.425] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:36:0)
[03:56:58.482] Build status: SCHEDULED
[03:56:58.482] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:37:0) after 1m27s
[03:58:25.498] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:37:0)
[03:58:25.517] Build status: SCHEDULED
[03:58:25.517] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:38:0) after 5m31s
[04:03:56.534] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:38:0)
[04:03:56.570] Build status: SCHEDULED
[04:03:56.570] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:39:0) after 5m57s
[04:09:53.586] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:39:0)
[04:09:53.601] Build status: SCHEDULED
[04:09:53.601] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:40:0) after 2m47s
[04:12:40.616] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:40:0)
[04:12:40.680] Build status: SCHEDULED
[04:12:40.680] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:41:0) after 6m31s
[04:19:11.694] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:41:0)
[04:19:11.713] Build status: SCHEDULED
[04:19:11.713] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:42:0) after 7m1s
[04:26:12.752] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:42:0)
[04:26:12.766] Build status: SCHEDULED
[04:26:12.766] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:43:0) after 7m43s
[04:33:55.785] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:43:0)
[04:33:55.900] Build status: SCHEDULED
[04:33:55.900] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:44:0) after 5m9s
[04:38:16.468] Received PubSub notification, asking Buildbucket for the build status
[04:38:16.484] Build status: STARTED
[04:39:04.916] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:44:0)
[04:39:04.976] Build status: STARTED
[04:39:04.976] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-vscode:8872214006270386080:46:0) after 7m27s
[04:42:01.564] Received PubSub notification, asking Buildbucket for the build status
[04:42:01.582] Build:
{
"id": "8706783944375047345",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-vscode"
},
"createdBy": "project:pigweed",
"createTime": "2025-08-12T01:06:35.959966253Z",
"startTime": "2025-08-12T04:38:16.268889989Z",
"endTime": "2025-08-12T04:41:57.241603955Z",
"updateTime": "2025-08-12T04:41:57.241603955Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "4cefbefa935422f8a394c243682b54c034c9d9b8",
"ref": "refs/heads/main"
}
}
}
[04:42:01.582] Invocation finished in 3h35m26.814119077s with status FAILED