[18:21:09.258] New invocation is queued and will start shortly
[18:21:10.303] Starting the invocation (attempt 1)
[18:21:10.334] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/58a69c54537dc47821458c3a1ce13fa073cd8cf2"
[18:21:10.334] Popped gitiles commit info from properties and tags
[18:21:10.334] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:21:10.334] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:21:10.335] Buildbucket request:
{
"requestId": "8872148916467335040",
"builder": {
"project": "pigweed",
"bucket": "matter.dev.ci",
"builder": "matter-envtest"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8872148916467335040",
"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": "07287a082d719c2524b1c8743c2c295ef44051e1"
},
"id": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/refs/heads/master@07287a082d719c2524b1c8743c2c295ef44051e1",
"title": "07287a082d719c2524b1c8743c2c295ef44051e1",
"url": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/07287a082d719c2524b1c8743c2c295ef44051e1"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip",
"revision": "b0f01772426e10d27212ea5a22b7603500026c29"
},
"id": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/refs/heads/master@b0f01772426e10d27212ea5a22b7603500026c29",
"title": "b0f01772426e10d27212ea5a22b7603500026c29",
"url": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/b0f01772426e10d27212ea5a22b7603500026c29"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip",
"revision": "501cfea948e25a26c037594b47ea40f5102d4d31"
},
"id": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/refs/heads/master@501cfea948e25a26c037594b47ea40f5102d4d31",
"title": "501cfea948e25a26c037594b47ea40f5102d4d31",
"url": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/501cfea948e25a26c037594b47ea40f5102d4d31"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip",
"revision": "c305e04c7f9a74d0af4ccdaf9e99a86beefa2a3c"
},
"id": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/refs/heads/master@c305e04c7f9a74d0af4ccdaf9e99a86beefa2a3c",
"title": "c305e04c7f9a74d0af4ccdaf9e99a86beefa2a3c",
"url": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/c305e04c7f9a74d0af4ccdaf9e99a86beefa2a3c"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip",
"revision": "58a69c54537dc47821458c3a1ce13fa073cd8cf2"
},
"id": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/refs/heads/master@58a69c54537dc47821458c3a1ce13fa073cd8cf2",
"title": "58a69c54537dc47821458c3a1ce13fa073cd8cf2",
"url": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/58a69c54537dc47821458c3a1ce13fa073cd8cf2"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "third_party/github/project-chip/connectedhomeip",
"id": "58a69c54537dc47821458c3a1ce13fa073cd8cf2",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8872148916467335040"
},
{
"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"
}
}
[18:21:10.795] Scheduled build:
{
"id": "8706718854584149201",
"builder": {
"project": "pigweed",
"bucket": "matter.dev.ci",
"builder": "matter-envtest"
},
"createdBy": "project:pigweed",
"createTime": "2025-08-12T18:21:10.421578966Z",
"updateTime": "2025-08-12T18:21:10.421578966Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "third_party/github/project-chip/connectedhomeip",
"id": "58a69c54537dc47821458c3a1ce13fa073cd8cf2",
"ref": "refs/heads/master"
}
}
}
[18:21:10.795] Task URL: https://cr-buildbucket.appspot.com/build/8706718854584149201
[18:21:10.795] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:2:0) after 2m13s
[18:21:10.977] Received PubSub notification, asking Buildbucket for the build status
[18:21:10.998] Build status: SCHEDULED
[18:23:23.811] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:2:0)
[18:23:23.894] Build status: SCHEDULED
[18:23:23.894] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:4:0) after 1m17s
[18:24:40.863] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:4:0)
[18:24:40.885] Build status: SCHEDULED
[18:24:40.885] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:5:0) after 3m44s
[18:28:24.938] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:5:0)
[18:28:24.964] Build status: SCHEDULED
[18:28:24.964] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:6:0) after 3m40s
[18:32:04.973] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:6:0)
[18:32:04.990] Build status: SCHEDULED
[18:32:04.990] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:7:0) after 1m56s
[18:34:01.023] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:7:0)
[18:34:01.044] Build status: SCHEDULED
[18:34:01.044] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:8:0) after 8m15s
[18:42:16.062] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:8:0)
[18:42:16.097] Build status: SCHEDULED
[18:42:16.097] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:9:0) after 6m16s
[18:48:32.117] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:9:0)
[18:48:32.147] Build status: SCHEDULED
[18:48:32.147] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:10:0) after 2m53s
[18:51:25.558] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:10:0)
[18:51:25.583] Build status: SCHEDULED
[18:51:25.583] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:11:0) after 1m0s
[18:52:25.627] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:11:0)
[18:52:25.667] Build status: SCHEDULED
[18:52:25.667] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:12:0) after 6m31s
[18:58:56.680] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:12:0)
[18:58:56.714] Build status: SCHEDULED
[18:58:56.714] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:13:0) after 2m8s
[19:01:04.727] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:13:0)
[19:01:04.752] Build status: SCHEDULED
[19:01:04.752] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:14:0) after 4m50s
[19:05:54.987] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:14:0)
[19:05:55.004] Build status: SCHEDULED
[19:05:55.004] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:15:0) after 6m39s
[19:12:34.164] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:15:0)
[19:12:34.184] Build status: SCHEDULED
[19:12:34.184] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:16:0) after 7m43s
[19:20:17.251] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:16:0)
[19:20:17.339] Build status: SCHEDULED
[19:20:17.339] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:17:0) after 5m43s
[19:26:00.414] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:17:0)
[19:26:00.445] Build status: SCHEDULED
[19:26:00.445] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:18:0) after 1m32s
[19:27:32.510] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:18:0)
[19:27:32.536] Build status: SCHEDULED
[19:27:32.536] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:19:0) after 1m11s
[19:28:43.535] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:19:0)
[19:28:43.561] Build status: SCHEDULED
[19:28:43.561] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:20:0) after 2m56s
[19:31:39.555] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:20:0)
[19:31:39.573] Build status: SCHEDULED
[19:31:39.573] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:21:0) after 8m50s
[19:40:29.771] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:21:0)
[19:40:29.798] Build status: SCHEDULED
[19:40:29.798] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:22:0) after 4m9s
[19:44:38.813] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:22:0)
[19:44:38.835] Build status: SCHEDULED
[19:44:38.835] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:23:0) after 3m54s
[19:48:32.951] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:23:0)
[19:48:32.988] Build status: SCHEDULED
[19:48:32.988] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:24:0) after 2m49s
[19:51:22.055] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:24:0)
[19:51:22.083] Build status: SCHEDULED
[19:51:22.083] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:25:0) after 4m34s
[19:55:56.179] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:25:0)
[19:55:56.200] Build status: SCHEDULED
[19:55:56.200] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:26:0) after 8m19s
[20:04:15.291] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:26:0)
[20:04:15.323] Build status: SCHEDULED
[20:04:15.323] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:27:0) after 3m29s
[20:07:44.342] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:27:0)
[20:07:44.363] Build status: SCHEDULED
[20:07:44.363] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:28:0) after 9m36s
[20:17:20.451] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:28:0)
[20:17:20.470] Build status: SCHEDULED
[20:17:20.470] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:29:0) after 7m5s
[20:24:25.597] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:29:0)
[20:24:25.615] Build status: SCHEDULED
[20:24:25.615] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:30:0) after 9m38s
[20:34:03.731] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:30:0)
[20:34:03.753] Build status: SCHEDULED
[20:34:03.754] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:31:0) after 6m17s
[20:40:20.737] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:31:0)
[20:40:20.761] Build status: SCHEDULED
[20:40:20.761] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:32:0) after 2m53s
[20:43:13.764] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:32:0)
[20:43:13.804] Build status: SCHEDULED
[20:43:13.804] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:33:0) after 6m46s
[20:50:00.021] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:33:0)
[20:50:00.046] Build status: SCHEDULED
[20:50:00.046] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:34:0) after 4m38s
[20:54:38.062] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:34:0)
[20:54:38.136] Build status: SCHEDULED
[20:54:38.137] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:35:0) after 6m34s
[21:01:12.160] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:35:0)
[21:01:12.189] Build status: SCHEDULED
[21:01:12.190] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:36:0) after 7m7s
[21:08:19.230] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:36:0)
[21:08:19.247] Build status: SCHEDULED
[21:08:19.247] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:37:0) after 2m53s
[21:11:12.324] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:37:0)
[21:11:12.522] Build status: SCHEDULED
[21:11:12.522] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:38:0) after 2m48s
[21:14:00.630] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:38:0)
[21:14:00.772] Build status: SCHEDULED
[21:14:00.772] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:39:0) after 8m20s
[21:22:20.896] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:39:0)
[21:22:20.916] Build status: SCHEDULED
[21:22:20.916] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:40:0) after 4m10s
[21:22:49.931] Received PubSub notification, asking Buildbucket for the build status
[21:22:49.959] Build status: STARTED
[21:26:30.986] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:40:0)
[21:26:31.015] Build status: STARTED
[21:26:31.015] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:42:0) after 1m41s
[21:28:12.205] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:42:0)
[21:28:12.224] Build status: STARTED
[21:28:12.224] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:43:0) after 6m14s
[21:34:26.302] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:43:0)
[21:34:26.322] Build status: STARTED
[21:34:26.322] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8872148916467335040:44:0) after 8m5s
[21:37:46.793] Received PubSub notification, asking Buildbucket for the build status
[21:37:47.605] Build:
{
"id": "8706718854584149201",
"builder": {
"project": "pigweed",
"bucket": "matter.dev.ci",
"builder": "matter-envtest"
},
"createdBy": "project:pigweed",
"createTime": "2025-08-12T18:21:10.421578966Z",
"startTime": "2025-08-12T21:22:49.522900664Z",
"endTime": "2025-08-12T21:37:25.846111423Z",
"updateTime": "2025-08-12T21:37:25.846111423Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "third_party/github/project-chip/connectedhomeip",
"id": "58a69c54537dc47821458c3a1ce13fa073cd8cf2",
"ref": "refs/heads/master"
}
}
}
[21:37:47.605] Invocation finished in 3h16m38.363992082s with status SUCCEEDED