[22:27:46.858] New invocation is queued and will start shortly
[22:27:48.173] Starting the invocation (attempt 1)
[22:27:48.202] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/616ca38645979c3d4301753fb889d890f1fffa4c"
[22:27:48.202] Popped gitiles commit info from properties and tags
[22:27:48.202] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:27:48.203] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:27:48.203] Buildbucket request:
{
"requestId": "8878837575559234208",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "coverage"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8878837575559234208",
"job": "pigweed/pigweed.dev.ci-coverage",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "835e0d6a7fe514ebcfdd6f8e6f211ffb7c648bdd"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@835e0d6a7fe514ebcfdd6f8e6f211ffb7c648bdd",
"title": "835e0d6a7fe514ebcfdd6f8e6f211ffb7c648bdd",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/835e0d6a7fe514ebcfdd6f8e6f211ffb7c648bdd"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "b5b26aa33107d1ea1717a010ef9ccb0f9851529c"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@b5b26aa33107d1ea1717a010ef9ccb0f9851529c",
"title": "b5b26aa33107d1ea1717a010ef9ccb0f9851529c",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/b5b26aa33107d1ea1717a010ef9ccb0f9851529c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "ed2d0a8c117a14f72c94835920fec10f577acd27"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@ed2d0a8c117a14f72c94835920fec10f577acd27",
"title": "ed2d0a8c117a14f72c94835920fec10f577acd27",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/ed2d0a8c117a14f72c94835920fec10f577acd27"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "0eb783c973761ddd5df8420fb417785ee629eacf"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@0eb783c973761ddd5df8420fb417785ee629eacf",
"title": "0eb783c973761ddd5df8420fb417785ee629eacf",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/0eb783c973761ddd5df8420fb417785ee629eacf"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "2ef103c4f2f02cdfd2ae8f37deae3e126239a5bf"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@2ef103c4f2f02cdfd2ae8f37deae3e126239a5bf",
"title": "2ef103c4f2f02cdfd2ae8f37deae3e126239a5bf",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/2ef103c4f2f02cdfd2ae8f37deae3e126239a5bf"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "616ca38645979c3d4301753fb889d890f1fffa4c"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@616ca38645979c3d4301753fb889d890f1fffa4c",
"title": "616ca38645979c3d4301753fb889d890f1fffa4c",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/616ca38645979c3d4301753fb889d890f1fffa4c"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "616ca38645979c3d4301753fb889d890f1fffa4c",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8878837575559234208"
},
{
"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"
}
}
[22:27:48.830] Scheduled build:
{
"id": "8713407513388842481",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "coverage"
},
"createdBy": "project:pigweed",
"createTime": "2025-05-30T22:27:48.307436229Z",
"updateTime": "2025-05-30T22:27:48.307436229Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "616ca38645979c3d4301753fb889d890f1fffa4c",
"ref": "refs/heads/main"
}
}
}
[22:27:48.830] Task URL: https://cr-buildbucket.appspot.com/build/8713407513388842481
[22:27:48.830] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:2:0) after 4m46s
[22:27:49.808] Received PubSub notification, asking Buildbucket for the build status
[22:27:49.827] Build status: SCHEDULED
[22:32:34.912] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:2:0)
[22:32:34.938] Build status: SCHEDULED
[22:32:34.938] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:4:0) after 2m12s
[22:34:46.998] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:4:0)
[22:34:47.031] Build status: SCHEDULED
[22:34:47.031] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:5:0) after 7m16s
[22:42:03.043] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:5:0)
[22:42:03.071] Build status: SCHEDULED
[22:42:03.072] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:6:0) after 6m6s
[22:48:09.147] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:6:0)
[22:48:09.175] Build status: SCHEDULED
[22:48:09.175] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:7:0) after 9m17s
[22:57:26.198] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:7:0)
[22:57:26.247] Build status: SCHEDULED
[22:57:26.247] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:8:0) after 2m30s
[22:59:56.260] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:8:0)
[22:59:56.284] Build status: SCHEDULED
[22:59:56.284] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:9:0) after 2m11s
[23:02:07.297] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:9:0)
[23:02:07.321] Build status: SCHEDULED
[23:02:07.321] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:10:0) after 7m57s
[23:10:04.343] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:10:0)
[23:10:04.421] Build status: SCHEDULED
[23:10:04.421] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:11:0) after 3m18s
[23:13:22.483] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:11:0)
[23:13:22.507] Build status: SCHEDULED
[23:13:22.507] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:12:0) after 8m48s
[23:22:10.522] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:12:0)
[23:22:10.544] Build status: SCHEDULED
[23:22:10.544] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:13:0) after 1m9s
[23:23:19.580] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:13:0)
[23:23:19.599] Build status: SCHEDULED
[23:23:19.599] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:14:0) after 6m56s
[23:30:15.653] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:14:0)
[23:30:15.730] Build status: SCHEDULED
[23:30:15.731] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:15:0) after 4m19s
[23:34:34.851] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:15:0)
[23:34:34.868] Build status: SCHEDULED
[23:34:34.868] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:16:0) after 4m29s
[23:39:03.894] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:16:0)
[23:39:03.957] Build status: SCHEDULED
[23:39:03.957] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:17:0) after 3m40s
[23:42:43.973] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:17:0)
[23:42:43.998] Build status: SCHEDULED
[23:42:43.999] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:18:0) after 3m50s
[23:46:34.030] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:18:0)
[23:46:34.061] Build status: SCHEDULED
[23:46:34.061] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:19:0) after 4m50s
[23:51:24.070] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:19:0)
[23:51:24.091] Build status: SCHEDULED
[23:51:24.091] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:20:0) after 9m45s
[00:01:09.085] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:20:0)
[00:01:09.122] Build status: SCHEDULED
[00:01:09.122] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:21:0) after 5m53s
[00:07:02.208] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:21:0)
[00:07:02.227] Build status: SCHEDULED
[00:07:02.227] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:22:0) after 9m42s
[00:16:44.242] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:22:0)
[00:16:44.312] Build status: SCHEDULED
[00:16:44.312] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:23:0) after 1m16s
[00:18:00.334] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:23:0)
[00:18:00.349] Build status: SCHEDULED
[00:18:00.349] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:24:0) after 7m19s
[00:25:19.360] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:24:0)
[00:25:19.384] Build status: SCHEDULED
[00:25:19.384] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:25:0) after 3m3s
[00:28:22.475] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:25:0)
[00:28:22.493] Build status: SCHEDULED
[00:28:22.493] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:26:0) after 8m46s
[00:37:08.548] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:26:0)
[00:37:08.561] Build status: SCHEDULED
[00:37:08.561] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:27:0) after 3m41s
[00:40:49.588] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:27:0)
[00:40:49.604] Build status: SCHEDULED
[00:40:49.604] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:28:0) after 3m49s
[00:44:38.717] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:28:0)
[00:44:38.736] Build status: SCHEDULED
[00:44:38.736] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:29:0) after 1m31s
[00:46:09.759] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:29:0)
[00:46:09.903] Build status: SCHEDULED
[00:46:09.903] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:30:0) after 4m0s
[00:50:09.932] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:30:0)
[00:50:09.956] Build status: SCHEDULED
[00:50:09.956] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:31:0) after 9m53s
[01:00:03.016] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:31:0)
[01:00:03.032] Build status: SCHEDULED
[01:00:03.032] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:32:0) after 6m22s
[01:06:25.061] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:32:0)
[01:06:25.079] Build status: SCHEDULED
[01:06:25.079] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:33:0) after 3m8s
[01:09:33.147] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:33:0)
[01:09:33.193] Build status: SCHEDULED
[01:09:33.193] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:34:0) after 5m16s
[01:14:49.281] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:34:0)
[01:14:49.299] Build status: SCHEDULED
[01:14:49.299] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:35:0) after 6m16s
[01:21:05.366] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:35:0)
[01:21:05.384] Build status: SCHEDULED
[01:21:05.384] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:36:0) after 2m0s
[01:23:05.387] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:36:0)
[01:23:05.403] Build status: SCHEDULED
[01:23:05.404] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:37:0) after 8m34s
[01:31:39.473] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:37:0)
[01:31:39.521] Build status: SCHEDULED
[01:31:39.521] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:38:0) after 7m39s
[01:39:18.542] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:38:0)
[01:39:18.560] Build status: SCHEDULED
[01:39:18.560] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8878837575559234208:39:0) after 9m4s
[01:39:45.531] Received PubSub notification, asking Buildbucket for the build status
[01:39:45.548] Build status: STARTED
[01:46:48.043] Received PubSub notification, asking Buildbucket for the build status
[01:46:48.060] Build:
{
"id": "8713407513388842481",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "coverage"
},
"createdBy": "project:pigweed",
"createTime": "2025-05-30T22:27:48.307436229Z",
"startTime": "2025-05-31T01:39:45.354786940Z",
"endTime": "2025-05-31T01:46:47.784379190Z",
"updateTime": "2025-05-31T01:46:47.784379190Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "616ca38645979c3d4301753fb889d890f1fffa4c",
"ref": "refs/heads/main"
}
}
}
[01:46:48.060] Invocation finished in 3h19m1.206335831s with status SUCCEEDED