[19:45:29.480] New invocation is queued and will start shortly
[19:45:30.538] Starting the invocation (attempt 1)
[19:45:30.564] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/34a27fb95e3d0d5c495a79bc987f1be42d252538"
[19:45:30.564] Popped gitiles commit info from properties and tags
[19:45:30.564] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:45:30.564] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:45:30.565] Buildbucket request:
{
"requestId": "8858825856379148560",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "envtest-mac-arm"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8858825856379148560",
"job": "pigweed/pigweed.dev.ci-envtest-mac-arm",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "497ce0da20935854db05126e792b66d20494eead"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@497ce0da20935854db05126e792b66d20494eead",
"title": "497ce0da20935854db05126e792b66d20494eead",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/497ce0da20935854db05126e792b66d20494eead"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "5146c837c0e4d2cd6d954cb6493c7f1b6d595703"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@5146c837c0e4d2cd6d954cb6493c7f1b6d595703",
"title": "5146c837c0e4d2cd6d954cb6493c7f1b6d595703",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/5146c837c0e4d2cd6d954cb6493c7f1b6d595703"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "fcbd482f3e910984d1873d4f04812e1456b0600a"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@fcbd482f3e910984d1873d4f04812e1456b0600a",
"title": "fcbd482f3e910984d1873d4f04812e1456b0600a",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/fcbd482f3e910984d1873d4f04812e1456b0600a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "34a27fb95e3d0d5c495a79bc987f1be42d252538"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@34a27fb95e3d0d5c495a79bc987f1be42d252538",
"title": "34a27fb95e3d0d5c495a79bc987f1be42d252538",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/34a27fb95e3d0d5c495a79bc987f1be42d252538"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "34a27fb95e3d0d5c495a79bc987f1be42d252538",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8858825856379148560"
},
{
"key": "scheduler_job_id",
"value": "pigweed/pigweed.dev.ci-envtest-mac-arm"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[19:45:31.132] Scheduled build:
{
"id": "8693395794501333697",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "envtest-mac-arm"
},
"createdBy": "project:pigweed",
"createTime": "2026-01-06T19:45:30.637018788Z",
"updateTime": "2026-01-06T19:45:30.637018788Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "34a27fb95e3d0d5c495a79bc987f1be42d252538",
"ref": "refs/heads/main"
}
}
}
[19:45:31.132] Task URL: https://cr-buildbucket.appspot.com/build/8693395794501333697
[19:45:31.132] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:2:0) after 6m12s
[19:46:00.088] Received PubSub notification, asking Buildbucket for the build status
[19:46:00.116] Build status: SCHEDULED
[19:51:43.189] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:2:0)
[19:51:43.210] Build status: SCHEDULED
[19:51:43.211] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:4:0) after 8m36s
[20:00:19.290] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:4:0)
[20:00:19.309] Build status: SCHEDULED
[20:00:19.309] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:5:0) after 9m37s
[20:09:56.339] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:5:0)
[20:09:56.392] Build status: SCHEDULED
[20:09:56.392] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:6:0) after 2m47s
[20:12:43.410] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:6:0)
[20:12:43.439] Build status: SCHEDULED
[20:12:43.439] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:7:0) after 9m29s
[20:22:12.504] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:7:0)
[20:22:12.521] Build status: SCHEDULED
[20:22:12.522] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:8:0) after 8m48s
[20:31:00.664] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:8:0)
[20:31:00.690] Build status: SCHEDULED
[20:31:00.690] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:9:0) after 6m11s
[20:37:11.755] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:9:0)
[20:37:11.772] Build status: SCHEDULED
[20:37:11.772] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:10:0) after 8m37s
[20:45:48.857] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:10:0)
[20:45:48.892] Build status: SCHEDULED
[20:45:48.892] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:11:0) after 1m41s
[20:47:29.904] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:11:0)
[20:47:29.917] Build status: SCHEDULED
[20:47:29.917] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:12:0) after 9m27s
[20:56:56.916] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:12:0)
[20:56:56.935] Build status: SCHEDULED
[20:56:56.936] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:13:0) after 9m40s
[21:06:37.056] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:13:0)
[21:06:37.077] Build status: SCHEDULED
[21:06:37.078] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:14:0) after 3m5s
[21:09:42.092] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:14:0)
[21:09:42.109] Build status: SCHEDULED
[21:09:42.109] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:15:0) after 7m58s
[21:17:40.132] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:15:0)
[21:17:40.157] Build status: SCHEDULED
[21:17:40.157] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:16:0) after 2m42s
[21:20:22.417] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:16:0)
[21:20:22.457] Build status: SCHEDULED
[21:20:22.457] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:17:0) after 9m58s
[21:30:20.470] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:17:0)
[21:30:20.520] Build status: SCHEDULED
[21:30:20.520] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:18:0) after 7m49s
[21:38:09.536] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:18:0)
[21:38:09.554] Build status: SCHEDULED
[21:38:09.554] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:19:0) after 9m0s
[21:47:09.575] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:19:0)
[21:47:09.592] Build status: SCHEDULED
[21:47:09.592] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:20:0) after 4m23s
[21:51:32.658] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:20:0)
[21:51:32.731] Build status: SCHEDULED
[21:51:32.731] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:21:0) after 8m0s
[21:59:32.743] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:21:0)
[21:59:32.763] Build status: SCHEDULED
[21:59:32.763] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:22:0) after 4m7s
[22:03:39.800] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:22:0)
[22:03:39.822] Build status: SCHEDULED
[22:03:39.822] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:23:0) after 2m34s
[22:06:13.888] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:23:0)
[22:06:13.914] Build status: SCHEDULED
[22:06:13.914] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:24:0) after 3m47s
[22:10:00.883] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:24:0)
[22:10:00.901] Build status: SCHEDULED
[22:10:00.901] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:25:0) after 6m52s
[22:16:52.916] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:25:0)
[22:16:52.936] Build status: SCHEDULED
[22:16:52.936] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:26:0) after 2m54s
[22:19:47.012] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:26:0)
[22:19:47.032] Build status: SCHEDULED
[22:19:47.032] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:27:0) after 1m44s
[22:21:31.073] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:27:0)
[22:21:31.103] Build status: SCHEDULED
[22:21:31.103] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:28:0) after 9m24s
[22:30:55.119] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:28:0)
[22:30:55.261] Build status: SCHEDULED
[22:30:55.261] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:29:0) after 2m58s
[22:33:53.275] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:29:0)
[22:33:53.288] Build status: SCHEDULED
[22:33:53.288] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:30:0) after 6m34s
[22:40:27.289] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:30:0)
[22:40:27.306] Build status: SCHEDULED
[22:40:27.306] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:31:0) after 7m37s
[22:48:04.436] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:31:0)
[22:48:04.454] Build status: SCHEDULED
[22:48:04.454] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:32:0) after 9m25s
[22:57:29.483] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:32:0)
[22:57:29.531] Build status: SCHEDULED
[22:57:29.531] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:33:0) after 1m40s
[22:59:09.555] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:33:0)
[22:59:09.592] Build status: SCHEDULED
[22:59:09.592] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:34:0) after 5m40s
[23:04:49.707] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:34:0)
[23:04:49.725] Build status: SCHEDULED
[23:04:49.725] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:35:0) after 8m54s
[23:10:03.809] Received PubSub notification, asking Buildbucket for the build status
[23:10:03.831] Build status: STARTED
[23:13:43.740] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:35:0)
[23:13:43.792] Build status: STARTED
[23:13:43.792] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8858825856379148560:37:0) after 3m5s
[23:15:42.830] Received PubSub notification, asking Buildbucket for the build status
[23:15:42.846] Build:
{
"id": "8693395794501333697",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "envtest-mac-arm"
},
"createdBy": "project:pigweed",
"createTime": "2026-01-06T19:45:30.637018788Z",
"startTime": "2026-01-06T23:10:03.543671415Z",
"endTime": "2026-01-06T23:15:42.496765951Z",
"updateTime": "2026-01-06T23:15:42.496765951Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "34a27fb95e3d0d5c495a79bc987f1be42d252538",
"ref": "refs/heads/main"
}
}
}
[23:15:42.846] Invocation finished in 3h30m13.38295115s with status SUCCEEDED