[20:03:21.683] New invocation is queued and will start shortly
[20:03:23.064] Starting the invocation (attempt 1)
[20:03:23.092] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/506d7f398d93a493d237924028f132cf8afa91e7"
[20:03:23.092] Popped gitiles commit info from properties and tags
[20:03:23.093] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:03:23.093] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:03:23.093] Buildbucket request:
{
"requestId": "8868246816594401248",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-san"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8868246816594401248",
"job": "pigweed/pigweed.dev.ci-pigweed-linux-gn-san",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "f2883cbecb30dde5228e67d3632839a54628a15a"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@f2883cbecb30dde5228e67d3632839a54628a15a",
"title": "f2883cbecb30dde5228e67d3632839a54628a15a",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/f2883cbecb30dde5228e67d3632839a54628a15a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "506d7f398d93a493d237924028f132cf8afa91e7"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@506d7f398d93a493d237924028f132cf8afa91e7",
"title": "506d7f398d93a493d237924028f132cf8afa91e7",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/506d7f398d93a493d237924028f132cf8afa91e7"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "506d7f398d93a493d237924028f132cf8afa91e7",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8868246816594401248"
},
{
"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"
}
}
[20:03:23.461] Scheduled build:
{
"id": "8702816754392818529",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-san"
},
"createdBy": "project:pigweed",
"createTime": "2025-09-24T20:03:23.153350092Z",
"updateTime": "2025-09-24T20:03:23.153350092Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "506d7f398d93a493d237924028f132cf8afa91e7",
"ref": "refs/heads/main"
}
}
}
[20:03:23.461] Task URL: https://cr-buildbucket.appspot.com/build/8702816754392818529
[20:03:23.461] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:2:0) after 3m9s
[20:03:24.403] Received PubSub notification, asking Buildbucket for the build status
[20:03:24.456] Build status: SCHEDULED
[20:06:32.575] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:2:0)
[20:06:32.596] Build status: SCHEDULED
[20:06:32.596] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:4:0) after 8m58s
[20:15:30.927] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:4:0)
[20:15:30.966] Build status: SCHEDULED
[20:15:30.966] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:5:0) after 5m57s
[20:21:28.605] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:5:0)
[20:21:28.684] Build status: SCHEDULED
[20:21:28.684] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:6:0) after 4m6s
[20:25:34.697] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:6:0)
[20:25:34.724] Build status: SCHEDULED
[20:25:34.724] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:7:0) after 3m10s
[20:28:44.739] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:7:0)
[20:28:44.761] Build status: SCHEDULED
[20:28:44.761] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:8:0) after 7m27s
[20:36:11.776] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:8:0)
[20:36:11.831] Build status: SCHEDULED
[20:36:11.831] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:9:0) after 7m32s
[20:43:43.848] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:9:0)
[20:43:43.867] Build status: SCHEDULED
[20:43:43.867] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:10:0) after 9m23s
[20:53:06.922] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:10:0)
[20:53:07.001] Build status: SCHEDULED
[20:53:07.001] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:11:0) after 3m26s
[20:56:33.105] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:11:0)
[20:56:33.164] Build status: SCHEDULED
[20:56:33.164] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:12:0) after 8m43s
[21:05:16.181] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:12:0)
[21:05:16.199] Build status: SCHEDULED
[21:05:16.199] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:13:0) after 8m3s
[21:13:19.219] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:13:0)
[21:13:19.262] Build status: SCHEDULED
[21:13:19.262] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:14:0) after 3m17s
[21:16:36.273] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:14:0)
[21:16:36.289] Build status: SCHEDULED
[21:16:36.289] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:15:0) after 3m30s
[21:20:06.276] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:15:0)
[21:20:06.291] Build status: SCHEDULED
[21:20:06.291] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:16:0) after 7m55s
[21:28:01.301] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:16:0)
[21:28:01.317] Build status: SCHEDULED
[21:28:01.317] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:17:0) after 5m38s
[21:33:39.343] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:17:0)
[21:33:39.360] Build status: SCHEDULED
[21:33:39.360] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:18:0) after 5m1s
[21:38:40.431] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:18:0)
[21:38:40.446] Build status: SCHEDULED
[21:38:40.446] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:19:0) after 8m7s
[21:46:47.465] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:19:0)
[21:46:47.490] Build status: SCHEDULED
[21:46:47.490] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:20:0) after 3m3s
[21:49:50.508] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:20:0)
[21:49:50.532] Build status: SCHEDULED
[21:49:50.532] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:21:0) after 2m12s
[21:52:02.568] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:21:0)
[21:52:02.588] Build status: SCHEDULED
[21:52:02.588] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:22:0) after 4m53s
[21:56:55.601] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:22:0)
[21:56:55.619] Build status: SCHEDULED
[21:56:55.619] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:23:0) after 4m30s
[22:01:25.702] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:23:0)
[22:01:25.719] Build status: SCHEDULED
[22:01:25.719] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:24:0) after 8m44s
[22:10:09.732] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:24:0)
[22:10:09.804] Build status: SCHEDULED
[22:10:09.804] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:25:0) after 2m47s
[22:12:56.816] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:25:0)
[22:12:56.834] Build status: SCHEDULED
[22:12:56.834] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:26:0) after 6m59s
[22:19:55.856] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:26:0)
[22:19:55.875] Build status: SCHEDULED
[22:19:55.875] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:27:0) after 9m48s
[22:29:43.892] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:27:0)
[22:29:43.910] Build status: SCHEDULED
[22:29:43.910] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:28:0) after 6m46s
[22:36:29.979] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:28:0)
[22:36:29.996] Build status: SCHEDULED
[22:36:29.996] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:29:0) after 2m36s
[22:39:06.009] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:29:0)
[22:39:06.027] Build status: SCHEDULED
[22:39:06.027] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:30:0) after 2m27s
[22:41:33.198] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:30:0)
[22:41:33.212] Build status: SCHEDULED
[22:41:33.212] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:31:0) after 1m21s
[22:42:54.231] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:31:0)
[22:42:54.251] Build status: SCHEDULED
[22:42:54.251] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:32:0) after 7m57s
[22:50:51.266] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:32:0)
[22:50:51.281] Build status: SCHEDULED
[22:50:51.281] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:33:0) after 6m6s
[22:56:57.366] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:33:0)
[22:56:57.420] Build status: SCHEDULED
[22:56:57.420] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:34:0) after 6m48s
[23:03:45.453] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:34:0)
[23:03:45.479] Build status: SCHEDULED
[23:03:45.480] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:35:0) after 8m58s
[23:07:17.356] Received PubSub notification, asking Buildbucket for the build status
[23:07:17.374] Build status: STARTED
[23:12:43.476] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:35:0)
[23:12:43.498] Build status: STARTED
[23:12:43.498] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:37:0) after 3m25s
[23:16:08.491] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:37:0)
[23:16:08.516] Build status: STARTED
[23:16:08.516] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:38:0) after 7m44s
[23:23:52.535] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:38:0)
[23:23:52.558] Build status: STARTED
[23:23:52.558] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-pigweed-linux-gn-san:8868246816594401248:39:0) after 9m3s
[23:31:07.920] Received PubSub notification, asking Buildbucket for the build status
[23:31:07.935] Build:
{
"id": "8702816754392818529",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "pigweed-linux-gn-san"
},
"createdBy": "project:pigweed",
"createTime": "2025-09-24T20:03:23.153350092Z",
"startTime": "2025-09-24T23:07:17.142335932Z",
"endTime": "2025-09-24T23:31:07.710680929Z",
"updateTime": "2025-09-24T23:31:07.710680929Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "506d7f398d93a493d237924028f132cf8afa91e7",
"ref": "refs/heads/main"
}
}
}
[23:31:07.935] Invocation finished in 3h27m46.264587088s with status SUCCEEDED