[00:10:46.519] New invocation is queued and will start shortly
[00:10:47.693] Starting the invocation (attempt 1)
[00:10:47.718] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/8bae44afd85f301beab18bae12b56dbb1ac92d15"
[00:10:47.718] Popped gitiles commit info from properties and tags
[00:10:47.718] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:10:47.718] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:10:47.718] Buildbucket request:
{
"requestId": "8878921692683453200",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-san"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8878921692683453200",
"job": "pigweed/pigweed.dev.ci-pigweed-linux-gn-san",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "ea85eb93c2a2be1eb601396154bd2c97fbd9b288"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@ea85eb93c2a2be1eb601396154bd2c97fbd9b288",
"title": "ea85eb93c2a2be1eb601396154bd2c97fbd9b288",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/ea85eb93c2a2be1eb601396154bd2c97fbd9b288"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "4869e6f1add6c33cfac4de965cd04c2189164850"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@4869e6f1add6c33cfac4de965cd04c2189164850",
"title": "4869e6f1add6c33cfac4de965cd04c2189164850",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/4869e6f1add6c33cfac4de965cd04c2189164850"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "d79101e65a28fe2193593614f100173af9d41236"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@d79101e65a28fe2193593614f100173af9d41236",
"title": "d79101e65a28fe2193593614f100173af9d41236",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/d79101e65a28fe2193593614f100173af9d41236"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "775e4ca29f6caea16e5f42bba90a12b9ddbf31f5"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@775e4ca29f6caea16e5f42bba90a12b9ddbf31f5",
"title": "775e4ca29f6caea16e5f42bba90a12b9ddbf31f5",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/775e4ca29f6caea16e5f42bba90a12b9ddbf31f5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "f40c5bf320654ede55f76b474cf66d57a2d74812"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@f40c5bf320654ede55f76b474cf66d57a2d74812",
"title": "f40c5bf320654ede55f76b474cf66d57a2d74812",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/f40c5bf320654ede55f76b474cf66d57a2d74812"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "8bae44afd85f301beab18bae12b56dbb1ac92d15"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@8bae44afd85f301beab18bae12b56dbb1ac92d15",
"title": "8bae44afd85f301beab18bae12b56dbb1ac92d15",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/8bae44afd85f301beab18bae12b56dbb1ac92d15"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "8bae44afd85f301beab18bae12b56dbb1ac92d15",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8878921692683453200"
},
{
"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"
}
}
[00:10:48.058] Scheduled build:
{
"id": "8713491630719777009",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-san"
},
"createdBy": "project:pigweed",
"createTime": "2025-05-30T00:10:47.769542827Z",
"updateTime": "2025-05-30T00:10:47.769542827Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "8bae44afd85f301beab18bae12b56dbb1ac92d15",
"ref": "refs/heads/main"
}
},
"canary": true
}
[00:10:48.058] Task URL: https://cr-buildbucket.appspot.com/build/8713491630719777009
[00:10:48.058] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:2:0) after 6m21s
[00:10:49.043] Received PubSub notification, asking Buildbucket for the build status
[00:10:49.070] Build status: SCHEDULED
[00:17:09.312] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:2:0)
[00:17:09.373] Build status: SCHEDULED
[00:17:09.373] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:4:0) after 7m57s
[00:25:06.545] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:4:0)
[00:25:06.570] Build status: SCHEDULED
[00:25:06.570] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:5:0) after 4m6s
[00:29:12.693] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:5:0)
[00:29:12.716] Build status: SCHEDULED
[00:29:12.716] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:6:0) after 5m0s
[00:34:12.783] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:6:0)
[00:34:12.854] Build status: SCHEDULED
[00:34:12.854] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:7:0) after 3m21s
[00:37:33.829] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:7:0)
[00:37:33.901] Build status: SCHEDULED
[00:37:33.901] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:8:0) after 7m55s
[00:45:29.128] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:8:0)
[00:45:29.155] Build status: SCHEDULED
[00:45:29.155] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:9:0) after 5m41s
[00:51:10.175] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:9:0)
[00:51:10.211] Build status: SCHEDULED
[00:51:10.211] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:10:0) after 2m0s
[00:53:10.227] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:10:0)
[00:53:10.310] Build status: SCHEDULED
[00:53:10.310] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:11:0) after 7m27s
[01:00:37.375] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:11:0)
[01:00:37.418] Build status: SCHEDULED
[01:00:37.418] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:12:0) after 9m18s
[01:09:55.439] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:12:0)
[01:09:55.457] Build status: SCHEDULED
[01:09:55.457] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:13:0) after 3m2s
[01:12:57.474] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:13:0)
[01:12:57.496] Build status: SCHEDULED
[01:12:57.496] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:14:0) after 3m40s
[01:16:37.493] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:14:0)
[01:16:37.518] Build status: SCHEDULED
[01:16:37.518] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:15:0) after 7m17s
[01:23:54.566] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:15:0)
[01:23:54.580] Build status: SCHEDULED
[01:23:54.580] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:16:0) after 8m18s
[01:32:12.607] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:16:0)
[01:32:12.626] Build status: SCHEDULED
[01:32:12.626] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:17:0) after 6m19s
[01:38:31.641] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:17:0)
[01:38:31.659] Build status: SCHEDULED
[01:38:31.659] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:18:0) after 3m23s
[01:41:54.679] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:18:0)
[01:41:54.696] Build status: SCHEDULED
[01:41:54.696] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:19:0) after 3m56s
[01:45:50.687] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:19:0)
[01:45:50.716] Build status: SCHEDULED
[01:45:50.716] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:20:0) after 1m9s
[01:46:59.834] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:20:0)
[01:46:59.852] Build status: SCHEDULED
[01:46:59.852] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:21:0) after 2m30s
[01:49:29.952] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:21:0)
[01:49:30.025] Build status: SCHEDULED
[01:49:30.025] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:22:0) after 9m41s
[01:59:11.059] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:22:0)
[01:59:11.079] Build status: SCHEDULED
[01:59:11.079] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:23:0) after 3m31s
[02:02:42.161] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:23:0)
[02:02:42.183] Build status: SCHEDULED
[02:02:42.183] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:24:0) after 8m2s
[02:10:44.197] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:24:0)
[02:10:44.216] Build status: SCHEDULED
[02:10:44.216] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:25:0) after 9m25s
[02:20:09.317] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:25:0)
[02:20:09.338] Build status: SCHEDULED
[02:20:09.338] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:26:0) after 6m14s
[02:26:23.361] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:26:0)
[02:26:23.377] Build status: SCHEDULED
[02:26:23.377] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:27:0) after 4m2s
[02:30:25.350] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:27:0)
[02:30:25.383] Build status: SCHEDULED
[02:30:25.383] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:28:0) after 7m10s
[02:37:35.408] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:28:0)
[02:37:35.438] Build status: SCHEDULED
[02:37:35.438] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:29:0) after 7m45s
[02:45:20.465] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:29:0)
[02:45:20.483] Build status: SCHEDULED
[02:45:20.483] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:30:0) after 5m2s
[02:50:22.508] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:30:0)
[02:50:22.523] Build status: SCHEDULED
[02:50:22.523] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:31:0) after 6m51s
[02:57:13.664] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:31:0)
[02:57:13.700] Build status: SCHEDULED
[02:57:13.700] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:32:0) after 9m14s
[03:06:27.769] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:32:0)
[03:06:27.817] Build status: SCHEDULED
[03:06:27.817] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:33:0) after 6m56s
[03:13:23.943] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:33:0)
[03:13:23.960] Build status: SCHEDULED
[03:13:23.960] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:34:0) after 1m9s
[03:14:32.980] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:34:0)
[03:14:33.002] Build status: SCHEDULED
[03:14:33.002] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:35:0) after 6m23s
[03:19:36.004] Received PubSub notification, asking Buildbucket for the build status
[03:19:36.033] Build status: STARTED
[03:20:56.004] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:35:0)
[03:20:56.019] Build status: STARTED
[03:20:56.019] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:37:0) after 2m29s
[03:23:24.996] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:37:0)
[03:23:25.010] Build status: STARTED
[03:23:25.010] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:38:0) after 1m32s
[03:24:57.029] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:38:0)
[03:24:57.046] Build status: STARTED
[03:24:57.047] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:39:0) after 9m14s
[03:34:11.075] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:39:0)
[03:34:11.093] Build status: STARTED
[03:34:11.093] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8878921692683453200:40:0) after 9m54s
[03:40:22.249] Received PubSub notification, asking Buildbucket for the build status
[03:40:22.266] Build:
{
"id": "8713491630719777009",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-san"
},
"createdBy": "project:pigweed",
"createTime": "2025-05-30T00:10:47.769542827Z",
"startTime": "2025-05-30T03:19:35.805111539Z",
"endTime": "2025-05-30T03:40:22.029293837Z",
"updateTime": "2025-05-30T03:40:22.029293837Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "8bae44afd85f301beab18bae12b56dbb1ac92d15",
"ref": "refs/heads/main"
}
},
"canary": true
}
[03:40:22.266] Invocation finished in 3h29m35.753738264s with status SUCCEEDED