[22:01:44.828] New invocation is queued and will start shortly
[22:01:45.918] Starting the invocation (attempt 1)
[22:01:45.946] Popped buildset tag "buildset:commit/gitiles/pigweed.googlesource.com/pigweed/pigweed/+/86cb968d79207a634c196bc1d289aa1cf25591c7"
[22:01:45.946] Popped gitiles commit info from properties and tags
[22:01:45.946] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:01:45.946] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:01:45.947] Buildbucket request:
{
"requestId": "8891160400908692912",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "coverage"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8891160400908692912",
"job": "pigweed/pigweed.dev.ci-coverage",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "bdc526311c230b9efa7d7856f3125f5a92195aa2"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@bdc526311c230b9efa7d7856f3125f5a92195aa2",
"title": "bdc526311c230b9efa7d7856f3125f5a92195aa2",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/bdc526311c230b9efa7d7856f3125f5a92195aa2"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "3d3c6631bee239d6e662d2971d8bc7c149092664"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@3d3c6631bee239d6e662d2971d8bc7c149092664",
"title": "3d3c6631bee239d6e662d2971d8bc7c149092664",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/3d3c6631bee239d6e662d2971d8bc7c149092664"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "e977933e9bba9b14150bab47fa5d6937c988f771"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@e977933e9bba9b14150bab47fa5d6937c988f771",
"title": "e977933e9bba9b14150bab47fa5d6937c988f771",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/e977933e9bba9b14150bab47fa5d6937c988f771"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "c3fc5a17f5ddd7e870606c176fdcbe68f1920868"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@c3fc5a17f5ddd7e870606c176fdcbe68f1920868",
"title": "c3fc5a17f5ddd7e870606c176fdcbe68f1920868",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/c3fc5a17f5ddd7e870606c176fdcbe68f1920868"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "41b6f9ada52fb2ae5878c67c88cd5187a73f3b16"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@41b6f9ada52fb2ae5878c67c88cd5187a73f3b16",
"title": "41b6f9ada52fb2ae5878c67c88cd5187a73f3b16",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/41b6f9ada52fb2ae5878c67c88cd5187a73f3b16"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "8e0e0a0d98c507083aa0414c3462543cc627ae1c"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@8e0e0a0d98c507083aa0414c3462543cc627ae1c",
"title": "8e0e0a0d98c507083aa0414c3462543cc627ae1c",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/8e0e0a0d98c507083aa0414c3462543cc627ae1c"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "a67cc226162325902a4f20d8d3c8ded4a8682733"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@a67cc226162325902a4f20d8d3c8ded4a8682733",
"title": "a67cc226162325902a4f20d8d3c8ded4a8682733",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/a67cc226162325902a4f20d8d3c8ded4a8682733"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "8c5043f5fed7653d343373948adbb6c76b8c09c9"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@8c5043f5fed7653d343373948adbb6c76b8c09c9",
"title": "8c5043f5fed7653d343373948adbb6c76b8c09c9",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/8c5043f5fed7653d343373948adbb6c76b8c09c9"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "a90ad7872bd7f178d049264214ae3404c212fc4d"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@a90ad7872bd7f178d049264214ae3404c212fc4d",
"title": "a90ad7872bd7f178d049264214ae3404c212fc4d",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/a90ad7872bd7f178d049264214ae3404c212fc4d"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "b3361cfce37651422ea255532e4e60029f7954df"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@b3361cfce37651422ea255532e4e60029f7954df",
"title": "b3361cfce37651422ea255532e4e60029f7954df",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/b3361cfce37651422ea255532e4e60029f7954df"
},
{
"gitiles": {
"ref": "refs/heads/main",
"repo": "https://pigweed.googlesource.com/pigweed/pigweed",
"revision": "86cb968d79207a634c196bc1d289aa1cf25591c7"
},
"id": "https://pigweed.googlesource.com/pigweed/pigweed/+/refs/heads/main@86cb968d79207a634c196bc1d289aa1cf25591c7",
"title": "86cb968d79207a634c196bc1d289aa1cf25591c7",
"url": "https://pigweed.googlesource.com/pigweed/pigweed/+/86cb968d79207a634c196bc1d289aa1cf25591c7"
}
]
}
},
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "86cb968d79207a634c196bc1d289aa1cf25591c7",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8891160400908692912"
},
{
"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:01:46.335] Scheduled build:
{
"id": "8725730339006356609",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "coverage"
},
"createdBy": "project:pigweed",
"createTime": "2025-01-14T22:01:46.010195169Z",
"updateTime": "2025-01-14T22:01:46.010195169Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "86cb968d79207a634c196bc1d289aa1cf25591c7",
"ref": "refs/heads/main"
}
}
}
[22:01:46.335] Task URL: https://cr-buildbucket.appspot.com/build/8725730339006356609
[22:01:46.335] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:2:0) after 9m27s
[22:01:46.865] Received PubSub notification, asking Buildbucket for the build status
[22:01:46.920] Build status: SCHEDULED
[22:11:13.351] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:2:0)
[22:11:13.451] Build status: SCHEDULED
[22:11:13.451] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:4:0) after 6m24s
[22:17:37.552] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:4:0)
[22:17:37.580] Build status: SCHEDULED
[22:17:37.580] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:5:0) after 9m52s
[22:27:29.605] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:5:0)
[22:27:29.625] Build status: SCHEDULED
[22:27:29.625] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:6:0) after 3m28s
[22:30:57.744] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:6:0)
[22:30:57.760] Build status: SCHEDULED
[22:30:57.760] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:7:0) after 9m7s
[22:40:04.776] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:7:0)
[22:40:04.809] Build status: SCHEDULED
[22:40:04.809] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:8:0) after 6m39s
[22:46:43.783] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:8:0)
[22:46:43.830] Build status: SCHEDULED
[22:46:43.830] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:9:0) after 3m28s
[22:50:11.850] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:9:0)
[22:50:11.871] Build status: SCHEDULED
[22:50:11.871] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:10:0) after 5m23s
[22:55:34.944] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:10:0)
[22:55:34.960] Build status: SCHEDULED
[22:55:34.960] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:11:0) after 7m29s
[23:03:04.031] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:11:0)
[23:03:04.054] Build status: SCHEDULED
[23:03:04.054] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:12:0) after 1m5s
[23:04:09.147] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:12:0)
[23:04:09.172] Build status: SCHEDULED
[23:04:09.172] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:13:0) after 9m33s
[23:13:42.199] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:13:0)
[23:13:42.222] Build status: SCHEDULED
[23:13:42.222] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:14:0) after 5m55s
[23:19:37.341] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:14:0)
[23:19:37.362] Build status: SCHEDULED
[23:19:37.362] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:15:0) after 4m17s
[23:23:54.494] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:15:0)
[23:23:54.510] Build status: SCHEDULED
[23:23:54.510] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:16:0) after 4m59s
[23:28:53.531] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:16:0)
[23:28:53.562] Build status: SCHEDULED
[23:28:53.562] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:17:0) after 9m26s
[23:38:19.601] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:17:0)
[23:38:19.625] Build status: SCHEDULED
[23:38:19.625] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:18:0) after 8m4s
[23:46:23.650] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:18:0)
[23:46:23.674] Build status: SCHEDULED
[23:46:23.674] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:19:0) after 7m21s
[23:53:44.691] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:19:0)
[23:53:44.799] Build status: SCHEDULED
[23:53:44.799] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:20:0) after 5m10s
[23:58:55.026] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:20:0)
[23:58:55.196] Build status: SCHEDULED
[23:58:55.196] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:21:0) after 4m54s
[00:03:49.283] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:21:0)
[00:03:49.302] Build status: SCHEDULED
[00:03:49.302] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:22:0) after 9m35s
[00:13:24.330] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:22:0)
[00:13:24.352] Build status: SCHEDULED
[00:13:24.352] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:23:0) after 2m49s
[00:16:13.466] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:23:0)
[00:16:13.485] Build status: SCHEDULED
[00:16:13.485] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:24:0) after 3m22s
[00:19:35.511] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:24:0)
[00:19:35.534] Build status: SCHEDULED
[00:19:35.534] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:25:0) after 3m53s
[00:23:28.631] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:25:0)
[00:23:28.667] Build status: SCHEDULED
[00:23:28.667] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:26:0) after 5m19s
[00:28:47.752] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:26:0)
[00:28:47.771] Build status: SCHEDULED
[00:28:47.771] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:27:0) after 8m37s
[00:37:24.949] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:27:0)
[00:37:25.533] Build status: SCHEDULED
[00:37:25.533] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:28:0) after 3m1s
[00:40:26.619] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:28:0)
[00:40:26.705] Build status: SCHEDULED
[00:40:26.705] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:29:0) after 5m32s
[00:45:58.720] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:29:0)
[00:45:58.745] Build status: SCHEDULED
[00:45:58.745] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:30:0) after 5m29s
[00:51:27.773] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:30:0)
[00:51:27.792] Build status: SCHEDULED
[00:51:27.792] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:31:0) after 6m14s
[00:52:13.895] Received PubSub notification, asking Buildbucket for the build status
[00:52:13.914] Build status: STARTED
[00:57:41.859] Handling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:31:0)
[00:57:41.884] Build status: STARTED
[00:57:41.884] Scheduling timer "check-buildbucket-build-status" (pigweed/pigweed.dev.ci-coverage:8891160400908692912:33:0) after 6m3s
[00:59:30.073] Received PubSub notification, asking Buildbucket for the build status
[00:59:30.091] Build:
{
"id": "8725730339006356609",
"builder": {
"project": "pigweed",
"bucket": "pigweed.dev.ci",
"builder": "coverage"
},
"createdBy": "project:pigweed",
"createTime": "2025-01-14T22:01:46.010195169Z",
"startTime": "2025-01-15T00:52:12.353953991Z",
"endTime": "2025-01-15T00:59:29.678269833Z",
"updateTime": "2025-01-15T00:59:29.678269833Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "pigweed.googlesource.com",
"project": "pigweed/pigweed",
"id": "86cb968d79207a634c196bc1d289aa1cf25591c7",
"ref": "refs/heads/main"
}
}
}
[00:59:30.091] Invocation finished in 2h57m45.278027682s with status SUCCEEDED