[17:37:32.784] New invocation is queued and will start shortly
[17:37:34.108] Starting the invocation (attempt 1)
[17:37:34.135] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/8e230ddefd817f34f6b416ee95157117f443750c"
[17:37:34.135] Popped gitiles commit info from properties and tags
[17:37:34.135] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:37:34.135] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:37:34.135] Buildbucket request:
{
"requestId": "8882842102066968608",
"builder": {
"project": "pigweed",
"bucket": "matter.dev.ci",
"builder": "matter-envtest"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8882842102066968608",
"job": "pigweed/matter.dev.ci-matter-envtest",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "4c53c4723d91ef56fbd81cbd07d805cabcf5a7f4"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@4c53c4723d91ef56fbd81cbd07d805cabcf5a7f4",
"title": "4c53c4723d91ef56fbd81cbd07d805cabcf5a7f4",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/4c53c4723d91ef56fbd81cbd07d805cabcf5a7f4"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "4c53c4723d91ef56fbd81cbd07d805cabcf5a7f4"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/master@4c53c4723d91ef56fbd81cbd07d805cabcf5a7f4",
"title": "4c53c4723d91ef56fbd81cbd07d805cabcf5a7f4",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/4c53c4723d91ef56fbd81cbd07d805cabcf5a7f4"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "8e230ddefd817f34f6b416ee95157117f443750c"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@8e230ddefd817f34f6b416ee95157117f443750c",
"title": "8e230ddefd817f34f6b416ee95157117f443750c",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/8e230ddefd817f34f6b416ee95157117f443750c"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "8e230ddefd817f34f6b416ee95157117f443750c"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/master@8e230ddefd817f34f6b416ee95157117f443750c",
"title": "8e230ddefd817f34f6b416ee95157117f443750c",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/8e230ddefd817f34f6b416ee95157117f443750c"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "8e230ddefd817f34f6b416ee95157117f443750c",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8882842102066968608"
},
{
"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"
}
}
[17:37:34.559] Scheduled build:
{
"id": "8717412039920247409",
"builder": {
"project": "pigweed",
"bucket": "matter.dev.ci",
"builder": "matter-envtest"
},
"createdBy": "project:pigweed",
"createTime": "2025-04-16T17:37:34.205016946Z",
"updateTime": "2025-04-16T17:37:34.205016946Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "8e230ddefd817f34f6b416ee95157117f443750c",
"ref": "refs/heads/master"
}
},
"canary": true
}
[17:37:34.559] Task URL: https://cr-buildbucket.appspot.com/build/8717412039920247409
[17:37:34.559] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:2:0) after 3m17s
[17:37:35.546] Received PubSub notification, asking Buildbucket for the build status
[17:37:35.604] Build status: SCHEDULED
[17:40:51.583] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:2:0)
[17:40:51.656] Build status: SCHEDULED
[17:40:51.656] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:4:0) after 1m33s
[17:42:24.742] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:4:0)
[17:42:24.771] Build status: SCHEDULED
[17:42:24.771] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:5:0) after 6m48s
[17:49:12.792] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:5:0)
[17:49:12.811] Build status: SCHEDULED
[17:49:12.811] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:6:0) after 8m37s
[17:57:49.926] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:6:0)
[17:57:49.948] Build status: SCHEDULED
[17:57:49.948] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:7:0) after 8m3s
[18:05:52.965] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:7:0)
[18:05:53.029] Build status: SCHEDULED
[18:05:53.029] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:8:0) after 9m51s
[18:15:44.075] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:8:0)
[18:15:44.160] Build status: SCHEDULED
[18:15:44.160] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:9:0) after 3m21s
[18:19:05.188] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:9:0)
[18:19:05.205] Build status: SCHEDULED
[18:19:05.205] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:10:0) after 2m10s
[18:21:15.263] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:10:0)
[18:21:15.302] Build status: SCHEDULED
[18:21:15.302] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:11:0) after 1m21s
[18:22:36.323] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:11:0)
[18:22:36.351] Build status: SCHEDULED
[18:22:36.351] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:12:0) after 3m37s
[18:26:13.374] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:12:0)
[18:26:13.401] Build status: SCHEDULED
[18:26:13.401] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:13:0) after 8m55s
[18:35:08.427] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:13:0)
[18:35:08.565] Build status: SCHEDULED
[18:35:08.565] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:14:0) after 9m15s
[18:44:23.604] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:14:0)
[18:44:23.627] Build status: SCHEDULED
[18:44:23.627] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:15:0) after 3m1s
[18:47:24.710] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:15:0)
[18:47:24.725] Build status: SCHEDULED
[18:47:24.725] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:16:0) after 7m0s
[18:54:24.792] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:16:0)
[18:54:24.822] Build status: SCHEDULED
[18:54:24.822] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:17:0) after 7m27s
[19:01:51.806] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:17:0)
[19:01:51.834] Build status: SCHEDULED
[19:01:51.834] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:18:0) after 4m49s
[19:06:40.852] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:18:0)
[19:06:41.082] Build status: SCHEDULED
[19:06:41.082] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:19:0) after 6m32s
[19:13:13.110] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:19:0)
[19:13:13.128] Build status: SCHEDULED
[19:13:13.128] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:20:0) after 3m36s
[19:16:49.169] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:20:0)
[19:16:49.196] Build status: SCHEDULED
[19:16:49.197] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:21:0) after 4m45s
[19:21:34.214] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:21:0)
[19:21:34.247] Build status: SCHEDULED
[19:21:34.247] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:22:0) after 3m32s
[19:25:06.215] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:22:0)
[19:25:06.259] Build status: SCHEDULED
[19:25:06.259] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:23:0) after 9m48s
[19:34:54.328] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:23:0)
[19:34:54.358] Build status: SCHEDULED
[19:34:54.358] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:24:0) after 8m49s
[19:43:43.339] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:24:0)
[19:43:43.358] Build status: SCHEDULED
[19:43:43.358] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:25:0) after 1m29s
[19:45:12.379] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:25:0)
[19:45:12.397] Build status: SCHEDULED
[19:45:12.397] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:26:0) after 2m29s
[19:47:41.509] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:26:0)
[19:47:41.535] Build status: SCHEDULED
[19:47:41.535] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:27:0) after 9m4s
[19:56:45.691] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:27:0)
[19:56:45.729] Build status: SCHEDULED
[19:56:45.729] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:28:0) after 2m7s
[19:58:52.753] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:28:0)
[19:58:52.779] Build status: SCHEDULED
[19:58:52.779] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:29:0) after 3m35s
[20:02:27.926] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:29:0)
[20:02:27.967] Build status: SCHEDULED
[20:02:27.967] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:30:0) after 6m49s
[20:09:16.985] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:30:0)
[20:09:17.003] Build status: SCHEDULED
[20:09:17.004] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:31:0) after 2m41s
[20:11:58.023] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:31:0)
[20:11:58.040] Build status: SCHEDULED
[20:11:58.040] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:32:0) after 2m10s
[20:14:08.130] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:32:0)
[20:14:08.164] Build status: SCHEDULED
[20:14:08.164] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:33:0) after 9m50s
[20:23:58.182] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:33:0)
[20:23:58.200] Build status: SCHEDULED
[20:23:58.200] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:34:0) after 7m52s
[20:31:50.228] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:34:0)
[20:31:50.266] Build status: SCHEDULED
[20:31:50.266] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:35:0) after 1m11s
[20:33:01.289] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:35:0)
[20:33:01.322] Build status: SCHEDULED
[20:33:01.322] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:36:0) after 6m57s
[20:39:58.445] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:36:0)
[20:39:58.474] Build status: SCHEDULED
[20:39:58.474] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:37:0) after 2m58s
[20:42:56.556] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:37:0)
[20:42:56.575] Build status: SCHEDULED
[20:42:56.575] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:38:0) after 2m27s
[20:45:23.592] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:38:0)
[20:45:23.613] Build status: SCHEDULED
[20:45:23.613] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:39:0) after 7m4s
[20:52:27.731] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:39:0)
[20:52:27.748] Build status: SCHEDULED
[20:52:27.748] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:40:0) after 7m7s
[20:59:34.923] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:40:0)
[20:59:34.945] Build status: SCHEDULED
[20:59:34.945] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:41:0) after 9m19s
[21:03:29.618] Received PubSub notification, asking Buildbucket for the build status
[21:03:29.649] Build status: STARTED
[21:08:53.972] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:41:0)
[21:08:53.991] Build status: STARTED
[21:08:53.992] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:43:0) after 8m36s
[21:17:30.258] Handling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:43:0)
[21:17:30.288] Build status: STARTED
[21:17:30.288] Scheduling timer "check-buildbucket-build-status" (pigweed/matter.dev.ci-matter-envtest:8882842102066968608:44:0) after 2m53s
[21:17:52.521] Received PubSub notification, asking Buildbucket for the build status
[21:17:52.540] Build:
{
"id": "8717412039920247409",
"builder": {
"project": "pigweed",
"bucket": "matter.dev.ci",
"builder": "matter-envtest"
},
"createdBy": "project:pigweed",
"createTime": "2025-04-16T17:37:34.205016946Z",
"startTime": "2025-04-16T21:03:29.460562319Z",
"endTime": "2025-04-16T21:17:52.140995477Z",
"updateTime": "2025-04-16T21:17:52.140995477Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "8e230ddefd817f34f6b416ee95157117f443750c",
"ref": "refs/heads/master"
}
},
"canary": true
}
[21:17:52.541] Invocation finished in 3h40m19.766378676s with status SUCCEEDED