[00:08:24.372] New invocation is queued and will start shortly
[00:08:25.506] Starting the invocation (attempt 1)
[00:08:25.532] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/4d16136c7739b4833cc308dc4b34d907b5d4500e"
[00:08:25.532] Popped gitiles commit info from properties and tags
[00:08:25.532] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:08:25.532] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:08:25.533] Buildbucket request:
{
"requestId": "8894866907830819808",
"builder": {
"project": "pigweed",
"bucket": "matter.dev.ci",
"builder": "matter-envtest"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8894866907830819808",
"job": "pigweed/matter.dev.ci-matter-envtest",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "f52ea2994b98edb656dcb01a705786936c437372"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@f52ea2994b98edb656dcb01a705786936c437372",
"title": "f52ea2994b98edb656dcb01a705786936c437372",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/f52ea2994b98edb656dcb01a705786936c437372"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "f52ea2994b98edb656dcb01a705786936c437372"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/master@f52ea2994b98edb656dcb01a705786936c437372",
"title": "f52ea2994b98edb656dcb01a705786936c437372",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/f52ea2994b98edb656dcb01a705786936c437372"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip",
"revision": "71dc879501d0000fa9eadf734e8d1644871f104b"
},
"id": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/refs/heads/master@71dc879501d0000fa9eadf734e8d1644871f104b",
"title": "71dc879501d0000fa9eadf734e8d1644871f104b",
"url": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/71dc879501d0000fa9eadf734e8d1644871f104b"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip",
"revision": "ca772d7565fe48e01fecbb16f7a8491229dcb864"
},
"id": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/refs/heads/master@ca772d7565fe48e01fecbb16f7a8491229dcb864",
"title": "ca772d7565fe48e01fecbb16f7a8491229dcb864",
"url": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/ca772d7565fe48e01fecbb16f7a8491229dcb864"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "4d16136c7739b4833cc308dc4b34d907b5d4500e"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@4d16136c7739b4833cc308dc4b34d907b5d4500e",
"title": "4d16136c7739b4833cc308dc4b34d907b5d4500e",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/4d16136c7739b4833cc308dc4b34d907b5d4500e"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "4d16136c7739b4833cc308dc4b34d907b5d4500e"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/master@4d16136c7739b4833cc308dc4b34d907b5d4500e",
"title": "4d16136c7739b4833cc308dc4b34d907b5d4500e",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/4d16136c7739b4833cc308dc4b34d907b5d4500e"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "4d16136c7739b4833cc308dc4b34d907b5d4500e",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8894866907830819808"
},
{
"key": "scheduler_job_id",
"value": "pigweed/matter.dev.ci-matter-envtest"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[00:08:26.146] Scheduled build:
{
"id": "8729436845888645169",
"builder": {
"project": "pigweed",
"bucket": "matter.dev.ci",
"builder": "matter-envtest"
},
"createdBy": "project:pigweed",
"createTime": "2024-12-05T00:08:25.593841233Z",
"updateTime": "2024-12-05T00:08:25.593841233Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "4d16136c7739b4833cc308dc4b34d907b5d4500e",
"ref": "refs/heads/master"
}
},
"canary": true
}
[00:08:26.146] Task URL: https://cr-buildbucket.appspot.com/build/8729436845888645169
[00:08:26.146] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:2:0) after 3m0s
[00:08:26.309] Received PubSub notification, asking Buildbucket for the build status
[00:08:26.401] Build status: SCHEDULED
[00:11:26.139] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:2:0)
[00:11:26.167] Build status: SCHEDULED
[00:11:26.167] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:4:0) after 8m17s
[00:19:43.234] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:4:0)
[00:19:43.255] Build status: SCHEDULED
[00:19:43.255] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:5:0) after 4m56s
[00:24:39.270] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:5:0)
[00:24:39.289] Build status: SCHEDULED
[00:24:39.289] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:6:0) after 1m15s
[00:25:54.287] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:6:0)
[00:25:54.311] Build status: SCHEDULED
[00:25:54.311] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:7:0) after 7m29s
[00:33:23.364] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:7:0)
[00:33:23.383] Build status: SCHEDULED
[00:33:23.383] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:8:0) after 5m27s
[00:38:50.398] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:8:0)
[00:38:50.420] Build status: SCHEDULED
[00:38:50.420] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:9:0) after 2m53s
[00:41:43.440] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:9:0)
[00:41:43.474] Build status: SCHEDULED
[00:41:43.474] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:10:0) after 3m27s
[00:45:10.544] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:10:0)
[00:45:10.572] Build status: SCHEDULED
[00:45:10.572] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:11:0) after 2m57s
[00:48:07.552] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:11:0)
[00:48:07.576] Build status: SCHEDULED
[00:48:07.576] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:12:0) after 8m7s
[00:56:14.641] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:12:0)
[00:56:14.682] Build status: SCHEDULED
[00:56:14.682] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:13:0) after 1m27s
[00:57:41.707] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:13:0)
[00:57:41.729] Build status: SCHEDULED
[00:57:41.729] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:14:0) after 1m43s
[00:59:24.752] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:14:0)
[00:59:24.778] Build status: SCHEDULED
[00:59:24.778] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:15:0) after 3m31s
[01:02:55.794] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:15:0)
[01:02:55.944] Build status: SCHEDULED
[01:02:55.944] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:16:0) after 9m4s
[01:11:59.961] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:16:0)
[01:11:59.980] Build status: SCHEDULED
[01:11:59.980] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:17:0) after 5m46s
[01:17:46.048] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:17:0)
[01:17:46.069] Build status: SCHEDULED
[01:17:46.069] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:18:0) after 9m21s
[01:27:07.089] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:18:0)
[01:27:07.301] Build status: SCHEDULED
[01:27:07.301] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:19:0) after 1m3s
[01:28:10.376] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:19:0)
[01:28:10.394] Build status: SCHEDULED
[01:28:10.395] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:20:0) after 4m20s
[01:32:30.427] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:20:0)
[01:32:30.446] Build status: SCHEDULED
[01:32:30.446] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:21:0) after 2m27s
[01:34:57.463] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:21:0)
[01:34:57.496] Build status: SCHEDULED
[01:34:57.496] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:22:0) after 5m4s
[01:40:01.551] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:22:0)
[01:40:01.572] Build status: SCHEDULED
[01:40:01.572] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:23:0) after 1m44s
[01:41:45.598] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:23:0)
[01:41:45.628] Build status: SCHEDULED
[01:41:45.628] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:24:0) after 3m27s
[01:45:12.698] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:24:0)
[01:45:12.729] Build status: SCHEDULED
[01:45:12.729] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:25:0) after 4m1s
[01:49:13.746] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:25:0)
[01:49:13.796] Build status: SCHEDULED
[01:49:13.796] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:26:0) after 6m28s
[01:55:41.841] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:26:0)
[01:55:41.857] Build status: SCHEDULED
[01:55:41.857] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:27:0) after 8m33s
[02:04:14.887] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:27:0)
[02:04:14.919] Build status: SCHEDULED
[02:04:14.919] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:28:0) after 8m43s
[02:12:57.987] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:28:0)
[02:12:58.012] Build status: SCHEDULED
[02:12:58.012] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:29:0) after 9m6s
[02:22:04.045] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:29:0)
[02:22:04.066] Build status: SCHEDULED
[02:22:04.066] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:30:0) after 1m28s
[02:23:32.087] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:30:0)
[02:23:32.109] Build status: SCHEDULED
[02:23:32.109] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:31:0) after 2m48s
[02:26:20.188] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:31:0)
[02:26:20.207] Build status: SCHEDULED
[02:26:20.207] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:32:0) after 9m54s
[02:36:14.267] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:32:0)
[02:36:14.289] Build status: SCHEDULED
[02:36:14.289] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:33:0) after 3m29s
[02:39:43.305] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:33:0)
[02:39:43.324] Build status: SCHEDULED
[02:39:43.324] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:34:0) after 7m48s
[02:47:31.406] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:34:0)
[02:47:31.427] Build status: SCHEDULED
[02:47:31.427] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:35:0) after 8m7s
[02:55:38.474] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:35:0)
[02:55:38.491] Build status: SCHEDULED
[02:55:38.491] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:36:0) after 2m41s
[02:58:19.644] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:36:0)
[02:58:19.663] Build status: SCHEDULED
[02:58:19.663] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:37:0) after 1m54s
[03:00:13.684] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:37:0)
[03:00:13.717] Build status: SCHEDULED
[03:00:13.717] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:38:0) after 8m26s
[03:04:38.385] Received PubSub notification, asking Buildbucket for the build status
[03:04:38.437] Build status: STARTED
[03:08:39.864] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:38:0)
[03:08:39.879] Build status: STARTED
[03:08:39.879] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8894866907830819808:40:0) after 4m21s
[03:11:21.157] Received PubSub notification, asking Buildbucket for the build status
[03:11:21.232] Build:
{
"id": "8729436845888645169",
"builder": {
"project": "pigweed",
"bucket": "matter.dev.ci",
"builder": "matter-envtest"
},
"createdBy": "project:pigweed",
"createTime": "2024-12-05T00:08:25.593841233Z",
"startTime": "2024-12-05T03:04:38.093500914Z",
"endTime": "2024-12-05T03:11:20.639305240Z",
"updateTime": "2024-12-05T03:11:20.639305240Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "4d16136c7739b4833cc308dc4b34d907b5d4500e",
"ref": "refs/heads/master"
}
},
"canary": true
}
[03:11:21.232] Invocation finished in 3h2m56.875305296s with status FAILED