[19:44:18.750] New invocation is queued and will start shortly
[19:44:20.192] Starting the invocation (attempt 1)
[19:44:20.228] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/f601d6506b83d885c657f386bd9d0366128ac573"
[19:44:20.228] Popped gitiles commit info from properties and tags
[19:44:20.228] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:44:20.228] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:44:20.228] Buildbucket request:
{
"requestId": "8872596669429722800",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "envtest-mac-arm"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8872596669429722800",
"job": "pigweed/pigweed.dev.ci-envtest-mac-arm",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "13d5a248a7b8e1acf1024dd8d5dde2d274d5bd90"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@13d5a248a7b8e1acf1024dd8d5dde2d274d5bd90",
"title": "13d5a248a7b8e1acf1024dd8d5dde2d274d5bd90",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/13d5a248a7b8e1acf1024dd8d5dde2d274d5bd90"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "f601d6506b83d885c657f386bd9d0366128ac573"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@f601d6506b83d885c657f386bd9d0366128ac573",
"title": "f601d6506b83d885c657f386bd9d0366128ac573",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/f601d6506b83d885c657f386bd9d0366128ac573"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "f601d6506b83d885c657f386bd9d0366128ac573",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8872596669429722800"
},
{
"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:44:20.588] Scheduled build:
{
"id": "8707166607157281345",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "envtest-mac-arm"
},
"createdBy": "project:pigweed",
"createTime": "2025-08-07T19:44:20.291614161Z",
"updateTime": "2025-08-07T19:44:20.291614161Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "f601d6506b83d885c657f386bd9d0366128ac573",
"ref": "refs/heads/main"
}
}
}
[19:44:20.588] Task URL: https://cr-buildbucket.appspot.com/build/8707166607157281345
[19:44:20.588] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:2:0) after 8m0s
[19:44:46.488] Received PubSub notification, asking Buildbucket for the build status
[19:44:46.514] Build status: SCHEDULED
[19:52:20.666] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:2:0)
[19:52:20.753] Build status: SCHEDULED
[19:52:20.754] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:4:0) after 1m10s
[19:53:30.880] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:4:0)
[19:53:30.958] Build status: SCHEDULED
[19:53:30.958] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:5:0) after 3m26s
[19:56:56.974] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:5:0)
[19:56:56.995] Build status: SCHEDULED
[19:56:56.995] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:6:0) after 3m55s
[20:00:52.015] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:6:0)
[20:00:52.034] Build status: SCHEDULED
[20:00:52.034] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:7:0) after 4m12s
[20:05:04.214] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:7:0)
[20:05:04.277] Build status: SCHEDULED
[20:05:04.277] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:8:0) after 2m31s
[20:07:35.307] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:8:0)
[20:07:35.338] Build status: SCHEDULED
[20:07:35.338] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:9:0) after 7m29s
[20:15:04.353] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:9:0)
[20:15:04.387] Build status: SCHEDULED
[20:15:04.387] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:10:0) after 6m38s
[20:21:42.462] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:10:0)
[20:21:42.481] Build status: SCHEDULED
[20:21:42.481] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:11:0) after 7m48s
[20:29:30.551] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:11:0)
[20:29:30.574] Build status: SCHEDULED
[20:29:30.574] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:12:0) after 8m56s
[20:38:26.851] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:12:0)
[20:38:26.867] Build status: SCHEDULED
[20:38:26.867] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:13:0) after 1m58s
[20:40:24.897] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:13:0)
[20:40:24.958] Build status: SCHEDULED
[20:40:24.958] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:14:0) after 2m36s
[20:43:01.073] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:14:0)
[20:43:01.093] Build status: SCHEDULED
[20:43:01.093] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:15:0) after 1m41s
[20:44:42.145] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:15:0)
[20:44:42.169] Build status: SCHEDULED
[20:44:42.169] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:16:0) after 8m53s
[20:53:35.258] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:16:0)
[20:53:35.497] Build status: SCHEDULED
[20:53:35.497] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:17:0) after 6m29s
[21:00:04.514] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:17:0)
[21:00:04.540] Build status: SCHEDULED
[21:00:04.540] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:18:0) after 6m6s
[21:06:10.735] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:18:0)
[21:06:10.761] Build status: SCHEDULED
[21:06:10.761] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:19:0) after 1m59s
[21:08:09.748] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:19:0)
[21:08:09.766] Build status: SCHEDULED
[21:08:09.766] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:20:0) after 9m46s
[21:17:55.827] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:20:0)
[21:17:55.901] Build status: SCHEDULED
[21:17:55.901] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:21:0) after 4m26s
[21:22:21.923] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:21:0)
[21:22:21.944] Build status: SCHEDULED
[21:22:21.944] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:22:0) after 7m57s
[21:30:18.985] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:22:0)
[21:30:19.048] Build status: SCHEDULED
[21:30:19.048] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:23:0) after 8m3s
[21:38:22.072] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:23:0)
[21:38:22.117] Build status: SCHEDULED
[21:38:22.117] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:24:0) after 2m5s
[21:40:27.134] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:24:0)
[21:40:27.202] Build status: SCHEDULED
[21:40:27.202] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:25:0) after 4m9s
[21:44:36.215] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:25:0)
[21:44:36.537] Build status: SCHEDULED
[21:44:36.538] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:26:0) after 5m6s
[21:49:42.603] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:26:0)
[21:49:42.633] Build status: SCHEDULED
[21:49:42.633] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:27:0) after 5m28s
[21:55:10.694] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:27:0)
[21:55:10.828] Build status: SCHEDULED
[21:55:10.828] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:28:0) after 4m8s
[21:59:18.876] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:28:0)
[21:59:18.893] Build status: SCHEDULED
[21:59:18.894] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:29:0) after 2m28s
[22:01:47.014] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:29:0)
[22:01:47.092] Build status: SCHEDULED
[22:01:47.092] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:30:0) after 7m7s
[22:08:54.134] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:30:0)
[22:08:54.159] Build status: SCHEDULED
[22:08:54.159] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:31:0) after 3m0s
[22:11:54.211] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:31:0)
[22:11:54.247] Build status: SCHEDULED
[22:11:54.247] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:32:0) after 5m6s
[22:17:00.260] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:32:0)
[22:17:00.278] Build status: SCHEDULED
[22:17:00.278] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:33:0) after 3m45s
[22:20:45.338] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:33:0)
[22:20:45.415] Build status: SCHEDULED
[22:20:45.415] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:34:0) after 2m7s
[22:22:52.432] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:34:0)
[22:22:52.465] Build status: SCHEDULED
[22:22:52.465] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:35:0) after 2m42s
[22:25:34.480] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:35:0)
[22:25:34.510] Build status: SCHEDULED
[22:25:34.510] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:36:0) after 3m1s
[22:28:35.528] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:36:0)
[22:28:35.547] Build status: SCHEDULED
[22:28:35.547] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:37:0) after 4m57s
[22:33:32.564] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:37:0)
[22:33:32.615] Build status: SCHEDULED
[22:33:32.615] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:38:0) after 2m1s
[22:35:33.657] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:38:0)
[22:35:33.670] Build status: SCHEDULED
[22:35:33.670] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:39:0) after 7m21s
[22:42:54.779] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:39:0)
[22:42:54.806] Build status: SCHEDULED
[22:42:54.806] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:40:0) after 9m43s
[22:52:37.833] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:40:0)
[22:52:37.906] Build status: SCHEDULED
[22:52:37.906] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:41:0) after 3m29s
[22:56:07.067] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:41:0)
[22:56:07.115] Build status: SCHEDULED
[22:56:07.115] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:42:0) after 9m27s
[23:05:34.128] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:42:0)
[23:05:34.158] Build status: SCHEDULED
[23:05:34.158] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:43:0) after 7m51s
[23:13:25.183] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:43:0)
[23:13:25.201] Build status: SCHEDULED
[23:13:25.202] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:44:0) after 3m45s
[23:17:10.260] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:44:0)
[23:17:10.278] Build status: SCHEDULED
[23:17:10.278] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:45:0) after 4m14s
[23:21:24.395] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:45:0)
[23:21:24.417] Build status: SCHEDULED
[23:21:24.417] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:46:0) after 7m22s
[23:24:23.129] Received PubSub notification, asking Buildbucket for the build status
[23:24:23.149] Build status: STARTED
[23:28:46.439] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:46:0)
[23:28:46.455] Build status: STARTED
[23:28:46.455] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8872596669429722800:48:0) after 5m41s
[23:31:11.510] Received PubSub notification, asking Buildbucket for the build status
[23:31:11.528] Build:
{
"id": "8707166607157281345",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "envtest-mac-arm"
},
"createdBy": "project:pigweed",
"createTime": "2025-08-07T19:44:20.291614161Z",
"startTime": "2025-08-07T23:24:22.701528978Z",
"endTime": "2025-08-07T23:31:11.220876916Z",
"updateTime": "2025-08-07T23:31:11.220876916Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "f601d6506b83d885c657f386bd9d0366128ac573",
"ref": "refs/heads/main"
}
}
}
[23:31:11.528] Invocation finished in 3h46m52.786904252s with status SUCCEEDED