[00:33:10.854] New invocation is queued and will start shortly
[00:33:11.907] Starting the invocation (attempt 1)
[00:33:11.930] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/05f07498722601b2a9d039dd44a933770331f1de"
[00:33:11.930] Popped gitiles commit info from properties and tags
[00:33:11.930] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[00:33:11.930] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[00:33:11.930] Buildbucket request:
{
"requestId": "8880279237549961376",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "coverage"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8880279237549961376",
"job": "pigweed/pigweed.dev.ci-coverage",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "4a743e6fd3bed17107e9cfa1bc4d0c280a96c966"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@4a743e6fd3bed17107e9cfa1bc4d0c280a96c966",
"title": "4a743e6fd3bed17107e9cfa1bc4d0c280a96c966",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/4a743e6fd3bed17107e9cfa1bc4d0c280a96c966"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "6a34b9b071ef6c532e8d711fc806791bac568ae6"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@6a34b9b071ef6c532e8d711fc806791bac568ae6",
"title": "6a34b9b071ef6c532e8d711fc806791bac568ae6",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/6a34b9b071ef6c532e8d711fc806791bac568ae6"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "d65cfb71d293ff60f370b907315d62079b23a463"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@d65cfb71d293ff60f370b907315d62079b23a463",
"title": "d65cfb71d293ff60f370b907315d62079b23a463",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/d65cfb71d293ff60f370b907315d62079b23a463"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "599d50f80c756b58c1eec7cc434333d42e6573a2"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@599d50f80c756b58c1eec7cc434333d42e6573a2",
"title": "599d50f80c756b58c1eec7cc434333d42e6573a2",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/599d50f80c756b58c1eec7cc434333d42e6573a2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "875cdae1ce0c15f465df6a5748fb3e7dd6a1f901"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@875cdae1ce0c15f465df6a5748fb3e7dd6a1f901",
"title": "875cdae1ce0c15f465df6a5748fb3e7dd6a1f901",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/875cdae1ce0c15f465df6a5748fb3e7dd6a1f901"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "1093edd813aa2821f012aeca6746a0d633e18375"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@1093edd813aa2821f012aeca6746a0d633e18375",
"title": "1093edd813aa2821f012aeca6746a0d633e18375",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/1093edd813aa2821f012aeca6746a0d633e18375"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "5ffef4df59c253a0ca73d188ec68ef954b844823"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@5ffef4df59c253a0ca73d188ec68ef954b844823",
"title": "5ffef4df59c253a0ca73d188ec68ef954b844823",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/5ffef4df59c253a0ca73d188ec68ef954b844823"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "05f07498722601b2a9d039dd44a933770331f1de"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@05f07498722601b2a9d039dd44a933770331f1de",
"title": "05f07498722601b2a9d039dd44a933770331f1de",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/05f07498722601b2a9d039dd44a933770331f1de"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "05f07498722601b2a9d039dd44a933770331f1de",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8880279237549961376"
},
{
"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"
}
}
[00:33:12.286] Scheduled build:
{
"id": "8714849175704704449",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "coverage"
},
"createdBy": "project:pigweed",
"createTime": "2025-05-15T00:33:11.984542181Z",
"updateTime": "2025-05-15T00:33:11.984542181Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "05f07498722601b2a9d039dd44a933770331f1de",
"ref": "refs/heads/main"
}
}
}
[00:33:12.286] Task URL: https://cr-buildbucket.appspot.com/build/8714849175704704449
[00:33:12.286] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:2:0) after 8m10s
[00:33:12.677] Received PubSub notification, asking Buildbucket for the build status
[00:33:12.713] Build status: SCHEDULED
[00:41:22.281] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:2:0)
[00:41:22.353] Build status: SCHEDULED
[00:41:22.353] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:4:0) after 7m0s
[00:48:22.439] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:4:0)
[00:48:22.478] Build status: SCHEDULED
[00:48:22.478] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:5:0) after 9m44s
[00:58:06.476] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:5:0)
[00:58:06.502] Build status: SCHEDULED
[00:58:06.502] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:6:0) after 9m42s
[01:07:48.571] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:6:0)
[01:07:48.594] Build status: SCHEDULED
[01:07:48.594] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:7:0) after 3m15s
[01:11:03.607] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:7:0)
[01:11:03.634] Build status: SCHEDULED
[01:11:03.634] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:8:0) after 1m10s
[01:12:13.707] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:8:0)
[01:12:13.738] Build status: SCHEDULED
[01:12:13.738] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:9:0) after 2m5s
[01:14:18.753] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:9:0)
[01:14:18.775] Build status: SCHEDULED
[01:14:18.775] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:10:0) after 3m26s
[01:17:44.794] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:10:0)
[01:17:44.838] Build status: SCHEDULED
[01:17:44.838] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:11:0) after 6m22s
[01:24:06.858] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:11:0)
[01:24:07.317] Build status: SCHEDULED
[01:24:07.317] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:12:0) after 8m26s
[01:32:33.380] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:12:0)
[01:32:33.401] Build status: SCHEDULED
[01:32:33.401] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:13:0) after 9m2s
[01:41:35.427] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:13:0)
[01:41:35.454] Build status: SCHEDULED
[01:41:35.454] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:14:0) after 3m56s
[01:45:31.518] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:14:0)
[01:45:31.539] Build status: SCHEDULED
[01:45:31.539] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:15:0) after 9m42s
[01:55:13.562] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:15:0)
[01:55:13.586] Build status: SCHEDULED
[01:55:13.586] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:16:0) after 2m38s
[01:57:51.652] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:16:0)
[01:57:51.725] Build status: SCHEDULED
[01:57:51.726] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:17:0) after 1m36s
[01:59:27.748] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:17:0)
[01:59:27.779] Build status: SCHEDULED
[01:59:27.779] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:18:0) after 3m19s
[02:02:46.765] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:18:0)
[02:02:46.786] Build status: SCHEDULED
[02:02:46.786] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:19:0) after 5m28s
[02:08:14.810] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:19:0)
[02:08:14.828] Build status: SCHEDULED
[02:08:14.828] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:20:0) after 6m39s
[02:14:53.853] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:20:0)
[02:14:53.869] Build status: SCHEDULED
[02:14:53.869] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:21:0) after 7m40s
[02:22:34.035] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:21:0)
[02:22:34.053] Build status: SCHEDULED
[02:22:34.053] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:22:0) after 6m30s
[02:29:04.110] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:22:0)
[02:29:04.131] Build status: SCHEDULED
[02:29:04.131] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:23:0) after 6m2s
[02:35:06.149] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:23:0)
[02:35:06.165] Build status: SCHEDULED
[02:35:06.165] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:24:0) after 1m32s
[02:36:38.190] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:24:0)
[02:36:38.216] Build status: SCHEDULED
[02:36:38.216] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:25:0) after 9m7s
[02:45:45.249] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:25:0)
[02:45:45.273] Build status: SCHEDULED
[02:45:45.273] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:26:0) after 7m4s
[02:52:49.289] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:26:0)
[02:52:49.310] Build status: SCHEDULED
[02:52:49.310] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:27:0) after 3m39s
[02:56:28.323] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:27:0)
[02:56:28.345] Build status: SCHEDULED
[02:56:28.345] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:28:0) after 5m4s
[03:01:32.416] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:28:0)
[03:01:32.471] Build status: SCHEDULED
[03:01:32.471] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:29:0) after 9m49s
[03:11:21.497] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:29:0)
[03:11:21.563] Build status: SCHEDULED
[03:11:21.563] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:30:0) after 2m0s
[03:13:21.593] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:30:0)
[03:13:21.618] Build status: SCHEDULED
[03:13:21.618] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:31:0) after 8m40s
[03:22:01.638] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:31:0)
[03:22:01.849] Build status: SCHEDULED
[03:22:01.849] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:32:0) after 6m55s
[03:28:56.849] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:32:0)
[03:28:56.879] Build status: SCHEDULED
[03:28:56.879] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:33:0) after 8m8s
[03:37:04.951] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:33:0)
[03:37:04.984] Build status: SCHEDULED
[03:37:04.984] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:34:0) after 8m4s
[03:45:09.089] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:34:0)
[03:45:09.119] Build status: SCHEDULED
[03:45:09.119] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:35:0) after 2m15s
[03:47:01.644] Received PubSub notification, asking Buildbucket for the build status
[03:47:01.669] Build status: STARTED
[03:47:24.139] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:35:0)
[03:47:24.161] Build status: STARTED
[03:47:24.161] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:37:0) after 2m22s
[03:49:46.242] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:37:0)
[03:49:46.257] Build status: STARTED
[03:49:46.257] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8880279237549961376:38:0) after 7m18s
[03:54:03.268] Received PubSub notification, asking Buildbucket for the build status
[03:54:03.285] Build:
{
"id": "8714849175704704449",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "coverage"
},
"createdBy": "project:pigweed",
"createTime": "2025-05-15T00:33:11.984542181Z",
"startTime": "2025-05-15T03:47:01.466891312Z",
"endTime": "2025-05-15T03:54:02.585239033Z",
"updateTime": "2025-05-15T03:54:02.585239033Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "05f07498722601b2a9d039dd44a933770331f1de",
"ref": "refs/heads/main"
}
}
}
[03:54:03.285] Invocation finished in 3h20m52.444309652s with status SUCCEEDED