[20:28:20.416] New invocation is queued and will start shortly
[20:28:21.799] Starting the invocation (attempt 1)
[20:28:21.823] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/31894f61b9b5463706d910c43d4ff101a3e68dca"
[20:28:21.823] Popped gitiles commit info from properties and tags
[20:28:21.823] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:28:21.823] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:28:21.823] Buildbucket request:
{
"requestId": "8901947316565384640",
"builder": {
"project": "pigweed",
"bucket": "matter.dev.ci",
"builder": "matter-envtest"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8901947316565384640",
"job": "pigweed/matter.dev.ci-matter-envtest",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "49ac120ed645f69507e7f589283e581d49579462"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@49ac120ed645f69507e7f589283e581d49579462",
"title": "49ac120ed645f69507e7f589283e581d49579462",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/49ac120ed645f69507e7f589283e581d49579462"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "49ac120ed645f69507e7f589283e581d49579462"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/master@49ac120ed645f69507e7f589283e581d49579462",
"title": "49ac120ed645f69507e7f589283e581d49579462",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/49ac120ed645f69507e7f589283e581d49579462"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "ed299e5334927edfb29e500cd4494670f17ab0cb"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@ed299e5334927edfb29e500cd4494670f17ab0cb",
"title": "ed299e5334927edfb29e500cd4494670f17ab0cb",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/ed299e5334927edfb29e500cd4494670f17ab0cb"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "ed299e5334927edfb29e500cd4494670f17ab0cb"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/master@ed299e5334927edfb29e500cd4494670f17ab0cb",
"title": "ed299e5334927edfb29e500cd4494670f17ab0cb",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/ed299e5334927edfb29e500cd4494670f17ab0cb"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "f27cc845c43fe31a8f1f72225c7792bf02e0fcf7"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@f27cc845c43fe31a8f1f72225c7792bf02e0fcf7",
"title": "f27cc845c43fe31a8f1f72225c7792bf02e0fcf7",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/f27cc845c43fe31a8f1f72225c7792bf02e0fcf7"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "f27cc845c43fe31a8f1f72225c7792bf02e0fcf7"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/master@f27cc845c43fe31a8f1f72225c7792bf02e0fcf7",
"title": "f27cc845c43fe31a8f1f72225c7792bf02e0fcf7",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/f27cc845c43fe31a8f1f72225c7792bf02e0fcf7"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip",
"revision": "85b2fd3d1810bafdbe461df1a3271d3fb1911a66"
},
"id": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/refs/heads/master@85b2fd3d1810bafdbe461df1a3271d3fb1911a66",
"title": "85b2fd3d1810bafdbe461df1a3271d3fb1911a66",
"url": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/85b2fd3d1810bafdbe461df1a3271d3fb1911a66"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip",
"revision": "31894f61b9b5463706d910c43d4ff101a3e68dca"
},
"id": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/refs/heads/master@31894f61b9b5463706d910c43d4ff101a3e68dca",
"title": "31894f61b9b5463706d910c43d4ff101a3e68dca",
"url": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/31894f61b9b5463706d910c43d4ff101a3e68dca"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "third_party/github/project-chip/connectedhomeip",
"id": "31894f61b9b5463706d910c43d4ff101a3e68dca",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8901947316565384640"
},
{
"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"
}
}
[20:28:22.578] Scheduled build:
{
"id": "8736517254350799441",
"builder": {
"project": "pigweed",
"bucket": "matter.dev.ci",
"builder": "matter-envtest"
},
"createdBy": "project:pigweed",
"createTime": "2024-09-17T20:28:21.893372482Z",
"updateTime": "2024-09-17T20:28:21.893372482Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "third_party/github/project-chip/connectedhomeip",
"id": "31894f61b9b5463706d910c43d4ff101a3e68dca",
"ref": "refs/heads/master"
}
}
}
[20:28:22.578] Task URL: https://cr-buildbucket.appspot.com/build/8736517254350799441
[20:28:22.578] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:2:0) after 6m11s
[20:28:23.461] Received PubSub notification, asking Buildbucket for the build status
[20:28:23.484] Build status: SCHEDULED
[20:34:33.625] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:2:0)
[20:34:33.649] Build status: SCHEDULED
[20:34:33.649] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:4:0) after 3m39s
[20:38:12.627] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:4:0)
[20:38:12.652] Build status: SCHEDULED
[20:38:12.652] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:5:0) after 9m51s
[20:48:03.717] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:5:0)
[20:48:03.760] Build status: SCHEDULED
[20:48:03.760] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:6:0) after 5m21s
[20:53:24.825] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:6:0)
[20:53:24.848] Build status: SCHEDULED
[20:53:24.848] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:7:0) after 1m29s
[20:54:54.015] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:7:0)
[20:54:54.131] Build status: SCHEDULED
[20:54:54.132] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:8:0) after 4m44s
[20:59:38.168] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:8:0)
[20:59:38.204] Build status: SCHEDULED
[20:59:38.204] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:9:0) after 8m36s
[21:08:14.220] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:9:0)
[21:08:14.236] Build status: SCHEDULED
[21:08:14.236] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:10:0) after 9m0s
[21:17:14.252] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:10:0)
[21:17:14.313] Build status: SCHEDULED
[21:17:14.313] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:11:0) after 4m33s
[21:21:47.333] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:11:0)
[21:21:47.352] Build status: SCHEDULED
[21:21:47.352] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:12:0) after 5m1s
[21:26:48.369] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:12:0)
[21:26:48.394] Build status: SCHEDULED
[21:26:48.394] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:13:0) after 2m54s
[21:29:42.413] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:13:0)
[21:29:42.522] Build status: SCHEDULED
[21:29:42.522] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:14:0) after 7m21s
[21:37:03.565] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:14:0)
[21:37:03.595] Build status: SCHEDULED
[21:37:03.595] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:15:0) after 3m21s
[21:40:24.643] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:15:0)
[21:40:24.658] Build status: SCHEDULED
[21:40:24.658] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:16:0) after 9m39s
[21:50:03.632] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:16:0)
[21:50:03.665] Build status: SCHEDULED
[21:50:03.665] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:17:0) after 9m35s
[21:59:38.680] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:17:0)
[21:59:38.704] Build status: SCHEDULED
[21:59:38.704] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:18:0) after 4m58s
[22:04:36.749] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:18:0)
[22:04:36.784] Build status: SCHEDULED
[22:04:36.784] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:19:0) after 2m2s
[22:06:38.826] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:19:0)
[22:06:38.868] Build status: SCHEDULED
[22:06:38.868] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:20:0) after 1m1s
[22:07:39.884] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:20:0)
[22:07:39.918] Build status: SCHEDULED
[22:07:39.918] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:21:0) after 9m58s
[22:17:37.962] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:21:0)
[22:17:37.989] Build status: SCHEDULED
[22:17:37.989] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:22:0) after 1m25s
[22:19:02.969] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:22:0)
[22:19:03.000] Build status: SCHEDULED
[22:19:03.000] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:23:0) after 1m48s
[22:20:51.009] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:23:0)
[22:20:51.031] Build status: SCHEDULED
[22:20:51.031] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:24:0) after 8m43s
[22:29:34.032] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:24:0)
[22:29:34.070] Build status: SCHEDULED
[22:29:34.070] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:25:0) after 5m55s
[22:35:29.089] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:25:0)
[22:35:29.106] Build status: SCHEDULED
[22:35:29.106] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:26:0) after 9m21s
[22:44:50.122] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:26:0)
[22:44:50.142] Build status: SCHEDULED
[22:44:50.142] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:27:0) after 3m42s
[22:48:32.268] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:27:0)
[22:48:32.292] Build status: SCHEDULED
[22:48:32.292] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:28:0) after 5m40s
[22:54:12.331] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:28:0)
[22:54:12.374] Build status: SCHEDULED
[22:54:12.374] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:29:0) after 1m28s
[22:55:40.449] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:29:0)
[22:55:40.472] Build status: SCHEDULED
[22:55:40.472] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:30:0) after 1m44s
[22:57:24.522] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:30:0)
[22:57:24.558] Build status: SCHEDULED
[22:57:24.558] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:31:0) after 4m17s
[23:01:41.611] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:31:0)
[23:01:41.639] Build status: SCHEDULED
[23:01:41.639] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:32:0) after 7m18s
[23:05:32.245] Received PubSub notification, asking Buildbucket for the build status
[23:05:32.264] Build status: STARTED
[23:08:59.660] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:32:0)
[23:08:59.690] Build status: STARTED
[23:08:59.690] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8901947316565384640:34:0) after 4m54s
[23:13:38.782] Received PubSub notification, asking Buildbucket for the build status
[23:13:38.869] Build:
{
"id": "8736517254350799441",
"builder": {
"project": "pigweed",
"bucket": "matter.dev.ci",
"builder": "matter-envtest"
},
"createdBy": "project:pigweed",
"createTime": "2024-09-17T20:28:21.893372482Z",
"startTime": "2024-09-17T23:05:32.053795617Z",
"endTime": "2024-09-17T23:13:38.286880363Z",
"updateTime": "2024-09-17T23:13:38.286880363Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "third_party/github/project-chip/connectedhomeip",
"id": "31894f61b9b5463706d910c43d4ff101a3e68dca",
"ref": "refs/heads/master"
}
}
}
[23:13:38.869] Invocation finished in 2h45m18.471462797s with status SUCCEEDED