[03:45:09.127] New invocation is queued and will start shortly
[03:45:10.237] Starting the invocation (attempt 1)
[03:45:10.260] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/acb09f4f227615cc3c3d1f7bbd5df887a0c1b3e3"
[03:45:10.260] Popped gitiles commit info from properties and tags
[03:45:10.260] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:45:10.260] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:45:10.261] Buildbucket request:
{
"requestId": "8867492987493779472",
"builder": {
"project": "pigweed",
"bucket": "matter.dev.ci",
"builder": "matter-envtest"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8867492987493779472",
"job": "pigweed/matter.dev.ci-matter-envtest",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip",
"revision": "ef59df7ec8ddb2649a91c0abbb8bd59c9c6fed87"
},
"id": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/refs/heads/master@ef59df7ec8ddb2649a91c0abbb8bd59c9c6fed87",
"title": "ef59df7ec8ddb2649a91c0abbb8bd59c9c6fed87",
"url": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/ef59df7ec8ddb2649a91c0abbb8bd59c9c6fed87"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip",
"revision": "471d46b3b857367de117854b17b2e411011b8d99"
},
"id": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/refs/heads/master@471d46b3b857367de117854b17b2e411011b8d99",
"title": "471d46b3b857367de117854b17b2e411011b8d99",
"url": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/471d46b3b857367de117854b17b2e411011b8d99"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "aa74068fdd8f52ebcc742a66d8f91cda46a372bb"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@aa74068fdd8f52ebcc742a66d8f91cda46a372bb",
"title": "aa74068fdd8f52ebcc742a66d8f91cda46a372bb",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/aa74068fdd8f52ebcc742a66d8f91cda46a372bb"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "aa74068fdd8f52ebcc742a66d8f91cda46a372bb"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/master@aa74068fdd8f52ebcc742a66d8f91cda46a372bb",
"title": "aa74068fdd8f52ebcc742a66d8f91cda46a372bb",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/aa74068fdd8f52ebcc742a66d8f91cda46a372bb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "83b3862437b4d01ea1fb7104a1e971bebc048cba"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@83b3862437b4d01ea1fb7104a1e971bebc048cba",
"title": "83b3862437b4d01ea1fb7104a1e971bebc048cba",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/83b3862437b4d01ea1fb7104a1e971bebc048cba"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "83b3862437b4d01ea1fb7104a1e971bebc048cba"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/master@83b3862437b4d01ea1fb7104a1e971bebc048cba",
"title": "83b3862437b4d01ea1fb7104a1e971bebc048cba",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/83b3862437b4d01ea1fb7104a1e971bebc048cba"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip",
"revision": "acb09f4f227615cc3c3d1f7bbd5df887a0c1b3e3"
},
"id": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/refs/heads/master@acb09f4f227615cc3c3d1f7bbd5df887a0c1b3e3",
"title": "acb09f4f227615cc3c3d1f7bbd5df887a0c1b3e3",
"url": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/acb09f4f227615cc3c3d1f7bbd5df887a0c1b3e3"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "third_party/github/project-chip/connectedhomeip",
"id": "acb09f4f227615cc3c3d1f7bbd5df887a0c1b3e3",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8867492987493779472"
},
{
"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"
}
}
[03:45:10.632] Scheduled build:
{
"id": "8702062925587036049",
"builder": {
"project": "pigweed",
"bucket": "matter.dev.ci",
"builder": "matter-envtest"
},
"createdBy": "project:pigweed",
"createTime": "2025-10-03T03:45:10.324620782Z",
"updateTime": "2025-10-03T03:45:10.324620782Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "third_party/github/project-chip/connectedhomeip",
"id": "acb09f4f227615cc3c3d1f7bbd5df887a0c1b3e3",
"ref": "refs/heads/master"
}
},
"canary": true
}
[03:45:10.632] Task URL: https://cr-buildbucket.appspot.com/build/8702062925587036049
[03:45:10.632] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:2:0) after 8m40s
[03:45:45.349] Received PubSub notification, asking Buildbucket for the build status
[03:45:45.368] Build status: SCHEDULED
[03:53:50.649] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:2:0)
[03:53:50.665] Build status: SCHEDULED
[03:53:50.665] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:4:0) after 7m57s
[04:01:47.731] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:4:0)
[04:01:47.755] Build status: SCHEDULED
[04:01:47.755] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:5:0) after 5m53s
[04:07:40.844] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:5:0)
[04:07:40.904] Build status: SCHEDULED
[04:07:40.904] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:6:0) after 7m8s
[04:14:48.925] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:6:0)
[04:14:48.952] Build status: SCHEDULED
[04:14:48.952] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:7:0) after 5m22s
[04:20:10.964] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:7:0)
[04:20:11.019] Build status: SCHEDULED
[04:20:11.019] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:8:0) after 5m48s
[04:25:59.111] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:8:0)
[04:25:59.181] Build status: SCHEDULED
[04:25:59.181] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:9:0) after 6m54s
[04:32:53.296] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:9:0)
[04:32:53.315] Build status: SCHEDULED
[04:32:53.315] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:10:0) after 9m51s
[04:42:44.470] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:10:0)
[04:42:44.497] Build status: SCHEDULED
[04:42:44.498] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:11:0) after 3m29s
[04:46:14.230] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:11:0)
[04:46:14.256] Build status: SCHEDULED
[04:46:14.256] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:12:0) after 8m54s
[04:55:08.496] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:12:0)
[04:55:08.540] Build status: SCHEDULED
[04:55:08.540] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:13:0) after 9m21s
[05:04:29.555] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:13:0)
[05:04:29.575] Build status: SCHEDULED
[05:04:29.575] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:14:0) after 3m48s
[05:08:17.693] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:14:0)
[05:08:17.711] Build status: SCHEDULED
[05:08:17.711] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:15:0) after 9m9s
[05:17:26.725] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:15:0)
[05:17:26.746] Build status: SCHEDULED
[05:17:26.746] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:16:0) after 9m4s
[05:26:30.869] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:16:0)
[05:26:30.890] Build status: SCHEDULED
[05:26:30.890] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:17:0) after 4m52s
[05:31:23.228] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:17:0)
[05:31:23.698] Build status: SCHEDULED
[05:31:23.698] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:18:0) after 4m30s
[05:35:53.838] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:18:0)
[05:35:53.941] Build status: SCHEDULED
[05:35:53.941] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:19:0) after 5m2s
[05:40:55.960] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:19:0)
[05:40:55.983] Build status: SCHEDULED
[05:40:55.983] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:20:0) after 7m8s
[05:48:04.079] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:20:0)
[05:48:04.106] Build status: SCHEDULED
[05:48:04.106] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:21:0) after 7m20s
[05:55:24.174] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:21:0)
[05:55:24.262] Build status: SCHEDULED
[05:55:24.262] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:22:0) after 7m18s
[06:02:42.278] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:22:0)
[06:02:42.345] Build status: SCHEDULED
[06:02:42.346] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:23:0) after 6m27s
[06:09:09.379] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:23:0)
[06:09:09.461] Build status: SCHEDULED
[06:09:09.461] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:24:0) after 9m50s
[06:18:59.479] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:24:0)
[06:18:59.497] Build status: SCHEDULED
[06:18:59.497] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:25:0) after 6m48s
[06:25:47.513] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:25:0)
[06:25:47.529] Build status: SCHEDULED
[06:25:47.529] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:26:0) after 9m50s
[06:35:37.568] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:26:0)
[06:35:37.583] Build status: SCHEDULED
[06:35:37.583] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:27:0) after 4m21s
[06:39:58.581] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:27:0)
[06:39:58.597] Build status: SCHEDULED
[06:39:58.597] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:28:0) after 3m57s
[06:43:55.669] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:28:0)
[06:43:55.724] Build status: SCHEDULED
[06:43:55.724] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:29:0) after 8m12s
[06:52:07.741] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:29:0)
[06:52:07.759] Build status: SCHEDULED
[06:52:07.759] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:30:0) after 1m25s
[06:53:32.881] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:30:0)
[06:53:32.932] Build status: SCHEDULED
[06:53:32.932] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:31:0) after 2m25s
[06:55:57.971] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:31:0)
[06:55:58.056] Build status: SCHEDULED
[06:55:58.056] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:32:0) after 4m21s
[06:58:57.941] Received PubSub notification, asking Buildbucket for the build status
[06:58:58.132] Build status: STARTED
[07:00:19.073] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:32:0)
[07:00:19.090] Build status: STARTED
[07:00:19.090] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8867492987493779472:34:0) after 4m36s
[07:00:23.115] Received PubSub notification, asking Buildbucket for the build status
[07:00:23.141] Build:
{
"id": "8702062925587036049",
"builder": {
"project": "pigweed",
"bucket": "matter.dev.ci",
"builder": "matter-envtest"
},
"createdBy": "project:pigweed",
"createTime": "2025-10-03T03:45:10.324620782Z",
"startTime": "2025-10-03T06:58:57.757282121Z",
"endTime": "2025-10-03T07:00:22.618253890Z",
"updateTime": "2025-10-03T07:00:22.618253890Z",
"status": "INFRA_FAILURE",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "third_party/github/project-chip/connectedhomeip",
"id": "acb09f4f227615cc3c3d1f7bbd5df887a0c1b3e3",
"ref": "refs/heads/master"
}
},
"canary": true
}
[07:00:23.141] Invocation finished in 3h15m14.019122267s with status FAILED