[18:50:34.182] New invocation is queued and will start shortly
[18:50:35.262] Starting the invocation (attempt 1)
[18:50:35.288] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/1dc9a789f52aad1f0e738a1cfd5993e2272c38d2"
[18:50:35.288] Popped gitiles commit info from properties and tags
[18:50:35.288] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:50:35.288] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:50:35.288] Buildbucket request:
{
"requestId": "8901138095055467520",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-sapphire"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8901138095055467520",
"job": "pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "d647f5e5df848f5bd6a2595386b2ee5b02d78d78"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@d647f5e5df848f5bd6a2595386b2ee5b02d78d78",
"title": "d647f5e5df848f5bd6a2595386b2ee5b02d78d78",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/d647f5e5df848f5bd6a2595386b2ee5b02d78d78"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "ce337e5ea22fe0c9fc0a88d14b1afde2ca3f1818"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@ce337e5ea22fe0c9fc0a88d14b1afde2ca3f1818",
"title": "ce337e5ea22fe0c9fc0a88d14b1afde2ca3f1818",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/ce337e5ea22fe0c9fc0a88d14b1afde2ca3f1818"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "34513322e16370670a4816e7affcf56cd9b50797"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@34513322e16370670a4816e7affcf56cd9b50797",
"title": "34513322e16370670a4816e7affcf56cd9b50797",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/34513322e16370670a4816e7affcf56cd9b50797"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "f1070484e7d2f4e429332c6d8520a3676b8cb965"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@f1070484e7d2f4e429332c6d8520a3676b8cb965",
"title": "f1070484e7d2f4e429332c6d8520a3676b8cb965",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/f1070484e7d2f4e429332c6d8520a3676b8cb965"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "0e5c33667e0cf7a129497860cbe4150ad8d62484"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@0e5c33667e0cf7a129497860cbe4150ad8d62484",
"title": "0e5c33667e0cf7a129497860cbe4150ad8d62484",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/0e5c33667e0cf7a129497860cbe4150ad8d62484"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "1dc9a789f52aad1f0e738a1cfd5993e2272c38d2"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@1dc9a789f52aad1f0e738a1cfd5993e2272c38d2",
"title": "1dc9a789f52aad1f0e738a1cfd5993e2272c38d2",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/1dc9a789f52aad1f0e738a1cfd5993e2272c38d2"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "1dc9a789f52aad1f0e738a1cfd5993e2272c38d2",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8901138095055467520"
},
{
"key": "scheduler_job_id",
"value": "pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[18:50:35.847] Scheduled build:
{
"id": "8735708033169945425",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-sapphire"
},
"createdBy": "project:pigweed",
"createTime": "2024-09-26T18:50:35.350648677Z",
"updateTime": "2024-09-26T18:50:35.350648677Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "1dc9a789f52aad1f0e738a1cfd5993e2272c38d2",
"ref": "refs/heads/main"
}
}
}
[18:50:35.847] Task URL: https://cr-buildbucket.appspot.com/build/8735708033169945425
[18:50:35.847] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:2:0) after 3m34s
[18:50:36.685] Received PubSub notification, asking Buildbucket for the build status
[18:50:36.714] Build status: SCHEDULED
[18:54:09.876] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:2:0)
[18:54:09.899] Build status: SCHEDULED
[18:54:09.900] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:4:0) after 3m8s
[18:57:17.896] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:4:0)
[18:57:17.932] Build status: SCHEDULED
[18:57:17.932] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:5:0) after 6m49s
[19:04:07.005] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:5:0)
[19:04:07.027] Build status: SCHEDULED
[19:04:07.027] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:6:0) after 6m48s
[19:10:55.103] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:6:0)
[19:10:55.123] Build status: SCHEDULED
[19:10:55.123] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:7:0) after 5m39s
[19:16:34.174] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:7:0)
[19:16:34.193] Build status: SCHEDULED
[19:16:34.193] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:8:0) after 4m8s
[19:20:42.208] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:8:0)
[19:20:42.342] Build status: SCHEDULED
[19:20:42.342] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:9:0) after 2m19s
[19:23:01.387] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:9:0)
[19:23:01.407] Build status: SCHEDULED
[19:23:01.407] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:10:0) after 2m48s
[19:25:49.432] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:10:0)
[19:25:49.471] Build status: SCHEDULED
[19:25:49.471] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:11:0) after 1m38s
[19:27:27.557] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:11:0)
[19:27:27.588] Build status: SCHEDULED
[19:27:27.588] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:12:0) after 4m13s
[19:31:40.606] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:12:0)
[19:31:40.623] Build status: SCHEDULED
[19:31:40.623] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:13:0) after 1m55s
[19:33:35.663] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:13:0)
[19:33:35.685] Build status: SCHEDULED
[19:33:35.686] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:14:0) after 1m6s
[19:34:41.804] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:14:0)
[19:34:41.828] Build status: SCHEDULED
[19:34:41.828] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:15:0) after 1m29s
[19:36:10.845] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:15:0)
[19:36:10.873] Build status: SCHEDULED
[19:36:10.873] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:16:0) after 1m56s
[19:38:06.902] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:16:0)
[19:38:06.918] Build status: SCHEDULED
[19:38:06.918] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:17:0) after 5m17s
[19:43:23.958] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:17:0)
[19:43:23.985] Build status: SCHEDULED
[19:43:23.985] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:18:0) after 9m53s
[19:53:17.093] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:18:0)
[19:53:17.113] Build status: SCHEDULED
[19:53:17.113] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:19:0) after 1m37s
[19:54:54.141] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:19:0)
[19:54:54.283] Build status: SCHEDULED
[19:54:54.283] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:20:0) after 4m22s
[19:59:16.299] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:20:0)
[19:59:16.319] Build status: SCHEDULED
[19:59:16.319] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:21:0) after 6m16s
[20:05:32.342] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:21:0)
[20:05:32.487] Build status: SCHEDULED
[20:05:32.487] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:22:0) after 6m48s
[20:12:20.559] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:22:0)
[20:12:20.594] Build status: SCHEDULED
[20:12:20.594] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:23:0) after 9m9s
[20:21:29.621] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:23:0)
[20:21:29.646] Build status: SCHEDULED
[20:21:29.646] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:24:0) after 8m35s
[20:30:04.692] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:24:0)
[20:30:04.736] Build status: SCHEDULED
[20:30:04.736] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:25:0) after 6m3s
[20:36:07.810] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:25:0)
[20:36:07.843] Build status: SCHEDULED
[20:36:07.843] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:26:0) after 1m41s
[20:37:49.037] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:26:0)
[20:37:49.055] Build status: SCHEDULED
[20:37:49.055] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:27:0) after 3m5s
[20:40:54.080] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:27:0)
[20:40:54.102] Build status: SCHEDULED
[20:40:54.102] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:28:0) after 5m9s
[20:46:03.081] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:28:0)
[20:46:03.118] Build status: SCHEDULED
[20:46:03.118] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:29:0) after 3m14s
[20:49:17.228] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:29:0)
[20:49:17.247] Build status: SCHEDULED
[20:49:17.248] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:30:0) after 5m27s
[20:52:24.861] Received PubSub notification, asking Buildbucket for the build status
[20:52:24.880] Build status: STARTED
[20:54:44.282] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:30:0)
[20:54:44.300] Build status: STARTED
[20:54:44.300] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:32:0) after 5m36s
[21:00:20.336] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:32:0)
[21:00:20.429] Build status: STARTED
[21:00:20.429] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:33:0) after 4m2s
[21:04:22.447] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:33:0)
[21:04:22.467] Build status: STARTED
[21:04:22.467] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:34:0) after 4m56s
[21:09:18.533] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:34:0)
[21:09:18.560] Build status: STARTED
[21:09:18.560] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-sapphire:8901138095055467520:35:0) after 6m56s
[21:14:21.188] Received PubSub notification, asking Buildbucket for the build status
[21:14:21.213] Build:
{
"id": "8735708033169945425",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-sapphire"
},
"createdBy": "project:pigweed",
"createTime": "2024-09-26T18:50:35.350648677Z",
"startTime": "2024-09-26T20:52:24.582385501Z",
"endTime": "2024-09-26T21:14:20.945685418Z",
"updateTime": "2024-09-26T21:14:20.945685418Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "1dc9a789f52aad1f0e738a1cfd5993e2272c38d2",
"ref": "refs/heads/main"
}
}
}
[21:14:21.213] Invocation finished in 2h23m47.044379331s with status SUCCEEDED