[19:19:50.923] New invocation is queued and will start shortly
[19:19:52.060] Starting the invocation (attempt 1)
[19:19:52.094] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/examples/+/aa3f578c545ec9302c8cd836ada6bcaf3d5b3da9"
[19:19:52.094] Popped gitiles commit info from properties and tags
[19:19:52.094] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:19:52.094] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:19:52.094] Buildbucket request:
{
"requestId": "8900049089377484432",
"builder": {
"project": "pigweed",
"bucket": "examples.dev.ci",
"builder": "examples-envtest-mac"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8900049089377484432",
"job": "pigweed/examples.dev.ci-examples-envtest-mac",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/examples",
"revision": "aa3f578c545ec9302c8cd836ada6bcaf3d5b3da9"
},
"id": "https://pigweed.googlesource.com/pigweed/examples/+/refs/heads/main@aa3f578c545ec9302c8cd836ada6bcaf3d5b3da9",
"title": "aa3f578c545ec9302c8cd836ada6bcaf3d5b3da9",
"url": "https://pigweed.googlesource.com/pigweed/examples/+/aa3f578c545ec9302c8cd836ada6bcaf3d5b3da9"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/examples",
"id": "aa3f578c545ec9302c8cd836ada6bcaf3d5b3da9",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8900049089377484432"
},
{
"key": "scheduler_job_id",
"value": "pigweed/examples.dev.ci-examples-envtest-mac"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[19:19:52.600] Scheduled build:
{
"id": "8734619027419492977",
"builder": {
"project": "pigweed",
"bucket": "examples.dev.ci",
"builder": "examples-envtest-mac"
},
"createdBy": "project:pigweed",
"createTime": "2024-10-08T19:19:52.164785159Z",
"updateTime": "2024-10-08T19:19:52.164785159Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/examples",
"id": "aa3f578c545ec9302c8cd836ada6bcaf3d5b3da9",
"ref": "refs/heads/main"
}
}
}
[19:19:52.600] Task URL: https://cr-buildbucket.appspot.com/build/8734619027419492977
[19:19:52.600] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:2:0) after 2m14s
[19:19:53.585] Received PubSub notification, asking Buildbucket for the build status
[19:19:53.630] Build status: SCHEDULED
[19:22:06.646] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:2:0)
[19:22:06.671] Build status: SCHEDULED
[19:22:06.671] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:4:0) after 1m9s
[19:23:15.749] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:4:0)
[19:23:15.775] Build status: SCHEDULED
[19:23:15.775] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:5:0) after 5m29s
[19:28:44.797] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:5:0)
[19:28:44.817] Build status: SCHEDULED
[19:28:44.817] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:6:0) after 8m27s
[19:37:11.877] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:6:0)
[19:37:11.904] Build status: SCHEDULED
[19:37:11.904] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:7:0) after 9m52s
[19:47:03.955] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:7:0)
[19:47:03.981] Build status: SCHEDULED
[19:47:03.982] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:8:0) after 7m36s
[19:54:40.004] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:8:0)
[19:54:40.067] Build status: SCHEDULED
[19:54:40.067] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:9:0) after 8m30s
[20:03:10.088] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:9:0)
[20:03:10.114] Build status: SCHEDULED
[20:03:10.114] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:10:0) after 1m15s
[20:04:25.139] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:10:0)
[20:04:25.159] Build status: SCHEDULED
[20:04:25.159] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:11:0) after 3m16s
[20:07:41.127] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:11:0)
[20:07:41.157] Build status: SCHEDULED
[20:07:41.157] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:12:0) after 5m4s
[20:12:45.223] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:12:0)
[20:12:45.258] Build status: SCHEDULED
[20:12:45.258] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:13:0) after 9m39s
[20:22:24.345] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:13:0)
[20:22:24.370] Build status: SCHEDULED
[20:22:24.370] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:14:0) after 4m50s
[20:27:14.393] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:14:0)
[20:27:14.432] Build status: SCHEDULED
[20:27:14.432] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:15:0) after 9m41s
[20:36:55.458] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:15:0)
[20:36:55.616] Build status: SCHEDULED
[20:36:55.616] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:16:0) after 4m58s
[20:41:53.633] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:16:0)
[20:41:53.658] Build status: SCHEDULED
[20:41:53.658] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:17:0) after 7m50s
[20:49:43.672] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:17:0)
[20:49:43.698] Build status: SCHEDULED
[20:49:43.698] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:18:0) after 2m54s
[20:52:37.717] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:18:0)
[20:52:37.737] Build status: SCHEDULED
[20:52:37.737] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:19:0) after 5m45s
[20:58:22.777] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:19:0)
[20:58:22.797] Build status: SCHEDULED
[20:58:22.797] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:20:0) after 7m6s
[21:05:28.833] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:20:0)
[21:05:28.857] Build status: SCHEDULED
[21:05:28.857] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:21:0) after 7m5s
[21:12:33.922] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:21:0)
[21:12:33.941] Build status: SCHEDULED
[21:12:33.941] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:22:0) after 9m0s
[21:21:33.975] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:22:0)
[21:21:33.996] Build status: SCHEDULED
[21:21:33.996] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:23:0) after 2m14s
[21:23:48.055] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:23:0)
[21:23:48.142] Build status: SCHEDULED
[21:23:48.142] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:24:0) after 9m36s
[21:33:24.167] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:24:0)
[21:33:24.195] Build status: SCHEDULED
[21:33:24.195] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:25:0) after 3m58s
[21:37:22.263] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:25:0)
[21:37:22.308] Build status: SCHEDULED
[21:37:22.309] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:26:0) after 7m16s
[21:44:38.284] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:26:0)
[21:44:38.305] Build status: SCHEDULED
[21:44:38.305] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:27:0) after 4m11s
[21:48:49.343] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:27:0)
[21:48:49.370] Build status: SCHEDULED
[21:48:49.370] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:28:0) after 1m8s
[21:49:57.384] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:28:0)
[21:49:57.414] Build status: SCHEDULED
[21:49:57.414] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:29:0) after 3m22s
[21:53:19.428] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:29:0)
[21:53:19.518] Build status: SCHEDULED
[21:53:19.518] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:30:0) after 2m50s
[21:56:09.540] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:30:0)
[21:56:09.558] Build status: SCHEDULED
[21:56:09.558] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:31:0) after 4m51s
[22:01:00.642] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:31:0)
[22:01:00.664] Build status: SCHEDULED
[22:01:00.664] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:32:0) after 9m0s
[22:10:00.773] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:32:0)
[22:10:00.800] Build status: SCHEDULED
[22:10:00.800] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:33:0) after 4m54s
[22:14:54.816] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:33:0)
[22:14:54.841] Build status: SCHEDULED
[22:14:54.841] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:34:0) after 7m41s
[22:22:35.948] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:34:0)
[22:22:35.965] Build status: SCHEDULED
[22:22:35.965] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:35:0) after 2m1s
[22:24:36.983] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:35:0)
[22:24:37.006] Build status: SCHEDULED
[22:24:37.006] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:36:0) after 6m20s
[22:30:57.130] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:36:0)
[22:30:57.152] Build status: SCHEDULED
[22:30:57.153] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:37:0) after 4m50s
[22:35:47.154] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:37:0)
[22:35:47.316] Build status: SCHEDULED
[22:35:47.316] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:38:0) after 4m7s
[22:39:54.295] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:38:0)
[22:39:54.314] Build status: SCHEDULED
[22:39:54.314] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:39:0) after 3m40s
[22:43:34.345] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:39:0)
[22:43:34.365] Build status: SCHEDULED
[22:43:34.365] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:40:0) after 4m56s
[22:48:30.414] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:40:0)
[22:48:30.437] Build status: SCHEDULED
[22:48:30.437] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:41:0) after 2m21s
[22:50:51.464] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:41:0)
[22:50:51.485] Build status: SCHEDULED
[22:50:51.485] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:42:0) after 3m5s
[22:53:56.477] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:42:0)
[22:53:56.505] Build status: SCHEDULED
[22:53:56.505] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:43:0) after 3m35s
[22:57:31.582] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:43:0)
[22:57:31.614] Build status: SCHEDULED
[22:57:31.616] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:44:0) after 6m13s
[23:03:44.690] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:44:0)
[23:03:44.720] Build status: SCHEDULED
[23:03:44.720] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:45:0) after 3m1s
[23:06:45.817] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:45:0)
[23:06:45.846] Build status: SCHEDULED
[23:06:45.846] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:46:0) after 6m34s
[23:13:19.865] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:46:0)
[23:13:19.902] Build status: SCHEDULED
[23:13:19.902] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:47:0) after 5m19s
[23:18:38.962] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:47:0)
[23:18:38.982] Build status: SCHEDULED
[23:18:38.982] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:48:0) after 3m3s
[23:21:42.050] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:48:0)
[23:21:42.191] Build status: SCHEDULED
[23:21:42.191] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:49:0) after 9m52s
[23:31:34.196] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:49:0)
[23:31:34.214] Build status: SCHEDULED
[23:31:34.214] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:50:0) after 9m21s
[23:40:55.232] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:50:0)
[23:40:55.257] Build status: SCHEDULED
[23:40:55.257] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:51:0) after 5m13s
[23:46:04.534] Received PubSub notification, asking Buildbucket for the build status
[23:46:04.555] Build status: STARTED
[23:46:08.334] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:51:0)
[23:46:08.354] Build status: STARTED
[23:46:08.354] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:53:0) after 8m59s
[23:55:07.399] Handling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:53:0)
[23:55:07.425] Build status: STARTED
[23:55:07.425] Scheduling timer "check-buildbucket-build-status" (pigweed/examples.dev.ci-examples-envtest-mac:8900049089377484432:54:0) after 2m8s
[23:55:38.478] Received PubSub notification, asking Buildbucket for the build status
[23:55:38.496] Build:
{
"id": "8734619027419492977",
"builder": {
"project": "pigweed",
"bucket": "examples.dev.ci",
"builder": "examples-envtest-mac"
},
"createdBy": "project:pigweed",
"createTime": "2024-10-08T19:19:52.164785159Z",
"startTime": "2024-10-08T23:46:04.373488305Z",
"endTime": "2024-10-08T23:55:38.264792896Z",
"updateTime": "2024-10-08T23:55:38.264792896Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/examples",
"id": "aa3f578c545ec9302c8cd836ada6bcaf3d5b3da9",
"ref": "refs/heads/main"
}
}
}
[23:55:38.496] Invocation finished in 4h35m47.582860902s with status SUCCEEDED