[18:22:41.071] New invocation is queued and will start shortly
[18:22:42.359] Starting the invocation (attempt 1)
[18:22:42.387] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/54ca0db892863f6d278dc9ca5be92591d1b9f98b"
[18:22:42.387] Popped gitiles commit info from properties and tags
[18:22:42.387] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[18:22:42.387] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[18:22:42.387] Buildbucket request:
{
"requestId": "8897606567748458720",
"builder": {
"project": "pigweed",
"bucket": "matter.dev.ci",
"builder": "matter-envtest"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8897606567748458720",
"job": "pigweed/matter.dev.ci-matter-envtest",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "d557e7f0bd31f9cbd8c209ee20db2a8515543c07"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@d557e7f0bd31f9cbd8c209ee20db2a8515543c07",
"title": "d557e7f0bd31f9cbd8c209ee20db2a8515543c07",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/d557e7f0bd31f9cbd8c209ee20db2a8515543c07"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "d557e7f0bd31f9cbd8c209ee20db2a8515543c07"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/master@d557e7f0bd31f9cbd8c209ee20db2a8515543c07",
"title": "d557e7f0bd31f9cbd8c209ee20db2a8515543c07",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/d557e7f0bd31f9cbd8c209ee20db2a8515543c07"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "30519ca068a57a2480175d0925bc9417766dd762"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@30519ca068a57a2480175d0925bc9417766dd762",
"title": "30519ca068a57a2480175d0925bc9417766dd762",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/30519ca068a57a2480175d0925bc9417766dd762"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "30519ca068a57a2480175d0925bc9417766dd762"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/master@30519ca068a57a2480175d0925bc9417766dd762",
"title": "30519ca068a57a2480175d0925bc9417766dd762",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/30519ca068a57a2480175d0925bc9417766dd762"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "6329e8d8b8ecd2c7b48f48696eabb951581d4c8a"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@6329e8d8b8ecd2c7b48f48696eabb951581d4c8a",
"title": "6329e8d8b8ecd2c7b48f48696eabb951581d4c8a",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/6329e8d8b8ecd2c7b48f48696eabb951581d4c8a"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "6329e8d8b8ecd2c7b48f48696eabb951581d4c8a"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/master@6329e8d8b8ecd2c7b48f48696eabb951581d4c8a",
"title": "6329e8d8b8ecd2c7b48f48696eabb951581d4c8a",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/6329e8d8b8ecd2c7b48f48696eabb951581d4c8a"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "29771c1afc60285b13a1f848af3fe576fe2a9959"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@29771c1afc60285b13a1f848af3fe576fe2a9959",
"title": "29771c1afc60285b13a1f848af3fe576fe2a9959",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/29771c1afc60285b13a1f848af3fe576fe2a9959"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "29771c1afc60285b13a1f848af3fe576fe2a9959"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/master@29771c1afc60285b13a1f848af3fe576fe2a9959",
"title": "29771c1afc60285b13a1f848af3fe576fe2a9959",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/29771c1afc60285b13a1f848af3fe576fe2a9959"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "54ca0db892863f6d278dc9ca5be92591d1b9f98b"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@54ca0db892863f6d278dc9ca5be92591d1b9f98b",
"title": "54ca0db892863f6d278dc9ca5be92591d1b9f98b",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/54ca0db892863f6d278dc9ca5be92591d1b9f98b"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "54ca0db892863f6d278dc9ca5be92591d1b9f98b"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/master@54ca0db892863f6d278dc9ca5be92591d1b9f98b",
"title": "54ca0db892863f6d278dc9ca5be92591d1b9f98b",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/54ca0db892863f6d278dc9ca5be92591d1b9f98b"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "54ca0db892863f6d278dc9ca5be92591d1b9f98b",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8897606567748458720"
},
{
"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"
}
}
[18:22:43.216] Scheduled build:
{
"id": "8732176505556877105",
"builder": {
"project": "pigweed",
"bucket": "matter.dev.ci",
"builder": "matter-envtest"
},
"createdBy": "project:pigweed",
"createTime": "2024-11-04T18:22:42.532920322Z",
"updateTime": "2024-11-04T18:22:42.532920322Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "54ca0db892863f6d278dc9ca5be92591d1b9f98b",
"ref": "refs/heads/master"
}
}
}
[18:22:43.216] Task URL: https://cr-buildbucket.appspot.com/build/8732176505556877105
[18:22:43.216] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:2:0) after 3m53s
[18:22:44.197] Received PubSub notification, asking Buildbucket for the build status
[18:22:44.299] Build status: SCHEDULED
[18:26:36.311] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:2:0)
[18:26:36.343] Build status: SCHEDULED
[18:26:36.343] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:4:0) after 6m3s
[18:32:39.396] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:4:0)
[18:32:39.425] Build status: SCHEDULED
[18:32:39.425] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:5:0) after 2m11s
[18:34:50.501] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:5:0)
[18:34:50.524] Build status: SCHEDULED
[18:34:50.525] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:6:0) after 8m18s
[18:43:08.742] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:6:0)
[18:43:08.778] Build status: SCHEDULED
[18:43:08.778] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:7:0) after 2m53s
[18:46:01.794] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:7:0)
[18:46:01.835] Build status: SCHEDULED
[18:46:01.835] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:8:0) after 7m1s
[18:53:02.918] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:8:0)
[18:53:02.948] Build status: SCHEDULED
[18:53:02.948] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:9:0) after 6m56s
[18:59:59.022] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:9:0)
[18:59:59.074] Build status: SCHEDULED
[18:59:59.074] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:10:0) after 5m27s
[19:05:26.180] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:10:0)
[19:05:26.205] Build status: SCHEDULED
[19:05:26.205] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:11:0) after 2m42s
[19:08:08.308] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:11:0)
[19:08:08.341] Build status: SCHEDULED
[19:08:08.341] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:12:0) after 4m10s
[19:12:18.316] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:12:0)
[19:12:18.348] Build status: SCHEDULED
[19:12:18.348] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:13:0) after 8m12s
[19:20:30.428] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:13:0)
[19:20:30.445] Build status: SCHEDULED
[19:20:30.445] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:14:0) after 3m40s
[19:24:10.455] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:14:0)
[19:24:10.478] Build status: SCHEDULED
[19:24:10.478] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:15:0) after 3m22s
[19:27:32.579] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:15:0)
[19:27:32.636] Build status: SCHEDULED
[19:27:32.637] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:16:0) after 7m56s
[19:35:28.657] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:16:0)
[19:35:28.710] Build status: SCHEDULED
[19:35:28.710] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:17:0) after 5m28s
[19:40:56.738] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:17:0)
[19:40:56.768] Build status: SCHEDULED
[19:40:56.768] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:18:0) after 7m48s
[19:48:44.903] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:18:0)
[19:48:44.919] Build status: SCHEDULED
[19:48:44.919] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:19:0) after 1m19s
[19:50:03.990] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:19:0)
[19:50:04.020] Build status: SCHEDULED
[19:50:04.020] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:20:0) after 6m39s
[19:56:43.037] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:20:0)
[19:56:43.065] Build status: SCHEDULED
[19:56:43.065] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:21:0) after 7m12s
[20:03:55.190] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:21:0)
[20:03:55.223] Build status: SCHEDULED
[20:03:55.223] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:22:0) after 2m24s
[20:06:19.240] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:22:0)
[20:06:19.261] Build status: SCHEDULED
[20:06:19.261] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:23:0) after 3m32s
[20:09:51.249] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:23:0)
[20:09:51.324] Build status: SCHEDULED
[20:09:51.324] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:24:0) after 7m3s
[20:16:54.361] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:24:0)
[20:16:54.378] Build status: SCHEDULED
[20:16:54.378] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:25:0) after 4m28s
[20:21:22.432] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:25:0)
[20:21:22.448] Build status: SCHEDULED
[20:21:22.448] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:26:0) after 6m26s
[20:27:48.463] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:26:0)
[20:27:48.491] Build status: SCHEDULED
[20:27:48.491] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:27:0) after 7m38s
[20:35:26.507] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:27:0)
[20:35:26.540] Build status: SCHEDULED
[20:35:26.540] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:28:0) after 6m41s
[20:42:07.558] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:28:0)
[20:42:07.586] Build status: SCHEDULED
[20:42:07.586] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:29:0) after 4m56s
[20:47:03.604] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:29:0)
[20:47:03.644] Build status: SCHEDULED
[20:47:03.644] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:30:0) after 7m1s
[20:54:04.660] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:30:0)
[20:54:04.686] Build status: SCHEDULED
[20:54:04.686] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:31:0) after 8m59s
[21:03:03.668] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:31:0)
[21:03:03.687] Build status: SCHEDULED
[21:03:03.687] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8897606567748458720:32:0) after 9m15s
[21:04:34.605] Received PubSub notification, asking Buildbucket for the build status
[21:04:34.627] Build status: STARTED
[21:11:17.310] Received PubSub notification, asking Buildbucket for the build status
[21:11:17.329] Build:
{
"id": "8732176505556877105",
"builder": {
"project": "pigweed",
"bucket": "matter.dev.ci",
"builder": "matter-envtest"
},
"createdBy": "project:pigweed",
"createTime": "2024-11-04T18:22:42.532920322Z",
"startTime": "2024-11-04T21:04:34.428102437Z",
"endTime": "2024-11-04T21:11:16.860824066Z",
"updateTime": "2024-11-04T21:11:16.860824066Z",
"status": "FAILURE",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "54ca0db892863f6d278dc9ca5be92591d1b9f98b",
"ref": "refs/heads/master"
}
}
}
[21:11:17.329] Invocation finished in 2h48m36.270671892s with status FAILED