[17:41:23.695] New invocation is queued and will start shortly
[17:41:24.928] Starting the invocation (attempt 1)
[17:41:24.959] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/6c6ca80d58de1c67fb0e9780ae38a9a0d88b2d8a"
[17:41:24.959] Popped gitiles commit info from properties and tags
[17:41:24.959] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:41:24.959] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:41:24.959] Buildbucket request:
{
"requestId": "8889093050623354912",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "envtest-mac-x86"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8889093050623354912",
"job": "pigweed/pigweed.dev.ci-envtest-mac-x86",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "6c6ca80d58de1c67fb0e9780ae38a9a0d88b2d8a"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@6c6ca80d58de1c67fb0e9780ae38a9a0d88b2d8a",
"title": "6c6ca80d58de1c67fb0e9780ae38a9a0d88b2d8a",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/6c6ca80d58de1c67fb0e9780ae38a9a0d88b2d8a"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "6c6ca80d58de1c67fb0e9780ae38a9a0d88b2d8a",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8889093050623354912"
},
{
"key": "scheduler_job_id",
"value": "pigweed/pigweed.dev.ci-envtest-mac-x86"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[17:41:25.432] Scheduled build:
{
"id": "8723662988518673793",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "envtest-mac-x86"
},
"createdBy": "project:pigweed",
"createTime": "2025-02-06T17:41:25.073093901Z",
"updateTime": "2025-02-06T17:41:25.073093901Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "6c6ca80d58de1c67fb0e9780ae38a9a0d88b2d8a",
"ref": "refs/heads/main"
}
}
}
[17:41:25.432] Task URL: https://cr-buildbucket.appspot.com/build/8723662988518673793
[17:41:25.432] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:2:0) after 1m13s
[17:41:55.037] Received PubSub notification, asking Buildbucket for the build status
[17:41:55.076] Build status: SCHEDULED
[17:42:38.457] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:2:0)
[17:42:38.503] Build status: SCHEDULED
[17:42:38.503] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:4:0) after 5m43s
[17:48:21.474] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:4:0)
[17:48:21.497] Build status: SCHEDULED
[17:48:21.497] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:5:0) after 3m45s
[17:52:06.665] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:5:0)
[17:52:06.700] Build status: SCHEDULED
[17:52:06.700] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:6:0) after 1m45s
[17:53:51.719] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:6:0)
[17:53:51.787] Build status: SCHEDULED
[17:53:51.787] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:7:0) after 5m37s
[17:59:28.865] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:7:0)
[17:59:28.890] Build status: SCHEDULED
[17:59:28.890] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:8:0) after 8m30s
[18:07:58.918] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:8:0)
[18:07:58.949] Build status: SCHEDULED
[18:07:58.950] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:9:0) after 4m6s
[18:12:04.997] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:9:0)
[18:12:05.020] Build status: SCHEDULED
[18:12:05.020] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:10:0) after 9m5s
[18:21:10.320] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:10:0)
[18:21:10.340] Build status: SCHEDULED
[18:21:10.340] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:11:0) after 7m6s
[18:28:16.471] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:11:0)
[18:28:16.491] Build status: SCHEDULED
[18:28:16.491] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:12:0) after 9m5s
[18:37:21.511] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:12:0)
[18:37:21.530] Build status: SCHEDULED
[18:37:21.530] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:13:0) after 4m8s
[18:41:29.571] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:13:0)
[18:41:29.587] Build status: SCHEDULED
[18:41:29.587] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:14:0) after 2m44s
[18:44:13.623] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:14:0)
[18:44:13.651] Build status: SCHEDULED
[18:44:13.651] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:15:0) after 1m28s
[18:45:41.674] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:15:0)
[18:45:41.691] Build status: SCHEDULED
[18:45:41.691] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:16:0) after 2m39s
[18:48:20.732] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:16:0)
[18:48:20.753] Build status: SCHEDULED
[18:48:20.753] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:17:0) after 8m35s
[18:56:55.929] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:17:0)
[18:56:55.950] Build status: SCHEDULED
[18:56:55.950] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:18:0) after 4m39s
[19:01:35.025] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:18:0)
[19:01:35.067] Build status: SCHEDULED
[19:01:35.067] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:19:0) after 6m36s
[19:08:11.098] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:19:0)
[19:08:11.115] Build status: SCHEDULED
[19:08:11.115] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:20:0) after 8m29s
[19:16:40.138] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:20:0)
[19:16:40.185] Build status: SCHEDULED
[19:16:40.185] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:21:0) after 4m9s
[19:20:49.257] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:21:0)
[19:20:49.290] Build status: SCHEDULED
[19:20:49.290] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:22:0) after 9m33s
[19:30:22.314] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:22:0)
[19:30:22.341] Build status: SCHEDULED
[19:30:22.341] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:23:0) after 8m30s
[19:38:52.367] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:23:0)
[19:38:52.383] Build status: SCHEDULED
[19:38:52.383] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:24:0) after 9m9s
[19:48:01.408] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:24:0)
[19:48:01.426] Build status: SCHEDULED
[19:48:01.426] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:25:0) after 2m48s
[19:50:49.403] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:25:0)
[19:50:49.445] Build status: SCHEDULED
[19:50:49.445] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:26:0) after 1m18s
[19:52:07.475] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:26:0)
[19:52:07.496] Build status: SCHEDULED
[19:52:07.496] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:27:0) after 2m26s
[19:54:33.536] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:27:0)
[19:54:33.555] Build status: SCHEDULED
[19:54:33.555] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:28:0) after 8m31s
[20:03:04.639] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:28:0)
[20:03:04.697] Build status: SCHEDULED
[20:03:04.697] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:29:0) after 7m19s
[20:10:23.803] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:29:0)
[20:10:23.826] Build status: SCHEDULED
[20:10:23.826] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:30:0) after 9m0s
[20:19:23.899] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:30:0)
[20:19:23.971] Build status: SCHEDULED
[20:19:23.971] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:31:0) after 5m53s
[20:25:16.996] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:31:0)
[20:25:17.014] Build status: SCHEDULED
[20:25:17.014] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:32:0) after 3m9s
[20:28:26.032] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:32:0)
[20:28:26.049] Build status: SCHEDULED
[20:28:26.049] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:33:0) after 6m34s
[20:35:00.160] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:33:0)
[20:35:00.178] Build status: SCHEDULED
[20:35:00.178] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:34:0) after 5m30s
[20:40:30.206] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:34:0)
[20:40:30.223] Build status: SCHEDULED
[20:40:30.223] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:35:0) after 7m53s
[20:48:23.249] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:35:0)
[20:48:23.293] Build status: SCHEDULED
[20:48:23.293] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:36:0) after 9m37s
[20:58:00.312] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:36:0)
[20:58:00.329] Build status: SCHEDULED
[20:58:00.329] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:37:0) after 7m25s
[21:05:25.351] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:37:0)
[21:05:25.375] Build status: SCHEDULED
[21:05:25.375] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:38:0) after 7m26s
[21:12:51.411] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:38:0)
[21:12:51.494] Build status: SCHEDULED
[21:12:51.494] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:39:0) after 1m29s
[21:14:20.514] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:39:0)
[21:14:20.531] Build status: SCHEDULED
[21:14:20.532] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:40:0) after 5m51s
[21:20:11.745] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:40:0)
[21:20:11.765] Build status: SCHEDULED
[21:20:11.765] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:41:0) after 5m15s
[21:25:26.835] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:41:0)
[21:25:26.855] Build status: SCHEDULED
[21:25:26.855] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:42:0) after 2m24s
[21:27:50.895] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:42:0)
[21:27:50.913] Build status: SCHEDULED
[21:27:50.914] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:43:0) after 4m10s
[21:32:00.944] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:43:0)
[21:32:00.971] Build status: SCHEDULED
[21:32:00.971] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:44:0) after 8m24s
[21:40:25.044] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:44:0)
[21:40:25.150] Build status: STARTED
[21:40:25.150] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:45:0) after 8m56s
[21:40:35.919] Received PubSub notification, asking Buildbucket for the build status
[21:40:35.965] Build status: STARTED
[21:49:21.155] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:45:0)
[21:49:21.171] Build status: STARTED
[21:49:21.171] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-x86:8889093050623354912:47:0) after 5m33s
[21:51:06.103] Received PubSub notification, asking Buildbucket for the build status
[21:51:06.157] Build:
{
"id": "8723662988518673793",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "envtest-mac-x86"
},
"createdBy": "project:pigweed",
"createTime": "2025-02-06T17:41:25.073093901Z",
"startTime": "2025-02-06T21:40:13.195874622Z",
"endTime": "2025-02-06T21:51:05.707024494Z",
"updateTime": "2025-02-06T21:51:05.707024494Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "6c6ca80d58de1c67fb0e9780ae38a9a0d88b2d8a",
"ref": "refs/heads/main"
}
}
}
[21:51:06.157] Invocation finished in 4h9m42.474169463s with status SUCCEEDED