[00:58:29.778] New invocation is queued and will start shortly
[00:58:31.988] Starting the invocation (attempt 1)
[00:58:32.017] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/233460458e48b7980f38d635f5a70ce4385396c7"
[00:58:32.017] Popped gitiles commit info from properties and tags
[00:58:32.017] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:58:32.017] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:58:32.018] Buildbucket request:
{
"requestId": "8873482872364867232",
"builder": {
"project": "pigweed",
"bucket": "matter.dev.ci",
"builder": "matter-envtest"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8873482872364867232",
"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": "b724452f990d62fd4ee8b9a5c59f6aae3261f03c"
},
"id": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/refs/heads/master@b724452f990d62fd4ee8b9a5c59f6aae3261f03c",
"title": "b724452f990d62fd4ee8b9a5c59f6aae3261f03c",
"url": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/b724452f990d62fd4ee8b9a5c59f6aae3261f03c"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip",
"revision": "38deaa2935339a7ef6fdc6011e8d840d4b14f83b"
},
"id": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/refs/heads/master@38deaa2935339a7ef6fdc6011e8d840d4b14f83b",
"title": "38deaa2935339a7ef6fdc6011e8d840d4b14f83b",
"url": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/38deaa2935339a7ef6fdc6011e8d840d4b14f83b"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip",
"revision": "9bc3761eceac83617745573ef8f610a3060bacf1"
},
"id": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/refs/heads/master@9bc3761eceac83617745573ef8f610a3060bacf1",
"title": "9bc3761eceac83617745573ef8f610a3060bacf1",
"url": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/9bc3761eceac83617745573ef8f610a3060bacf1"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "233460458e48b7980f38d635f5a70ce4385396c7"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@233460458e48b7980f38d635f5a70ce4385396c7",
"title": "233460458e48b7980f38d635f5a70ce4385396c7",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/233460458e48b7980f38d635f5a70ce4385396c7"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "233460458e48b7980f38d635f5a70ce4385396c7"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/master@233460458e48b7980f38d635f5a70ce4385396c7",
"title": "233460458e48b7980f38d635f5a70ce4385396c7",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/233460458e48b7980f38d635f5a70ce4385396c7"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "233460458e48b7980f38d635f5a70ce4385396c7",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8873482872364867232"
},
{
"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:58:33.067] Scheduled build:
{
"id": "8708052809293471809",
"builder": {
"project": "pigweed",
"bucket": "matter.dev.ci",
"builder": "matter-envtest"
},
"createdBy": "project:pigweed",
"createTime": "2025-07-29T00:58:32.075741965Z",
"updateTime": "2025-07-29T00:58:32.075741965Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "233460458e48b7980f38d635f5a70ce4385396c7",
"ref": "refs/heads/master"
}
}
}
[00:58:33.067] Task URL: https://cr-buildbucket.appspot.com/build/8708052809293471809
[00:58:33.067] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:2:0) after 5m19s
[00:58:33.801] Received PubSub notification, asking Buildbucket for the build status
[00:58:33.831] Build status: SCHEDULED
[01:03:52.080] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:2:0)
[01:03:52.177] Build status: SCHEDULED
[01:03:52.177] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:4:0) after 9m16s
[01:13:08.203] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:4:0)
[01:13:08.240] Build status: SCHEDULED
[01:13:08.240] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:5:0) after 1m26s
[01:14:34.315] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:5:0)
[01:14:34.397] Build status: SCHEDULED
[01:14:34.397] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:6:0) after 2m23s
[01:16:57.463] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:6:0)
[01:16:57.480] Build status: SCHEDULED
[01:16:57.480] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:7:0) after 3m40s
[01:20:37.547] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:7:0)
[01:20:37.574] Build status: SCHEDULED
[01:20:37.574] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:8:0) after 6m25s
[01:27:02.544] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:8:0)
[01:27:02.583] Build status: SCHEDULED
[01:27:02.583] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:9:0) after 5m30s
[01:32:32.612] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:9:0)
[01:32:32.633] Build status: SCHEDULED
[01:32:32.633] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:10:0) after 7m45s
[01:40:17.748] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:10:0)
[01:40:17.820] Build status: SCHEDULED
[01:40:17.821] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:11:0) after 9m7s
[01:49:24.837] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:11:0)
[01:49:24.853] Build status: SCHEDULED
[01:49:24.853] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:12:0) after 4m6s
[01:53:30.905] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:12:0)
[01:53:30.958] Build status: SCHEDULED
[01:53:30.958] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:13:0) after 1m5s
[01:54:35.991] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:13:0)
[01:54:36.015] Build status: SCHEDULED
[01:54:36.015] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:14:0) after 5m10s
[01:59:46.135] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:14:0)
[01:59:46.179] Build status: SCHEDULED
[01:59:46.179] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:15:0) after 9m56s
[02:09:42.204] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:15:0)
[02:09:42.240] Build status: SCHEDULED
[02:09:42.240] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:16:0) after 6m17s
[02:15:59.279] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:16:0)
[02:15:59.299] Build status: SCHEDULED
[02:15:59.299] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:17:0) after 7m53s
[02:23:52.352] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:17:0)
[02:23:52.374] Build status: SCHEDULED
[02:23:52.374] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:18:0) after 4m1s
[02:27:53.400] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:18:0)
[02:27:53.470] Build status: SCHEDULED
[02:27:53.470] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:19:0) after 1m3s
[02:28:56.525] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:19:0)
[02:28:56.573] Build status: SCHEDULED
[02:28:56.573] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:20:0) after 4m59s
[02:33:55.665] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:20:0)
[02:33:55.686] Build status: SCHEDULED
[02:33:55.686] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:21:0) after 2m30s
[02:36:25.710] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:21:0)
[02:36:25.772] Build status: SCHEDULED
[02:36:25.772] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:22:0) after 2m37s
[02:39:02.849] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:22:0)
[02:39:02.867] Build status: SCHEDULED
[02:39:02.867] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:23:0) after 6m58s
[02:46:00.892] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:23:0)
[02:46:00.911] Build status: SCHEDULED
[02:46:00.911] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:24:0) after 8m14s
[02:54:14.925] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:24:0)
[02:54:14.968] Build status: SCHEDULED
[02:54:14.968] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:25:0) after 8m7s
[03:02:22.018] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:25:0)
[03:02:22.050] Build status: SCHEDULED
[03:02:22.050] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:26:0) after 8m46s
[03:11:08.139] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:26:0)
[03:11:08.157] Build status: SCHEDULED
[03:11:08.158] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:27:0) after 4m28s
[03:15:36.208] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:27:0)
[03:15:36.236] Build status: SCHEDULED
[03:15:36.236] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:28:0) after 5m13s
[03:20:49.305] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:28:0)
[03:20:49.327] Build status: SCHEDULED
[03:20:49.327] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:29:0) after 7m32s
[03:28:21.354] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:29:0)
[03:28:21.384] Build status: SCHEDULED
[03:28:21.384] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:30:0) after 6m14s
[03:34:35.458] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:30:0)
[03:34:35.477] Build status: SCHEDULED
[03:34:35.477] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:31:0) after 9m31s
[03:44:06.509] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:31:0)
[03:44:06.531] Build status: SCHEDULED
[03:44:06.531] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:32:0) after 5m58s
[03:50:04.609] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:32:0)
[03:50:04.628] Build status: SCHEDULED
[03:50:04.628] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:33:0) after 3m51s
[03:53:55.654] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:33:0)
[03:53:55.686] Build status: SCHEDULED
[03:53:55.686] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:34:0) after 6m43s
[04:00:38.704] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:34:0)
[04:00:38.729] Build status: SCHEDULED
[04:00:38.729] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:35:0) after 2m30s
[04:03:08.757] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:35:0)
[04:03:08.849] Build status: SCHEDULED
[04:03:08.849] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:36:0) after 6m22s
[04:09:30.866] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:36:0)
[04:09:30.982] Build status: SCHEDULED
[04:09:30.982] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:37:0) after 6m21s
[04:10:02.046] Received PubSub notification, asking Buildbucket for the build status
[04:10:02.065] Build status: STARTED
[04:15:52.085] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:37:0)
[04:15:52.103] Build status: STARTED
[04:15:52.104] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:39:0) after 3m54s
[04:19:46.127] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:39:0)
[04:19:46.165] Build status: STARTED
[04:19:46.165] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:40:0) after 2m43s
[04:22:29.239] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:40:0)
[04:22:29.279] Build status: STARTED
[04:22:29.279] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8873482872364867232:41:0) after 6m44s
[04:24:58.368] Received PubSub notification, asking Buildbucket for the build status
[04:24:58.387] Build:
{
"id": "8708052809293471809",
"builder": {
"project": "pigweed",
"bucket": "matter.dev.ci",
"builder": "matter-envtest"
},
"createdBy": "project:pigweed",
"createTime": "2025-07-29T00:58:32.075741965Z",
"startTime": "2025-07-29T04:10:01.791407582Z",
"endTime": "2025-07-29T04:24:58.090867047Z",
"updateTime": "2025-07-29T04:24:58.090867047Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "233460458e48b7980f38d635f5a70ce4385396c7",
"ref": "refs/heads/master"
}
}
}
[04:24:58.387] Invocation finished in 3h26m28.62501532s with status SUCCEEDED