[20:04:34.189] New invocation is queued and will start shortly
[20:04:35.273] Starting the invocation (attempt 1)
[20:04:35.304] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/f9948c36272736eba98bd145b6f90723d1c7adb4"
[20:04:35.304] Popped gitiles commit info from properties and tags
[20:04:35.304] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:04:35.304] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:04:35.305] Buildbucket request:
{
"requestId": "8873863752480446736",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "coverage"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8873863752480446736",
"job": "pigweed/pigweed.dev.ci-coverage",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "861126d4a963dd3104dee678b03c1a9cef8d91ff"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@861126d4a963dd3104dee678b03c1a9cef8d91ff",
"title": "861126d4a963dd3104dee678b03c1a9cef8d91ff",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/861126d4a963dd3104dee678b03c1a9cef8d91ff"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "6b4a6e06114829a0f3ac40caa0e2c6912eac8bfc"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@6b4a6e06114829a0f3ac40caa0e2c6912eac8bfc",
"title": "6b4a6e06114829a0f3ac40caa0e2c6912eac8bfc",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/6b4a6e06114829a0f3ac40caa0e2c6912eac8bfc"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "f9948c36272736eba98bd145b6f90723d1c7adb4"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@f9948c36272736eba98bd145b6f90723d1c7adb4",
"title": "f9948c36272736eba98bd145b6f90723d1c7adb4",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/f9948c36272736eba98bd145b6f90723d1c7adb4"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "f9948c36272736eba98bd145b6f90723d1c7adb4",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8873863752480446736"
},
{
"key": "scheduler_job_id",
"value": "pigweed/pigweed.dev.ci-coverage"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[20:04:35.808] Scheduled build:
{
"id": "8708433690595558001",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "coverage"
},
"createdBy": "project:pigweed",
"createTime": "2025-07-24T20:04:35.360901563Z",
"updateTime": "2025-07-24T20:04:35.360901563Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "f9948c36272736eba98bd145b6f90723d1c7adb4",
"ref": "refs/heads/main"
}
}
}
[20:04:35.808] Task URL: https://cr-buildbucket.appspot.com/build/8708433690595558001
[20:04:35.808] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:2:0) after 8m6s
[20:04:36.819] Received PubSub notification, asking Buildbucket for the build status
[20:04:36.845] Build status: SCHEDULED
[20:12:41.805] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:2:0)
[20:12:41.839] Build status: SCHEDULED
[20:12:41.839] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:4:0) after 7m0s
[20:19:41.861] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:4:0)
[20:19:41.883] Build status: SCHEDULED
[20:19:41.883] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:5:0) after 6m13s
[20:25:54.899] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:5:0)
[20:25:54.966] Build status: SCHEDULED
[20:25:54.966] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:6:0) after 3m14s
[20:29:09.083] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:6:0)
[20:29:09.107] Build status: SCHEDULED
[20:29:09.107] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:7:0) after 7m54s
[20:37:03.131] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:7:0)
[20:37:03.150] Build status: SCHEDULED
[20:37:03.150] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:8:0) after 9m3s
[20:46:06.216] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:8:0)
[20:46:06.233] Build status: SCHEDULED
[20:46:06.233] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:9:0) after 5m59s
[20:52:05.433] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:9:0)
[20:52:05.516] Build status: SCHEDULED
[20:52:05.516] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:10:0) after 4m49s
[20:56:54.538] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:10:0)
[20:56:54.623] Build status: SCHEDULED
[20:56:54.623] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:11:0) after 8m50s
[21:05:44.643] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:11:0)
[21:05:44.688] Build status: SCHEDULED
[21:05:44.689] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:12:0) after 3m38s
[21:09:22.709] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:12:0)
[21:09:22.762] Build status: SCHEDULED
[21:09:22.762] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:13:0) after 8m55s
[21:18:17.786] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:13:0)
[21:18:17.805] Build status: SCHEDULED
[21:18:17.805] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:14:0) after 7m5s
[21:25:22.796] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:14:0)
[21:25:22.824] Build status: SCHEDULED
[21:25:22.824] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:15:0) after 1m21s
[21:26:43.846] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:15:0)
[21:26:43.885] Build status: SCHEDULED
[21:26:43.885] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:16:0) after 1m49s
[21:28:32.933] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:16:0)
[21:28:32.958] Build status: SCHEDULED
[21:28:32.959] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:17:0) after 3m43s
[21:32:16.082] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:17:0)
[21:32:16.097] Build status: SCHEDULED
[21:32:16.097] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:18:0) after 3m34s
[21:35:50.207] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:18:0)
[21:35:50.226] Build status: SCHEDULED
[21:35:50.226] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:19:0) after 7m41s
[21:43:31.242] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:19:0)
[21:43:31.260] Build status: SCHEDULED
[21:43:31.260] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:20:0) after 3m55s
[21:47:26.385] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:20:0)
[21:47:26.411] Build status: SCHEDULED
[21:47:26.411] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:21:0) after 3m7s
[21:50:33.427] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:21:0)
[21:50:33.454] Build status: SCHEDULED
[21:50:33.454] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:22:0) after 9m17s
[21:59:50.516] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:22:0)
[21:59:50.539] Build status: SCHEDULED
[21:59:50.539] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:23:0) after 8m28s
[22:08:18.607] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:23:0)
[22:08:18.631] Build status: SCHEDULED
[22:08:18.631] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:24:0) after 1m49s
[22:10:07.654] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:24:0)
[22:10:07.677] Build status: SCHEDULED
[22:10:07.677] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:25:0) after 4m55s
[22:15:02.655] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:25:0)
[22:15:02.678] Build status: SCHEDULED
[22:15:02.678] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:26:0) after 4m36s
[22:19:38.702] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:26:0)
[22:19:38.729] Build status: SCHEDULED
[22:19:38.729] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:27:0) after 3m52s
[22:23:30.874] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:27:0)
[22:23:30.893] Build status: SCHEDULED
[22:23:30.893] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:28:0) after 5m40s
[22:29:10.947] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:28:0)
[22:29:10.969] Build status: SCHEDULED
[22:29:10.969] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:29:0) after 1m46s
[22:30:56.997] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:29:0)
[22:30:57.027] Build status: SCHEDULED
[22:30:57.027] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:30:0) after 6m19s
[22:37:16.049] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:30:0)
[22:37:16.558] Build status: SCHEDULED
[22:37:16.558] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:31:0) after 6m40s
[22:43:56.581] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:31:0)
[22:43:56.607] Build status: SCHEDULED
[22:43:56.607] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:32:0) after 7m35s
[22:51:31.685] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:32:0)
[22:51:31.704] Build status: SCHEDULED
[22:51:31.704] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:33:0) after 2m24s
[22:53:55.776] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:33:0)
[22:53:55.792] Build status: SCHEDULED
[22:53:55.792] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:34:0) after 6m40s
[23:00:36.014] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:34:0)
[23:00:36.037] Build status: SCHEDULED
[23:00:36.037] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:35:0) after 5m10s
[23:05:46.077] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:35:0)
[23:05:46.108] Build status: SCHEDULED
[23:05:46.108] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:36:0) after 6m30s
[23:12:16.134] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:36:0)
[23:12:16.183] Build status: SCHEDULED
[23:12:16.183] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:37:0) after 2m40s
[23:14:56.346] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:37:0)
[23:14:56.363] Build status: SCHEDULED
[23:14:56.363] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:38:0) after 6m16s
[23:21:12.439] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:38:0)
[23:21:12.516] Build status: SCHEDULED
[23:21:12.516] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:39:0) after 5m34s
[23:26:46.648] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:39:0)
[23:26:46.698] Build status: SCHEDULED
[23:26:46.698] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:40:0) after 7m36s
[23:34:22.984] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:40:0)
[23:34:23.006] Build status: SCHEDULED
[23:34:23.006] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:41:0) after 2m0s
[23:36:23.035] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:41:0)
[23:36:23.050] Build status: SCHEDULED
[23:36:23.050] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:42:0) after 6m27s
[23:42:50.147] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:42:0)
[23:42:50.178] Build status: SCHEDULED
[23:42:50.178] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:43:0) after 8m27s
[23:51:17.228] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:43:0)
[23:51:17.258] Build status: SCHEDULED
[23:51:17.258] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:44:0) after 3m0s
[23:52:13.520] Received PubSub notification, asking Buildbucket for the build status
[23:52:13.538] Build status: STARTED
[23:54:17.370] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:44:0)
[23:54:17.393] Build status: STARTED
[23:54:17.393] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:46:0) after 2m51s
[23:57:08.411] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:46:0)
[23:57:08.432] Build status: STARTED
[23:57:08.432] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8873863752480446736:47:0) after 7m1s
[23:59:21.684] Received PubSub notification, asking Buildbucket for the build status
[23:59:21.704] Build:
{
"id": "8708433690595558001",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "coverage"
},
"createdBy": "project:pigweed",
"createTime": "2025-07-24T20:04:35.360901563Z",
"startTime": "2025-07-24T23:52:13.249301505Z",
"endTime": "2025-07-24T23:59:21.452659744Z",
"updateTime": "2025-07-24T23:59:21.452659744Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "f9948c36272736eba98bd145b6f90723d1c7adb4",
"ref": "refs/heads/main"
}
}
}
[23:59:21.705] Invocation finished in 3h54m47.525520672s with status SUCCEEDED