[20:04:40.311] New invocation is queued and will start shortly
[20:04:41.359] Starting the invocation (attempt 1)
[20:04:41.387] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/320427e20c5756f178239373d4a28ede70017ce8"
[20:04:41.387] Popped gitiles commit info from properties and tags
[20:04:41.387] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:04:41.387] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:04:41.387] Buildbucket request:
{
"requestId": "8921336556453669616",
"builder": {
"project": "pigweed",
"bucket": "matter.dev.ci",
"builder": "matter-envtest"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8921336556453669616",
"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": "fd92b3edc4e3ee903d9215fb7daf1b128e9f0a08"
},
"id": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/refs/heads/master@fd92b3edc4e3ee903d9215fb7daf1b128e9f0a08",
"title": "fd92b3edc4e3ee903d9215fb7daf1b128e9f0a08",
"url": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/fd92b3edc4e3ee903d9215fb7daf1b128e9f0a08"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip",
"revision": "3258b46218889d6df56dcb88cf787bec94f13d5e"
},
"id": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/refs/heads/master@3258b46218889d6df56dcb88cf787bec94f13d5e",
"title": "3258b46218889d6df56dcb88cf787bec94f13d5e",
"url": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/3258b46218889d6df56dcb88cf787bec94f13d5e"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip",
"revision": "a609e596cfbe2133f6d2448489d60524efd49dd3"
},
"id": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/refs/heads/master@a609e596cfbe2133f6d2448489d60524efd49dd3",
"title": "a609e596cfbe2133f6d2448489d60524efd49dd3",
"url": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/a609e596cfbe2133f6d2448489d60524efd49dd3"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip",
"revision": "d8f53c80d297aedd1b73042be430ee26fd2fd2d8"
},
"id": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/refs/heads/master@d8f53c80d297aedd1b73042be430ee26fd2fd2d8",
"title": "d8f53c80d297aedd1b73042be430ee26fd2fd2d8",
"url": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/d8f53c80d297aedd1b73042be430ee26fd2fd2d8"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "e8de610d576dd4a92564fbbc0bc2ff9a9d228627"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@e8de610d576dd4a92564fbbc0bc2ff9a9d228627",
"title": "e8de610d576dd4a92564fbbc0bc2ff9a9d228627",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/e8de610d576dd4a92564fbbc0bc2ff9a9d228627"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "e8de610d576dd4a92564fbbc0bc2ff9a9d228627"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/master@e8de610d576dd4a92564fbbc0bc2ff9a9d228627",
"title": "e8de610d576dd4a92564fbbc0bc2ff9a9d228627",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/e8de610d576dd4a92564fbbc0bc2ff9a9d228627"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip",
"revision": "39b2382c32129e52ecf394dd487063c7b93f01a5"
},
"id": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/refs/heads/master@39b2382c32129e52ecf394dd487063c7b93f01a5",
"title": "39b2382c32129e52ecf394dd487063c7b93f01a5",
"url": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/39b2382c32129e52ecf394dd487063c7b93f01a5"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip",
"revision": "573511d733210bb253d9c2014d79a02b7a5671a4"
},
"id": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/refs/heads/master@573511d733210bb253d9c2014d79a02b7a5671a4",
"title": "573511d733210bb253d9c2014d79a02b7a5671a4",
"url": "https://pigweed.googlesource.com/third_party/github/project-chip/connectedhomeip/+/573511d733210bb253d9c2014d79a02b7a5671a4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "a27434745dc5cfd170f31d8fafc3e83ba292aaa7"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@a27434745dc5cfd170f31d8fafc3e83ba292aaa7",
"title": "a27434745dc5cfd170f31d8fafc3e83ba292aaa7",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/a27434745dc5cfd170f31d8fafc3e83ba292aaa7"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "a27434745dc5cfd170f31d8fafc3e83ba292aaa7"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/master@a27434745dc5cfd170f31d8fafc3e83ba292aaa7",
"title": "a27434745dc5cfd170f31d8fafc3e83ba292aaa7",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/a27434745dc5cfd170f31d8fafc3e83ba292aaa7"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "c050599082a7277ba7b7260f817817ca39cfa820"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@c050599082a7277ba7b7260f817817ca39cfa820",
"title": "c050599082a7277ba7b7260f817817ca39cfa820",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/c050599082a7277ba7b7260f817817ca39cfa820"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "c050599082a7277ba7b7260f817817ca39cfa820"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/master@c050599082a7277ba7b7260f817817ca39cfa820",
"title": "c050599082a7277ba7b7260f817817ca39cfa820",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/c050599082a7277ba7b7260f817817ca39cfa820"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "0d0b9919b7c38f6cb2a1c0a0f7198b6282aa208e"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@0d0b9919b7c38f6cb2a1c0a0f7198b6282aa208e",
"title": "0d0b9919b7c38f6cb2a1c0a0f7198b6282aa208e",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/0d0b9919b7c38f6cb2a1c0a0f7198b6282aa208e"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "0d0b9919b7c38f6cb2a1c0a0f7198b6282aa208e"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/master@0d0b9919b7c38f6cb2a1c0a0f7198b6282aa208e",
"title": "0d0b9919b7c38f6cb2a1c0a0f7198b6282aa208e",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/0d0b9919b7c38f6cb2a1c0a0f7198b6282aa208e"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "33cd552638c42ecd68d4ad03bf987fe6b1382526"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@33cd552638c42ecd68d4ad03bf987fe6b1382526",
"title": "33cd552638c42ecd68d4ad03bf987fe6b1382526",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/33cd552638c42ecd68d4ad03bf987fe6b1382526"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "33cd552638c42ecd68d4ad03bf987fe6b1382526"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/master@33cd552638c42ecd68d4ad03bf987fe6b1382526",
"title": "33cd552638c42ecd68d4ad03bf987fe6b1382526",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/33cd552638c42ecd68d4ad03bf987fe6b1382526"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "320427e20c5756f178239373d4a28ede70017ce8"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@320427e20c5756f178239373d4a28ede70017ce8",
"title": "320427e20c5756f178239373d4a28ede70017ce8",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/320427e20c5756f178239373d4a28ede70017ce8"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "320427e20c5756f178239373d4a28ede70017ce8"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/master@320427e20c5756f178239373d4a28ede70017ce8",
"title": "320427e20c5756f178239373d4a28ede70017ce8",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/320427e20c5756f178239373d4a28ede70017ce8"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "320427e20c5756f178239373d4a28ede70017ce8",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8921336556453669616"
},
{
"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:04:42.286] Scheduled build:
{
"id": "8755906494604189665",
"builder": {
"project": "pigweed",
"bucket": "matter.dev.ci",
"builder": "matter-envtest"
},
"createdBy": "project:pigweed",
"createTime": "2024-02-16T20:04:41.449541046Z",
"updateTime": "2024-02-16T20:04:41.449541046Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "320427e20c5756f178239373d4a28ede70017ce8",
"ref": "refs/heads/master"
}
}
}
[20:04:42.286] Task URL: https://cr-buildbucket.appspot.com/build/8755906494604189665
[20:04:42.287] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:2:0) after 6m5s
[20:04:42.950] Received PubSub notification, asking Buildbucket for the build status
[20:04:42.977] Build status: SCHEDULED
[20:10:47.314] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:2:0)
[20:10:47.421] Build status: SCHEDULED
[20:10:47.421] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:4:0) after 3m16s
[20:14:03.438] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:4:0)
[20:14:03.461] Build status: SCHEDULED
[20:14:03.461] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:5:0) after 7m27s
[20:21:30.526] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:5:0)
[20:21:30.599] Build status: SCHEDULED
[20:21:30.599] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:6:0) after 8m14s
[20:29:44.655] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:6:0)
[20:29:44.684] Build status: SCHEDULED
[20:29:44.684] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:7:0) after 4m24s
[20:34:08.723] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:7:0)
[20:34:08.813] Build status: SCHEDULED
[20:34:08.813] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:8:0) after 8m2s
[20:42:10.931] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:8:0)
[20:42:11.028] Build status: SCHEDULED
[20:42:11.028] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:9:0) after 7m52s
[20:50:03.076] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:9:0)
[20:50:03.148] Build status: SCHEDULED
[20:50:03.148] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:10:0) after 8m20s
[20:58:23.158] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:10:0)
[20:58:23.180] Build status: SCHEDULED
[20:58:23.180] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:11:0) after 2m34s
[21:00:57.208] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:11:0)
[21:00:57.237] Build status: SCHEDULED
[21:00:57.237] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:12:0) after 9m26s
[21:10:23.515] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:12:0)
[21:10:23.554] Build status: SCHEDULED
[21:10:23.554] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:13:0) after 8m2s
[21:18:25.587] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:13:0)
[21:18:25.607] Build status: SCHEDULED
[21:18:25.607] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:14:0) after 2m15s
[21:20:40.631] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:14:0)
[21:20:40.702] Build status: SCHEDULED
[21:20:40.702] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:15:0) after 2m22s
[21:23:02.725] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:15:0)
[21:23:02.743] Build status: SCHEDULED
[21:23:02.743] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:16:0) after 5m38s
[21:28:40.765] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:16:0)
[21:28:40.855] Build status: SCHEDULED
[21:28:40.855] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:17:0) after 8m25s
[21:37:05.875] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:17:0)
[21:37:06.052] Build status: SCHEDULED
[21:37:06.052] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:18:0) after 7m41s
[21:44:47.120] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:18:0)
[21:44:47.156] Build status: SCHEDULED
[21:44:47.156] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:19:0) after 1m5s
[21:45:52.176] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:19:0)
[21:45:52.205] Build status: SCHEDULED
[21:45:52.205] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:20:0) after 8m19s
[21:54:11.212] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:20:0)
[21:54:11.260] Build status: SCHEDULED
[21:54:11.260] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:21:0) after 1m20s
[21:55:31.324] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:21:0)
[21:55:31.371] Build status: SCHEDULED
[21:55:31.371] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:22:0) after 1m25s
[21:56:56.423] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:22:0)
[21:56:56.456] Build status: SCHEDULED
[21:56:56.456] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:23:0) after 4m7s
[22:01:03.459] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:23:0)
[22:01:03.484] Build status: SCHEDULED
[22:01:03.484] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:24:0) after 7m8s
[22:08:11.565] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:24:0)
[22:08:11.585] Build status: SCHEDULED
[22:08:11.585] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:25:0) after 6m25s
[22:14:36.635] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:25:0)
[22:14:36.664] Build status: SCHEDULED
[22:14:36.664] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:26:0) after 5m23s
[22:19:59.874] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:26:0)
[22:19:59.905] Build status: SCHEDULED
[22:19:59.905] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:27:0) after 3m22s
[22:23:21.909] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:27:0)
[22:23:21.946] Build status: SCHEDULED
[22:23:21.946] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:28:0) after 7m21s
[22:28:25.311] Received PubSub notification, asking Buildbucket for the build status
[22:28:25.498] Build status: STARTED
[22:30:42.975] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:28:0)
[22:30:43.011] Build status: STARTED
[22:30:43.011] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8921336556453669616:30:0) after 9m26s
[22:34:38.946] Received PubSub notification, asking Buildbucket for the build status
[22:34:38.979] Build:
{
"id": "8755906494604189665",
"builder": {
"project": "pigweed",
"bucket": "matter.dev.ci",
"builder": "matter-envtest"
},
"createdBy": "project:pigweed",
"createTime": "2024-02-16T20:04:41.449541046Z",
"startTime": "2024-02-16T22:28:23.974056Z",
"endTime": "2024-02-16T22:34:38.311777Z",
"updateTime": "2024-02-16T22:34:38.311777Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "320427e20c5756f178239373d4a28ede70017ce8",
"ref": "refs/heads/master"
}
}
}
[22:34:38.979] Invocation finished in 2h29m58.677947238s with status FAILED