[23:33:58.295] New invocation is queued and will start shortly
[23:33:59.453] Starting the invocation (attempt 1)
[23:33:59.478] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/e011157a9cb05a002ce43ecf67e563469ca27ac4"
[23:33:59.478] Popped gitiles commit info from properties and tags
[23:33:59.478] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:33:59.478] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:33:59.478] Buildbucket request:
{
"requestId": "8905106531466475168",
"builder": {
"project": "pigweed",
"bucket": "matter.dev.ci",
"builder": "matter-envtest"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8905106531466475168",
"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": "b1e41b9db649537f09238ee61497953e488c18b7"
},
"id": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/refs/heads/master@b1e41b9db649537f09238ee61497953e488c18b7",
"title": "b1e41b9db649537f09238ee61497953e488c18b7",
"url": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/b1e41b9db649537f09238ee61497953e488c18b7"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip",
"revision": "4e30ae817e3f92e4dcaf6a9cf1a94b9d52198c99"
},
"id": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/refs/heads/master@4e30ae817e3f92e4dcaf6a9cf1a94b9d52198c99",
"title": "4e30ae817e3f92e4dcaf6a9cf1a94b9d52198c99",
"url": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/4e30ae817e3f92e4dcaf6a9cf1a94b9d52198c99"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "c59ef96f75b79fed9c7fca47382ffc86f48a5995"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@c59ef96f75b79fed9c7fca47382ffc86f48a5995",
"title": "c59ef96f75b79fed9c7fca47382ffc86f48a5995",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/c59ef96f75b79fed9c7fca47382ffc86f48a5995"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "c59ef96f75b79fed9c7fca47382ffc86f48a5995"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/master@c59ef96f75b79fed9c7fca47382ffc86f48a5995",
"title": "c59ef96f75b79fed9c7fca47382ffc86f48a5995",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/c59ef96f75b79fed9c7fca47382ffc86f48a5995"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip",
"revision": "1d2ddd4131f3b6a10092d93a74ea58f0fe09510b"
},
"id": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/refs/heads/master@1d2ddd4131f3b6a10092d93a74ea58f0fe09510b",
"title": "1d2ddd4131f3b6a10092d93a74ea58f0fe09510b",
"url": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/1d2ddd4131f3b6a10092d93a74ea58f0fe09510b"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip",
"revision": "3884820327714eb16396da7e837b66a162375646"
},
"id": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/refs/heads/master@3884820327714eb16396da7e837b66a162375646",
"title": "3884820327714eb16396da7e837b66a162375646",
"url": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/3884820327714eb16396da7e837b66a162375646"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip",
"revision": "74b8ce19ade75de7f6562d70f375a47024b40e0e"
},
"id": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/refs/heads/master@74b8ce19ade75de7f6562d70f375a47024b40e0e",
"title": "74b8ce19ade75de7f6562d70f375a47024b40e0e",
"url": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/74b8ce19ade75de7f6562d70f375a47024b40e0e"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip",
"revision": "128c37afaa94643ba7c7d992d7d48dd350902a65"
},
"id": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/refs/heads/master@128c37afaa94643ba7c7d992d7d48dd350902a65",
"title": "128c37afaa94643ba7c7d992d7d48dd350902a65",
"url": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/128c37afaa94643ba7c7d992d7d48dd350902a65"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "e011157a9cb05a002ce43ecf67e563469ca27ac4"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@e011157a9cb05a002ce43ecf67e563469ca27ac4",
"title": "e011157a9cb05a002ce43ecf67e563469ca27ac4",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/e011157a9cb05a002ce43ecf67e563469ca27ac4"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "e011157a9cb05a002ce43ecf67e563469ca27ac4"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/master@e011157a9cb05a002ce43ecf67e563469ca27ac4",
"title": "e011157a9cb05a002ce43ecf67e563469ca27ac4",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/e011157a9cb05a002ce43ecf67e563469ca27ac4"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "e011157a9cb05a002ce43ecf67e563469ca27ac4",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8905106531466475168"
},
{
"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"
}
}
[23:34:00.173] Scheduled build:
{
"id": "8739676469478129121",
"builder": {
"project": "pigweed",
"bucket": "matter.dev.ci",
"builder": "matter-envtest"
},
"createdBy": "project:pigweed",
"createTime": "2024-08-13T23:33:59.566011692Z",
"updateTime": "2024-08-13T23:33:59.566011692Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "e011157a9cb05a002ce43ecf67e563469ca27ac4",
"ref": "refs/heads/master"
}
}
}
[23:34:00.173] Task URL: https://cr-buildbucket.appspot.com/build/8739676469478129121
[23:34:00.173] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:2:0) after 7m32s
[23:34:01.009] Received PubSub notification, asking Buildbucket for the build status
[23:34:01.063] Build status: SCHEDULED
[23:41:32.230] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:2:0)
[23:41:32.249] Build status: SCHEDULED
[23:41:32.249] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:4:0) after 4m58s
[23:46:30.321] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:4:0)
[23:46:30.343] Build status: SCHEDULED
[23:46:30.343] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:5:0) after 8m10s
[23:54:40.398] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:5:0)
[23:54:40.425] Build status: SCHEDULED
[23:54:40.425] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:6:0) after 9m34s
[00:04:14.462] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:6:0)
[00:04:14.494] Build status: SCHEDULED
[00:04:14.494] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:7:0) after 4m8s
[00:08:22.608] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:7:0)
[00:08:22.631] Build status: SCHEDULED
[00:08:22.631] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:8:0) after 2m23s
[00:10:45.669] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:8:0)
[00:10:45.761] Build status: SCHEDULED
[00:10:45.761] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:9:0) after 5m29s
[00:16:14.828] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:9:0)
[00:16:14.962] Build status: SCHEDULED
[00:16:14.962] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:10:0) after 5m5s
[00:21:19.983] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:10:0)
[00:21:20.059] Build status: SCHEDULED
[00:21:20.059] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:11:0) after 4m39s
[00:25:59.158] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:11:0)
[00:25:59.186] Build status: SCHEDULED
[00:25:59.186] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:12:0) after 2m59s
[00:28:58.207] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:12:0)
[00:28:58.303] Build status: SCHEDULED
[00:28:58.303] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:13:0) after 1m51s
[00:30:49.324] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:13:0)
[00:30:49.357] Build status: SCHEDULED
[00:30:49.357] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:14:0) after 9m19s
[00:40:08.376] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:14:0)
[00:40:08.409] Build status: SCHEDULED
[00:40:08.409] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:15:0) after 7m9s
[00:47:17.478] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:15:0)
[00:47:17.536] Build status: SCHEDULED
[00:47:17.536] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:16:0) after 8m22s
[00:55:39.619] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:16:0)
[00:55:39.636] Build status: SCHEDULED
[00:55:39.636] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:17:0) after 6m0s
[01:01:39.673] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:17:0)
[01:01:39.690] Build status: SCHEDULED
[01:01:39.690] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:18:0) after 6m49s
[01:08:28.751] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:18:0)
[01:08:28.771] Build status: SCHEDULED
[01:08:28.771] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:19:0) after 3m22s
[01:11:50.839] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:19:0)
[01:11:50.877] Build status: SCHEDULED
[01:11:50.877] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:20:0) after 8m18s
[01:20:08.904] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:20:0)
[01:20:08.924] Build status: SCHEDULED
[01:20:08.924] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:21:0) after 2m3s
[01:22:11.909] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:21:0)
[01:22:11.935] Build status: SCHEDULED
[01:22:11.935] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:22:0) after 7m0s
[01:29:11.928] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:22:0)
[01:29:11.945] Build status: SCHEDULED
[01:29:11.945] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:23:0) after 7m7s
[01:36:19.094] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:23:0)
[01:36:19.147] Build status: SCHEDULED
[01:36:19.147] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:24:0) after 1m10s
[01:37:29.166] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:24:0)
[01:37:29.208] Build status: SCHEDULED
[01:37:29.208] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:25:0) after 1m36s
[01:39:05.240] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:25:0)
[01:39:05.276] Build status: SCHEDULED
[01:39:05.276] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:26:0) after 1m19s
[01:40:24.332] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:26:0)
[01:40:24.359] Build status: SCHEDULED
[01:40:24.359] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:27:0) after 6m4s
[01:46:28.380] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:27:0)
[01:46:28.413] Build status: SCHEDULED
[01:46:28.413] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:28:0) after 7m31s
[01:53:59.438] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:28:0)
[01:53:59.524] Build status: SCHEDULED
[01:53:59.524] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:29:0) after 1m36s
[01:55:35.509] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:29:0)
[01:55:35.535] Build status: SCHEDULED
[01:55:35.535] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:30:0) after 3m59s
[01:59:34.589] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:30:0)
[01:59:34.618] Build status: SCHEDULED
[01:59:34.618] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:31:0) after 9m32s
[02:09:06.710] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:31:0)
[02:09:06.728] Build status: SCHEDULED
[02:09:06.728] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:32:0) after 2m55s
[02:12:01.790] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:32:0)
[02:12:01.808] Build status: SCHEDULED
[02:12:01.808] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:33:0) after 7m52s
[02:19:25.507] Received PubSub notification, asking Buildbucket for the build status
[02:19:25.542] Build status: STARTED
[02:19:53.834] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:33:0)
[02:19:53.856] Build status: STARTED
[02:19:53.857] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:35:0) after 3m9s
[02:23:03.190] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:35:0)
[02:23:03.218] Build status: STARTED
[02:23:03.218] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:36:0) after 2m32s
[02:25:35.232] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:36:0)
[02:25:35.251] Build status: STARTED
[02:25:35.251] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8905106531466475168:37:0) after 3m49s
[02:26:07.119] Received PubSub notification, asking Buildbucket for the build status
[02:26:07.138] Build:
{
"id": "8739676469478129121",
"builder": {
"project": "pigweed",
"bucket": "matter.dev.ci",
"builder": "matter-envtest"
},
"createdBy": "project:pigweed",
"createTime": "2024-08-13T23:33:59.566011692Z",
"startTime": "2024-08-14T02:19:24.989034331Z",
"endTime": "2024-08-14T02:26:06.752467738Z",
"updateTime": "2024-08-14T02:26:06.752467738Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "e011157a9cb05a002ce43ecf67e563469ca27ac4",
"ref": "refs/heads/master"
}
}
}
[02:26:07.138] Invocation finished in 2h52m8.851929021s with status FAILED