[20:45:05.248] New invocation is queued and will start shortly
[20:45:06.649] Starting the invocation (attempt 1)
[20:45:06.684] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/dc898a59fd2e418fc48e4ff6e796780ab03612d7"
[20:45:06.712] Popped gitiles commit info from properties and tags
[20:45:06.712] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:45:06.712] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:45:06.712] Buildbucket request:
{
"requestId": "8892161790541950496",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "envtest-mac-arm"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8892161790541950496",
"job": "pigweed/pigweed.dev.ci-envtest-mac-arm",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "83e02f4a26269791f4c215b7b0ec5e72d08696bf"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@83e02f4a26269791f4c215b7b0ec5e72d08696bf",
"title": "83e02f4a26269791f4c215b7b0ec5e72d08696bf",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/83e02f4a26269791f4c215b7b0ec5e72d08696bf"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "52507bf5a518620fb0539db0665453d67b526fe9"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@52507bf5a518620fb0539db0665453d67b526fe9",
"title": "52507bf5a518620fb0539db0665453d67b526fe9",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/52507bf5a518620fb0539db0665453d67b526fe9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "2bfed7f127a1e27256894c775f0af0ea03723a0c"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@2bfed7f127a1e27256894c775f0af0ea03723a0c",
"title": "2bfed7f127a1e27256894c775f0af0ea03723a0c",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/2bfed7f127a1e27256894c775f0af0ea03723a0c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "a74450be314a2f8b4a31b069ad10c073110f082b"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@a74450be314a2f8b4a31b069ad10c073110f082b",
"title": "a74450be314a2f8b4a31b069ad10c073110f082b",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/a74450be314a2f8b4a31b069ad10c073110f082b"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "dc898a59fd2e418fc48e4ff6e796780ab03612d7"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@dc898a59fd2e418fc48e4ff6e796780ab03612d7",
"title": "dc898a59fd2e418fc48e4ff6e796780ab03612d7",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/dc898a59fd2e418fc48e4ff6e796780ab03612d7"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "dc898a59fd2e418fc48e4ff6e796780ab03612d7",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8892161790541950496"
},
{
"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"
}
}
[20:45:07.152] Scheduled build:
{
"id": "8726731728269468177",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "envtest-mac-arm"
},
"createdBy": "project:pigweed",
"createTime": "2025-01-03T20:45:06.789696667Z",
"updateTime": "2025-01-03T20:45:06.789696667Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "dc898a59fd2e418fc48e4ff6e796780ab03612d7",
"ref": "refs/heads/main"
}
}
}
[20:45:07.152] Task URL: https://cr-buildbucket.appspot.com/build/8726731728269468177
[20:45:07.152] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:2:0) after 8m42s
[20:45:08.129] Received PubSub notification, asking Buildbucket for the build status
[20:45:08.210] Build status: SCHEDULED
[20:53:49.241] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:2:0)
[20:53:49.265] Build status: SCHEDULED
[20:53:49.265] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:4:0) after 5m27s
[20:59:16.308] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:4:0)
[20:59:16.325] Build status: SCHEDULED
[20:59:16.325] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:5:0) after 5m27s
[21:04:43.346] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:5:0)
[21:04:43.377] Build status: SCHEDULED
[21:04:43.377] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:6:0) after 9m29s
[21:14:12.446] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:6:0)
[21:14:12.464] Build status: SCHEDULED
[21:14:12.464] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:7:0) after 9m14s
[21:23:26.482] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:7:0)
[21:23:26.504] Build status: SCHEDULED
[21:23:26.504] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:8:0) after 2m21s
[21:25:47.531] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:8:0)
[21:25:47.625] Build status: SCHEDULED
[21:25:47.625] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:9:0) after 7m32s
[21:33:19.650] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:9:0)
[21:33:19.672] Build status: SCHEDULED
[21:33:19.672] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:10:0) after 1m5s
[21:34:24.687] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:10:0)
[21:34:24.708] Build status: SCHEDULED
[21:34:24.708] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:11:0) after 6m49s
[21:41:13.850] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:11:0)
[21:41:13.872] Build status: SCHEDULED
[21:41:13.873] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:12:0) after 8m31s
[21:49:44.996] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:12:0)
[21:49:45.020] Build status: SCHEDULED
[21:49:45.020] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:13:0) after 4m14s
[21:53:59.093] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:13:0)
[21:53:59.126] Build status: SCHEDULED
[21:53:59.126] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:14:0) after 9m17s
[22:03:16.142] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:14:0)
[22:03:16.164] Build status: SCHEDULED
[22:03:16.164] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:15:0) after 5m48s
[22:09:04.233] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:15:0)
[22:09:04.254] Build status: SCHEDULED
[22:09:04.254] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:16:0) after 1m13s
[22:10:17.293] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:16:0)
[22:10:17.317] Build status: SCHEDULED
[22:10:17.317] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:17:0) after 7m12s
[22:17:29.411] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:17:0)
[22:17:29.564] Build status: SCHEDULED
[22:17:29.564] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:18:0) after 3m12s
[22:20:41.635] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:18:0)
[22:20:41.652] Build status: SCHEDULED
[22:20:41.652] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:19:0) after 5m2s
[22:25:43.682] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:19:0)
[22:25:43.706] Build status: SCHEDULED
[22:25:43.707] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:20:0) after 8m3s
[22:33:46.728] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:20:0)
[22:33:46.747] Build status: SCHEDULED
[22:33:46.747] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:21:0) after 2m21s
[22:36:07.766] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:21:0)
[22:36:07.881] Build status: SCHEDULED
[22:36:07.881] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:22:0) after 8m59s
[22:45:06.903] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:22:0)
[22:45:06.921] Build status: SCHEDULED
[22:45:06.921] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:23:0) after 4m57s
[22:50:03.948] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:23:0)
[22:50:03.972] Build status: SCHEDULED
[22:50:03.972] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:24:0) after 6m14s
[22:56:17.995] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:24:0)
[22:56:18.014] Build status: SCHEDULED
[22:56:18.014] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:25:0) after 1m38s
[22:57:56.054] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:25:0)
[22:57:56.103] Build status: SCHEDULED
[22:57:56.103] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:26:0) after 8m48s
[23:06:44.227] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:26:0)
[23:06:44.242] Build status: SCHEDULED
[23:06:44.242] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:27:0) after 7m25s
[23:14:09.368] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:27:0)
[23:14:09.467] Build status: SCHEDULED
[23:14:09.467] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:28:0) after 6m34s
[23:20:43.494] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:28:0)
[23:20:43.507] Build status: SCHEDULED
[23:20:43.507] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:29:0) after 4m4s
[23:21:39.400] Received PubSub notification, asking Buildbucket for the build status
[23:21:39.417] Build status: STARTED
[23:24:47.526] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:29:0)
[23:24:47.543] Build status: STARTED
[23:24:47.544] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-envtest-mac-arm:8892161790541950496:31:0) after 8m34s
[23:26:55.519] Received PubSub notification, asking Buildbucket for the build status
[23:26:55.533] Build:
{
"id": "8726731728269468177",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "envtest-mac-arm"
},
"createdBy": "project:pigweed",
"createTime": "2025-01-03T20:45:06.789696667Z",
"startTime": "2025-01-03T23:21:39.229924280Z",
"endTime": "2025-01-03T23:26:55.255874147Z",
"updateTime": "2025-01-03T23:26:55.255874147Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "dc898a59fd2e418fc48e4ff6e796780ab03612d7",
"ref": "refs/heads/main"
}
}
}
[23:26:55.533] Invocation finished in 2h41m50.293553766s with status SUCCEEDED