[01:23:34.252] New invocation is queued and will start shortly
[01:23:35.333] Starting the invocation (attempt 1)
[01:23:35.362] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/695a171d4d7e4287441e7eafa0c9d77040d331e9"
[01:23:35.362] Popped gitiles commit info from properties and tags
[01:23:35.362] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[01:23:35.362] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[01:23:35.363] Buildbucket request:
{
"requestId": "8870853982781205728",
"builder": {
"project": "pigweed",
"bucket": "matter.dev.ci",
"builder": "matter-envtest"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8870853982781205728",
"job": "pigweed/matter.dev.ci-matter-envtest",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "03581907fcee871e446d25a3f71b47612f0884d5"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@03581907fcee871e446d25a3f71b47612f0884d5",
"title": "03581907fcee871e446d25a3f71b47612f0884d5",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/03581907fcee871e446d25a3f71b47612f0884d5"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "03581907fcee871e446d25a3f71b47612f0884d5"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/master@03581907fcee871e446d25a3f71b47612f0884d5",
"title": "03581907fcee871e446d25a3f71b47612f0884d5",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/03581907fcee871e446d25a3f71b47612f0884d5"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "cb3cacf28d0a82c120c9de7b89779f2682592bd8"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@cb3cacf28d0a82c120c9de7b89779f2682592bd8",
"title": "cb3cacf28d0a82c120c9de7b89779f2682592bd8",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/cb3cacf28d0a82c120c9de7b89779f2682592bd8"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "cb3cacf28d0a82c120c9de7b89779f2682592bd8"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/master@cb3cacf28d0a82c120c9de7b89779f2682592bd8",
"title": "cb3cacf28d0a82c120c9de7b89779f2682592bd8",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/cb3cacf28d0a82c120c9de7b89779f2682592bd8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "3eef7316431adcd53c2a529290ae25150e71aef8"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@3eef7316431adcd53c2a529290ae25150e71aef8",
"title": "3eef7316431adcd53c2a529290ae25150e71aef8",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/3eef7316431adcd53c2a529290ae25150e71aef8"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "3eef7316431adcd53c2a529290ae25150e71aef8"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/master@3eef7316431adcd53c2a529290ae25150e71aef8",
"title": "3eef7316431adcd53c2a529290ae25150e71aef8",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/3eef7316431adcd53c2a529290ae25150e71aef8"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip",
"revision": "d97f6412aeb54b5101fcb711675dbbe77dddafed"
},
"id": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/refs/heads/master@d97f6412aeb54b5101fcb711675dbbe77dddafed",
"title": "d97f6412aeb54b5101fcb711675dbbe77dddafed",
"url": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/d97f6412aeb54b5101fcb711675dbbe77dddafed"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "695a171d4d7e4287441e7eafa0c9d77040d331e9"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@695a171d4d7e4287441e7eafa0c9d77040d331e9",
"title": "695a171d4d7e4287441e7eafa0c9d77040d331e9",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/695a171d4d7e4287441e7eafa0c9d77040d331e9"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "695a171d4d7e4287441e7eafa0c9d77040d331e9"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/master@695a171d4d7e4287441e7eafa0c9d77040d331e9",
"title": "695a171d4d7e4287441e7eafa0c9d77040d331e9",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/695a171d4d7e4287441e7eafa0c9d77040d331e9"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "695a171d4d7e4287441e7eafa0c9d77040d331e9",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8870853982781205728"
},
{
"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"
}
}
[01:23:35.830] Scheduled build:
{
"id": "8705423920887589729",
"builder": {
"project": "pigweed",
"bucket": "matter.dev.ci",
"builder": "matter-envtest"
},
"createdBy": "project:pigweed",
"createTime": "2025-08-27T01:23:35.428370453Z",
"updateTime": "2025-08-27T01:23:35.428370453Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "695a171d4d7e4287441e7eafa0c9d77040d331e9",
"ref": "refs/heads/master"
}
}
}
[01:23:35.830] Task URL: https://cr-buildbucket.appspot.com/build/8705423920887589729
[01:23:35.830] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:2:0) after 5m47s
[01:23:36.060] Received PubSub notification, asking Buildbucket for the build status
[01:23:36.093] Build status: SCHEDULED
[01:29:22.892] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:2:0)
[01:29:22.951] Build status: SCHEDULED
[01:29:22.951] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:4:0) after 7m1s
[01:36:24.039] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:4:0)
[01:36:24.058] Build status: SCHEDULED
[01:36:24.058] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:5:0) after 3m11s
[01:39:35.076] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:5:0)
[01:39:35.093] Build status: SCHEDULED
[01:39:35.093] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:6:0) after 4m46s
[01:44:21.112] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:6:0)
[01:44:21.132] Build status: SCHEDULED
[01:44:21.132] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:7:0) after 2m50s
[01:47:11.244] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:7:0)
[01:47:11.297] Build status: SCHEDULED
[01:47:11.297] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:8:0) after 3m54s
[01:51:05.303] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:8:0)
[01:51:05.318] Build status: SCHEDULED
[01:51:05.318] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:9:0) after 6m16s
[01:57:21.333] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:9:0)
[01:57:21.374] Build status: SCHEDULED
[01:57:21.374] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:10:0) after 2m20s
[01:59:41.537] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:10:0)
[01:59:41.563] Build status: SCHEDULED
[01:59:41.564] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:11:0) after 8m0s
[02:07:41.584] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:11:0)
[02:07:41.956] Build status: SCHEDULED
[02:07:41.956] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:12:0) after 4m42s
[02:12:24.000] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:12:0)
[02:12:24.017] Build status: SCHEDULED
[02:12:24.017] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:13:0) after 5m7s
[02:17:31.037] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:13:0)
[02:17:31.054] Build status: SCHEDULED
[02:17:31.054] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:14:0) after 5m55s
[02:23:26.217] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:14:0)
[02:23:26.243] Build status: SCHEDULED
[02:23:26.243] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:15:0) after 3m34s
[02:27:00.334] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:15:0)
[02:27:00.361] Build status: SCHEDULED
[02:27:00.361] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:16:0) after 4m31s
[02:31:31.431] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:16:0)
[02:31:31.746] Build status: SCHEDULED
[02:31:31.746] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:17:0) after 3m1s
[02:34:32.765] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:17:0)
[02:34:32.788] Build status: SCHEDULED
[02:34:32.788] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:18:0) after 5m9s
[02:39:41.805] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:18:0)
[02:39:41.859] Build status: SCHEDULED
[02:39:41.859] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:19:0) after 8m26s
[02:48:07.924] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:19:0)
[02:48:07.944] Build status: SCHEDULED
[02:48:07.944] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:20:0) after 7m13s
[02:55:20.962] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:20:0)
[02:55:20.980] Build status: SCHEDULED
[02:55:20.980] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:21:0) after 3m13s
[02:58:33.996] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:21:0)
[02:58:34.014] Build status: SCHEDULED
[02:58:34.014] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:22:0) after 4m47s
[03:03:21.035] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:22:0)
[03:03:21.053] Build status: SCHEDULED
[03:03:21.053] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:23:0) after 5m54s
[03:09:15.075] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:23:0)
[03:09:15.103] Build status: SCHEDULED
[03:09:15.103] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:24:0) after 8m42s
[03:17:57.119] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:24:0)
[03:17:57.157] Build status: SCHEDULED
[03:17:57.157] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:25:0) after 3m21s
[03:21:18.173] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:25:0)
[03:21:18.316] Build status: SCHEDULED
[03:21:18.316] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:26:0) after 9m12s
[03:30:30.434] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:26:0)
[03:30:30.453] Build status: SCHEDULED
[03:30:30.453] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:27:0) after 9m59s
[03:40:29.483] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:27:0)
[03:40:29.497] Build status: SCHEDULED
[03:40:29.497] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:28:0) after 2m48s
[03:43:17.598] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:28:0)
[03:43:17.614] Build status: SCHEDULED
[03:43:17.614] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:29:0) after 4m10s
[03:47:27.633] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:29:0)
[03:47:27.656] Build status: SCHEDULED
[03:47:27.656] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:30:0) after 8m11s
[03:55:38.722] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:30:0)
[03:55:38.741] Build status: SCHEDULED
[03:55:38.741] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:31:0) after 3m51s
[03:59:30.101] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:31:0)
[03:59:30.174] Build status: SCHEDULED
[03:59:30.174] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:32:0) after 8m0s
[04:07:30.358] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:32:0)
[04:07:30.406] Build status: SCHEDULED
[04:07:30.406] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:33:0) after 8m33s
[04:16:03.434] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:33:0)
[04:16:03.457] Build status: SCHEDULED
[04:16:03.457] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:34:0) after 5m1s
[04:21:04.591] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:34:0)
[04:21:04.638] Build status: SCHEDULED
[04:21:04.638] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:35:0) after 1m7s
[04:22:11.756] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:35:0)
[04:22:11.797] Build status: SCHEDULED
[04:22:11.797] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:36:0) after 7m12s
[04:29:24.458] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:36:0)
[04:29:24.527] Build status: SCHEDULED
[04:29:24.527] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:37:0) after 2m37s
[04:32:01.554] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:37:0)
[04:32:01.590] Build status: SCHEDULED
[04:32:01.590] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:38:0) after 5m8s
[04:37:09.726] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:38:0)
[04:37:09.750] Build status: SCHEDULED
[04:37:09.751] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:39:0) after 8m35s
[04:45:44.774] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:39:0)
[04:45:44.795] Build status: SCHEDULED
[04:45:44.795] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:40:0) after 6m10s
[04:47:09.560] Received PubSub notification, asking Buildbucket for the build status
[04:47:09.578] Build status: STARTED
[04:51:54.818] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:40:0)
[04:51:54.837] Build status: STARTED
[04:51:54.837] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:42:0) after 4m21s
[04:56:15.856] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:42:0)
[04:56:15.882] Build status: STARTED
[04:56:15.882] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8870853982781205728:43:0) after 6m27s
[05:01:33.113] Received PubSub notification, asking Buildbucket for the build status
[05:01:33.156] Build:
{
"id": "8705423920887589729",
"builder": {
"project": "pigweed",
"bucket": "matter.dev.ci",
"builder": "matter-envtest"
},
"createdBy": "project:pigweed",
"createTime": "2025-08-27T01:23:35.428370453Z",
"startTime": "2025-08-27T04:46:37.989768898Z",
"endTime": "2025-08-27T05:01:32.862083321Z",
"updateTime": "2025-08-27T05:01:32.862083321Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "695a171d4d7e4287441e7eafa0c9d77040d331e9",
"ref": "refs/heads/master"
}
}
}
[05:01:33.156] Invocation finished in 3h37m58.917074507s with status SUCCEEDED